-- Logs begin at Thu 2019-02-14 18:12:00 CST, end at Sat 2025-07-05 17:02:54 CST. -- Jul 05 17:01:01 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 05 17:01:05 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 05 17:01:07 volumio kernel: usb 1-1.2: reset high-speed USB device number 5 using dwc_otg Jul 05 17:01:29 volumio kernel: usb 1-1.2: device descriptor read/64, error -110 Jul 05 17:01:29 volumio kernel: usb 1-1.2: device descriptor read/64, error -110 Jul 05 17:01:29 volumio kernel: usb 1-1.2: reset high-speed USB device number 5 using dwc_otg Jul 05 17:01:29 volumio kernel: usb 1-1.2: device descriptor read/64, error -71 Jul 05 17:01:29 volumio kernel: usb 1-1.2: device descriptor read/64, error -71 Jul 05 17:01:29 volumio kernel: usb 1-1.2: reset high-speed USB device number 5 using dwc_otg Jul 05 17:01:29 volumio kernel: usb 1-1.2: device not accepting address 5, error -71 Jul 05 17:01:29 volumio kernel: usb 1-1.2: reset high-speed USB device number 5 using dwc_otg Jul 05 17:01:29 volumio kernel: usb 1-1.2: device not accepting address 5, error -71 Jul 05 17:01:29 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x03 driverbyte=DRIVER_OK cmd_age=52s Jul 05 17:01:29 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 04 ac 82 80 00 00 f0 00 Jul 05 17:01:29 volumio kernel: I/O error, dev sda, sector 78414464 op 0x0:(READ) flags 0x84700 phys_seg 2 prio class 2 Jul 05 17:01:29 volumio kernel: usb 1-1.2: USB disconnect, device number 5 Jul 05 17:01:29 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=0s Jul 05 17:01:29 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 04 ac 83 70 00 00 f0 00 Jul 05 17:01:29 volumio kernel: I/O error, dev sda, sector 78414704 op 0x0:(READ) flags 0x84700 phys_seg 2 prio class 2 Jul 05 17:01:29 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=0s Jul 05 17:01:29 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 04 ac 84 60 00 00 20 00 Jul 05 17:01:29 volumio kernel: I/O error, dev sda, sector 78414944 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 Jul 05 17:01:29 volumio kernel: device offline error, dev sda, sector 78502336 op 0x0:(READ) flags 0x84700 phys_seg 10 prio class 3 Jul 05 17:01:29 volumio kernel: device offline error, dev sda, sector 78502576 op 0x0:(READ) flags 0x80700 phys_seg 2 prio class 3 Jul 05 17:01:29 volumio kernel: device offline error, dev sda, sector 78502336 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 3 Jul 05 17:01:29 volumio kernel: device offline error, dev sda, sector 78502336 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 3 Jul 05 17:01:29 volumio kernel: device offline error, dev sda, sector 9626 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 3 Jul 05 17:01:29 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 9594) Jul 05 17:01:29 volumio kernel: device offline error, dev sda, sector 9626 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 3 Jul 05 17:01:29 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 9594) Jul 05 17:01:29 volumio kernel: device offline error, dev sda, sector 9626 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 3 Jul 05 17:01:29 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 9594) Jul 05 17:01:29 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 9594) Jul 05 17:01:29 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 9594) Jul 05 17:01:29 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 9594) Jul 05 17:01:29 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 9594) Jul 05 17:01:29 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 9594) Jul 05 17:01:29 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 9594) Jul 05 17:01:29 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 9594) Jul 05 17:01:29 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 05 17:01:29 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jul 05 17:01:29 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand rescan Jul 05 17:01:29 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:01:29 volumio volumio[1028]: info: Jul 05 17:01:29 volumio volumio[1028]: ---------------------------- MPD announces state update: update Jul 05 17:01:29 volumio volumio[1028]: info: sendMpdCommand rescan took 13 milliseconds Jul 05 17:01:29 volumio volumio[1028]: info: ControllerMpd::getState Jul 05 17:01:29 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:01:29 volumio volumio[1028]: info: Jul 05 17:01:29 volumio volumio[1028]: ---------------------------- MPD announces state update: update Jul 05 17:01:29 volumio volumio[1028]: info: ControllerMpd::getState Jul 05 17:01:29 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:01:29 volumio volumio[1028]: info: Jul 05 17:01:29 volumio volumio[1028]: ---------------------------- MPD announces state update: update Jul 05 17:01:29 volumio volumio[1028]: info: ControllerMpd::getState Jul 05 17:01:29 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:01:29 volumio volumio[1028]: info: sendMpdCommand status took 19 milliseconds Jul 05 17:01:29 volumio volumio[1028]: info: sendMpdCommand status took 16 milliseconds Jul 05 17:01:29 volumio volumio[1028]: info: sendMpdCommand status took 15 milliseconds Jul 05 17:01:29 volumio volumio[1028]: info: sendMpdCommand status took 12 milliseconds Jul 05 17:01:29 volumio volumio[1028]: info: sendMpdCommand status took 9 milliseconds Jul 05 17:01:29 volumio volumio[1028]: verbose: ControllerMpd::parseState Jul 05 17:01:29 volumio volumio[1028]: info: Command Router : Notfying DB Updatetrue Jul 05 17:01:29 volumio volumio[1028]: verbose: ControllerMpd::parseState Jul 05 17:01:29 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 17:01:29 volumio volumio[1028]: verbose: ControllerMpd::parseState Jul 05 17:01:29 volumio volumio[1028]: info: Command Router : Notfying DB Updatetrue Jul 05 17:01:29 volumio volumio[1028]: info: CoreCommandRouter::Close All Modals sent Jul 05 17:01:29 volumio volumio[1028]: verbose: ControllerMpd::parseState Jul 05 17:01:29 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 17:01:29 volumio volumio[1028]: verbose: ControllerMpd::parseState Jul 05 17:01:29 volumio volumio[1028]: info: Command Router : Notfying DB Updatetrue Jul 05 17:01:29 volumio volumio[1028]: info: CoreCommandRouter::Close All Modals sent Jul 05 17:01:29 volumio volumio[1028]: verbose: ControllerMpd::parseState Jul 05 17:01:29 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 17:01:29 volumio volumio[1028]: info: sendMpdCommand playlistinfo took 106 milliseconds Jul 05 17:01:29 volumio volumio[1028]: info: sendMpdCommand playlistinfo took 69 milliseconds Jul 05 17:01:29 volumio volumio[1028]: info: sendMpdCommand playlistinfo took 31 milliseconds Jul 05 17:01:29 volumio volumio[1028]: verbose: ControllerMpd::parseTrackInfo Jul 05 17:01:29 volumio volumio[1028]: info: ControllerMpd::pushState Jul 05 17:01:29 volumio volumio[1028]: info: CoreCommandRouter::servicePushState Jul 05 17:01:29 volumio volumio[1028]: info: CorePlayQueue::getTrack 7 Jul 05 17:01:29 volumio volumio[1028]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5074,"duration":333,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"You're Looking At Me","artist":"Diana Krall","album":"All For You","uri":"USB/F4F2-C02C/Music/Diana Krall/All For You/08 You're Looking At Me.mp3","trackType":"mp3"} Jul 05 17:01:29 volumio volumio[1028]: verbose: CURRENT POSITION 7 Jul 05 17:01:29 volumio volumio[1028]: info: CoreStateMachine::syncState stateService play Jul 05 17:01:29 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus play Jul 05 17:01:29 volumio volumio[1028]: info: Received an update from plugin. extracting info from payload Jul 05 17:01:29 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:29 volumio volumio[1028]: info: CorePlayQueue::getTrack 7 Jul 05 17:01:29 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 17:01:29 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:29 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:29 volumio volumio[1028]: info: CorePlayQueue::getTrack 7 Jul 05 17:01:29 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:29 volumio volumio[1028]: info: ControllerMpd::pushState Jul 05 17:01:29 volumio volumio[1028]: info: CoreCommandRouter::servicePushState Jul 05 17:01:29 volumio volumio[1028]: info: CorePlayQueue::getTrack 7 Jul 05 17:01:29 volumio volumio[1028]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5074,"duration":333,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"You're Looking At Me","artist":"Diana Krall","album":"All For You","uri":"USB/F4F2-C02C/Music/Diana Krall/All For You/08 You're Looking At Me.mp3","trackType":"mp3"} Jul 05 17:01:29 volumio volumio[1028]: verbose: CURRENT POSITION 7 Jul 05 17:01:29 volumio volumio[1028]: info: CoreStateMachine::syncState stateService play Jul 05 17:01:29 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus play Jul 05 17:01:29 volumio volumio[1028]: info: Received an update from plugin. extracting info from payload Jul 05 17:01:29 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:29 volumio volumio[1028]: info: CorePlayQueue::getTrack 7 Jul 05 17:01:29 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:29 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:29 volumio volumio[1028]: info: CorePlayQueue::getTrack 7 Jul 05 17:01:29 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:29 volumio volumio[1028]: info: ControllerMpd::pushState Jul 05 17:01:29 volumio volumio[1028]: info: CoreCommandRouter::servicePushState Jul 05 17:01:29 volumio volumio[1028]: info: CorePlayQueue::getTrack 7 Jul 05 17:01:29 volumio volumio[1028]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5074,"duration":333,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"You're Looking At Me","artist":"Diana Krall","album":"All For You","uri":"USB/F4F2-C02C/Music/Diana Krall/All For You/08 You're Looking At Me.mp3","trackType":"mp3"} Jul 05 17:01:29 volumio volumio[1028]: verbose: CURRENT POSITION 7 Jul 05 17:01:29 volumio volumio[1028]: info: CoreStateMachine::syncState stateService play Jul 05 17:01:29 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus play Jul 05 17:01:29 volumio volumio[1028]: info: Received an update from plugin. extracting info from payload Jul 05 17:01:29 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:29 volumio volumio[1028]: info: CorePlayQueue::getTrack 7 Jul 05 17:01:29 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:29 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:29 volumio volumio[1028]: info: CorePlayQueue::getTrack 7 Jul 05 17:01:29 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:29 volumio volumio[1028]: info: ------------------------------ 339ms Jul 05 17:01:29 volumio volumio[1028]: info: ------------------------------ 335ms Jul 05 17:01:29 volumio volumio[1028]: info: ------------------------------ 329ms Jul 05 17:01:29 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 05 17:01:29 volumio kernel: usb 1-1.2: new high-speed USB device number 7 using dwc_otg Jul 05 17:01:29 volumio volumio[1028]: info: MPD Database updated - AlbumList cache refreshed Jul 05 17:01:29 volumio volumio[1028]: info: Jul 05 17:01:29 volumio volumio[1028]: ---------------------------- MPD announces state update: database Jul 05 17:01:29 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:01:29 volumio volumio[1028]: info: Jul 05 17:01:29 volumio volumio[1028]: ---------------------------- MPD announces state update: update Jul 05 17:01:29 volumio volumio[1028]: info: ControllerMpd::getState Jul 05 17:01:29 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:01:29 volumio volumio[1028]: info: ControllerMpd::getState Jul 05 17:01:29 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:01:29 volumio volumio[1028]: info: MPD Database updated - AlbumList cache refreshed Jul 05 17:01:29 volumio volumio[1028]: info: Jul 05 17:01:29 volumio volumio[1028]: ---------------------------- MPD announces state update: database Jul 05 17:01:29 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:01:29 volumio volumio[1028]: info: Jul 05 17:01:29 volumio volumio[1028]: ---------------------------- MPD announces state update: update Jul 05 17:01:29 volumio volumio[1028]: info: ControllerMpd::getState Jul 05 17:01:29 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:01:29 volumio volumio[1028]: info: ControllerMpd::getState Jul 05 17:01:29 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:01:29 volumio volumio[1028]: info: MPD Database updated - AlbumList cache refreshed Jul 05 17:01:29 volumio volumio[1028]: info: Jul 05 17:01:29 volumio volumio[1028]: ---------------------------- MPD announces state update: database Jul 05 17:01:29 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:01:29 volumio volumio[1028]: info: Jul 05 17:01:29 volumio volumio[1028]: ---------------------------- MPD announces state update: update Jul 05 17:01:29 volumio volumio[1028]: info: ControllerMpd::getState Jul 05 17:01:29 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:01:29 volumio volumio[1028]: info: ControllerMpd::getState Jul 05 17:01:29 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:01:29 volumio volumio[1028]: info: sendMpdCommand status took 51 milliseconds Jul 05 17:01:29 volumio volumio[1028]: info: sendMpdCommand status took 48 milliseconds Jul 05 17:01:29 volumio volumio[1028]: info: sendMpdCommand status took 46 milliseconds Jul 05 17:01:29 volumio volumio[1028]: info: sendMpdCommand status took 40 milliseconds Jul 05 17:01:29 volumio volumio[1028]: info: sendMpdCommand status took 36 milliseconds Jul 05 17:01:29 volumio volumio[1028]: info: sendMpdCommand status took 36 milliseconds Jul 05 17:01:29 volumio volumio[1028]: verbose: ControllerMpd::parseState Jul 05 17:01:29 volumio kernel: usb 1-1.2: device descriptor read/64, error -71 Jul 05 17:01:29 volumio volumio[1028]: info: Command Router : Notfying DB Updatefalse Jul 05 17:01:29 volumio volumio[1028]: info: CoreCommandRouter::Close All Modals sent Jul 05 17:01:29 volumio volumio[1028]: verbose: ControllerMpd::parseState Jul 05 17:01:29 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 17:01:29 volumio volumio[1028]: verbose: ControllerMpd::parseState Jul 05 17:01:29 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 17:01:29 volumio volumio[1028]: verbose: ControllerMpd::parseState Jul 05 17:01:29 volumio volumio[1028]: info: Command Router : Notfying DB Updatefalse Jul 05 17:01:29 volumio volumio[1028]: info: CoreCommandRouter::Close All Modals sent Jul 05 17:01:29 volumio volumio[1028]: verbose: ControllerMpd::parseState Jul 05 17:01:29 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 17:01:29 volumio volumio[1028]: verbose: ControllerMpd::parseState Jul 05 17:01:29 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 17:01:29 volumio volumio[1028]: info: sendMpdCommand status took 158 milliseconds Jul 05 17:01:29 volumio volumio[1028]: info: sendMpdCommand status took 157 milliseconds Jul 05 17:01:29 volumio volumio[1028]: info: sendMpdCommand status took 157 milliseconds Jul 05 17:01:29 volumio volumio[1028]: info: sendMpdCommand playlistinfo took 72 milliseconds Jul 05 17:01:29 volumio volumio[1028]: info: sendMpdCommand playlistinfo took 71 milliseconds Jul 05 17:01:29 volumio volumio[1028]: info: sendMpdCommand playlistinfo took 34 milliseconds Jul 05 17:01:29 volumio volumio[1028]: info: sendMpdCommand playlistinfo took 32 milliseconds Jul 05 17:01:29 volumio volumio[1028]: verbose: ControllerMpd::parseState Jul 05 17:01:29 volumio volumio[1028]: info: Command Router : Notfying DB Updatefalse Jul 05 17:01:29 volumio volumio[1028]: info: CoreCommandRouter::Close All Modals sent Jul 05 17:01:29 volumio volumio[1028]: verbose: ControllerMpd::parseState Jul 05 17:01:29 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 17:01:29 volumio volumio[1028]: verbose: ControllerMpd::parseState Jul 05 17:01:29 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 17:01:29 volumio volumio[1028]: verbose: ControllerMpd::parseTrackInfo Jul 05 17:01:29 volumio volumio[1028]: verbose: ControllerMpd::parseTrackInfo Jul 05 17:01:29 volumio volumio[1028]: verbose: ControllerMpd::parseTrackInfo Jul 05 17:01:29 volumio volumio[1028]: verbose: ControllerMpd::parseTrackInfo Jul 05 17:01:29 volumio volumio[1028]: info: ControllerMpd::pushState Jul 05 17:01:29 volumio volumio[1028]: info: CoreCommandRouter::servicePushState Jul 05 17:01:29 volumio volumio[1028]: info: CorePlayQueue::getTrack 7 Jul 05 17:01:29 volumio volumio[1028]: verbose: STATE SERVICE {"status":"play","position":0,"seek":6068,"duration":333,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"You're Looking At Me","artist":"Diana Krall","album":"All For You","uri":"USB/F4F2-C02C/Music/Diana Krall/All For You/08 You're Looking At Me.mp3","trackType":"mp3"} Jul 05 17:01:29 volumio volumio[1028]: verbose: CURRENT POSITION 7 Jul 05 17:01:29 volumio volumio[1028]: info: CoreStateMachine::syncState stateService play Jul 05 17:01:29 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus play Jul 05 17:01:29 volumio volumio[1028]: info: Received an update from plugin. extracting info from payload Jul 05 17:01:29 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:29 volumio volumio[1028]: info: CorePlayQueue::getTrack 7 Jul 05 17:01:29 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 17:01:29 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:29 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:29 volumio volumio[1028]: info: CorePlayQueue::getTrack 7 Jul 05 17:01:29 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:29 volumio volumio[1028]: info: ControllerMpd::pushState Jul 05 17:01:29 volumio volumio[1028]: info: CoreCommandRouter::servicePushState Jul 05 17:01:29 volumio volumio[1028]: info: CorePlayQueue::getTrack 7 Jul 05 17:01:29 volumio volumio[1028]: verbose: STATE SERVICE {"status":"play","position":0,"seek":6068,"duration":333,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"You're Looking At Me","artist":"Diana Krall","album":"All For You","uri":"USB/F4F2-C02C/Music/Diana Krall/All For You/08 You're Looking At Me.mp3","trackType":"mp3"} Jul 05 17:01:29 volumio volumio[1028]: verbose: CURRENT POSITION 7 Jul 05 17:01:29 volumio volumio[1028]: info: CoreStateMachine::syncState stateService play Jul 05 17:01:29 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus play Jul 05 17:01:29 volumio volumio[1028]: info: Received an update from plugin. extracting info from payload Jul 05 17:01:29 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:29 volumio volumio[1028]: info: CorePlayQueue::getTrack 7 Jul 05 17:01:29 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:29 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:29 volumio volumio[1028]: info: CorePlayQueue::getTrack 7 Jul 05 17:01:29 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:29 volumio volumio[1028]: info: ControllerMpd::pushState Jul 05 17:01:29 volumio volumio[1028]: info: CoreCommandRouter::servicePushState Jul 05 17:01:29 volumio volumio[1028]: info: CorePlayQueue::getTrack 7 Jul 05 17:01:29 volumio volumio[1028]: verbose: STATE SERVICE {"status":"play","position":0,"seek":6194,"duration":333,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"You're Looking At Me","artist":"Diana Krall","album":"All For You","uri":"USB/F4F2-C02C/Music/Diana Krall/All For You/08 You're Looking At Me.mp3","trackType":"mp3"} Jul 05 17:01:29 volumio volumio[1028]: verbose: CURRENT POSITION 7 Jul 05 17:01:29 volumio volumio[1028]: info: CoreStateMachine::syncState stateService play Jul 05 17:01:29 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus play Jul 05 17:01:29 volumio volumio[1028]: info: Received an update from plugin. extracting info from payload Jul 05 17:01:29 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:29 volumio volumio[1028]: info: CorePlayQueue::getTrack 7 Jul 05 17:01:29 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 17:01:29 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:29 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:29 volumio volumio[1028]: info: CorePlayQueue::getTrack 7 Jul 05 17:01:29 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:29 volumio volumio[1028]: info: ControllerMpd::pushState Jul 05 17:01:29 volumio volumio[1028]: info: CoreCommandRouter::servicePushState Jul 05 17:01:29 volumio volumio[1028]: info: CorePlayQueue::getTrack 7 Jul 05 17:01:29 volumio volumio[1028]: verbose: STATE SERVICE {"status":"play","position":0,"seek":6194,"duration":333,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"You're Looking At Me","artist":"Diana Krall","album":"All For You","uri":"USB/F4F2-C02C/Music/Diana Krall/All For You/08 You're Looking At Me.mp3","trackType":"mp3"} Jul 05 17:01:29 volumio volumio[1028]: verbose: CURRENT POSITION 7 Jul 05 17:01:29 volumio volumio[1028]: info: CoreStateMachine::syncState stateService play Jul 05 17:01:29 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus play Jul 05 17:01:29 volumio volumio[1028]: info: Received an update from plugin. extracting info from payload Jul 05 17:01:29 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:29 volumio volumio[1028]: info: CorePlayQueue::getTrack 7 Jul 05 17:01:29 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:29 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:29 volumio volumio[1028]: info: CorePlayQueue::getTrack 7 Jul 05 17:01:29 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:29 volumio kernel: usb 1-1.2: device descriptor read/64, error -71 Jul 05 17:01:29 volumio volumio[1028]: info: ------------------------------ 384ms Jul 05 17:01:29 volumio volumio[1028]: info: ------------------------------ 384ms Jul 05 17:01:29 volumio volumio[1028]: info: ------------------------------ 373ms Jul 05 17:01:29 volumio volumio[1028]: info: ------------------------------ 372ms Jul 05 17:01:29 volumio volumio[1028]: info: sendMpdCommand playlistinfo took 191 milliseconds Jul 05 17:01:29 volumio volumio[1028]: verbose: ControllerMpd::parseTrackInfo Jul 05 17:01:29 volumio volumio[1028]: verbose: ControllerMpd::parseTrackInfo Jul 05 17:01:29 volumio volumio[1028]: info: ControllerMpd::pushState Jul 05 17:01:29 volumio volumio[1028]: info: CoreCommandRouter::servicePushState Jul 05 17:01:29 volumio volumio[1028]: info: CorePlayQueue::getTrack 7 Jul 05 17:01:29 volumio volumio[1028]: verbose: STATE SERVICE {"status":"play","position":0,"seek":6194,"duration":333,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"You're Looking At Me","artist":"Diana Krall","album":"All For You","uri":"USB/F4F2-C02C/Music/Diana Krall/All For You/08 You're Looking At Me.mp3","trackType":"mp3"} Jul 05 17:01:29 volumio volumio[1028]: verbose: CURRENT POSITION 7 Jul 05 17:01:29 volumio volumio[1028]: info: CoreStateMachine::syncState stateService play Jul 05 17:01:29 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus play Jul 05 17:01:29 volumio volumio[1028]: info: Received an update from plugin. extracting info from payload Jul 05 17:01:29 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:29 volumio volumio[1028]: info: CorePlayQueue::getTrack 7 Jul 05 17:01:29 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:29 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:29 volumio volumio[1028]: info: CorePlayQueue::getTrack 7 Jul 05 17:01:29 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:29 volumio volumio[1028]: info: ControllerMpd::pushState Jul 05 17:01:29 volumio volumio[1028]: info: CoreCommandRouter::servicePushState Jul 05 17:01:29 volumio volumio[1028]: info: CorePlayQueue::getTrack 7 Jul 05 17:01:29 volumio volumio[1028]: verbose: STATE SERVICE {"status":"play","position":0,"seek":6194,"duration":333,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"You're Looking At Me","artist":"Diana Krall","album":"All For You","uri":"USB/F4F2-C02C/Music/Diana Krall/All For You/08 You're Looking At Me.mp3","trackType":"mp3"} Jul 05 17:01:29 volumio volumio[1028]: verbose: CURRENT POSITION 7 Jul 05 17:01:29 volumio volumio[1028]: info: CoreStateMachine::syncState stateService play Jul 05 17:01:29 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus play Jul 05 17:01:29 volumio volumio[1028]: info: Received an update from plugin. extracting info from payload Jul 05 17:01:29 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:29 volumio volumio[1028]: info: CorePlayQueue::getTrack 7 Jul 05 17:01:29 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:29 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:29 volumio volumio[1028]: info: CorePlayQueue::getTrack 7 Jul 05 17:01:29 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:30 volumio volumio[1028]: info: ------------------------------ 472ms Jul 05 17:01:30 volumio volumio[1028]: info: ------------------------------ 473ms Jul 05 17:01:30 volumio kernel: usb 1-1.2: new high-speed USB device number 8 using dwc_otg Jul 05 17:01:30 volumio kernel: usb 1-1.2: device descriptor read/64, error -71 Jul 05 17:01:30 volumio kernel: usb 1-1.2: device descriptor read/64, error -71 Jul 05 17:01:30 volumio kernel: usb 1-1-port2: attempt power cycle Jul 05 17:01:31 volumio kernel: usb 1-1.2: new high-speed USB device number 9 using dwc_otg Jul 05 17:01:31 volumio kernel: usb 1-1.2: device descriptor read/8, error -71 Jul 05 17:01:31 volumio kernel: usb 1-1.2: device descriptor read/8, error -71 Jul 05 17:01:31 volumio kernel: usb 1-1.2: new high-speed USB device number 10 using dwc_otg Jul 05 17:01:31 volumio kernel: usb 1-1.2: device descriptor read/8, error -71 Jul 05 17:01:31 volumio kernel: usb 1-1.2: device descriptor read/8, error -71 Jul 05 17:01:32 volumio kernel: usb 1-1-port2: unable to enumerate USB device Jul 05 17:01:32 volumio volumio[1028]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","DEVLINKS":"/dev/disk/by-id/usb-USB_SanDisk_3.2Gen1_00017413082024094215-0:0-part1 /dev/disk/by-uuid/F4F2-C02C /dev/disk/by-path/platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0-part1","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"28","ID_BUS":"usb","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"F4F2-C02C","ID_FS_UUID_ENC":"F4F2-C02C","ID_FS_VERSION":"FAT32","ID_INSTANCE":"0:0","ID_MODEL":"SanDisk_3.2Gen1","ID_MODEL_ENC":"\\x20SanDisk\\x203.2Gen1","ID_MODEL_ID":"5583","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"32","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"120164320","ID_PART_ENTRY_TYPE":"0xc","ID_PART_TABLE_TYPE":"dos","ID_PATH":"platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-3f980000_usb-usb-0_1_2_1_0-scsi-0_0_0_0","ID_REVISION":"1.00","ID_SERIAL":"USB_SanDisk_3.2Gen1_00017413082024094215-0:0","ID_SERIAL_SHORT":"00017413082024094215","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:080662:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"USB","ID_VENDOR_ENC":"\\x20USB\\x20\\x20\\x20\\x20","ID_VENDOR_ID":"0781","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"1989","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"4795740"} Jul 05 17:01:32 volumio wpa_supplicant[898]: RRM: Ignoring radio measurement request: Not RRM network Jul 05 17:01:32 volumio sudo[1860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda1 Jul 05 17:01:32 volumio sudo[1860]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 17:01:32 volumio systemd[1]: media-F4F2\x2dC02C.mount: Succeeded. Jul 05 17:01:32 volumio sudo[1860]: pam_unix(sudo:session): session closed for user root Jul 05 17:01:33 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 05 17:01:36 volumio volumio[1028]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/F4F2-C02C Jul 05 17:01:36 volumio volumio[1028]: info: Jul 05 17:01:36 volumio volumio[1028]: ---------------------------- MPD announces state update: player Jul 05 17:01:36 volumio volumio[1028]: info: ControllerMpd::getState Jul 05 17:01:36 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:01:36 volumio volumio[1028]: info: Jul 05 17:01:36 volumio volumio[1028]: ---------------------------- MPD announces state update: player Jul 05 17:01:36 volumio volumio[1028]: info: ControllerMpd::getState Jul 05 17:01:36 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:01:36 volumio volumio[1028]: info: Jul 05 17:01:36 volumio volumio[1028]: ---------------------------- MPD announces state update: player Jul 05 17:01:36 volumio volumio[1028]: info: ControllerMpd::getState Jul 05 17:01:36 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:01:36 volumio volumio[1028]: info: sendMpdCommand status took 10 milliseconds Jul 05 17:01:36 volumio volumio[1028]: info: sendMpdCommand status took 7 milliseconds Jul 05 17:01:36 volumio volumio[1028]: info: sendMpdCommand status took 4 milliseconds Jul 05 17:01:36 volumio volumio[1028]: verbose: ControllerMpd::parseState Jul 05 17:01:36 volumio volumio[1028]: verbose: ControllerMpd::parseState Jul 05 17:01:36 volumio volumio[1028]: verbose: ControllerMpd::parseState Jul 05 17:01:36 volumio volumio[1028]: info: ControllerMpd::pushState Jul 05 17:01:36 volumio volumio[1028]: info: CoreCommandRouter::servicePushState Jul 05 17:01:36 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:36 volumio volumio[1028]: info: CorePlayQueue::getTrack 7 Jul 05 17:01:36 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 17:01:36 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:36 volumio volumio[1028]: info: CorePlayQueue::getTrack 7 Jul 05 17:01:36 volumio volumio[1028]: 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} Jul 05 17:01:36 volumio volumio[1028]: verbose: CURRENT POSITION 7 Jul 05 17:01:36 volumio volumio[1028]: info: CoreStateMachine::syncState stateService stop Jul 05 17:01:36 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus play Jul 05 17:01:36 volumio volumio[1028]: info: CoreStateMachine::play index undefined Jul 05 17:01:36 volumio volumio[1028]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 05 17:01:36 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:36 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:36 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 17:01:36 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:36 volumio volumio[1028]: info: ControllerMpd::pushState Jul 05 17:01:36 volumio volumio[1028]: info: CoreCommandRouter::servicePushState Jul 05 17:01:36 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:36 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:36 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:36 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:36 volumio volumio[1028]: 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} Jul 05 17:01:36 volumio volumio[1028]: verbose: CURRENT POSITION 3 Jul 05 17:01:36 volumio volumio[1028]: info: CoreStateMachine::syncState stateService stop Jul 05 17:01:36 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus stop Jul 05 17:01:36 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:36 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:36 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:36 volumio volumio[1028]: info: No code Jul 05 17:01:36 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:36 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:36 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:36 volumio volumio[1028]: info: ControllerMpd::pushState Jul 05 17:01:36 volumio volumio[1028]: info: CoreCommandRouter::servicePushState Jul 05 17:01:36 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:36 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:36 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:36 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:36 volumio volumio[1028]: 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} Jul 05 17:01:36 volumio volumio[1028]: verbose: CURRENT POSITION 3 Jul 05 17:01:36 volumio volumio[1028]: info: CoreStateMachine::syncState stateService stop Jul 05 17:01:36 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus stop Jul 05 17:01:36 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:36 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:36 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:36 volumio volumio[1028]: info: No code Jul 05 17:01:36 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:36 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:36 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:36 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:36 volumio volumio[1028]: info: CoreStateMachine::startPlaybackTimer Jul 05 17:01:36 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:36 volumio volumio[1028]: verbose: ControllerMpd::clearAddPlayTracks USB/F4F2-C02C/Music/Diana Krall/All For You/04 Frim Fram Sauce.mp3 Jul 05 17:01:36 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand stop Jul 05 17:01:36 volumio volumio[1028]: info: ------------------------------ 174ms Jul 05 17:01:36 volumio volumio[1028]: info: ------------------------------ 169ms Jul 05 17:01:36 volumio volumio[1028]: info: ------------------------------ 170ms Jul 05 17:01:36 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:36 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:36 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 17:01:36 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:37 volumio volumio[1028]: info: sendMpdCommand stop took 37 milliseconds Jul 05 17:01:37 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand clear Jul 05 17:01:37 volumio volumio[1028]: info: Jul 05 17:01:37 volumio volumio[1028]: ---------------------------- MPD announces system playlist update Jul 05 17:01:37 volumio volumio[1028]: info: Ignoring MPD Status Update Jul 05 17:01:37 volumio volumio[1028]: info: sendMpdCommand clear took 23 milliseconds Jul 05 17:01:37 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand add "USB/F4F2-C02C/Music/Diana Krall/All For You/04 Frim Fram Sauce.mp3" Jul 05 17:01:37 volumio volumio[1028]: info: Jul 05 17:01:37 volumio volumio[1028]: ---------------------------- MPD announces system playlist update Jul 05 17:01:37 volumio volumio[1028]: info: Ignoring MPD Status Update Jul 05 17:01:37 volumio volumio[1028]: info: Jul 05 17:01:37 volumio volumio[1028]: ---------------------------- MPD announces system playlist update Jul 05 17:01:37 volumio volumio[1028]: info: Ignoring MPD Status Update Jul 05 17:01:37 volumio volumio[1028]: error: updateQueue error: null Jul 05 17:01:37 volumio volumio[1028]: info: Jul 05 17:01:37 volumio volumio[1028]: ---------------------------- MPD announces system playlist update Jul 05 17:01:37 volumio volumio[1028]: info: Ignoring MPD Status Update Jul 05 17:01:37 volumio volumio[1028]: info: ------------------------------ 13ms Jul 05 17:01:37 volumio volumio[1028]: info: sendMpdCommand add "USB/F4F2-C02C/Music/Diana Krall/All For You/04 Frim Fram Sauce.mp3" took 11 milliseconds Jul 05 17:01:37 volumio volumio[1028]: info: ------------------------------ 10ms Jul 05 17:01:37 volumio volumio[1028]: info: ------------------------------ 8ms Jul 05 17:01:37 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand play Jul 05 17:01:37 volumio volumio[1028]: info: Jul 05 17:01:37 volumio volumio[1028]: ---------------------------- MPD announces system playlist update Jul 05 17:01:37 volumio volumio[1028]: info: Ignoring MPD Status Update Jul 05 17:01:37 volumio volumio[1028]: info: Jul 05 17:01:37 volumio volumio[1028]: ---------------------------- MPD announces system playlist update Jul 05 17:01:37 volumio volumio[1028]: info: Ignoring MPD Status Update Jul 05 17:01:37 volumio volumio[1028]: info: ------------------------------ 14ms Jul 05 17:01:37 volumio volumio[1028]: info: sendMpdCommand play took 10 milliseconds Jul 05 17:01:37 volumio volumio[1028]: info: ------------------------------ 9ms Jul 05 17:01:37 volumio volumio[1028]: info: ------------------------------ 7ms Jul 05 17:01:37 volumio volumio[1028]: info: Jul 05 17:01:37 volumio volumio[1028]: ---------------------------- MPD announces state update: player Jul 05 17:01:37 volumio volumio[1028]: info: ControllerMpd::getState Jul 05 17:01:37 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:01:37 volumio volumio[1028]: info: Jul 05 17:01:37 volumio volumio[1028]: ---------------------------- MPD announces state update: player Jul 05 17:01:37 volumio volumio[1028]: info: ControllerMpd::getState Jul 05 17:01:37 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:01:37 volumio volumio[1028]: info: Jul 05 17:01:37 volumio volumio[1028]: ---------------------------- MPD announces state update: player Jul 05 17:01:37 volumio volumio[1028]: info: ControllerMpd::getState Jul 05 17:01:37 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:01:37 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 05 17:01:37 volumio volumio[1028]: info: sendMpdCommand status took 19 milliseconds Jul 05 17:01:37 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 05 17:01:37 volumio volumio[1028]: info: sendMpdCommand status took 18 milliseconds Jul 05 17:01:37 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 05 17:01:37 volumio volumio[1028]: info: sendMpdCommand status took 18 milliseconds Jul 05 17:01:37 volumio volumio[1028]: verbose: ControllerMpd::parseState Jul 05 17:01:37 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 17:01:37 volumio volumio[1028]: verbose: ControllerMpd::parseState Jul 05 17:01:37 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 17:01:37 volumio volumio[1028]: verbose: ControllerMpd::parseState Jul 05 17:01:37 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 17:01:37 volumio volumio[1028]: info: sendMpdCommand clearerror took 29 milliseconds Jul 05 17:01:37 volumio volumio[1028]: info: sendMpdCommand clearerror took 27 milliseconds Jul 05 17:01:37 volumio volumio[1028]: info: sendMpdCommand clearerror took 25 milliseconds Jul 05 17:01:37 volumio volumio[1028]: info: sendMpdCommand playlistinfo took 12 milliseconds Jul 05 17:01:37 volumio volumio[1028]: info: sendMpdCommand playlistinfo took 8 milliseconds Jul 05 17:01:37 volumio volumio[1028]: verbose: ControllerMpd::parseTrackInfo Jul 05 17:01:37 volumio volumio[1028]: verbose: ControllerMpd::parseTrackInfo Jul 05 17:01:37 volumio volumio[1028]: info: ControllerMpd::pushState Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::servicePushState Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:37 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:37 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:37 volumio volumio[1028]: 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":"Frim Fram Sauce","artist":"Diana Krall","album":"All For You","uri":"USB/F4F2-C02C/Music/Diana Krall/All For You/04 Frim Fram Sauce.mp3","trackType":"mp3"} Jul 05 17:01:37 volumio volumio[1028]: verbose: CURRENT POSITION 3 Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::syncState stateService stop Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus stop Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:37 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:37 volumio volumio[1028]: info: No code Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:37 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:37 volumio volumio[1028]: info: ControllerMpd::pushState Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::servicePushState Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:37 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:37 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:37 volumio volumio[1028]: 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":"Frim Fram Sauce","artist":"Diana Krall","album":"All For You","uri":"USB/F4F2-C02C/Music/Diana Krall/All For You/04 Frim Fram Sauce.mp3","trackType":"mp3"} Jul 05 17:01:37 volumio volumio[1028]: verbose: CURRENT POSITION 3 Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::syncState stateService stop Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus stop Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:37 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:37 volumio volumio[1028]: info: No code Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:37 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:37 volumio volumio[1028]: info: ControllerMpd::pushState Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::servicePushState Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:37 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:37 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:37 volumio volumio[1028]: 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":"Frim Fram Sauce","artist":"Diana Krall","album":"All For You","uri":"USB/F4F2-C02C/Music/Diana Krall/All For You/04 Frim Fram Sauce.mp3","trackType":"mp3"} Jul 05 17:01:37 volumio volumio[1028]: verbose: CURRENT POSITION 3 Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::syncState stateService stop Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus stop Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:37 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:37 volumio volumio[1028]: info: No code Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:37 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:37 volumio volumio[1028]: info: ------------------------------ 229ms Jul 05 17:01:37 volumio volumio[1028]: info: ------------------------------ 228ms Jul 05 17:01:37 volumio volumio[1028]: info: ------------------------------ 227ms Jul 05 17:01:37 volumio volumio[1028]: info: Scanning removed location : "USB/F4F2-C02C" Jul 05 17:01:37 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:01:37 volumio volumio[1028]: info: Jul 05 17:01:37 volumio volumio[1028]: ---------------------------- MPD announces state update: update Jul 05 17:01:37 volumio volumio[1028]: info: ControllerMpd::getState Jul 05 17:01:37 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:01:37 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:01:37 volumio volumio[1028]: info: Jul 05 17:01:37 volumio volumio[1028]: ---------------------------- MPD announces state update: update Jul 05 17:01:37 volumio volumio[1028]: info: ControllerMpd::getState Jul 05 17:01:37 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:01:37 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:01:37 volumio volumio[1028]: info: Jul 05 17:01:37 volumio volumio[1028]: ---------------------------- MPD announces state update: update Jul 05 17:01:37 volumio volumio[1028]: info: ControllerMpd::getState Jul 05 17:01:37 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:01:37 volumio volumio[1028]: info: MPD Database updated - AlbumList cache refreshed Jul 05 17:01:37 volumio volumio[1028]: info: Jul 05 17:01:37 volumio volumio[1028]: ---------------------------- MPD announces state update: database Jul 05 17:01:37 volumio volumio[1028]: info: Jul 05 17:01:37 volumio volumio[1028]: ---------------------------- MPD announces system playlist update Jul 05 17:01:37 volumio volumio[1028]: info: Ignoring MPD Status Update Jul 05 17:01:37 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:01:37 volumio volumio[1028]: info: Jul 05 17:01:37 volumio volumio[1028]: ---------------------------- MPD announces state update: update Jul 05 17:01:37 volumio volumio[1028]: info: ControllerMpd::getState Jul 05 17:01:37 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:01:37 volumio volumio[1028]: info: ControllerMpd::getState Jul 05 17:01:37 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:01:37 volumio volumio[1028]: info: sendMpdCommand status took 20 milliseconds Jul 05 17:01:37 volumio volumio[1028]: info: sendMpdCommand status took 18 milliseconds Jul 05 17:01:37 volumio volumio[1028]: info: sendMpdCommand status took 18 milliseconds Jul 05 17:01:37 volumio volumio[1028]: info: sendMpdCommand status took 15 milliseconds Jul 05 17:01:37 volumio volumio[1028]: info: sendMpdCommand status took 15 milliseconds Jul 05 17:01:37 volumio volumio[1028]: info: sendMpdCommand status took 13 milliseconds Jul 05 17:01:37 volumio volumio[1028]: verbose: ControllerMpd::parseState Jul 05 17:01:37 volumio volumio[1028]: info: Command Router : Notfying DB Updatefalse Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::Close All Modals sent Jul 05 17:01:37 volumio volumio[1028]: verbose: ControllerMpd::parseState Jul 05 17:01:37 volumio volumio[1028]: verbose: ControllerMpd::parseState Jul 05 17:01:37 volumio volumio[1028]: info: Command Router : Notfying DB Updatefalse Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::Close All Modals sent Jul 05 17:01:37 volumio volumio[1028]: verbose: ControllerMpd::parseState Jul 05 17:01:37 volumio volumio[1028]: verbose: ControllerMpd::parseState Jul 05 17:01:37 volumio volumio[1028]: info: Command Router : Notfying DB Updatefalse Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::Close All Modals sent Jul 05 17:01:37 volumio volumio[1028]: verbose: ControllerMpd::parseState Jul 05 17:01:37 volumio volumio[1028]: info: ControllerMpd::pushState Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::servicePushState Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:37 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:37 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:37 volumio volumio[1028]: 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} Jul 05 17:01:37 volumio volumio[1028]: verbose: CURRENT POSITION 3 Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::syncState stateService stop Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus stop Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:37 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:37 volumio volumio[1028]: info: No code Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:37 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:37 volumio volumio[1028]: info: ControllerMpd::pushState Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::servicePushState Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:37 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:37 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:37 volumio volumio[1028]: 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} Jul 05 17:01:37 volumio volumio[1028]: verbose: CURRENT POSITION 3 Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::syncState stateService stop Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus stop Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:37 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:37 volumio volumio[1028]: info: No code Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:37 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:37 volumio volumio[1028]: info: ControllerMpd::pushState Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::servicePushState Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:37 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:37 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:37 volumio volumio[1028]: 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} Jul 05 17:01:37 volumio volumio[1028]: verbose: CURRENT POSITION 3 Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::syncState stateService stop Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus stop Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:37 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:37 volumio volumio[1028]: info: No code Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:37 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:37 volumio volumio[1028]: info: ------------------------------ 251ms Jul 05 17:01:37 volumio volumio[1028]: info: ------------------------------ 248ms Jul 05 17:01:37 volumio volumio[1028]: info: ------------------------------ 246ms Jul 05 17:01:37 volumio volumio[1028]: info: MPD Database updated - AlbumList cache refreshed Jul 05 17:01:37 volumio volumio[1028]: info: Jul 05 17:01:37 volumio volumio[1028]: ---------------------------- MPD announces state update: database Jul 05 17:01:37 volumio volumio[1028]: info: Jul 05 17:01:37 volumio volumio[1028]: ---------------------------- MPD announces system playlist update Jul 05 17:01:37 volumio volumio[1028]: info: Ignoring MPD Status Update Jul 05 17:01:37 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:01:37 volumio volumio[1028]: info: Jul 05 17:01:37 volumio volumio[1028]: ---------------------------- MPD announces state update: update Jul 05 17:01:37 volumio volumio[1028]: info: ControllerMpd::getState Jul 05 17:01:37 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:01:37 volumio volumio[1028]: info: ControllerMpd::getState Jul 05 17:01:37 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:01:37 volumio volumio[1028]: info: MPD Database updated - AlbumList cache refreshed Jul 05 17:01:37 volumio volumio[1028]: info: Jul 05 17:01:37 volumio volumio[1028]: ---------------------------- MPD announces state update: database Jul 05 17:01:37 volumio volumio[1028]: info: Jul 05 17:01:37 volumio volumio[1028]: ---------------------------- MPD announces system playlist update Jul 05 17:01:37 volumio volumio[1028]: info: Ignoring MPD Status Update Jul 05 17:01:37 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:01:37 volumio volumio[1028]: info: Jul 05 17:01:37 volumio volumio[1028]: ---------------------------- MPD announces state update: update Jul 05 17:01:37 volumio volumio[1028]: info: ControllerMpd::getState Jul 05 17:01:37 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:01:37 volumio volumio[1028]: info: ControllerMpd::getState Jul 05 17:01:37 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:01:37 volumio volumio[1028]: error: updateQueue error: null Jul 05 17:01:37 volumio volumio[1028]: error: updateQueue error: null Jul 05 17:01:37 volumio volumio[1028]: error: updateQueue error: null Jul 05 17:01:37 volumio volumio[1028]: info: sendMpdCommand status took 273 milliseconds Jul 05 17:01:37 volumio volumio[1028]: info: sendMpdCommand status took 271 milliseconds Jul 05 17:01:37 volumio volumio[1028]: info: ------------------------------ 275ms Jul 05 17:01:37 volumio volumio[1028]: info: sendMpdCommand status took 271 milliseconds Jul 05 17:01:37 volumio volumio[1028]: info: sendMpdCommand status took 28 milliseconds Jul 05 17:01:37 volumio volumio[1028]: info: sendMpdCommand status took 26 milliseconds Jul 05 17:01:37 volumio volumio[1028]: info: ------------------------------ 32ms Jul 05 17:01:37 volumio volumio[1028]: info: sendMpdCommand status took 25 milliseconds Jul 05 17:01:37 volumio volumio[1028]: info: sendMpdCommand status took 22 milliseconds Jul 05 17:01:37 volumio volumio[1028]: info: sendMpdCommand status took 20 milliseconds Jul 05 17:01:37 volumio volumio[1028]: info: ------------------------------ 24ms Jul 05 17:01:37 volumio volumio[1028]: info: sendMpdCommand status took 20 milliseconds Jul 05 17:01:37 volumio volumio[1028]: verbose: ControllerMpd::parseState Jul 05 17:01:37 volumio volumio[1028]: info: Command Router : Notfying DB Updatefalse Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::Close All Modals sent Jul 05 17:01:37 volumio volumio[1028]: verbose: ControllerMpd::parseState Jul 05 17:01:37 volumio volumio[1028]: verbose: ControllerMpd::parseState Jul 05 17:01:37 volumio volumio[1028]: verbose: ControllerMpd::parseState Jul 05 17:01:37 volumio volumio[1028]: info: Command Router : Notfying DB Updatefalse Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::Close All Modals sent Jul 05 17:01:37 volumio volumio[1028]: verbose: ControllerMpd::parseState Jul 05 17:01:37 volumio volumio[1028]: verbose: ControllerMpd::parseState Jul 05 17:01:37 volumio volumio[1028]: verbose: ControllerMpd::parseState Jul 05 17:01:37 volumio volumio[1028]: info: Command Router : Notfying DB Updatefalse Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::Close All Modals sent Jul 05 17:01:37 volumio volumio[1028]: verbose: ControllerMpd::parseState Jul 05 17:01:37 volumio volumio[1028]: verbose: ControllerMpd::parseState Jul 05 17:01:37 volumio volumio[1028]: info: ControllerMpd::pushState Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::servicePushState Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:37 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:37 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:37 volumio volumio[1028]: 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} Jul 05 17:01:37 volumio volumio[1028]: verbose: CURRENT POSITION 3 Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::syncState stateService stop Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus stop Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:37 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:37 volumio volumio[1028]: info: No code Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:37 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:37 volumio volumio[1028]: info: ControllerMpd::pushState Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::servicePushState Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:37 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:37 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:37 volumio volumio[1028]: 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} Jul 05 17:01:37 volumio volumio[1028]: verbose: CURRENT POSITION 3 Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::syncState stateService stop Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus stop Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:37 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:37 volumio volumio[1028]: info: No code Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:37 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:37 volumio volumio[1028]: info: ControllerMpd::pushState Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::servicePushState Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:37 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:37 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:37 volumio volumio[1028]: 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} Jul 05 17:01:37 volumio volumio[1028]: verbose: CURRENT POSITION 3 Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::syncState stateService stop Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus stop Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:37 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:37 volumio volumio[1028]: info: No code Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:37 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:37 volumio volumio[1028]: info: ControllerMpd::pushState Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::servicePushState Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:37 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:37 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:37 volumio volumio[1028]: 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} Jul 05 17:01:37 volumio volumio[1028]: verbose: CURRENT POSITION 3 Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::syncState stateService stop Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus stop Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:37 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:37 volumio volumio[1028]: info: No code Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:37 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:37 volumio volumio[1028]: info: ControllerMpd::pushState Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::servicePushState Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:37 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:37 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:37 volumio volumio[1028]: 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} Jul 05 17:01:37 volumio volumio[1028]: verbose: CURRENT POSITION 3 Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::syncState stateService stop Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus stop Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:37 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:37 volumio volumio[1028]: info: No code Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:37 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:37 volumio volumio[1028]: info: ControllerMpd::pushState Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::servicePushState Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:37 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:37 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:37 volumio volumio[1028]: 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} Jul 05 17:01:37 volumio volumio[1028]: verbose: CURRENT POSITION 3 Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::syncState stateService stop Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus stop Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:37 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:37 volumio volumio[1028]: info: No code Jul 05 17:01:37 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:37 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:37 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:38 volumio volumio[1028]: info: ------------------------------ 734ms Jul 05 17:01:38 volumio volumio[1028]: info: ------------------------------ 734ms Jul 05 17:01:38 volumio volumio[1028]: info: ------------------------------ 493ms Jul 05 17:01:38 volumio volumio[1028]: info: ------------------------------ 491ms Jul 05 17:01:38 volumio volumio[1028]: info: ------------------------------ 486ms Jul 05 17:01:38 volumio volumio[1028]: info: ------------------------------ 486ms Jul 05 17:01:43 volumio kernel: hwmon hwmon1: Voltage normalised Jul 05 17:01:45 volumio kernel: hwmon hwmon1: Undervoltage detected! Jul 05 17:01:46 volumio volumio[1028]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 17:01:46 volumio volumio[1028]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 17:01:46 volumio volumio[1028]: info: Retrieving Cloud Streaming UI Jul 05 17:01:46 volumio volumio[1028]: info: Getting Tidal Cloud Configuration Jul 05 17:01:46 volumio volumio[1028]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 17:01:46 volumio volumio[1028]: info: Getting Qobuz Cloud Configuration Jul 05 17:01:46 volumio volumio[1028]: info: Asking plugin for UI Config Jul 05 17:01:46 volumio volumio[1028]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 17:01:46 volumio volumio[1028]: info: Getting Spotify Cloud Configuration Jul 05 17:01:46 volumio volumio[1028]: info: Asking plugin for UI Config Jul 05 17:01:46 volumio volumio[1028]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 17:01:46 volumio volumio[1028]: info: Saving Spotify Acccount Jul 05 17:01:46 volumio volumio[1028]: info: Got Tidal Cloud Configuration Jul 05 17:01:46 volumio volumio[1028]: info: Got it Jul 05 17:01:46 volumio volumio[1028]: info: Got it Jul 05 17:01:46 volumio volumio[1028]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jul 05 17:01:46 volumio volumio[1028]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 17:01:46 volumio volumio[1028]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jul 05 17:01:46 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 05 17:01:46 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 05 17:01:46 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 05 17:01:46 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 05 17:01:46 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 17:01:46 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 17:01:46 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 17:01:46 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 17:01:46 volumio volumio[1028]: info: CoreCommandRouter::volumioGetBrowseSources Jul 05 17:01:46 volumio volumio[1028]: info: CoreCommandRouter::volumioGetBrowseSources Jul 05 17:01:46 volumio volumio[1028]: info: CoreCommandRouter::volumioGetBrowseSources Jul 05 17:01:46 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 05 17:01:46 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 05 17:01:50 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 05 17:01:50 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jul 05 17:01:50 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand rescan Jul 05 17:01:50 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:01:50 volumio volumio[1028]: info: Jul 05 17:01:50 volumio volumio[1028]: ---------------------------- MPD announces state update: update Jul 05 17:01:50 volumio volumio[1028]: info: sendMpdCommand rescan took 3 milliseconds Jul 05 17:01:50 volumio volumio[1028]: info: ControllerMpd::getState Jul 05 17:01:50 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:01:50 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:01:50 volumio volumio[1028]: info: Jul 05 17:01:50 volumio volumio[1028]: ---------------------------- MPD announces state update: update Jul 05 17:01:50 volumio volumio[1028]: info: ControllerMpd::getState Jul 05 17:01:50 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:01:50 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:01:50 volumio volumio[1028]: info: Jul 05 17:01:50 volumio volumio[1028]: ---------------------------- MPD announces state update: update Jul 05 17:01:50 volumio volumio[1028]: info: ControllerMpd::getState Jul 05 17:01:50 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:01:50 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:01:50 volumio volumio[1028]: info: Jul 05 17:01:50 volumio volumio[1028]: ---------------------------- MPD announces state update: update Jul 05 17:01:50 volumio volumio[1028]: info: ControllerMpd::getState Jul 05 17:01:50 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:01:50 volumio volumio[1028]: info: sendMpdCommand status took 9 milliseconds Jul 05 17:01:50 volumio volumio[1028]: info: sendMpdCommand status took 7 milliseconds Jul 05 17:01:50 volumio volumio[1028]: info: sendMpdCommand status took 6 milliseconds Jul 05 17:01:50 volumio volumio[1028]: info: sendMpdCommand status took 6 milliseconds Jul 05 17:01:50 volumio volumio[1028]: info: sendMpdCommand status took 5 milliseconds Jul 05 17:01:50 volumio volumio[1028]: info: sendMpdCommand status took 4 milliseconds Jul 05 17:01:50 volumio volumio[1028]: verbose: ControllerMpd::parseState Jul 05 17:01:50 volumio volumio[1028]: info: Command Router : Notfying DB Updatefalse Jul 05 17:01:50 volumio volumio[1028]: info: CoreCommandRouter::Close All Modals sent Jul 05 17:01:50 volumio volumio[1028]: verbose: ControllerMpd::parseState Jul 05 17:01:50 volumio volumio[1028]: verbose: ControllerMpd::parseState Jul 05 17:01:51 volumio volumio[1028]: info: Command Router : Notfying DB Updatefalse Jul 05 17:01:51 volumio volumio[1028]: info: CoreCommandRouter::Close All Modals sent Jul 05 17:01:51 volumio volumio[1028]: verbose: ControllerMpd::parseState Jul 05 17:01:51 volumio volumio[1028]: verbose: ControllerMpd::parseState Jul 05 17:01:51 volumio volumio[1028]: info: Command Router : Notfying DB Updatefalse Jul 05 17:01:51 volumio volumio[1028]: info: CoreCommandRouter::Close All Modals sent Jul 05 17:01:51 volumio volumio[1028]: verbose: ControllerMpd::parseState Jul 05 17:01:51 volumio volumio[1028]: info: ControllerMpd::pushState Jul 05 17:01:51 volumio volumio[1028]: info: CoreCommandRouter::servicePushState Jul 05 17:01:51 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:51 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:51 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 17:01:51 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:51 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:51 volumio volumio[1028]: 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} Jul 05 17:01:51 volumio volumio[1028]: verbose: CURRENT POSITION 3 Jul 05 17:01:51 volumio volumio[1028]: info: CoreStateMachine::syncState stateService stop Jul 05 17:01:51 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus stop Jul 05 17:01:51 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:51 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:51 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:51 volumio volumio[1028]: info: No code Jul 05 17:01:51 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:51 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:51 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:51 volumio volumio[1028]: info: ControllerMpd::pushState Jul 05 17:01:51 volumio volumio[1028]: info: CoreCommandRouter::servicePushState Jul 05 17:01:51 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:51 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:51 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:51 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:51 volumio volumio[1028]: 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} Jul 05 17:01:51 volumio volumio[1028]: verbose: CURRENT POSITION 3 Jul 05 17:01:51 volumio volumio[1028]: info: CoreStateMachine::syncState stateService stop Jul 05 17:01:51 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus stop Jul 05 17:01:51 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:51 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:51 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:51 volumio volumio[1028]: info: No code Jul 05 17:01:51 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:51 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:51 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:51 volumio volumio[1028]: info: ControllerMpd::pushState Jul 05 17:01:51 volumio volumio[1028]: info: CoreCommandRouter::servicePushState Jul 05 17:01:51 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:51 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:51 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:51 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:51 volumio volumio[1028]: 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} Jul 05 17:01:51 volumio volumio[1028]: verbose: CURRENT POSITION 3 Jul 05 17:01:51 volumio volumio[1028]: info: CoreStateMachine::syncState stateService stop Jul 05 17:01:51 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus stop Jul 05 17:01:51 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:51 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:51 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:51 volumio volumio[1028]: info: No code Jul 05 17:01:51 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:51 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:51 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:51 volumio volumio[1028]: info: ------------------------------ 260ms Jul 05 17:01:51 volumio volumio[1028]: info: ------------------------------ 259ms Jul 05 17:01:51 volumio volumio[1028]: info: ------------------------------ 259ms Jul 05 17:01:51 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:01:51 volumio volumio[1028]: info: Jul 05 17:01:51 volumio volumio[1028]: ---------------------------- MPD announces state update: update Jul 05 17:01:51 volumio volumio[1028]: info: ControllerMpd::getState Jul 05 17:01:51 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:01:51 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:01:51 volumio volumio[1028]: info: Jul 05 17:01:51 volumio volumio[1028]: ---------------------------- MPD announces state update: update Jul 05 17:01:51 volumio volumio[1028]: info: ControllerMpd::getState Jul 05 17:01:51 volumio volumio[1028]: verbose: ControllerMpd::sendMpdCommand status Jul 05 17:01:51 volumio volumio[1028]: info: sendMpdCommand status took 270 milliseconds Jul 05 17:01:51 volumio volumio[1028]: info: sendMpdCommand status took 269 milliseconds Jul 05 17:01:51 volumio volumio[1028]: info: sendMpdCommand status took 12 milliseconds Jul 05 17:01:51 volumio volumio[1028]: info: sendMpdCommand status took 10 milliseconds Jul 05 17:01:51 volumio volumio[1028]: info: sendMpdCommand status took 9 milliseconds Jul 05 17:01:51 volumio volumio[1028]: info: sendMpdCommand status took 7 milliseconds Jul 05 17:01:51 volumio volumio[1028]: verbose: ControllerMpd::parseState Jul 05 17:01:51 volumio volumio[1028]: info: Command Router : Notfying DB Updatefalse Jul 05 17:01:51 volumio volumio[1028]: info: CoreCommandRouter::Close All Modals sent Jul 05 17:01:51 volumio volumio[1028]: verbose: ControllerMpd::parseState Jul 05 17:01:51 volumio volumio[1028]: verbose: ControllerMpd::parseState Jul 05 17:01:51 volumio volumio[1028]: info: Command Router : Notfying DB Updatefalse Jul 05 17:01:51 volumio volumio[1028]: info: CoreCommandRouter::Close All Modals sent Jul 05 17:01:51 volumio volumio[1028]: verbose: ControllerMpd::parseState Jul 05 17:01:51 volumio volumio[1028]: verbose: ControllerMpd::parseState Jul 05 17:01:51 volumio volumio[1028]: info: Command Router : Notfying DB Updatefalse Jul 05 17:01:51 volumio volumio[1028]: info: CoreCommandRouter::Close All Modals sent Jul 05 17:01:51 volumio volumio[1028]: verbose: ControllerMpd::parseState Jul 05 17:01:51 volumio volumio[1028]: info: ControllerMpd::pushState Jul 05 17:01:51 volumio volumio[1028]: info: CoreCommandRouter::servicePushState Jul 05 17:01:51 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:51 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:51 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 17:01:51 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:51 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:51 volumio volumio[1028]: 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} Jul 05 17:01:51 volumio volumio[1028]: verbose: CURRENT POSITION 3 Jul 05 17:01:51 volumio volumio[1028]: info: CoreStateMachine::syncState stateService stop Jul 05 17:01:51 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus stop Jul 05 17:01:51 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:51 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:51 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:51 volumio volumio[1028]: info: No code Jul 05 17:01:51 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:51 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:51 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:51 volumio volumio[1028]: info: ControllerMpd::pushState Jul 05 17:01:51 volumio volumio[1028]: info: CoreCommandRouter::servicePushState Jul 05 17:01:51 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:51 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:51 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:51 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:51 volumio volumio[1028]: 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} Jul 05 17:01:51 volumio volumio[1028]: verbose: CURRENT POSITION 3 Jul 05 17:01:51 volumio volumio[1028]: info: CoreStateMachine::syncState stateService stop Jul 05 17:01:51 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus stop Jul 05 17:01:51 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:51 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:51 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:51 volumio volumio[1028]: info: No code Jul 05 17:01:51 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:51 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:51 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:51 volumio volumio[1028]: info: ControllerMpd::pushState Jul 05 17:01:51 volumio volumio[1028]: info: CoreCommandRouter::servicePushState Jul 05 17:01:51 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:51 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:51 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:51 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:51 volumio volumio[1028]: 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} Jul 05 17:01:51 volumio volumio[1028]: verbose: CURRENT POSITION 3 Jul 05 17:01:51 volumio volumio[1028]: info: CoreStateMachine::syncState stateService stop Jul 05 17:01:51 volumio volumio[1028]: info: CoreStateMachine::syncState currentStatus stop Jul 05 17:01:51 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:51 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:51 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:51 volumio volumio[1028]: info: No code Jul 05 17:01:51 volumio volumio[1028]: info: CoreStateMachine::pushState Jul 05 17:01:51 volumio volumio[1028]: info: CorePlayQueue::getTrack 3 Jul 05 17:01:51 volumio volumio[1028]: info: CoreCommandRouter::volumioPushState Jul 05 17:01:51 volumio volumio[1028]: info: ------------------------------ 564ms Jul 05 17:01:51 volumio volumio[1028]: info: ------------------------------ 306ms Jul 05 17:01:51 volumio volumio[1028]: info: ------------------------------ 304ms Jul 05 17:01:54 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 05 17:01:58 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 05 17:02:00 volumio kernel: hwmon hwmon1: Voltage normalised Jul 05 17:02:02 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 05 17:02:06 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 05 17:02:08 volumio kernel: hwmon hwmon1: Undervoltage detected! Jul 05 17:02:10 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 05 17:02:16 volumio volumio[1028]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 17:02:16 volumio volumio[1028]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 17:02:16 volumio volumio[1028]: info: Retrieving Cloud Streaming UI Jul 05 17:02:16 volumio volumio[1028]: info: Getting Tidal Cloud Configuration Jul 05 17:02:16 volumio volumio[1028]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 17:02:16 volumio volumio[1028]: info: Getting Qobuz Cloud Configuration Jul 05 17:02:16 volumio volumio[1028]: info: Asking plugin for UI Config Jul 05 17:02:16 volumio volumio[1028]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 17:02:16 volumio volumio[1028]: info: Getting Spotify Cloud Configuration Jul 05 17:02:16 volumio volumio[1028]: info: Asking plugin for UI Config Jul 05 17:02:16 volumio volumio[1028]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 17:02:16 volumio volumio[1028]: info: Saving Spotify Acccount Jul 05 17:02:16 volumio volumio[1028]: info: Got Tidal Cloud Configuration Jul 05 17:02:16 volumio volumio[1028]: info: Got it Jul 05 17:02:16 volumio volumio[1028]: info: Got it Jul 05 17:02:16 volumio volumio[1028]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jul 05 17:02:16 volumio volumio[1028]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 17:02:16 volumio volumio[1028]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jul 05 17:02:16 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 05 17:02:16 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 05 17:02:16 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 05 17:02:16 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 05 17:02:16 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 17:02:16 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 17:02:16 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 17:02:16 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 17:02:16 volumio volumio[1028]: info: CoreCommandRouter::volumioGetBrowseSources Jul 05 17:02:16 volumio volumio[1028]: info: CoreCommandRouter::volumioGetBrowseSources Jul 05 17:02:16 volumio volumio[1028]: info: CoreCommandRouter::volumioGetBrowseSources Jul 05 17:02:16 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 05 17:02:17 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 05 17:02:27 volumio kernel: hwmon hwmon1: Voltage normalised Jul 05 17:02:29 volumio kernel: hwmon hwmon1: Undervoltage detected! Jul 05 17:02:31 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 05 17:02:31 volumio volumio[1028]: info: CURURI: music-library Jul 05 17:02:31 volumio volumio[1028]: info: Preload queue cleared Jul 05 17:02:33 volumio wpa_supplicant[898]: RRM: Ignoring radio measurement request: Not RRM network Jul 05 17:02:34 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 05 17:02:34 volumio volumio[1028]: info: CURURI: music-library/INTERNAL Jul 05 17:02:34 volumio volumio[1028]: error: Failed LSINFO: null Jul 05 17:02:34 volumio volumio[1028]: info: Preload queue cleared Jul 05 17:02:37 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 05 17:02:37 volumio volumio[1028]: info: CURURI: music-library Jul 05 17:02:37 volumio volumio[1028]: info: Preload queue cleared Jul 05 17:02:38 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 05 17:02:38 volumio volumio[1028]: info: CURURI: music-library/USB Jul 05 17:02:38 volumio volumio[1028]: info: Preload queue cleared Jul 05 17:02:40 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 05 17:02:40 volumio volumio[1028]: info: CURURI: music-library/USB/Y1 Jul 05 17:02:40 volumio volumio[1028]: error: Failed LSINFO: null Jul 05 17:02:40 volumio volumio[1028]: info: Preload queue cleared Jul 05 17:02:43 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 05 17:02:43 volumio volumio[1028]: info: CURURI: music-library Jul 05 17:02:43 volumio volumio[1028]: info: Preload queue cleared Jul 05 17:02:45 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 05 17:02:45 volumio volumio[1028]: info: CURURI: music-library/USB Jul 05 17:02:45 volumio volumio[1028]: info: Preload queue cleared Jul 05 17:02:45 volumio volumio[1028]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 05 17:02:45 volumio volumio[1028]: info: CURURI: music-library/USB/F4F2-C02C Jul 05 17:02:45 volumio volumio[1028]: error: Failed LSINFO: null Jul 05 17:02:46 volumio volumio[1028]: info: Preload queue cleared Jul 05 17:02:53 volumio volumio[1028]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 17:02:53 volumio volumio[1028]: Error: connect ETIMEDOUT 128.242.240.20:443 Jul 05 17:02:53 volumio volumio[1028]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Jul 05 17:02:53 volumio volumio[1028]: errno: -110, Jul 05 17:02:53 volumio volumio[1028]: code: 'ETIMEDOUT', Jul 05 17:02:53 volumio volumio[1028]: syscall: 'connect', Jul 05 17:02:53 volumio volumio[1028]: address: '128.242.240.20', Jul 05 17:02:53 volumio volumio[1028]: port: 443 Jul 05 17:02:53 volumio volumio[1028]: } Jul 05 17:02:53 volumio volumio[1028]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 17:02:54 volumio sudo[2119]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-05 17:01 Jul 05 17:02:54 volumio sudo[2119]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 04:07:25 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3b9115b1943141a9d7186aa434f8f3c2"