Dec 22 17:22:48 volpb kernel: usb 2-2: new SuperSpeed USB device number 2 using xhci_hcd Dec 22 17:22:48 volpb kernel: usb 2-2: New USB device found, idVendor=0781, idProduct=5591, bcdDevice= 1.00 Dec 22 17:22:48 volpb kernel: usb 2-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 22 17:22:48 volpb kernel: usb 2-2: Product: SanDisk 3.2Gen1 Dec 22 17:22:48 volpb kernel: usb 2-2: Manufacturer: USB Dec 22 17:22:48 volpb kernel: usb 2-2: SerialNumber: 0101ee0d13b6ef1311880770c2eae4c60465e4f17cff223ace162dd7d03dc44218ab0000000000000000000002b30613008e58009155810741b29dcb Dec 22 17:22:48 volpb kernel: usb-storage 2-2:1.0: USB Mass Storage device detected Dec 22 17:22:48 volpb kernel: scsi host1: usb-storage 2-2:1.0 Dec 22 17:22:48 volpb systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 22 17:22:48 volpb systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 22 17:22:48 volpb systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 22 17:22:49 volpb kernel: scsi 1:0:0:0: Direct-Access USB SanDisk 3.2Gen1 1.00 PQ: 0 ANSI: 6 Dec 22 17:22:49 volpb kernel: sd 1:0:0:0: Attached scsi generic sg0 type 0 Dec 22 17:22:49 volpb kernel: sd 1:0:0:0: [sdb] 240353280 512-byte logical blocks: (123 GB/115 GiB) Dec 22 17:22:49 volpb kernel: sd 1:0:0:0: [sdb] Write Protect is off Dec 22 17:22:49 volpb kernel: sd 1:0:0:0: [sdb] Mode Sense: 43 00 00 00 Dec 22 17:22:49 volpb kernel: sd 1:0:0:0: [sdb] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA Dec 22 17:22:49 volpb kernel: sdb: sdb1 Dec 22 17:22:49 volpb kernel: sd 1:0:0:0: [sdb] Attached SCSI removable disk Dec 22 17:22:49 volpb volumio[1134]: info: Mounting Device D227-1B22 Dec 22 17:22:49 volpb sudo[4709]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/D227-1B22 -o noatime Dec 22 17:22:49 volpb sudo[4709]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 17:22:49 volpb sudo[4709]: pam_unix(sudo:session): session closed for user root Dec 22 17:22:49 volpb volumio[1134]: info: Scanning new location : "USB/D227-1B22" Dec 22 17:22:49 volpb volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Dec 22 17:22:49 volpb volumio[1134]: info: Dec 22 17:22:49 volpb volumio[1134]: ---------------------------- MPD announces state update: update Dec 22 17:22:49 volpb volumio[1134]: info: ControllerMpd::getState Dec 22 17:22:49 volpb volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Dec 22 17:22:49 volpb volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Dec 22 17:22:49 volpb volumio[1134]: info: Dec 22 17:22:49 volpb volumio[1134]: ---------------------------- MPD announces state update: update Dec 22 17:22:49 volpb volumio[1134]: info: ControllerMpd::getState Dec 22 17:22:49 volpb volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Dec 22 17:22:49 volpb volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Dec 22 17:22:49 volpb volumio[1134]: info: Dec 22 17:22:49 volpb volumio[1134]: ---------------------------- MPD announces state update: update Dec 22 17:22:49 volpb volumio[1134]: info: ControllerMpd::getState Dec 22 17:22:49 volpb volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Dec 22 17:22:49 volpb volumio[1134]: info: sendMpdCommand status took 6 milliseconds Dec 22 17:22:49 volpb volumio[1134]: info: sendMpdCommand status took 4 milliseconds Dec 22 17:22:49 volpb volumio[1134]: info: sendMpdCommand status took 4 milliseconds Dec 22 17:22:49 volpb volumio[1134]: info: sendMpdCommand status took 3 milliseconds Dec 22 17:22:49 volpb volumio[1134]: verbose: ControllerMpd::parseState Dec 22 17:22:49 volpb volumio[1134]: info: Command Router : Notfying DB Updatetrue Dec 22 17:22:49 volpb volumio[1134]: info: CoreCommandRouter::Close All Modals sent Dec 22 17:22:49 volpb volumio[1134]: verbose: ControllerMpd::parseState Dec 22 17:22:49 volpb volumio[1134]: verbose: ControllerMpd::parseState Dec 22 17:22:49 volpb volumio[1134]: info: Command Router : Notfying DB Updatetrue Dec 22 17:22:49 volpb volumio[1134]: info: CoreCommandRouter::Close All Modals sent Dec 22 17:22:49 volpb volumio[1134]: verbose: ControllerMpd::parseState Dec 22 17:22:49 volpb volumio[1134]: info: ControllerMpd::pushState Dec 22 17:22:49 volpb volumio[1134]: info: CoreCommandRouter::servicePushState Dec 22 17:22:49 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:22:49 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:22:49 volpb volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 17:22:49 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:22:49 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:22:49 volpb volumio[1134]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 22 17:22:49 volpb volumio[1134]: verbose: CURRENT POSITION 4 Dec 22 17:22:49 volpb volumio[1134]: info: CoreStateMachine::syncState stateService stop Dec 22 17:22:49 volpb volumio[1134]: info: CoreStateMachine::syncState currentStatus stop Dec 22 17:22:49 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:22:49 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:22:49 volpb volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 17:22:49 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:22:49 volpb volumio[1134]: info: No code Dec 22 17:22:49 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:22:49 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:22:49 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:22:49 volpb volumio[1134]: info: ControllerMpd::pushState Dec 22 17:22:49 volpb volumio[1134]: info: CoreCommandRouter::servicePushState Dec 22 17:22:49 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:22:49 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:22:49 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:22:49 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:22:49 volpb volumio[1134]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 22 17:22:49 volpb volumio[1134]: verbose: CURRENT POSITION 4 Dec 22 17:22:49 volpb volumio[1134]: info: CoreStateMachine::syncState stateService stop Dec 22 17:22:49 volpb volumio[1134]: info: CoreStateMachine::syncState currentStatus stop Dec 22 17:22:49 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:22:49 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:22:49 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:22:49 volpb volumio[1134]: info: No code Dec 22 17:22:49 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:22:49 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:22:49 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:22:49 volpb volumio[1134]: info: ------------------------------ 112ms Dec 22 17:22:49 volpb volumio[1134]: info: ------------------------------ 112ms Dec 22 17:22:49 volpb volumio[1134]: info: sendMpdCommand status took 111 milliseconds Dec 22 17:22:49 volpb volumio[1134]: info: sendMpdCommand status took 110 milliseconds Dec 22 17:22:49 volpb volumio[1134]: verbose: ControllerMpd::parseState Dec 22 17:22:49 volpb volumio[1134]: info: Command Router : Notfying DB Updatetrue Dec 22 17:22:49 volpb volumio[1134]: info: CoreCommandRouter::Close All Modals sent Dec 22 17:22:49 volpb volumio[1134]: verbose: ControllerMpd::parseState Dec 22 17:22:49 volpb volumio[1134]: info: ControllerMpd::pushState Dec 22 17:22:49 volpb volumio[1134]: info: CoreCommandRouter::servicePushState Dec 22 17:22:49 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:22:49 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:22:49 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:22:49 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:22:49 volpb volumio[1134]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 22 17:22:49 volpb volumio[1134]: verbose: CURRENT POSITION 4 Dec 22 17:22:49 volpb volumio[1134]: info: CoreStateMachine::syncState stateService stop Dec 22 17:22:49 volpb volumio[1134]: info: CoreStateMachine::syncState currentStatus stop Dec 22 17:22:49 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:22:49 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:22:49 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:22:49 volpb volumio[1134]: info: No code Dec 22 17:22:49 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:22:49 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:22:49 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:22:49 volpb volumio[1134]: info: ------------------------------ 144ms Dec 22 17:22:50 volpb volumio[1134]: info: MPD Database updated - AlbumList cache refreshed Dec 22 17:22:50 volpb volumio[1134]: info: Dec 22 17:22:50 volpb volumio[1134]: ---------------------------- MPD announces state update: database Dec 22 17:22:50 volpb volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Dec 22 17:22:50 volpb volumio[1134]: info: Dec 22 17:22:50 volpb volumio[1134]: ---------------------------- MPD announces state update: update Dec 22 17:22:50 volpb volumio[1134]: info: ControllerMpd::getState Dec 22 17:22:50 volpb volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Dec 22 17:22:50 volpb volumio[1134]: info: ControllerMpd::getState Dec 22 17:22:50 volpb volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Dec 22 17:22:50 volpb volumio[1134]: info: MPD Database updated - AlbumList cache refreshed Dec 22 17:22:50 volpb volumio[1134]: info: Dec 22 17:22:50 volpb volumio[1134]: ---------------------------- MPD announces state update: database Dec 22 17:22:50 volpb volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Dec 22 17:22:50 volpb volumio[1134]: info: Dec 22 17:22:50 volpb volumio[1134]: ---------------------------- MPD announces state update: update Dec 22 17:22:50 volpb volumio[1134]: info: ControllerMpd::getState Dec 22 17:22:50 volpb volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Dec 22 17:22:50 volpb volumio[1134]: info: ControllerMpd::getState Dec 22 17:22:50 volpb volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Dec 22 17:22:50 volpb volumio[1134]: info: MPD Database updated - AlbumList cache refreshed Dec 22 17:22:50 volpb volumio[1134]: info: Dec 22 17:22:50 volpb volumio[1134]: ---------------------------- MPD announces state update: database Dec 22 17:22:50 volpb volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Dec 22 17:22:50 volpb volumio[1134]: info: Dec 22 17:22:50 volpb volumio[1134]: ---------------------------- MPD announces state update: update Dec 22 17:22:50 volpb volumio[1134]: info: ControllerMpd::getState Dec 22 17:22:50 volpb volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Dec 22 17:22:50 volpb volumio[1134]: info: ControllerMpd::getState Dec 22 17:22:50 volpb volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Dec 22 17:22:50 volpb volumio[1134]: info: sendMpdCommand status took 10 milliseconds Dec 22 17:22:50 volpb volumio[1134]: info: sendMpdCommand status took 9 milliseconds Dec 22 17:22:50 volpb volumio[1134]: info: sendMpdCommand status took 10 milliseconds Dec 22 17:22:50 volpb volumio[1134]: verbose: ControllerMpd::parseState Dec 22 17:22:50 volpb volumio[1134]: info: Command Router : Notfying DB Updatefalse Dec 22 17:22:50 volpb volumio[1134]: info: CoreCommandRouter::Close All Modals sent Dec 22 17:22:50 volpb volumio[1134]: verbose: ControllerMpd::parseState Dec 22 17:22:50 volpb volumio[1134]: verbose: ControllerMpd::parseState Dec 22 17:22:50 volpb volumio[1134]: info: ControllerMpd::pushState Dec 22 17:22:50 volpb volumio[1134]: info: CoreCommandRouter::servicePushState Dec 22 17:22:50 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:22:50 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:22:50 volpb volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 17:22:50 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:22:50 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:22:50 volpb volumio[1134]: 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} Dec 22 17:22:50 volpb volumio[1134]: verbose: CURRENT POSITION 4 Dec 22 17:22:50 volpb volumio[1134]: info: CoreStateMachine::syncState stateService stop Dec 22 17:22:50 volpb volumio[1134]: info: CoreStateMachine::syncState currentStatus stop Dec 22 17:22:50 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:22:50 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:22:50 volpb volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 17:22:50 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:22:50 volpb volumio[1134]: info: No code Dec 22 17:22:50 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:22:50 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:22:50 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:22:50 volpb volumio[1134]: info: ControllerMpd::pushState Dec 22 17:22:50 volpb volumio[1134]: info: CoreCommandRouter::servicePushState Dec 22 17:22:50 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:22:50 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:22:50 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:22:50 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:22:50 volpb volumio[1134]: 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} Dec 22 17:22:50 volpb volumio[1134]: verbose: CURRENT POSITION 4 Dec 22 17:22:50 volpb volumio[1134]: info: CoreStateMachine::syncState stateService stop Dec 22 17:22:50 volpb volumio[1134]: info: CoreStateMachine::syncState currentStatus stop Dec 22 17:22:50 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:22:50 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:22:50 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:22:50 volpb volumio[1134]: info: No code Dec 22 17:22:50 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:22:50 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:22:50 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:22:50 volpb volumio[1134]: info: ------------------------------ 79ms Dec 22 17:22:50 volpb volumio[1134]: info: ------------------------------ 79ms Dec 22 17:22:50 volpb volumio[1134]: info: sendMpdCommand status took 87 milliseconds Dec 22 17:22:50 volpb volumio[1134]: info: sendMpdCommand status took 87 milliseconds Dec 22 17:22:50 volpb volumio[1134]: info: sendMpdCommand status took 87 milliseconds Dec 22 17:22:50 volpb volumio[1134]: info: sendMpdCommand status took 86 milliseconds Dec 22 17:22:50 volpb volumio[1134]: info: sendMpdCommand status took 85 milliseconds Dec 22 17:22:50 volpb volumio[1134]: info: sendMpdCommand status took 85 milliseconds Dec 22 17:22:50 volpb volumio[1134]: verbose: ControllerMpd::parseState Dec 22 17:22:50 volpb volumio[1134]: info: Command Router : Notfying DB Updatefalse Dec 22 17:22:50 volpb volumio[1134]: info: CoreCommandRouter::Close All Modals sent Dec 22 17:22:50 volpb volumio[1134]: verbose: ControllerMpd::parseState Dec 22 17:22:50 volpb volumio[1134]: verbose: ControllerMpd::parseState Dec 22 17:22:50 volpb volumio[1134]: verbose: ControllerMpd::parseState Dec 22 17:22:50 volpb volumio[1134]: info: Command Router : Notfying DB Updatefalse Dec 22 17:22:50 volpb volumio[1134]: info: CoreCommandRouter::Close All Modals sent Dec 22 17:22:50 volpb volumio[1134]: verbose: ControllerMpd::parseState Dec 22 17:22:50 volpb volumio[1134]: verbose: ControllerMpd::parseState Dec 22 17:22:50 volpb volumio[1134]: info: ControllerMpd::pushState Dec 22 17:22:50 volpb volumio[1134]: info: CoreCommandRouter::servicePushState Dec 22 17:22:50 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:22:50 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:22:50 volpb volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 17:22:50 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:22:50 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:22:50 volpb volumio[1134]: 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} Dec 22 17:22:50 volpb volumio[1134]: verbose: CURRENT POSITION 4 Dec 22 17:22:50 volpb volumio[1134]: info: CoreStateMachine::syncState stateService stop Dec 22 17:22:50 volpb volumio[1134]: info: CoreStateMachine::syncState currentStatus stop Dec 22 17:22:50 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:22:50 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:22:50 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:22:50 volpb volumio[1134]: info: No code Dec 22 17:22:50 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:22:50 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:22:50 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:22:50 volpb volumio[1134]: info: ControllerMpd::pushState Dec 22 17:22:50 volpb volumio[1134]: info: CoreCommandRouter::servicePushState Dec 22 17:22:50 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:22:50 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:22:50 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:22:50 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:22:50 volpb volumio[1134]: 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} Dec 22 17:22:50 volpb volumio[1134]: verbose: CURRENT POSITION 4 Dec 22 17:22:50 volpb volumio[1134]: info: CoreStateMachine::syncState stateService stop Dec 22 17:22:50 volpb volumio[1134]: info: CoreStateMachine::syncState currentStatus stop Dec 22 17:22:50 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:22:50 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:22:50 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:22:50 volpb volumio[1134]: info: No code Dec 22 17:22:50 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:22:50 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:22:50 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:22:50 volpb volumio[1134]: info: ControllerMpd::pushState Dec 22 17:22:50 volpb volumio[1134]: info: CoreCommandRouter::servicePushState Dec 22 17:22:50 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:22:50 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:22:50 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:22:50 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:22:50 volpb volumio[1134]: 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} Dec 22 17:22:50 volpb volumio[1134]: verbose: CURRENT POSITION 4 Dec 22 17:22:50 volpb volumio[1134]: info: CoreStateMachine::syncState stateService stop Dec 22 17:22:50 volpb volumio[1134]: info: CoreStateMachine::syncState currentStatus stop Dec 22 17:22:50 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:22:50 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:22:50 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:22:50 volpb volumio[1134]: info: No code Dec 22 17:22:50 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:22:50 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:22:50 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:22:50 volpb volumio[1134]: info: ControllerMpd::pushState Dec 22 17:22:50 volpb volumio[1134]: info: CoreCommandRouter::servicePushState Dec 22 17:22:50 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:22:50 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:22:50 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:22:50 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:22:50 volpb volumio[1134]: 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} Dec 22 17:22:50 volpb volumio[1134]: verbose: CURRENT POSITION 4 Dec 22 17:22:50 volpb volumio[1134]: info: CoreStateMachine::syncState stateService stop Dec 22 17:22:50 volpb volumio[1134]: info: CoreStateMachine::syncState currentStatus stop Dec 22 17:22:50 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:22:50 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:22:50 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:22:50 volpb volumio[1134]: info: No code Dec 22 17:22:50 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:22:50 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:22:50 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:22:50 volpb volumio[1134]: info: ------------------------------ 155ms Dec 22 17:22:50 volpb volumio[1134]: info: ------------------------------ 155ms Dec 22 17:22:50 volpb volumio[1134]: info: ------------------------------ 154ms Dec 22 17:22:50 volpb volumio[1134]: info: ------------------------------ 154ms Dec 22 17:22:57 volpb kernel: fat__get_entry: 614 callbacks suppressed Dec 22 17:22:57 volpb kernel: FAT-fs (sda1): Directory bread(block 32768) failed Dec 22 17:22:57 volpb kernel: FAT-fs (sda1): Directory bread(block 32769) failed Dec 22 17:22:57 volpb kernel: FAT-fs (sda1): Directory bread(block 32770) failed Dec 22 17:22:57 volpb kernel: FAT-fs (sda1): Directory bread(block 32771) failed Dec 22 17:22:57 volpb kernel: FAT-fs (sda1): Directory bread(block 32772) failed Dec 22 17:22:57 volpb kernel: FAT-fs (sda1): Directory bread(block 32773) failed Dec 22 17:22:57 volpb kernel: FAT-fs (sda1): Directory bread(block 32774) failed Dec 22 17:22:57 volpb kernel: FAT-fs (sda1): Directory bread(block 32775) failed Dec 22 17:22:57 volpb kernel: FAT-fs (sda1): Directory bread(block 32776) failed Dec 22 17:22:57 volpb kernel: FAT-fs (sda1): Directory bread(block 32777) failed Dec 22 17:23:02 volpb volumio[1134]: info: CoreCommandRouter::volumioGetState Dec 22 17:23:02 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:23:04 volpb volumio[1134]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 17:23:04 volpb volumio[1134]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 17:23:04 volpb volumio[1134]: info: Retrieving Cloud Streaming UI Dec 22 17:23:04 volpb volumio[1134]: info: Getting Tidal Cloud Configuration Dec 22 17:23:04 volpb volumio[1134]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 17:23:04 volpb volumio[1134]: info: Getting Qobuz Cloud Configuration Dec 22 17:23:04 volpb volumio[1134]: info: Asking plugin for UI Config Dec 22 17:23:04 volpb volumio[1134]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 17:23:04 volpb volumio[1134]: info: Getting Spotify Cloud Configuration Dec 22 17:23:04 volpb volumio[1134]: info: Asking plugin for UI Config Dec 22 17:23:04 volpb volumio[1134]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 17:23:04 volpb volumio[1134]: info: Saving Spotify Acccount Dec 22 17:23:04 volpb volumio[1134]: info: Got Tidal Cloud Configuration Dec 22 17:23:04 volpb volumio[1134]: info: Got it Dec 22 17:23:04 volpb volumio[1134]: info: Got it Dec 22 17:23:04 volpb volumio[1134]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 22 17:23:04 volpb volumio[1134]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 17:23:04 volpb volumio[1134]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Dec 22 17:23:04 volpb volumio[1134]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 22 17:23:04 volpb volumio[1134]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 22 17:23:04 volpb volumio[1134]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 22 17:23:04 volpb volumio[1134]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 22 17:23:04 volpb volumio[1134]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 22 17:23:04 volpb volumio[1134]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 22 17:23:04 volpb volumio[1134]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 22 17:23:04 volpb volumio[1134]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 22 17:23:04 volpb volumio[1134]: info: CoreCommandRouter::volumioGetBrowseSources Dec 22 17:23:04 volpb volumio[1134]: info: CoreCommandRouter::volumioGetBrowseSources Dec 22 17:23:04 volpb volumio[1134]: info: CoreCommandRouter::volumioGetBrowseSources Dec 22 17:23:04 volpb volumio[1134]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 22 17:23:04 volpb volumio[1134]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 22 17:23:07 volpb volumio[1134]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Dec 22 17:23:07 volpb volumio[1134]: verbose: ControllerMpd::sendMpdCommand rescan Dec 22 17:23:07 volpb volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Dec 22 17:23:07 volpb volumio[1134]: info: Dec 22 17:23:07 volpb volumio[1134]: ---------------------------- MPD announces state update: update Dec 22 17:23:07 volpb volumio[1134]: info: sendMpdCommand rescan took 2 milliseconds Dec 22 17:23:07 volpb volumio[1134]: info: ControllerMpd::getState Dec 22 17:23:07 volpb volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Dec 22 17:23:07 volpb volumio[1134]: info: sendMpdCommand status took 1 milliseconds Dec 22 17:23:07 volpb volumio[1134]: info: sendMpdCommand status took 1 milliseconds Dec 22 17:23:07 volpb volumio[1134]: verbose: ControllerMpd::parseState Dec 22 17:23:07 volpb kernel: fat__get_entry: 326 callbacks suppressed Dec 22 17:23:07 volpb kernel: FAT-fs (sda1): Directory bread(block 32768) failed Dec 22 17:23:07 volpb kernel: FAT-fs (sda1): Directory bread(block 32769) failed Dec 22 17:23:07 volpb kernel: FAT-fs (sda1): Directory bread(block 32770) failed Dec 22 17:23:07 volpb kernel: FAT-fs (sda1): Directory bread(block 32771) failed Dec 22 17:23:07 volpb kernel: FAT-fs (sda1): Directory bread(block 32772) failed Dec 22 17:23:07 volpb kernel: FAT-fs (sda1): Directory bread(block 32773) failed Dec 22 17:23:07 volpb kernel: FAT-fs (sda1): Directory bread(block 32774) failed Dec 22 17:23:07 volpb kernel: FAT-fs (sda1): Directory bread(block 32775) failed Dec 22 17:23:07 volpb kernel: FAT-fs (sda1): Directory bread(block 32776) failed Dec 22 17:23:07 volpb kernel: FAT-fs (sda1): Directory bread(block 32777) failed Dec 22 17:23:07 volpb volumio[1134]: info: Command Router : Notfying DB Updatetrue Dec 22 17:23:07 volpb volumio[1134]: info: CoreCommandRouter::Close All Modals sent Dec 22 17:23:07 volpb volumio[1134]: verbose: ControllerMpd::parseState Dec 22 17:23:07 volpb volumio[1134]: info: ControllerMpd::pushState Dec 22 17:23:07 volpb volumio[1134]: info: CoreCommandRouter::servicePushState Dec 22 17:23:07 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:23:07 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:23:07 volpb volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 17:23:07 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:23:07 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:23:07 volpb volumio[1134]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 22 17:23:07 volpb volumio[1134]: verbose: CURRENT POSITION 4 Dec 22 17:23:07 volpb volumio[1134]: info: CoreStateMachine::syncState stateService stop Dec 22 17:23:07 volpb volumio[1134]: info: CoreStateMachine::syncState currentStatus stop Dec 22 17:23:07 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:23:07 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:23:07 volpb volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 17:23:07 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:23:07 volpb volumio[1134]: info: No code Dec 22 17:23:07 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:23:07 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:23:07 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:23:07 volpb volumio[1134]: info: ------------------------------ 59ms Dec 22 17:23:07 volpb volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Dec 22 17:23:07 volpb volumio[1134]: info: Dec 22 17:23:07 volpb volumio[1134]: ---------------------------- MPD announces state update: update Dec 22 17:23:07 volpb volumio[1134]: info: ControllerMpd::getState Dec 22 17:23:07 volpb volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Dec 22 17:23:07 volpb volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Dec 22 17:23:07 volpb volumio[1134]: info: Dec 22 17:23:07 volpb volumio[1134]: ---------------------------- MPD announces state update: update Dec 22 17:23:07 volpb volumio[1134]: info: ControllerMpd::getState Dec 22 17:23:07 volpb volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Dec 22 17:23:07 volpb volumio[1134]: info: MPD Database updated - AlbumList cache refreshed Dec 22 17:23:07 volpb volumio[1134]: info: Dec 22 17:23:07 volpb volumio[1134]: ---------------------------- MPD announces state update: database Dec 22 17:23:07 volpb volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Dec 22 17:23:07 volpb volumio[1134]: info: Dec 22 17:23:07 volpb volumio[1134]: ---------------------------- MPD announces state update: update Dec 22 17:23:07 volpb volumio[1134]: info: ControllerMpd::getState Dec 22 17:23:07 volpb volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Dec 22 17:23:07 volpb volumio[1134]: info: ControllerMpd::getState Dec 22 17:23:07 volpb volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Dec 22 17:23:07 volpb volumio[1134]: info: sendMpdCommand status took 5 milliseconds Dec 22 17:23:07 volpb volumio[1134]: info: sendMpdCommand status took 3 milliseconds Dec 22 17:23:07 volpb volumio[1134]: info: sendMpdCommand status took 2 milliseconds Dec 22 17:23:07 volpb volumio[1134]: info: sendMpdCommand status took 2 milliseconds Dec 22 17:23:07 volpb volumio[1134]: verbose: ControllerMpd::parseState Dec 22 17:23:07 volpb volumio[1134]: info: Command Router : Notfying DB Updatefalse Dec 22 17:23:07 volpb volumio[1134]: info: CoreCommandRouter::Close All Modals sent Dec 22 17:23:07 volpb volumio[1134]: verbose: ControllerMpd::parseState Dec 22 17:23:07 volpb volumio[1134]: verbose: ControllerMpd::parseState Dec 22 17:23:07 volpb volumio[1134]: info: Command Router : Notfying DB Updatefalse Dec 22 17:23:07 volpb volumio[1134]: info: CoreCommandRouter::Close All Modals sent Dec 22 17:23:07 volpb volumio[1134]: verbose: ControllerMpd::parseState Dec 22 17:23:07 volpb volumio[1134]: info: ControllerMpd::pushState Dec 22 17:23:07 volpb volumio[1134]: info: CoreCommandRouter::servicePushState Dec 22 17:23:07 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:23:07 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:23:07 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:23:07 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:23:07 volpb volumio[1134]: 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} Dec 22 17:23:07 volpb volumio[1134]: verbose: CURRENT POSITION 4 Dec 22 17:23:07 volpb volumio[1134]: info: CoreStateMachine::syncState stateService stop Dec 22 17:23:07 volpb volumio[1134]: info: CoreStateMachine::syncState currentStatus stop Dec 22 17:23:07 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:23:07 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:23:07 volpb volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 17:23:07 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:23:07 volpb volumio[1134]: info: No code Dec 22 17:23:07 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:23:07 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:23:07 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:23:07 volpb volumio[1134]: info: ControllerMpd::pushState Dec 22 17:23:07 volpb volumio[1134]: info: CoreCommandRouter::servicePushState Dec 22 17:23:07 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:23:07 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:23:07 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:23:07 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:23:07 volpb volumio[1134]: 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} Dec 22 17:23:07 volpb volumio[1134]: verbose: CURRENT POSITION 4 Dec 22 17:23:07 volpb volumio[1134]: info: CoreStateMachine::syncState stateService stop Dec 22 17:23:07 volpb volumio[1134]: info: CoreStateMachine::syncState currentStatus stop Dec 22 17:23:07 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:23:07 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:23:07 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:23:07 volpb volumio[1134]: info: No code Dec 22 17:23:07 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:23:07 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:23:07 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:23:07 volpb volumio[1134]: info: ------------------------------ 82ms Dec 22 17:23:07 volpb volumio[1134]: info: ------------------------------ 80ms Dec 22 17:23:07 volpb volumio[1134]: info: MPD Database updated - AlbumList cache refreshed Dec 22 17:23:07 volpb volumio[1134]: info: Dec 22 17:23:07 volpb volumio[1134]: ---------------------------- MPD announces state update: database Dec 22 17:23:07 volpb volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Dec 22 17:23:07 volpb volumio[1134]: info: Dec 22 17:23:07 volpb volumio[1134]: ---------------------------- MPD announces state update: update Dec 22 17:23:07 volpb volumio[1134]: info: ControllerMpd::getState Dec 22 17:23:07 volpb volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Dec 22 17:23:07 volpb volumio[1134]: info: ControllerMpd::getState Dec 22 17:23:07 volpb volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Dec 22 17:23:07 volpb volumio[1134]: info: MPD Database updated - AlbumList cache refreshed Dec 22 17:23:07 volpb volumio[1134]: info: Dec 22 17:23:07 volpb volumio[1134]: ---------------------------- MPD announces state update: database Dec 22 17:23:07 volpb volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Dec 22 17:23:07 volpb volumio[1134]: info: Dec 22 17:23:07 volpb volumio[1134]: ---------------------------- MPD announces state update: update Dec 22 17:23:07 volpb volumio[1134]: info: ControllerMpd::getState Dec 22 17:23:07 volpb volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Dec 22 17:23:07 volpb volumio[1134]: info: ControllerMpd::getState Dec 22 17:23:07 volpb volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Dec 22 17:23:07 volpb volumio[1134]: info: sendMpdCommand status took 106 milliseconds Dec 22 17:23:07 volpb volumio[1134]: info: sendMpdCommand status took 106 milliseconds Dec 22 17:23:07 volpb volumio[1134]: info: sendMpdCommand status took 105 milliseconds Dec 22 17:23:07 volpb volumio[1134]: info: sendMpdCommand status took 28 milliseconds Dec 22 17:23:07 volpb volumio[1134]: info: sendMpdCommand status took 27 milliseconds Dec 22 17:23:07 volpb volumio[1134]: info: sendMpdCommand status took 27 milliseconds Dec 22 17:23:07 volpb volumio[1134]: verbose: ControllerMpd::parseState Dec 22 17:23:07 volpb volumio[1134]: info: Command Router : Notfying DB Updatefalse Dec 22 17:23:07 volpb volumio[1134]: info: CoreCommandRouter::Close All Modals sent Dec 22 17:23:07 volpb volumio[1134]: verbose: ControllerMpd::parseState Dec 22 17:23:07 volpb volumio[1134]: verbose: ControllerMpd::parseState Dec 22 17:23:07 volpb volumio[1134]: verbose: ControllerMpd::parseState Dec 22 17:23:07 volpb volumio[1134]: info: Command Router : Notfying DB Updatefalse Dec 22 17:23:07 volpb volumio[1134]: info: CoreCommandRouter::Close All Modals sent Dec 22 17:23:07 volpb volumio[1134]: verbose: ControllerMpd::parseState Dec 22 17:23:07 volpb volumio[1134]: verbose: ControllerMpd::parseState Dec 22 17:23:07 volpb volumio[1134]: info: ControllerMpd::pushState Dec 22 17:23:07 volpb volumio[1134]: info: CoreCommandRouter::servicePushState Dec 22 17:23:07 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:23:07 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:23:07 volpb volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 17:23:07 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:23:07 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:23:07 volpb volumio[1134]: 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} Dec 22 17:23:07 volpb volumio[1134]: verbose: CURRENT POSITION 4 Dec 22 17:23:07 volpb volumio[1134]: info: CoreStateMachine::syncState stateService stop Dec 22 17:23:07 volpb volumio[1134]: info: CoreStateMachine::syncState currentStatus stop Dec 22 17:23:07 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:23:07 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:23:07 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:23:07 volpb volumio[1134]: info: No code Dec 22 17:23:07 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:23:07 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:23:07 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:23:07 volpb volumio[1134]: info: ControllerMpd::pushState Dec 22 17:23:07 volpb volumio[1134]: info: CoreCommandRouter::servicePushState Dec 22 17:23:07 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:23:07 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:23:07 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:23:07 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:23:07 volpb volumio[1134]: 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} Dec 22 17:23:07 volpb volumio[1134]: verbose: CURRENT POSITION 4 Dec 22 17:23:07 volpb volumio[1134]: info: CoreStateMachine::syncState stateService stop Dec 22 17:23:07 volpb volumio[1134]: info: CoreStateMachine::syncState currentStatus stop Dec 22 17:23:07 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:23:07 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:23:07 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:23:07 volpb volumio[1134]: info: No code Dec 22 17:23:07 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:23:07 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:23:07 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:23:07 volpb volumio[1134]: info: ControllerMpd::pushState Dec 22 17:23:07 volpb volumio[1134]: info: CoreCommandRouter::servicePushState Dec 22 17:23:07 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:23:07 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:23:07 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:23:07 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:23:07 volpb volumio[1134]: 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} Dec 22 17:23:07 volpb volumio[1134]: verbose: CURRENT POSITION 4 Dec 22 17:23:07 volpb volumio[1134]: info: CoreStateMachine::syncState stateService stop Dec 22 17:23:07 volpb volumio[1134]: info: CoreStateMachine::syncState currentStatus stop Dec 22 17:23:07 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:23:07 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:23:07 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:23:07 volpb volumio[1134]: info: No code Dec 22 17:23:07 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:23:07 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:23:07 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:23:07 volpb volumio[1134]: info: ControllerMpd::pushState Dec 22 17:23:07 volpb volumio[1134]: info: CoreCommandRouter::servicePushState Dec 22 17:23:07 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:23:07 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:23:07 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:23:07 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:23:07 volpb volumio[1134]: 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} Dec 22 17:23:07 volpb volumio[1134]: verbose: CURRENT POSITION 4 Dec 22 17:23:07 volpb volumio[1134]: info: CoreStateMachine::syncState stateService stop Dec 22 17:23:07 volpb volumio[1134]: info: CoreStateMachine::syncState currentStatus stop Dec 22 17:23:07 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:23:07 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:23:07 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:23:07 volpb volumio[1134]: info: No code Dec 22 17:23:07 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:23:07 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:23:07 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:23:07 volpb volumio[1134]: info: ------------------------------ 175ms Dec 22 17:23:07 volpb volumio[1134]: info: ------------------------------ 175ms Dec 22 17:23:07 volpb volumio[1134]: info: ------------------------------ 97ms Dec 22 17:23:07 volpb volumio[1134]: info: ------------------------------ 97ms Dec 22 17:23:07 volpb volumio[1134]: info: sendMpdCommand status took 100 milliseconds Dec 22 17:23:07 volpb volumio[1134]: info: sendMpdCommand status took 100 milliseconds Dec 22 17:23:07 volpb volumio[1134]: info: sendMpdCommand status took 100 milliseconds Dec 22 17:23:07 volpb volumio[1134]: verbose: ControllerMpd::parseState Dec 22 17:23:07 volpb volumio[1134]: info: Command Router : Notfying DB Updatefalse Dec 22 17:23:07 volpb volumio[1134]: info: CoreCommandRouter::Close All Modals sent Dec 22 17:23:07 volpb volumio[1134]: verbose: ControllerMpd::parseState Dec 22 17:23:07 volpb volumio[1134]: verbose: ControllerMpd::parseState Dec 22 17:23:07 volpb volumio[1134]: info: ControllerMpd::pushState Dec 22 17:23:07 volpb volumio[1134]: info: CoreCommandRouter::servicePushState Dec 22 17:23:07 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:23:07 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:23:07 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:23:07 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:23:07 volpb volumio[1134]: 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} Dec 22 17:23:07 volpb volumio[1134]: verbose: CURRENT POSITION 4 Dec 22 17:23:07 volpb volumio[1134]: info: CoreStateMachine::syncState stateService stop Dec 22 17:23:07 volpb volumio[1134]: info: CoreStateMachine::syncState currentStatus stop Dec 22 17:23:07 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:23:07 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:23:07 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:23:07 volpb volumio[1134]: info: No code Dec 22 17:23:07 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:23:07 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:23:07 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:23:07 volpb volumio[1134]: info: ControllerMpd::pushState Dec 22 17:23:07 volpb volumio[1134]: info: CoreCommandRouter::servicePushState Dec 22 17:23:07 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:23:07 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:23:07 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:23:07 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:23:07 volpb volumio[1134]: 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} Dec 22 17:23:07 volpb volumio[1134]: verbose: CURRENT POSITION 4 Dec 22 17:23:07 volpb volumio[1134]: info: CoreStateMachine::syncState stateService stop Dec 22 17:23:07 volpb volumio[1134]: info: CoreStateMachine::syncState currentStatus stop Dec 22 17:23:07 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:23:07 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:23:07 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:23:07 volpb volumio[1134]: info: No code Dec 22 17:23:07 volpb volumio[1134]: info: CoreStateMachine::pushState Dec 22 17:23:07 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:23:07 volpb volumio[1134]: info: CoreCommandRouter::volumioPushState Dec 22 17:23:07 volpb volumio[1134]: info: ------------------------------ 136ms Dec 22 17:23:07 volpb volumio[1134]: info: ------------------------------ 137ms Dec 22 17:23:08 volpb volumio[1134]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 22 17:23:11 volpb volumio[1134]: info: CoreCommandRouter::volumioGetState Dec 22 17:23:11 volpb volumio[1134]: info: CorePlayQueue::getTrack 4 Dec 22 17:23:20 volpb volumio[1134]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 22 17:23:20 volpb volumio[1134]: info: CURURI: playlists Dec 22 17:23:20 volpb volumio[1134]: info: Listing playlists Dec 22 17:23:21 volpb volumio[1134]: info: Preload queue cleared Dec 22 17:23:23 volpb volumio[1134]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 22 17:23:23 volpb volumio[1134]: info: CURURI: playlists/undefined Dec 22 17:23:23 volpb volumio[1134]: info: Preload queue cleared Dec 22 17:23:27 volpb volumio[1134]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 22 17:23:27 volpb volumio[1134]: info: CURURI: music-library Dec 22 17:23:27 volpb volumio[1134]: info: Preload queue cleared Dec 22 17:23:29 volpb volumio[1134]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 22 17:23:29 volpb volumio[1134]: info: CURURI: music-library/USB Dec 22 17:23:29 volpb volumio[1134]: info: Preload queue cleared Dec 22 17:23:31 volpb volumio[1134]: info: Preload queue cleared Dec 22 17:23:31 volpb volumio[1134]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 22 17:23:31 volpb volumio[1134]: info: CoreStateMachine::ClearQueue Dec 22 17:23:31 volpb volumio[1134]: info: CoreStateMachine::stop Dec 22 17:23:31 volpb volumio[1134]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 22 17:23:31 volpb volumio[1134]: info: CorePlayQueue::clearPlayQueue Dec 22 17:23:31 volpb volumio[1134]: info: CorePlayQueue::saveQueue Dec 22 17:23:31 volpb volumio[1134]: info: CoreCommandRouter::volumioPushQueue Dec 22 17:23:31 volpb volumio[1134]: info: CoreStateMachine::addQueueItems Dec 22 17:23:31 volpb volumio[1134]: info: CorePlayQueue::addQueueItems Dec 22 17:23:31 volpb volumio[1134]: info: Preload queue cleared Dec 22 17:23:31 volpb volumio[1134]: info: Adding Item to queue: music-library/USB/D227-1B22 Dec 22 17:23:31 volpb volumio[1134]: info: Exploding uri music-library/USB/D227-1B22 in service mpd Dec 22 17:23:31 volpb volumio[1134]: info: ALBUMART /albumart?cacheid=400&web=BELLATRIX/I%20Love%20KOTO%20-%20Covers%20and%20Remixes/extralarge&path=%2Fmnt%2FUSB%2FD227-1B22%2FBELLATRIX%20-%20I%20Love%20KOTO%20-%20Covers%20and%20Remixes%20%5B2018%5D%2F01.%20Acknowledge.flac&metadata=false Dec 22 17:23:31 volpb volumio[1134]: info: URI /mnt/USB/D227-1B22/BELLATRIX - I Love KOTO - Covers and Remixes [2018]/01. Acknowledge.flac Dec 22 17:23:31 volpb volumio[1134]: info: ALBUMART /albumart?cacheid=400&web=BELLATRIX/I%20Love%20KOTO%20-%20Covers%20and%20Remixes/extralarge&path=%2Fmnt%2FUSB%2FD227-1B22%2FBELLATRIX%20-%20I%20Love%20KOTO%20-%20Covers%20and%20Remixes%20%5B2018%5D%2F02.%20Chinese%20Revenge.flac&metadata=false Dec 22 17:23:31 volpb volumio[1134]: info: URI /mnt/USB/D227-1B22/BELLATRIX - I Love KOTO - Covers and Remixes [2018]/02. Chinese Revenge.flac Dec 22 17:23:31 volpb volumio[1134]: info: ALBUMART /albumart?cacheid=400&web=BELLATRIX/I%20Love%20KOTO%20-%20Covers%20and%20Remixes/extralarge&path=%2Fmnt%2FUSB%2FD227-1B22%2FBELLATRIX%20-%20I%20Love%20KOTO%20-%20Covers%20and%20Remixes%20%5B2018%5D%2F03.%20Dragon's%20Legend.flac&metadata=false Dec 22 17:23:31 volpb volumio[1134]: info: URI /mnt/USB/D227-1B22/BELLATRIX - I Love KOTO - Covers and Remixes [2018]/03. Dragon's Legend.flac Dec 22 17:23:31 volpb volumio[1134]: info: ALBUMART /albumart?cacheid=400&web=BELLATRIX/I%20Love%20KOTO%20-%20Covers%20and%20Remixes/extralarge&path=%2Fmnt%2FUSB%2FD227-1B22%2FBELLATRIX%20-%20I%20Love%20KOTO%20-%20Covers%20and%20Remixes%20%5B2018%5D%2F04.%20Jabdah.flac&metadata=false Dec 22 17:23:31 volpb volumio[1134]: info: URI /mnt/USB/D227-1B22/BELLATRIX - I Love KOTO - Covers and Remixes [2018]/04. Jabdah.flac Dec 22 17:23:31 volpb volumio[1134]: info: ALBUMART /albumart?cacheid=400&web=BELLATRIX/I%20Love%20KOTO%20-%20Covers%20and%20Remixes/extralarge&path=%2Fmnt%2FUSB%2FD227-1B22%2FBELLATRIX%20-%20I%20Love%20KOTO%20-%20Covers%20and%20Remixes%20%5B2018%5D%2F05.%20Japanese%20War%20Game.flac&metadata=false Dec 22 17:23:31 volpb volumio[1134]: info: URI /mnt/USB/D227-1B22/BELLATRIX - I Love KOTO - Covers and Remixes [2018]/05. Japanese War Game.flac Dec 22 17:23:31 volpb volumio[1134]: info: ALBUMART /albumart?cacheid=400&web=BELLATRIX/I%20Love%20KOTO%20-%20Covers%20and%20Remixes/extralarge&path=%2Fmnt%2FUSB%2FD227-1B22%2FBELLATRIX%20-%20I%20Love%20KOTO%20-%20Covers%20and%20Remixes%20%5B2018%5D%2F06.%20Visitors.flac&metadata=false Dec 22 17:23:31 volpb volumio[1134]: info: URI /mnt/USB/D227-1B22/BELLATRIX - I Love KOTO - Covers and Remixes [2018]/06. Visitors.flac Dec 22 17:23:31 volpb volumio[1134]: info: ALBUMART /albumart?cacheid=400&web=BELLATRIX/I%20Love%20KOTO%20-%20Covers%20and%20Remixes/extralarge&path=%2Fmnt%2FUSB%2FD227-1B22%2FBELLATRIX%20-%20I%20Love%20KOTO%20-%20Covers%20and%20Remixes%20%5B2018%5D%2F07.%20Mind%20Machine.flac&metadata=false Dec 22 17:23:31 volpb volumio[1134]: info: URI /mnt/USB/D227-1B22/BELLATRIX - I Love KOTO - Covers and Remixes [2018]/07. Mind Machine.flac Dec 22 17:23:31 volpb volumio[1134]: info: ALBUMART /albumart?cacheid=400&web=Bellatrix-music/BELLATRIX%20-%20Droids/extralarge&path=%2Fmnt%2FUSB%2FD227-1B22%2FBellatrix%20-%20Droids%20FLAC%202020%2F01%20Story%20from%20space.flac&metadata=false Dec 22 17:23:31 volpb volumio[1134]: info: URI /mnt/USB/D227-1B22/Bellatrix - Droids FLAC 2020/01 Story from space.flac Dec 22 17:23:31 volpb volumio[1134]: info: ALBUMART /albumart?cacheid=400&web=Bellatrix-music/BELLATRIX%20-%20Droids/extralarge&path=%2Fmnt%2FUSB%2FD227-1B22%2FBellatrix%20-%20Droids%20FLAC%202020%2F02%20Vector.flac&metadata=false Dec 22 17:23:31 volpb volumio[1134]: info: URI /mnt/USB/D227-1B22/Bellatrix - Droids FLAC 2020/02 Vector.flac Dec 22 17:23:31 volpb volumio[1134]: info: ALBUMART /albumart?cacheid=400&web=Bellatrix-music/BELLATRIX%20-%20Droids/extralarge&path=%2Fmnt%2FUSB%2FD227-1B22%2FBellatrix%20-%20Droids%20FLAC%202020%2F03%20Glass%20Heart.flac&metadata=false Dec 22 17:23:31 volpb volumio[1134]: info: URI /mnt/USB/D227-1B22/Bellatrix - Droids FLAC 2020/03 Glass Heart.flac Dec 22 17:23:31 volpb volumio[1134]: info: ALBUMART /albumart?cacheid=400&web=Bellatrix-music/BELLATRIX%20-%20Droids/extralarge&path=%2Fmnt%2FUSB%2FD227-1B22%2FBellatrix%20-%20Droids%20FLAC%202020%2F04%20Abyss.flac&metadata=false Dec 22 17:23:31 volpb volumio[1134]: info: URI /mnt/USB/D227-1B22/Bellatrix - Droids FLAC 2020/04 Abyss.flac Dec 22 17:23:31 volpb volumio[1134]: info: ALBUMART /albumart?cacheid=400&web=Bellatrix-music/BELLATRIX%20-%20Droids/extralarge&path=%2Fmnt%2FUSB%2FD227-1B22%2FBellatrix%20-%20Droids%20FLAC%202020%2F05%20Monomatic.flac&metadata=false Dec 22 17:23:31 volpb volumio[1134]: info: URI /mnt/USB/D227-1B22/Bellatrix - Droids FLAC 2020/05 Monomatic.flac Dec 22 17:23:31 volpb volumio[1134]: info: ALBUMART /albumart?cacheid=400&web=Bellatrix-music/BELLATRIX%20-%20Droids/extralarge&path=%2Fmnt%2FUSB%2FD227-1B22%2FBellatrix%20-%20Droids%20FLAC%202020%2F06%20Satellite.flac&metadata=false Dec 22 17:23:31 volpb volumio[1134]: info: URI /mnt/USB/D227-1B22/Bellatrix - Droids FLAC 2020/06 Satellite.flac Dec 22 17:23:31 volpb volumio[1134]: info: ALBUMART /albumart?cacheid=400&web=Bellatrix-music/BELLATRIX%20-%20Droids/extralarge&path=%2Fmnt%2FUSB%2FD227-1B22%2FBellatrix%20-%20Droids%20FLAC%202020%2F07%20Droids.flac&metadata=false Dec 22 17:23:31 volpb volumio[1134]: info: URI /mnt/USB/D227-1B22/Bellatrix - Droids FLAC 2020/07 Droids.flac Dec 22 17:23:31 volpb volumio[1134]: info: ALBUMART /albumart?cacheid=400&web=Bellatrix-music/BELLATRIX%20-%20Droids/extralarge&path=%2Fmnt%2FUSB%2FD227-1B22%2FBellatrix%20-%20Droids%20FLAC%202020%2F08%20Touch%20Interior.flac&metadata=false Dec 22 17:23:31 volpb volumio[1134]: info: URI /mnt/USB/D227-1B22/Bellatrix - Droids FLAC 2020/08 Touch Interior.flac Dec 22 17:23:31 volpb volumio[1134]: info: ALBUMART /albumart?cacheid=400&web=Bellatrix-music/BELLATRIX%20-%20Droids/extralarge&path=%2Fmnt%2FUSB%2FD227-1B22%2FBellatrix%20-%20Droids%20FLAC%202020%2F09%20Around%20the%20planets.flac&metadata=false Dec 22 17:23:31 volpb volumio[1134]: info: URI /mnt/USB/D227-1B22/Bellatrix - Droids FLAC 2020/09 Around the planets.flac Dec 22 17:23:31 volpb volumio[1134]: info: ALBUMART /albumart?cacheid=400&web=Bellatrix-music/BELLATRIX%20-%20Droids/extralarge&path=%2Fmnt%2FUSB%2FD227-1B22%2FBellatrix%20-%20Droids%20FLAC%202020%2F10%20We%20can%20fly.flac&metadata=false Dec 22 17:23:31 volpb volumio[1134]: info: URI /mnt/USB/D227-1B22/Bellatrix - Droids FLAC 2020/10 We can fly.flac Dec 22 17:23:31 volpb volumio[1134]: info: ALBUMART /albumart?cacheid=400&web=Bellatrix-music/BELLATRIX%20-%20Droids/extralarge&path=%2Fmnt%2FUSB%2FD227-1B22%2FBellatrix%20-%20Droids%20FLAC%202020%2F11%20Dynamic%20range.flac&metadata=false Dec 22 17:23:31 volpb volumio[1134]: info: URI /mnt/USB/D227-1B22/Bellatrix - Droids FLAC 2020/11 Dynamic range.flac Dec 22 17:23:31 volpb volumio[1134]: info: ALBUMART /albumart?cacheid=400&web=Bellatrix-music/BELLATRIX%20-%20Droids/extralarge&path=%2Fmnt%2FUSB%2FD227-1B22%2FBellatrix%20-%20Droids%20FLAC%202020%2F12%20Walking%20away.flac&metadata=false Dec 22 17:23:31 volpb volumio[1134]: info: URI /mnt/USB/D227-1B22/Bellatrix - Droids FLAC 2020/12 Walking away.flac Dec 22 17:23:31 volpb volumio[1134]: info: ALBUMART /albumart?cacheid=400&web=Elysion/Silent%20Scream/extralarge&path=%2Fmnt%2FUSB%2FD227-1B22%2FElysion%20-%202009%20-%20Silent%20Scream%2F01-%20Dreamer.mp3&metadata=false Dec 22 17:23:31 volpb volumio[1134]: info: URI /mnt/USB/D227-1B22/Elysion - 2009 - Silent Scream/01- Dreamer.mp3 Dec 22 17:23:31 volpb volumio[1134]: info: ALBUMART /albumart?cacheid=400&web=Elysion/Silent%20Scream/extralarge&path=%2Fmnt%2FUSB%2FD227-1B22%2FElysion%20-%202009%20-%20Silent%20Scream%2F02-%20Killing%20My%20Dreams.mp3&metadata=false Dec 22 17:23:31 volpb volumio[1134]: info: URI /mnt/USB/D227-1B22/Elysion - 2009 - Silent Scream/02- Killing My Dreams.mp3 Dec 22 17:23:31 volpb volumio[1134]: info: ALBUMART /albumart?cacheid=400&web=Elysion/Silent%20Scream/extralarge&path=%2Fmnt%2FUSB%2FD227-1B22%2FElysion%20-%202009%20-%20Silent%20Scream%2F03-%20Never%20Forever.mp3&metadata=false Dec 22 17:23:31 volpb volumio[1134]: info: URI /mnt/USB/D227-1B22/Elysion - 2009 - Silent Scream/03- Never Forever.mp3 Dec 22 17:23:31 volpb volumio[1134]: info: ALBUMART /albumart?cacheid=400&web=Elysion/Silent%20Scream/extralarge&path=%2Fmnt%2FUSB%2FD227-1B22%2FElysion%20-%202009%20-%20Silent%20Scream%2F04-%20Weakness%20In%20Your%20Eyes.mp3&metadata=false Dec 22 17:23:31 volpb volumio[1134]: info: URI /mnt/USB/D227-1B22/Elysion - 2009 - Silent Scream/04- Weakness In Your Eyes.mp3 Dec 22 17:23:31 volpb volumio[1134]: info: ALBUMART /albumart?cacheid=400&web=Elysion/Silent%20Scream/extralarge&path=%2Fmnt%2FUSB%2FD227-1B22%2FElysion%20-%202009%20-%20Silent%20Scream%2F05-%20Don't%20Say%20A%20Word.mp3&metadata=false Dec 22 17:23:31 volpb volumio[1134]: info: URI /mnt/USB/D227-1B22/Elysion - 2009 - Silent Scream/05- Don't Say A Word.mp3 Dec 22 17:23:31 volpb volumio[1134]: info: ALBUMART /albumart?cacheid=400&web=Elysion/Silent%20Scream/extralarge&path=%2Fmnt%2FUSB%2FD227-1B22%2FElysion%20-%202009%20-%20Silent%20Scream%2F06-%20The%20Rules.mp3&metadata=false Dec 22 17:23:31 volpb volumio[1134]: info: URI /mnt/USB/D227-1B22/Elysion - 2009 - Silent Scream/06- The Rules.mp3 Dec 22 17:23:31 volpb volumio[1134]: info: ALBUMART /albumart?cacheid=400&web=Elysion/Silent%20Scream/extralarge&path=%2Fmnt%2FUSB%2FD227-1B22%2FElysion%20-%202009%20-%20Silent%20Scream%2F07-%20Bleeding.mp3&metadata=false Dec 22 17:23:31 volpb volumio[1134]: info: URI /mnt/USB/D227-1B22/Elysion - 2009 - Silent Scream/07- Bleeding.mp3 Dec 22 17:23:31 volpb volumio[1134]: info: ALBUMART /albumart?cacheid=400&web=Elysion/Silent%20Scream/extralarge&path=%2Fmnt%2FUSB%2FD227-1B22%2FElysion%20-%202009%20-%20Silent%20Scream%2F08-%20Walk%20Away.mp3&metadata=false Dec 22 17:23:31 volpb volumio[1134]: info: URI /mnt/USB/D227-1B22/Elysion - 2009 - Silent Scream/08- Walk Away.mp3 Dec 22 17:23:31 volpb volumio[1134]: info: ALBUMART /albumart?cacheid=400&web=Elysion/Silent%20Scream/extralarge&path=%2Fmnt%2FUSB%2FD227-1B22%2FElysion%20-%202009%20-%20Silent%20Scream%2F09-%20Loss.mp3&metadata=false Dec 22 17:23:31 volpb volumio[1134]: info: URI /mnt/USB/D227-1B22/Elysion - 2009 - Silent Scream/09- Loss.mp3 Dec 22 17:23:31 volpb volumio[1134]: info: ALBUMART /albumart?cacheid=400&web=Elysion/Silent%20Scream/extralarge&path=%2Fmnt%2FUSB%2FD227-1B22%2FElysion%20-%202009%20-%20Silent%20Scream%2F10-%20Far%20From%20The%20Edge.mp3&metadata=false Dec 22 17:23:31 volpb volumio[1134]: info: URI /mnt/USB/D227-1B22/Elysion - 2009 - Silent Scream/10- Far From The Edge.mp3 Dec 22 17:23:31 volpb volumio[1134]: info: ALBUMART /albumart?cacheid=400&web=Elysion/Silent%20Scream/extralarge&path=%2Fmnt%2FUSB%2FD227-1B22%2FElysion%20-%202009%20-%20Silent%20Scream%2F11-%20Erase%20Me.mp3&metadata=false Dec 22 17:23:31 volpb volumio[1134]: info: URI /mnt/USB/D227-1B22/Elysion - 2009 - Silent Scream/11- Erase Me.mp3 Dec 22 17:23:31 volpb volumio[1134]: info: ALBUMART /albumart?cacheid=400&web=Galaxy%20Hunter/Running%20High/extralarge&path=%2Fmnt%2FUSB%2FD227-1B22%2FGalaxy%20Hunter%20-%20Running%20High%20(2010%20Space%20Sound%20Records)%20(Lossless)%2FGalaxy%20Hunter%20-%20Running%20High.cue&metadata=false Dec 22 17:23:31 volpb volumio[1134]: info: URI /mnt/USB/D227-1B22/Galaxy Hunter - Running High (2010 Space Sound Records) (Lossless)/Galaxy Hunter - Running High.cue Dec 22 17:23:31 volpb volumio[1134]: info: ALBUMART /albumart?cacheid=400&web=Galaxy%20Hunter/Running%20High/extralarge&path=%2Fmnt%2FUSB%2FD227-1B22%2FGalaxy%20Hunter%20-%20Running%20High%20(2010%20Space%20Sound%20Records)%20(Lossless)%2FGalaxy%20Hunter%20-%20Running%20High.cue&metadata=false Dec 22 17:23:31 volpb volumio[1134]: info: URI /mnt/USB/D227-1B22/Galaxy Hunter - Running High (2010 Space Sound Records) (Lossless)/Galaxy Hunter - Running High.cue Dec 22 17:23:31 volpb volumio[1134]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 22 17:23:31 volpb volumio[1134]: Error: Unable to resolve or reject the same promise twice Dec 22 17:23:31 volpb volumio[1134]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Dec 22 17:23:31 volpb volumio[1134]: at /volumio/app/plugins/music_service/mpd/index.js:2587:21 Dec 22 17:23:31 volpb volumio[1134]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Dec 22 17:23:31 volpb volumio[1134]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Dec 22 17:23:31 volpb volumio[1134]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Dec 22 17:23:31 volpb volumio[1134]: at Socket.emit (node:events:514:28) Dec 22 17:23:31 volpb volumio[1134]: at addChunk (node:internal/streams/readable:343:12) Dec 22 17:23:31 volpb volumio[1134]: at readableAddChunk (node:internal/streams/readable:312:11) Dec 22 17:23:31 volpb volumio[1134]: at Readable.push (node:internal/streams/readable:253:10) Dec 22 17:23:31 volpb volumio[1134]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Dec 22 17:23:31 volpb volumio[1134]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 22 17:23:31 volpb sudo[4847]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-22 17:22' Dec 22 17:23:31 volpb sudo[4847]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"