-- Logs begin at Sat 2021-12-11 09:45:07 UTC, end at Tue 2024-10-08 07:54:34 UTC. -- Oct 08 07:53:08 volumio volumio[904]: info: CoreCommandRouter::volumioNext Oct 08 07:53:08 volumio volumio[904]: info: CoreStateMachine::next Oct 08 07:53:08 volumio volumio[904]: info: CoreStateMachine::stop Oct 08 07:53:08 volumio volumio[904]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 08 07:53:08 volumio volumio[904]: verbose: UNSET VOLATILE Oct 08 07:53:09 volumio volumio[904]: info: CoreStateMachine::stPlaybackTimer Oct 08 07:53:09 volumio volumio[904]: info: CoreStateMachine::updateTrackBlock Oct 08 07:53:09 volumio volumio[904]: info: CorePlayQueue::getTrackBlock Oct 08 07:53:09 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:53:09 volumio volumio[904]: info: CorePlayQueue::getTrack 1 Oct 08 07:53:09 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:53:09 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:53:09 volumio volumio[904]: info: CoreStateMachine::serviceStop Oct 08 07:53:09 volumio volumio[904]: info: CorePlayQueue::getTrack 1 Oct 08 07:53:09 volumio volumio[904]: info: CoreCommandRouter::serviceStop Oct 08 07:53:09 volumio volumio[904]: info: ControllerMpd::stop Oct 08 07:53:09 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand stop Oct 08 07:53:09 volumio volumio[904]: info: touch_display: Setting screensaver timeout to 0 seconds. Oct 08 07:53:09 volumio volumio[904]: info: Oct 08 07:53:09 volumio volumio[904]: ---------------------------- MPD announces state update: player Oct 08 07:53:09 volumio volumio[904]: info: sendMpdCommand stop took 76 milliseconds Oct 08 07:53:09 volumio volumio[904]: info: ControllerMpd::getState Oct 08 07:53:09 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:53:09 volumio volumio[904]: info: CoreStateMachine::play index undefined Oct 08 07:53:09 volumio volumio[904]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 08 07:53:09 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:53:09 volumio volumio[904]: info: CoreStateMachine::startPlaybackTimer Oct 08 07:53:09 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:53:09 volumio volumio[904]: verbose: ControllerMpd::clearAddPlayTracks USB/5426-A066/3 王杰 极品精选/03 - 幾分傷心幾分癡.dff Oct 08 07:53:09 volumio volumio[904]: info: Setting Volume to 100 automatically for DSD Oct 08 07:53:09 volumio volumio[904]: info: VolumeController::SetAlsaVolume100 Oct 08 07:53:09 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand stop Oct 08 07:53:09 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:53:09 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:53:09 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:53:09 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:53:09 volumio volumio[904]: info: CoreStateMachine::updateTrackBlock Oct 08 07:53:09 volumio volumio[904]: info: CorePlayQueue::getTrackBlock Oct 08 07:53:09 volumio volumio[904]: info: sendMpdCommand status took 24 milliseconds Oct 08 07:53:09 volumio volumio[904]: info: sendMpdCommand stop took 13 milliseconds Oct 08 07:53:09 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:53:09 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 08 07:53:09 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand clear Oct 08 07:53:09 volumio volumio[904]: info: Oct 08 07:53:09 volumio volumio[904]: ---------------------------- MPD announces system playlist update Oct 08 07:53:09 volumio volumio[904]: info: Ignoring MPD Status Update Oct 08 07:53:09 volumio volumio[904]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 08 07:53:09 volumio volumio[904]: info: sendMpdCommand clear took 10 milliseconds Oct 08 07:53:09 volumio volumio[904]: verbose: ControllerMpd::parseTrackInfo Oct 08 07:53:09 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand add "USB/5426-A066/3 王杰 极品精选/03 - 幾分傷心幾分癡.dff" Oct 08 07:53:09 volumio volumio[904]: info: ControllerMpd::pushState Oct 08 07:53:09 volumio volumio[904]: info: CoreCommandRouter::servicePushState Oct 08 07:53:09 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:53:09 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:53:09 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:53:09 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:53:09 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:53:09 volumio volumio[904]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02 - 今生無悔.dff","artist":null,"album":null,"uri":"USB/5426-A066/3 王杰 极品精选/02 - 今生無悔.dff","trackType":"dff"} Oct 08 07:53:09 volumio volumio[904]: verbose: CURRENT POSITION 2 Oct 08 07:53:09 volumio volumio[904]: info: CoreStateMachine::syncState stateService stop Oct 08 07:53:09 volumio volumio[904]: info: CoreStateMachine::syncState currentStatus stop Oct 08 07:53:09 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:53:09 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:53:09 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:53:09 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:53:09 volumio volumio[904]: info: No code Oct 08 07:53:09 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:53:09 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:53:09 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:53:09 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:53:09 volumio volumio[904]: info: ------------------------------ 61ms Oct 08 07:53:09 volumio volumio[904]: error: updateQueue error: null Oct 08 07:53:09 volumio volumio[904]: info: Oct 08 07:53:09 volumio volumio[904]: ---------------------------- MPD announces system playlist update Oct 08 07:53:09 volumio volumio[904]: info: Ignoring MPD Status Update Oct 08 07:53:09 volumio volumio[904]: info: ------------------------------ 39ms Oct 08 07:53:09 volumio volumio[904]: info: sendMpdCommand add "USB/5426-A066/3 王杰 极品精选/03 - 幾分傷心幾分癡.dff" took 38 milliseconds Oct 08 07:53:09 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand play Oct 08 07:53:09 volumio volumio[904]: info: Oct 08 07:53:09 volumio volumio[904]: ---------------------------- MPD announces state update: player Oct 08 07:53:09 volumio volumio[904]: info: ------------------------------ 50ms Oct 08 07:53:09 volumio volumio[904]: info: sendMpdCommand play took 49 milliseconds Oct 08 07:53:09 volumio volumio[904]: info: ControllerMpd::getState Oct 08 07:53:09 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:53:09 volumio volumio[904]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Oct 08 07:53:09 volumio volumio[904]: info: Oct 08 07:53:09 volumio volumio[904]: ---------------------------- MPD announces state update: player Oct 08 07:53:09 volumio volumio[904]: info: ControllerMpd::getState Oct 08 07:53:09 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:53:09 volumio volumio[904]: info: sendMpdCommand status took 115 milliseconds Oct 08 07:53:09 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:53:09 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 08 07:53:09 volumio volumio[904]: info: sendMpdCommand status took 3 milliseconds Oct 08 07:53:09 volumio volumio[904]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 08 07:53:09 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:53:09 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 08 07:53:09 volumio volumio[904]: verbose: ControllerMpd::parseTrackInfo Oct 08 07:53:09 volumio volumio[904]: info: ControllerMpd::pushState Oct 08 07:53:09 volumio volumio[904]: info: CoreCommandRouter::servicePushState Oct 08 07:53:09 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:53:09 volumio volumio[904]: verbose: STATE SERVICE {"status":"play","position":0,"seek":75,"duration":252,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"03 - 幾分傷心幾分癡.dff","artist":null,"album":null,"uri":"USB/5426-A066/3 王杰 极品精选/03 - 幾分傷心幾分癡.dff","trackType":"dff"} Oct 08 07:53:09 volumio volumio[904]: verbose: CURRENT POSITION 2 Oct 08 07:53:09 volumio volumio[904]: info: CoreStateMachine::syncState stateService play Oct 08 07:53:09 volumio volumio[904]: info: CoreStateMachine::syncState currentStatus stop Oct 08 07:53:09 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:53:09 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:53:09 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:53:09 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:53:09 volumio volumio[904]: info: ------------------------------ 127ms Oct 08 07:53:09 volumio volumio[904]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 08 07:53:09 volumio volumio[904]: verbose: ControllerMpd::parseTrackInfo Oct 08 07:53:09 volumio volumio[904]: info: ControllerMpd::pushState Oct 08 07:53:09 volumio volumio[904]: info: CoreCommandRouter::servicePushState Oct 08 07:53:09 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:53:09 volumio volumio[904]: verbose: STATE SERVICE {"status":"play","position":0,"seek":516,"duration":252,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"03 - 幾分傷心幾分癡.dff","artist":null,"album":null,"uri":"USB/5426-A066/3 王杰 极品精选/03 - 幾分傷心幾分癡.dff","trackType":"dff"} Oct 08 07:53:09 volumio volumio[904]: verbose: CURRENT POSITION 2 Oct 08 07:53:09 volumio volumio[904]: info: CoreStateMachine::syncState stateService play Oct 08 07:53:09 volumio volumio[904]: info: CoreStateMachine::syncState currentStatus play Oct 08 07:53:09 volumio volumio[904]: info: Received an update from plugin. extracting info from payload Oct 08 07:53:09 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:53:09 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:53:09 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:53:09 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:53:09 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:53:09 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:53:09 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:53:09 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:53:09 volumio volumio[904]: info: ------------------------------ 26ms Oct 08 07:53:09 volumio volumio[904]: info: touch_display: Setting screensaver timeout to 0 seconds. Oct 08 07:53:44 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=0x00 Oct 08 07:53:44 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 03 7f d4 70 00 00 f0 00 Oct 08 07:53:44 volumio kernel: print_req_error: I/O error, dev sda, sector 58709104 Oct 08 07:53:44 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=0x00 Oct 08 07:53:44 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 03 7f d5 60 00 00 f0 00 Oct 08 07:53:44 volumio kernel: print_req_error: I/O error, dev sda, sector 58709344 Oct 08 07:53:44 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=0x00 Oct 08 07:53:44 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 03 7f d6 50 00 00 20 00 Oct 08 07:53:44 volumio kernel: print_req_error: I/O error, dev sda, sector 58709584 Oct 08 07:53:44 volumio kernel: usb 1-1.1: USB disconnect, device number 8 Oct 08 07:53:44 volumio kernel: print_req_error: I/O error, dev sda, sector 58709616 Oct 08 07:53:44 volumio kernel: print_req_error: I/O error, dev sda, sector 58709856 Oct 08 07:53:44 volumio kernel: print_req_error: I/O error, dev sda, sector 58710096 Oct 08 07:53:44 volumio kernel: print_req_error: I/O error, dev sda, sector 58709104 Oct 08 07:53:44 volumio kernel: print_req_error: I/O error, dev sda, sector 58709104 Oct 08 07:53:44 volumio udisks-glue[609]: Device file /dev/sda1 unmounted from /media/5426-A066 Oct 08 07:53:44 volumio udisks-glue[609]: Device file /dev/sda1 removed Oct 08 07:53:44 volumio udisks-glue[609]: Device file /dev/sda removed Oct 08 07:53:44 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:53:44 volumio udisks-glue[609]: USB/5426-A066/3 王杰 极品精选/03 - 幾分傷心幾分癡.dff Oct 08 07:53:44 volumio udisks-glue[609]: [playing] #1/1 0:36/4:12 (14%) Oct 08 07:53:44 volumio udisks-glue[609]: Updating DB (#7) ... Oct 08 07:53:44 volumio udisks-glue[609]: volume: 40% repeat: off random: off single: off consume: off Oct 08 07:53:44 volumio volumio[904]: info: Oct 08 07:53:44 volumio volumio[904]: ---------------------------- MPD announces state update: update Oct 08 07:53:44 volumio volumio[904]: info: ControllerMpd::getState Oct 08 07:53:44 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:53:44 volumio volumio[904]: info: sendMpdCommand status took 1 milliseconds Oct 08 07:53:44 volumio volumio[904]: info: sendMpdCommand status took 1 milliseconds Oct 08 07:53:44 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:53:44 volumio udisks-glue[609]: USB/5426-A066/3 王杰 极品精选/03 - 幾分傷心幾分癡.dff Oct 08 07:53:44 volumio udisks-glue[609]: [playing] #1/1 0:36/4:12 (14%) Oct 08 07:53:44 volumio udisks-glue[609]: Updating DB (#7) ... Oct 08 07:53:44 volumio udisks-glue[609]: volume: 40% repeat: off random: off single: off consume: off Oct 08 07:53:44 volumio volumio[904]: info: Command Router : Notfying DB Updatetrue Oct 08 07:53:44 volumio volumio[904]: info: CoreCommandRouter::Close All Modals sent Oct 08 07:53:44 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:53:44 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 08 07:53:44 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:53:44 volumio volumio[904]: info: Oct 08 07:53:44 volumio volumio[904]: ---------------------------- MPD announces state update: update Oct 08 07:53:44 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:53:44 volumio volumio[904]: info: Oct 08 07:53:44 volumio volumio[904]: ---------------------------- MPD announces state update: update Oct 08 07:53:44 volumio volumio[904]: info: ControllerMpd::getState Oct 08 07:53:44 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:53:44 volumio volumio[904]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 08 07:53:44 volumio volumio[904]: info: ControllerMpd::getState Oct 08 07:53:44 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:53:44 volumio volumio[904]: verbose: ControllerMpd::parseTrackInfo Oct 08 07:53:44 volumio volumio[904]: info: ControllerMpd::pushState Oct 08 07:53:44 volumio volumio[904]: info: CoreCommandRouter::servicePushState Oct 08 07:53:44 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:53:44 volumio volumio[904]: verbose: STATE SERVICE {"status":"play","position":0,"seek":35770,"duration":252,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"03 - 幾分傷心幾分癡.dff","artist":null,"album":null,"uri":"USB/5426-A066/3 王杰 极品精选/03 - 幾分傷心幾分癡.dff","trackType":"dff"} Oct 08 07:53:44 volumio volumio[904]: verbose: CURRENT POSITION 2 Oct 08 07:53:44 volumio volumio[904]: info: CoreStateMachine::syncState stateService play Oct 08 07:53:44 volumio volumio[904]: info: CoreStateMachine::syncState currentStatus play Oct 08 07:53:44 volumio volumio[904]: info: Received an update from plugin. extracting info from payload Oct 08 07:53:44 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:53:44 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:53:44 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:53:44 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:53:44 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:53:44 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:53:44 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:53:44 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:53:44 volumio volumio[904]: info: ------------------------------ 61ms Oct 08 07:53:44 volumio volumio[904]: info: sendMpdCommand status took 34 milliseconds Oct 08 07:53:44 volumio volumio[904]: info: sendMpdCommand status took 33 milliseconds Oct 08 07:53:44 volumio volumio[904]: info: sendMpdCommand status took 33 milliseconds Oct 08 07:53:44 volumio volumio[904]: info: sendMpdCommand status took 33 milliseconds Oct 08 07:53:44 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:53:44 volumio volumio[904]: info: Command Router : Notfying DB Updatefalse Oct 08 07:53:44 volumio volumio[904]: info: CoreCommandRouter::Close All Modals sent Oct 08 07:53:44 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:53:44 volumio volumio[904]: info: Command Router : Notfying DB Updatefalse Oct 08 07:53:44 volumio volumio[904]: info: CoreCommandRouter::Close All Modals sent Oct 08 07:53:44 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:53:44 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 08 07:53:44 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:53:44 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 08 07:53:44 volumio volumio[904]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 08 07:53:44 volumio volumio[904]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 08 07:53:44 volumio volumio[904]: verbose: ControllerMpd::parseTrackInfo Oct 08 07:53:44 volumio volumio[904]: verbose: ControllerMpd::parseTrackInfo Oct 08 07:53:44 volumio volumio[904]: info: ControllerMpd::pushState Oct 08 07:53:44 volumio volumio[904]: info: CoreCommandRouter::servicePushState Oct 08 07:53:44 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:53:44 volumio volumio[904]: verbose: STATE SERVICE {"status":"play","position":0,"seek":35770,"duration":252,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"03 - 幾分傷心幾分癡.dff","artist":null,"album":null,"uri":"USB/5426-A066/3 王杰 极品精选/03 - 幾分傷心幾分癡.dff","trackType":"dff"} Oct 08 07:53:44 volumio volumio[904]: verbose: CURRENT POSITION 2 Oct 08 07:53:44 volumio volumio[904]: info: CoreStateMachine::syncState stateService play Oct 08 07:53:44 volumio volumio[904]: info: CoreStateMachine::syncState currentStatus play Oct 08 07:53:44 volumio volumio[904]: info: Received an update from plugin. extracting info from payload Oct 08 07:53:44 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:53:44 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:53:44 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:53:44 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:53:44 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:53:44 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:53:44 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:53:44 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:53:44 volumio volumio[904]: info: ControllerMpd::pushState Oct 08 07:53:44 volumio volumio[904]: info: CoreCommandRouter::servicePushState Oct 08 07:53:44 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:53:44 volumio volumio[904]: verbose: STATE SERVICE {"status":"play","position":0,"seek":35770,"duration":252,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"03 - 幾分傷心幾分癡.dff","artist":null,"album":null,"uri":"USB/5426-A066/3 王杰 极品精选/03 - 幾分傷心幾分癡.dff","trackType":"dff"} Oct 08 07:53:44 volumio volumio[904]: verbose: CURRENT POSITION 2 Oct 08 07:53:44 volumio volumio[904]: info: CoreStateMachine::syncState stateService play Oct 08 07:53:44 volumio volumio[904]: info: CoreStateMachine::syncState currentStatus play Oct 08 07:53:44 volumio volumio[904]: info: Received an update from plugin. extracting info from payload Oct 08 07:53:44 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:53:44 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:53:44 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:53:44 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:53:44 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:53:44 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:53:44 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:53:44 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:53:44 volumio volumio[904]: info: ------------------------------ 125ms Oct 08 07:53:44 volumio volumio[904]: info: ------------------------------ 125ms Oct 08 07:53:47 volumio volumio[904]: info: Oct 08 07:53:47 volumio volumio[904]: ---------------------------- MPD announces state update: player Oct 08 07:53:47 volumio volumio[904]: info: ControllerMpd::getState Oct 08 07:53:47 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:53:47 volumio volumio[904]: info: sendMpdCommand status took 1 milliseconds Oct 08 07:53:47 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:53:47 volumio volumio[904]: info: ControllerMpd::pushState Oct 08 07:53:47 volumio volumio[904]: info: CoreCommandRouter::servicePushState Oct 08 07:53:47 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:53:47 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:53:47 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:53:47 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:53:47 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:53:47 volumio volumio[904]: 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} Oct 08 07:53:47 volumio volumio[904]: verbose: CURRENT POSITION 2 Oct 08 07:53:47 volumio volumio[904]: info: CoreStateMachine::syncState stateService stop Oct 08 07:53:47 volumio volumio[904]: info: CoreStateMachine::syncState currentStatus play Oct 08 07:53:47 volumio volumio[904]: info: CoreStateMachine::play index undefined Oct 08 07:53:47 volumio volumio[904]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 08 07:53:47 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:53:47 volumio volumio[904]: info: CorePlayQueue::getTrack 3 Oct 08 07:53:47 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:53:47 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:53:47 volumio volumio[904]: info: CorePlayQueue::getTrack 3 Oct 08 07:53:47 volumio volumio[904]: info: CoreStateMachine::startPlaybackTimer Oct 08 07:53:47 volumio volumio[904]: info: CorePlayQueue::getTrack 3 Oct 08 07:53:47 volumio volumio[904]: verbose: ControllerMpd::clearAddPlayTracks USB/5426-A066/3 王杰 极品精选/04 - 故事的角色.dff Oct 08 07:53:47 volumio volumio[904]: info: Setting Volume to 100 automatically for DSD Oct 08 07:53:47 volumio volumio[904]: info: VolumeController::SetAlsaVolume100 Oct 08 07:53:47 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand stop Oct 08 07:53:47 volumio volumio[904]: info: ------------------------------ 30ms Oct 08 07:53:47 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:53:47 volumio volumio[904]: info: CorePlayQueue::getTrack 3 Oct 08 07:53:47 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:53:47 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:53:47 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:53:47 volumio volumio[904]: info: CorePlayQueue::getTrack 3 Oct 08 07:53:47 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:53:47 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:53:47 volumio volumio[904]: info: sendMpdCommand stop took 27 milliseconds Oct 08 07:53:47 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand clear Oct 08 07:53:47 volumio volumio[904]: info: Oct 08 07:53:47 volumio volumio[904]: ---------------------------- MPD announces system playlist update Oct 08 07:53:47 volumio volumio[904]: info: Ignoring MPD Status Update Oct 08 07:53:47 volumio volumio[904]: info: sendMpdCommand clear took 32 milliseconds Oct 08 07:53:47 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand add "USB/5426-A066/3 王杰 极品精选/04 - 故事的角色.dff" Oct 08 07:53:47 volumio volumio[904]: error: updateQueue error: null Oct 08 07:53:47 volumio volumio[904]: info: ------------------------------ 4ms Oct 08 07:53:47 volumio volumio[904]: info: touch_display: Setting screensaver timeout to 0 seconds. Oct 08 07:53:49 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:53:49 volumio volumio[904]: info: Oct 08 07:53:49 volumio volumio[904]: ---------------------------- MPD announces state update: update Oct 08 07:53:49 volumio volumio[904]: info: ControllerMpd::getState Oct 08 07:53:49 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:53:49 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:53:49 volumio volumio[904]: info: Oct 08 07:53:49 volumio volumio[904]: ---------------------------- MPD announces state update: update Oct 08 07:53:49 volumio volumio[904]: info: ControllerMpd::getState Oct 08 07:53:49 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:53:49 volumio volumio[904]: info: sendMpdCommand status took 2 milliseconds Oct 08 07:53:49 volumio volumio[904]: info: sendMpdCommand status took 2 milliseconds Oct 08 07:53:49 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:53:49 volumio volumio[904]: info: Command Router : Notfying DB Updatefalse Oct 08 07:53:49 volumio volumio[904]: info: CoreCommandRouter::Close All Modals sent Oct 08 07:53:49 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:53:49 volumio volumio[904]: info: ControllerMpd::pushState Oct 08 07:53:49 volumio volumio[904]: info: CoreCommandRouter::servicePushState Oct 08 07:53:49 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:53:49 volumio volumio[904]: info: CorePlayQueue::getTrack 3 Oct 08 07:53:49 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:53:49 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:53:49 volumio volumio[904]: info: CorePlayQueue::getTrack 3 Oct 08 07:53:49 volumio volumio[904]: 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} Oct 08 07:53:49 volumio volumio[904]: verbose: CURRENT POSITION 3 Oct 08 07:53:49 volumio volumio[904]: info: CoreStateMachine::syncState stateService stop Oct 08 07:53:49 volumio volumio[904]: info: CoreStateMachine::syncState currentStatus stop Oct 08 07:53:49 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:53:49 volumio volumio[904]: info: CorePlayQueue::getTrack 3 Oct 08 07:53:49 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:53:49 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:53:49 volumio volumio[904]: info: No code Oct 08 07:53:49 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:53:49 volumio volumio[904]: info: CorePlayQueue::getTrack 3 Oct 08 07:53:49 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:53:49 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:53:49 volumio kernel: usb 1-1.3: new high-speed USB device number 9 using xhci_hcd Oct 08 07:53:49 volumio volumio[904]: info: ------------------------------ 48ms Oct 08 07:53:49 volumio volumio[904]: info: sendMpdCommand status took 48 milliseconds Oct 08 07:53:49 volumio volumio[904]: info: sendMpdCommand status took 48 milliseconds Oct 08 07:53:49 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:53:49 volumio volumio[904]: info: Command Router : Notfying DB Updatefalse Oct 08 07:53:49 volumio volumio[904]: info: CoreCommandRouter::Close All Modals sent Oct 08 07:53:49 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:53:49 volumio volumio[904]: info: ControllerMpd::pushState Oct 08 07:53:49 volumio volumio[904]: info: CoreCommandRouter::servicePushState Oct 08 07:53:49 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:53:49 volumio volumio[904]: info: CorePlayQueue::getTrack 3 Oct 08 07:53:49 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:53:49 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:53:49 volumio volumio[904]: info: CorePlayQueue::getTrack 3 Oct 08 07:53:49 volumio volumio[904]: 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} Oct 08 07:53:49 volumio volumio[904]: verbose: CURRENT POSITION 3 Oct 08 07:53:49 volumio volumio[904]: info: CoreStateMachine::syncState stateService stop Oct 08 07:53:49 volumio volumio[904]: info: CoreStateMachine::syncState currentStatus stop Oct 08 07:53:49 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:53:49 volumio volumio[904]: info: CorePlayQueue::getTrack 3 Oct 08 07:53:49 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:53:49 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:53:49 volumio volumio[904]: info: No code Oct 08 07:53:49 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:53:49 volumio volumio[904]: info: CorePlayQueue::getTrack 3 Oct 08 07:53:49 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:53:49 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:53:49 volumio volumio[904]: info: ------------------------------ 86ms Oct 08 07:53:49 volumio kernel: usb 1-1.3: New USB device found, idVendor=0781, idProduct=5567, bcdDevice= 1.00 Oct 08 07:53:49 volumio kernel: usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Oct 08 07:53:49 volumio kernel: usb 1-1.3: Product: Cruzer Blade Oct 08 07:53:49 volumio kernel: usb 1-1.3: Manufacturer: SanDisk Oct 08 07:53:49 volumio kernel: usb 1-1.3: SerialNumber: 4C530000220511217060 Oct 08 07:53:49 volumio kernel: usb-storage 1-1.3:1.0: USB Mass Storage device detected Oct 08 07:53:49 volumio kernel: scsi host0: usb-storage 1-1.3:1.0 Oct 08 07:53:50 volumio kernel: scsi 0:0:0:0: Direct-Access SanDisk Cruzer Blade 1.00 PQ: 0 ANSI: 6 Oct 08 07:53:50 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Oct 08 07:53:50 volumio kernel: sd 0:0:0:0: [sda] 120225792 512-byte logical blocks: (61.6 GB/57.3 GiB) Oct 08 07:53:50 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Oct 08 07:53:50 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00 Oct 08 07:53:50 volumio kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA Oct 08 07:53:50 volumio kernel: sda: sda1 Oct 08 07:53:50 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI removable disk Oct 08 07:53:51 volumio udisks-glue[609]: Device file /dev/sda inserted Oct 08 07:53:51 volumio udisks-glue[609]: Device file /dev/sda1 inserted Oct 08 07:53:51 volumio udisks-glue[609]: Trying to automount /dev/sda1... Oct 08 07:53:51 volumio kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Oct 08 07:53:51 volumio udisks-glue[609]: Successfully automounted /dev/sda1 at /media/5426-A066 Oct 08 07:53:51 volumio udisks-glue[609]: Device file /dev/sda1 mounted at /media/5426-A066 Oct 08 07:53:51 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:53:51 volumio volumio[904]: info: Oct 08 07:53:51 volumio volumio[904]: ---------------------------- MPD announces state update: update Oct 08 07:53:51 volumio volumio[904]: info: ControllerMpd::getState Oct 08 07:53:51 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:53:51 volumio volumio[904]: info: sendMpdCommand status took 1 milliseconds Oct 08 07:53:51 volumio volumio[904]: info: sendMpdCommand status took 1 milliseconds Oct 08 07:53:51 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:53:51 volumio udisks-glue[609]: Updating DB (#10) ... Oct 08 07:53:51 volumio udisks-glue[609]: volume: 40% repeat: off random: off single: off consume: off Oct 08 07:53:51 volumio volumio[904]: info: Command Router : Notfying DB Updatetrue Oct 08 07:53:51 volumio volumio[904]: info: CoreCommandRouter::Close All Modals sent Oct 08 07:53:51 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:53:51 volumio volumio[904]: info: ControllerMpd::pushState Oct 08 07:53:51 volumio volumio[904]: info: CoreCommandRouter::servicePushState Oct 08 07:53:51 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:53:51 volumio volumio[904]: info: CorePlayQueue::getTrack 3 Oct 08 07:53:51 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:53:51 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:53:51 volumio volumio[904]: info: CorePlayQueue::getTrack 3 Oct 08 07:53:51 volumio volumio[904]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 08 07:53:51 volumio volumio[904]: verbose: CURRENT POSITION 3 Oct 08 07:53:51 volumio volumio[904]: info: CoreStateMachine::syncState stateService stop Oct 08 07:53:51 volumio volumio[904]: info: CoreStateMachine::syncState currentStatus stop Oct 08 07:53:51 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:53:51 volumio volumio[904]: info: CorePlayQueue::getTrack 3 Oct 08 07:53:51 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:53:51 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:53:51 volumio volumio[904]: info: No code Oct 08 07:53:51 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:53:51 volumio volumio[904]: info: CorePlayQueue::getTrack 3 Oct 08 07:53:51 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:53:51 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:53:51 volumio volumio[904]: info: ------------------------------ 65ms Oct 08 07:53:52 volumio kernel: usb 1-1.3: reset high-speed USB device number 9 using xhci_hcd Oct 08 07:53:52 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 08 07:53:52 volumio volumio[904]: info: CURURI: music-library Oct 08 07:53:52 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=0x00 Oct 08 07:53:52 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 e8 b0 00 00 f0 00 Oct 08 07:53:52 volumio kernel: print_req_error: I/O error, dev sda, sector 59568 Oct 08 07:53:53 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 08 07:53:53 volumio volumio[904]: info: CURURI: music-library/USB Oct 08 07:53:54 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 08 07:53:54 volumio volumio[904]: info: CURURI: music-library/USB/5426-A066 Oct 08 07:53:55 volumio kernel: usb 1-1.3: reset high-speed USB device number 9 using xhci_hcd Oct 08 07:53:55 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=0x00 Oct 08 07:53:55 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 e9 a0 00 00 20 00 Oct 08 07:53:55 volumio kernel: print_req_error: I/O error, dev sda, sector 59808 Oct 08 07:53:57 volumio kernel: usb 1-1.3: reset high-speed USB device number 9 using xhci_hcd Oct 08 07:53:57 volumio kernel: usb 1-1.3: Device not responding to setup address. Oct 08 07:53:57 volumio kernel: usb 1-1.3: Device not responding to setup address. Oct 08 07:53:57 volumio kernel: usb 1-1.3: device not accepting address 9, error -71 Oct 08 07:53:58 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 08 07:53:58 volumio volumio[904]: info: CURURI: music-library/USB/5426-A066/4 阿杜 极品精选 Oct 08 07:53:58 volumio kernel: usb 1-1.3: reset high-speed USB device number 9 using xhci_hcd Oct 08 07:53:58 volumio kernel: usb 1-1.3: device descriptor read/64, error -71 Oct 08 07:53:59 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 08 07:53:59 volumio volumio[904]: info: CURURI: music-library/USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac) Oct 08 07:53:59 volumio kernel: usb 1-1.3: reset high-speed USB device number 9 using xhci_hcd Oct 08 07:53:59 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=0x00 Oct 08 07:53:59 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 4a f2 20 00 00 f0 00 Oct 08 07:53:59 volumio kernel: print_req_error: I/O error, dev sda, sector 4911648 Oct 08 07:54:00 volumio volumio[904]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 08 07:54:00 volumio volumio[904]: info: CoreStateMachine::ClearQueue Oct 08 07:54:00 volumio volumio[904]: info: CoreStateMachine::stop Oct 08 07:54:00 volumio volumio[904]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 08 07:54:00 volumio volumio[904]: verbose: UNSET VOLATILE Oct 08 07:54:00 volumio volumio[904]: info: CorePlayQueue::clearPlayQueue Oct 08 07:54:00 volumio volumio[904]: info: CorePlayQueue::saveQueue Oct 08 07:54:00 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:00 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:00 volumio volumio[904]: info: CoreCommandRouter::volumioPushQueue Oct 08 07:54:00 volumio volumio[904]: info: CoreStateMachine::addQueueItems Oct 08 07:54:00 volumio volumio[904]: info: CorePlayQueue::addQueueItems Oct 08 07:54:00 volumio volumio[904]: info: Adding Item to queue: music-library/USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac)/01.他一定很爱你.flac Oct 08 07:54:00 volumio volumio[904]: info: Exploding uri music-library/USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac)/01.他一定很爱你.flac in service mpd Oct 08 07:54:00 volumio volumio[904]: info: Adding Item to queue: music-library/USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac)/02.天黑.flac Oct 08 07:54:00 volumio volumio[904]: info: Exploding uri music-library/USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac)/02.天黑.flac in service mpd Oct 08 07:54:00 volumio volumio[904]: info: Adding Item to queue: music-library/USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac)/03.天天看到你.flac Oct 08 07:54:00 volumio volumio[904]: info: Exploding uri music-library/USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac)/03.天天看到你.flac in service mpd Oct 08 07:54:00 volumio volumio[904]: info: Adding Item to queue: music-library/USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac)/04.一个人住.flac Oct 08 07:54:00 volumio volumio[904]: info: Exploding uri music-library/USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac)/04.一个人住.flac in service mpd Oct 08 07:54:00 volumio volumio[904]: info: Adding Item to queue: music-library/USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac)/05.Andy.flac Oct 08 07:54:00 volumio volumio[904]: info: Exploding uri music-library/USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac)/05.Andy.flac in service mpd Oct 08 07:54:00 volumio volumio[904]: info: Adding Item to queue: music-library/USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac)/06.撕夜.flac Oct 08 07:54:00 volumio volumio[904]: info: Exploding uri music-library/USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac)/06.撕夜.flac in service mpd Oct 08 07:54:00 volumio volumio[904]: info: Adding Item to queue: music-library/USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac)/07.无法阻挡.flac Oct 08 07:54:00 volumio volumio[904]: info: Exploding uri music-library/USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac)/07.无法阻挡.flac in service mpd Oct 08 07:54:00 volumio volumio[904]: info: Adding Item to queue: music-library/USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac)/08.你很好.flac Oct 08 07:54:00 volumio volumio[904]: info: Exploding uri music-library/USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac)/08.你很好.flac in service mpd Oct 08 07:54:00 volumio volumio[904]: info: Adding Item to queue: music-library/USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac)/09.离别.flac Oct 08 07:54:00 volumio volumio[904]: info: Exploding uri music-library/USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac)/09.离别.flac in service mpd Oct 08 07:54:00 volumio volumio[904]: info: Adding Item to queue: music-library/USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac)/10.Right Here Waiting.flac Oct 08 07:54:00 volumio volumio[904]: info: Exploding uri music-library/USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac)/10.Right Here Waiting.flac in service mpd Oct 08 07:54:00 volumio volumio[904]: info: ALBUMART /albumart?cacheid=406&web=%E9%98%BF%E6%9D%9C/%E5%A4%A9%E9%BB%91/extralarge&path=%2Fmnt%2FUSB%2F5426-A066%2F4%20%E9%98%BF%E6%9D%9C%20%20%E6%9E%81%E5%93%81%E7%B2%BE%E9%80%89%2F%E5%A4%A9%E9%BB%91(44.1kHz16bitFlac)%2F01.%E4%BB%96%E4%B8%80%E5%AE%9A%E5%BE%88%E7%88%B1%E4%BD%A0.flac&metadata=false Oct 08 07:54:00 volumio volumio[904]: info: URI /mnt/USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac)/01.他一定很爱你.flac Oct 08 07:54:00 volumio volumio[904]: info: ALBUMART /albumart?cacheid=406&web=%E9%98%BF%E6%9D%9C/%E5%A4%A9%E9%BB%91/extralarge&path=%2Fmnt%2FUSB%2F5426-A066%2F4%20%E9%98%BF%E6%9D%9C%20%20%E6%9E%81%E5%93%81%E7%B2%BE%E9%80%89%2F%E5%A4%A9%E9%BB%91(44.1kHz16bitFlac)%2F02.%E5%A4%A9%E9%BB%91.flac&metadata=false Oct 08 07:54:00 volumio volumio[904]: info: URI /mnt/USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac)/02.天黑.flac Oct 08 07:54:00 volumio volumio[904]: info: ALBUMART /albumart?cacheid=406&web=%E9%98%BF%E6%9D%9C/%E5%A4%A9%E9%BB%91/extralarge&path=%2Fmnt%2FUSB%2F5426-A066%2F4%20%E9%98%BF%E6%9D%9C%20%20%E6%9E%81%E5%93%81%E7%B2%BE%E9%80%89%2F%E5%A4%A9%E9%BB%91(44.1kHz16bitFlac)%2F03.%E5%A4%A9%E5%A4%A9%E7%9C%8B%E5%88%B0%E4%BD%A0.flac&metadata=false Oct 08 07:54:00 volumio volumio[904]: info: URI /mnt/USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac)/03.天天看到你.flac Oct 08 07:54:00 volumio volumio[904]: info: ALBUMART /albumart?cacheid=406&web=%E9%98%BF%E6%9D%9C/%E5%A4%A9%E9%BB%91/extralarge&path=%2Fmnt%2FUSB%2F5426-A066%2F4%20%E9%98%BF%E6%9D%9C%20%20%E6%9E%81%E5%93%81%E7%B2%BE%E9%80%89%2F%E5%A4%A9%E9%BB%91(44.1kHz16bitFlac)%2F04.%E4%B8%80%E4%B8%AA%E4%BA%BA%E4%BD%8F.flac&metadata=false Oct 08 07:54:00 volumio volumio[904]: info: URI /mnt/USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac)/04.一个人住.flac Oct 08 07:54:00 volumio volumio[904]: info: ALBUMART /albumart?cacheid=406&web=%E9%98%BF%E6%9D%9C/%E5%A4%A9%E9%BB%91/extralarge&path=%2Fmnt%2FUSB%2F5426-A066%2F4%20%E9%98%BF%E6%9D%9C%20%20%E6%9E%81%E5%93%81%E7%B2%BE%E9%80%89%2F%E5%A4%A9%E9%BB%91(44.1kHz16bitFlac)%2F05.Andy.flac&metadata=false Oct 08 07:54:00 volumio volumio[904]: info: URI /mnt/USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac)/05.Andy.flac Oct 08 07:54:00 volumio volumio[904]: info: ALBUMART /albumart?cacheid=406&web=%E9%98%BF%E6%9D%9C/%E5%A4%A9%E9%BB%91/extralarge&path=%2Fmnt%2FUSB%2F5426-A066%2F4%20%E9%98%BF%E6%9D%9C%20%20%E6%9E%81%E5%93%81%E7%B2%BE%E9%80%89%2F%E5%A4%A9%E9%BB%91(44.1kHz16bitFlac)%2F06.%E6%92%95%E5%A4%9C.flac&metadata=false Oct 08 07:54:00 volumio volumio[904]: info: URI /mnt/USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac)/06.撕夜.flac Oct 08 07:54:00 volumio volumio[904]: info: ALBUMART /albumart?cacheid=406&web=%E9%98%BF%E6%9D%9C/%E5%A4%A9%E9%BB%91/extralarge&path=%2Fmnt%2FUSB%2F5426-A066%2F4%20%E9%98%BF%E6%9D%9C%20%20%E6%9E%81%E5%93%81%E7%B2%BE%E9%80%89%2F%E5%A4%A9%E9%BB%91(44.1kHz16bitFlac)%2F07.%E6%97%A0%E6%B3%95%E9%98%BB%E6%8C%A1.flac&metadata=false Oct 08 07:54:00 volumio volumio[904]: info: URI /mnt/USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac)/07.无法阻挡.flac Oct 08 07:54:00 volumio volumio[904]: info: ALBUMART /albumart?cacheid=406&web=%E9%98%BF%E6%9D%9C/%E5%A4%A9%E9%BB%91/extralarge&path=%2Fmnt%2FUSB%2F5426-A066%2F4%20%E9%98%BF%E6%9D%9C%20%20%E6%9E%81%E5%93%81%E7%B2%BE%E9%80%89%2F%E5%A4%A9%E9%BB%91(44.1kHz16bitFlac)%2F08.%E4%BD%A0%E5%BE%88%E5%A5%BD.flac&metadata=false Oct 08 07:54:00 volumio volumio[904]: info: URI /mnt/USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac)/08.你很好.flac Oct 08 07:54:00 volumio volumio[904]: info: ALBUMART /albumart?cacheid=406&web=%E9%98%BF%E6%9D%9C/%E5%A4%A9%E9%BB%91/extralarge&path=%2Fmnt%2FUSB%2F5426-A066%2F4%20%E9%98%BF%E6%9D%9C%20%20%E6%9E%81%E5%93%81%E7%B2%BE%E9%80%89%2F%E5%A4%A9%E9%BB%91(44.1kHz16bitFlac)%2F09.%E7%A6%BB%E5%88%AB.flac&metadata=false Oct 08 07:54:00 volumio volumio[904]: info: URI /mnt/USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac)/09.离别.flac Oct 08 07:54:00 volumio volumio[904]: info: ALBUMART /albumart?cacheid=406&web=%E9%98%BF%E6%9D%9C/%E5%A4%A9%E9%BB%91/extralarge&path=%2Fmnt%2FUSB%2F5426-A066%2F4%20%E9%98%BF%E6%9D%9C%20%20%E6%9E%81%E5%93%81%E7%B2%BE%E9%80%89%2F%E5%A4%A9%E9%BB%91(44.1kHz16bitFlac)%2F10.Right%20Here%20Waiting.flac&metadata=false Oct 08 07:54:00 volumio volumio[904]: info: URI /mnt/USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac)/10.Right Here Waiting.flac Oct 08 07:54:00 volumio volumio[904]: info: CorePlayQueue::saveQueue Oct 08 07:54:00 volumio volumio[904]: info: CoreCommandRouter::volumioPushQueue Oct 08 07:54:00 volumio volumio[904]: info: CoreStateMachine::updateTrackBlock Oct 08 07:54:00 volumio volumio[904]: info: CorePlayQueue::getTrackBlock Oct 08 07:54:00 volumio volumio[904]: info: CoreCommandRouter::volumioPlay Oct 08 07:54:00 volumio volumio[904]: verbose: UNSET VOLATILE Oct 08 07:54:00 volumio volumio[904]: info: CoreStateMachine::play index 1 Oct 08 07:54:00 volumio volumio[904]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 08 07:54:00 volumio volumio[904]: info: CoreStateMachine::stop Oct 08 07:54:00 volumio volumio[904]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 08 07:54:00 volumio volumio[904]: verbose: UNSET VOLATILE Oct 08 07:54:00 volumio volumio[904]: info: CoreStateMachine::play index undefined Oct 08 07:54:00 volumio volumio[904]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 08 07:54:00 volumio volumio[904]: info: CorePlayQueue::getTrack 1 Oct 08 07:54:00 volumio volumio[904]: info: CoreStateMachine::startPlaybackTimer Oct 08 07:54:00 volumio volumio[904]: info: CorePlayQueue::getTrack 1 Oct 08 07:54:00 volumio volumio[904]: verbose: ControllerMpd::clearAddPlayTracks USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac)/02.天黑.flac Oct 08 07:54:00 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand stop Oct 08 07:54:00 volumio volumio[904]: info: sendMpdCommand stop took 3 milliseconds Oct 08 07:54:00 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand clear Oct 08 07:54:00 volumio volumio[904]: info: Oct 08 07:54:00 volumio volumio[904]: ---------------------------- MPD announces system playlist update Oct 08 07:54:00 volumio volumio[904]: info: Ignoring MPD Status Update Oct 08 07:54:00 volumio volumio[904]: info: sendMpdCommand clear took 3 milliseconds Oct 08 07:54:00 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand add "USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac)/02.天黑.flac" Oct 08 07:54:00 volumio volumio[904]: error: updateQueue error: null Oct 08 07:54:00 volumio volumio[904]: info: Oct 08 07:54:00 volumio volumio[904]: ---------------------------- MPD announces system playlist update Oct 08 07:54:00 volumio volumio[904]: info: Ignoring MPD Status Update Oct 08 07:54:00 volumio volumio[904]: info: ------------------------------ 2ms Oct 08 07:54:00 volumio volumio[904]: info: sendMpdCommand add "USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac)/02.天黑.flac" took 1 milliseconds Oct 08 07:54:00 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand play Oct 08 07:54:00 volumio volumio[904]: info: ------------------------------ 3ms Oct 08 07:54:00 volumio volumio[904]: info: sendMpdCommand play took 2 milliseconds Oct 08 07:54:00 volumio kernel: usb 1-1.3: reset high-speed USB device number 9 using xhci_hcd Oct 08 07:54:00 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=0x00 Oct 08 07:54:00 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 4a f3 10 00 00 f0 00 Oct 08 07:54:00 volumio kernel: print_req_error: I/O error, dev sda, sector 4911888 Oct 08 07:54:00 volumio volumio[904]: info: Oct 08 07:54:00 volumio volumio[904]: ---------------------------- MPD announces state update: player Oct 08 07:54:00 volumio volumio[904]: info: ControllerMpd::getState Oct 08 07:54:00 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:54:00 volumio volumio[904]: info: Oct 08 07:54:00 volumio volumio[904]: ---------------------------- MPD announces state update: player Oct 08 07:54:00 volumio volumio[904]: info: sendMpdCommand status took 18 milliseconds Oct 08 07:54:00 volumio volumio[904]: info: ControllerMpd::getState Oct 08 07:54:00 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:54:00 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:54:00 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 08 07:54:00 volumio kernel: bcm2835-i2s fe203000.i2s: I2S SYNC error! Oct 08 07:54:00 volumio volumio[904]: info: sendMpdCommand status took 1 milliseconds Oct 08 07:54:00 volumio volumio[904]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 08 07:54:00 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:54:00 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 08 07:54:00 volumio volumio[904]: verbose: ControllerMpd::parseTrackInfo Oct 08 07:54:00 volumio volumio[904]: info: ControllerMpd::pushState Oct 08 07:54:00 volumio volumio[904]: info: CoreCommandRouter::servicePushState Oct 08 07:54:00 volumio volumio[904]: info: CorePlayQueue::getTrack 1 Oct 08 07:54:00 volumio volumio[904]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":260,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1036 Kbps","isStreaming":false,"title":"天黑","artist":"阿杜","album":"天黑","uri":"USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac)/02.天黑.flac","trackType":"flac"} Oct 08 07:54:00 volumio volumio[904]: verbose: CURRENT POSITION 1 Oct 08 07:54:00 volumio volumio[904]: info: CoreStateMachine::syncState stateService play Oct 08 07:54:00 volumio volumio[904]: info: CoreStateMachine::syncState currentStatus stop Oct 08 07:54:00 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:00 volumio volumio[904]: info: CorePlayQueue::getTrack 1 Oct 08 07:54:00 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:00 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:00 volumio volumio[904]: info: ------------------------------ 34ms Oct 08 07:54:00 volumio volumio[904]: info: sendMpdCommand playlistinfo took 12 milliseconds Oct 08 07:54:00 volumio volumio[904]: verbose: ControllerMpd::parseTrackInfo Oct 08 07:54:00 volumio volumio[904]: info: ControllerMpd::pushState Oct 08 07:54:00 volumio volumio[904]: info: CoreCommandRouter::servicePushState Oct 08 07:54:00 volumio volumio[904]: info: CorePlayQueue::getTrack 1 Oct 08 07:54:00 volumio volumio[904]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":260,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"945 Kbps","isStreaming":false,"title":"天黑","artist":"阿杜","album":"天黑","uri":"USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac)/02.天黑.flac","trackType":"flac"} Oct 08 07:54:00 volumio volumio[904]: verbose: CURRENT POSITION 1 Oct 08 07:54:00 volumio volumio[904]: info: CoreStateMachine::syncState stateService play Oct 08 07:54:00 volumio volumio[904]: info: CoreStateMachine::syncState currentStatus play Oct 08 07:54:00 volumio volumio[904]: info: Received an update from plugin. extracting info from payload Oct 08 07:54:00 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:00 volumio volumio[904]: info: CorePlayQueue::getTrack 1 Oct 08 07:54:00 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:00 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:00 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:00 volumio volumio[904]: info: CorePlayQueue::getTrack 1 Oct 08 07:54:00 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:00 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:00 volumio volumio[904]: info: ------------------------------ 43ms Oct 08 07:54:01 volumio kernel: usb 1-1.3: reset high-speed USB device number 9 using xhci_hcd Oct 08 07:54:02 volumio kernel: usb 1-1.3: device descriptor read/64, error -71 Oct 08 07:54:03 volumio kernel: usb 1-1.3: device descriptor read/64, error -71 Oct 08 07:54:03 volumio kernel: usb 1-1.3: reset high-speed USB device number 9 using xhci_hcd Oct 08 07:54:06 volumio kernel: usb 1-1.3: device descriptor read/64, error -71 Oct 08 07:54:08 volumio kernel: usb 1-1.3: device descriptor read/64, error -71 Oct 08 07:54:08 volumio kernel: usb 1-1.3: reset high-speed USB device number 9 using xhci_hcd Oct 08 07:54:08 volumio volumio[904]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 08 07:54:08 volumio volumio[904]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 08 07:54:08 volumio volumio[904]: info: Retrieving Cloud Streaming UI Oct 08 07:54:08 volumio volumio[904]: info: Getting Tidal Cloud Configuration Oct 08 07:54:08 volumio volumio[904]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 08 07:54:08 volumio volumio[904]: info: Getting Qobuz Cloud Configuration Oct 08 07:54:08 volumio volumio[904]: info: Asking plugin for UI Config Oct 08 07:54:08 volumio volumio[904]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 08 07:54:08 volumio volumio[904]: info: Getting Spotify Cloud Configuration Oct 08 07:54:08 volumio volumio[904]: info: Asking plugin for UI Config Oct 08 07:54:08 volumio volumio[904]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 08 07:54:08 volumio volumio[904]: info: Saving Spotify Acccount Oct 08 07:54:08 volumio volumio[904]: info: Got Tidal Cloud Configuration Oct 08 07:54:08 volumio volumio[904]: info: Got it Oct 08 07:54:08 volumio volumio[904]: info: Got it Oct 08 07:54:08 volumio volumio[904]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 08 07:54:08 volumio volumio[904]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 08 07:54:08 volumio volumio[904]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 08 07:54:09 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 08 07:54:09 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 08 07:54:09 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 08 07:54:09 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 08 07:54:09 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 08 07:54:09 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 08 07:54:09 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 08 07:54:09 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 08 07:54:09 volumio volumio[904]: info: CoreCommandRouter::volumioGetBrowseSources Oct 08 07:54:09 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 08 07:54:10 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 08 07:54:11 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Oct 08 07:54:11 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand update Oct 08 07:54:11 volumio volumio[904]: info: sendMpdCommand update took 3 milliseconds Oct 08 07:54:11 volumio kernel: usb 1-1.3: device descriptor read/8, error -71 Oct 08 07:54:11 volumio kernel: usb 1-1.3: device descriptor read/8, error -71 Oct 08 07:54:11 volumio kernel: usb 1-1.3: reset high-speed USB device number 9 using xhci_hcd Oct 08 07:54:11 volumio kernel: usb 1-1.3: device descriptor read/8, error -71 Oct 08 07:54:12 volumio kernel: usb 1-1.3: device descriptor read/8, error -71 Oct 08 07:54:12 volumio kernel: usb 1-1.3: USB disconnect, device number 9 Oct 08 07:54:12 volumio kernel: print_req_error: I/O error, dev sda, sector 1954 Oct 08 07:54:12 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 1890) Oct 08 07:54:12 volumio kernel: print_req_error: I/O error, dev sda, sector 26627040 Oct 08 07:54:12 volumio kernel: print_req_error: I/O error, dev sda, sector 26627280 Oct 08 07:54:12 volumio kernel: print_req_error: I/O error, dev sda, sector 26627520 Oct 08 07:54:12 volumio kernel: print_req_error: I/O error, dev sda, sector 1954 Oct 08 07:54:12 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 1890) Oct 08 07:54:12 volumio kernel: print_req_error: I/O error, dev sda, sector 26627552 Oct 08 07:54:12 volumio kernel: print_req_error: I/O error, dev sda, sector 26627792 Oct 08 07:54:12 volumio kernel: print_req_error: I/O error, dev sda, sector 26628032 Oct 08 07:54:12 volumio kernel: print_req_error: I/O error, dev sda, sector 26627040 Oct 08 07:54:12 volumio kernel: print_req_error: I/O error, dev sda, sector 1954 Oct 08 07:54:12 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 1890) Oct 08 07:54:12 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 1520) Oct 08 07:54:12 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 1298) Oct 08 07:54:12 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 1298) Oct 08 07:54:12 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 1298) Oct 08 07:54:12 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 1298) Oct 08 07:54:12 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 1298) Oct 08 07:54:12 volumio kernel: FAT-fs (sda1): Directory bread(block 58784) failed Oct 08 07:54:12 volumio kernel: FAT-fs (sda1): Directory bread(block 58785) failed Oct 08 07:54:12 volumio kernel: FAT-fs (sda1): Directory bread(block 58786) failed Oct 08 07:54:12 volumio kernel: FAT-fs (sda1): Directory bread(block 58787) failed Oct 08 07:54:12 volumio kernel: FAT-fs (sda1): Directory bread(block 58788) failed Oct 08 07:54:12 volumio kernel: FAT-fs (sda1): Directory bread(block 58789) failed Oct 08 07:54:12 volumio kernel: FAT-fs (sda1): Directory bread(block 58790) failed Oct 08 07:54:12 volumio kernel: FAT-fs (sda1): Directory bread(block 58791) failed Oct 08 07:54:12 volumio kernel: FAT-fs (sda1): Directory bread(block 58792) failed Oct 08 07:54:12 volumio kernel: FAT-fs (sda1): Directory bread(block 58793) failed Oct 08 07:54:12 volumio volumio[904]: info: MPD Database updated - AlbumList cache refreshed Oct 08 07:54:12 volumio volumio[904]: info: Oct 08 07:54:12 volumio volumio[904]: ---------------------------- MPD announces state update: database Oct 08 07:54:12 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:54:12 volumio volumio[904]: info: Oct 08 07:54:12 volumio volumio[904]: ---------------------------- MPD announces state update: update Oct 08 07:54:12 volumio volumio[904]: info: ControllerMpd::getState Oct 08 07:54:12 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:54:12 volumio volumio[904]: info: ControllerMpd::getState Oct 08 07:54:12 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:54:12 volumio volumio[904]: info: sendMpdCommand status took 7 milliseconds Oct 08 07:54:12 volumio volumio[904]: info: sendMpdCommand status took 7 milliseconds Oct 08 07:54:12 volumio volumio[904]: info: sendMpdCommand status took 6 milliseconds Oct 08 07:54:12 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:54:12 volumio systemd-udevd[2939]: inotify_add_watch(6, /dev/sda, 10) failed: No such file or directory Oct 08 07:54:12 volumio udisks-glue[609]: Unable to get property "DeviceIsMounted": Method "Get" with signature "ss" on interface "org.freedesktop.DBus.Properties" doesn't exist Oct 08 07:54:12 volumio udisks-glue[609]: Unable to get property "DeviceIsMediaAvailable": Method "Get" with signature "ss" on interface "org.freedesktop.DBus.Properties" doesn't exist Oct 08 07:54:12 volumio udisks-glue[609]: Device file /dev/sda1 unmounted from /media/5426-A066 Oct 08 07:54:12 volumio udisks-glue[609]: Device file /dev/sda1 removed Oct 08 07:54:12 volumio udisks-glue[609]: Device file /dev/sda removed Oct 08 07:54:12 volumio udisks-glue[609]: 阿杜 - 天黑 Oct 08 07:54:12 volumio udisks-glue[609]: [playing] #1/1 0:12/4:20 (4%) Oct 08 07:54:12 volumio udisks-glue[609]: volume: 40% repeat: off random: off single: off consume: off Oct 08 07:54:12 volumio udisks-glue[609]: 阿杜 - 天黑 Oct 08 07:54:12 volumio udisks-glue[609]: [playing] #1/1 0:12/4:20 (4%) Oct 08 07:54:12 volumio udisks-glue[609]: volume: 40% repeat: off random: off single: off consume: off Oct 08 07:54:12 volumio volumio[904]: info: Command Router : Notfying DB Updatetrue Oct 08 07:54:12 volumio volumio[904]: info: CoreCommandRouter::Close All Modals sent Oct 08 07:54:12 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:54:12 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 08 07:54:12 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:54:12 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 08 07:54:12 volumio volumio[904]: info: MPD Database updated - AlbumList cache refreshed Oct 08 07:54:12 volumio volumio[904]: info: Oct 08 07:54:12 volumio volumio[904]: ---------------------------- MPD announces state update: database Oct 08 07:54:12 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:54:12 volumio volumio[904]: info: Oct 08 07:54:12 volumio volumio[904]: ---------------------------- MPD announces state update: update Oct 08 07:54:12 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:54:12 volumio volumio[904]: info: Oct 08 07:54:12 volumio volumio[904]: ---------------------------- MPD announces state update: update Oct 08 07:54:12 volumio volumio[904]: info: ControllerMpd::getState Oct 08 07:54:12 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:54:12 volumio volumio[904]: info: ControllerMpd::getState Oct 08 07:54:12 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:54:12 volumio volumio[904]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 08 07:54:12 volumio volumio[904]: info: ControllerMpd::getState Oct 08 07:54:12 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:54:12 volumio volumio[904]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 08 07:54:12 volumio volumio[904]: verbose: ControllerMpd::parseTrackInfo Oct 08 07:54:12 volumio volumio[904]: verbose: ControllerMpd::parseTrackInfo Oct 08 07:54:12 volumio volumio[904]: info: ControllerMpd::pushState Oct 08 07:54:12 volumio volumio[904]: info: CoreCommandRouter::servicePushState Oct 08 07:54:12 volumio volumio[904]: info: CorePlayQueue::getTrack 1 Oct 08 07:54:12 volumio volumio[904]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11772,"duration":260,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"632 Kbps","isStreaming":false,"title":"天黑","artist":"阿杜","album":"天黑","uri":"USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac)/02.天黑.flac","trackType":"flac"} Oct 08 07:54:12 volumio volumio[904]: verbose: CURRENT POSITION 1 Oct 08 07:54:12 volumio volumio[904]: info: CoreStateMachine::syncState stateService play Oct 08 07:54:12 volumio volumio[904]: info: CoreStateMachine::syncState currentStatus play Oct 08 07:54:12 volumio volumio[904]: info: Received an update from plugin. extracting info from payload Oct 08 07:54:12 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:12 volumio volumio[904]: info: CorePlayQueue::getTrack 1 Oct 08 07:54:12 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:12 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:12 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:12 volumio volumio[904]: info: CorePlayQueue::getTrack 1 Oct 08 07:54:12 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:12 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:12 volumio volumio[904]: info: ControllerMpd::pushState Oct 08 07:54:12 volumio volumio[904]: info: CoreCommandRouter::servicePushState Oct 08 07:54:12 volumio volumio[904]: info: CorePlayQueue::getTrack 1 Oct 08 07:54:12 volumio volumio[904]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11772,"duration":260,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"632 Kbps","isStreaming":false,"title":"天黑","artist":"阿杜","album":"天黑","uri":"USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac)/02.天黑.flac","trackType":"flac"} Oct 08 07:54:12 volumio volumio[904]: verbose: CURRENT POSITION 1 Oct 08 07:54:12 volumio volumio[904]: info: CoreStateMachine::syncState stateService play Oct 08 07:54:12 volumio volumio[904]: info: CoreStateMachine::syncState currentStatus play Oct 08 07:54:12 volumio volumio[904]: info: Received an update from plugin. extracting info from payload Oct 08 07:54:12 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:12 volumio volumio[904]: info: CorePlayQueue::getTrack 1 Oct 08 07:54:12 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:12 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:12 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:12 volumio volumio[904]: info: CorePlayQueue::getTrack 1 Oct 08 07:54:12 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:12 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:12 volumio volumio[904]: info: ------------------------------ 134ms Oct 08 07:54:12 volumio volumio[904]: info: ------------------------------ 135ms Oct 08 07:54:12 volumio volumio[904]: info: sendMpdCommand status took 57 milliseconds Oct 08 07:54:12 volumio volumio[904]: info: sendMpdCommand status took 57 milliseconds Oct 08 07:54:12 volumio volumio[904]: info: sendMpdCommand status took 56 milliseconds Oct 08 07:54:12 volumio volumio[904]: info: sendMpdCommand status took 55 milliseconds Oct 08 07:54:12 volumio volumio[904]: info: sendMpdCommand status took 54 milliseconds Oct 08 07:54:12 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:54:12 volumio volumio[904]: info: Command Router : Notfying DB Updatefalse Oct 08 07:54:12 volumio volumio[904]: info: CoreCommandRouter::Close All Modals sent Oct 08 07:54:12 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:54:12 volumio volumio[904]: info: Command Router : Notfying DB Updatefalse Oct 08 07:54:12 volumio volumio[904]: info: CoreCommandRouter::Close All Modals sent Oct 08 07:54:12 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:54:12 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 08 07:54:12 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:54:12 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 08 07:54:12 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:54:12 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 08 07:54:12 volumio volumio[904]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 08 07:54:12 volumio volumio[904]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 08 07:54:12 volumio volumio[904]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 08 07:54:12 volumio volumio[904]: verbose: ControllerMpd::parseTrackInfo Oct 08 07:54:12 volumio volumio[904]: verbose: ControllerMpd::parseTrackInfo Oct 08 07:54:12 volumio volumio[904]: verbose: ControllerMpd::parseTrackInfo Oct 08 07:54:12 volumio volumio[904]: info: ControllerMpd::pushState Oct 08 07:54:12 volumio volumio[904]: info: CoreCommandRouter::servicePushState Oct 08 07:54:12 volumio volumio[904]: info: CorePlayQueue::getTrack 1 Oct 08 07:54:12 volumio volumio[904]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11865,"duration":260,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"615 Kbps","isStreaming":false,"title":"天黑","artist":"阿杜","album":"天黑","uri":"USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac)/02.天黑.flac","trackType":"flac"} Oct 08 07:54:12 volumio volumio[904]: verbose: CURRENT POSITION 1 Oct 08 07:54:12 volumio volumio[904]: info: CoreStateMachine::syncState stateService play Oct 08 07:54:12 volumio volumio[904]: info: CoreStateMachine::syncState currentStatus play Oct 08 07:54:12 volumio volumio[904]: info: Received an update from plugin. extracting info from payload Oct 08 07:54:12 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:12 volumio volumio[904]: info: CorePlayQueue::getTrack 1 Oct 08 07:54:12 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:12 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:12 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:12 volumio volumio[904]: info: CorePlayQueue::getTrack 1 Oct 08 07:54:12 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:12 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:12 volumio volumio[904]: info: ControllerMpd::pushState Oct 08 07:54:12 volumio volumio[904]: info: CoreCommandRouter::servicePushState Oct 08 07:54:12 volumio volumio[904]: info: CorePlayQueue::getTrack 1 Oct 08 07:54:12 volumio volumio[904]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11865,"duration":260,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"615 Kbps","isStreaming":false,"title":"天黑","artist":"阿杜","album":"天黑","uri":"USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac)/02.天黑.flac","trackType":"flac"} Oct 08 07:54:12 volumio volumio[904]: verbose: CURRENT POSITION 1 Oct 08 07:54:12 volumio volumio[904]: info: CoreStateMachine::syncState stateService play Oct 08 07:54:12 volumio volumio[904]: info: CoreStateMachine::syncState currentStatus play Oct 08 07:54:12 volumio volumio[904]: info: Received an update from plugin. extracting info from payload Oct 08 07:54:12 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:12 volumio volumio[904]: info: CorePlayQueue::getTrack 1 Oct 08 07:54:12 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:12 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:12 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:12 volumio volumio[904]: info: CorePlayQueue::getTrack 1 Oct 08 07:54:12 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:12 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:12 volumio volumio[904]: info: ControllerMpd::pushState Oct 08 07:54:12 volumio volumio[904]: info: CoreCommandRouter::servicePushState Oct 08 07:54:12 volumio volumio[904]: info: CorePlayQueue::getTrack 1 Oct 08 07:54:12 volumio volumio[904]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11865,"duration":260,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"615 Kbps","isStreaming":false,"title":"天黑","artist":"阿杜","album":"天黑","uri":"USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac)/02.天黑.flac","trackType":"flac"} Oct 08 07:54:12 volumio volumio[904]: verbose: CURRENT POSITION 1 Oct 08 07:54:12 volumio volumio[904]: info: CoreStateMachine::syncState stateService play Oct 08 07:54:12 volumio volumio[904]: info: CoreStateMachine::syncState currentStatus play Oct 08 07:54:12 volumio volumio[904]: info: Received an update from plugin. extracting info from payload Oct 08 07:54:12 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:12 volumio volumio[904]: info: CorePlayQueue::getTrack 1 Oct 08 07:54:12 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:12 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:12 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:12 volumio volumio[904]: info: CorePlayQueue::getTrack 1 Oct 08 07:54:12 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:12 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:12 volumio volumio[904]: info: ------------------------------ 156ms Oct 08 07:54:12 volumio volumio[904]: info: ------------------------------ 155ms Oct 08 07:54:12 volumio volumio[904]: info: ------------------------------ 155ms Oct 08 07:54:12 volumio kernel: usb 1-1.3: new high-speed USB device number 10 using xhci_hcd Oct 08 07:54:12 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Oct 08 07:54:12 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand rescan Oct 08 07:54:12 volumio volumio[904]: info: sendMpdCommand rescan took 3 milliseconds Oct 08 07:54:12 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:54:12 volumio volumio[904]: info: Oct 08 07:54:12 volumio volumio[904]: ---------------------------- MPD announces state update: update Oct 08 07:54:12 volumio volumio[904]: info: ControllerMpd::getState Oct 08 07:54:12 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:54:12 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:54:12 volumio volumio[904]: info: Oct 08 07:54:12 volumio volumio[904]: ---------------------------- MPD announces state update: update Oct 08 07:54:12 volumio volumio[904]: info: ControllerMpd::getState Oct 08 07:54:12 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:54:12 volumio volumio[904]: info: sendMpdCommand status took 4 milliseconds Oct 08 07:54:12 volumio volumio[904]: info: sendMpdCommand status took 3 milliseconds Oct 08 07:54:12 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:54:12 volumio volumio[904]: info: Command Router : Notfying DB Updatefalse Oct 08 07:54:12 volumio volumio[904]: info: CoreCommandRouter::Close All Modals sent Oct 08 07:54:12 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:54:12 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 08 07:54:12 volumio volumio[904]: info: sendMpdCommand status took 31 milliseconds Oct 08 07:54:12 volumio volumio[904]: info: sendMpdCommand status took 31 milliseconds Oct 08 07:54:12 volumio volumio[904]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 08 07:54:13 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:54:13 volumio volumio[904]: info: Command Router : Notfying DB Updatefalse Oct 08 07:54:13 volumio volumio[904]: info: CoreCommandRouter::Close All Modals sent Oct 08 07:54:13 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:54:13 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 08 07:54:13 volumio volumio[904]: verbose: ControllerMpd::parseTrackInfo Oct 08 07:54:13 volumio volumio[904]: info: ControllerMpd::pushState Oct 08 07:54:13 volumio volumio[904]: info: CoreCommandRouter::servicePushState Oct 08 07:54:13 volumio volumio[904]: info: CorePlayQueue::getTrack 1 Oct 08 07:54:13 volumio volumio[904]: verbose: STATE SERVICE {"status":"play","position":0,"seek":12469,"duration":260,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"583 Kbps","isStreaming":false,"title":"天黑","artist":"阿杜","album":"天黑","uri":"USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac)/02.天黑.flac","trackType":"flac"} Oct 08 07:54:13 volumio volumio[904]: verbose: CURRENT POSITION 1 Oct 08 07:54:13 volumio volumio[904]: info: CoreStateMachine::syncState stateService play Oct 08 07:54:13 volumio volumio[904]: info: CoreStateMachine::syncState currentStatus play Oct 08 07:54:13 volumio volumio[904]: info: Received an update from plugin. extracting info from payload Oct 08 07:54:13 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:13 volumio volumio[904]: info: CorePlayQueue::getTrack 1 Oct 08 07:54:13 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:13 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:13 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:13 volumio volumio[904]: info: CorePlayQueue::getTrack 1 Oct 08 07:54:13 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:13 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:13 volumio volumio[904]: info: ------------------------------ 72ms Oct 08 07:54:13 volumio volumio[904]: info: sendMpdCommand playlistinfo took 32 milliseconds Oct 08 07:54:13 volumio volumio[904]: verbose: ControllerMpd::parseTrackInfo Oct 08 07:54:13 volumio volumio[904]: info: ControllerMpd::pushState Oct 08 07:54:13 volumio volumio[904]: info: CoreCommandRouter::servicePushState Oct 08 07:54:13 volumio volumio[904]: info: CorePlayQueue::getTrack 1 Oct 08 07:54:13 volumio volumio[904]: verbose: STATE SERVICE {"status":"play","position":0,"seek":12469,"duration":260,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"583 Kbps","isStreaming":false,"title":"天黑","artist":"阿杜","album":"天黑","uri":"USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac)/02.天黑.flac","trackType":"flac"} Oct 08 07:54:13 volumio volumio[904]: verbose: CURRENT POSITION 1 Oct 08 07:54:13 volumio volumio[904]: info: CoreStateMachine::syncState stateService play Oct 08 07:54:13 volumio volumio[904]: info: CoreStateMachine::syncState currentStatus play Oct 08 07:54:13 volumio volumio[904]: info: Received an update from plugin. extracting info from payload Oct 08 07:54:13 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:13 volumio volumio[904]: info: CorePlayQueue::getTrack 1 Oct 08 07:54:13 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:13 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:13 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:13 volumio volumio[904]: info: CorePlayQueue::getTrack 1 Oct 08 07:54:13 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:13 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:13 volumio volumio[904]: info: ------------------------------ 96ms Oct 08 07:54:13 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 08 07:54:13 volumio kernel: usb 1-1.3: device descriptor read/64, error -71 Oct 08 07:54:14 volumio kernel: usb 1-1.3: device descriptor read/64, error -71 Oct 08 07:54:15 volumio kernel: usb 1-1.3: new high-speed USB device number 11 using xhci_hcd Oct 08 07:54:15 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Oct 08 07:54:15 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand rescan Oct 08 07:54:15 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:54:15 volumio volumio[904]: info: Oct 08 07:54:15 volumio volumio[904]: ---------------------------- MPD announces state update: update Oct 08 07:54:15 volumio volumio[904]: info: sendMpdCommand rescan took 3 milliseconds Oct 08 07:54:15 volumio volumio[904]: info: ControllerMpd::getState Oct 08 07:54:15 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:54:15 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:54:15 volumio volumio[904]: info: Oct 08 07:54:15 volumio volumio[904]: ---------------------------- MPD announces state update: update Oct 08 07:54:15 volumio volumio[904]: info: sendMpdCommand status took 2 milliseconds Oct 08 07:54:15 volumio volumio[904]: info: sendMpdCommand status took 2 milliseconds Oct 08 07:54:15 volumio volumio[904]: info: ControllerMpd::getState Oct 08 07:54:15 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:54:15 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:54:15 volumio volumio[904]: info: Command Router : Notfying DB Updatetrue Oct 08 07:54:15 volumio volumio[904]: info: CoreCommandRouter::Close All Modals sent Oct 08 07:54:15 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:54:15 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 08 07:54:15 volumio volumio[904]: info: sendMpdCommand status took 34 milliseconds Oct 08 07:54:15 volumio volumio[904]: info: sendMpdCommand status took 33 milliseconds Oct 08 07:54:15 volumio volumio[904]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 08 07:54:15 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:54:15 volumio volumio[904]: info: Command Router : Notfying DB Updatefalse Oct 08 07:54:15 volumio volumio[904]: info: CoreCommandRouter::Close All Modals sent Oct 08 07:54:15 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:54:15 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 08 07:54:15 volumio volumio[904]: verbose: ControllerMpd::parseTrackInfo Oct 08 07:54:15 volumio volumio[904]: info: ControllerMpd::pushState Oct 08 07:54:15 volumio volumio[904]: info: CoreCommandRouter::servicePushState Oct 08 07:54:15 volumio volumio[904]: info: CorePlayQueue::getTrack 1 Oct 08 07:54:15 volumio volumio[904]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14860,"duration":260,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1010 Kbps","isStreaming":false,"title":"天黑","artist":"阿杜","album":"天黑","uri":"USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac)/02.天黑.flac","trackType":"flac"} Oct 08 07:54:15 volumio volumio[904]: verbose: CURRENT POSITION 1 Oct 08 07:54:15 volumio volumio[904]: info: CoreStateMachine::syncState stateService play Oct 08 07:54:15 volumio volumio[904]: info: CoreStateMachine::syncState currentStatus play Oct 08 07:54:15 volumio volumio[904]: info: Received an update from plugin. extracting info from payload Oct 08 07:54:15 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:15 volumio volumio[904]: info: CorePlayQueue::getTrack 1 Oct 08 07:54:15 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:15 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:15 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:15 volumio volumio[904]: info: CorePlayQueue::getTrack 1 Oct 08 07:54:15 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:15 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:15 volumio volumio[904]: info: ------------------------------ 73ms Oct 08 07:54:15 volumio volumio[904]: info: sendMpdCommand playlistinfo took 24 milliseconds Oct 08 07:54:15 volumio volumio[904]: verbose: ControllerMpd::parseTrackInfo Oct 08 07:54:15 volumio volumio[904]: info: ControllerMpd::pushState Oct 08 07:54:15 volumio volumio[904]: info: CoreCommandRouter::servicePushState Oct 08 07:54:15 volumio volumio[904]: info: CorePlayQueue::getTrack 1 Oct 08 07:54:15 volumio volumio[904]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14976,"duration":260,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"929 Kbps","isStreaming":false,"title":"天黑","artist":"阿杜","album":"天黑","uri":"USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac)/02.天黑.flac","trackType":"flac"} Oct 08 07:54:15 volumio volumio[904]: verbose: CURRENT POSITION 1 Oct 08 07:54:15 volumio volumio[904]: info: CoreStateMachine::syncState stateService play Oct 08 07:54:15 volumio volumio[904]: info: CoreStateMachine::syncState currentStatus play Oct 08 07:54:15 volumio volumio[904]: info: Received an update from plugin. extracting info from payload Oct 08 07:54:15 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:15 volumio volumio[904]: info: CorePlayQueue::getTrack 1 Oct 08 07:54:15 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:15 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:15 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:15 volumio volumio[904]: info: CorePlayQueue::getTrack 1 Oct 08 07:54:15 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:15 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:15 volumio volumio[904]: info: ------------------------------ 101ms Oct 08 07:54:17 volumio kernel: usb 1-1.3: device descriptor read/64, error -71 Oct 08 07:54:17 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Oct 08 07:54:17 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand update Oct 08 07:54:17 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:54:17 volumio volumio[904]: info: Oct 08 07:54:17 volumio volumio[904]: ---------------------------- MPD announces state update: update Oct 08 07:54:17 volumio volumio[904]: info: sendMpdCommand update took 3 milliseconds Oct 08 07:54:17 volumio volumio[904]: info: ControllerMpd::getState Oct 08 07:54:17 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:54:17 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:54:17 volumio volumio[904]: info: Oct 08 07:54:17 volumio volumio[904]: ---------------------------- MPD announces state update: update Oct 08 07:54:17 volumio volumio[904]: info: sendMpdCommand status took 3 milliseconds Oct 08 07:54:17 volumio volumio[904]: info: sendMpdCommand status took 3 milliseconds Oct 08 07:54:17 volumio volumio[904]: info: ControllerMpd::getState Oct 08 07:54:17 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:54:17 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:54:17 volumio volumio[904]: info: Command Router : Notfying DB Updatetrue Oct 08 07:54:17 volumio volumio[904]: info: CoreCommandRouter::Close All Modals sent Oct 08 07:54:17 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:54:17 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 08 07:54:17 volumio volumio[904]: info: sendMpdCommand status took 36 milliseconds Oct 08 07:54:17 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:54:17 volumio volumio[904]: info: Command Router : Notfying DB Updatefalse Oct 08 07:54:17 volumio volumio[904]: info: CoreCommandRouter::Close All Modals sent Oct 08 07:54:17 volumio kernel: usb 1-1.3: device descriptor read/64, error -71 Oct 08 07:54:17 volumio volumio[904]: info: sendMpdCommand status took 510 milliseconds Oct 08 07:54:17 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:54:17 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 08 07:54:17 volumio volumio[904]: info: Oct 08 07:54:17 volumio volumio[904]: ---------------------------- MPD announces state update: player Oct 08 07:54:17 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:54:17 volumio volumio[904]: info: Oct 08 07:54:17 volumio volumio[904]: ---------------------------- MPD announces state update: update Oct 08 07:54:17 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:54:17 volumio volumio[904]: info: Oct 08 07:54:17 volumio volumio[904]: ---------------------------- MPD announces state update: update Oct 08 07:54:17 volumio volumio[904]: info: ControllerMpd::getState Oct 08 07:54:17 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:54:17 volumio volumio[904]: info: ControllerMpd::getState Oct 08 07:54:17 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:54:17 volumio volumio[904]: info: sendMpdCommand playlistinfo took 506 milliseconds Oct 08 07:54:17 volumio volumio[904]: info: ControllerMpd::getState Oct 08 07:54:17 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:54:17 volumio volumio[904]: info: sendMpdCommand playlistinfo took 16 milliseconds Oct 08 07:54:17 volumio volumio[904]: verbose: ControllerMpd::parseTrackInfo Oct 08 07:54:17 volumio volumio[904]: verbose: ControllerMpd::parseTrackInfo Oct 08 07:54:17 volumio volumio[904]: info: ControllerMpd::pushState Oct 08 07:54:17 volumio volumio[904]: info: CoreCommandRouter::servicePushState Oct 08 07:54:17 volumio volumio[904]: info: CorePlayQueue::getTrack 1 Oct 08 07:54:17 volumio volumio[904]: verbose: STATE SERVICE {"status":"play","position":0,"seek":16555,"duration":260,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"806 Kbps","isStreaming":false,"title":"天黑","artist":"阿杜","album":"天黑","uri":"USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac)/02.天黑.flac","trackType":"flac"} Oct 08 07:54:17 volumio volumio[904]: verbose: CURRENT POSITION 1 Oct 08 07:54:17 volumio volumio[904]: info: CoreStateMachine::syncState stateService play Oct 08 07:54:17 volumio volumio[904]: info: CoreStateMachine::syncState currentStatus play Oct 08 07:54:17 volumio volumio[904]: info: Received an update from plugin. extracting info from payload Oct 08 07:54:17 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:17 volumio volumio[904]: info: CorePlayQueue::getTrack 1 Oct 08 07:54:17 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:17 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:17 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:17 volumio volumio[904]: info: CorePlayQueue::getTrack 1 Oct 08 07:54:17 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:17 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:17 volumio volumio[904]: info: ControllerMpd::pushState Oct 08 07:54:17 volumio kernel: usb 1-1-port3: attempt power cycle Oct 08 07:54:17 volumio volumio[904]: info: CoreCommandRouter::servicePushState Oct 08 07:54:17 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:17 volumio volumio[904]: info: CorePlayQueue::getTrack 1 Oct 08 07:54:17 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:17 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:17 volumio volumio[904]: info: CorePlayQueue::getTrack 1 Oct 08 07:54:17 volumio volumio[904]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"天黑","artist":"阿杜","album":"天黑","uri":"USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac)/02.天黑.flac","trackType":"flac"} Oct 08 07:54:17 volumio volumio[904]: verbose: CURRENT POSITION 1 Oct 08 07:54:17 volumio volumio[904]: info: CoreStateMachine::syncState stateService stop Oct 08 07:54:17 volumio volumio[904]: info: CoreStateMachine::syncState currentStatus play Oct 08 07:54:17 volumio volumio[904]: info: CoreStateMachine::play index undefined Oct 08 07:54:17 volumio volumio[904]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 08 07:54:17 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:17 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:54:17 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:17 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:17 volumio volumio[904]: info: ------------------------------ 566ms Oct 08 07:54:17 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:54:17 volumio volumio[904]: info: CoreStateMachine::startPlaybackTimer Oct 08 07:54:17 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:54:17 volumio volumio[904]: verbose: ControllerMpd::clearAddPlayTracks USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac)/03.天天看到你.flac Oct 08 07:54:17 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand stop Oct 08 07:54:17 volumio volumio[904]: info: ------------------------------ 565ms Oct 08 07:54:17 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:17 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:54:17 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:17 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:17 volumio volumio[904]: info: sendMpdCommand status took 60 milliseconds Oct 08 07:54:17 volumio volumio[904]: info: sendMpdCommand status took 61 milliseconds Oct 08 07:54:17 volumio volumio[904]: info: sendMpdCommand status took 60 milliseconds Oct 08 07:54:17 volumio volumio[904]: info: sendMpdCommand status took 48 milliseconds Oct 08 07:54:17 volumio volumio[904]: info: sendMpdCommand status took 47 milliseconds Oct 08 07:54:17 volumio volumio[904]: info: sendMpdCommand stop took 12 milliseconds Oct 08 07:54:17 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:54:17 volumio volumio[904]: info: Command Router : Notfying DB Updatefalse Oct 08 07:54:17 volumio volumio[904]: info: CoreCommandRouter::Close All Modals sent Oct 08 07:54:17 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:54:17 volumio volumio[904]: info: Command Router : Notfying DB Updatefalse Oct 08 07:54:17 volumio volumio[904]: info: CoreCommandRouter::Close All Modals sent Oct 08 07:54:17 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:54:17 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:54:17 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:54:17 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand clear Oct 08 07:54:17 volumio volumio[904]: info: ControllerMpd::pushState Oct 08 07:54:17 volumio volumio[904]: info: CoreCommandRouter::servicePushState Oct 08 07:54:17 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:17 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:54:17 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:17 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:17 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:54:17 volumio volumio[904]: 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} Oct 08 07:54:17 volumio volumio[904]: verbose: CURRENT POSITION 2 Oct 08 07:54:17 volumio volumio[904]: info: CoreStateMachine::syncState stateService stop Oct 08 07:54:17 volumio volumio[904]: info: CoreStateMachine::syncState currentStatus stop Oct 08 07:54:17 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:17 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:54:17 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:17 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:17 volumio volumio[904]: info: No code Oct 08 07:54:17 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:17 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:54:17 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:17 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:17 volumio volumio[904]: info: ControllerMpd::pushState Oct 08 07:54:17 volumio volumio[904]: info: CoreCommandRouter::servicePushState Oct 08 07:54:17 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:17 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:54:17 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:17 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:17 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:54:17 volumio volumio[904]: 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} Oct 08 07:54:17 volumio volumio[904]: verbose: CURRENT POSITION 2 Oct 08 07:54:17 volumio volumio[904]: info: CoreStateMachine::syncState stateService stop Oct 08 07:54:17 volumio volumio[904]: info: CoreStateMachine::syncState currentStatus stop Oct 08 07:54:17 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:17 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:54:17 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:17 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:17 volumio volumio[904]: info: No code Oct 08 07:54:17 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:17 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:54:17 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:17 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:17 volumio volumio[904]: info: ControllerMpd::pushState Oct 08 07:54:17 volumio volumio[904]: info: CoreCommandRouter::servicePushState Oct 08 07:54:17 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:17 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:54:17 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:17 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:17 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:54:17 volumio volumio[904]: 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} Oct 08 07:54:17 volumio volumio[904]: verbose: CURRENT POSITION 2 Oct 08 07:54:17 volumio volumio[904]: info: CoreStateMachine::syncState stateService stop Oct 08 07:54:17 volumio volumio[904]: info: CoreStateMachine::syncState currentStatus stop Oct 08 07:54:17 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:17 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:54:17 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:17 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:17 volumio volumio[904]: info: No code Oct 08 07:54:17 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:17 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:54:17 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:17 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:17 volumio volumio[904]: info: ------------------------------ 164ms Oct 08 07:54:17 volumio volumio[904]: info: ------------------------------ 164ms Oct 08 07:54:17 volumio volumio[904]: info: ------------------------------ 164ms Oct 08 07:54:17 volumio volumio[904]: info: Oct 08 07:54:17 volumio volumio[904]: ---------------------------- MPD announces system playlist update Oct 08 07:54:17 volumio volumio[904]: info: Ignoring MPD Status Update Oct 08 07:54:17 volumio volumio[904]: info: sendMpdCommand clear took 100 milliseconds Oct 08 07:54:17 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand add "USB/5426-A066/4 阿杜 极品精选/天黑(44.1kHz16bitFlac)/03.天天看到你.flac" Oct 08 07:54:17 volumio volumio[904]: error: updateQueue error: null Oct 08 07:54:17 volumio volumio[904]: info: ------------------------------ 18ms Oct 08 07:54:17 volumio volumio[904]: info: touch_display: Setting screensaver timeout to 0 seconds. Oct 08 07:54:17 volumio volumio[904]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Oct 08 07:54:17 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 08 07:54:17 volumio volumio[904]: info: touch_display: Setting screensaver timeout to 0 seconds. Oct 08 07:54:18 volumio kernel: usb 1-1.3: new high-speed USB device number 12 using xhci_hcd Oct 08 07:54:19 volumio kernel: usb 1-1.3: device descriptor read/8, error -71 Oct 08 07:54:19 volumio kernel: usb 1-1.3: device descriptor read/8, error -71 Oct 08 07:54:19 volumio kernel: usb 1-1.3: new high-speed USB device number 13 using xhci_hcd Oct 08 07:54:20 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Oct 08 07:54:20 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand rescan Oct 08 07:54:20 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:54:20 volumio volumio[904]: info: Oct 08 07:54:20 volumio volumio[904]: ---------------------------- MPD announces state update: update Oct 08 07:54:20 volumio volumio[904]: info: sendMpdCommand rescan took 5 milliseconds Oct 08 07:54:20 volumio volumio[904]: info: ControllerMpd::getState Oct 08 07:54:20 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:54:20 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:54:20 volumio volumio[904]: info: Oct 08 07:54:20 volumio volumio[904]: ---------------------------- MPD announces state update: update Oct 08 07:54:20 volumio volumio[904]: info: ControllerMpd::getState Oct 08 07:54:20 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:54:20 volumio volumio[904]: info: sendMpdCommand status took 4 milliseconds Oct 08 07:54:20 volumio volumio[904]: info: sendMpdCommand status took 3 milliseconds Oct 08 07:54:20 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:54:20 volumio volumio[904]: info: Command Router : Notfying DB Updatefalse Oct 08 07:54:20 volumio volumio[904]: info: CoreCommandRouter::Close All Modals sent Oct 08 07:54:20 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:54:20 volumio volumio[904]: info: ControllerMpd::pushState Oct 08 07:54:20 volumio volumio[904]: info: CoreCommandRouter::servicePushState Oct 08 07:54:20 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:20 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:54:20 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:20 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:20 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:54:20 volumio volumio[904]: 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} Oct 08 07:54:20 volumio volumio[904]: verbose: CURRENT POSITION 2 Oct 08 07:54:20 volumio volumio[904]: info: CoreStateMachine::syncState stateService stop Oct 08 07:54:20 volumio volumio[904]: info: CoreStateMachine::syncState currentStatus stop Oct 08 07:54:20 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:20 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:54:20 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:20 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:20 volumio volumio[904]: info: No code Oct 08 07:54:20 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:20 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:54:20 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:20 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:20 volumio volumio[904]: info: ------------------------------ 47ms Oct 08 07:54:20 volumio volumio[904]: info: sendMpdCommand status took 47 milliseconds Oct 08 07:54:20 volumio volumio[904]: info: sendMpdCommand status took 47 milliseconds Oct 08 07:54:20 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:54:20 volumio volumio[904]: info: Command Router : Notfying DB Updatefalse Oct 08 07:54:20 volumio volumio[904]: info: CoreCommandRouter::Close All Modals sent Oct 08 07:54:20 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:54:20 volumio volumio[904]: info: ControllerMpd::pushState Oct 08 07:54:20 volumio volumio[904]: info: CoreCommandRouter::servicePushState Oct 08 07:54:20 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:20 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:54:20 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:20 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:20 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:54:20 volumio volumio[904]: 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} Oct 08 07:54:20 volumio volumio[904]: verbose: CURRENT POSITION 2 Oct 08 07:54:20 volumio volumio[904]: info: CoreStateMachine::syncState stateService stop Oct 08 07:54:20 volumio volumio[904]: info: CoreStateMachine::syncState currentStatus stop Oct 08 07:54:20 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:20 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:54:20 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:20 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:20 volumio volumio[904]: info: No code Oct 08 07:54:20 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:20 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:54:20 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:20 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:20 volumio volumio[904]: info: ------------------------------ 84ms Oct 08 07:54:20 volumio kernel: usb 1-1.3: device descriptor read/8, error -71 Oct 08 07:54:20 volumio kernel: usb 1-1.3: device descriptor read/8, error -71 Oct 08 07:54:20 volumio kernel: usb 1-1-port3: unable to enumerate USB device Oct 08 07:54:21 volumio kernel: usb 1-1.3: new high-speed USB device number 14 using xhci_hcd Oct 08 07:54:21 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 08 07:54:22 volumio kernel: usb 1-1.3: device descriptor read/64, error -71 Oct 08 07:54:22 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Oct 08 07:54:22 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand update Oct 08 07:54:22 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:54:22 volumio volumio[904]: info: Oct 08 07:54:22 volumio volumio[904]: ---------------------------- MPD announces state update: update Oct 08 07:54:22 volumio volumio[904]: info: sendMpdCommand update took 4 milliseconds Oct 08 07:54:22 volumio volumio[904]: info: ControllerMpd::getState Oct 08 07:54:22 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:54:22 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:54:22 volumio volumio[904]: info: Oct 08 07:54:22 volumio volumio[904]: ---------------------------- MPD announces state update: update Oct 08 07:54:22 volumio volumio[904]: info: ControllerMpd::getState Oct 08 07:54:22 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:54:22 volumio volumio[904]: info: sendMpdCommand status took 3 milliseconds Oct 08 07:54:22 volumio volumio[904]: info: sendMpdCommand status took 2 milliseconds Oct 08 07:54:22 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:54:22 volumio volumio[904]: info: Command Router : Notfying DB Updatefalse Oct 08 07:54:22 volumio volumio[904]: info: CoreCommandRouter::Close All Modals sent Oct 08 07:54:22 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:54:22 volumio volumio[904]: info: ControllerMpd::pushState Oct 08 07:54:22 volumio volumio[904]: info: CoreCommandRouter::servicePushState Oct 08 07:54:22 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:22 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:54:22 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:22 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:22 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:54:22 volumio volumio[904]: 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} Oct 08 07:54:22 volumio volumio[904]: verbose: CURRENT POSITION 2 Oct 08 07:54:22 volumio volumio[904]: info: CoreStateMachine::syncState stateService stop Oct 08 07:54:22 volumio volumio[904]: info: CoreStateMachine::syncState currentStatus stop Oct 08 07:54:22 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:22 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:54:22 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:22 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:22 volumio volumio[904]: info: No code Oct 08 07:54:22 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:22 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:54:22 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:22 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:22 volumio volumio[904]: info: ------------------------------ 46ms Oct 08 07:54:22 volumio volumio[904]: info: sendMpdCommand status took 46 milliseconds Oct 08 07:54:22 volumio volumio[904]: info: sendMpdCommand status took 45 milliseconds Oct 08 07:54:22 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:54:22 volumio volumio[904]: info: Command Router : Notfying DB Updatefalse Oct 08 07:54:22 volumio volumio[904]: info: CoreCommandRouter::Close All Modals sent Oct 08 07:54:22 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:54:22 volumio volumio[904]: info: ControllerMpd::pushState Oct 08 07:54:22 volumio volumio[904]: info: CoreCommandRouter::servicePushState Oct 08 07:54:22 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:22 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:54:22 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:22 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:22 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:54:22 volumio volumio[904]: 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} Oct 08 07:54:22 volumio volumio[904]: verbose: CURRENT POSITION 2 Oct 08 07:54:22 volumio volumio[904]: info: CoreStateMachine::syncState stateService stop Oct 08 07:54:22 volumio volumio[904]: info: CoreStateMachine::syncState currentStatus stop Oct 08 07:54:22 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:22 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:54:22 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:22 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:22 volumio volumio[904]: info: No code Oct 08 07:54:22 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:22 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:54:22 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:22 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:22 volumio volumio[904]: info: ------------------------------ 90ms Oct 08 07:54:22 volumio kernel: usb 1-1.3: device descriptor read/64, error -71 Oct 08 07:54:23 volumio kernel: usb 1-1.3: new high-speed USB device number 15 using xhci_hcd Oct 08 07:54:23 volumio kernel: usb 1-1.3: device descriptor read/64, error -71 Oct 08 07:54:24 volumio kernel: usb 1-1.3: device descriptor read/64, error -71 Oct 08 07:54:25 volumio kernel: usb 1-1-port3: attempt power cycle Oct 08 07:54:25 volumio kernel: usb 1-1.3: new high-speed USB device number 16 using xhci_hcd Oct 08 07:54:25 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 08 07:54:25 volumio kernel: usb 1-1.3: device descriptor read/8, error -71 Oct 08 07:54:26 volumio kernel: usb 1-1.3: device descriptor read/8, error -71 Oct 08 07:54:26 volumio kernel: usb 1-1.3: new high-speed USB device number 17 using xhci_hcd Oct 08 07:54:26 volumio kernel: usb 1-1.3: Device not responding to setup address. Oct 08 07:54:26 volumio kernel: usb 1-1.3: Device not responding to setup address. Oct 08 07:54:26 volumio kernel: usb 1-1.3: device not accepting address 17, error -71 Oct 08 07:54:26 volumio kernel: usb 1-1-port3: unable to enumerate USB device Oct 08 07:54:29 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 08 07:54:31 volumio kernel: usb 1-1.3: new high-speed USB device number 18 using xhci_hcd Oct 08 07:54:31 volumio kernel: usb 1-1.3: New USB device found, idVendor=0781, idProduct=5567, bcdDevice= 1.00 Oct 08 07:54:31 volumio kernel: usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Oct 08 07:54:31 volumio kernel: usb 1-1.3: Product: Cruzer Blade Oct 08 07:54:31 volumio kernel: usb 1-1.3: Manufacturer: SanDisk Oct 08 07:54:31 volumio kernel: usb 1-1.3: SerialNumber: 4C530000220511217060 Oct 08 07:54:31 volumio kernel: usb-storage 1-1.3:1.0: USB Mass Storage device detected Oct 08 07:54:31 volumio kernel: scsi host0: usb-storage 1-1.3:1.0 Oct 08 07:54:32 volumio kernel: scsi 0:0:0:0: Direct-Access SanDisk Cruzer Blade 1.00 PQ: 0 ANSI: 6 Oct 08 07:54:32 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Oct 08 07:54:32 volumio kernel: sd 0:0:0:0: [sda] 120225792 512-byte logical blocks: (61.6 GB/57.3 GiB) Oct 08 07:54:32 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Oct 08 07:54:32 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00 Oct 08 07:54:32 volumio kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA Oct 08 07:54:32 volumio kernel: sda: sda1 Oct 08 07:54:32 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI removable disk Oct 08 07:54:32 volumio udisks-glue[609]: Device file /dev/sda inserted Oct 08 07:54:33 volumio udisks-glue[609]: Device file /dev/sda1 inserted Oct 08 07:54:33 volumio udisks-glue[609]: Trying to automount /dev/sda1... Oct 08 07:54:33 volumio kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Oct 08 07:54:33 volumio udisks-glue[609]: Successfully automounted /dev/sda1 at /media/5426-A066 Oct 08 07:54:33 volumio udisks-glue[609]: Device file /dev/sda1 mounted at /media/5426-A066 Oct 08 07:54:33 volumio udisks-glue[609]: Updating DB (#20) ... Oct 08 07:54:33 volumio udisks-glue[609]: volume: 40% repeat: off random: off single: off consume: off Oct 08 07:54:33 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:54:33 volumio volumio[904]: info: Oct 08 07:54:33 volumio volumio[904]: ---------------------------- MPD announces state update: update Oct 08 07:54:33 volumio volumio[904]: info: ControllerMpd::getState Oct 08 07:54:33 volumio volumio[904]: verbose: ControllerMpd::sendMpdCommand status Oct 08 07:54:33 volumio volumio[904]: info: sendMpdCommand status took 2 milliseconds Oct 08 07:54:33 volumio volumio[904]: info: sendMpdCommand status took 1 milliseconds Oct 08 07:54:33 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:54:33 volumio volumio[904]: info: Command Router : Notfying DB Updatetrue Oct 08 07:54:33 volumio volumio[904]: info: CoreCommandRouter::Close All Modals sent Oct 08 07:54:33 volumio volumio[904]: verbose: ControllerMpd::parseState Oct 08 07:54:33 volumio volumio[904]: info: ControllerMpd::pushState Oct 08 07:54:33 volumio volumio[904]: info: CoreCommandRouter::servicePushState Oct 08 07:54:33 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:33 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:54:33 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:33 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:33 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:54:33 volumio volumio[904]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 08 07:54:33 volumio volumio[904]: verbose: CURRENT POSITION 2 Oct 08 07:54:33 volumio volumio[904]: info: CoreStateMachine::syncState stateService stop Oct 08 07:54:33 volumio volumio[904]: info: CoreStateMachine::syncState currentStatus stop Oct 08 07:54:33 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:33 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:54:33 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:33 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:33 volumio volumio[904]: info: No code Oct 08 07:54:33 volumio volumio[904]: info: CoreStateMachine::pushState Oct 08 07:54:33 volumio volumio[904]: info: CorePlayQueue::getTrack 2 Oct 08 07:54:33 volumio volumio[904]: info: CoreCommandRouter::volumioPushState Oct 08 07:54:33 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 07:54:33 volumio volumio[904]: info: ------------------------------ 64ms Oct 08 07:54:33 volumio volumio[904]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 08 07:54:34 volumio volumio[904]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 08 07:54:34 volumio volumio[904]: { Error: connect ETIMEDOUT 199.16.156.103:443 Oct 08 07:54:34 volumio volumio[904]: at Object._errnoException (util.js:1022:11) Oct 08 07:54:34 volumio volumio[904]: at _exceptionWithHostPort (util.js:1044:20) Oct 08 07:54:34 volumio volumio[904]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Oct 08 07:54:34 volumio volumio[904]: code: 'ETIMEDOUT', Oct 08 07:54:34 volumio volumio[904]: errno: 'ETIMEDOUT', Oct 08 07:54:34 volumio volumio[904]: syscall: 'connect', Oct 08 07:54:34 volumio volumio[904]: address: '199.16.156.103', Oct 08 07:54:34 volumio volumio[904]: port: 443 } Oct 08 07:54:34 volumio volumio[904]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 08 07:54:34 volumio sudo[3069]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-08 07:53 Oct 08 07:54:34 volumio sudo[3069]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="307f91893fca011201acba9973e1c41bd3a0ad5d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Sep 30 22:42:09 CEST 2021" VOLUMIO_VERSION="2.915" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="02ee61bb422c79b6e5c0b6d1847a78d9"