-- Logs begin at Tue 2025-05-20 18:46:48 KST, end at Tue 2025-05-20 19:08:55 KST. -- May 20 19:07:03 volumio volumio[706]: info: CoreCommandRouter::getUIConfigOnPlugin May 20 19:07:03 volumio volumio[706]: info: May 20 19:07:03 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:07:03 volumio volumio[706]: info: ControllerMpd::getState May 20 19:07:03 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:07:03 volumio volumio[706]: info: May 20 19:07:03 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:07:03 volumio volumio[706]: info: ControllerMpd::getState May 20 19:07:03 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:07:04 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 20 19:07:04 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 19:07:04 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 20 19:07:04 volumio volumio[706]: info: Getting Alsa Cards List without I2S DAC May 20 19:07:04 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber May 20 19:07:04 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 19:07:05 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 20 19:07:05 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 20 19:07:05 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 20 19:07:05 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 20 19:07:05 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 20 19:07:05 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 20 19:07:05 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 20 19:07:05 volumio volumio[706]: info: sendMpdCommand status took 1460 milliseconds May 20 19:07:05 volumio volumio[706]: info: sendMpdCommand status took 1439 milliseconds May 20 19:07:05 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:07:05 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:07:05 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:07:05 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:05 volumio volumio[706]: info: CorePlayQueue::getTrack 1 May 20 19:07:05 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:07:05 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:05 volumio volumio[706]: info: CorePlayQueue::getTrack 1 May 20 19:07:05 volumio volumio[706]: 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 20 19:07:05 volumio volumio[706]: verbose: CURRENT POSITION 1 May 20 19:07:05 volumio volumio[706]: info: CoreStateMachine::syncState stateService stop May 20 19:07:05 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus play May 20 19:07:05 volumio volumio[706]: info: CoreStateMachine::play index undefined May 20 19:07:05 volumio volumio[706]: info: CoreStateMachine::setConsumeUpdateService undefined May 20 19:07:05 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:05 volumio volumio[706]: info: CorePlayQueue::getTrack 2 May 20 19:07:05 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:07:05 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:05 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:07:05 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:07:05 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:05 volumio volumio[706]: info: CorePlayQueue::getTrack 2 May 20 19:07:05 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:05 volumio volumio[706]: info: CorePlayQueue::getTrack 2 May 20 19:07:05 volumio volumio[706]: 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 20 19:07:05 volumio volumio[706]: verbose: CURRENT POSITION 2 May 20 19:07:05 volumio volumio[706]: info: CoreStateMachine::syncState stateService stop May 20 19:07:05 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus stop May 20 19:07:05 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:05 volumio volumio[706]: info: CorePlayQueue::getTrack 2 May 20 19:07:05 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:05 volumio volumio[706]: info: No code May 20 19:07:05 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:05 volumio volumio[706]: info: CorePlayQueue::getTrack 2 May 20 19:07:05 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:05 volumio volumio[706]: info: CorePlayQueue::getTrack 2 May 20 19:07:05 volumio volumio[706]: info: CoreStateMachine::startPlaybackTimer May 20 19:07:05 volumio volumio[706]: info: CorePlayQueue::getTrack 2 May 20 19:07:05 volumio volumio[706]: verbose: ControllerMpd::clearAddPlayTracks USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/03 Suite Espanola No1 Op47 Asturias (Albeniz).ape May 20 19:07:05 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand stop May 20 19:07:05 volumio volumio[706]: info: ------------------------------ 1788ms May 20 19:07:05 volumio volumio[706]: info: ------------------------------ 1772ms May 20 19:07:05 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:05 volumio volumio[706]: info: CorePlayQueue::getTrack 2 May 20 19:07:05 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:07:05 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:05 volumio volumio[706]: info: sendMpdCommand stop took 114 milliseconds May 20 19:07:05 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand clear May 20 19:07:05 volumio volumio[706]: info: May 20 19:07:05 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:07:05 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:07:05 volumio volumio[706]: info: sendMpdCommand clear took 60 milliseconds May 20 19:07:05 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand add "USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/03 Suite Espanola No1 Op47 Asturias (Albeniz).ape" May 20 19:07:05 volumio volumio[706]: info: May 20 19:07:05 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:07:05 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:07:05 volumio volumio[706]: error: updateQueue error: null May 20 19:07:05 volumio volumio[706]: info: May 20 19:07:05 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:07:05 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:07:05 volumio volumio[706]: info: ------------------------------ 35ms May 20 19:07:05 volumio volumio[706]: info: sendMpdCommand add "USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/03 Suite Espanola No1 Op47 Asturias (Albeniz).ape" took 27 milliseconds May 20 19:07:05 volumio volumio[706]: info: ------------------------------ 25ms May 20 19:07:05 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand play May 20 19:07:05 volumio volumio[706]: info: May 20 19:07:05 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:07:05 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:07:05 volumio volumio[706]: info: ------------------------------ 58ms May 20 19:07:05 volumio volumio[706]: info: sendMpdCommand play took 48 milliseconds May 20 19:07:05 volumio volumio[706]: info: ------------------------------ 39ms May 20 19:07:05 volumio volumio[706]: info: May 20 19:07:05 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:07:05 volumio volumio[706]: info: ControllerMpd::getState May 20 19:07:05 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:07:05 volumio volumio[706]: info: May 20 19:07:05 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:07:05 volumio volumio[706]: info: ControllerMpd::getState May 20 19:07:05 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:07:05 volumio volumio[706]: info: sendMpdCommand status took 29 milliseconds May 20 19:07:05 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:07:05 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 19:07:06 volumio volumio[706]: info: sendMpdCommand status took 39 milliseconds May 20 19:07:06 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:07:06 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 19:07:06 volumio volumio[706]: info: sendMpdCommand playlistinfo took 144 milliseconds May 20 19:07:06 volumio volumio[706]: info: sendMpdCommand playlistinfo took 118 milliseconds May 20 19:07:06 volumio volumio[706]: verbose: ControllerMpd::parseTrackInfo May 20 19:07:06 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:07:06 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:07:06 volumio volumio[706]: info: CorePlayQueue::getTrack 2 May 20 19:07:06 volumio volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":439,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"03 Suite Espanola No1 Op47 Asturias (Albeniz).ape","artist":null,"album":null,"uri":"USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/03 Suite Espanola No1 Op47 Asturias (Albeniz).ape","trackType":"ape"} May 20 19:07:06 volumio volumio[706]: verbose: CURRENT POSITION 2 May 20 19:07:06 volumio volumio[706]: info: CoreStateMachine::syncState stateService play May 20 19:07:06 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus stop May 20 19:07:06 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:07:06 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:07:06 volumio volumio[706]: info: CorePlayQueue::getTrack 2 May 20 19:07:06 volumio volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":439,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"03 Suite Espanola No1 Op47 Asturias (Albeniz).ape","artist":null,"album":null,"uri":"USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/03 Suite Espanola No1 Op47 Asturias (Albeniz).ape","trackType":"ape"} May 20 19:07:06 volumio volumio[706]: verbose: CURRENT POSITION 2 May 20 19:07:06 volumio volumio[706]: info: CoreStateMachine::syncState stateService play May 20 19:07:06 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus play May 20 19:07:06 volumio volumio[706]: info: Received an update from plugin. extracting info from payload May 20 19:07:06 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:06 volumio volumio[706]: info: CorePlayQueue::getTrack 2 May 20 19:07:06 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:07:06 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:06 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:06 volumio volumio[706]: info: CorePlayQueue::getTrack 2 May 20 19:07:06 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:06 volumio volumio[706]: info: ------------------------------ 345ms May 20 19:07:06 volumio volumio[706]: info: ------------------------------ 467ms May 20 19:07:06 volumio mount.exfat[2990]: failed to read cluster 0x157a1 May 20 19:07:06 volumio kernel: I/O error, dev sda, sector 22558656 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 May 20 19:07:06 volumio kernel: I/O error, dev sda, sector 22558656 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:07:06 volumio kernel: Buffer I/O error on dev sda1, logical block 2815736, async page read May 20 19:07:07 volumio mount.exfat[2990]: failed to read cluster 0x157a2 May 20 19:07:07 volumio kernel: I/O error, dev sda, sector 22558912 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 May 20 19:07:07 volumio kernel: I/O error, dev sda, sector 22558912 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:07:07 volumio kernel: Buffer I/O error on dev sda1, logical block 2815768, async page read May 20 19:07:07 volumio kernel: I/O error, dev sda, sector 22559168 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 May 20 19:07:07 volumio kernel: I/O error, dev sda, sector 22559168 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:07:07 volumio kernel: Buffer I/O error on dev sda1, logical block 2815800, async page read May 20 19:07:07 volumio mount.exfat[2990]: failed to read cluster 0x157a3 May 20 19:07:08 volumio mount.exfat[2990]: failed to read cluster 0x157a1 May 20 19:07:08 volumio mount.exfat[2990]: failed to read cluster 0x157a1 May 20 19:07:08 volumio kernel: I/O error, dev sda, sector 22558656 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:07:08 volumio kernel: Buffer I/O error on dev sda1, logical block 2815736, async page read May 20 19:07:08 volumio kernel: I/O error, dev sda, sector 22558656 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:07:08 volumio kernel: Buffer I/O error on dev sda1, logical block 2815736, async page read May 20 19:07:08 volumio kernel: I/O error, dev sda, sector 22558656 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:07:08 volumio kernel: Buffer I/O error on dev sda1, logical block 2815736, async page read May 20 19:07:08 volumio mount.exfat[2990]: failed to read cluster 0x157a1 May 20 19:07:13 volumio volumio[706]: info: May 20 19:07:13 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:07:13 volumio volumio[706]: info: ControllerMpd::getState May 20 19:07:13 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:07:13 volumio volumio[706]: info: May 20 19:07:13 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:07:13 volumio volumio[706]: info: ControllerMpd::getState May 20 19:07:13 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:07:13 volumio volumio[706]: info: sendMpdCommand status took 39 milliseconds May 20 19:07:13 volumio volumio[706]: info: sendMpdCommand status took 19 milliseconds May 20 19:07:13 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:07:13 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:07:13 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:07:13 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:13 volumio volumio[706]: info: CorePlayQueue::getTrack 2 May 20 19:07:13 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:07:13 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:13 volumio volumio[706]: info: CorePlayQueue::getTrack 2 May 20 19:07:13 volumio volumio[706]: 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 20 19:07:13 volumio volumio[706]: verbose: CURRENT POSITION 2 May 20 19:07:13 volumio volumio[706]: info: CoreStateMachine::syncState stateService stop May 20 19:07:13 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus play May 20 19:07:13 volumio volumio[706]: info: CoreStateMachine::play index undefined May 20 19:07:13 volumio volumio[706]: info: CoreStateMachine::setConsumeUpdateService undefined May 20 19:07:13 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:13 volumio volumio[706]: info: CorePlayQueue::getTrack 3 May 20 19:07:13 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:07:13 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:13 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:07:13 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:07:13 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:13 volumio volumio[706]: info: CorePlayQueue::getTrack 3 May 20 19:07:13 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:13 volumio volumio[706]: info: CorePlayQueue::getTrack 3 May 20 19:07:13 volumio volumio[706]: 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 20 19:07:13 volumio volumio[706]: verbose: CURRENT POSITION 3 May 20 19:07:13 volumio volumio[706]: info: CoreStateMachine::syncState stateService stop May 20 19:07:13 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus stop May 20 19:07:13 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:13 volumio volumio[706]: info: CorePlayQueue::getTrack 3 May 20 19:07:13 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:13 volumio volumio[706]: info: No code May 20 19:07:13 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:13 volumio volumio[706]: info: CorePlayQueue::getTrack 3 May 20 19:07:13 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:13 volumio volumio[706]: info: CorePlayQueue::getTrack 3 May 20 19:07:13 volumio volumio[706]: info: CoreStateMachine::startPlaybackTimer May 20 19:07:13 volumio volumio[706]: info: CorePlayQueue::getTrack 3 May 20 19:07:13 volumio volumio[706]: verbose: ControllerMpd::clearAddPlayTracks USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/04 Rumores De La Caleta Op71 (Albeniz).ape May 20 19:07:13 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand stop May 20 19:07:13 volumio volumio[706]: info: ------------------------------ 416ms May 20 19:07:13 volumio volumio[706]: info: ------------------------------ 411ms May 20 19:07:13 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:13 volumio volumio[706]: info: CorePlayQueue::getTrack 3 May 20 19:07:13 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:07:13 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:13 volumio volumio[706]: info: sendMpdCommand stop took 83 milliseconds May 20 19:07:13 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand clear May 20 19:07:13 volumio volumio[706]: info: May 20 19:07:13 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:07:13 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:07:13 volumio volumio[706]: info: sendMpdCommand clear took 57 milliseconds May 20 19:07:13 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand add "USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/04 Rumores De La Caleta Op71 (Albeniz).ape" May 20 19:07:13 volumio volumio[706]: info: May 20 19:07:13 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:07:13 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:07:13 volumio volumio[706]: error: updateQueue error: null May 20 19:07:13 volumio volumio[706]: info: May 20 19:07:13 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:07:13 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:07:13 volumio volumio[706]: info: ------------------------------ 33ms May 20 19:07:13 volumio volumio[706]: info: sendMpdCommand add "USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/04 Rumores De La Caleta Op71 (Albeniz).ape" took 26 milliseconds May 20 19:07:13 volumio volumio[706]: info: ------------------------------ 24ms May 20 19:07:13 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand play May 20 19:07:13 volumio volumio[706]: info: May 20 19:07:13 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:07:13 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:07:13 volumio volumio[706]: info: ------------------------------ 41ms May 20 19:07:13 volumio volumio[706]: info: sendMpdCommand play took 43 milliseconds May 20 19:07:13 volumio volumio[706]: info: May 20 19:07:13 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:07:13 volumio volumio[706]: info: ------------------------------ 51ms May 20 19:07:13 volumio volumio[706]: info: ControllerMpd::getState May 20 19:07:13 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:07:13 volumio volumio[706]: info: May 20 19:07:13 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:07:13 volumio volumio[706]: info: ControllerMpd::getState May 20 19:07:13 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:07:13 volumio volumio[706]: info: May 20 19:07:13 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:07:13 volumio volumio[706]: info: ControllerMpd::getState May 20 19:07:13 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:07:13 volumio volumio[706]: info: sendMpdCommand status took 41 milliseconds May 20 19:07:13 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:07:13 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 19:07:13 volumio volumio[706]: info: May 20 19:07:13 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:07:13 volumio volumio[706]: info: ControllerMpd::getState May 20 19:07:13 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:07:13 volumio volumio[706]: info: sendMpdCommand status took 65 milliseconds May 20 19:07:13 volumio volumio[706]: info: sendMpdCommand status took 57 milliseconds May 20 19:07:13 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:07:13 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 19:07:13 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:07:13 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 19:07:14 volumio volumio[706]: info: sendMpdCommand playlistinfo took 91 milliseconds May 20 19:07:14 volumio volumio[706]: info: sendMpdCommand status took 76 milliseconds May 20 19:07:14 volumio volumio[706]: verbose: ControllerMpd::parseTrackInfo May 20 19:07:14 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:07:14 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 19:07:14 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:07:14 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:07:14 volumio volumio[706]: info: CorePlayQueue::getTrack 3 May 20 19:07:14 volumio volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":260,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"04 Rumores De La Caleta Op71 (Albeniz).ape","artist":null,"album":null,"uri":"USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/04 Rumores De La Caleta Op71 (Albeniz).ape","trackType":"ape"} May 20 19:07:14 volumio volumio[706]: verbose: CURRENT POSITION 3 May 20 19:07:14 volumio volumio[706]: info: CoreStateMachine::syncState stateService play May 20 19:07:14 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus stop May 20 19:07:14 volumio volumio[706]: info: ------------------------------ 287ms May 20 19:07:14 volumio volumio[706]: info: sendMpdCommand playlistinfo took 178 milliseconds May 20 19:07:14 volumio volumio[706]: info: sendMpdCommand playlistinfo took 185 milliseconds May 20 19:07:14 volumio volumio[706]: info: sendMpdCommand playlistinfo took 100 milliseconds May 20 19:07:14 volumio volumio[706]: verbose: ControllerMpd::parseTrackInfo May 20 19:07:14 volumio volumio[706]: verbose: ControllerMpd::parseTrackInfo May 20 19:07:14 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:07:14 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:07:14 volumio volumio[706]: info: CorePlayQueue::getTrack 3 May 20 19:07:14 volumio volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":260,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"04 Rumores De La Caleta Op71 (Albeniz).ape","artist":null,"album":null,"uri":"USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/04 Rumores De La Caleta Op71 (Albeniz).ape","trackType":"ape"} May 20 19:07:14 volumio volumio[706]: verbose: CURRENT POSITION 3 May 20 19:07:14 volumio volumio[706]: info: CoreStateMachine::syncState stateService play May 20 19:07:14 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus play May 20 19:07:14 volumio volumio[706]: info: Received an update from plugin. extracting info from payload May 20 19:07:14 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:14 volumio volumio[706]: info: CorePlayQueue::getTrack 3 May 20 19:07:14 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:07:14 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:14 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:14 volumio volumio[706]: info: CorePlayQueue::getTrack 3 May 20 19:07:14 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:14 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:07:14 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:07:14 volumio volumio[706]: info: CorePlayQueue::getTrack 3 May 20 19:07:14 volumio volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":260,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"04 Rumores De La Caleta Op71 (Albeniz).ape","artist":null,"album":null,"uri":"USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/04 Rumores De La Caleta Op71 (Albeniz).ape","trackType":"ape"} May 20 19:07:14 volumio volumio[706]: verbose: CURRENT POSITION 3 May 20 19:07:14 volumio volumio[706]: info: CoreStateMachine::syncState stateService play May 20 19:07:14 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus play May 20 19:07:14 volumio volumio[706]: info: Received an update from plugin. extracting info from payload May 20 19:07:14 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:14 volumio volumio[706]: info: CorePlayQueue::getTrack 3 May 20 19:07:14 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:14 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:14 volumio volumio[706]: info: CorePlayQueue::getTrack 3 May 20 19:07:14 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:14 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:07:14 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:07:14 volumio volumio[706]: info: CorePlayQueue::getTrack 3 May 20 19:07:14 volumio volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":260,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"04 Rumores De La Caleta Op71 (Albeniz).ape","artist":null,"album":null,"uri":"USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/04 Rumores De La Caleta Op71 (Albeniz).ape","trackType":"ape"} May 20 19:07:14 volumio volumio[706]: verbose: CURRENT POSITION 3 May 20 19:07:14 volumio volumio[706]: info: CoreStateMachine::syncState stateService play May 20 19:07:14 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus play May 20 19:07:14 volumio volumio[706]: info: Received an update from plugin. extracting info from payload May 20 19:07:14 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:14 volumio volumio[706]: info: CorePlayQueue::getTrack 3 May 20 19:07:14 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:14 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:14 volumio volumio[706]: info: CorePlayQueue::getTrack 3 May 20 19:07:14 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:14 volumio mount.exfat[2990]: failed to read cluster 0x15849 May 20 19:07:14 volumio kernel: I/O error, dev sda, sector 22601664 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 May 20 19:07:14 volumio kernel: I/O error, dev sda, sector 22601664 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:07:14 volumio kernel: Buffer I/O error on dev sda1, logical block 2821112, async page read May 20 19:07:15 volumio volumio[706]: info: ------------------------------ 1491ms May 20 19:07:15 volumio volumio[706]: info: ------------------------------ 1491ms May 20 19:07:15 volumio volumio[706]: info: ------------------------------ 1463ms May 20 19:07:15 volumio kernel: I/O error, dev sda, sector 22601920 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 May 20 19:07:15 volumio kernel: I/O error, dev sda, sector 22601920 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:07:15 volumio kernel: Buffer I/O error on dev sda1, logical block 2821144, async page read May 20 19:07:15 volumio kernel: I/O error, dev sda, sector 22602176 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 May 20 19:07:15 volumio kernel: I/O error, dev sda, sector 22602176 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:07:15 volumio kernel: Buffer I/O error on dev sda1, logical block 2821176, async page read May 20 19:07:15 volumio mount.exfat[2990]: failed to read cluster 0x1584a May 20 19:07:15 volumio mount.exfat[2990]: failed to read cluster 0x1584b May 20 19:07:16 volumio mount.exfat[2990]: failed to read cluster 0x15849 May 20 19:07:16 volumio kernel: I/O error, dev sda, sector 22601664 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:07:16 volumio kernel: Buffer I/O error on dev sda1, logical block 2821112, async page read May 20 19:07:16 volumio kernel: I/O error, dev sda, sector 22601664 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:07:16 volumio kernel: Buffer I/O error on dev sda1, logical block 2821112, async page read May 20 19:07:16 volumio kernel: I/O error, dev sda, sector 22601664 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:07:16 volumio kernel: Buffer I/O error on dev sda1, logical block 2821112, async page read May 20 19:07:16 volumio mount.exfat[2990]: failed to read cluster 0x15849 May 20 19:07:16 volumio mount.exfat[2990]: failed to read cluster 0x15849 May 20 19:07:21 volumio volumio[706]: info: May 20 19:07:21 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:07:21 volumio volumio[706]: info: ControllerMpd::getState May 20 19:07:21 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:07:21 volumio volumio[706]: info: May 20 19:07:21 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:07:21 volumio volumio[706]: info: ControllerMpd::getState May 20 19:07:21 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:07:21 volumio volumio[706]: info: sendMpdCommand status took 59 milliseconds May 20 19:07:21 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:07:21 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:07:21 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:07:21 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:21 volumio volumio[706]: info: CorePlayQueue::getTrack 3 May 20 19:07:21 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:07:21 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:21 volumio volumio[706]: info: CorePlayQueue::getTrack 3 May 20 19:07:21 volumio volumio[706]: 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 20 19:07:21 volumio volumio[706]: verbose: CURRENT POSITION 3 May 20 19:07:21 volumio volumio[706]: info: CoreStateMachine::syncState stateService stop May 20 19:07:21 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus play May 20 19:07:21 volumio volumio[706]: info: CoreStateMachine::play index undefined May 20 19:07:21 volumio volumio[706]: info: CoreStateMachine::setConsumeUpdateService undefined May 20 19:07:21 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:21 volumio volumio[706]: info: CorePlayQueue::getTrack 4 May 20 19:07:21 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:07:21 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:21 volumio volumio[706]: info: CorePlayQueue::getTrack 4 May 20 19:07:21 volumio volumio[706]: info: CoreStateMachine::startPlaybackTimer May 20 19:07:21 volumio volumio[706]: info: CorePlayQueue::getTrack 4 May 20 19:07:21 volumio volumio[706]: verbose: ControllerMpd::clearAddPlayTracks USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/05 Torre Bermeja Op92 (Albeniz).ape May 20 19:07:21 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand stop May 20 19:07:21 volumio volumio[706]: info: ------------------------------ 242ms May 20 19:07:21 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:21 volumio volumio[706]: info: CorePlayQueue::getTrack 4 May 20 19:07:21 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:07:21 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:21 volumio volumio[706]: info: sendMpdCommand status took 260 milliseconds May 20 19:07:21 volumio volumio[706]: info: sendMpdCommand stop took 80 milliseconds May 20 19:07:21 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:07:21 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand clear May 20 19:07:21 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:07:21 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:07:21 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:21 volumio volumio[706]: info: CorePlayQueue::getTrack 4 May 20 19:07:21 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:21 volumio volumio[706]: info: CorePlayQueue::getTrack 4 May 20 19:07:21 volumio volumio[706]: 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 20 19:07:21 volumio volumio[706]: verbose: CURRENT POSITION 4 May 20 19:07:21 volumio volumio[706]: info: CoreStateMachine::syncState stateService stop May 20 19:07:21 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus stop May 20 19:07:21 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:21 volumio volumio[706]: info: CorePlayQueue::getTrack 4 May 20 19:07:21 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:21 volumio volumio[706]: info: No code May 20 19:07:21 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:21 volumio volumio[706]: info: CorePlayQueue::getTrack 4 May 20 19:07:21 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:21 volumio volumio[706]: info: ------------------------------ 517ms May 20 19:07:21 volumio volumio[706]: info: May 20 19:07:21 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:07:21 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:07:21 volumio volumio[706]: info: sendMpdCommand clear took 275 milliseconds May 20 19:07:21 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand add "USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/05 Torre Bermeja Op92 (Albeniz).ape" May 20 19:07:21 volumio volumio[706]: info: May 20 19:07:21 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:07:21 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:07:21 volumio volumio[706]: info: CoreCommandRouter::getUIConfigOnPlugin May 20 19:07:21 volumio volumio[706]: error: updateQueue error: null May 20 19:07:21 volumio volumio[706]: info: May 20 19:07:21 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:07:21 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:07:21 volumio volumio[706]: info: ------------------------------ 95ms May 20 19:07:21 volumio volumio[706]: info: sendMpdCommand add "USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/05 Torre Bermeja Op92 (Albeniz).ape" took 88 milliseconds May 20 19:07:21 volumio volumio[706]: info: ------------------------------ 82ms May 20 19:07:21 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand play May 20 19:07:21 volumio volumio[706]: info: May 20 19:07:21 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:07:21 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:07:21 volumio volumio[706]: info: May 20 19:07:21 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:07:21 volumio volumio[706]: info: ------------------------------ 113ms May 20 19:07:21 volumio volumio[706]: info: sendMpdCommand play took 102 milliseconds May 20 19:07:21 volumio volumio[706]: info: ------------------------------ 95ms May 20 19:07:21 volumio volumio[706]: info: ControllerMpd::getState May 20 19:07:21 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:07:21 volumio volumio[706]: info: May 20 19:07:21 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:07:22 volumio volumio[706]: info: ControllerMpd::getState May 20 19:07:22 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:07:22 volumio volumio[706]: info: May 20 19:07:22 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:07:22 volumio volumio[706]: info: ControllerMpd::getState May 20 19:07:22 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:07:22 volumio volumio[706]: info: sendMpdCommand status took 90 milliseconds May 20 19:07:22 volumio volumio[706]: info: sendMpdCommand status took 72 milliseconds May 20 19:07:22 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:07:22 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 19:07:22 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:07:22 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 19:07:22 volumio volumio[706]: info: May 20 19:07:22 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:07:22 volumio volumio[706]: info: ControllerMpd::getState May 20 19:07:22 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:07:22 volumio volumio[706]: info: sendMpdCommand status took 250 milliseconds May 20 19:07:22 volumio volumio[706]: info: sendMpdCommand playlistinfo took 211 milliseconds May 20 19:07:22 volumio volumio[706]: info: sendMpdCommand playlistinfo took 238 milliseconds May 20 19:07:22 volumio volumio[706]: info: sendMpdCommand status took 153 milliseconds May 20 19:07:22 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:07:22 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 19:07:22 volumio volumio[706]: verbose: ControllerMpd::parseTrackInfo May 20 19:07:22 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:07:22 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 19:07:22 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:07:22 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:07:22 volumio volumio[706]: info: CorePlayQueue::getTrack 4 May 20 19:07:22 volumio volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"05 Torre Bermeja Op92 (Albeniz).ape","artist":null,"album":null,"uri":"USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/05 Torre Bermeja Op92 (Albeniz).ape","trackType":"ape"} May 20 19:07:22 volumio volumio[706]: verbose: CURRENT POSITION 4 May 20 19:07:22 volumio volumio[706]: info: CoreStateMachine::syncState stateService play May 20 19:07:22 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus stop May 20 19:07:22 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:07:22 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:07:22 volumio volumio[706]: info: CorePlayQueue::getTrack 4 May 20 19:07:22 volumio volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"05 Torre Bermeja Op92 (Albeniz).ape","artist":null,"album":null,"uri":"USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/05 Torre Bermeja Op92 (Albeniz).ape","trackType":"ape"} May 20 19:07:22 volumio volumio[706]: verbose: CURRENT POSITION 4 May 20 19:07:22 volumio volumio[706]: info: CoreStateMachine::syncState stateService play May 20 19:07:22 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus play May 20 19:07:22 volumio volumio[706]: info: Received an update from plugin. extracting info from payload May 20 19:07:22 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:22 volumio volumio[706]: info: CorePlayQueue::getTrack 4 May 20 19:07:22 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:07:22 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:22 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:22 volumio volumio[706]: info: CorePlayQueue::getTrack 4 May 20 19:07:22 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:22 volumio volumio[706]: info: ------------------------------ 933ms May 20 19:07:23 volumio volumio[706]: info: ------------------------------ 1073ms May 20 19:07:23 volumio mount.exfat[2990]: failed to read cluster 0x158b5 May 20 19:07:23 volumio kernel: I/O error, dev sda, sector 22629312 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 May 20 19:07:23 volumio kernel: I/O error, dev sda, sector 22629312 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:07:23 volumio kernel: Buffer I/O error on dev sda1, logical block 2824568, async page read May 20 19:07:23 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 20 19:07:23 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 19:07:23 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 20 19:07:23 volumio volumio[706]: info: Getting Alsa Cards List without I2S DAC May 20 19:07:24 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber May 20 19:07:24 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 19:07:25 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 20 19:07:25 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 20 19:07:25 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 20 19:07:25 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 20 19:07:25 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 20 19:07:25 volumio mount.exfat[2990]: failed to read cluster 0x158b6 May 20 19:07:25 volumio kernel: I/O error, dev sda, sector 22629568 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 May 20 19:07:25 volumio kernel: I/O error, dev sda, sector 22629568 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:07:25 volumio kernel: Buffer I/O error on dev sda1, logical block 2824600, async page read May 20 19:07:25 volumio kernel: I/O error, dev sda, sector 22629824 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 May 20 19:07:25 volumio kernel: I/O error, dev sda, sector 22629824 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:07:25 volumio kernel: Buffer I/O error on dev sda1, logical block 2824632, async page read May 20 19:07:25 volumio volumio[706]: info: sendMpdCommand playlistinfo took 2827 milliseconds May 20 19:07:25 volumio mount.exfat[2990]: failed to read cluster 0x158b7 May 20 19:07:25 volumio volumio[706]: info: sendMpdCommand playlistinfo took 2824 milliseconds May 20 19:07:25 volumio volumio[706]: verbose: ControllerMpd::parseTrackInfo May 20 19:07:25 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:07:25 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:07:25 volumio volumio[706]: info: CorePlayQueue::getTrack 4 May 20 19:07:25 volumio volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"05 Torre Bermeja Op92 (Albeniz).ape","artist":null,"album":null,"uri":"USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/05 Torre Bermeja Op92 (Albeniz).ape","trackType":"ape"} May 20 19:07:25 volumio volumio[706]: verbose: CURRENT POSITION 4 May 20 19:07:25 volumio volumio[706]: info: CoreStateMachine::syncState stateService play May 20 19:07:25 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus play May 20 19:07:25 volumio volumio[706]: info: Received an update from plugin. extracting info from payload May 20 19:07:25 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:25 volumio volumio[706]: info: CorePlayQueue::getTrack 4 May 20 19:07:25 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:25 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:25 volumio volumio[706]: info: CorePlayQueue::getTrack 4 May 20 19:07:25 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:25 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:07:25 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:07:25 volumio volumio[706]: info: CorePlayQueue::getTrack 4 May 20 19:07:25 volumio volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"05 Torre Bermeja Op92 (Albeniz).ape","artist":null,"album":null,"uri":"USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/05 Torre Bermeja Op92 (Albeniz).ape","trackType":"ape"} May 20 19:07:25 volumio volumio[706]: verbose: CURRENT POSITION 4 May 20 19:07:25 volumio volumio[706]: info: CoreStateMachine::syncState stateService play May 20 19:07:25 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus play May 20 19:07:25 volumio volumio[706]: info: Received an update from plugin. extracting info from payload May 20 19:07:25 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:25 volumio volumio[706]: info: CorePlayQueue::getTrack 4 May 20 19:07:25 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:25 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:25 volumio volumio[706]: info: CorePlayQueue::getTrack 4 May 20 19:07:25 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:25 volumio volumio[706]: info: ------------------------------ 3634ms May 20 19:07:25 volumio volumio[706]: info: ------------------------------ 3524ms May 20 19:07:25 volumio volumio[706]: Upnp client error: Error: This socket has been ended by the other party May 20 19:07:26 volumio mount.exfat[2990]: failed to read cluster 0x158b5 May 20 19:07:26 volumio mount.exfat[2990]: failed to read cluster 0x158b5 May 20 19:07:26 volumio mount.exfat[2990]: failed to read cluster 0x158b5 May 20 19:07:26 volumio kernel: I/O error, dev sda, sector 22629312 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:07:26 volumio kernel: Buffer I/O error on dev sda1, logical block 2824568, async page read May 20 19:07:26 volumio kernel: I/O error, dev sda, sector 22629312 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:07:26 volumio kernel: Buffer I/O error on dev sda1, logical block 2824568, async page read May 20 19:07:26 volumio kernel: I/O error, dev sda, sector 22629312 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:07:26 volumio kernel: Buffer I/O error on dev sda1, logical block 2824568, async page read May 20 19:07:32 volumio volumio[706]: info: May 20 19:07:32 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:07:32 volumio volumio[706]: info: ControllerMpd::getState May 20 19:07:32 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:07:32 volumio volumio[706]: info: May 20 19:07:32 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:07:32 volumio volumio[706]: info: ControllerMpd::getState May 20 19:07:32 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:07:32 volumio volumio[706]: info: sendMpdCommand status took 21 milliseconds May 20 19:07:32 volumio volumio[706]: info: sendMpdCommand status took 24 milliseconds May 20 19:07:32 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:07:32 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:07:32 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:07:32 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:32 volumio volumio[706]: info: CorePlayQueue::getTrack 4 May 20 19:07:32 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:07:32 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:32 volumio volumio[706]: info: CorePlayQueue::getTrack 4 May 20 19:07:32 volumio volumio[706]: 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 20 19:07:32 volumio volumio[706]: verbose: CURRENT POSITION 4 May 20 19:07:32 volumio volumio[706]: info: CoreStateMachine::syncState stateService stop May 20 19:07:32 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus play May 20 19:07:32 volumio volumio[706]: info: CoreStateMachine::play index undefined May 20 19:07:32 volumio volumio[706]: info: CoreStateMachine::setConsumeUpdateService undefined May 20 19:07:32 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:32 volumio volumio[706]: info: CorePlayQueue::getTrack 5 May 20 19:07:32 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:07:32 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:32 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:07:32 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:07:32 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:32 volumio volumio[706]: info: CorePlayQueue::getTrack 5 May 20 19:07:32 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:32 volumio volumio[706]: info: CorePlayQueue::getTrack 5 May 20 19:07:32 volumio volumio[706]: 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 20 19:07:32 volumio volumio[706]: verbose: CURRENT POSITION 5 May 20 19:07:32 volumio volumio[706]: info: CoreStateMachine::syncState stateService stop May 20 19:07:32 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus stop May 20 19:07:32 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:32 volumio volumio[706]: info: CorePlayQueue::getTrack 5 May 20 19:07:32 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:32 volumio volumio[706]: info: No code May 20 19:07:32 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:32 volumio volumio[706]: info: CorePlayQueue::getTrack 5 May 20 19:07:32 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:33 volumio volumio[706]: info: CorePlayQueue::getTrack 5 May 20 19:07:33 volumio volumio[706]: info: CoreStateMachine::startPlaybackTimer May 20 19:07:33 volumio volumio[706]: info: CorePlayQueue::getTrack 5 May 20 19:07:33 volumio volumio[706]: verbose: ControllerMpd::clearAddPlayTracks USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/06 Madronos (Torroba).ape May 20 19:07:33 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand stop May 20 19:07:33 volumio volumio[706]: info: ------------------------------ 515ms May 20 19:07:33 volumio volumio[706]: info: ------------------------------ 518ms May 20 19:07:33 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:33 volumio volumio[706]: info: CorePlayQueue::getTrack 5 May 20 19:07:33 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:07:33 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:33 volumio volumio[706]: info: sendMpdCommand stop took 245 milliseconds May 20 19:07:33 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand clear May 20 19:07:33 volumio volumio[706]: info: May 20 19:07:33 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:07:33 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:07:33 volumio volumio[706]: info: sendMpdCommand clear took 149 milliseconds May 20 19:07:33 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand add "USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/06 Madronos (Torroba).ape" May 20 19:07:33 volumio volumio[706]: info: May 20 19:07:33 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:07:33 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:07:33 volumio volumio[706]: error: updateQueue error: null May 20 19:07:33 volumio volumio[706]: info: May 20 19:07:33 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:07:33 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:07:33 volumio volumio[706]: info: ------------------------------ 50ms May 20 19:07:33 volumio volumio[706]: info: sendMpdCommand add "USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/06 Madronos (Torroba).ape" took 32 milliseconds May 20 19:07:33 volumio volumio[706]: info: ------------------------------ 25ms May 20 19:07:33 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand play May 20 19:07:33 volumio volumio[706]: info: May 20 19:07:33 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:07:33 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:07:33 volumio volumio[706]: info: ------------------------------ 39ms May 20 19:07:33 volumio volumio[706]: info: sendMpdCommand play took 34 milliseconds May 20 19:07:33 volumio volumio[706]: info: ------------------------------ 28ms May 20 19:07:33 volumio volumio[706]: info: May 20 19:07:33 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:07:33 volumio volumio[706]: info: ControllerMpd::getState May 20 19:07:33 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:07:33 volumio volumio[706]: info: May 20 19:07:33 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:07:33 volumio volumio[706]: info: ControllerMpd::getState May 20 19:07:33 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:07:33 volumio volumio[706]: info: sendMpdCommand status took 32 milliseconds May 20 19:07:33 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:07:33 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 19:07:33 volumio volumio[706]: info: sendMpdCommand status took 51 milliseconds May 20 19:07:33 volumio volumio[706]: info: sendMpdCommand playlistinfo took 44 milliseconds May 20 19:07:33 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:07:33 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 19:07:33 volumio volumio[706]: verbose: ControllerMpd::parseTrackInfo May 20 19:07:33 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:07:33 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:07:33 volumio volumio[706]: info: CorePlayQueue::getTrack 5 May 20 19:07:33 volumio volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":173,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"06 Madronos (Torroba).ape","artist":null,"album":null,"uri":"USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/06 Madronos (Torroba).ape","trackType":"ape"} May 20 19:07:33 volumio volumio[706]: verbose: CURRENT POSITION 5 May 20 19:07:33 volumio volumio[706]: info: CoreStateMachine::syncState stateService play May 20 19:07:33 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus stop May 20 19:07:33 volumio volumio[706]: info: ------------------------------ 116ms May 20 19:07:33 volumio volumio[706]: info: sendMpdCommand playlistinfo took 52 milliseconds May 20 19:07:33 volumio volumio[706]: verbose: ControllerMpd::parseTrackInfo May 20 19:07:33 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:07:33 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:07:33 volumio volumio[706]: info: CorePlayQueue::getTrack 5 May 20 19:07:33 volumio volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":173,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"06 Madronos (Torroba).ape","artist":null,"album":null,"uri":"USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/06 Madronos (Torroba).ape","trackType":"ape"} May 20 19:07:33 volumio volumio[706]: verbose: CURRENT POSITION 5 May 20 19:07:33 volumio volumio[706]: info: CoreStateMachine::syncState stateService play May 20 19:07:33 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus play May 20 19:07:33 volumio volumio[706]: info: Received an update from plugin. extracting info from payload May 20 19:07:33 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:33 volumio volumio[706]: info: CorePlayQueue::getTrack 5 May 20 19:07:33 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:07:33 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:33 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:33 volumio volumio[706]: info: CorePlayQueue::getTrack 5 May 20 19:07:33 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:34 volumio volumio[706]: info: ------------------------------ 400ms May 20 19:07:34 volumio mount.exfat[2990]: failed to read cluster 0x15923 May 20 19:07:34 volumio kernel: I/O error, dev sda, sector 22657472 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 May 20 19:07:34 volumio kernel: I/O error, dev sda, sector 22657472 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:07:34 volumio kernel: Buffer I/O error on dev sda1, logical block 2828088, async page read May 20 19:07:35 volumio mount.exfat[2990]: failed to read cluster 0x15924 May 20 19:07:35 volumio kernel: I/O error, dev sda, sector 22657728 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 May 20 19:07:35 volumio kernel: I/O error, dev sda, sector 22657728 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:07:35 volumio kernel: Buffer I/O error on dev sda1, logical block 2828120, async page read May 20 19:07:35 volumio kernel: I/O error, dev sda, sector 22657984 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 May 20 19:07:35 volumio kernel: I/O error, dev sda, sector 22657984 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:07:35 volumio kernel: Buffer I/O error on dev sda1, logical block 2828152, async page read May 20 19:07:35 volumio mount.exfat[2990]: failed to read cluster 0x15925 May 20 19:07:36 volumio mount.exfat[2990]: failed to read cluster 0x15923 May 20 19:07:36 volumio kernel: I/O error, dev sda, sector 22657472 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:07:36 volumio kernel: Buffer I/O error on dev sda1, logical block 2828088, async page read May 20 19:07:36 volumio kernel: I/O error, dev sda, sector 22657472 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:07:36 volumio kernel: Buffer I/O error on dev sda1, logical block 2828088, async page read May 20 19:07:36 volumio kernel: I/O error, dev sda, sector 22657472 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:07:36 volumio kernel: Buffer I/O error on dev sda1, logical block 2828088, async page read May 20 19:07:36 volumio mount.exfat[2990]: failed to read cluster 0x15923 May 20 19:07:36 volumio mount.exfat[2990]: failed to read cluster 0x15923 May 20 19:07:44 volumio volumio[706]: info: May 20 19:07:44 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:07:44 volumio volumio[706]: info: ControllerMpd::getState May 20 19:07:44 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:07:44 volumio volumio[706]: info: May 20 19:07:44 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:07:44 volumio volumio[706]: info: ControllerMpd::getState May 20 19:07:44 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:07:44 volumio volumio[706]: info: sendMpdCommand status took 26 milliseconds May 20 19:07:44 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:07:44 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:07:44 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:07:44 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:44 volumio volumio[706]: info: CorePlayQueue::getTrack 5 May 20 19:07:44 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:07:44 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:44 volumio volumio[706]: info: CorePlayQueue::getTrack 5 May 20 19:07:44 volumio volumio[706]: 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 20 19:07:44 volumio volumio[706]: verbose: CURRENT POSITION 5 May 20 19:07:44 volumio volumio[706]: info: CoreStateMachine::syncState stateService stop May 20 19:07:44 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus play May 20 19:07:44 volumio volumio[706]: info: CoreStateMachine::play index undefined May 20 19:07:44 volumio volumio[706]: info: CoreStateMachine::setConsumeUpdateService undefined May 20 19:07:44 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:44 volumio volumio[706]: info: CorePlayQueue::getTrack 6 May 20 19:07:44 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:07:44 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:44 volumio volumio[706]: info: CorePlayQueue::getTrack 6 May 20 19:07:44 volumio volumio[706]: info: CoreStateMachine::startPlaybackTimer May 20 19:07:44 volumio volumio[706]: info: CorePlayQueue::getTrack 6 May 20 19:07:44 volumio volumio[706]: verbose: ControllerMpd::clearAddPlayTracks USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/07 Capricho Arabe (Tarrega).ape May 20 19:07:44 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand stop May 20 19:07:44 volumio volumio[706]: info: ------------------------------ 220ms May 20 19:07:44 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:44 volumio volumio[706]: info: CorePlayQueue::getTrack 6 May 20 19:07:44 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:07:44 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:44 volumio volumio[706]: info: sendMpdCommand status took 285 milliseconds May 20 19:07:44 volumio volumio[706]: info: sendMpdCommand stop took 93 milliseconds May 20 19:07:44 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:07:44 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand clear May 20 19:07:44 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:07:44 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:07:44 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:44 volumio volumio[706]: info: CorePlayQueue::getTrack 6 May 20 19:07:44 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:07:44 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:44 volumio volumio[706]: info: CorePlayQueue::getTrack 6 May 20 19:07:44 volumio volumio[706]: 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 20 19:07:44 volumio volumio[706]: verbose: CURRENT POSITION 6 May 20 19:07:44 volumio volumio[706]: info: CoreStateMachine::syncState stateService stop May 20 19:07:44 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus stop May 20 19:07:44 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:44 volumio volumio[706]: info: CorePlayQueue::getTrack 6 May 20 19:07:44 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:44 volumio volumio[706]: info: No code May 20 19:07:44 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:44 volumio volumio[706]: info: CorePlayQueue::getTrack 6 May 20 19:07:44 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:44 volumio volumio[706]: info: ------------------------------ 566ms May 20 19:07:44 volumio volumio[706]: info: May 20 19:07:44 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:07:44 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:07:44 volumio volumio[706]: info: sendMpdCommand clear took 355 milliseconds May 20 19:07:44 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand add "USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/07 Capricho Arabe (Tarrega).ape" May 20 19:07:44 volumio volumio[706]: info: May 20 19:07:44 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:07:44 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:07:44 volumio volumio[706]: error: updateQueue error: null May 20 19:07:44 volumio volumio[706]: info: May 20 19:07:44 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:07:44 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:07:44 volumio volumio[706]: info: ------------------------------ 35ms May 20 19:07:44 volumio volumio[706]: info: sendMpdCommand add "USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/07 Capricho Arabe (Tarrega).ape" took 27 milliseconds May 20 19:07:44 volumio volumio[706]: info: ------------------------------ 23ms May 20 19:07:44 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand play May 20 19:07:44 volumio volumio[706]: info: May 20 19:07:44 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:07:44 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:07:44 volumio volumio[706]: info: ------------------------------ 36ms May 20 19:07:45 volumio volumio[706]: info: sendMpdCommand play took 25 milliseconds May 20 19:07:45 volumio volumio[706]: info: May 20 19:07:45 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:07:45 volumio volumio[706]: info: ------------------------------ 44ms May 20 19:07:45 volumio volumio[706]: info: ControllerMpd::getState May 20 19:07:45 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:07:45 volumio volumio[706]: info: May 20 19:07:45 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:07:45 volumio volumio[706]: info: ControllerMpd::getState May 20 19:07:45 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:07:45 volumio volumio[706]: info: May 20 19:07:45 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:07:45 volumio volumio[706]: info: ControllerMpd::getState May 20 19:07:45 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:07:45 volumio volumio[706]: info: sendMpdCommand status took 43 milliseconds May 20 19:07:45 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:07:45 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 19:07:45 volumio volumio[706]: info: sendMpdCommand status took 34 milliseconds May 20 19:07:45 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:07:45 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 19:07:45 volumio volumio[706]: info: May 20 19:07:45 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:07:45 volumio volumio[706]: info: ControllerMpd::getState May 20 19:07:45 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:07:45 volumio volumio[706]: info: sendMpdCommand status took 78 milliseconds May 20 19:07:45 volumio volumio[706]: info: sendMpdCommand playlistinfo took 80 milliseconds May 20 19:07:45 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:07:45 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 19:07:45 volumio volumio[706]: verbose: ControllerMpd::parseTrackInfo May 20 19:07:45 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:07:45 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:07:45 volumio volumio[706]: info: CorePlayQueue::getTrack 6 May 20 19:07:45 volumio volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":322,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"07 Capricho Arabe (Tarrega).ape","artist":null,"album":null,"uri":"USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/07 Capricho Arabe (Tarrega).ape","trackType":"ape"} May 20 19:07:45 volumio volumio[706]: verbose: CURRENT POSITION 6 May 20 19:07:45 volumio volumio[706]: info: CoreStateMachine::syncState stateService play May 20 19:07:45 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus stop May 20 19:07:45 volumio volumio[706]: info: ------------------------------ 165ms May 20 19:07:45 volumio volumio[706]: info: sendMpdCommand playlistinfo took 106 milliseconds May 20 19:07:45 volumio volumio[706]: info: sendMpdCommand status took 92 milliseconds May 20 19:07:45 volumio volumio[706]: info: sendMpdCommand playlistinfo took 53 milliseconds May 20 19:07:45 volumio volumio[706]: verbose: ControllerMpd::parseTrackInfo May 20 19:07:45 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:07:45 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 19:07:45 volumio volumio[706]: verbose: ControllerMpd::parseTrackInfo May 20 19:07:45 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:07:45 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:07:45 volumio volumio[706]: info: CorePlayQueue::getTrack 6 May 20 19:07:45 volumio volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":322,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"07 Capricho Arabe (Tarrega).ape","artist":null,"album":null,"uri":"USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/07 Capricho Arabe (Tarrega).ape","trackType":"ape"} May 20 19:07:45 volumio volumio[706]: verbose: CURRENT POSITION 6 May 20 19:07:45 volumio volumio[706]: info: CoreStateMachine::syncState stateService play May 20 19:07:45 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus play May 20 19:07:45 volumio volumio[706]: info: Received an update from plugin. extracting info from payload May 20 19:07:45 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:45 volumio volumio[706]: info: CorePlayQueue::getTrack 6 May 20 19:07:45 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:07:45 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:45 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:45 volumio volumio[706]: info: CorePlayQueue::getTrack 6 May 20 19:07:45 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:45 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:07:45 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:07:45 volumio volumio[706]: info: CorePlayQueue::getTrack 6 May 20 19:07:45 volumio volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":322,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"07 Capricho Arabe (Tarrega).ape","artist":null,"album":null,"uri":"USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/07 Capricho Arabe (Tarrega).ape","trackType":"ape"} May 20 19:07:45 volumio volumio[706]: verbose: CURRENT POSITION 6 May 20 19:07:45 volumio volumio[706]: info: CoreStateMachine::syncState stateService play May 20 19:07:45 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus play May 20 19:07:45 volumio volumio[706]: info: Received an update from plugin. extracting info from payload May 20 19:07:45 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:45 volumio volumio[706]: info: CorePlayQueue::getTrack 6 May 20 19:07:45 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:45 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:45 volumio volumio[706]: info: CorePlayQueue::getTrack 6 May 20 19:07:45 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:45 volumio volumio[706]: info: ------------------------------ 702ms May 20 19:07:45 volumio volumio[706]: info: ------------------------------ 805ms May 20 19:07:45 volumio volumio[706]: info: sendMpdCommand playlistinfo took 626 milliseconds May 20 19:07:45 volumio volumio[706]: verbose: ControllerMpd::parseTrackInfo May 20 19:07:45 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:07:45 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:07:45 volumio volumio[706]: info: CorePlayQueue::getTrack 6 May 20 19:07:45 volumio volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":322,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"07 Capricho Arabe (Tarrega).ape","artist":null,"album":null,"uri":"USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/07 Capricho Arabe (Tarrega).ape","trackType":"ape"} May 20 19:07:45 volumio volumio[706]: verbose: CURRENT POSITION 6 May 20 19:07:45 volumio volumio[706]: info: CoreStateMachine::syncState stateService play May 20 19:07:45 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus play May 20 19:07:45 volumio volumio[706]: info: Received an update from plugin. extracting info from payload May 20 19:07:45 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:45 volumio volumio[706]: info: CorePlayQueue::getTrack 6 May 20 19:07:45 volumio kernel: I/O error, dev sda, sector 22676160 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 May 20 19:07:45 volumio kernel: I/O error, dev sda, sector 22676160 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:07:45 volumio kernel: Buffer I/O error on dev sda1, logical block 2830424, async page read May 20 19:07:45 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:45 volumio mount.exfat[2990]: failed to read cluster 0x1596c May 20 19:07:46 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:46 volumio volumio[706]: info: CorePlayQueue::getTrack 6 May 20 19:07:46 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:46 volumio volumio[706]: info: ------------------------------ 1107ms May 20 19:07:46 volumio mount.exfat[2990]: failed to read cluster 0x1596d May 20 19:07:46 volumio kernel: I/O error, dev sda, sector 22676416 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 May 20 19:07:46 volumio kernel: I/O error, dev sda, sector 22676416 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:07:46 volumio kernel: Buffer I/O error on dev sda1, logical block 2830456, async page read May 20 19:07:46 volumio kernel: I/O error, dev sda, sector 22676672 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 May 20 19:07:46 volumio kernel: I/O error, dev sda, sector 22676672 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:07:46 volumio kernel: Buffer I/O error on dev sda1, logical block 2830488, async page read May 20 19:07:46 volumio mount.exfat[2990]: failed to read cluster 0x1596e May 20 19:07:47 volumio mount.exfat[2990]: failed to read cluster 0x1596c May 20 19:07:47 volumio kernel: I/O error, dev sda, sector 22676160 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:07:47 volumio kernel: Buffer I/O error on dev sda1, logical block 2830424, async page read May 20 19:07:47 volumio kernel: I/O error, dev sda, sector 22676160 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:07:47 volumio kernel: Buffer I/O error on dev sda1, logical block 2830424, async page read May 20 19:07:47 volumio kernel: I/O error, dev sda, sector 22676160 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:07:47 volumio kernel: Buffer I/O error on dev sda1, logical block 2830424, async page read May 20 19:07:47 volumio mount.exfat[2990]: failed to read cluster 0x1596c May 20 19:07:47 volumio mount.exfat[2990]: failed to read cluster 0x1596c May 20 19:07:52 volumio volumio[706]: info: May 20 19:07:52 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:07:52 volumio volumio[706]: info: ControllerMpd::getState May 20 19:07:52 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:07:52 volumio volumio[706]: info: May 20 19:07:52 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:07:52 volumio volumio[706]: info: ControllerMpd::getState May 20 19:07:52 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:07:52 volumio volumio[706]: info: sendMpdCommand status took 32 milliseconds May 20 19:07:52 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:07:52 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:07:52 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:07:52 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:52 volumio volumio[706]: info: CorePlayQueue::getTrack 6 May 20 19:07:52 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:07:52 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:52 volumio volumio[706]: info: CorePlayQueue::getTrack 6 May 20 19:07:52 volumio volumio[706]: 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 20 19:07:52 volumio volumio[706]: verbose: CURRENT POSITION 6 May 20 19:07:52 volumio volumio[706]: info: CoreStateMachine::syncState stateService stop May 20 19:07:52 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus play May 20 19:07:52 volumio volumio[706]: info: CoreStateMachine::play index undefined May 20 19:07:52 volumio volumio[706]: info: CoreStateMachine::setConsumeUpdateService undefined May 20 19:07:52 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:52 volumio volumio[706]: info: CorePlayQueue::getTrack 7 May 20 19:07:52 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:07:52 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:52 volumio volumio[706]: info: CorePlayQueue::getTrack 7 May 20 19:07:52 volumio volumio[706]: info: CoreStateMachine::startPlaybackTimer May 20 19:07:52 volumio volumio[706]: info: CorePlayQueue::getTrack 7 May 20 19:07:52 volumio volumio[706]: verbose: ControllerMpd::clearAddPlayTracks USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/08 La Alborada (Tarrega).ape May 20 19:07:52 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand stop May 20 19:07:52 volumio volumio[706]: info: ------------------------------ 243ms May 20 19:07:52 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:52 volumio volumio[706]: info: CorePlayQueue::getTrack 7 May 20 19:07:52 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:07:52 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:52 volumio volumio[706]: info: sendMpdCommand status took 288 milliseconds May 20 19:07:52 volumio volumio[706]: info: sendMpdCommand stop took 79 milliseconds May 20 19:07:52 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:07:52 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand clear May 20 19:07:52 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:07:52 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:07:52 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:52 volumio volumio[706]: info: CorePlayQueue::getTrack 7 May 20 19:07:52 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:07:52 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:52 volumio volumio[706]: info: CorePlayQueue::getTrack 7 May 20 19:07:52 volumio volumio[706]: 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 20 19:07:52 volumio volumio[706]: verbose: CURRENT POSITION 7 May 20 19:07:52 volumio volumio[706]: info: CoreStateMachine::syncState stateService stop May 20 19:07:52 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus stop May 20 19:07:52 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:52 volumio volumio[706]: info: CorePlayQueue::getTrack 7 May 20 19:07:52 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:52 volumio volumio[706]: info: No code May 20 19:07:52 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:52 volumio volumio[706]: info: CorePlayQueue::getTrack 7 May 20 19:07:52 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:52 volumio volumio[706]: info: ------------------------------ 598ms May 20 19:07:53 volumio volumio[706]: info: May 20 19:07:53 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:07:53 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:07:53 volumio volumio[706]: info: sendMpdCommand clear took 338 milliseconds May 20 19:07:53 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand add "USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/08 La Alborada (Tarrega).ape" May 20 19:07:53 volumio volumio[706]: info: May 20 19:07:53 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:07:53 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:07:53 volumio volumio[706]: error: updateQueue error: null May 20 19:07:53 volumio volumio[706]: info: May 20 19:07:53 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:07:53 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:07:53 volumio volumio[706]: info: ------------------------------ 33ms May 20 19:07:53 volumio volumio[706]: info: sendMpdCommand add "USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/08 La Alborada (Tarrega).ape" took 27 milliseconds May 20 19:07:53 volumio volumio[706]: info: ------------------------------ 18ms May 20 19:07:53 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand play May 20 19:07:53 volumio volumio[706]: info: May 20 19:07:53 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:07:53 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:07:53 volumio volumio[706]: info: ------------------------------ 38ms May 20 19:07:53 volumio volumio[706]: info: sendMpdCommand play took 38 milliseconds May 20 19:07:53 volumio volumio[706]: info: ------------------------------ 27ms May 20 19:07:53 volumio volumio[706]: info: May 20 19:07:53 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:07:53 volumio volumio[706]: info: ControllerMpd::getState May 20 19:07:53 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:07:53 volumio volumio[706]: info: May 20 19:07:53 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:07:53 volumio volumio[706]: info: ControllerMpd::getState May 20 19:07:53 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:07:53 volumio volumio[706]: info: sendMpdCommand status took 29 milliseconds May 20 19:07:53 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:07:53 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 19:07:53 volumio volumio[706]: info: sendMpdCommand status took 57 milliseconds May 20 19:07:53 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:07:53 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 19:07:53 volumio volumio[706]: info: sendMpdCommand playlistinfo took 50 milliseconds May 20 19:07:53 volumio volumio[706]: info: sendMpdCommand playlistinfo took 15 milliseconds May 20 19:07:53 volumio volumio[706]: verbose: ControllerMpd::parseTrackInfo May 20 19:07:53 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:07:53 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:07:53 volumio volumio[706]: info: CorePlayQueue::getTrack 7 May 20 19:07:53 volumio volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":122,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"08 La Alborada (Tarrega).ape","artist":null,"album":null,"uri":"USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/08 La Alborada (Tarrega).ape","trackType":"ape"} May 20 19:07:53 volumio volumio[706]: verbose: CURRENT POSITION 7 May 20 19:07:53 volumio volumio[706]: info: CoreStateMachine::syncState stateService play May 20 19:07:53 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus stop May 20 19:07:53 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:07:53 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:07:53 volumio volumio[706]: info: CorePlayQueue::getTrack 7 May 20 19:07:53 volumio volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":122,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"08 La Alborada (Tarrega).ape","artist":null,"album":null,"uri":"USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/08 La Alborada (Tarrega).ape","trackType":"ape"} May 20 19:07:53 volumio volumio[706]: verbose: CURRENT POSITION 7 May 20 19:07:53 volumio volumio[706]: info: CoreStateMachine::syncState stateService play May 20 19:07:53 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus play May 20 19:07:53 volumio volumio[706]: info: Received an update from plugin. extracting info from payload May 20 19:07:53 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:53 volumio volumio[706]: info: CorePlayQueue::getTrack 7 May 20 19:07:53 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:07:53 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:53 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:53 volumio volumio[706]: info: CorePlayQueue::getTrack 7 May 20 19:07:53 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:53 volumio volumio[706]: info: ------------------------------ 257ms May 20 19:07:53 volumio volumio[706]: info: ------------------------------ 383ms May 20 19:07:53 volumio mount.exfat[2990]: failed to read cluster 0x159ec May 20 19:07:53 volumio kernel: I/O error, dev sda, sector 22708928 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 May 20 19:07:53 volumio kernel: I/O error, dev sda, sector 22708928 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:07:53 volumio kernel: Buffer I/O error on dev sda1, logical block 2834520, async page read May 20 19:07:54 volumio mount.exfat[2990]: failed to read cluster 0x159ed May 20 19:07:54 volumio kernel: I/O error, dev sda, sector 22709184 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 May 20 19:07:54 volumio kernel: I/O error, dev sda, sector 22709184 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:07:54 volumio kernel: Buffer I/O error on dev sda1, logical block 2834552, async page read May 20 19:07:54 volumio kernel: I/O error, dev sda, sector 22709440 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 May 20 19:07:54 volumio kernel: I/O error, dev sda, sector 22709440 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:07:54 volumio kernel: Buffer I/O error on dev sda1, logical block 2834584, async page read May 20 19:07:54 volumio mount.exfat[2990]: failed to read cluster 0x159ee May 20 19:07:55 volumio mount.exfat[2990]: failed to read cluster 0x159ec May 20 19:07:55 volumio kernel: I/O error, dev sda, sector 22708928 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:07:55 volumio kernel: Buffer I/O error on dev sda1, logical block 2834520, async page read May 20 19:07:55 volumio kernel: I/O error, dev sda, sector 22708928 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:07:55 volumio kernel: Buffer I/O error on dev sda1, logical block 2834520, async page read May 20 19:07:55 volumio kernel: I/O error, dev sda, sector 22708928 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:07:55 volumio kernel: Buffer I/O error on dev sda1, logical block 2834520, async page read May 20 19:07:55 volumio mount.exfat[2990]: failed to read cluster 0x159ec May 20 19:07:55 volumio mount.exfat[2990]: failed to read cluster 0x159ec May 20 19:07:58 volumio volumio[706]: info: May 20 19:07:58 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:07:58 volumio volumio[706]: info: ControllerMpd::getState May 20 19:07:58 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:07:58 volumio volumio[706]: info: May 20 19:07:58 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:07:58 volumio volumio[706]: info: ControllerMpd::getState May 20 19:07:58 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:07:58 volumio volumio[706]: info: sendMpdCommand status took 34 milliseconds May 20 19:07:58 volumio volumio[706]: info: sendMpdCommand status took 43 milliseconds May 20 19:07:58 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:07:58 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:07:58 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:07:58 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:58 volumio volumio[706]: info: CorePlayQueue::getTrack 7 May 20 19:07:58 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:07:58 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:58 volumio volumio[706]: info: CorePlayQueue::getTrack 7 May 20 19:07:58 volumio volumio[706]: 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 20 19:07:58 volumio volumio[706]: verbose: CURRENT POSITION 7 May 20 19:07:58 volumio volumio[706]: info: CoreStateMachine::syncState stateService stop May 20 19:07:58 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus play May 20 19:07:58 volumio volumio[706]: info: CoreStateMachine::play index undefined May 20 19:07:58 volumio volumio[706]: info: CoreStateMachine::setConsumeUpdateService undefined May 20 19:07:58 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:58 volumio volumio[706]: info: CorePlayQueue::getTrack 8 May 20 19:07:58 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:07:58 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:58 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:07:58 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:07:58 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:58 volumio volumio[706]: info: CorePlayQueue::getTrack 8 May 20 19:07:58 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:58 volumio volumio[706]: info: CorePlayQueue::getTrack 8 May 20 19:07:58 volumio volumio[706]: 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 20 19:07:58 volumio volumio[706]: verbose: CURRENT POSITION 8 May 20 19:07:58 volumio volumio[706]: info: CoreStateMachine::syncState stateService stop May 20 19:07:58 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus stop May 20 19:07:58 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:58 volumio volumio[706]: info: CorePlayQueue::getTrack 8 May 20 19:07:58 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:58 volumio volumio[706]: info: No code May 20 19:07:58 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:58 volumio volumio[706]: info: CorePlayQueue::getTrack 8 May 20 19:07:58 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:59 volumio volumio[706]: info: CorePlayQueue::getTrack 8 May 20 19:07:59 volumio volumio[706]: info: CoreStateMachine::startPlaybackTimer May 20 19:07:59 volumio volumio[706]: info: CorePlayQueue::getTrack 8 May 20 19:07:59 volumio volumio[706]: verbose: ControllerMpd::clearAddPlayTracks USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/09 Vals Venezolano No3 (Lauro).ape May 20 19:07:59 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand stop May 20 19:07:59 volumio volumio[706]: info: ------------------------------ 419ms May 20 19:07:59 volumio volumio[706]: info: ------------------------------ 422ms May 20 19:07:59 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:59 volumio volumio[706]: info: CorePlayQueue::getTrack 8 May 20 19:07:59 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:07:59 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:07:59 volumio volumio[706]: info: sendMpdCommand stop took 82 milliseconds May 20 19:07:59 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand clear May 20 19:07:59 volumio volumio[706]: info: May 20 19:07:59 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:07:59 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:07:59 volumio volumio[706]: info: sendMpdCommand clear took 55 milliseconds May 20 19:07:59 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand add "USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/09 Vals Venezolano No3 (Lauro).ape" May 20 19:07:59 volumio volumio[706]: info: May 20 19:07:59 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:07:59 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:07:59 volumio volumio[706]: error: updateQueue error: null May 20 19:07:59 volumio volumio[706]: info: May 20 19:07:59 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:07:59 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:07:59 volumio volumio[706]: info: ------------------------------ 31ms May 20 19:07:59 volumio volumio[706]: info: sendMpdCommand add "USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/09 Vals Venezolano No3 (Lauro).ape" took 25 milliseconds May 20 19:07:59 volumio volumio[706]: info: ------------------------------ 18ms May 20 19:07:59 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand play May 20 19:07:59 volumio volumio[706]: info: May 20 19:07:59 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:07:59 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:07:59 volumio volumio[706]: info: ------------------------------ 37ms May 20 19:07:59 volumio volumio[706]: info: sendMpdCommand play took 31 milliseconds May 20 19:07:59 volumio volumio[706]: info: May 20 19:07:59 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:07:59 volumio volumio[706]: info: ------------------------------ 60ms May 20 19:07:59 volumio volumio[706]: info: ControllerMpd::getState May 20 19:07:59 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:07:59 volumio volumio[706]: info: May 20 19:07:59 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:07:59 volumio volumio[706]: info: ControllerMpd::getState May 20 19:07:59 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:07:59 volumio volumio[706]: info: May 20 19:07:59 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:07:59 volumio volumio[706]: info: ControllerMpd::getState May 20 19:07:59 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:07:59 volumio volumio[706]: info: sendMpdCommand status took 51 milliseconds May 20 19:07:59 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:07:59 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 19:07:59 volumio volumio[706]: info: May 20 19:07:59 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:07:59 volumio volumio[706]: info: ControllerMpd::getState May 20 19:07:59 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:07:59 volumio volumio[706]: info: sendMpdCommand status took 197 milliseconds May 20 19:07:59 volumio volumio[706]: info: sendMpdCommand status took 192 milliseconds May 20 19:07:59 volumio volumio[706]: info: sendMpdCommand playlistinfo took 76 milliseconds May 20 19:07:59 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:07:59 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 19:07:59 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:07:59 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 19:07:59 volumio volumio[706]: verbose: ControllerMpd::parseTrackInfo May 20 19:07:59 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:07:59 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:07:59 volumio volumio[706]: info: CorePlayQueue::getTrack 8 May 20 19:07:59 volumio volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":172,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"09 Vals Venezolano No3 (Lauro).ape","artist":null,"album":null,"uri":"USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/09 Vals Venezolano No3 (Lauro).ape","trackType":"ape"} May 20 19:07:59 volumio volumio[706]: verbose: CURRENT POSITION 8 May 20 19:07:59 volumio volumio[706]: info: CoreStateMachine::syncState stateService play May 20 19:07:59 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus stop May 20 19:07:59 volumio volumio[706]: info: ------------------------------ 295ms May 20 19:07:59 volumio volumio[706]: info: sendMpdCommand status took 104 milliseconds May 20 19:07:59 volumio volumio[706]: info: sendMpdCommand playlistinfo took 71 milliseconds May 20 19:07:59 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:07:59 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 19:07:59 volumio volumio[706]: verbose: ControllerMpd::parseTrackInfo May 20 19:07:59 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:07:59 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:07:59 volumio volumio[706]: info: CorePlayQueue::getTrack 8 May 20 19:07:59 volumio volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":172,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"09 Vals Venezolano No3 (Lauro).ape","artist":null,"album":null,"uri":"USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/09 Vals Venezolano No3 (Lauro).ape","trackType":"ape"} May 20 19:07:59 volumio volumio[706]: verbose: CURRENT POSITION 8 May 20 19:07:59 volumio volumio[706]: info: CoreStateMachine::syncState stateService play May 20 19:07:59 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus play May 20 19:07:59 volumio volumio[706]: info: Received an update from plugin. extracting info from payload May 20 19:07:59 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:07:59 volumio volumio[706]: info: CorePlayQueue::getTrack 8 May 20 19:07:59 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:07:59 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:00 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:00 volumio volumio[706]: info: CorePlayQueue::getTrack 8 May 20 19:08:00 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:00 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:08:00 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:08:00 volumio volumio[706]: info: CorePlayQueue::getTrack 8 May 20 19:08:00 volumio volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":172,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"09 Vals Venezolano No3 (Lauro).ape","artist":null,"album":null,"uri":"USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/09 Vals Venezolano No3 (Lauro).ape","trackType":"ape"} May 20 19:08:00 volumio volumio[706]: verbose: CURRENT POSITION 8 May 20 19:08:00 volumio volumio[706]: info: CoreStateMachine::syncState stateService play May 20 19:08:00 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus play May 20 19:08:00 volumio volumio[706]: info: Received an update from plugin. extracting info from payload May 20 19:08:00 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:00 volumio volumio[706]: info: CorePlayQueue::getTrack 8 May 20 19:08:00 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:00 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:00 volumio volumio[706]: info: CorePlayQueue::getTrack 8 May 20 19:08:00 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:00 volumio kernel: I/O error, dev sda, sector 22720960 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 May 20 19:08:00 volumio kernel: I/O error, dev sda, sector 22720960 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:08:00 volumio kernel: Buffer I/O error on dev sda1, logical block 2836024, async page read May 20 19:08:00 volumio mount.exfat[2990]: failed to read cluster 0x15a1b May 20 19:08:00 volumio volumio[706]: info: ------------------------------ 971ms May 20 19:08:00 volumio volumio[706]: info: ------------------------------ 958ms May 20 19:08:00 volumio volumio[706]: info: sendMpdCommand playlistinfo took 694 milliseconds May 20 19:08:00 volumio volumio[706]: verbose: ControllerMpd::parseTrackInfo May 20 19:08:00 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:08:00 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:08:00 volumio volumio[706]: info: CorePlayQueue::getTrack 8 May 20 19:08:00 volumio volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":172,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"09 Vals Venezolano No3 (Lauro).ape","artist":null,"album":null,"uri":"USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/09 Vals Venezolano No3 (Lauro).ape","trackType":"ape"} May 20 19:08:00 volumio volumio[706]: verbose: CURRENT POSITION 8 May 20 19:08:00 volumio volumio[706]: info: CoreStateMachine::syncState stateService play May 20 19:08:00 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus play May 20 19:08:00 volumio volumio[706]: info: Received an update from plugin. extracting info from payload May 20 19:08:00 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:00 volumio volumio[706]: info: CorePlayQueue::getTrack 8 May 20 19:08:00 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:00 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:00 volumio volumio[706]: info: CorePlayQueue::getTrack 8 May 20 19:08:00 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:01 volumio volumio[706]: info: ------------------------------ 1408ms May 20 19:08:01 volumio kernel: I/O error, dev sda, sector 22721216 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 May 20 19:08:01 volumio kernel: I/O error, dev sda, sector 22721216 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:08:01 volumio kernel: Buffer I/O error on dev sda1, logical block 2836056, async page read May 20 19:08:01 volumio kernel: I/O error, dev sda, sector 22721472 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 May 20 19:08:01 volumio kernel: I/O error, dev sda, sector 22721472 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:08:01 volumio kernel: Buffer I/O error on dev sda1, logical block 2836088, async page read May 20 19:08:01 volumio mount.exfat[2990]: failed to read cluster 0x15a1c May 20 19:08:01 volumio mount.exfat[2990]: failed to read cluster 0x15a1d May 20 19:08:01 volumio mount.exfat[2990]: failed to read cluster 0x15a1b May 20 19:08:01 volumio kernel: I/O error, dev sda, sector 22720960 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:08:01 volumio kernel: Buffer I/O error on dev sda1, logical block 2836024, async page read May 20 19:08:01 volumio mount.exfat[2990]: failed to read cluster 0x15a1b May 20 19:08:01 volumio mount.exfat[2990]: failed to read cluster 0x15a1b May 20 19:08:02 volumio kernel: I/O error, dev sda, sector 22720960 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:08:02 volumio kernel: Buffer I/O error on dev sda1, logical block 2836024, async page read May 20 19:08:02 volumio kernel: I/O error, dev sda, sector 22720960 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:08:02 volumio kernel: Buffer I/O error on dev sda1, logical block 2836024, async page read May 20 19:08:06 volumio volumio[706]: info: May 20 19:08:06 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:08:06 volumio volumio[706]: info: ControllerMpd::getState May 20 19:08:06 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:08:06 volumio volumio[706]: info: May 20 19:08:06 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:08:06 volumio volumio[706]: info: ControllerMpd::getState May 20 19:08:06 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:08:06 volumio volumio[706]: info: sendMpdCommand status took 19 milliseconds May 20 19:08:06 volumio volumio[706]: info: sendMpdCommand status took 15 milliseconds May 20 19:08:06 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:08:06 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:08:06 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:08:06 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:06 volumio volumio[706]: info: CorePlayQueue::getTrack 8 May 20 19:08:06 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:08:06 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:06 volumio volumio[706]: info: CorePlayQueue::getTrack 8 May 20 19:08:06 volumio volumio[706]: 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 20 19:08:06 volumio volumio[706]: verbose: CURRENT POSITION 8 May 20 19:08:06 volumio volumio[706]: info: CoreStateMachine::syncState stateService stop May 20 19:08:06 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus play May 20 19:08:06 volumio volumio[706]: info: CoreStateMachine::play index undefined May 20 19:08:06 volumio volumio[706]: info: CoreStateMachine::setConsumeUpdateService undefined May 20 19:08:06 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:06 volumio volumio[706]: info: CorePlayQueue::getTrack 9 May 20 19:08:06 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:08:06 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:06 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:08:06 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:08:06 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:06 volumio volumio[706]: info: CorePlayQueue::getTrack 9 May 20 19:08:06 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:06 volumio volumio[706]: info: CorePlayQueue::getTrack 9 May 20 19:08:06 volumio volumio[706]: 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 20 19:08:06 volumio volumio[706]: verbose: CURRENT POSITION 9 May 20 19:08:06 volumio volumio[706]: info: CoreStateMachine::syncState stateService stop May 20 19:08:06 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus stop May 20 19:08:06 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:06 volumio volumio[706]: info: CorePlayQueue::getTrack 9 May 20 19:08:06 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:06 volumio volumio[706]: info: No code May 20 19:08:06 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:06 volumio volumio[706]: info: CorePlayQueue::getTrack 9 May 20 19:08:06 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:07 volumio volumio[706]: info: CorePlayQueue::getTrack 9 May 20 19:08:07 volumio volumio[706]: info: CoreStateMachine::startPlaybackTimer May 20 19:08:07 volumio volumio[706]: info: CorePlayQueue::getTrack 9 May 20 19:08:07 volumio volumio[706]: verbose: ControllerMpd::clearAddPlayTracks USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/10 Choro No1 In G (Villa-Lobos).ape May 20 19:08:07 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand stop May 20 19:08:07 volumio volumio[706]: info: ------------------------------ 331ms May 20 19:08:07 volumio volumio[706]: info: ------------------------------ 333ms May 20 19:08:07 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:07 volumio volumio[706]: info: CorePlayQueue::getTrack 9 May 20 19:08:07 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:08:07 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:07 volumio volumio[706]: info: sendMpdCommand stop took 86 milliseconds May 20 19:08:07 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand clear May 20 19:08:07 volumio volumio[706]: info: May 20 19:08:07 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:08:07 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:08:07 volumio volumio[706]: info: sendMpdCommand clear took 68 milliseconds May 20 19:08:07 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand add "USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/10 Choro No1 In G (Villa-Lobos).ape" May 20 19:08:07 volumio volumio[706]: info: May 20 19:08:07 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:08:07 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:08:07 volumio volumio[706]: error: updateQueue error: null May 20 19:08:07 volumio volumio[706]: info: May 20 19:08:07 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:08:07 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:08:07 volumio volumio[706]: info: ------------------------------ 59ms May 20 19:08:07 volumio volumio[706]: info: sendMpdCommand add "USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/10 Choro No1 In G (Villa-Lobos).ape" took 43 milliseconds May 20 19:08:07 volumio volumio[706]: info: ------------------------------ 17ms May 20 19:08:07 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand play May 20 19:08:07 volumio volumio[706]: info: May 20 19:08:07 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:08:07 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:08:07 volumio volumio[706]: info: ------------------------------ 52ms May 20 19:08:07 volumio volumio[706]: info: sendMpdCommand play took 26 milliseconds May 20 19:08:07 volumio volumio[706]: info: May 20 19:08:07 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:08:07 volumio volumio[706]: info: ------------------------------ 66ms May 20 19:08:07 volumio volumio[706]: info: ControllerMpd::getState May 20 19:08:07 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:08:07 volumio volumio[706]: info: May 20 19:08:07 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:08:07 volumio volumio[706]: info: ControllerMpd::getState May 20 19:08:07 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:08:07 volumio volumio[706]: info: May 20 19:08:07 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:08:07 volumio volumio[706]: info: ControllerMpd::getState May 20 19:08:07 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:08:07 volumio volumio[706]: info: May 20 19:08:07 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:08:07 volumio volumio[706]: info: ControllerMpd::getState May 20 19:08:07 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:08:07 volumio volumio[706]: info: sendMpdCommand status took 51 milliseconds May 20 19:08:07 volumio volumio[706]: info: sendMpdCommand status took 47 milliseconds May 20 19:08:07 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:08:07 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 19:08:07 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:08:07 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 19:08:07 volumio volumio[706]: info: sendMpdCommand status took 61 milliseconds May 20 19:08:07 volumio volumio[706]: info: sendMpdCommand status took 47 milliseconds May 20 19:08:07 volumio volumio[706]: info: sendMpdCommand playlistinfo took 28 milliseconds May 20 19:08:07 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:08:07 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 19:08:07 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:08:07 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 19:08:07 volumio volumio[706]: verbose: ControllerMpd::parseTrackInfo May 20 19:08:07 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:08:07 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:08:07 volumio volumio[706]: info: CorePlayQueue::getTrack 9 May 20 19:08:07 volumio volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"10 Choro No1 In G (Villa-Lobos).ape","artist":null,"album":null,"uri":"USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/10 Choro No1 In G (Villa-Lobos).ape","trackType":"ape"} May 20 19:08:07 volumio volumio[706]: verbose: CURRENT POSITION 9 May 20 19:08:07 volumio volumio[706]: info: CoreStateMachine::syncState stateService play May 20 19:08:07 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus stop May 20 19:08:07 volumio volumio[706]: info: ------------------------------ 142ms May 20 19:08:07 volumio volumio[706]: info: sendMpdCommand playlistinfo took 100 milliseconds May 20 19:08:07 volumio volumio[706]: info: sendMpdCommand playlistinfo took 67 milliseconds May 20 19:08:07 volumio volumio[706]: info: sendMpdCommand playlistinfo took 66 milliseconds May 20 19:08:07 volumio volumio[706]: verbose: ControllerMpd::parseTrackInfo May 20 19:08:07 volumio volumio[706]: verbose: ControllerMpd::parseTrackInfo May 20 19:08:07 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:08:07 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:08:07 volumio volumio[706]: info: CorePlayQueue::getTrack 9 May 20 19:08:07 volumio volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"10 Choro No1 In G (Villa-Lobos).ape","artist":null,"album":null,"uri":"USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/10 Choro No1 In G (Villa-Lobos).ape","trackType":"ape"} May 20 19:08:07 volumio volumio[706]: verbose: CURRENT POSITION 9 May 20 19:08:07 volumio volumio[706]: info: CoreStateMachine::syncState stateService play May 20 19:08:07 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus play May 20 19:08:07 volumio volumio[706]: info: Received an update from plugin. extracting info from payload May 20 19:08:07 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:07 volumio volumio[706]: info: CorePlayQueue::getTrack 9 May 20 19:08:07 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:08:07 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:07 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:07 volumio volumio[706]: info: CorePlayQueue::getTrack 9 May 20 19:08:07 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:07 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:08:07 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:08:07 volumio volumio[706]: info: CorePlayQueue::getTrack 9 May 20 19:08:07 volumio volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"10 Choro No1 In G (Villa-Lobos).ape","artist":null,"album":null,"uri":"USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/10 Choro No1 In G (Villa-Lobos).ape","trackType":"ape"} May 20 19:08:07 volumio volumio[706]: verbose: CURRENT POSITION 9 May 20 19:08:07 volumio volumio[706]: info: CoreStateMachine::syncState stateService play May 20 19:08:07 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus play May 20 19:08:07 volumio volumio[706]: info: Received an update from plugin. extracting info from payload May 20 19:08:07 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:07 volumio volumio[706]: info: CorePlayQueue::getTrack 9 May 20 19:08:07 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:07 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:07 volumio volumio[706]: info: CorePlayQueue::getTrack 9 May 20 19:08:07 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:07 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:08:07 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:08:07 volumio volumio[706]: info: CorePlayQueue::getTrack 9 May 20 19:08:07 volumio volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"10 Choro No1 In G (Villa-Lobos).ape","artist":null,"album":null,"uri":"USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/10 Choro No1 In G (Villa-Lobos).ape","trackType":"ape"} May 20 19:08:07 volumio volumio[706]: verbose: CURRENT POSITION 9 May 20 19:08:07 volumio volumio[706]: info: CoreStateMachine::syncState stateService play May 20 19:08:07 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus play May 20 19:08:07 volumio volumio[706]: info: Received an update from plugin. extracting info from payload May 20 19:08:07 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:07 volumio volumio[706]: info: CorePlayQueue::getTrack 9 May 20 19:08:07 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:07 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:07 volumio volumio[706]: info: CorePlayQueue::getTrack 9 May 20 19:08:07 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:08 volumio volumio[706]: info: ------------------------------ 905ms May 20 19:08:08 volumio volumio[706]: info: ------------------------------ 895ms May 20 19:08:08 volumio kernel: I/O error, dev sda, sector 22740416 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 May 20 19:08:08 volumio kernel: I/O error, dev sda, sector 22740416 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:08:08 volumio kernel: Buffer I/O error on dev sda1, logical block 2838456, async page read May 20 19:08:08 volumio mount.exfat[2990]: failed to read cluster 0x15a67 May 20 19:08:08 volumio volumio[706]: info: ------------------------------ 907ms May 20 19:08:09 volumio mount.exfat[2990]: failed to read cluster 0x15a68 May 20 19:08:09 volumio mount.exfat[2990]: failed to read cluster 0x15a69 May 20 19:08:09 volumio kernel: I/O error, dev sda, sector 22740672 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 May 20 19:08:09 volumio kernel: I/O error, dev sda, sector 22740672 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:08:09 volumio kernel: Buffer I/O error on dev sda1, logical block 2838488, async page read May 20 19:08:09 volumio kernel: I/O error, dev sda, sector 22740928 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 May 20 19:08:09 volumio kernel: I/O error, dev sda, sector 22740928 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:08:09 volumio kernel: Buffer I/O error on dev sda1, logical block 2838520, async page read May 20 19:08:09 volumio mount.exfat[2990]: failed to read cluster 0x15a67 May 20 19:08:09 volumio kernel: I/O error, dev sda, sector 22740416 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:08:09 volumio kernel: Buffer I/O error on dev sda1, logical block 2838456, async page read May 20 19:08:09 volumio kernel: I/O error, dev sda, sector 22740416 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:08:09 volumio kernel: Buffer I/O error on dev sda1, logical block 2838456, async page read May 20 19:08:09 volumio mount.exfat[2990]: failed to read cluster 0x15a67 May 20 19:08:09 volumio kernel: I/O error, dev sda, sector 22740416 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:08:09 volumio kernel: Buffer I/O error on dev sda1, logical block 2838456, async page read May 20 19:08:09 volumio mount.exfat[2990]: failed to read cluster 0x15a67 May 20 19:08:14 volumio volumio[706]: info: May 20 19:08:14 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:08:14 volumio volumio[706]: info: ControllerMpd::getState May 20 19:08:14 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:08:14 volumio volumio[706]: info: May 20 19:08:14 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:08:14 volumio volumio[706]: info: ControllerMpd::getState May 20 19:08:14 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:08:14 volumio volumio[706]: info: sendMpdCommand status took 41 milliseconds May 20 19:08:14 volumio volumio[706]: info: sendMpdCommand status took 14 milliseconds May 20 19:08:14 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:08:14 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:08:14 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:08:14 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:14 volumio volumio[706]: info: CorePlayQueue::getTrack 9 May 20 19:08:14 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:08:14 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:14 volumio volumio[706]: info: CorePlayQueue::getTrack 9 May 20 19:08:14 volumio volumio[706]: 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 20 19:08:14 volumio volumio[706]: verbose: CURRENT POSITION 9 May 20 19:08:14 volumio volumio[706]: info: CoreStateMachine::syncState stateService stop May 20 19:08:14 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus play May 20 19:08:14 volumio volumio[706]: info: CoreStateMachine::play index undefined May 20 19:08:14 volumio volumio[706]: info: CoreStateMachine::setConsumeUpdateService undefined May 20 19:08:14 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:14 volumio volumio[706]: info: CorePlayQueue::getTrack 10 May 20 19:08:14 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:08:14 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:14 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:08:14 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:08:14 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:14 volumio volumio[706]: info: CorePlayQueue::getTrack 10 May 20 19:08:14 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:14 volumio volumio[706]: info: CorePlayQueue::getTrack 10 May 20 19:08:14 volumio volumio[706]: 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 20 19:08:14 volumio volumio[706]: verbose: CURRENT POSITION 10 May 20 19:08:14 volumio volumio[706]: info: CoreStateMachine::syncState stateService stop May 20 19:08:14 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus stop May 20 19:08:14 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:14 volumio volumio[706]: info: CorePlayQueue::getTrack 10 May 20 19:08:14 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:14 volumio volumio[706]: info: No code May 20 19:08:14 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:14 volumio volumio[706]: info: CorePlayQueue::getTrack 10 May 20 19:08:14 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:15 volumio volumio[706]: info: CorePlayQueue::getTrack 10 May 20 19:08:15 volumio volumio[706]: info: CoreStateMachine::startPlaybackTimer May 20 19:08:15 volumio volumio[706]: info: CorePlayQueue::getTrack 10 May 20 19:08:15 volumio volumio[706]: verbose: ControllerMpd::clearAddPlayTracks USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/11 Five Preludes No1 In E Minor (Villa-Lobos).ape May 20 19:08:15 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand stop May 20 19:08:15 volumio volumio[706]: info: ------------------------------ 500ms May 20 19:08:15 volumio volumio[706]: info: ------------------------------ 469ms May 20 19:08:15 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:15 volumio volumio[706]: info: CorePlayQueue::getTrack 10 May 20 19:08:15 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:08:15 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:15 volumio volumio[706]: info: sendMpdCommand stop took 91 milliseconds May 20 19:08:15 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand clear May 20 19:08:15 volumio volumio[706]: info: May 20 19:08:15 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:08:15 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:08:15 volumio volumio[706]: info: sendMpdCommand clear took 78 milliseconds May 20 19:08:15 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand add "USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/11 Five Preludes No1 In E Minor (Villa-Lobos).ape" May 20 19:08:15 volumio volumio[706]: info: May 20 19:08:15 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:08:15 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:08:15 volumio volumio[706]: error: updateQueue error: null May 20 19:08:15 volumio volumio[706]: info: May 20 19:08:15 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:08:15 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:08:15 volumio volumio[706]: info: ------------------------------ 45ms May 20 19:08:15 volumio volumio[706]: info: sendMpdCommand add "USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/11 Five Preludes No1 In E Minor (Villa-Lobos).ape" took 31 milliseconds May 20 19:08:15 volumio volumio[706]: info: ------------------------------ 105ms May 20 19:08:15 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand play May 20 19:08:15 volumio volumio[706]: info: May 20 19:08:15 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:08:15 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:08:15 volumio volumio[706]: info: ------------------------------ 105ms May 20 19:08:15 volumio volumio[706]: info: sendMpdCommand play took 23 milliseconds May 20 19:08:15 volumio volumio[706]: info: May 20 19:08:15 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:08:15 volumio volumio[706]: info: ------------------------------ 68ms May 20 19:08:15 volumio volumio[706]: info: ControllerMpd::getState May 20 19:08:15 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:08:15 volumio volumio[706]: info: May 20 19:08:15 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:08:15 volumio volumio[706]: info: ControllerMpd::getState May 20 19:08:15 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:08:15 volumio volumio[706]: info: May 20 19:08:15 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:08:15 volumio volumio[706]: info: ControllerMpd::getState May 20 19:08:15 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:08:15 volumio volumio[706]: info: sendMpdCommand status took 50 milliseconds May 20 19:08:15 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:08:15 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 19:08:15 volumio volumio[706]: info: sendMpdCommand status took 74 milliseconds May 20 19:08:15 volumio volumio[706]: info: sendMpdCommand status took 113 milliseconds May 20 19:08:15 volumio volumio[706]: info: sendMpdCommand playlistinfo took 60 milliseconds May 20 19:08:15 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:08:15 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 19:08:15 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:08:15 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 19:08:15 volumio volumio[706]: verbose: ControllerMpd::parseTrackInfo May 20 19:08:15 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:08:15 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:08:15 volumio volumio[706]: info: CorePlayQueue::getTrack 10 May 20 19:08:15 volumio volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":287,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"11 Five Preludes No1 In E Minor (Villa-Lobos).ape","artist":null,"album":null,"uri":"USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/11 Five Preludes No1 In E Minor (Villa-Lobos).ape","trackType":"ape"} May 20 19:08:15 volumio volumio[706]: verbose: CURRENT POSITION 10 May 20 19:08:15 volumio volumio[706]: info: CoreStateMachine::syncState stateService play May 20 19:08:15 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus stop May 20 19:08:15 volumio volumio[706]: info: ------------------------------ 242ms May 20 19:08:15 volumio volumio[706]: info: May 20 19:08:15 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:08:15 volumio volumio[706]: info: ControllerMpd::getState May 20 19:08:15 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:08:15 volumio volumio[706]: info: sendMpdCommand playlistinfo took 141 milliseconds May 20 19:08:15 volumio volumio[706]: info: sendMpdCommand playlistinfo took 140 milliseconds May 20 19:08:15 volumio volumio[706]: verbose: ControllerMpd::parseTrackInfo May 20 19:08:15 volumio volumio[706]: verbose: ControllerMpd::parseTrackInfo May 20 19:08:15 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:08:15 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:08:15 volumio volumio[706]: info: CorePlayQueue::getTrack 10 May 20 19:08:15 volumio volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":287,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"11 Five Preludes No1 In E Minor (Villa-Lobos).ape","artist":null,"album":null,"uri":"USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/11 Five Preludes No1 In E Minor (Villa-Lobos).ape","trackType":"ape"} May 20 19:08:15 volumio volumio[706]: verbose: CURRENT POSITION 10 May 20 19:08:15 volumio volumio[706]: info: CoreStateMachine::syncState stateService play May 20 19:08:15 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus play May 20 19:08:15 volumio volumio[706]: info: Received an update from plugin. extracting info from payload May 20 19:08:15 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:15 volumio volumio[706]: info: CorePlayQueue::getTrack 10 May 20 19:08:15 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:08:15 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:15 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:15 volumio volumio[706]: info: CorePlayQueue::getTrack 10 May 20 19:08:15 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:15 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:08:15 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:08:15 volumio volumio[706]: info: CorePlayQueue::getTrack 10 May 20 19:08:15 volumio volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":287,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"11 Five Preludes No1 In E Minor (Villa-Lobos).ape","artist":null,"album":null,"uri":"USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/11 Five Preludes No1 In E Minor (Villa-Lobos).ape","trackType":"ape"} May 20 19:08:15 volumio volumio[706]: verbose: CURRENT POSITION 10 May 20 19:08:15 volumio volumio[706]: info: CoreStateMachine::syncState stateService play May 20 19:08:15 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus play May 20 19:08:15 volumio volumio[706]: info: Received an update from plugin. extracting info from payload May 20 19:08:15 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:15 volumio volumio[706]: info: CorePlayQueue::getTrack 10 May 20 19:08:15 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:15 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:15 volumio volumio[706]: info: CorePlayQueue::getTrack 10 May 20 19:08:15 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:16 volumio volumio[706]: info: ------------------------------ 751ms May 20 19:08:16 volumio volumio[706]: info: ------------------------------ 749ms May 20 19:08:16 volumio volumio[706]: info: sendMpdCommand status took 514 milliseconds May 20 19:08:16 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:08:16 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 19:08:16 volumio mount.exfat[2990]: failed to read cluster 0x15ad0 May 20 19:08:16 volumio kernel: I/O error, dev sda, sector 22767296 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 May 20 19:08:16 volumio kernel: I/O error, dev sda, sector 22767296 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:08:16 volumio kernel: Buffer I/O error on dev sda1, logical block 2841816, async page read May 20 19:08:16 volumio volumio[706]: info: sendMpdCommand playlistinfo took 100 milliseconds May 20 19:08:16 volumio volumio[706]: verbose: ControllerMpd::parseTrackInfo May 20 19:08:16 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:08:16 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:08:16 volumio volumio[706]: info: CorePlayQueue::getTrack 10 May 20 19:08:16 volumio volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":287,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"11 Five Preludes No1 In E Minor (Villa-Lobos).ape","artist":null,"album":null,"uri":"USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/11 Five Preludes No1 In E Minor (Villa-Lobos).ape","trackType":"ape"} May 20 19:08:16 volumio volumio[706]: verbose: CURRENT POSITION 10 May 20 19:08:16 volumio volumio[706]: info: CoreStateMachine::syncState stateService play May 20 19:08:16 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus play May 20 19:08:16 volumio volumio[706]: info: Received an update from plugin. extracting info from payload May 20 19:08:16 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:16 volumio volumio[706]: info: CorePlayQueue::getTrack 10 May 20 19:08:16 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:16 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:16 volumio volumio[706]: info: CorePlayQueue::getTrack 10 May 20 19:08:16 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:16 volumio volumio[706]: info: ------------------------------ 967ms May 20 19:08:17 volumio mount.exfat[2990]: failed to read cluster 0x15ad1 May 20 19:08:17 volumio mount.exfat[2990]: failed to read cluster 0x15ad2 May 20 19:08:17 volumio kernel: I/O error, dev sda, sector 22767552 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 May 20 19:08:17 volumio kernel: I/O error, dev sda, sector 22767552 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:08:17 volumio kernel: Buffer I/O error on dev sda1, logical block 2841848, async page read May 20 19:08:17 volumio kernel: I/O error, dev sda, sector 22767808 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 May 20 19:08:17 volumio kernel: I/O error, dev sda, sector 22767808 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:08:17 volumio kernel: Buffer I/O error on dev sda1, logical block 2841880, async page read May 20 19:08:17 volumio mount.exfat[2990]: failed to read cluster 0x15ad0 May 20 19:08:17 volumio kernel: I/O error, dev sda, sector 22767296 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:08:17 volumio kernel: Buffer I/O error on dev sda1, logical block 2841816, async page read May 20 19:08:17 volumio kernel: I/O error, dev sda, sector 22767296 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:08:17 volumio kernel: Buffer I/O error on dev sda1, logical block 2841816, async page read May 20 19:08:17 volumio kernel: I/O error, dev sda, sector 22767296 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:08:17 volumio kernel: Buffer I/O error on dev sda1, logical block 2841816, async page read May 20 19:08:17 volumio mount.exfat[2990]: failed to read cluster 0x15ad0 May 20 19:08:17 volumio mount.exfat[2990]: failed to read cluster 0x15ad0 May 20 19:08:21 volumio volumio[706]: info: May 20 19:08:21 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:08:21 volumio volumio[706]: info: ControllerMpd::getState May 20 19:08:21 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:08:21 volumio volumio[706]: info: May 20 19:08:21 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:08:21 volumio volumio[706]: info: ControllerMpd::getState May 20 19:08:21 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:08:21 volumio volumio[706]: info: sendMpdCommand status took 24 milliseconds May 20 19:08:21 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:08:21 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:08:21 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:08:21 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:21 volumio volumio[706]: info: CorePlayQueue::getTrack 10 May 20 19:08:21 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:08:21 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:21 volumio volumio[706]: info: CorePlayQueue::getTrack 10 May 20 19:08:21 volumio volumio[706]: 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 20 19:08:21 volumio volumio[706]: verbose: CURRENT POSITION 10 May 20 19:08:21 volumio volumio[706]: info: CoreStateMachine::syncState stateService stop May 20 19:08:21 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus play May 20 19:08:21 volumio volumio[706]: info: CoreStateMachine::play index undefined May 20 19:08:21 volumio volumio[706]: info: CoreStateMachine::setConsumeUpdateService undefined May 20 19:08:21 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:21 volumio volumio[706]: info: CorePlayQueue::getTrack 11 May 20 19:08:21 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:08:21 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:21 volumio volumio[706]: info: CorePlayQueue::getTrack 11 May 20 19:08:21 volumio volumio[706]: info: CoreStateMachine::startPlaybackTimer May 20 19:08:21 volumio volumio[706]: info: CorePlayQueue::getTrack 11 May 20 19:08:21 volumio volumio[706]: verbose: ControllerMpd::clearAddPlayTracks USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/12 Five Preludes No2 In E (Villa-Lobos).ape May 20 19:08:21 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand stop May 20 19:08:21 volumio volumio[706]: info: ------------------------------ 214ms May 20 19:08:21 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:21 volumio volumio[706]: info: CorePlayQueue::getTrack 11 May 20 19:08:21 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:08:21 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:21 volumio volumio[706]: info: sendMpdCommand status took 272 milliseconds May 20 19:08:21 volumio volumio[706]: info: sendMpdCommand stop took 79 milliseconds May 20 19:08:21 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:08:21 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand clear May 20 19:08:21 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:08:21 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:08:21 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:21 volumio volumio[706]: info: CorePlayQueue::getTrack 11 May 20 19:08:21 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:08:21 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:21 volumio volumio[706]: info: CorePlayQueue::getTrack 11 May 20 19:08:21 volumio volumio[706]: 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 20 19:08:21 volumio volumio[706]: verbose: CURRENT POSITION 11 May 20 19:08:21 volumio volumio[706]: info: CoreStateMachine::syncState stateService stop May 20 19:08:21 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus stop May 20 19:08:21 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:21 volumio volumio[706]: info: CorePlayQueue::getTrack 11 May 20 19:08:21 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:21 volumio volumio[706]: info: No code May 20 19:08:21 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:21 volumio volumio[706]: info: CorePlayQueue::getTrack 11 May 20 19:08:21 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:21 volumio volumio[706]: info: ------------------------------ 466ms May 20 19:08:21 volumio volumio[706]: info: May 20 19:08:21 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:08:21 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:08:21 volumio volumio[706]: info: sendMpdCommand clear took 241 milliseconds May 20 19:08:21 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand add "USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/12 Five Preludes No2 In E (Villa-Lobos).ape" May 20 19:08:21 volumio volumio[706]: info: May 20 19:08:21 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:08:21 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:08:21 volumio volumio[706]: error: updateQueue error: null May 20 19:08:21 volumio volumio[706]: info: May 20 19:08:21 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:08:21 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:08:21 volumio volumio[706]: info: ------------------------------ 33ms May 20 19:08:21 volumio volumio[706]: info: sendMpdCommand add "USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/12 Five Preludes No2 In E (Villa-Lobos).ape" took 26 milliseconds May 20 19:08:21 volumio volumio[706]: info: ------------------------------ 19ms May 20 19:08:21 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand play May 20 19:08:21 volumio volumio[706]: info: May 20 19:08:21 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:08:21 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:08:21 volumio volumio[706]: info: ------------------------------ 35ms May 20 19:08:21 volumio volumio[706]: info: sendMpdCommand play took 29 milliseconds May 20 19:08:21 volumio volumio[706]: info: May 20 19:08:21 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:08:21 volumio volumio[706]: info: ------------------------------ 53ms May 20 19:08:21 volumio volumio[706]: info: ControllerMpd::getState May 20 19:08:21 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:08:21 volumio volumio[706]: info: May 20 19:08:21 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:08:21 volumio volumio[706]: info: ControllerMpd::getState May 20 19:08:21 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:08:21 volumio volumio[706]: info: sendMpdCommand status took 28 milliseconds May 20 19:08:21 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:08:21 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 19:08:21 volumio volumio[706]: info: sendMpdCommand status took 41 milliseconds May 20 19:08:21 volumio volumio[706]: info: sendMpdCommand playlistinfo took 28 milliseconds May 20 19:08:21 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:08:21 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 19:08:21 volumio volumio[706]: verbose: ControllerMpd::parseTrackInfo May 20 19:08:21 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:08:21 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:08:21 volumio volumio[706]: info: CorePlayQueue::getTrack 11 May 20 19:08:21 volumio volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":179,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"12 Five Preludes No2 In E (Villa-Lobos).ape","artist":null,"album":null,"uri":"USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/12 Five Preludes No2 In E (Villa-Lobos).ape","trackType":"ape"} May 20 19:08:21 volumio volumio[706]: verbose: CURRENT POSITION 11 May 20 19:08:21 volumio volumio[706]: info: CoreStateMachine::syncState stateService play May 20 19:08:21 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus stop May 20 19:08:21 volumio volumio[706]: info: ------------------------------ 101ms May 20 19:08:21 volumio volumio[706]: info: sendMpdCommand playlistinfo took 34 milliseconds May 20 19:08:21 volumio volumio[706]: verbose: ControllerMpd::parseTrackInfo May 20 19:08:21 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:08:21 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:08:21 volumio volumio[706]: info: CorePlayQueue::getTrack 11 May 20 19:08:21 volumio volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":179,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"12 Five Preludes No2 In E (Villa-Lobos).ape","artist":null,"album":null,"uri":"USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/12 Five Preludes No2 In E (Villa-Lobos).ape","trackType":"ape"} May 20 19:08:21 volumio volumio[706]: verbose: CURRENT POSITION 11 May 20 19:08:21 volumio volumio[706]: info: CoreStateMachine::syncState stateService play May 20 19:08:21 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus play May 20 19:08:21 volumio volumio[706]: info: Received an update from plugin. extracting info from payload May 20 19:08:21 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:21 volumio volumio[706]: info: CorePlayQueue::getTrack 11 May 20 19:08:21 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:08:21 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:22 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:22 volumio volumio[706]: info: CorePlayQueue::getTrack 11 May 20 19:08:22 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:22 volumio volumio[706]: info: ------------------------------ 397ms May 20 19:08:22 volumio mount.exfat[2990]: failed to read cluster 0x15b5a May 20 19:08:22 volumio kernel: I/O error, dev sda, sector 22802624 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 May 20 19:08:22 volumio kernel: I/O error, dev sda, sector 22802624 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:08:22 volumio kernel: Buffer I/O error on dev sda1, logical block 2846232, async page read May 20 19:08:22 volumio mount.exfat[2990]: failed to read cluster 0x15b5b May 20 19:08:22 volumio mount.exfat[2990]: failed to read cluster 0x15b5c May 20 19:08:22 volumio kernel: I/O error, dev sda, sector 22802880 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 May 20 19:08:22 volumio kernel: I/O error, dev sda, sector 22802880 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:08:22 volumio kernel: Buffer I/O error on dev sda1, logical block 2846264, async page read May 20 19:08:22 volumio kernel: I/O error, dev sda, sector 22803136 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 May 20 19:08:22 volumio kernel: I/O error, dev sda, sector 22803136 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:08:22 volumio kernel: Buffer I/O error on dev sda1, logical block 2846296, async page read May 20 19:08:23 volumio mount.exfat[2990]: failed to read cluster 0x15b5a May 20 19:08:23 volumio kernel: I/O error, dev sda, sector 22802624 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:08:23 volumio kernel: Buffer I/O error on dev sda1, logical block 2846232, async page read May 20 19:08:23 volumio kernel: I/O error, dev sda, sector 22802624 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:08:23 volumio kernel: Buffer I/O error on dev sda1, logical block 2846232, async page read May 20 19:08:23 volumio kernel: I/O error, dev sda, sector 22802624 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:08:23 volumio kernel: Buffer I/O error on dev sda1, logical block 2846232, async page read May 20 19:08:23 volumio mount.exfat[2990]: failed to read cluster 0x15b5a May 20 19:08:23 volumio mount.exfat[2990]: failed to read cluster 0x15b5a May 20 19:08:27 volumio volumio[706]: info: May 20 19:08:27 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:08:27 volumio volumio[706]: info: ControllerMpd::getState May 20 19:08:27 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:08:27 volumio volumio[706]: info: May 20 19:08:27 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:08:27 volumio volumio[706]: info: ControllerMpd::getState May 20 19:08:27 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:08:27 volumio volumio[706]: info: sendMpdCommand status took 22 milliseconds May 20 19:08:27 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:08:27 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:08:27 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:08:27 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:27 volumio volumio[706]: info: CorePlayQueue::getTrack 11 May 20 19:08:27 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:08:27 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:27 volumio volumio[706]: info: CorePlayQueue::getTrack 11 May 20 19:08:27 volumio volumio[706]: 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 20 19:08:27 volumio volumio[706]: verbose: CURRENT POSITION 11 May 20 19:08:27 volumio volumio[706]: info: CoreStateMachine::syncState stateService stop May 20 19:08:27 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus play May 20 19:08:27 volumio volumio[706]: info: CoreStateMachine::play index undefined May 20 19:08:27 volumio volumio[706]: info: CoreStateMachine::setConsumeUpdateService undefined May 20 19:08:27 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:27 volumio volumio[706]: info: CorePlayQueue::getTrack 12 May 20 19:08:27 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:08:27 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:27 volumio volumio[706]: info: CorePlayQueue::getTrack 12 May 20 19:08:27 volumio volumio[706]: info: CoreStateMachine::startPlaybackTimer May 20 19:08:27 volumio volumio[706]: info: CorePlayQueue::getTrack 12 May 20 19:08:27 volumio volumio[706]: verbose: ControllerMpd::clearAddPlayTracks USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/13 Study No1 In E Minor (Villa-Lobos).ape May 20 19:08:27 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand stop May 20 19:08:27 volumio volumio[706]: info: ------------------------------ 212ms May 20 19:08:27 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:27 volumio volumio[706]: info: CorePlayQueue::getTrack 12 May 20 19:08:27 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:08:27 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:27 volumio volumio[706]: info: sendMpdCommand status took 272 milliseconds May 20 19:08:27 volumio volumio[706]: info: sendMpdCommand stop took 89 milliseconds May 20 19:08:27 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:08:27 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand clear May 20 19:08:27 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:08:27 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:08:27 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:27 volumio volumio[706]: info: CorePlayQueue::getTrack 12 May 20 19:08:27 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:08:27 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:27 volumio volumio[706]: info: CorePlayQueue::getTrack 12 May 20 19:08:27 volumio volumio[706]: 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 20 19:08:27 volumio volumio[706]: verbose: CURRENT POSITION 12 May 20 19:08:27 volumio volumio[706]: info: CoreStateMachine::syncState stateService stop May 20 19:08:27 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus stop May 20 19:08:27 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:27 volumio volumio[706]: info: CorePlayQueue::getTrack 12 May 20 19:08:27 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:27 volumio volumio[706]: info: No code May 20 19:08:27 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:27 volumio volumio[706]: info: CorePlayQueue::getTrack 12 May 20 19:08:27 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:27 volumio volumio[706]: info: ------------------------------ 457ms May 20 19:08:28 volumio volumio[706]: info: May 20 19:08:28 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:08:28 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:08:28 volumio volumio[706]: info: sendMpdCommand clear took 276 milliseconds May 20 19:08:28 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand add "USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/13 Study No1 In E Minor (Villa-Lobos).ape" May 20 19:08:28 volumio volumio[706]: info: May 20 19:08:28 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:08:28 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:08:28 volumio volumio[706]: error: updateQueue error: null May 20 19:08:28 volumio volumio[706]: info: May 20 19:08:28 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:08:28 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:08:28 volumio volumio[706]: info: ------------------------------ 33ms May 20 19:08:28 volumio volumio[706]: info: sendMpdCommand add "USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/13 Study No1 In E Minor (Villa-Lobos).ape" took 25 milliseconds May 20 19:08:28 volumio volumio[706]: info: ------------------------------ 19ms May 20 19:08:28 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand play May 20 19:08:28 volumio volumio[706]: info: May 20 19:08:28 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:08:28 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:08:28 volumio volumio[706]: info: ------------------------------ 47ms May 20 19:08:28 volumio volumio[706]: info: sendMpdCommand play took 64 milliseconds May 20 19:08:28 volumio volumio[706]: info: ------------------------------ 51ms May 20 19:08:28 volumio volumio[706]: info: May 20 19:08:28 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:08:28 volumio volumio[706]: info: ControllerMpd::getState May 20 19:08:28 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:08:28 volumio volumio[706]: info: May 20 19:08:28 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:08:28 volumio volumio[706]: info: ControllerMpd::getState May 20 19:08:28 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:08:28 volumio volumio[706]: info: sendMpdCommand status took 245 milliseconds May 20 19:08:28 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:08:28 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 19:08:28 volumio volumio[706]: info: sendMpdCommand status took 45 milliseconds May 20 19:08:28 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:08:28 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 19:08:28 volumio volumio[706]: info: sendMpdCommand playlistinfo took 49 milliseconds May 20 19:08:28 volumio volumio[706]: verbose: ControllerMpd::parseTrackInfo May 20 19:08:28 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:08:28 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:08:28 volumio volumio[706]: info: CorePlayQueue::getTrack 12 May 20 19:08:28 volumio volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":129,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"13 Study No1 In E Minor (Villa-Lobos).ape","artist":null,"album":null,"uri":"USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/13 Study No1 In E Minor (Villa-Lobos).ape","trackType":"ape"} May 20 19:08:28 volumio volumio[706]: verbose: CURRENT POSITION 12 May 20 19:08:28 volumio volumio[706]: info: CoreStateMachine::syncState stateService play May 20 19:08:28 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus stop May 20 19:08:28 volumio volumio[706]: info: ------------------------------ 350ms May 20 19:08:28 volumio volumio[706]: info: sendMpdCommand playlistinfo took 30 milliseconds May 20 19:08:28 volumio volumio[706]: verbose: ControllerMpd::parseTrackInfo May 20 19:08:28 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:08:28 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:08:28 volumio volumio[706]: info: CorePlayQueue::getTrack 12 May 20 19:08:28 volumio volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":129,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"13 Study No1 In E Minor (Villa-Lobos).ape","artist":null,"album":null,"uri":"USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/13 Study No1 In E Minor (Villa-Lobos).ape","trackType":"ape"} May 20 19:08:28 volumio volumio[706]: verbose: CURRENT POSITION 12 May 20 19:08:28 volumio volumio[706]: info: CoreStateMachine::syncState stateService play May 20 19:08:28 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus play May 20 19:08:28 volumio volumio[706]: info: Received an update from plugin. extracting info from payload May 20 19:08:28 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:28 volumio volumio[706]: info: CorePlayQueue::getTrack 12 May 20 19:08:28 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:08:28 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:29 volumio kernel: I/O error, dev sda, sector 22822848 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 May 20 19:08:29 volumio kernel: I/O error, dev sda, sector 22822848 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:08:29 volumio kernel: Buffer I/O error on dev sda1, logical block 2848760, async page read May 20 19:08:29 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:29 volumio mount.exfat[2990]: failed to read cluster 0x15ba9 May 20 19:08:29 volumio volumio[706]: info: CorePlayQueue::getTrack 12 May 20 19:08:29 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:29 volumio volumio[706]: info: ------------------------------ 987ms May 20 19:08:29 volumio mount.exfat[2990]: failed to read cluster 0x15baa May 20 19:08:29 volumio mount.exfat[2990]: failed to read cluster 0x15bab May 20 19:08:29 volumio kernel: I/O error, dev sda, sector 22823104 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 May 20 19:08:29 volumio kernel: I/O error, dev sda, sector 22823104 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:08:29 volumio kernel: Buffer I/O error on dev sda1, logical block 2848792, async page read May 20 19:08:29 volumio kernel: I/O error, dev sda, sector 22823360 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 May 20 19:08:29 volumio kernel: I/O error, dev sda, sector 22823360 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:08:29 volumio kernel: Buffer I/O error on dev sda1, logical block 2848824, async page read May 20 19:08:30 volumio mount.exfat[2990]: failed to read cluster 0x15ba9 May 20 19:08:30 volumio kernel: I/O error, dev sda, sector 22822848 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:08:30 volumio kernel: Buffer I/O error on dev sda1, logical block 2848760, async page read May 20 19:08:30 volumio kernel: I/O error, dev sda, sector 22822848 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:08:30 volumio kernel: Buffer I/O error on dev sda1, logical block 2848760, async page read May 20 19:08:30 volumio kernel: I/O error, dev sda, sector 22822848 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:08:30 volumio kernel: Buffer I/O error on dev sda1, logical block 2848760, async page read May 20 19:08:30 volumio mount.exfat[2990]: failed to read cluster 0x15ba9 May 20 19:08:30 volumio mount.exfat[2990]: failed to read cluster 0x15ba9 May 20 19:08:33 volumio volumio[706]: info: May 20 19:08:33 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:08:33 volumio volumio[706]: info: ControllerMpd::getState May 20 19:08:33 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:08:33 volumio volumio[706]: info: May 20 19:08:33 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:08:33 volumio volumio[706]: info: ControllerMpd::getState May 20 19:08:33 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:08:33 volumio volumio[706]: info: sendMpdCommand status took 45 milliseconds May 20 19:08:33 volumio volumio[706]: info: sendMpdCommand status took 43 milliseconds May 20 19:08:33 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:08:33 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:08:33 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:08:33 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:33 volumio volumio[706]: info: CorePlayQueue::getTrack 12 May 20 19:08:33 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:08:33 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:33 volumio volumio[706]: info: CorePlayQueue::getTrack 12 May 20 19:08:33 volumio volumio[706]: 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 20 19:08:33 volumio volumio[706]: verbose: CURRENT POSITION 12 May 20 19:08:33 volumio volumio[706]: info: CoreStateMachine::syncState stateService stop May 20 19:08:33 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus play May 20 19:08:33 volumio volumio[706]: info: CoreStateMachine::play index undefined May 20 19:08:33 volumio volumio[706]: info: CoreStateMachine::setConsumeUpdateService undefined May 20 19:08:33 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:33 volumio volumio[706]: info: CorePlayQueue::getTrack 13 May 20 19:08:33 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:08:33 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:34 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:08:34 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:08:34 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:34 volumio volumio[706]: info: CorePlayQueue::getTrack 13 May 20 19:08:34 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:34 volumio volumio[706]: info: CorePlayQueue::getTrack 13 May 20 19:08:34 volumio volumio[706]: 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 20 19:08:34 volumio volumio[706]: verbose: CURRENT POSITION 13 May 20 19:08:34 volumio volumio[706]: info: CoreStateMachine::syncState stateService stop May 20 19:08:34 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus stop May 20 19:08:34 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:34 volumio volumio[706]: info: CorePlayQueue::getTrack 13 May 20 19:08:34 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:34 volumio volumio[706]: info: No code May 20 19:08:34 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:34 volumio volumio[706]: info: CorePlayQueue::getTrack 13 May 20 19:08:34 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:34 volumio volumio[706]: info: CorePlayQueue::getTrack 13 May 20 19:08:34 volumio volumio[706]: info: CoreStateMachine::startPlaybackTimer May 20 19:08:34 volumio volumio[706]: info: CorePlayQueue::getTrack 13 May 20 19:08:34 volumio volumio[706]: verbose: ControllerMpd::clearAddPlayTracks USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/14 En Los Trigales (Rodrigo).ape May 20 19:08:34 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand stop May 20 19:08:34 volumio volumio[706]: info: ------------------------------ 406ms May 20 19:08:34 volumio volumio[706]: info: ------------------------------ 395ms May 20 19:08:34 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:34 volumio volumio[706]: info: CorePlayQueue::getTrack 13 May 20 19:08:34 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:08:34 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:34 volumio volumio[706]: info: sendMpdCommand stop took 95 milliseconds May 20 19:08:34 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand clear May 20 19:08:34 volumio volumio[706]: info: May 20 19:08:34 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:08:34 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:08:34 volumio volumio[706]: info: sendMpdCommand clear took 71 milliseconds May 20 19:08:34 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand add "USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/14 En Los Trigales (Rodrigo).ape" May 20 19:08:34 volumio volumio[706]: info: May 20 19:08:34 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:08:34 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:08:34 volumio volumio[706]: error: updateQueue error: null May 20 19:08:34 volumio volumio[706]: info: May 20 19:08:34 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:08:34 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:08:34 volumio volumio[706]: info: ------------------------------ 30ms May 20 19:08:34 volumio volumio[706]: info: sendMpdCommand add "USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/14 En Los Trigales (Rodrigo).ape" took 23 milliseconds May 20 19:08:34 volumio volumio[706]: info: ------------------------------ 22ms May 20 19:08:34 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand play May 20 19:08:34 volumio volumio[706]: info: May 20 19:08:34 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:08:34 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:08:34 volumio volumio[706]: info: ------------------------------ 35ms May 20 19:08:34 volumio volumio[706]: info: sendMpdCommand play took 35 milliseconds May 20 19:08:34 volumio volumio[706]: info: ------------------------------ 32ms May 20 19:08:34 volumio volumio[706]: info: May 20 19:08:34 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:08:34 volumio volumio[706]: info: ControllerMpd::getState May 20 19:08:34 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:08:34 volumio volumio[706]: info: May 20 19:08:34 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:08:34 volumio volumio[706]: info: ControllerMpd::getState May 20 19:08:34 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:08:34 volumio volumio[706]: info: May 20 19:08:34 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:08:34 volumio volumio[706]: info: ControllerMpd::getState May 20 19:08:34 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:08:34 volumio volumio[706]: info: sendMpdCommand status took 30 milliseconds May 20 19:08:34 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:08:34 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 19:08:34 volumio volumio[706]: info: May 20 19:08:34 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:08:34 volumio volumio[706]: info: ControllerMpd::getState May 20 19:08:34 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:08:34 volumio volumio[706]: info: sendMpdCommand status took 125 milliseconds May 20 19:08:34 volumio volumio[706]: info: sendMpdCommand status took 104 milliseconds May 20 19:08:34 volumio volumio[706]: info: sendMpdCommand playlistinfo took 92 milliseconds May 20 19:08:34 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:08:34 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 19:08:34 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:08:34 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 19:08:34 volumio volumio[706]: verbose: ControllerMpd::parseTrackInfo May 20 19:08:34 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:08:34 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:08:34 volumio volumio[706]: info: CorePlayQueue::getTrack 13 May 20 19:08:34 volumio volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":239,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"14 En Los Trigales (Rodrigo).ape","artist":null,"album":null,"uri":"USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/14 En Los Trigales (Rodrigo).ape","trackType":"ape"} May 20 19:08:34 volumio volumio[706]: verbose: CURRENT POSITION 13 May 20 19:08:34 volumio volumio[706]: info: CoreStateMachine::syncState stateService play May 20 19:08:34 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus stop May 20 19:08:34 volumio volumio[706]: info: ------------------------------ 189ms May 20 19:08:34 volumio volumio[706]: info: sendMpdCommand status took 108 milliseconds May 20 19:08:34 volumio volumio[706]: info: sendMpdCommand playlistinfo took 49 milliseconds May 20 19:08:34 volumio volumio[706]: info: sendMpdCommand playlistinfo took 59 milliseconds May 20 19:08:34 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:08:34 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 19:08:34 volumio volumio[706]: verbose: ControllerMpd::parseTrackInfo May 20 19:08:34 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:08:34 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:08:34 volumio volumio[706]: info: CorePlayQueue::getTrack 13 May 20 19:08:34 volumio volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":239,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"14 En Los Trigales (Rodrigo).ape","artist":null,"album":null,"uri":"USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/14 En Los Trigales (Rodrigo).ape","trackType":"ape"} May 20 19:08:34 volumio volumio[706]: verbose: CURRENT POSITION 13 May 20 19:08:34 volumio volumio[706]: info: CoreStateMachine::syncState stateService play May 20 19:08:34 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus play May 20 19:08:34 volumio volumio[706]: info: Received an update from plugin. extracting info from payload May 20 19:08:34 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:34 volumio volumio[706]: info: CorePlayQueue::getTrack 13 May 20 19:08:34 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:08:34 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:34 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:34 volumio volumio[706]: info: CorePlayQueue::getTrack 13 May 20 19:08:34 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:34 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:08:34 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:08:34 volumio volumio[706]: info: CorePlayQueue::getTrack 13 May 20 19:08:34 volumio volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":239,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"14 En Los Trigales (Rodrigo).ape","artist":null,"album":null,"uri":"USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/14 En Los Trigales (Rodrigo).ape","trackType":"ape"} May 20 19:08:34 volumio volumio[706]: verbose: CURRENT POSITION 13 May 20 19:08:34 volumio volumio[706]: info: CoreStateMachine::syncState stateService play May 20 19:08:34 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus play May 20 19:08:34 volumio volumio[706]: info: Received an update from plugin. extracting info from payload May 20 19:08:34 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:34 volumio volumio[706]: info: CorePlayQueue::getTrack 13 May 20 19:08:34 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:34 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:34 volumio volumio[706]: info: CorePlayQueue::getTrack 13 May 20 19:08:34 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:35 volumio volumio[706]: info: ------------------------------ 831ms May 20 19:08:35 volumio volumio[706]: info: ------------------------------ 846ms May 20 19:08:35 volumio volumio[706]: info: sendMpdCommand playlistinfo took 693 milliseconds May 20 19:08:35 volumio mount.exfat[2990]: failed to read cluster 0x15be0 May 20 19:08:35 volumio kernel: I/O error, dev sda, sector 22836928 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 May 20 19:08:35 volumio kernel: I/O error, dev sda, sector 22836928 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:08:35 volumio kernel: Buffer I/O error on dev sda1, logical block 2850520, async page read May 20 19:08:35 volumio volumio[706]: verbose: ControllerMpd::parseTrackInfo May 20 19:08:35 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:08:35 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:08:35 volumio volumio[706]: info: CorePlayQueue::getTrack 13 May 20 19:08:35 volumio volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":239,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"14 En Los Trigales (Rodrigo).ape","artist":null,"album":null,"uri":"USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/14 En Los Trigales (Rodrigo).ape","trackType":"ape"} May 20 19:08:35 volumio volumio[706]: verbose: CURRENT POSITION 13 May 20 19:08:35 volumio volumio[706]: info: CoreStateMachine::syncState stateService play May 20 19:08:35 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus play May 20 19:08:35 volumio volumio[706]: info: Received an update from plugin. extracting info from payload May 20 19:08:35 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:35 volumio volumio[706]: info: CorePlayQueue::getTrack 13 May 20 19:08:35 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:35 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:35 volumio volumio[706]: info: CorePlayQueue::getTrack 13 May 20 19:08:35 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:36 volumio volumio[706]: info: ------------------------------ 1520ms May 20 19:08:36 volumio mount.exfat[2990]: failed to read cluster 0x15be1 May 20 19:08:36 volumio mount.exfat[2990]: failed to read cluster 0x15be2 May 20 19:08:36 volumio kernel: I/O error, dev sda, sector 22837184 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 May 20 19:08:36 volumio kernel: I/O error, dev sda, sector 22837184 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:08:36 volumio kernel: Buffer I/O error on dev sda1, logical block 2850552, async page read May 20 19:08:36 volumio kernel: I/O error, dev sda, sector 22837440 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 May 20 19:08:36 volumio kernel: I/O error, dev sda, sector 22837440 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:08:36 volumio kernel: Buffer I/O error on dev sda1, logical block 2850584, async page read May 20 19:08:37 volumio mount.exfat[2990]: failed to read cluster 0x15be0 May 20 19:08:37 volumio kernel: I/O error, dev sda, sector 22836928 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:08:37 volumio kernel: Buffer I/O error on dev sda1, logical block 2850520, async page read May 20 19:08:37 volumio kernel: I/O error, dev sda, sector 22836928 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:08:37 volumio kernel: Buffer I/O error on dev sda1, logical block 2850520, async page read May 20 19:08:37 volumio kernel: I/O error, dev sda, sector 22836928 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:08:37 volumio kernel: Buffer I/O error on dev sda1, logical block 2850520, async page read May 20 19:08:37 volumio mount.exfat[2990]: failed to read cluster 0x15be0 May 20 19:08:37 volumio mount.exfat[2990]: failed to read cluster 0x15be0 May 20 19:08:41 volumio volumio[706]: info: May 20 19:08:41 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:08:41 volumio volumio[706]: info: ControllerMpd::getState May 20 19:08:41 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:08:41 volumio volumio[706]: info: May 20 19:08:41 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:08:41 volumio volumio[706]: info: ControllerMpd::getState May 20 19:08:41 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:08:41 volumio volumio[706]: info: sendMpdCommand status took 20 milliseconds May 20 19:08:41 volumio volumio[706]: info: sendMpdCommand status took 12 milliseconds May 20 19:08:41 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:08:41 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:08:41 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:08:41 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:41 volumio volumio[706]: info: CorePlayQueue::getTrack 13 May 20 19:08:41 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:08:41 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:41 volumio volumio[706]: info: CorePlayQueue::getTrack 13 May 20 19:08:41 volumio volumio[706]: 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 20 19:08:41 volumio volumio[706]: verbose: CURRENT POSITION 13 May 20 19:08:41 volumio volumio[706]: info: CoreStateMachine::syncState stateService stop May 20 19:08:41 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus play May 20 19:08:41 volumio volumio[706]: info: CoreStateMachine::play index undefined May 20 19:08:41 volumio volumio[706]: info: CoreStateMachine::setConsumeUpdateService undefined May 20 19:08:41 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:41 volumio volumio[706]: info: CorePlayQueue::getTrack 14 May 20 19:08:41 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:08:41 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:41 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:08:41 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:08:41 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:41 volumio volumio[706]: info: CorePlayQueue::getTrack 14 May 20 19:08:41 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:41 volumio volumio[706]: info: CorePlayQueue::getTrack 14 May 20 19:08:41 volumio volumio[706]: 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 20 19:08:41 volumio volumio[706]: verbose: CURRENT POSITION 14 May 20 19:08:41 volumio volumio[706]: info: CoreStateMachine::syncState stateService stop May 20 19:08:41 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus stop May 20 19:08:41 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:41 volumio volumio[706]: info: CorePlayQueue::getTrack 14 May 20 19:08:41 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:41 volumio volumio[706]: info: No code May 20 19:08:41 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:41 volumio volumio[706]: info: CorePlayQueue::getTrack 14 May 20 19:08:41 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:42 volumio volumio[706]: info: CorePlayQueue::getTrack 14 May 20 19:08:42 volumio volumio[706]: info: CoreStateMachine::startPlaybackTimer May 20 19:08:42 volumio volumio[706]: info: CorePlayQueue::getTrack 14 May 20 19:08:42 volumio volumio[706]: verbose: ControllerMpd::clearAddPlayTracks USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/15 Vals Venezolano (Borges).ape May 20 19:08:42 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand stop May 20 19:08:42 volumio volumio[706]: info: ------------------------------ 342ms May 20 19:08:42 volumio volumio[706]: info: ------------------------------ 336ms May 20 19:08:42 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:42 volumio volumio[706]: info: CorePlayQueue::getTrack 14 May 20 19:08:42 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:08:42 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:42 volumio volumio[706]: info: sendMpdCommand stop took 182 milliseconds May 20 19:08:42 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand clear May 20 19:08:42 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 20 19:08:42 volumio volumio[706]: info: May 20 19:08:42 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:08:42 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:08:42 volumio volumio[706]: info: sendMpdCommand clear took 144 milliseconds May 20 19:08:42 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand add "USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/15 Vals Venezolano (Borges).ape" May 20 19:08:42 volumio volumio[706]: info: May 20 19:08:42 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:08:42 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:08:42 volumio volumio[706]: info: Preload queue cleared May 20 19:08:42 volumio volumio[706]: error: updateQueue error: null May 20 19:08:42 volumio volumio[706]: info: May 20 19:08:42 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:08:42 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:08:42 volumio volumio[706]: info: ------------------------------ 74ms May 20 19:08:42 volumio volumio[706]: info: sendMpdCommand add "USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/15 Vals Venezolano (Borges).ape" took 71 milliseconds May 20 19:08:42 volumio volumio[706]: info: ------------------------------ 26ms May 20 19:08:42 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand play May 20 19:08:42 volumio volumio[706]: info: May 20 19:08:42 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:08:42 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:08:42 volumio volumio[706]: info: ------------------------------ 43ms May 20 19:08:42 volumio volumio[706]: info: sendMpdCommand play took 39 milliseconds May 20 19:08:42 volumio volumio[706]: info: ------------------------------ 35ms May 20 19:08:42 volumio volumio[706]: info: May 20 19:08:42 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:08:42 volumio volumio[706]: info: ControllerMpd::getState May 20 19:08:42 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:08:42 volumio volumio[706]: info: May 20 19:08:42 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:08:42 volumio volumio[706]: info: ControllerMpd::getState May 20 19:08:42 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:08:42 volumio volumio[706]: info: sendMpdCommand status took 25 milliseconds May 20 19:08:42 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:08:42 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 19:08:42 volumio volumio[706]: info: sendMpdCommand status took 45 milliseconds May 20 19:08:42 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:08:42 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 19:08:42 volumio volumio[706]: info: sendMpdCommand playlistinfo took 36 milliseconds May 20 19:08:42 volumio volumio[706]: verbose: ControllerMpd::parseTrackInfo May 20 19:08:42 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:08:42 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:08:42 volumio volumio[706]: info: CorePlayQueue::getTrack 14 May 20 19:08:42 volumio volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":142,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"15 Vals Venezolano (Borges).ape","artist":null,"album":null,"uri":"USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/15 Vals Venezolano (Borges).ape","trackType":"ape"} May 20 19:08:42 volumio volumio[706]: verbose: CURRENT POSITION 14 May 20 19:08:42 volumio volumio[706]: info: CoreStateMachine::syncState stateService play May 20 19:08:42 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus stop May 20 19:08:42 volumio volumio[706]: info: ------------------------------ 105ms May 20 19:08:42 volumio volumio[706]: info: sendMpdCommand playlistinfo took 36 milliseconds May 20 19:08:42 volumio volumio[706]: verbose: ControllerMpd::parseTrackInfo May 20 19:08:42 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:08:42 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:08:42 volumio volumio[706]: info: CorePlayQueue::getTrack 14 May 20 19:08:42 volumio volumio[706]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":142,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"15 Vals Venezolano (Borges).ape","artist":null,"album":null,"uri":"USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/15 Vals Venezolano (Borges).ape","trackType":"ape"} May 20 19:08:42 volumio volumio[706]: verbose: CURRENT POSITION 14 May 20 19:08:42 volumio volumio[706]: info: CoreStateMachine::syncState stateService play May 20 19:08:42 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus play May 20 19:08:42 volumio volumio[706]: info: Received an update from plugin. extracting info from payload May 20 19:08:42 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:42 volumio volumio[706]: info: CorePlayQueue::getTrack 14 May 20 19:08:42 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:08:42 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:42 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:42 volumio volumio[706]: info: CorePlayQueue::getTrack 14 May 20 19:08:42 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:43 volumio volumio[706]: info: ------------------------------ 376ms May 20 19:08:43 volumio kernel: I/O error, dev sda, sector 22860992 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 May 20 19:08:43 volumio kernel: I/O error, dev sda, sector 22860992 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:08:43 volumio kernel: Buffer I/O error on dev sda1, logical block 2853528, async page read May 20 19:08:43 volumio mount.exfat[2990]: failed to read cluster 0x15c3e May 20 19:08:43 volumio kernel: I/O error, dev sda, sector 22861248 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 May 20 19:08:43 volumio mount.exfat[2990]: failed to read cluster 0x15c3f May 20 19:08:43 volumio mount.exfat[2990]: failed to read cluster 0x15c40 May 20 19:08:43 volumio kernel: I/O error, dev sda, sector 22861248 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:08:43 volumio kernel: Buffer I/O error on dev sda1, logical block 2853560, async page read May 20 19:08:43 volumio kernel: I/O error, dev sda, sector 22861504 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 May 20 19:08:43 volumio kernel: I/O error, dev sda, sector 22861504 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:08:43 volumio kernel: Buffer I/O error on dev sda1, logical block 2853592, async page read May 20 19:08:44 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 20 19:08:44 volumio mount.exfat[2990]: failed to read cluster 0x15c3e May 20 19:08:44 volumio kernel: I/O error, dev sda, sector 22860992 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:08:44 volumio kernel: Buffer I/O error on dev sda1, logical block 2853528, async page read May 20 19:08:44 volumio kernel: I/O error, dev sda, sector 22860992 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:08:44 volumio kernel: Buffer I/O error on dev sda1, logical block 2853528, async page read May 20 19:08:44 volumio kernel: I/O error, dev sda, sector 22860992 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:08:44 volumio kernel: Buffer I/O error on dev sda1, logical block 2853528, async page read May 20 19:08:44 volumio mount.exfat[2990]: failed to read cluster 0x15c3e May 20 19:08:44 volumio mount.exfat[2990]: failed to read cluster 0x15c3e May 20 19:08:45 volumio volumio[706]: info: Preload queue cleared May 20 19:08:46 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 20 19:08:46 volumio volumio[706]: info: Preload queue cleared May 20 19:08:49 volumio volumio[706]: info: May 20 19:08:49 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:08:49 volumio volumio[706]: info: ControllerMpd::getState May 20 19:08:49 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:08:50 volumio volumio[706]: info: May 20 19:08:50 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:08:50 volumio volumio[706]: info: ControllerMpd::getState May 20 19:08:50 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:08:50 volumio volumio[706]: info: sendMpdCommand status took 15 milliseconds May 20 19:08:50 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:08:50 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:08:50 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:08:50 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:50 volumio volumio[706]: info: CorePlayQueue::getTrack 14 May 20 19:08:50 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:08:50 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:50 volumio volumio[706]: info: CorePlayQueue::getTrack 14 May 20 19:08:50 volumio volumio[706]: 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 20 19:08:50 volumio volumio[706]: verbose: CURRENT POSITION 14 May 20 19:08:50 volumio volumio[706]: info: CoreStateMachine::syncState stateService stop May 20 19:08:50 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus play May 20 19:08:50 volumio volumio[706]: info: CoreStateMachine::play index undefined May 20 19:08:50 volumio volumio[706]: info: CoreStateMachine::setConsumeUpdateService undefined May 20 19:08:50 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:50 volumio volumio[706]: info: CorePlayQueue::getTrack 15 May 20 19:08:50 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:08:50 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:50 volumio volumio[706]: info: CorePlayQueue::getTrack 15 May 20 19:08:50 volumio volumio[706]: info: CoreStateMachine::startPlaybackTimer May 20 19:08:50 volumio volumio[706]: info: CorePlayQueue::getTrack 15 May 20 19:08:50 volumio volumio[706]: verbose: ControllerMpd::clearAddPlayTracks USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/16 Andaluza Op37 No5 (Granados).ape May 20 19:08:50 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand stop May 20 19:08:50 volumio volumio[706]: info: ------------------------------ 219ms May 20 19:08:50 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:50 volumio volumio[706]: info: CorePlayQueue::getTrack 15 May 20 19:08:50 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:08:50 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:50 volumio volumio[706]: info: sendMpdCommand status took 267 milliseconds May 20 19:08:50 volumio volumio[706]: info: sendMpdCommand stop took 77 milliseconds May 20 19:08:50 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:08:50 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand clear May 20 19:08:50 volumio volumio[706]: info: ControllerMpd::pushState May 20 19:08:50 volumio volumio[706]: info: CoreCommandRouter::servicePushState May 20 19:08:50 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:50 volumio volumio[706]: info: CorePlayQueue::getTrack 15 May 20 19:08:50 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 19:08:50 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:50 volumio volumio[706]: info: CorePlayQueue::getTrack 15 May 20 19:08:50 volumio volumio[706]: 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 20 19:08:50 volumio volumio[706]: verbose: CURRENT POSITION 15 May 20 19:08:50 volumio volumio[706]: info: CoreStateMachine::syncState stateService stop May 20 19:08:50 volumio volumio[706]: info: CoreStateMachine::syncState currentStatus stop May 20 19:08:50 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:50 volumio volumio[706]: info: CorePlayQueue::getTrack 15 May 20 19:08:50 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:50 volumio volumio[706]: info: No code May 20 19:08:50 volumio volumio[706]: info: CoreStateMachine::pushState May 20 19:08:50 volumio volumio[706]: info: CorePlayQueue::getTrack 15 May 20 19:08:50 volumio volumio[706]: info: CoreCommandRouter::volumioPushState May 20 19:08:50 volumio volumio[706]: info: ------------------------------ 473ms May 20 19:08:50 volumio volumio[706]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 20 19:08:50 volumio volumio[706]: info: May 20 19:08:50 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:08:50 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:08:50 volumio volumio[706]: info: sendMpdCommand clear took 276 milliseconds May 20 19:08:50 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand add "USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/16 Andaluza Op37 No5 (Granados).ape" May 20 19:08:50 volumio volumio[706]: info: May 20 19:08:50 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:08:50 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:08:50 volumio volumio[706]: error: updateQueue error: null May 20 19:08:50 volumio volumio[706]: info: May 20 19:08:50 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:08:50 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:08:50 volumio volumio[706]: info: ------------------------------ 35ms May 20 19:08:50 volumio volumio[706]: info: sendMpdCommand add "USB/E40A-4AF3/classic/클래식 기타 [FLAC]/Julian Byzantine-Popular Guitar Classics/16 Andaluza Op37 No5 (Granados).ape" took 31 milliseconds May 20 19:08:50 volumio volumio[706]: info: ------------------------------ 23ms May 20 19:08:50 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand play May 20 19:08:50 volumio volumio[706]: info: May 20 19:08:50 volumio volumio[706]: ---------------------------- MPD announces system playlist update May 20 19:08:50 volumio volumio[706]: info: Ignoring MPD Status Update May 20 19:08:50 volumio volumio[706]: info: May 20 19:08:50 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:08:50 volumio volumio[706]: info: ------------------------------ 119ms May 20 19:08:50 volumio volumio[706]: info: sendMpdCommand play took 112 milliseconds May 20 19:08:50 volumio volumio[706]: info: ------------------------------ 113ms May 20 19:08:50 volumio volumio[706]: info: ControllerMpd::getState May 20 19:08:50 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:08:50 volumio volumio[706]: info: May 20 19:08:50 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:08:50 volumio volumio[706]: info: ControllerMpd::getState May 20 19:08:50 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:08:50 volumio volumio[706]: info: May 20 19:08:50 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:08:50 volumio volumio[706]: info: ControllerMpd::getState May 20 19:08:50 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:08:50 volumio volumio[706]: info: sendMpdCommand status took 51 milliseconds May 20 19:08:50 volumio volumio[706]: info: sendMpdCommand status took 43 milliseconds May 20 19:08:50 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:08:50 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 19:08:50 volumio volumio[706]: verbose: ControllerMpd::parseState May 20 19:08:50 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 20 19:08:50 volumio volumio[706]: info: May 20 19:08:50 volumio volumio[706]: ---------------------------- MPD announces state update: player May 20 19:08:50 volumio volumio[706]: info: ControllerMpd::getState May 20 19:08:50 volumio volumio[706]: verbose: ControllerMpd::sendMpdCommand status May 20 19:08:50 volumio volumio[706]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 20 19:08:51 volumio volumio[706]: TypeError: Cannot read property 'length' of undefined May 20 19:08:51 volumio volumio[706]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 May 20 19:08:51 volumio volumio[706]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 May 20 19:08:51 volumio volumio[706]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) May 20 19:08:51 volumio volumio[706]: at Parser.emit (events.js:315:20) May 20 19:08:51 volumio volumio[706]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) May 20 19:08:51 volumio volumio[706]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) May 20 19:08:51 volumio volumio[706]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) May 20 19:08:51 volumio volumio[706]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) May 20 19:08:51 volumio volumio[706]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) May 20 19:08:51 volumio volumio[706]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) May 20 19:08:51 volumio volumio[706]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) May 20 19:08:51 volumio volumio[706]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) May 20 19:08:51 volumio volumio[706]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) May 20 19:08:51 volumio volumio[706]: at IncomingMessage.emit (events.js:327:22) May 20 19:08:51 volumio volumio[706]: at endReadableNT (internal/streams/readable.js:1327:12) May 20 19:08:51 volumio volumio[706]: at processTicksAndRejections (internal/process/task_queues.js:80:21) May 20 19:08:51 volumio volumio[706]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 20 19:08:51 volumio mount.exfat[2990]: failed to read cluster 0x15c7e May 20 19:08:51 volumio kernel: I/O error, dev sda, sector 22877376 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 May 20 19:08:51 volumio kernel: I/O error, dev sda, sector 22877376 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:08:51 volumio kernel: Buffer I/O error on dev sda1, logical block 2855576, async page read May 20 19:08:52 volumio mount.exfat[2990]: failed to read cluster 0x15c7f May 20 19:08:52 volumio kernel: I/O error, dev sda, sector 22877632 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 May 20 19:08:52 volumio kernel: I/O error, dev sda, sector 22877632 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:08:52 volumio kernel: Buffer I/O error on dev sda1, logical block 2855608, async page read May 20 19:08:52 volumio kernel: I/O error, dev sda, sector 22877888 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 May 20 19:08:52 volumio kernel: I/O error, dev sda, sector 22877888 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:08:52 volumio kernel: Buffer I/O error on dev sda1, logical block 2855640, async page read May 20 19:08:52 volumio mount.exfat[2990]: failed to read cluster 0x15c80 May 20 19:08:53 volumio mount.exfat[2990]: failed to read cluster 0x15c7e May 20 19:08:53 volumio kernel: I/O error, dev sda, sector 22877376 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:08:53 volumio kernel: Buffer I/O error on dev sda1, logical block 2855576, async page read May 20 19:08:53 volumio kernel: I/O error, dev sda, sector 22877376 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:08:53 volumio kernel: Buffer I/O error on dev sda1, logical block 2855576, async page read May 20 19:08:53 volumio kernel: I/O error, dev sda, sector 22877376 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 20 19:08:53 volumio kernel: Buffer I/O error on dev sda1, logical block 2855576, async page read May 20 19:08:53 volumio mount.exfat[2990]: failed to read cluster 0x15c7e May 20 19:08:53 volumio mount.exfat[2990]: failed to read cluster 0x15c7e May 20 19:08:55 volumio sudo[5621]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-20 19:07 May 20 19:08:55 volumio sudo[5621]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"