Mar 29 20:08:06 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 20:08:06 volumio volumio[1138]: info: CURURI: artists://%E5%BC%A0%E6%A2%A6%E5%BC%98 Mar 29 20:08:06 volumio volumio[1138]: info: Preload queue cleared Mar 29 20:08:06 volumio volumio[1138]: info: Preload queue cleared Mar 29 20:08:06 volumio volumio[1138]: info: No valid Plugin REST Endpoint Mar 29 20:08:06 volumio volumio[1138]: info: No valid Plugin REST Endpoint Mar 29 20:08:06 volumio volumio[1138]: info: No valid Plugin REST Endpoint Mar 29 20:08:06 volumio volumio[1138]: info: No valid Plugin REST Endpoint Mar 29 20:08:15 volumio volumio[1138]: info: CoreCommandRouter::volumioGetState Mar 29 20:08:15 volumio volumio[1138]: info: CorePlayQueue::getTrack 21 Mar 29 20:08:52 volumio sudo[3218]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 29 20:08:52 volumio sudo[3218]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 20:08:52 volumio sudo[3218]: pam_unix(sudo:session): session closed for user root Mar 29 20:08:52 volumio sudo[3220]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 29 20:08:52 volumio sudo[3220]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 20:08:52 volumio sudo[3220]: pam_unix(sudo:session): session closed for user root Mar 29 20:08:52 volumio volumio[1138]: verbose: New Socket.io Connection to 192.168.1.2 from 192.168.1.3 UA: Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/55.0.2883.87 UBrowser/6.2.4098.3 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Mar 29 20:08:53 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 29 20:08:53 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 29 20:08:53 volumio sudo[3224]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 29 20:08:53 volumio sudo[3224]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 20:08:53 volumio sudo[3224]: pam_unix(sudo:session): session closed for user root Mar 29 20:08:53 volumio sudo[3226]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 29 20:08:53 volumio sudo[3226]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 20:08:53 volumio sudo[3226]: pam_unix(sudo:session): session closed for user root Mar 29 20:08:53 volumio volumio[1138]: verbose: New Socket.io Connection to 192.168.1.2 from 192.168.1.3 UA: Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/55.0.2883.87 UBrowser/6.2.4098.3 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Mar 29 20:08:54 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 29 20:08:54 volumio volumio[1138]: info: CoreCommandRouter::volumioGetVisibleSources Mar 29 20:08:54 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 20:08:54 volumio volumio[1138]: info: CoreCommandRouter::volumioGetState Mar 29 20:08:54 volumio volumio[1138]: info: CorePlayQueue::getTrack 21 Mar 29 20:08:54 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 29 20:08:54 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 29 20:08:54 volumio volumio[1138]: info: Received Get System Info Mar 29 20:08:54 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 20:08:54 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 20:08:54 volumio volumio[1138]: info: Discovery: Getting this device information Mar 29 20:08:54 volumio volumio[1138]: info: CoreCommandRouter::volumioGetState Mar 29 20:08:54 volumio volumio[1138]: info: CorePlayQueue::getTrack 21 Mar 29 20:08:54 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 20:08:54 volumio volumio[1138]: info: CoreCommandRouter::volumioGetState Mar 29 20:08:54 volumio volumio[1138]: info: CorePlayQueue::getTrack 21 Mar 29 20:08:54 volumio volumio[1138]: info: Listing playlists Mar 29 20:08:54 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 29 20:08:54 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 29 20:08:54 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 29 20:08:54 volumio volumio[1138]: info: CoreCommandRouter::volumioGetState Mar 29 20:08:54 volumio volumio[1138]: info: CorePlayQueue::getTrack 21 Mar 29 20:08:55 volumio volumio[1138]: info: CoreCommandRouter::volumioPause Mar 29 20:08:55 volumio volumio[1138]: info: CoreStateMachine::pause Mar 29 20:08:55 volumio volumio[1138]: info: CoreStateMachine::stPlaybackTimer Mar 29 20:08:55 volumio volumio[1138]: info: CoreStateMachine::servicePause Mar 29 20:08:55 volumio volumio[1138]: info: CorePlayQueue::getTrack 21 Mar 29 20:08:55 volumio volumio[1138]: info: CoreCommandRouter::servicePause Mar 29 20:08:55 volumio volumio[1138]: info: ControllerMpd::pause Mar 29 20:08:55 volumio volumio[1138]: verbose: ControllerMpd::sendMpdCommand pause Mar 29 20:08:55 volumio volumio[1138]: info: Mar 29 20:08:55 volumio volumio[1138]: ---------------------------- MPD announces state update: player Mar 29 20:08:55 volumio volumio[1138]: info: sendMpdCommand pause took 4 milliseconds Mar 29 20:08:55 volumio volumio[1138]: info: ControllerMpd::getState Mar 29 20:08:55 volumio volumio[1138]: verbose: ControllerMpd::sendMpdCommand status Mar 29 20:08:55 volumio volumio[1138]: info: Mar 29 20:08:55 volumio volumio[1138]: ---------------------------- MPD announces state update: player Mar 29 20:08:55 volumio volumio[1138]: info: ControllerMpd::getState Mar 29 20:08:55 volumio volumio[1138]: verbose: ControllerMpd::sendMpdCommand status Mar 29 20:08:55 volumio volumio[1138]: info: Mar 29 20:08:55 volumio volumio[1138]: ---------------------------- MPD announces state update: player Mar 29 20:08:55 volumio volumio[1138]: info: ControllerMpd::getState Mar 29 20:08:55 volumio volumio[1138]: verbose: ControllerMpd::sendMpdCommand status Mar 29 20:08:55 volumio volumio[1138]: info: sendMpdCommand status took 9 milliseconds Mar 29 20:08:55 volumio volumio[1138]: info: sendMpdCommand status took 6 milliseconds Mar 29 20:08:55 volumio volumio[1138]: info: sendMpdCommand status took 3 milliseconds Mar 29 20:08:55 volumio volumio[1138]: verbose: ControllerMpd::parseState Mar 29 20:08:55 volumio volumio[1138]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 20:08:55 volumio volumio[1138]: verbose: ControllerMpd::parseState Mar 29 20:08:55 volumio volumio[1138]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 20:08:55 volumio volumio[1138]: verbose: ControllerMpd::parseState Mar 29 20:08:55 volumio volumio[1138]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 20:08:55 volumio volumio[1138]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 29 20:08:55 volumio volumio[1138]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 29 20:08:55 volumio volumio[1138]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 29 20:08:55 volumio volumio[1138]: verbose: ControllerMpd::parseTrackInfo Mar 29 20:08:55 volumio volumio[1138]: verbose: ControllerMpd::parseTrackInfo Mar 29 20:08:55 volumio volumio[1138]: verbose: ControllerMpd::parseTrackInfo Mar 29 20:08:55 volumio volumio[1138]: info: ControllerMpd::pushState Mar 29 20:08:55 volumio volumio[1138]: info: CoreCommandRouter::servicePushState Mar 29 20:08:55 volumio volumio[1138]: info: CorePlayQueue::getTrack 21 Mar 29 20:08:55 volumio volumio[1138]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":134272,"duration":279,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"花祭","artist":"齐秦","album":"青梅竹马","uri":"USB/342D-697D/新建文件夹/齐秦 - 花祭.mp3","trackType":"mp3"} Mar 29 20:08:55 volumio volumio[1138]: verbose: CURRENT POSITION 21 Mar 29 20:08:55 volumio volumio[1138]: info: CoreStateMachine::syncState stateService pause Mar 29 20:08:55 volumio volumio[1138]: info: CoreStateMachine::syncState currentStatus pause Mar 29 20:08:55 volumio volumio[1138]: info: CoreStateMachine::pushState Mar 29 20:08:55 volumio volumio[1138]: info: CorePlayQueue::getTrack 21 Mar 29 20:08:55 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 20:08:55 volumio volumio[1138]: info: CoreCommandRouter::volumioPushState Mar 29 20:08:55 volumio volumio[1138]: info: CoreStateMachine::stPlaybackTimer Mar 29 20:08:55 volumio volumio[1138]: info: ControllerMpd::pushState Mar 29 20:08:55 volumio volumio[1138]: info: CoreCommandRouter::servicePushState Mar 29 20:08:55 volumio volumio[1138]: info: CorePlayQueue::getTrack 21 Mar 29 20:08:55 volumio volumio[1138]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":134272,"duration":279,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"花祭","artist":"齐秦","album":"青梅竹马","uri":"USB/342D-697D/新建文件夹/齐秦 - 花祭.mp3","trackType":"mp3"} Mar 29 20:08:55 volumio volumio[1138]: verbose: CURRENT POSITION 21 Mar 29 20:08:55 volumio volumio[1138]: info: CoreStateMachine::syncState stateService pause Mar 29 20:08:55 volumio volumio[1138]: info: CoreStateMachine::syncState currentStatus pause Mar 29 20:08:55 volumio volumio[1138]: info: CoreStateMachine::pushState Mar 29 20:08:55 volumio volumio[1138]: info: CorePlayQueue::getTrack 21 Mar 29 20:08:55 volumio volumio[1138]: info: CoreCommandRouter::volumioPushState Mar 29 20:08:55 volumio volumio[1138]: info: CoreStateMachine::stPlaybackTimer Mar 29 20:08:55 volumio volumio[1138]: info: ControllerMpd::pushState Mar 29 20:08:55 volumio volumio[1138]: info: CoreCommandRouter::servicePushState Mar 29 20:08:55 volumio volumio[1138]: info: CorePlayQueue::getTrack 21 Mar 29 20:08:55 volumio volumio[1138]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":134272,"duration":279,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"花祭","artist":"齐秦","album":"青梅竹马","uri":"USB/342D-697D/新建文件夹/齐秦 - 花祭.mp3","trackType":"mp3"} Mar 29 20:08:55 volumio volumio[1138]: verbose: CURRENT POSITION 21 Mar 29 20:08:55 volumio volumio[1138]: info: CoreStateMachine::syncState stateService pause Mar 29 20:08:55 volumio volumio[1138]: info: CoreStateMachine::syncState currentStatus pause Mar 29 20:08:55 volumio volumio[1138]: info: CoreStateMachine::pushState Mar 29 20:08:55 volumio volumio[1138]: info: CorePlayQueue::getTrack 21 Mar 29 20:08:55 volumio volumio[1138]: info: CoreCommandRouter::volumioPushState Mar 29 20:08:55 volumio volumio[1138]: info: CoreStateMachine::stPlaybackTimer Mar 29 20:08:55 volumio volumio[1138]: info: ------------------------------ 106ms Mar 29 20:08:55 volumio volumio[1138]: info: ------------------------------ 103ms Mar 29 20:08:55 volumio volumio[1138]: info: ------------------------------ 100ms Mar 29 20:08:55 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 20:08:55 volumio volumio[1138]: info: Received Get System Info Mar 29 20:08:55 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 20:08:55 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 20:08:55 volumio volumio[1138]: info: Discovery: Getting this device information Mar 29 20:08:55 volumio volumio[1138]: info: CoreCommandRouter::volumioGetState Mar 29 20:08:55 volumio volumio[1138]: info: CorePlayQueue::getTrack 21 Mar 29 20:08:55 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 20:08:55 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 29 20:09:00 volumio kernel: usb 1-1.5: USB disconnect, device number 46 Mar 29 20:09:00 volumio volumio[1138]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.5/1-1.5:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","CURRENT_TAGS":":systemd:","DEVLINKS":"/dev/disk/by-id/usb-Kingston_DT_101_G2_001FC6B87093ACA0991492B7-0:0-part1 /dev/disk/by-path/platform-3f980000.usb-usb-0:1.5:1.0-scsi-0:0:0:0-part1 /dev/disk/by-uuid/342D-697D","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.5/1-1.5:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"113","ID_BUS":"usb","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"342D-697D","ID_FS_UUID_ENC":"342D-697D","ID_FS_VERSION":"FAT32","ID_INSTANCE":"0:0","ID_MODEL":"DT_101_G2","ID_MODEL_ENC":"DT\\x20101\\x20G2","ID_MODEL_ID":"1642","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_FLAGS":"0x80","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"1961984","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"12320768","ID_PART_ENTRY_TYPE":"0xb","ID_PART_TABLE_TYPE":"dos","ID_PATH":"platform-3f980000.usb-usb-0:1.5:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-3f980000_usb-usb-0_1_5_1_0-scsi-0_0_0_0","ID_REVISION":"1.00","ID_SERIAL":"Kingston_DT_101_G2_001FC6B87093ACA0991492B7-0:0","ID_SERIAL_SHORT":"001FC6B87093ACA0991492B7","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":"DT_101_G2","ID_USB_MODEL_ENC":"DT\\x20101\\x20G2","ID_USB_MODEL_ID":"1642","ID_USB_REVISION":"1.00","ID_USB_SERIAL":"Kingston_DT_101_G2_001FC6B87093ACA0991492B7-0:0","ID_USB_SERIAL_SHORT":"001FC6B87093ACA0991492B7","ID_USB_TYPE":"disk","ID_USB_VENDOR":"Kingston","ID_USB_VENDOR_ENC":"Kingston","ID_USB_VENDOR_ID":"0951","ID_VENDOR":"Kingston","ID_VENDOR_ENC":"Kingston","ID_VENDOR_ID":"0951","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"3625","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"631549347"} Mar 29 20:09:00 volumio sudo[3255]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda1 Mar 29 20:09:00 volumio sudo[3255]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 20:09:00 volumio sudo[3255]: pam_unix(sudo:session): session closed for user root Mar 29 20:09:00 volumio volumio[1138]: umount: /media/342D-697D: target is busy. Mar 29 20:09:00 volumio volumio[1138]: error: Failed to umount 342D-697D: Error: Command failed: /usr/bin/sudo /bin/umount -f "/dev/sda1" Mar 29 20:09:00 volumio volumio[1138]: umount: /media/342D-697D: target is busy. Mar 29 20:09:00 volumio volumio[1138]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.5/1-1.5:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","ACTION":"remove","CURRENT_TAGS":":systemd:","DEVLINKS":"/dev/disk/by-label/EFI /dev/disk/by-uuid/0CA2-0713 /dev/disk/by-id/usb-Kingston_DT_101_G2_001FC6B87093ACA0991492B7-0:0-part2 /dev/disk/by-path/platform-3f980000.usb-usb-0:1.5:1.0-scsi-0:0:0:0-part2","DEVNAME":"/dev/sda2","DEVPATH":"/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.5/1-1.5:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","DEVTYPE":"partition","DISKSEQ":"113","ID_BUS":"usb","ID_FS_LABEL":"EFI","ID_FS_LABEL_ENC":"EFI","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"0CA2-0713","ID_FS_UUID_ENC":"0CA2-0713","ID_FS_VERSION":"FAT32","ID_INSTANCE":"0:0","ID_MODEL":"DT_101_G2","ID_MODEL_ENC":"DT\\x20101\\x20G2","ID_MODEL_ID":"1642","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_NUMBER":"2","ID_PART_ENTRY_OFFSET":"14282752","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"917503","ID_PART_ENTRY_TYPE":"0x1b","ID_PART_TABLE_TYPE":"dos","ID_PATH":"platform-3f980000.usb-usb-0:1.5:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-3f980000_usb-usb-0_1_5_1_0-scsi-0_0_0_0","ID_REVISION":"1.00","ID_SERIAL":"Kingston_DT_101_G2_001FC6B87093ACA0991492B7-0:0","ID_SERIAL_SHORT":"001FC6B87093ACA0991492B7","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":"DT_101_G2","ID_USB_MODEL_ENC":"DT\\x20101\\x20G2","ID_USB_MODEL_ID":"1642","ID_USB_REVISION":"1.00","ID_USB_SERIAL":"Kingston_DT_101_G2_001FC6B87093ACA0991492B7-0:0","ID_USB_SERIAL_SHORT":"001FC6B87093ACA0991492B7","ID_USB_TYPE":"disk","ID_USB_VENDOR":"Kingston","ID_USB_VENDOR_ENC":"Kingston","ID_USB_VENDOR_ID":"0951","ID_VENDOR":"Kingston","ID_VENDOR_ENC":"Kingston","ID_VENDOR_ID":"0951","MAJOR":"8","MINOR":"2","PARTN":"2","SEQNUM":"3626","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"631550301"} Mar 29 20:09:00 volumio sudo[3259]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda2 Mar 29 20:09:00 volumio sudo[3259]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 20:09:00 volumio sudo[3259]: pam_unix(sudo:session): session closed for user root Mar 29 20:09:00 volumio systemd[1]: media-EFI.mount: Deactivated successfully. Mar 29 20:09:02 volumio kernel: usb 1-1.3: new high-speed USB device number 47 using dwc_otg Mar 29 20:09:02 volumio kernel: usb 1-1.3: New USB device found, idVendor=0951, idProduct=1642, bcdDevice= 0.00 Mar 29 20:09:02 volumio kernel: usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Mar 29 20:09:02 volumio kernel: usb 1-1.3: Product: DT 101 G2 Mar 29 20:09:02 volumio kernel: usb 1-1.3: Manufacturer: Kingston Mar 29 20:09:02 volumio kernel: usb 1-1.3: SerialNumber: 001FC6B87093ACA0991492B7 Mar 29 20:09:02 volumio kernel: usb-storage 1-1.3:1.0: USB Mass Storage device detected Mar 29 20:09:02 volumio kernel: scsi host1: usb-storage 1-1.3:1.0 Mar 29 20:09:03 volumio kernel: scsi 1:0:0:0: Direct-Access 1.00 PQ: 0 ANSI: 4 Mar 29 20:09:03 volumio kernel: sd 1:0:0:0: Attached scsi generic sg0 type 0 Mar 29 20:09:03 volumio kernel: sd 1:0:0:0: [sdb] 15204352 512-byte logical blocks: (7.78 GB/7.25 GiB) Mar 29 20:09:03 volumio kernel: sd 1:0:0:0: [sdb] Write Protect is off Mar 29 20:09:03 volumio kernel: sd 1:0:0:0: [sdb] Mode Sense: 2f 00 00 00 Mar 29 20:09:03 volumio kernel: sd 1:0:0:0: [sdb] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA Mar 29 20:09:03 volumio kernel: sdb: sdb1 sdb2 Mar 29 20:09:03 volumio kernel: sd 1:0:0:0: [sdb] Attached SCSI removable disk Mar 29 20:09:03 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 29 20:09:03 volumio volumio[1138]: info: Mounting Device 342D-697D Mar 29 20:09:04 volumio sudo[3271]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/342D-697D -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Mar 29 20:09:04 volumio sudo[3271]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 20:09:04 volumio kernel: FAT-fs (sdb1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Mar 29 20:09:04 volumio kernel: FAT-fs (sdb1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Mar 29 20:09:04 volumio sudo[3271]: pam_unix(sudo:session): session closed for user root Mar 29 20:09:04 volumio volumio[1138]: info: Mounting Device EFI Mar 29 20:09:04 volumio sudo[3280]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb2 /mnt/USB/EFI -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Mar 29 20:09:04 volumio sudo[3280]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 20:09:04 volumio kernel: FAT-fs (sdb2): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Mar 29 20:09:04 volumio kernel: FAT-fs (sdb2): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Mar 29 20:09:04 volumio sudo[3280]: pam_unix(sudo:session): session closed for user root Mar 29 20:09:04 volumio volumio[1138]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/EFI Mar 29 20:09:05 volumio volumio[1138]: info: Scanning removed location : "USB/342D-697D" Mar 29 20:09:05 volumio volumio[1138]: verbose: ControllerMpd::sendMpdCommand status Mar 29 20:09:05 volumio volumio[1138]: info: Mar 29 20:09:05 volumio volumio[1138]: ---------------------------- MPD announces state update: update Mar 29 20:09:05 volumio volumio[1138]: info: ControllerMpd::getState Mar 29 20:09:05 volumio volumio[1138]: verbose: ControllerMpd::sendMpdCommand status Mar 29 20:09:05 volumio volumio[1138]: verbose: ControllerMpd::sendMpdCommand status Mar 29 20:09:05 volumio volumio[1138]: info: Mar 29 20:09:05 volumio volumio[1138]: ---------------------------- MPD announces state update: update Mar 29 20:09:05 volumio volumio[1138]: info: ControllerMpd::getState Mar 29 20:09:05 volumio volumio[1138]: verbose: ControllerMpd::sendMpdCommand status Mar 29 20:09:05 volumio volumio[1138]: verbose: ControllerMpd::sendMpdCommand status Mar 29 20:09:05 volumio volumio[1138]: info: Mar 29 20:09:05 volumio volumio[1138]: ---------------------------- MPD announces state update: update Mar 29 20:09:05 volumio volumio[1138]: info: ControllerMpd::getState Mar 29 20:09:05 volumio volumio[1138]: verbose: ControllerMpd::sendMpdCommand status Mar 29 20:09:05 volumio volumio[1138]: info: sendMpdCommand status took 12 milliseconds Mar 29 20:09:05 volumio volumio[1138]: info: sendMpdCommand status took 10 milliseconds Mar 29 20:09:05 volumio volumio[1138]: info: sendMpdCommand status took 9 milliseconds Mar 29 20:09:05 volumio volumio[1138]: info: sendMpdCommand status took 7 milliseconds Mar 29 20:09:05 volumio volumio[1138]: verbose: ControllerMpd::parseState Mar 29 20:09:05 volumio volumio[1138]: info: Command Router : Notfying DB Updatetrue Mar 29 20:09:05 volumio volumio[1138]: verbose: ControllerMpd::parseState Mar 29 20:09:05 volumio volumio[1138]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 20:09:05 volumio volumio[1138]: verbose: ControllerMpd::parseState Mar 29 20:09:05 volumio volumio[1138]: info: Command Router : Notfying DB Updatetrue Mar 29 20:09:05 volumio volumio[1138]: info: CoreCommandRouter::Close All Modals sent Mar 29 20:09:05 volumio volumio[1138]: verbose: ControllerMpd::parseState Mar 29 20:09:05 volumio volumio[1138]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 20:09:05 volumio volumio[1138]: info: sendMpdCommand status took 203 milliseconds Mar 29 20:09:05 volumio volumio[1138]: info: sendMpdCommand status took 202 milliseconds Mar 29 20:09:05 volumio volumio[1138]: info: sendMpdCommand playlistinfo took 62 milliseconds Mar 29 20:09:05 volumio volumio[1138]: info: sendMpdCommand playlistinfo took 34 milliseconds Mar 29 20:09:05 volumio volumio[1138]: verbose: ControllerMpd::parseState Mar 29 20:09:05 volumio volumio[1138]: info: Command Router : Notfying DB Updatetrue Mar 29 20:09:05 volumio volumio[1138]: info: CoreCommandRouter::Close All Modals sent Mar 29 20:09:05 volumio volumio[1138]: verbose: ControllerMpd::parseState Mar 29 20:09:05 volumio volumio[1138]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 20:09:05 volumio volumio[1138]: verbose: ControllerMpd::parseTrackInfo Mar 29 20:09:05 volumio volumio[1138]: verbose: ControllerMpd::parseTrackInfo Mar 29 20:09:05 volumio volumio[1138]: info: ControllerMpd::pushState Mar 29 20:09:05 volumio volumio[1138]: info: CoreCommandRouter::servicePushState Mar 29 20:09:05 volumio volumio[1138]: info: CorePlayQueue::getTrack 21 Mar 29 20:09:05 volumio volumio[1138]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":134272,"duration":279,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"花祭","artist":"齐秦","album":"青梅竹马","uri":"USB/342D-697D/新建文件夹/齐秦 - 花祭.mp3","trackType":"mp3"} Mar 29 20:09:05 volumio volumio[1138]: verbose: CURRENT POSITION 21 Mar 29 20:09:05 volumio volumio[1138]: info: CoreStateMachine::syncState stateService pause Mar 29 20:09:05 volumio volumio[1138]: info: CoreStateMachine::syncState currentStatus pause Mar 29 20:09:05 volumio volumio[1138]: info: CoreStateMachine::pushState Mar 29 20:09:05 volumio volumio[1138]: info: CorePlayQueue::getTrack 21 Mar 29 20:09:05 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 20:09:05 volumio volumio[1138]: info: CoreCommandRouter::volumioPushState Mar 29 20:09:05 volumio volumio[1138]: info: CoreStateMachine::stPlaybackTimer Mar 29 20:09:05 volumio volumio[1138]: info: ControllerMpd::pushState Mar 29 20:09:05 volumio volumio[1138]: info: CoreCommandRouter::servicePushState Mar 29 20:09:05 volumio volumio[1138]: info: CorePlayQueue::getTrack 21 Mar 29 20:09:05 volumio volumio[1138]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":134272,"duration":279,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"花祭","artist":"齐秦","album":"青梅竹马","uri":"USB/342D-697D/新建文件夹/齐秦 - 花祭.mp3","trackType":"mp3"} Mar 29 20:09:05 volumio volumio[1138]: verbose: CURRENT POSITION 21 Mar 29 20:09:05 volumio volumio[1138]: info: CoreStateMachine::syncState stateService pause Mar 29 20:09:05 volumio volumio[1138]: info: CoreStateMachine::syncState currentStatus pause Mar 29 20:09:05 volumio volumio[1138]: info: CoreStateMachine::pushState Mar 29 20:09:05 volumio volumio[1138]: info: CorePlayQueue::getTrack 21 Mar 29 20:09:05 volumio volumio[1138]: info: CoreCommandRouter::volumioPushState Mar 29 20:09:05 volumio volumio[1138]: info: CoreStateMachine::stPlaybackTimer Mar 29 20:09:05 volumio volumio[1138]: info: ------------------------------ 332ms Mar 29 20:09:05 volumio volumio[1138]: info: ------------------------------ 329ms Mar 29 20:09:06 volumio volumio[1138]: info: sendMpdCommand playlistinfo took 124 milliseconds Mar 29 20:09:06 volumio volumio[1138]: verbose: ControllerMpd::parseTrackInfo Mar 29 20:09:06 volumio volumio[1138]: info: ControllerMpd::pushState Mar 29 20:09:06 volumio volumio[1138]: info: CoreCommandRouter::servicePushState Mar 29 20:09:06 volumio volumio[1138]: info: CorePlayQueue::getTrack 21 Mar 29 20:09:06 volumio volumio[1138]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":134272,"duration":279,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"花祭","artist":"齐秦","album":"青梅竹马","uri":"USB/342D-697D/新建文件夹/齐秦 - 花祭.mp3","trackType":"mp3"} Mar 29 20:09:06 volumio volumio[1138]: verbose: CURRENT POSITION 21 Mar 29 20:09:06 volumio volumio[1138]: info: CoreStateMachine::syncState stateService pause Mar 29 20:09:06 volumio volumio[1138]: info: CoreStateMachine::syncState currentStatus pause Mar 29 20:09:06 volumio volumio[1138]: info: CoreStateMachine::pushState Mar 29 20:09:06 volumio volumio[1138]: info: CorePlayQueue::getTrack 21 Mar 29 20:09:06 volumio volumio[1138]: info: CoreCommandRouter::volumioPushState Mar 29 20:09:06 volumio volumio[1138]: info: CoreStateMachine::stPlaybackTimer Mar 29 20:09:06 volumio volumio[1138]: info: ------------------------------ 416ms Mar 29 20:09:06 volumio volumio[1138]: info: Scanning removed location : "USB/EFI" Mar 29 20:09:07 volumio volumio[1138]: info: MPD Database updated - AlbumList cache refreshed Mar 29 20:09:07 volumio volumio[1138]: info: Mar 29 20:09:07 volumio volumio[1138]: ---------------------------- MPD announces state update: database Mar 29 20:09:07 volumio volumio[1138]: verbose: ControllerMpd::sendMpdCommand status Mar 29 20:09:07 volumio volumio[1138]: info: Mar 29 20:09:07 volumio volumio[1138]: ---------------------------- MPD announces state update: update Mar 29 20:09:07 volumio volumio[1138]: info: ControllerMpd::getState Mar 29 20:09:07 volumio volumio[1138]: verbose: ControllerMpd::sendMpdCommand status Mar 29 20:09:07 volumio volumio[1138]: info: ControllerMpd::getState Mar 29 20:09:07 volumio volumio[1138]: verbose: ControllerMpd::sendMpdCommand status Mar 29 20:09:07 volumio volumio[1138]: info: MPD Database updated - AlbumList cache refreshed Mar 29 20:09:07 volumio volumio[1138]: info: Mar 29 20:09:07 volumio volumio[1138]: ---------------------------- MPD announces state update: database Mar 29 20:09:07 volumio volumio[1138]: verbose: ControllerMpd::sendMpdCommand status Mar 29 20:09:07 volumio volumio[1138]: info: Mar 29 20:09:07 volumio volumio[1138]: ---------------------------- MPD announces state update: update Mar 29 20:09:07 volumio volumio[1138]: info: ControllerMpd::getState Mar 29 20:09:07 volumio volumio[1138]: verbose: ControllerMpd::sendMpdCommand status Mar 29 20:09:07 volumio volumio[1138]: info: ControllerMpd::getState Mar 29 20:09:07 volumio volumio[1138]: verbose: ControllerMpd::sendMpdCommand status Mar 29 20:09:07 volumio volumio[1138]: info: MPD Database updated - AlbumList cache refreshed Mar 29 20:09:07 volumio volumio[1138]: info: Mar 29 20:09:07 volumio volumio[1138]: ---------------------------- MPD announces state update: database Mar 29 20:09:07 volumio volumio[1138]: verbose: ControllerMpd::sendMpdCommand status Mar 29 20:09:07 volumio volumio[1138]: info: Mar 29 20:09:07 volumio volumio[1138]: ---------------------------- MPD announces state update: update Mar 29 20:09:07 volumio volumio[1138]: info: ControllerMpd::getState Mar 29 20:09:07 volumio volumio[1138]: verbose: ControllerMpd::sendMpdCommand status Mar 29 20:09:07 volumio volumio[1138]: info: ControllerMpd::getState Mar 29 20:09:07 volumio volumio[1138]: verbose: ControllerMpd::sendMpdCommand status Mar 29 20:09:07 volumio volumio[1138]: info: sendMpdCommand status took 39 milliseconds Mar 29 20:09:07 volumio volumio[1138]: info: sendMpdCommand status took 37 milliseconds Mar 29 20:09:07 volumio volumio[1138]: info: sendMpdCommand status took 37 milliseconds Mar 29 20:09:07 volumio volumio[1138]: verbose: ControllerMpd::parseState Mar 29 20:09:07 volumio volumio[1138]: info: Command Router : Notfying DB Updatetrue Mar 29 20:09:07 volumio volumio[1138]: info: CoreCommandRouter::Close All Modals sent Mar 29 20:09:07 volumio volumio[1138]: verbose: ControllerMpd::parseState Mar 29 20:09:07 volumio volumio[1138]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 20:09:07 volumio volumio[1138]: verbose: ControllerMpd::parseState Mar 29 20:09:07 volumio volumio[1138]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 20:09:07 volumio volumio[1138]: verbose: ControllerMpd::sendMpdCommand status Mar 29 20:09:07 volumio volumio[1138]: info: Mar 29 20:09:07 volumio volumio[1138]: ---------------------------- MPD announces state update: update Mar 29 20:09:07 volumio volumio[1138]: info: ControllerMpd::getState Mar 29 20:09:07 volumio volumio[1138]: verbose: ControllerMpd::sendMpdCommand status Mar 29 20:09:07 volumio volumio[1138]: verbose: ControllerMpd::sendMpdCommand status Mar 29 20:09:07 volumio volumio[1138]: info: Mar 29 20:09:07 volumio volumio[1138]: ---------------------------- MPD announces state update: update Mar 29 20:09:07 volumio volumio[1138]: info: ControllerMpd::getState Mar 29 20:09:07 volumio volumio[1138]: verbose: ControllerMpd::sendMpdCommand status Mar 29 20:09:07 volumio volumio[1138]: info: sendMpdCommand status took 187 milliseconds Mar 29 20:09:07 volumio volumio[1138]: info: sendMpdCommand status took 185 milliseconds Mar 29 20:09:07 volumio volumio[1138]: info: sendMpdCommand status took 185 milliseconds Mar 29 20:09:07 volumio volumio[1138]: verbose: ControllerMpd::parseState Mar 29 20:09:07 volumio volumio[1138]: info: Command Router : Notfying DB Updatetrue Mar 29 20:09:07 volumio volumio[1138]: info: CoreCommandRouter::Close All Modals sent Mar 29 20:09:07 volumio volumio[1138]: verbose: ControllerMpd::parseState Mar 29 20:09:07 volumio volumio[1138]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 20:09:07 volumio volumio[1138]: verbose: ControllerMpd::parseState Mar 29 20:09:07 volumio volumio[1138]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 20:09:08 volumio volumio[1138]: verbose: ControllerMpd::sendMpdCommand status Mar 29 20:09:08 volumio volumio[1138]: info: Mar 29 20:09:08 volumio volumio[1138]: ---------------------------- MPD announces state update: update Mar 29 20:09:08 volumio volumio[1138]: info: sendMpdCommand status took 260 milliseconds Mar 29 20:09:08 volumio volumio[1138]: info: sendMpdCommand status took 239 milliseconds Mar 29 20:09:08 volumio volumio[1138]: info: sendMpdCommand status took 239 milliseconds Mar 29 20:09:08 volumio volumio[1138]: info: ControllerMpd::getState Mar 29 20:09:08 volumio volumio[1138]: verbose: ControllerMpd::sendMpdCommand status Mar 29 20:09:08 volumio volumio[1138]: info: sendMpdCommand playlistinfo took 126 milliseconds Mar 29 20:09:08 volumio volumio[1138]: info: sendMpdCommand playlistinfo took 126 milliseconds Mar 29 20:09:08 volumio volumio[1138]: info: sendMpdCommand status took 117 milliseconds Mar 29 20:09:08 volumio volumio[1138]: info: sendMpdCommand status took 117 milliseconds Mar 29 20:09:08 volumio volumio[1138]: info: sendMpdCommand status took 116 milliseconds Mar 29 20:09:08 volumio volumio[1138]: info: sendMpdCommand status took 114 milliseconds Mar 29 20:09:08 volumio volumio[1138]: info: sendMpdCommand playlistinfo took 51 milliseconds Mar 29 20:09:08 volumio volumio[1138]: info: sendMpdCommand playlistinfo took 50 milliseconds Mar 29 20:09:08 volumio volumio[1138]: verbose: ControllerMpd::parseState Mar 29 20:09:08 volumio volumio[1138]: info: Command Router : Notfying DB Updatetrue Mar 29 20:09:08 volumio volumio[1138]: info: CoreCommandRouter::Close All Modals sent Mar 29 20:09:08 volumio volumio[1138]: verbose: ControllerMpd::parseState Mar 29 20:09:08 volumio volumio[1138]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 20:09:08 volumio volumio[1138]: verbose: ControllerMpd::parseState Mar 29 20:09:08 volumio volumio[1138]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 20:09:08 volumio volumio[1138]: verbose: ControllerMpd::parseTrackInfo Mar 29 20:09:08 volumio volumio[1138]: verbose: ControllerMpd::parseTrackInfo Mar 29 20:09:08 volumio volumio[1138]: verbose: ControllerMpd::parseState Mar 29 20:09:08 volumio volumio[1138]: info: Command Router : Notfying DB Updatefalse Mar 29 20:09:08 volumio volumio[1138]: info: CoreCommandRouter::Close All Modals sent Mar 29 20:09:08 volumio volumio[1138]: verbose: ControllerMpd::parseState Mar 29 20:09:08 volumio volumio[1138]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 20:09:08 volumio volumio[1138]: verbose: ControllerMpd::parseState Mar 29 20:09:08 volumio volumio[1138]: info: Command Router : Notfying DB Updatefalse Mar 29 20:09:08 volumio volumio[1138]: info: CoreCommandRouter::Close All Modals sent Mar 29 20:09:08 volumio volumio[1138]: verbose: ControllerMpd::parseState Mar 29 20:09:08 volumio volumio[1138]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 20:09:08 volumio volumio[1138]: verbose: ControllerMpd::parseTrackInfo Mar 29 20:09:08 volumio volumio[1138]: verbose: ControllerMpd::parseTrackInfo Mar 29 20:09:08 volumio volumio[1138]: info: ControllerMpd::pushState Mar 29 20:09:08 volumio volumio[1138]: info: CoreCommandRouter::servicePushState Mar 29 20:09:08 volumio volumio[1138]: info: CorePlayQueue::getTrack 21 Mar 29 20:09:08 volumio volumio[1138]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":134272,"duration":279,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"花祭","artist":"齐秦","album":"青梅竹马","uri":"USB/342D-697D/新建文件夹/齐秦 - 花祭.mp3","trackType":"mp3"} Mar 29 20:09:08 volumio volumio[1138]: verbose: CURRENT POSITION 21 Mar 29 20:09:08 volumio volumio[1138]: info: CoreStateMachine::syncState stateService pause Mar 29 20:09:08 volumio volumio[1138]: info: CoreStateMachine::syncState currentStatus pause Mar 29 20:09:08 volumio volumio[1138]: info: CoreStateMachine::pushState Mar 29 20:09:08 volumio volumio[1138]: info: CorePlayQueue::getTrack 21 Mar 29 20:09:08 volumio volumio[1138]: info: CoreCommandRouter::volumioPushState Mar 29 20:09:08 volumio volumio[1138]: info: CoreStateMachine::stPlaybackTimer Mar 29 20:09:08 volumio volumio[1138]: info: ControllerMpd::pushState Mar 29 20:09:08 volumio volumio[1138]: info: CoreCommandRouter::servicePushState Mar 29 20:09:08 volumio volumio[1138]: info: CorePlayQueue::getTrack 21 Mar 29 20:09:08 volumio volumio[1138]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":134272,"duration":279,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"花祭","artist":"齐秦","album":"青梅竹马","uri":"USB/342D-697D/新建文件夹/齐秦 - 花祭.mp3","trackType":"mp3"} Mar 29 20:09:08 volumio volumio[1138]: verbose: CURRENT POSITION 21 Mar 29 20:09:08 volumio volumio[1138]: info: CoreStateMachine::syncState stateService pause Mar 29 20:09:08 volumio volumio[1138]: info: CoreStateMachine::syncState currentStatus pause Mar 29 20:09:08 volumio volumio[1138]: info: CoreStateMachine::pushState Mar 29 20:09:08 volumio volumio[1138]: info: CorePlayQueue::getTrack 21 Mar 29 20:09:08 volumio volumio[1138]: info: CoreCommandRouter::volumioPushState Mar 29 20:09:08 volumio volumio[1138]: info: CoreStateMachine::stPlaybackTimer Mar 29 20:09:08 volumio volumio[1138]: info: ControllerMpd::pushState Mar 29 20:09:08 volumio volumio[1138]: info: CoreCommandRouter::servicePushState Mar 29 20:09:08 volumio volumio[1138]: info: CorePlayQueue::getTrack 21 Mar 29 20:09:08 volumio volumio[1138]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":134272,"duration":279,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"花祭","artist":"齐秦","album":"青梅竹马","uri":"USB/342D-697D/新建文件夹/齐秦 - 花祭.mp3","trackType":"mp3"} Mar 29 20:09:08 volumio volumio[1138]: verbose: CURRENT POSITION 21 Mar 29 20:09:08 volumio volumio[1138]: info: CoreStateMachine::syncState stateService pause Mar 29 20:09:08 volumio volumio[1138]: info: CoreStateMachine::syncState currentStatus pause Mar 29 20:09:08 volumio volumio[1138]: info: CoreStateMachine::pushState Mar 29 20:09:08 volumio volumio[1138]: info: CorePlayQueue::getTrack 21 Mar 29 20:09:08 volumio volumio[1138]: info: CoreCommandRouter::volumioPushState Mar 29 20:09:08 volumio volumio[1138]: info: CoreStateMachine::stPlaybackTimer Mar 29 20:09:08 volumio volumio[1138]: info: ControllerMpd::pushState Mar 29 20:09:08 volumio volumio[1138]: info: CoreCommandRouter::servicePushState Mar 29 20:09:08 volumio volumio[1138]: info: CorePlayQueue::getTrack 21 Mar 29 20:09:08 volumio volumio[1138]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":134272,"duration":279,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"花祭","artist":"齐秦","album":"青梅竹马","uri":"USB/342D-697D/新建文件夹/齐秦 - 花祭.mp3","trackType":"mp3"} Mar 29 20:09:08 volumio volumio[1138]: verbose: CURRENT POSITION 21 Mar 29 20:09:08 volumio volumio[1138]: info: CoreStateMachine::syncState stateService pause Mar 29 20:09:08 volumio volumio[1138]: info: CoreStateMachine::syncState currentStatus pause Mar 29 20:09:08 volumio volumio[1138]: info: CoreStateMachine::pushState Mar 29 20:09:08 volumio volumio[1138]: info: CorePlayQueue::getTrack 21 Mar 29 20:09:08 volumio volumio[1138]: info: CoreCommandRouter::volumioPushState Mar 29 20:09:08 volumio volumio[1138]: info: CoreStateMachine::stPlaybackTimer Mar 29 20:09:08 volumio volumio[1138]: info: ------------------------------ 512ms Mar 29 20:09:08 volumio volumio[1138]: info: ------------------------------ 513ms Mar 29 20:09:08 volumio volumio[1138]: info: ------------------------------ 507ms Mar 29 20:09:08 volumio volumio[1138]: info: ------------------------------ 507ms Mar 29 20:09:08 volumio volumio[1138]: info: sendMpdCommand status took 247 milliseconds Mar 29 20:09:08 volumio volumio[1138]: info: sendMpdCommand status took 242 milliseconds Mar 29 20:09:08 volumio volumio[1138]: info: sendMpdCommand playlistinfo took 202 milliseconds Mar 29 20:09:08 volumio volumio[1138]: info: sendMpdCommand playlistinfo took 201 milliseconds Mar 29 20:09:08 volumio volumio[1138]: info: sendMpdCommand playlistinfo took 173 milliseconds Mar 29 20:09:08 volumio volumio[1138]: info: sendMpdCommand playlistinfo took 139 milliseconds Mar 29 20:09:08 volumio volumio[1138]: verbose: ControllerMpd::parseState Mar 29 20:09:08 volumio volumio[1138]: info: Command Router : Notfying DB Updatefalse Mar 29 20:09:08 volumio volumio[1138]: info: CoreCommandRouter::Close All Modals sent Mar 29 20:09:08 volumio volumio[1138]: verbose: ControllerMpd::parseState Mar 29 20:09:08 volumio volumio[1138]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 20:09:08 volumio volumio[1138]: verbose: ControllerMpd::parseTrackInfo Mar 29 20:09:08 volumio volumio[1138]: verbose: ControllerMpd::parseTrackInfo Mar 29 20:09:08 volumio volumio[1138]: verbose: ControllerMpd::parseTrackInfo Mar 29 20:09:08 volumio volumio[1138]: verbose: ControllerMpd::parseTrackInfo Mar 29 20:09:08 volumio volumio[1138]: info: ControllerMpd::pushState Mar 29 20:09:08 volumio volumio[1138]: info: CoreCommandRouter::servicePushState Mar 29 20:09:08 volumio volumio[1138]: info: CorePlayQueue::getTrack 21 Mar 29 20:09:08 volumio volumio[1138]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":134272,"duration":279,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"花祭","artist":"齐秦","album":"青梅竹马","uri":"USB/342D-697D/新建文件夹/齐秦 - 花祭.mp3","trackType":"mp3"} Mar 29 20:09:08 volumio volumio[1138]: verbose: CURRENT POSITION 21 Mar 29 20:09:08 volumio volumio[1138]: info: CoreStateMachine::syncState stateService pause Mar 29 20:09:08 volumio volumio[1138]: info: CoreStateMachine::syncState currentStatus pause Mar 29 20:09:08 volumio volumio[1138]: info: CoreStateMachine::pushState Mar 29 20:09:08 volumio volumio[1138]: info: CorePlayQueue::getTrack 21 Mar 29 20:09:08 volumio volumio[1138]: info: CoreCommandRouter::volumioPushState Mar 29 20:09:08 volumio volumio[1138]: info: CoreStateMachine::stPlaybackTimer Mar 29 20:09:08 volumio volumio[1138]: info: ControllerMpd::pushState Mar 29 20:09:08 volumio volumio[1138]: info: CoreCommandRouter::servicePushState Mar 29 20:09:08 volumio volumio[1138]: info: CorePlayQueue::getTrack 21 Mar 29 20:09:08 volumio volumio[1138]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":134272,"duration":279,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"花祭","artist":"齐秦","album":"青梅竹马","uri":"USB/342D-697D/新建文件夹/齐秦 - 花祭.mp3","trackType":"mp3"} Mar 29 20:09:08 volumio volumio[1138]: verbose: CURRENT POSITION 21 Mar 29 20:09:08 volumio volumio[1138]: info: CoreStateMachine::syncState stateService pause Mar 29 20:09:08 volumio volumio[1138]: info: CoreStateMachine::syncState currentStatus pause Mar 29 20:09:08 volumio volumio[1138]: info: CoreStateMachine::pushState Mar 29 20:09:08 volumio volumio[1138]: info: CorePlayQueue::getTrack 21 Mar 29 20:09:08 volumio volumio[1138]: info: CoreCommandRouter::volumioPushState Mar 29 20:09:08 volumio volumio[1138]: info: CoreStateMachine::stPlaybackTimer Mar 29 20:09:08 volumio volumio[1138]: info: ControllerMpd::pushState Mar 29 20:09:08 volumio volumio[1138]: info: CoreCommandRouter::servicePushState Mar 29 20:09:08 volumio volumio[1138]: info: CorePlayQueue::getTrack 21 Mar 29 20:09:08 volumio volumio[1138]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":134272,"duration":279,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"花祭","artist":"齐秦","album":"青梅竹马","uri":"USB/342D-697D/新建文件夹/齐秦 - 花祭.mp3","trackType":"mp3"} Mar 29 20:09:08 volumio volumio[1138]: verbose: CURRENT POSITION 21 Mar 29 20:09:08 volumio volumio[1138]: info: CoreStateMachine::syncState stateService pause Mar 29 20:09:08 volumio volumio[1138]: info: CoreStateMachine::syncState currentStatus pause Mar 29 20:09:08 volumio volumio[1138]: info: CoreStateMachine::pushState Mar 29 20:09:08 volumio volumio[1138]: info: CorePlayQueue::getTrack 21 Mar 29 20:09:08 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 20:09:08 volumio volumio[1138]: info: CoreCommandRouter::volumioPushState Mar 29 20:09:08 volumio volumio[1138]: info: CoreStateMachine::stPlaybackTimer Mar 29 20:09:08 volumio volumio[1138]: info: ControllerMpd::pushState Mar 29 20:09:08 volumio volumio[1138]: info: CoreCommandRouter::servicePushState Mar 29 20:09:08 volumio volumio[1138]: info: CorePlayQueue::getTrack 21 Mar 29 20:09:08 volumio volumio[1138]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":134272,"duration":279,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"花祭","artist":"齐秦","album":"青梅竹马","uri":"USB/342D-697D/新建文件夹/齐秦 - 花祭.mp3","trackType":"mp3"} Mar 29 20:09:08 volumio volumio[1138]: verbose: CURRENT POSITION 21 Mar 29 20:09:08 volumio volumio[1138]: info: CoreStateMachine::syncState stateService pause Mar 29 20:09:08 volumio volumio[1138]: info: CoreStateMachine::syncState currentStatus pause Mar 29 20:09:08 volumio volumio[1138]: info: CoreStateMachine::pushState Mar 29 20:09:08 volumio volumio[1138]: info: CorePlayQueue::getTrack 21 Mar 29 20:09:08 volumio volumio[1138]: info: CoreCommandRouter::volumioPushState Mar 29 20:09:08 volumio volumio[1138]: info: CoreStateMachine::stPlaybackTimer Mar 29 20:09:08 volumio volumio[1138]: info: ------------------------------ 650ms Mar 29 20:09:08 volumio volumio[1138]: info: ------------------------------ 649ms Mar 29 20:09:08 volumio volumio[1138]: info: ------------------------------ 503ms Mar 29 20:09:08 volumio volumio[1138]: info: ------------------------------ 501ms Mar 29 20:09:08 volumio volumio[1138]: info: sendMpdCommand playlistinfo took 183 milliseconds Mar 29 20:09:08 volumio volumio[1138]: verbose: ControllerMpd::parseTrackInfo Mar 29 20:09:08 volumio volumio[1138]: info: ControllerMpd::pushState Mar 29 20:09:08 volumio volumio[1138]: info: CoreCommandRouter::servicePushState Mar 29 20:09:08 volumio volumio[1138]: info: CorePlayQueue::getTrack 21 Mar 29 20:09:08 volumio volumio[1138]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":134272,"duration":279,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"花祭","artist":"齐秦","album":"青梅竹马","uri":"USB/342D-697D/新建文件夹/齐秦 - 花祭.mp3","trackType":"mp3"} Mar 29 20:09:08 volumio volumio[1138]: verbose: CURRENT POSITION 21 Mar 29 20:09:08 volumio volumio[1138]: info: CoreStateMachine::syncState stateService pause Mar 29 20:09:08 volumio volumio[1138]: info: CoreStateMachine::syncState currentStatus pause Mar 29 20:09:08 volumio volumio[1138]: info: CoreStateMachine::pushState Mar 29 20:09:08 volumio volumio[1138]: info: CorePlayQueue::getTrack 21 Mar 29 20:09:08 volumio volumio[1138]: info: CoreCommandRouter::volumioPushState Mar 29 20:09:08 volumio volumio[1138]: info: CoreStateMachine::stPlaybackTimer Mar 29 20:09:08 volumio volumio[1138]: info: ------------------------------ 496ms Mar 29 20:09:10 volumio volumio[1138]: info: CoreCommandRouter::volumioGetQueue Mar 29 20:09:10 volumio volumio[1138]: info: CoreStateMachine::getQueue Mar 29 20:09:10 volumio volumio[1138]: info: CorePlayQueue::getQueue Mar 29 20:09:17 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 20:09:17 volumio volumio[1138]: info: CURURI: music-library Mar 29 20:09:17 volumio volumio[1138]: info: Preload queue cleared Mar 29 20:09:18 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 20:09:18 volumio volumio[1138]: info: CURURI: music-library/USB Mar 29 20:09:18 volumio volumio[1138]: info: Preload queue cleared Mar 29 20:09:20 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 20:09:20 volumio volumio[1138]: info: CURURI: music-library/USB/EFI Mar 29 20:09:20 volumio volumio[1138]: info: Preload queue cleared Mar 29 20:09:22 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 20:09:22 volumio volumio[1138]: info: CURURI: music-library/USB/EFI/efi Mar 29 20:09:22 volumio volumio[1138]: info: Preload queue cleared Mar 29 20:09:23 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 20:09:23 volumio volumio[1138]: info: CURURI: music-library/USB/EFI/efi/boot Mar 29 20:09:23 volumio volumio[1138]: info: Preload queue cleared Mar 29 20:09:23 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 20:09:23 volumio volumio[1138]: info: CURURI: music-library/USB/EFI/efi/boot/zh-cn Mar 29 20:09:23 volumio volumio[1138]: error: Failed LSINFO: null Mar 29 20:09:23 volumio volumio[1138]: info: Preload queue cleared Mar 29 20:09:27 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 20:09:27 volumio volumio[1138]: info: CURURI: music-library Mar 29 20:09:27 volumio volumio[1138]: info: Preload queue cleared Mar 29 20:09:28 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 20:09:28 volumio volumio[1138]: info: CURURI: music-library/USB Mar 29 20:09:28 volumio volumio[1138]: info: Preload queue cleared Mar 29 20:09:29 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 20:09:29 volumio volumio[1138]: info: CURURI: music-library/USB/342D-697D Mar 29 20:09:29 volumio volumio[1138]: info: Preload queue cleared Mar 29 20:09:35 volumio volumio[1138]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 20:09:35 volumio volumio[1138]: info: CURURI: music-library/USB/342D-697D/新建文件夹 (5) Mar 29 20:09:35 volumio volumio[1138]: info: Preload queue cleared Mar 29 20:09:56 volumio volumio[1138]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 20:09:56 volumio volumio[1138]: Error: connect ETIMEDOUT 31.13.94.10:80 Mar 29 20:09:56 volumio volumio[1138]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Mar 29 20:09:56 volumio volumio[1138]: errno: -110, Mar 29 20:09:56 volumio volumio[1138]: code: 'ETIMEDOUT', Mar 29 20:09:56 volumio volumio[1138]: syscall: 'connect', Mar 29 20:09:56 volumio volumio[1138]: address: '31.13.94.10', Mar 29 20:09:56 volumio volumio[1138]: port: 80 Mar 29 20:09:56 volumio volumio[1138]: } Mar 29 20:09:56 volumio volumio[1138]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 20:09:58 volumio sudo[3425]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-29 20:08' Mar 29 20:09:58 volumio sudo[3425]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"