Dec 28 00:44:41 volumio ntpd[817]: PROTO: 193.182.111.141 unlink local addr 192.168.50.55 -> Dec 28 00:44:41 volumio ntpd[817]: PROTO: 193.182.111.142 unlink local addr 192.168.50.55 -> Dec 28 00:44:41 volumio systemd[1]: Starting systemd-tmpfiles-clean.service - Cleanup of Temporary Directories... Dec 28 00:44:41 volumio systemd[1]: systemd-tmpfiles-clean.service: Deactivated successfully. Dec 28 00:44:41 volumio systemd[1]: Finished systemd-tmpfiles-clean.service - Cleanup of Temporary Directories. Dec 28 00:44:41 volumio systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dclean.service.mount: Deactivated successfully. Dec 28 00:44:42 volumio ntpd[817]: PROTO: 84.16.73.33 unlink local addr 192.168.50.55 -> Dec 28 00:44:43 volumio kernel: usb 1-1.1.3: USB disconnect, device number 6 Dec 28 00:44:43 volumio volumio[2219]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1/1-1.1.3/1-1.1.3:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","CURRENT_TAGS":":systemd:","DEVLINKS":"/dev/disk/by-path/platform-3f980000.usb-usb-0:1.1.3:1.0-scsi-0:0:0:0-part1 /dev/disk/by-uuid/E2D8-2F33 /dev/disk/by-id/usb-SanDisk_Cruzer_Blade_03024705040422160252-0:0-part1 /dev/disk/by-partuuid/8f6f91dc-01","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1/1-1.1.3/1-1.1.3:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"26","ID_BUS":"usb","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"E2D8-2F33","ID_FS_UUID_ENC":"E2D8-2F33","ID_FS_VERSION":"FAT32","ID_INSTANCE":"0:0","ID_MODEL":"Cruzer_Blade","ID_MODEL_ENC":"Cruzer\\x20Blade\\x20\\x20\\x20\\x20","ID_MODEL_ID":"5567","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":"31260640","ID_PART_ENTRY_TYPE":"0xc","ID_PART_ENTRY_UUID":"8f6f91dc-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"8f6f91dc","ID_PATH":"platform-3f980000.usb-usb-0:1.1.3:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-3f980000_usb-usb-0_1_1_3_1_0-scsi-0_0_0_0","ID_REVISION":"1.00","ID_SERIAL":"SanDisk_Cruzer_Blade_03024705040422160252-0:0","ID_SERIAL_SHORT":"03024705040422160252","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INSTANCE":"0:0","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_USB_MODEL":"Cruzer_Blade","ID_USB_MODEL_ENC":"Cruzer\\x20Blade\\x20\\x20\\x20\\x20","ID_USB_MODEL_ID":"5567","ID_USB_REVISION":"1.00","ID_USB_SERIAL":"SanDisk_Cruzer_Blade_03024705040422160252-0:0","ID_USB_SERIAL_SHORT":"03024705040422160252","ID_USB_TYPE":"disk","ID_USB_VENDOR":"SanDisk","ID_USB_VENDOR_ENC":"SanDisk\\x20","ID_USB_VENDOR_ID":"0781","ID_VENDOR":"SanDisk","ID_VENDOR_ENC":"SanDisk\\x20","ID_VENDOR_ID":"0781","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"2150","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"9586217"} Dec 28 00:44:43 volumio sudo[4190]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda1 Dec 28 00:44:43 volumio sudo[4190]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 00:44:43 volumio sudo[4190]: pam_unix(sudo:session): session closed for user root Dec 28 00:44:43 volumio volumio[2219]: umount: /media/E2D8-2F33: target is busy. Dec 28 00:44:43 volumio volumio[2219]: error: Failed to umount E2D8-2F33: Error: Command failed: /usr/bin/sudo /bin/umount -f "/dev/sda1" Dec 28 00:44:43 volumio volumio[2219]: umount: /media/E2D8-2F33: target is busy. Dec 28 00:44:44 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 2616) Dec 28 00:44:44 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 2616) Dec 28 00:44:44 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 2616) Dec 28 00:44:44 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 2616) Dec 28 00:44:44 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 2616) Dec 28 00:44:44 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 2616) Dec 28 00:44:44 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 2616) Dec 28 00:44:44 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 2616) Dec 28 00:44:44 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 2616) Dec 28 00:44:44 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 2616) Dec 28 00:44:44 volumio kernel: usb 1-1.2: new high-speed USB device number 8 using dwc_otg Dec 28 00:44:45 volumio kernel: usb 1-1.2: device descriptor read/64, error -71 Dec 28 00:44:45 volumio kernel: usb 1-1.2: New USB device found, idVendor=1058, idProduct=10a8, bcdDevice=10.42 Dec 28 00:44:45 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 28 00:44:45 volumio kernel: usb 1-1.2: Product: Elements 10A8 Dec 28 00:44:45 volumio kernel: usb 1-1.2: Manufacturer: Western Digital Dec 28 00:44:45 volumio kernel: usb 1-1.2: SerialNumber: 57584B314137373137454C4C Dec 28 00:44:45 volumio kernel: usb-storage 1-1.2:1.0: USB Mass Storage device detected Dec 28 00:44:45 volumio kernel: scsi host1: usb-storage 1-1.2:1.0 Dec 28 00:44:45 volumio kernel: usb 1-1.1.3: new high-speed USB device number 9 using dwc_otg Dec 28 00:44:45 volumio kernel: usb 1-1.1.3: New USB device found, idVendor=0781, idProduct=5567, bcdDevice= 1.00 Dec 28 00:44:45 volumio kernel: usb 1-1.1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 28 00:44:45 volumio kernel: usb 1-1.1.3: Product: Cruzer Blade Dec 28 00:44:45 volumio kernel: usb 1-1.1.3: Manufacturer: SanDisk Dec 28 00:44:45 volumio kernel: usb 1-1.1.3: SerialNumber: 03024705040422160252 Dec 28 00:44:45 volumio kernel: usb-storage 1-1.1.3:1.0: USB Mass Storage device detected Dec 28 00:44:45 volumio kernel: scsi host2: usb-storage 1-1.1.3:1.0 Dec 28 00:44:46 volumio kernel: scsi 1:0:0:0: Direct-Access WD Elements 10A8 1042 PQ: 0 ANSI: 6 Dec 28 00:44:46 volumio kernel: sd 1:0:0:0: Attached scsi generic sg0 type 0 Dec 28 00:44:46 volumio kernel: sd 1:0:0:0: [sdb] Spinning up disk... Dec 28 00:44:46 volumio kernel: scsi 2:0:0:0: Direct-Access SanDisk Cruzer Blade 1.00 PQ: 0 ANSI: 6 Dec 28 00:44:46 volumio kernel: sd 2:0:0:0: Attached scsi generic sg1 type 0 Dec 28 00:44:46 volumio kernel: sd 2:0:0:0: [sdc] 31260672 512-byte logical blocks: (16.0 GB/14.9 GiB) Dec 28 00:44:46 volumio kernel: sd 2:0:0:0: [sdc] Write Protect is off Dec 28 00:44:46 volumio kernel: sd 2:0:0:0: [sdc] Mode Sense: 43 00 00 00 Dec 28 00:44:46 volumio kernel: sd 2:0:0:0: [sdc] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA Dec 28 00:44:46 volumio kernel: sdc: sdc1 Dec 28 00:44:46 volumio kernel: sd 2:0:0:0: [sdc] Attached SCSI removable disk Dec 28 00:44:46 volumio volumio[2219]: info: Mounting Device E2D8-2F33 Dec 28 00:44:46 volumio sudo[4206]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdc1 /mnt/USB/E2D8-2F33 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 28 00:44:46 volumio sudo[4206]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 00:44:47 volumio kernel: FAT-fs (sdc1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Dec 28 00:44:47 volumio sudo[4206]: pam_unix(sudo:session): session closed for user root Dec 28 00:44:47 volumio kernel: FAT-fs (sdc1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Dec 28 00:44:48 volumio volumio[2219]: info: Scanning removed location : "USB/E2D8-2F33" Dec 28 00:44:48 volumio volumio[2219]: verbose: ControllerMpd::sendMpdCommand status Dec 28 00:44:48 volumio volumio[2219]: info: Dec 28 00:44:48 volumio volumio[2219]: ---------------------------- MPD announces state update: update Dec 28 00:44:48 volumio volumio[2219]: info: ControllerMpd::getState Dec 28 00:44:48 volumio volumio[2219]: verbose: ControllerMpd::sendMpdCommand status Dec 28 00:44:48 volumio volumio[2219]: verbose: ControllerMpd::sendMpdCommand status Dec 28 00:44:48 volumio volumio[2219]: info: Dec 28 00:44:48 volumio volumio[2219]: ---------------------------- MPD announces state update: update Dec 28 00:44:48 volumio volumio[2219]: info: ControllerMpd::getState Dec 28 00:44:48 volumio volumio[2219]: verbose: ControllerMpd::sendMpdCommand status Dec 28 00:44:48 volumio volumio[2219]: verbose: ControllerMpd::sendMpdCommand status Dec 28 00:44:48 volumio volumio[2219]: info: Dec 28 00:44:48 volumio volumio[2219]: ---------------------------- MPD announces state update: update Dec 28 00:44:48 volumio volumio[2219]: info: ControllerMpd::getState Dec 28 00:44:48 volumio volumio[2219]: verbose: ControllerMpd::sendMpdCommand status Dec 28 00:44:48 volumio volumio[2219]: info: sendMpdCommand status took 13 milliseconds Dec 28 00:44:48 volumio volumio[2219]: verbose: ControllerMpd::parseState Dec 28 00:44:49 volumio volumio[2219]: info: Command Router : Notfying DB Updatetrue Dec 28 00:44:49 volumio volumio[2219]: info: sendMpdCommand status took 246 milliseconds Dec 28 00:44:49 volumio volumio[2219]: info: sendMpdCommand status took 246 milliseconds Dec 28 00:44:49 volumio volumio[2219]: info: sendMpdCommand status took 245 milliseconds Dec 28 00:44:49 volumio volumio[2219]: info: sendMpdCommand status took 245 milliseconds Dec 28 00:44:49 volumio volumio[2219]: info: sendMpdCommand status took 244 milliseconds Dec 28 00:44:49 volumio volumio[2219]: verbose: ControllerMpd::parseState Dec 28 00:44:49 volumio volumio[2219]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 00:44:49 volumio volumio[2219]: verbose: ControllerMpd::parseState Dec 28 00:44:49 volumio volumio[2219]: info: Command Router : Notfying DB Updatetrue Dec 28 00:44:49 volumio volumio[2219]: info: CoreCommandRouter::Close All Modals sent Dec 28 00:44:49 volumio volumio[2219]: verbose: ControllerMpd::parseState Dec 28 00:44:49 volumio volumio[2219]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 00:44:49 volumio volumio[2219]: verbose: ControllerMpd::parseState Dec 28 00:44:49 volumio volumio[2219]: info: Command Router : Notfying DB Updatetrue Dec 28 00:44:49 volumio volumio[2219]: info: CoreCommandRouter::Close All Modals sent Dec 28 00:44:49 volumio volumio[2219]: verbose: ControllerMpd::parseState Dec 28 00:44:49 volumio volumio[2219]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 00:44:49 volumio volumio[2219]: info: sendMpdCommand playlistinfo took 122 milliseconds Dec 28 00:44:49 volumio volumio[2219]: info: sendMpdCommand playlistinfo took 79 milliseconds Dec 28 00:44:49 volumio volumio[2219]: info: sendMpdCommand playlistinfo took 47 milliseconds Dec 28 00:44:49 volumio volumio[2219]: verbose: ControllerMpd::parseTrackInfo Dec 28 00:44:49 volumio volumio[2219]: verbose: ControllerMpd::parseTrackInfo Dec 28 00:44:49 volumio volumio[2219]: verbose: ControllerMpd::parseTrackInfo Dec 28 00:44:49 volumio volumio[2219]: info: ControllerMpd::pushState Dec 28 00:44:49 volumio volumio[2219]: info: CoreCommandRouter::servicePushState Dec 28 00:44:49 volumio volumio[2219]: info: CorePlayQueue::getTrack 6 Dec 28 00:44:49 volumio volumio[2219]: verbose: STATE SERVICE {"status":"play","position":0,"seek":93213,"duration":237,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"485 Kbps","isStreaming":false,"title":"小夜曲","artist":"舒伯特","album":null,"uri":"USB/E2D8-2F33/经典古典/舒伯特-小夜曲.flac","trackType":"flac"} Dec 28 00:44:49 volumio volumio[2219]: verbose: CURRENT POSITION 6 Dec 28 00:44:49 volumio volumio[2219]: info: CoreStateMachine::syncState stateService play Dec 28 00:44:49 volumio volumio[2219]: info: CoreStateMachine::syncState currentStatus play Dec 28 00:44:49 volumio volumio[2219]: info: Received an update from plugin. extracting info from payload Dec 28 00:44:49 volumio volumio[2219]: info: CoreStateMachine::pushState Dec 28 00:44:49 volumio volumio[2219]: info: CorePlayQueue::getTrack 6 Dec 28 00:44:49 volumio volumio[2219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 00:44:49 volumio volumio[2219]: info: CoreCommandRouter::volumioPushState Dec 28 00:44:49 volumio volumio[2219]: info: CoreStateMachine::pushState Dec 28 00:44:49 volumio volumio[2219]: info: CorePlayQueue::getTrack 6 Dec 28 00:44:49 volumio volumio[2219]: info: CoreCommandRouter::volumioPushState Dec 28 00:44:49 volumio volumio[2219]: info: ControllerMpd::pushState Dec 28 00:44:49 volumio volumio[2219]: info: CoreCommandRouter::servicePushState Dec 28 00:44:49 volumio volumio[2219]: info: CorePlayQueue::getTrack 6 Dec 28 00:44:49 volumio volumio[2219]: verbose: STATE SERVICE {"status":"play","position":0,"seek":93213,"duration":237,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"485 Kbps","isStreaming":false,"title":"小夜曲","artist":"舒伯特","album":null,"uri":"USB/E2D8-2F33/经典古典/舒伯特-小夜曲.flac","trackType":"flac"} Dec 28 00:44:49 volumio volumio[2219]: verbose: CURRENT POSITION 6 Dec 28 00:44:49 volumio volumio[2219]: info: CoreStateMachine::syncState stateService play Dec 28 00:44:49 volumio volumio[2219]: info: CoreStateMachine::syncState currentStatus play Dec 28 00:44:49 volumio volumio[2219]: info: Received an update from plugin. extracting info from payload Dec 28 00:44:49 volumio volumio[2219]: info: CoreStateMachine::pushState Dec 28 00:44:49 volumio volumio[2219]: info: CorePlayQueue::getTrack 6 Dec 28 00:44:49 volumio volumio[2219]: info: CoreCommandRouter::volumioPushState Dec 28 00:44:49 volumio volumio[2219]: info: CoreStateMachine::pushState Dec 28 00:44:49 volumio volumio[2219]: info: CorePlayQueue::getTrack 6 Dec 28 00:44:49 volumio volumio[2219]: info: CoreCommandRouter::volumioPushState Dec 28 00:44:49 volumio volumio[2219]: info: ControllerMpd::pushState Dec 28 00:44:49 volumio volumio[2219]: info: CoreCommandRouter::servicePushState Dec 28 00:44:49 volumio volumio[2219]: info: CorePlayQueue::getTrack 6 Dec 28 00:44:49 volumio volumio[2219]: verbose: STATE SERVICE {"status":"play","position":0,"seek":93213,"duration":237,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"485 Kbps","isStreaming":false,"title":"小夜曲","artist":"舒伯特","album":null,"uri":"USB/E2D8-2F33/经典古典/舒伯特-小夜曲.flac","trackType":"flac"} Dec 28 00:44:49 volumio volumio[2219]: verbose: CURRENT POSITION 6 Dec 28 00:44:49 volumio volumio[2219]: info: CoreStateMachine::syncState stateService play Dec 28 00:44:49 volumio volumio[2219]: info: CoreStateMachine::syncState currentStatus play Dec 28 00:44:49 volumio volumio[2219]: info: Received an update from plugin. extracting info from payload Dec 28 00:44:49 volumio volumio[2219]: info: CoreStateMachine::pushState Dec 28 00:44:49 volumio volumio[2219]: info: CorePlayQueue::getTrack 6 Dec 28 00:44:49 volumio volumio[2219]: info: CoreCommandRouter::volumioPushState Dec 28 00:44:49 volumio volumio[2219]: info: CoreStateMachine::pushState Dec 28 00:44:49 volumio volumio[2219]: info: CorePlayQueue::getTrack 6 Dec 28 00:44:49 volumio volumio[2219]: info: CoreCommandRouter::volumioPushState Dec 28 00:44:49 volumio volumio[2219]: info: ------------------------------ 517ms Dec 28 00:44:49 volumio volumio[2219]: info: ------------------------------ 515ms Dec 28 00:44:49 volumio volumio[2219]: info: ------------------------------ 514ms Dec 28 00:44:50 volumio volumio[2219]: verbose: ControllerMpd::sendMpdCommand status Dec 28 00:44:50 volumio volumio[2219]: info: Dec 28 00:44:50 volumio volumio[2219]: ---------------------------- MPD announces state update: update Dec 28 00:44:50 volumio volumio[2219]: info: ControllerMpd::getState Dec 28 00:44:50 volumio volumio[2219]: verbose: ControllerMpd::sendMpdCommand status Dec 28 00:44:50 volumio volumio[2219]: verbose: ControllerMpd::sendMpdCommand status Dec 28 00:44:50 volumio volumio[2219]: info: Dec 28 00:44:50 volumio volumio[2219]: ---------------------------- MPD announces state update: update Dec 28 00:44:50 volumio volumio[2219]: info: ControllerMpd::getState Dec 28 00:44:50 volumio volumio[2219]: verbose: ControllerMpd::sendMpdCommand status Dec 28 00:44:50 volumio volumio[2219]: verbose: ControllerMpd::sendMpdCommand status Dec 28 00:44:50 volumio volumio[2219]: info: Dec 28 00:44:50 volumio volumio[2219]: ---------------------------- MPD announces state update: update Dec 28 00:44:50 volumio volumio[2219]: info: ControllerMpd::getState Dec 28 00:44:50 volumio volumio[2219]: verbose: ControllerMpd::sendMpdCommand status Dec 28 00:44:50 volumio volumio[2219]: info: sendMpdCommand status took 9 milliseconds Dec 28 00:44:50 volumio volumio[2219]: info: sendMpdCommand status took 7 milliseconds Dec 28 00:44:50 volumio volumio[2219]: info: sendMpdCommand status took 7 milliseconds Dec 28 00:44:50 volumio volumio[2219]: verbose: ControllerMpd::parseState Dec 28 00:44:50 volumio volumio[2219]: info: Command Router : Notfying DB Updatefalse Dec 28 00:44:50 volumio volumio[2219]: info: CoreCommandRouter::Close All Modals sent Dec 28 00:44:50 volumio volumio[2219]: verbose: ControllerMpd::parseState Dec 28 00:44:50 volumio volumio[2219]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 00:44:50 volumio volumio[2219]: verbose: ControllerMpd::parseState Dec 28 00:44:50 volumio volumio[2219]: info: Command Router : Notfying DB Updatefalse Dec 28 00:44:50 volumio volumio[2219]: info: CoreCommandRouter::Close All Modals sent Dec 28 00:44:50 volumio volumio[2219]: info: sendMpdCommand status took 85 milliseconds Dec 28 00:44:50 volumio volumio[2219]: info: sendMpdCommand status took 85 milliseconds Dec 28 00:44:50 volumio volumio[2219]: info: sendMpdCommand status took 84 milliseconds Dec 28 00:44:50 volumio volumio[2219]: info: sendMpdCommand playlistinfo took 50 milliseconds Dec 28 00:44:50 volumio volumio[2219]: verbose: ControllerMpd::parseState Dec 28 00:44:50 volumio volumio[2219]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 00:44:50 volumio volumio[2219]: verbose: ControllerMpd::parseState Dec 28 00:44:50 volumio volumio[2219]: info: Command Router : Notfying DB Updatefalse Dec 28 00:44:50 volumio volumio[2219]: info: CoreCommandRouter::Close All Modals sent Dec 28 00:44:50 volumio volumio[2219]: verbose: ControllerMpd::parseState Dec 28 00:44:50 volumio volumio[2219]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 00:44:50 volumio volumio[2219]: verbose: ControllerMpd::parseTrackInfo Dec 28 00:44:50 volumio volumio[2219]: info: ControllerMpd::pushState Dec 28 00:44:50 volumio volumio[2219]: info: CoreCommandRouter::servicePushState Dec 28 00:44:50 volumio volumio[2219]: info: CorePlayQueue::getTrack 6 Dec 28 00:44:50 volumio volumio[2219]: verbose: STATE SERVICE {"status":"play","position":0,"seek":94337,"duration":237,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"481 Kbps","isStreaming":false,"title":"小夜曲","artist":"舒伯特","album":null,"uri":"USB/E2D8-2F33/经典古典/舒伯特-小夜曲.flac","trackType":"flac"} Dec 28 00:44:50 volumio volumio[2219]: verbose: CURRENT POSITION 6 Dec 28 00:44:50 volumio volumio[2219]: info: CoreStateMachine::syncState stateService play Dec 28 00:44:50 volumio volumio[2219]: info: CoreStateMachine::syncState currentStatus play Dec 28 00:44:50 volumio volumio[2219]: info: Received an update from plugin. extracting info from payload Dec 28 00:44:50 volumio volumio[2219]: info: CoreStateMachine::pushState Dec 28 00:44:50 volumio volumio[2219]: info: CorePlayQueue::getTrack 6 Dec 28 00:44:50 volumio volumio[2219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 00:44:50 volumio volumio[2219]: info: CoreCommandRouter::volumioPushState Dec 28 00:44:50 volumio volumio[2219]: info: CoreStateMachine::pushState Dec 28 00:44:50 volumio volumio[2219]: info: CorePlayQueue::getTrack 6 Dec 28 00:44:50 volumio volumio[2219]: info: CoreCommandRouter::volumioPushState Dec 28 00:44:50 volumio volumio[2219]: info: ------------------------------ 173ms Dec 28 00:44:50 volumio volumio[2219]: info: sendMpdCommand playlistinfo took 101 milliseconds Dec 28 00:44:50 volumio volumio[2219]: info: sendMpdCommand playlistinfo took 65 milliseconds Dec 28 00:44:50 volumio volumio[2219]: verbose: ControllerMpd::parseTrackInfo Dec 28 00:44:50 volumio volumio[2219]: verbose: ControllerMpd::parseTrackInfo Dec 28 00:44:50 volumio volumio[2219]: info: ControllerMpd::pushState Dec 28 00:44:50 volumio volumio[2219]: info: CoreCommandRouter::servicePushState Dec 28 00:44:50 volumio volumio[2219]: info: CorePlayQueue::getTrack 6 Dec 28 00:44:50 volumio volumio[2219]: verbose: STATE SERVICE {"status":"play","position":0,"seek":94337,"duration":237,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"481 Kbps","isStreaming":false,"title":"小夜曲","artist":"舒伯特","album":null,"uri":"USB/E2D8-2F33/经典古典/舒伯特-小夜曲.flac","trackType":"flac"} Dec 28 00:44:50 volumio volumio[2219]: verbose: CURRENT POSITION 6 Dec 28 00:44:50 volumio volumio[2219]: info: CoreStateMachine::syncState stateService play Dec 28 00:44:50 volumio volumio[2219]: info: CoreStateMachine::syncState currentStatus play Dec 28 00:44:50 volumio volumio[2219]: info: Received an update from plugin. extracting info from payload Dec 28 00:44:50 volumio volumio[2219]: info: CoreStateMachine::pushState Dec 28 00:44:50 volumio volumio[2219]: info: CorePlayQueue::getTrack 6 Dec 28 00:44:50 volumio volumio[2219]: info: CoreCommandRouter::volumioPushState Dec 28 00:44:50 volumio volumio[2219]: info: CoreStateMachine::pushState Dec 28 00:44:50 volumio volumio[2219]: info: CorePlayQueue::getTrack 6 Dec 28 00:44:50 volumio volumio[2219]: info: CoreCommandRouter::volumioPushState Dec 28 00:44:50 volumio volumio[2219]: info: ControllerMpd::pushState Dec 28 00:44:50 volumio volumio[2219]: info: CoreCommandRouter::servicePushState Dec 28 00:44:50 volumio volumio[2219]: info: CorePlayQueue::getTrack 6 Dec 28 00:44:50 volumio volumio[2219]: verbose: STATE SERVICE {"status":"play","position":0,"seek":94337,"duration":237,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"481 Kbps","isStreaming":false,"title":"小夜曲","artist":"舒伯特","album":null,"uri":"USB/E2D8-2F33/经典古典/舒伯特-小夜曲.flac","trackType":"flac"} Dec 28 00:44:50 volumio volumio[2219]: verbose: CURRENT POSITION 6 Dec 28 00:44:50 volumio volumio[2219]: info: CoreStateMachine::syncState stateService play Dec 28 00:44:50 volumio volumio[2219]: info: CoreStateMachine::syncState currentStatus play Dec 28 00:44:50 volumio volumio[2219]: info: Received an update from plugin. extracting info from payload Dec 28 00:44:50 volumio volumio[2219]: info: CoreStateMachine::pushState Dec 28 00:44:50 volumio volumio[2219]: info: CorePlayQueue::getTrack 6 Dec 28 00:44:50 volumio volumio[2219]: info: CoreCommandRouter::volumioPushState Dec 28 00:44:50 volumio volumio[2219]: info: CoreStateMachine::pushState Dec 28 00:44:50 volumio volumio[2219]: info: CorePlayQueue::getTrack 6 Dec 28 00:44:50 volumio volumio[2219]: info: CoreCommandRouter::volumioPushState Dec 28 00:44:50 volumio volumio[2219]: info: ------------------------------ 263ms Dec 28 00:44:50 volumio volumio[2219]: info: ------------------------------ 261ms Dec 28 00:44:58 volumio volumio[2219]: info: Dec 28 00:44:58 volumio volumio[2219]: ---------------------------- MPD announces state update: player Dec 28 00:44:58 volumio volumio[2219]: info: ControllerMpd::getState Dec 28 00:44:58 volumio volumio[2219]: verbose: ControllerMpd::sendMpdCommand status Dec 28 00:44:58 volumio volumio[2219]: info: Dec 28 00:44:58 volumio volumio[2219]: ---------------------------- MPD announces state update: player Dec 28 00:44:58 volumio volumio[2219]: info: ControllerMpd::getState Dec 28 00:44:58 volumio volumio[2219]: verbose: ControllerMpd::sendMpdCommand status Dec 28 00:44:58 volumio volumio[2219]: info: Dec 28 00:44:58 volumio volumio[2219]: ---------------------------- MPD announces state update: player Dec 28 00:44:58 volumio volumio[2219]: info: ControllerMpd::getState Dec 28 00:44:58 volumio volumio[2219]: verbose: ControllerMpd::sendMpdCommand status Dec 28 00:44:58 volumio volumio[2219]: info: sendMpdCommand status took 6 milliseconds Dec 28 00:44:58 volumio volumio[2219]: info: sendMpdCommand status took 3 milliseconds Dec 28 00:44:58 volumio volumio[2219]: verbose: ControllerMpd::parseState Dec 28 00:44:58 volumio volumio[2219]: verbose: ControllerMpd::parseState Dec 28 00:44:58 volumio volumio[2219]: info: ControllerMpd::pushState Dec 28 00:44:58 volumio volumio[2219]: info: CoreCommandRouter::servicePushState Dec 28 00:44:58 volumio volumio[2219]: info: CoreStateMachine::pushState Dec 28 00:44:58 volumio volumio[2219]: info: CorePlayQueue::getTrack 6 Dec 28 00:44:58 volumio volumio[2219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 00:44:58 volumio volumio[2219]: info: CoreCommandRouter::volumioPushState Dec 28 00:44:58 volumio volumio[2219]: info: CorePlayQueue::getTrack 6 Dec 28 00:44:58 volumio volumio[2219]: 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 28 00:44:58 volumio volumio[2219]: verbose: CURRENT POSITION 6 Dec 28 00:44:58 volumio volumio[2219]: info: CoreStateMachine::syncState stateService stop Dec 28 00:44:58 volumio volumio[2219]: info: CoreStateMachine::syncState currentStatus play Dec 28 00:44:58 volumio volumio[2219]: info: CoreStateMachine::play index undefined Dec 28 00:44:58 volumio volumio[2219]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 00:44:58 volumio volumio[2219]: info: CoreStateMachine::pushState Dec 28 00:44:58 volumio volumio[2219]: info: CorePlayQueue::getTrack 7 Dec 28 00:44:58 volumio volumio[2219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 00:44:58 volumio volumio[2219]: info: CoreCommandRouter::volumioPushState Dec 28 00:44:58 volumio volumio[2219]: info: ControllerMpd::pushState Dec 28 00:44:58 volumio volumio[2219]: info: CoreCommandRouter::servicePushState Dec 28 00:44:58 volumio volumio[2219]: info: CoreStateMachine::pushState Dec 28 00:44:58 volumio volumio[2219]: info: CorePlayQueue::getTrack 7 Dec 28 00:44:58 volumio volumio[2219]: info: CoreCommandRouter::volumioPushState Dec 28 00:44:58 volumio volumio[2219]: info: CorePlayQueue::getTrack 7 Dec 28 00:44:58 volumio volumio[2219]: 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 28 00:44:58 volumio volumio[2219]: verbose: CURRENT POSITION 7 Dec 28 00:44:58 volumio volumio[2219]: info: CoreStateMachine::syncState stateService stop Dec 28 00:44:58 volumio volumio[2219]: info: CoreStateMachine::syncState currentStatus stop Dec 28 00:44:58 volumio volumio[2219]: info: CoreStateMachine::pushState Dec 28 00:44:58 volumio volumio[2219]: info: CorePlayQueue::getTrack 7 Dec 28 00:44:58 volumio volumio[2219]: info: CoreCommandRouter::volumioPushState Dec 28 00:44:58 volumio volumio[2219]: info: No code Dec 28 00:44:58 volumio volumio[2219]: info: CoreStateMachine::pushState Dec 28 00:44:58 volumio volumio[2219]: info: CorePlayQueue::getTrack 7 Dec 28 00:44:58 volumio volumio[2219]: info: CoreCommandRouter::volumioPushState Dec 28 00:44:58 volumio volumio[2219]: info: CorePlayQueue::getTrack 7 Dec 28 00:44:58 volumio volumio[2219]: info: CoreStateMachine::startPlaybackTimer Dec 28 00:44:58 volumio volumio[2219]: info: CorePlayQueue::getTrack 7 Dec 28 00:44:58 volumio volumio[2219]: verbose: ControllerMpd::clearAddPlayTracks USB/E2D8-2F33/经典古典/崔世光-山泉.flac Dec 28 00:44:58 volumio volumio[2219]: verbose: ControllerMpd::sendMpdCommand stop Dec 28 00:44:58 volumio volumio[2219]: info: ------------------------------ 130ms Dec 28 00:44:58 volumio volumio[2219]: info: ------------------------------ 129ms Dec 28 00:44:58 volumio volumio[2219]: info: CoreStateMachine::pushState Dec 28 00:44:58 volumio volumio[2219]: info: CorePlayQueue::getTrack 7 Dec 28 00:44:58 volumio volumio[2219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 00:44:58 volumio volumio[2219]: info: CoreCommandRouter::volumioPushState Dec 28 00:44:58 volumio volumio[2219]: info: sendMpdCommand status took 147 milliseconds Dec 28 00:44:58 volumio volumio[2219]: info: sendMpdCommand stop took 26 milliseconds Dec 28 00:44:58 volumio volumio[2219]: verbose: ControllerMpd::parseState Dec 28 00:44:58 volumio volumio[2219]: verbose: ControllerMpd::sendMpdCommand clear Dec 28 00:44:58 volumio volumio[2219]: info: ControllerMpd::pushState Dec 28 00:44:58 volumio volumio[2219]: info: CoreCommandRouter::servicePushState Dec 28 00:44:58 volumio volumio[2219]: info: CoreStateMachine::pushState Dec 28 00:44:58 volumio volumio[2219]: info: CorePlayQueue::getTrack 7 Dec 28 00:44:58 volumio volumio[2219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 00:44:58 volumio volumio[2219]: info: CoreCommandRouter::volumioPushState Dec 28 00:44:58 volumio volumio[2219]: info: CorePlayQueue::getTrack 7 Dec 28 00:44:58 volumio volumio[2219]: 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 28 00:44:58 volumio volumio[2219]: verbose: CURRENT POSITION 7 Dec 28 00:44:58 volumio volumio[2219]: info: CoreStateMachine::syncState stateService stop Dec 28 00:44:58 volumio volumio[2219]: info: CoreStateMachine::syncState currentStatus stop Dec 28 00:44:58 volumio volumio[2219]: info: CoreStateMachine::pushState Dec 28 00:44:58 volumio volumio[2219]: info: CorePlayQueue::getTrack 7 Dec 28 00:44:58 volumio volumio[2219]: info: CoreCommandRouter::volumioPushState Dec 28 00:44:58 volumio volumio[2219]: info: No code Dec 28 00:44:58 volumio volumio[2219]: info: CoreStateMachine::pushState Dec 28 00:44:58 volumio volumio[2219]: info: CorePlayQueue::getTrack 7 Dec 28 00:44:58 volumio volumio[2219]: info: CoreCommandRouter::volumioPushState Dec 28 00:44:58 volumio volumio[2219]: info: ------------------------------ 209ms Dec 28 00:44:59 volumio volumio[2219]: info: Dec 28 00:44:59 volumio volumio[2219]: ---------------------------- MPD announces system playlist update Dec 28 00:44:59 volumio volumio[2219]: info: Ignoring MPD Status Update Dec 28 00:44:59 volumio volumio[2219]: info: sendMpdCommand clear took 107 milliseconds Dec 28 00:44:59 volumio volumio[2219]: verbose: ControllerMpd::sendMpdCommand add "USB/E2D8-2F33/经典古典/崔世光-山泉.flac" Dec 28 00:44:59 volumio volumio[2219]: info: Dec 28 00:44:59 volumio volumio[2219]: ---------------------------- MPD announces system playlist update Dec 28 00:44:59 volumio volumio[2219]: info: Ignoring MPD Status Update Dec 28 00:44:59 volumio volumio[2219]: info: Dec 28 00:44:59 volumio volumio[2219]: ---------------------------- MPD announces system playlist update Dec 28 00:44:59 volumio volumio[2219]: info: Ignoring MPD Status Update Dec 28 00:44:59 volumio volumio[2219]: error: updateQueue error: null Dec 28 00:44:59 volumio volumio[2219]: info: Dec 28 00:44:59 volumio volumio[2219]: ---------------------------- MPD announces system playlist update Dec 28 00:44:59 volumio volumio[2219]: info: Ignoring MPD Status Update Dec 28 00:44:59 volumio volumio[2219]: info: ------------------------------ 16ms Dec 28 00:44:59 volumio volumio[2219]: info: sendMpdCommand add "USB/E2D8-2F33/经典古典/崔世光-山泉.flac" took 14 milliseconds Dec 28 00:44:59 volumio volumio[2219]: info: ------------------------------ 13ms Dec 28 00:44:59 volumio volumio[2219]: info: ------------------------------ 11ms Dec 28 00:44:59 volumio volumio[2219]: verbose: ControllerMpd::sendMpdCommand play Dec 28 00:44:59 volumio volumio[2219]: info: Dec 28 00:44:59 volumio volumio[2219]: ---------------------------- MPD announces system playlist update Dec 28 00:44:59 volumio volumio[2219]: info: Ignoring MPD Status Update Dec 28 00:44:59 volumio volumio[2219]: info: Dec 28 00:44:59 volumio volumio[2219]: ---------------------------- MPD announces system playlist update Dec 28 00:44:59 volumio volumio[2219]: info: Ignoring MPD Status Update Dec 28 00:44:59 volumio volumio[2219]: info: ------------------------------ 19ms Dec 28 00:44:59 volumio volumio[2219]: info: sendMpdCommand play took 16 milliseconds Dec 28 00:44:59 volumio volumio[2219]: info: ------------------------------ 15ms Dec 28 00:44:59 volumio volumio[2219]: info: ------------------------------ 13ms Dec 28 00:44:59 volumio volumio[2219]: info: Dec 28 00:44:59 volumio volumio[2219]: ---------------------------- MPD announces state update: player Dec 28 00:44:59 volumio volumio[2219]: info: ControllerMpd::getState Dec 28 00:44:59 volumio volumio[2219]: verbose: ControllerMpd::sendMpdCommand status Dec 28 00:44:59 volumio volumio[2219]: info: Dec 28 00:44:59 volumio volumio[2219]: ---------------------------- MPD announces state update: player Dec 28 00:44:59 volumio volumio[2219]: info: ControllerMpd::getState Dec 28 00:44:59 volumio volumio[2219]: verbose: ControllerMpd::sendMpdCommand status Dec 28 00:44:59 volumio volumio[2219]: info: Dec 28 00:44:59 volumio volumio[2219]: ---------------------------- MPD announces state update: player Dec 28 00:44:59 volumio volumio[2219]: info: ControllerMpd::getState Dec 28 00:44:59 volumio volumio[2219]: verbose: ControllerMpd::sendMpdCommand status Dec 28 00:44:59 volumio volumio[2219]: info: Dec 28 00:44:59 volumio volumio[2219]: ---------------------------- MPD announces state update: player Dec 28 00:44:59 volumio volumio[2219]: info: ControllerMpd::getState Dec 28 00:44:59 volumio volumio[2219]: verbose: ControllerMpd::sendMpdCommand status Dec 28 00:44:59 volumio volumio[2219]: info: sendMpdCommand status took 15 milliseconds Dec 28 00:44:59 volumio volumio[2219]: verbose: ControllerMpd::parseState Dec 28 00:44:59 volumio volumio[2219]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 00:44:59 volumio volumio[2219]: info: Dec 28 00:44:59 volumio volumio[2219]: ---------------------------- MPD announces state update: player Dec 28 00:44:59 volumio volumio[2219]: info: ControllerMpd::getState Dec 28 00:44:59 volumio volumio[2219]: verbose: ControllerMpd::sendMpdCommand status Dec 28 00:44:59 volumio volumio[2219]: info: sendMpdCommand status took 18 milliseconds Dec 28 00:44:59 volumio volumio[2219]: info: sendMpdCommand status took 15 milliseconds Dec 28 00:44:59 volumio volumio[2219]: info: sendMpdCommand status took 13 milliseconds Dec 28 00:44:59 volumio volumio[2219]: info: sendMpdCommand playlistinfo took 10 milliseconds Dec 28 00:44:59 volumio volumio[2219]: verbose: ControllerMpd::parseState Dec 28 00:44:59 volumio volumio[2219]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 00:44:59 volumio volumio[2219]: verbose: ControllerMpd::parseState Dec 28 00:44:59 volumio volumio[2219]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 00:44:59 volumio volumio[2219]: verbose: ControllerMpd::parseState Dec 28 00:44:59 volumio volumio[2219]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 00:44:59 volumio volumio[2219]: verbose: ControllerMpd::parseTrackInfo Dec 28 00:44:59 volumio volumio[2219]: info: ControllerMpd::pushState Dec 28 00:44:59 volumio volumio[2219]: info: CoreCommandRouter::servicePushState Dec 28 00:44:59 volumio volumio[2219]: info: CorePlayQueue::getTrack 7 Dec 28 00:44:59 volumio volumio[2219]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":311,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"山泉","artist":"崔世光","album":"Chinese Piano: Vol. 1 - Shepherd Boys Flute (Gang Qin Zhong Guo Yi: Mu Tong Duan Di)","uri":"USB/E2D8-2F33/经典古典/崔世光-山泉.flac","trackType":"flac"} Dec 28 00:44:59 volumio volumio[2219]: verbose: CURRENT POSITION 7 Dec 28 00:44:59 volumio volumio[2219]: info: CoreStateMachine::syncState stateService play Dec 28 00:44:59 volumio volumio[2219]: info: CoreStateMachine::syncState currentStatus stop Dec 28 00:44:59 volumio volumio[2219]: info: ------------------------------ 38ms Dec 28 00:44:59 volumio volumio[2219]: info: Dec 28 00:44:59 volumio volumio[2219]: ---------------------------- MPD announces state update: player Dec 28 00:44:59 volumio volumio[2219]: info: ControllerMpd::getState Dec 28 00:44:59 volumio volumio[2219]: verbose: ControllerMpd::sendMpdCommand status Dec 28 00:44:59 volumio volumio[2219]: info: sendMpdCommand status took 20 milliseconds Dec 28 00:44:59 volumio volumio[2219]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 28 00:44:59 volumio volumio[2219]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 28 00:44:59 volumio volumio[2219]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 28 00:44:59 volumio volumio[2219]: info: sendMpdCommand status took 6 milliseconds Dec 28 00:44:59 volumio volumio[2219]: verbose: ControllerMpd::parseState Dec 28 00:44:59 volumio volumio[2219]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 00:44:59 volumio volumio[2219]: verbose: ControllerMpd::parseTrackInfo Dec 28 00:44:59 volumio volumio[2219]: verbose: ControllerMpd::parseTrackInfo Dec 28 00:44:59 volumio volumio[2219]: verbose: ControllerMpd::parseTrackInfo Dec 28 00:44:59 volumio volumio[2219]: verbose: ControllerMpd::parseState Dec 28 00:44:59 volumio volumio[2219]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 00:44:59 volumio volumio[2219]: info: ControllerMpd::pushState Dec 28 00:44:59 volumio volumio[2219]: info: CoreCommandRouter::servicePushState Dec 28 00:44:59 volumio volumio[2219]: info: CorePlayQueue::getTrack 7 Dec 28 00:44:59 volumio volumio[2219]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":311,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"山泉","artist":"崔世光","album":"Chinese Piano: Vol. 1 - Shepherd Boys Flute (Gang Qin Zhong Guo Yi: Mu Tong Duan Di)","uri":"USB/E2D8-2F33/经典古典/崔世光-山泉.flac","trackType":"flac"} Dec 28 00:44:59 volumio volumio[2219]: verbose: CURRENT POSITION 7 Dec 28 00:44:59 volumio volumio[2219]: info: CoreStateMachine::syncState stateService play Dec 28 00:44:59 volumio volumio[2219]: info: CoreStateMachine::syncState currentStatus play Dec 28 00:44:59 volumio volumio[2219]: info: Received an update from plugin. extracting info from payload Dec 28 00:44:59 volumio volumio[2219]: info: CoreStateMachine::pushState Dec 28 00:44:59 volumio volumio[2219]: info: CorePlayQueue::getTrack 7 Dec 28 00:44:59 volumio volumio[2219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 00:44:59 volumio volumio[2219]: info: CoreCommandRouter::volumioPushState Dec 28 00:44:59 volumio volumio[2219]: info: CoreStateMachine::pushState Dec 28 00:44:59 volumio volumio[2219]: info: CorePlayQueue::getTrack 7 Dec 28 00:44:59 volumio volumio[2219]: info: CoreCommandRouter::volumioPushState Dec 28 00:44:59 volumio volumio[2219]: info: ControllerMpd::pushState Dec 28 00:44:59 volumio volumio[2219]: info: CoreCommandRouter::servicePushState Dec 28 00:44:59 volumio volumio[2219]: info: CorePlayQueue::getTrack 7 Dec 28 00:44:59 volumio volumio[2219]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":311,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"山泉","artist":"崔世光","album":"Chinese Piano: Vol. 1 - Shepherd Boys Flute (Gang Qin Zhong Guo Yi: Mu Tong Duan Di)","uri":"USB/E2D8-2F33/经典古典/崔世光-山泉.flac","trackType":"flac"} Dec 28 00:44:59 volumio volumio[2219]: verbose: CURRENT POSITION 7 Dec 28 00:44:59 volumio volumio[2219]: info: CoreStateMachine::syncState stateService play Dec 28 00:44:59 volumio volumio[2219]: info: CoreStateMachine::syncState currentStatus play Dec 28 00:44:59 volumio volumio[2219]: info: Received an update from plugin. extracting info from payload Dec 28 00:44:59 volumio volumio[2219]: info: CoreStateMachine::pushState Dec 28 00:44:59 volumio volumio[2219]: info: CorePlayQueue::getTrack 7 Dec 28 00:44:59 volumio volumio[2219]: info: CoreCommandRouter::volumioPushState Dec 28 00:44:59 volumio volumio[2219]: info: CoreStateMachine::pushState Dec 28 00:44:59 volumio volumio[2219]: info: CorePlayQueue::getTrack 7 Dec 28 00:44:59 volumio volumio[2219]: info: CoreCommandRouter::volumioPushState Dec 28 00:44:59 volumio volumio[2219]: info: ControllerMpd::pushState Dec 28 00:44:59 volumio volumio[2219]: info: CoreCommandRouter::servicePushState Dec 28 00:44:59 volumio volumio[2219]: info: CorePlayQueue::getTrack 7 Dec 28 00:44:59 volumio volumio[2219]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":311,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"山泉","artist":"崔世光","album":"Chinese Piano: Vol. 1 - Shepherd Boys Flute (Gang Qin Zhong Guo Yi: Mu Tong Duan Di)","uri":"USB/E2D8-2F33/经典古典/崔世光-山泉.flac","trackType":"flac"} Dec 28 00:44:59 volumio volumio[2219]: verbose: CURRENT POSITION 7 Dec 28 00:44:59 volumio volumio[2219]: info: CoreStateMachine::syncState stateService play Dec 28 00:44:59 volumio volumio[2219]: info: CoreStateMachine::syncState currentStatus play Dec 28 00:44:59 volumio volumio[2219]: info: Received an update from plugin. extracting info from payload Dec 28 00:44:59 volumio volumio[2219]: info: CoreStateMachine::pushState Dec 28 00:44:59 volumio volumio[2219]: info: CorePlayQueue::getTrack 7 Dec 28 00:44:59 volumio volumio[2219]: info: CoreCommandRouter::volumioPushState Dec 28 00:44:59 volumio volumio[2219]: info: CoreStateMachine::pushState Dec 28 00:44:59 volumio volumio[2219]: info: CorePlayQueue::getTrack 7 Dec 28 00:44:59 volumio volumio[2219]: info: CoreCommandRouter::volumioPushState Dec 28 00:44:59 volumio volumio[2219]: info: ------------------------------ 190ms Dec 28 00:44:59 volumio volumio[2219]: info: ------------------------------ 188ms Dec 28 00:44:59 volumio volumio[2219]: info: ------------------------------ 187ms Dec 28 00:44:59 volumio volumio[2219]: info: sendMpdCommand playlistinfo took 152 milliseconds Dec 28 00:44:59 volumio volumio[2219]: info: sendMpdCommand playlistinfo took 150 milliseconds Dec 28 00:44:59 volumio volumio[2219]: verbose: ControllerMpd::parseTrackInfo Dec 28 00:44:59 volumio volumio[2219]: verbose: ControllerMpd::parseTrackInfo Dec 28 00:44:59 volumio volumio[2219]: info: ControllerMpd::pushState Dec 28 00:44:59 volumio volumio[2219]: info: CoreCommandRouter::servicePushState Dec 28 00:44:59 volumio volumio[2219]: info: CorePlayQueue::getTrack 7 Dec 28 00:44:59 volumio volumio[2219]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":311,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"170 Kbps","isStreaming":false,"title":"山泉","artist":"崔世光","album":"Chinese Piano: Vol. 1 - Shepherd Boys Flute (Gang Qin Zhong Guo Yi: Mu Tong Duan Di)","uri":"USB/E2D8-2F33/经典古典/崔世光-山泉.flac","trackType":"flac"} Dec 28 00:44:59 volumio volumio[2219]: verbose: CURRENT POSITION 7 Dec 28 00:44:59 volumio volumio[2219]: info: CoreStateMachine::syncState stateService play Dec 28 00:44:59 volumio volumio[2219]: info: CoreStateMachine::syncState currentStatus play Dec 28 00:44:59 volumio volumio[2219]: info: Received an update from plugin. extracting info from payload Dec 28 00:44:59 volumio volumio[2219]: info: CoreStateMachine::pushState Dec 28 00:44:59 volumio volumio[2219]: info: CorePlayQueue::getTrack 7 Dec 28 00:44:59 volumio volumio[2219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 00:44:59 volumio volumio[2219]: info: CoreCommandRouter::volumioPushState Dec 28 00:44:59 volumio volumio[2219]: info: CoreStateMachine::pushState Dec 28 00:44:59 volumio volumio[2219]: info: CorePlayQueue::getTrack 7 Dec 28 00:44:59 volumio volumio[2219]: info: CoreCommandRouter::volumioPushState Dec 28 00:44:59 volumio volumio[2219]: info: ControllerMpd::pushState Dec 28 00:44:59 volumio volumio[2219]: info: CoreCommandRouter::servicePushState Dec 28 00:44:59 volumio volumio[2219]: info: CorePlayQueue::getTrack 7 Dec 28 00:44:59 volumio volumio[2219]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":311,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"171 Kbps","isStreaming":false,"title":"山泉","artist":"崔世光","album":"Chinese Piano: Vol. 1 - Shepherd Boys Flute (Gang Qin Zhong Guo Yi: Mu Tong Duan Di)","uri":"USB/E2D8-2F33/经典古典/崔世光-山泉.flac","trackType":"flac"} Dec 28 00:44:59 volumio volumio[2219]: verbose: CURRENT POSITION 7 Dec 28 00:44:59 volumio volumio[2219]: info: CoreStateMachine::syncState stateService play Dec 28 00:44:59 volumio volumio[2219]: info: CoreStateMachine::syncState currentStatus play Dec 28 00:44:59 volumio volumio[2219]: info: Received an update from plugin. extracting info from payload Dec 28 00:44:59 volumio volumio[2219]: info: CoreStateMachine::pushState Dec 28 00:44:59 volumio volumio[2219]: info: CorePlayQueue::getTrack 7 Dec 28 00:44:59 volumio volumio[2219]: info: CoreCommandRouter::volumioPushState Dec 28 00:44:59 volumio volumio[2219]: info: CoreStateMachine::pushState Dec 28 00:44:59 volumio volumio[2219]: info: CorePlayQueue::getTrack 7 Dec 28 00:44:59 volumio volumio[2219]: info: CoreCommandRouter::volumioPushState Dec 28 00:44:59 volumio volumio[2219]: info: ------------------------------ 253ms Dec 28 00:44:59 volumio volumio[2219]: info: ------------------------------ 237ms Dec 28 00:45:06 volumio volumio[2219]: info: CoreCommandRouter::volumioGetState Dec 28 00:45:06 volumio volumio[2219]: info: CorePlayQueue::getTrack 7 Dec 28 00:45:08 volumio volumio[2219]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 28 00:45:08 volumio volumio[2219]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 28 00:45:08 volumio volumio[2219]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 28 00:45:08 volumio sudo[4284]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 28 00:45:08 volumio sudo[4284]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 00:45:08 volumio sudo[4289]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 28 00:45:08 volumio sudo[4289]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 00:45:08 volumio sudo[4289]: pam_unix(sudo:session): session closed for user root Dec 28 00:45:08 volumio sudo[4284]: pam_unix(sudo:session): session closed for user root Dec 28 00:45:08 volumio sudo[4295]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 28 00:45:08 volumio sudo[4295]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 00:45:08 volumio sudo[4295]: pam_unix(sudo:session): session closed for user root Dec 28 00:45:08 volumio sudo[4306]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 28 00:45:08 volumio sudo[4300]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 28 00:45:08 volumio sudo[4306]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 00:45:08 volumio volumio[2219]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Dec 28 00:45:08 volumio volumio[2219]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Dec 28 00:45:08 volumio sudo[4300]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 00:45:08 volumio sudo[4300]: pam_unix(sudo:session): session closed for user root Dec 28 00:45:08 volumio sudo[4306]: pam_unix(sudo:session): session closed for user root Dec 28 00:45:08 volumio sudo[4311]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 28 00:45:08 volumio sudo[4311]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 00:45:08 volumio sudo[4311]: pam_unix(sudo:session): session closed for user root Dec 28 00:45:08 volumio sudo[4316]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 28 00:45:08 volumio sudo[4316]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 00:45:11 volumio volumio[2219]: info: CoreCommandRouter::volumioGetState Dec 28 00:45:11 volumio volumio[2219]: info: CorePlayQueue::getTrack 7 Dec 28 00:45:12 volumio sudo[4316]: pam_unix(sudo:session): session closed for user root Dec 28 00:45:19 volumio sudo[4338]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 28 00:45:19 volumio sudo[4338]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 00:45:19 volumio sudo[4338]: pam_unix(sudo:session): session closed for user root Dec 28 00:45:19 volumio sudo[4340]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 28 00:45:19 volumio sudo[4340]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 00:45:19 volumio sudo[4340]: pam_unix(sudo:session): session closed for user root Dec 28 00:45:19 volumio volumio[2219]: verbose: New Socket.io Connection to 192.168.50.55 from 192.168.50.204 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/97.0.4692.98 Safari/537.36 T7/13.38 languageType/0 bdh_dvt/1 bdh_de/1 bdh_ds/1 bdhonorbrowser/9.7.0.3 (P1 14) Engine version: 3 Transport: polling Total Clients: 4 Dec 28 00:45:20 volumio sudo[4344]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 28 00:45:20 volumio sudo[4344]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 00:45:20 volumio sudo[4346]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 28 00:45:20 volumio sudo[4346]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 00:45:20 volumio sudo[4344]: pam_unix(sudo:session): session closed for user root Dec 28 00:45:20 volumio sudo[4346]: pam_unix(sudo:session): session closed for user root Dec 28 00:45:20 volumio volumio[2219]: verbose: New Socket.io Connection to 192.168.50.55 from 192.168.50.204 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/97.0.4692.98 Safari/537.36 T7/13.38 languageType/0 bdh_dvt/1 bdh_de/1 bdh_ds/1 bdhonorbrowser/9.7.0.3 (P1 14) Engine version: 3 Transport: polling Total Clients: 5 Dec 28 00:45:20 volumio volumio[2219]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 28 00:45:20 volumio volumio[2219]: info: CoreCommandRouter::volumioGetVisibleSources Dec 28 00:45:20 volumio volumio[2219]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 00:45:20 volumio volumio[2219]: info: CoreCommandRouter::volumioGetState Dec 28 00:45:20 volumio volumio[2219]: info: CorePlayQueue::getTrack 7 Dec 28 00:45:20 volumio volumio[2219]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 28 00:45:20 volumio volumio[2219]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 28 00:45:20 volumio volumio[2219]: info: Received Get System Info Dec 28 00:45:20 volumio volumio[2219]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 28 00:45:20 volumio volumio[2219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 28 00:45:20 volumio volumio[2219]: info: Discovery: Getting this device information Dec 28 00:45:20 volumio volumio[2219]: info: CoreCommandRouter::volumioGetState Dec 28 00:45:20 volumio volumio[2219]: info: CorePlayQueue::getTrack 7 Dec 28 00:45:20 volumio volumio[2219]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 28 00:45:20 volumio volumio[2219]: info: CoreCommandRouter::volumioGetState Dec 28 00:45:20 volumio volumio[2219]: info: CorePlayQueue::getTrack 7 Dec 28 00:45:21 volumio volumio[2219]: info: Listing playlists Dec 28 00:45:21 volumio volumio[2219]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 28 00:45:21 volumio volumio[2219]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 28 00:45:21 volumio volumio[2219]: info: CoreCommandRouter::volumioGetState Dec 28 00:45:21 volumio volumio[2219]: info: CorePlayQueue::getTrack 7 Dec 28 00:45:22 volumio volumio[2219]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 28 00:45:22 volumio volumio[2219]: info: Received Get System Info Dec 28 00:45:22 volumio volumio[2219]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 28 00:45:22 volumio volumio[2219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 28 00:45:22 volumio volumio[2219]: info: Discovery: Getting this device information Dec 28 00:45:22 volumio volumio[2219]: info: CoreCommandRouter::volumioGetState Dec 28 00:45:22 volumio volumio[2219]: info: CorePlayQueue::getTrack 7 Dec 28 00:45:22 volumio volumio[2219]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 28 00:45:22 volumio volumio[2219]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 28 00:45:25 volumio volumio[2219]: info: CoreCommandRouter::volumioGetQueue Dec 28 00:45:25 volumio volumio[2219]: info: CoreStateMachine::getQueue Dec 28 00:45:25 volumio volumio[2219]: info: CorePlayQueue::getQueue Dec 28 00:45:27 volumio volumio[2219]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 28 00:45:27 volumio volumio[2219]: info: CURURI: music-library Dec 28 00:45:27 volumio volumio[2219]: info: Preload queue cleared Dec 28 00:45:29 volumio volumio[2219]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 28 00:45:29 volumio volumio[2219]: info: CURURI: music-library/USB Dec 28 00:45:29 volumio volumio[2219]: info: Preload queue cleared Dec 28 00:45:30 volumio volumio[2219]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 28 00:45:30 volumio volumio[2219]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 28 00:45:30 volumio volumio[2219]: info: CURURI: music-library/USB/E2D8-2F33 Dec 28 00:45:30 volumio volumio[2219]: info: Preload queue cleared Dec 28 00:45:31 volumio volumio[2219]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 00:45:31 volumio volumio[2219]: Error: connect ETIMEDOUT 157.240.1.33:80 Dec 28 00:45:31 volumio volumio[2219]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Dec 28 00:45:31 volumio volumio[2219]: errno: -110, Dec 28 00:45:31 volumio volumio[2219]: code: 'ETIMEDOUT', Dec 28 00:45:31 volumio volumio[2219]: syscall: 'connect', Dec 28 00:45:31 volumio volumio[2219]: address: '157.240.1.33', Dec 28 00:45:31 volumio volumio[2219]: port: 80 Dec 28 00:45:31 volumio volumio[2219]: } Dec 28 00:45:31 volumio volumio[2219]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 00:45:32 volumio sudo[4379]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-28 00:44' Dec 28 00:45:32 volumio sudo[4379]: 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"