-- Logs begin at Mon 2024-07-22 20:17:11 HKT, end at Mon 2024-07-22 20:19:15 HKT. -- Jul 22 20:18:09 volumio volumio[931]: info: CoreCommandRouter::volumioGetState Jul 22 20:18:09 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Jul 22 20:18:09 volumio volumio[931]: info: CoreCommandRouter::volumioGetState Jul 22 20:18:09 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Jul 22 20:18:20 volumio volumio[931]: info: CoreCommandRouter::volumioGetState Jul 22 20:18:20 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Jul 22 20:18:20 volumio volumio[931]: info: CoreCommandRouter::volumioGetState Jul 22 20:18:20 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Jul 22 20:18:21 volumio kernel: usb 1-3.3: USB disconnect, device number 4 Jul 22 20:18:21 volumio volumio[931]: info: Partition removed: {"syspath":"/sys/devices/pci0000:00/0000:00:14.0/usb1/1-3/1-3.3/1-3.3:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb1","ACTION":"remove","DEVLINKS":"/dev/disk/by-uuid/3082A9BB82A985C6 /dev/disk/by-path/pci-0000:00:14.0-usb-0:3.3:1.0-scsi-0:0:0:0-part1 /dev/disk/by-label/music /dev/disk/by-partuuid/d88a1120-01 /dev/disk/by-id/usb-SMI_USB_DISK-0:0-part1","DEVNAME":"/dev/sdb1","DEVPATH":"/devices/pci0000:00/0000:00:14.0/usb1/1-3/1-3.3/1-3.3:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb1","DEVTYPE":"partition","DISKSEQ":"2","ID_BUS":"usb","ID_FS_LABEL":"music","ID_FS_LABEL_ENC":"music","ID_FS_TYPE":"ntfs","ID_FS_USAGE":"filesystem","ID_FS_UUID":"3082A9BB82A985C6","ID_FS_UUID_ENC":"3082A9BB82A985C6","ID_INSTANCE":"0:0","ID_MODEL":"USB_DISK","ID_MODEL_ENC":"USB\\x20DISK\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20","ID_MODEL_ID":"1000","ID_PART_ENTRY_DISK":"8:16","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"2048","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"31330304","ID_PART_ENTRY_TYPE":"0x7","ID_PART_ENTRY_UUID":"d88a1120-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"d88a1120","ID_PATH":"pci-0000:00:14.0-usb-0:3.3:1.0-scsi-0:0:0:0","ID_PATH_TAG":"pci-0000_00_14_0-usb-0_3_3_1_0-scsi-0_0_0_0","ID_REVISION":"1100","ID_SERIAL":"SMI_USB_DISK-0:0","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"SMI","ID_VENDOR_ENC":"SMI\\x20\\x20\\x20\\x20\\x20","ID_VENDOR_ID":"090c","MAJOR":"8","MINOR":"17","PARTN":"1","SEQNUM":"3265","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"3833364"} Jul 22 20:18:21 volumio kernel: sd 1:0:0:0: [sdb] Synchronizing SCSI cache Jul 22 20:18:21 volumio kernel: sd 1:0:0:0: [sdb] Synchronize Cache(10) failed: Result: hostbyte=DID_NO_CONNECT driverbyte=DRIVER_OK Jul 22 20:18:21 volumio sudo[1897]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sdb1 Jul 22 20:18:21 volumio sudo[1897]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 20:18:21 volumio ntfs-3g[1168]: Unmounting /dev/sdb1 (music) Jul 22 20:18:21 volumio ntfs-3g[1168]: Failed to sync device /dev/sdb1: Input/output error Jul 22 20:18:21 volumio ntfs-3g[1168]: Failed to close volume /dev/sdb1: Input/output error Jul 22 20:18:21 volumio systemd[1]: media-music.mount: Succeeded. Jul 22 20:18:21 volumio sudo[1897]: pam_unix(sudo:session): session closed for user root Jul 22 20:18:23 volumio kernel: usb 1-3.3: new high-speed USB device number 5 using xhci_hcd Jul 22 20:18:23 volumio kernel: usb 1-3.3: New USB device found, idVendor=090c, idProduct=1000, bcdDevice=11.00 Jul 22 20:18:23 volumio kernel: usb 1-3.3: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Jul 22 20:18:23 volumio kernel: usb 1-3.3: Product: USB DISK Jul 22 20:18:23 volumio kernel: usb 1-3.3: Manufacturer: SMI Corporation Jul 22 20:18:23 volumio kernel: usb-storage 1-3.3:1.0: USB Mass Storage device detected Jul 22 20:18:23 volumio kernel: scsi host1: usb-storage 1-3.3:1.0 Jul 22 20:18:25 volumio kernel: scsi 1:0:0:0: Direct-Access SMI USB DISK 1100 PQ: 0 ANSI: 4 Jul 22 20:18:25 volumio kernel: sd 1:0:0:0: Attached scsi generic sg1 type 0 Jul 22 20:18:25 volumio kernel: sd 1:0:0:0: [sdb] 31334400 512-byte logical blocks: (16.0 GB/14.9 GiB) Jul 22 20:18:25 volumio kernel: sd 1:0:0:0: [sdb] Write Protect is off Jul 22 20:18:25 volumio kernel: sd 1:0:0:0: [sdb] Mode Sense: 43 00 00 00 Jul 22 20:18:25 volumio kernel: sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Jul 22 20:18:25 volumio kernel: sdb: sdb1 Jul 22 20:18:25 volumio kernel: sd 1:0:0:0: [sdb] Attached SCSI removable disk Jul 22 20:18:25 volumio volumio[931]: info: Mounting Device music Jul 22 20:18:25 volumio sudo[1908]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/music -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jul 22 20:18:25 volumio sudo[1908]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 20:18:25 volumio ntfs-3g[1911]: Version 2017.3.23AR.3 integrated FUSE 28 Jul 22 20:18:25 volumio ntfs-3g[1911]: Mounted /dev/sdb1 (Read-Write, label "music", NTFS 3.1) Jul 22 20:18:25 volumio ntfs-3g[1911]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Jul 22 20:18:25 volumio ntfs-3g[1911]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sdb1,blkdev,blksize=4096 Jul 22 20:18:25 volumio ntfs-3g[1911]: Global ownership and permissions enforced, configuration type 7 Jul 22 20:18:25 volumio sudo[1908]: pam_unix(sudo:session): session closed for user root Jul 22 20:18:25 volumio volumio[931]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/music Jul 22 20:18:26 volumio volumio[931]: info: Scanning removed location : "USB/music" Jul 22 20:18:26 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand status Jul 22 20:18:26 volumio volumio[931]: info: Jul 22 20:18:26 volumio volumio[931]: ---------------------------- MPD announces state update: update Jul 22 20:18:26 volumio volumio[931]: info: ControllerMpd::getState Jul 22 20:18:26 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand status Jul 22 20:18:26 volumio volumio[931]: info: sendMpdCommand status took 6 milliseconds Jul 22 20:18:26 volumio volumio[931]: info: sendMpdCommand status took 4 milliseconds Jul 22 20:18:26 volumio volumio[931]: verbose: ControllerMpd::parseState Jul 22 20:18:26 volumio volumio[931]: info: Command Router : Notfying DB Updatetrue Jul 22 20:18:26 volumio volumio[931]: verbose: ControllerMpd::parseState Jul 22 20:18:26 volumio volumio[931]: info: ControllerMpd::pushState Jul 22 20:18:26 volumio volumio[931]: info: CoreCommandRouter::servicePushState Jul 22 20:18:26 volumio volumio[931]: info: CoreStateMachine::pushState Jul 22 20:18:26 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Jul 22 20:18:26 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Jul 22 20:18:26 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Jul 22 20:18:26 volumio volumio[931]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 22 20:18:26 volumio volumio[931]: verbose: CURRENT POSITION 0 Jul 22 20:18:26 volumio volumio[931]: info: CoreStateMachine::syncState stateService stop Jul 22 20:18:26 volumio volumio[931]: info: CoreStateMachine::syncState currentStatus stop Jul 22 20:18:26 volumio volumio[931]: info: CoreStateMachine::pushState Jul 22 20:18:26 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Jul 22 20:18:26 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 22 20:18:26 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Jul 22 20:18:26 volumio volumio[931]: info: No code Jul 22 20:18:26 volumio volumio[931]: info: CoreStateMachine::pushState Jul 22 20:18:26 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Jul 22 20:18:26 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Jul 22 20:18:26 volumio volumio[931]: info: ------------------------------ 107ms Jul 22 20:18:28 volumio volumio[931]: info: CoreCommandRouter::Close All Modals sent Jul 22 20:18:30 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 22 20:18:30 volumio volumio[931]: info: CURURI: music-library Jul 22 20:18:30 volumio volumio[931]: info: Preload queue cleared Jul 22 20:18:31 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 22 20:18:31 volumio volumio[931]: info: CURURI: music-library/USB Jul 22 20:18:31 volumio volumio[931]: info: Preload queue cleared Jul 22 20:18:31 volumio volumio[931]: info: CoreCommandRouter::volumioGetState Jul 22 20:18:31 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Jul 22 20:18:32 volumio volumio[931]: info: CoreCommandRouter::volumioGetState Jul 22 20:18:32 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Jul 22 20:18:32 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 22 20:18:32 volumio volumio[931]: info: CURURI: music-library/USB/music Jul 22 20:18:32 volumio volumio[931]: info: Preload queue cleared Jul 22 20:18:33 volumio ntpd[1503]: Soliciting pool server 193.182.111.143 Jul 22 20:18:33 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 22 20:18:33 volumio volumio[931]: info: CURURI: music-library/USB/music/《步步高_试机碟》 Jul 22 20:18:33 volumio volumio[931]: info: Preload queue cleared Jul 22 20:18:33 volumio volumio[931]: info: Preloading song: music-library/USB/music/《步步高_试机碟》/步步高_试机碟.wav Jul 22 20:18:33 volumio volumio[931]: info: Exploding uri music-library/USB/music/《步步高_试机碟》/步步高_试机碟.wav in service mpd Jul 22 20:18:33 volumio volumio[931]: info: ALBUMART /albumart?cacheid=71&web=//extralarge&path=%2Fmnt%2FUSB%2Fmusic%2F%E3%80%8A%E6%AD%A5%E6%AD%A5%E9%AB%98_%E8%AF%95%E6%9C%BA%E7%A2%9F%E3%80%8B%2F%E6%AD%A5%E6%AD%A5%E9%AB%98_%E8%AF%95%E6%9C%BA%E7%A2%9F.wav&metadata=false Jul 22 20:18:33 volumio volumio[931]: info: URI /mnt/USB/music/《步步高_试机碟》/步步高_试机碟.wav Jul 22 20:18:34 volumio volumio[931]: info: Preload queue cleared Jul 22 20:18:34 volumio volumio[931]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 22 20:18:34 volumio volumio[931]: info: CoreStateMachine::ClearQueue Jul 22 20:18:34 volumio volumio[931]: info: CoreStateMachine::stop Jul 22 20:18:34 volumio volumio[931]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 22 20:18:34 volumio volumio[931]: info: CorePlayQueue::clearPlayQueue Jul 22 20:18:34 volumio volumio[931]: info: CorePlayQueue::saveQueue Jul 22 20:18:34 volumio volumio[931]: info: CoreCommandRouter::volumioPushQueue Jul 22 20:18:34 volumio volumio[931]: info: CoreStateMachine::addQueueItems Jul 22 20:18:34 volumio volumio[931]: info: CorePlayQueue::addQueueItems Jul 22 20:18:34 volumio volumio[931]: info: Preload queue cleared Jul 22 20:18:34 volumio volumio[931]: info: Adding Item to queue: music-library/USB/music/《步步高_试机碟》/步步高_试机碟.wav Jul 22 20:18:34 volumio volumio[931]: info: Using cached record of: music-library/USB/music/《步步高_试机碟》/步步高_试机碟.wav Jul 22 20:18:34 volumio volumio[931]: info: CoreCommandRouter::volumioPushQueue Jul 22 20:18:34 volumio volumio[931]: info: CorePlayQueue::saveQueue Jul 22 20:18:34 volumio volumio[931]: info: CoreStateMachine::updateTrackBlock Jul 22 20:18:34 volumio volumio[931]: info: CorePlayQueue::getTrackBlock Jul 22 20:18:34 volumio volumio[931]: info: CoreCommandRouter::volumioPlay Jul 22 20:18:34 volumio volumio[931]: info: CoreStateMachine::play index 0 Jul 22 20:18:34 volumio volumio[931]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 22 20:18:34 volumio volumio[931]: info: CoreStateMachine::stop Jul 22 20:18:34 volumio volumio[931]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 22 20:18:34 volumio volumio[931]: info: CoreStateMachine::play index undefined Jul 22 20:18:34 volumio volumio[931]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 22 20:18:34 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Jul 22 20:18:34 volumio volumio[931]: info: CoreStateMachine::startPlaybackTimer Jul 22 20:18:34 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Jul 22 20:18:34 volumio volumio[931]: verbose: ControllerMpd::clearAddPlayTracks USB/music/《步步高_试机碟》/步步高_试机碟.wav Jul 22 20:18:34 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand stop Jul 22 20:18:34 volumio volumio[931]: info: sendMpdCommand stop took 5 milliseconds Jul 22 20:18:34 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand clear Jul 22 20:18:34 volumio volumio[931]: info: Jul 22 20:18:34 volumio volumio[931]: ---------------------------- MPD announces system playlist update Jul 22 20:18:34 volumio volumio[931]: info: Ignoring MPD Status Update Jul 22 20:18:34 volumio volumio[931]: info: sendMpdCommand clear took 4 milliseconds Jul 22 20:18:34 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand add "USB/music/《步步高_试机碟》/步步高_试机碟.wav" Jul 22 20:18:34 volumio volumio[931]: error: updateQueue error: null Jul 22 20:18:34 volumio volumio[931]: info: Jul 22 20:18:34 volumio volumio[931]: ---------------------------- MPD announces system playlist update Jul 22 20:18:34 volumio volumio[931]: info: Ignoring MPD Status Update Jul 22 20:18:34 volumio volumio[931]: info: ------------------------------ 7ms Jul 22 20:18:34 volumio volumio[931]: info: sendMpdCommand add "USB/music/《步步高_试机碟》/步步高_试机碟.wav" took 4 milliseconds Jul 22 20:18:34 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand play Jul 22 20:18:34 volumio volumio[931]: error: Upnp client error: Error: This socket has been ended by the other party Jul 22 20:18:34 volumio volumio[931]: info: Jul 22 20:18:34 volumio volumio[931]: ---------------------------- MPD announces state update: player Jul 22 20:18:34 volumio volumio[931]: info: ------------------------------ 9ms Jul 22 20:18:34 volumio volumio[931]: info: sendMpdCommand play took 7 milliseconds Jul 22 20:18:34 volumio volumio[931]: info: ControllerMpd::getState Jul 22 20:18:34 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand status Jul 22 20:18:34 volumio volumio[931]: info: Jul 22 20:18:34 volumio volumio[931]: ---------------------------- MPD announces state update: player Jul 22 20:18:34 volumio volumio[931]: info: sendMpdCommand status took 45 milliseconds Jul 22 20:18:34 volumio volumio[931]: info: ControllerMpd::getState Jul 22 20:18:34 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand status Jul 22 20:18:34 volumio volumio[931]: verbose: ControllerMpd::parseState Jul 22 20:18:34 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 22 20:18:34 volumio volumio[931]: info: sendMpdCommand status took 3 milliseconds Jul 22 20:18:34 volumio volumio[931]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 22 20:18:34 volumio volumio[931]: verbose: ControllerMpd::parseState Jul 22 20:18:34 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 22 20:18:34 volumio volumio[931]: verbose: ControllerMpd::parseTrackInfo Jul 22 20:18:34 volumio volumio[931]: info: ControllerMpd::pushState Jul 22 20:18:34 volumio volumio[931]: info: CoreCommandRouter::servicePushState Jul 22 20:18:34 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Jul 22 20:18:34 volumio volumio[931]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3443,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"步步高_试机碟.wav","artist":null,"album":null,"uri":"USB/music/《步步高_试机碟》/步步高_试机碟.wav","trackType":"wav"} Jul 22 20:18:34 volumio volumio[931]: verbose: CURRENT POSITION 0 Jul 22 20:18:34 volumio volumio[931]: info: CoreStateMachine::syncState stateService play Jul 22 20:18:34 volumio volumio[931]: info: CoreStateMachine::syncState currentStatus stop Jul 22 20:18:34 volumio volumio[931]: info: ------------------------------ 57ms Jul 22 20:18:34 volumio volumio[931]: info: sendMpdCommand playlistinfo took 7 milliseconds Jul 22 20:18:34 volumio volumio[931]: verbose: ControllerMpd::parseTrackInfo Jul 22 20:18:34 volumio volumio[931]: info: ControllerMpd::pushState Jul 22 20:18:34 volumio volumio[931]: info: CoreCommandRouter::servicePushState Jul 22 20:18:34 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Jul 22 20:18:34 volumio volumio[931]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":3443,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"步步高_试机碟.wav","artist":null,"album":null,"uri":"USB/music/《步步高_试机碟》/步步高_试机碟.wav","trackType":"wav"} Jul 22 20:18:34 volumio volumio[931]: verbose: CURRENT POSITION 0 Jul 22 20:18:34 volumio volumio[931]: info: CoreStateMachine::syncState stateService play Jul 22 20:18:34 volumio volumio[931]: info: CoreStateMachine::syncState currentStatus play Jul 22 20:18:34 volumio volumio[931]: info: Received an update from plugin. extracting info from payload Jul 22 20:18:34 volumio volumio[931]: info: CoreStateMachine::pushState Jul 22 20:18:34 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Jul 22 20:18:34 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 22 20:18:34 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Jul 22 20:18:34 volumio volumio[931]: info: CoreStateMachine::pushState Jul 22 20:18:34 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Jul 22 20:18:34 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Jul 22 20:18:34 volumio volumio[931]: info: ------------------------------ 49ms Jul 22 20:18:36 volumio volumio[931]: info: CoreCommandRouter::volumioGetState Jul 22 20:18:36 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Jul 22 20:18:36 volumio ntpd[1503]: Soliciting pool server 211.68.71.118 Jul 22 20:18:37 volumio ntpd[1503]: Soliciting pool server 202.118.1.81 Jul 22 20:18:42 volumio volumio[931]: info: MPD Database updated - AlbumList cache refreshed Jul 22 20:18:42 volumio volumio[931]: info: Jul 22 20:18:42 volumio volumio[931]: ---------------------------- MPD announces state update: database Jul 22 20:18:42 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand status Jul 22 20:18:42 volumio volumio[931]: info: Jul 22 20:18:42 volumio volumio[931]: ---------------------------- MPD announces state update: update Jul 22 20:18:42 volumio volumio[931]: info: ControllerMpd::getState Jul 22 20:18:42 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand status Jul 22 20:18:42 volumio volumio[931]: info: ControllerMpd::getState Jul 22 20:18:42 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand status Jul 22 20:18:42 volumio volumio[931]: info: sendMpdCommand status took 7 milliseconds Jul 22 20:18:42 volumio volumio[931]: verbose: ControllerMpd::parseState Jul 22 20:18:42 volumio volumio[931]: info: Command Router : Notfying DB Updatefalse Jul 22 20:18:42 volumio volumio[931]: info: CoreCommandRouter::Close All Modals sent Jul 22 20:18:42 volumio volumio[931]: info: sendMpdCommand status took 51 milliseconds Jul 22 20:18:42 volumio volumio[931]: info: sendMpdCommand status took 50 milliseconds Jul 22 20:18:42 volumio volumio[931]: verbose: ControllerMpd::parseState Jul 22 20:18:42 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 22 20:18:42 volumio volumio[931]: verbose: ControllerMpd::parseState Jul 22 20:18:42 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 22 20:18:42 volumio volumio[931]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 22 20:18:42 volumio volumio[931]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 22 20:18:42 volumio volumio[931]: verbose: ControllerMpd::parseTrackInfo Jul 22 20:18:42 volumio volumio[931]: verbose: ControllerMpd::parseTrackInfo Jul 22 20:18:42 volumio volumio[931]: info: ControllerMpd::pushState Jul 22 20:18:42 volumio volumio[931]: info: CoreCommandRouter::servicePushState Jul 22 20:18:42 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Jul 22 20:18:42 volumio volumio[931]: verbose: STATE SERVICE {"status":"play","position":0,"seek":9152,"duration":3443,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"步步高_试机碟.wav","artist":null,"album":null,"uri":"USB/music/《步步高_试机碟》/步步高_试机碟.wav","trackType":"wav"} Jul 22 20:18:42 volumio volumio[931]: verbose: CURRENT POSITION 0 Jul 22 20:18:42 volumio volumio[931]: info: CoreStateMachine::syncState stateService play Jul 22 20:18:42 volumio volumio[931]: info: CoreStateMachine::syncState currentStatus play Jul 22 20:18:42 volumio volumio[931]: info: Received an update from plugin. extracting info from payload Jul 22 20:18:42 volumio volumio[931]: info: CoreStateMachine::pushState Jul 22 20:18:42 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Jul 22 20:18:42 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 22 20:18:42 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Jul 22 20:18:42 volumio volumio[931]: info: CoreStateMachine::pushState Jul 22 20:18:42 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Jul 22 20:18:42 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Jul 22 20:18:42 volumio volumio[931]: info: ControllerMpd::pushState Jul 22 20:18:42 volumio volumio[931]: info: CoreCommandRouter::servicePushState Jul 22 20:18:42 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Jul 22 20:18:42 volumio volumio[931]: verbose: STATE SERVICE {"status":"play","position":0,"seek":9152,"duration":3443,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"步步高_试机碟.wav","artist":null,"album":null,"uri":"USB/music/《步步高_试机碟》/步步高_试机碟.wav","trackType":"wav"} Jul 22 20:18:42 volumio volumio[931]: verbose: CURRENT POSITION 0 Jul 22 20:18:42 volumio volumio[931]: info: CoreStateMachine::syncState stateService play Jul 22 20:18:42 volumio volumio[931]: info: CoreStateMachine::syncState currentStatus play Jul 22 20:18:42 volumio volumio[931]: info: Received an update from plugin. extracting info from payload Jul 22 20:18:42 volumio volumio[931]: info: CoreStateMachine::pushState Jul 22 20:18:42 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Jul 22 20:18:42 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Jul 22 20:18:42 volumio volumio[931]: info: CoreStateMachine::pushState Jul 22 20:18:42 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Jul 22 20:18:42 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Jul 22 20:18:42 volumio volumio[931]: info: ------------------------------ 95ms Jul 22 20:18:42 volumio volumio[931]: info: ------------------------------ 96ms Jul 22 20:18:43 volumio volumio[931]: info: CoreCommandRouter::volumioGetState Jul 22 20:18:43 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Jul 22 20:18:43 volumio sudo[1958]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Jul 22 20:18:43 volumio sudo[1958]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 20:18:45 volumio volumio[931]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 6 Jul 22 20:18:45 volumio volumio[931]: info: CoreCommandRouter::volumioGetState Jul 22 20:18:45 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Jul 22 20:18:51 volumio sshd[1996]: Accepted password for volumio from 192.168.255.3 port 60184 ssh2 Jul 22 20:18:51 volumio sshd[1996]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Jul 22 20:18:52 volumio systemd[1]: Created slice User Slice of UID 1000. Jul 22 20:18:52 volumio systemd[1]: Starting User Runtime Directory /run/user/1000... Jul 22 20:18:52 volumio systemd-logind[712]: New session 1 of user volumio. Jul 22 20:18:52 volumio systemd[1]: Started User Runtime Directory /run/user/1000. Jul 22 20:18:52 volumio systemd[1]: Starting User Manager for UID 1000... Jul 22 20:18:52 volumio systemd[2009]: pam_unix(systemd-user:session): session opened for user volumio by (uid=0) Jul 22 20:18:52 volumio systemd[2009]: Listening on GnuPG network certificate management daemon. Jul 22 20:18:52 volumio systemd[2009]: Starting D-Bus User Message Bus Socket. Jul 22 20:18:52 volumio systemd[2009]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers). Jul 22 20:18:52 volumio systemd[2009]: Listening on GnuPG cryptographic agent and passphrase cache (restricted). Jul 22 20:18:52 volumio systemd[2009]: Listening on Sound System. Jul 22 20:18:52 volumio systemd[2009]: Reached target Paths. Jul 22 20:18:52 volumio systemd[2009]: Reached target Timers. Jul 22 20:18:52 volumio systemd[2009]: Listening on GnuPG cryptographic agent and passphrase cache. Jul 22 20:18:52 volumio systemd[2009]: Listening on GnuPG cryptographic agent (ssh-agent emulation). Jul 22 20:18:52 volumio systemd[2009]: Listening on D-Bus User Message Bus Socket. Jul 22 20:18:52 volumio systemd[2009]: Reached target Sockets. Jul 22 20:18:52 volumio systemd[2009]: Reached target Basic System. Jul 22 20:18:52 volumio systemd[2009]: Starting Music Player Daemon... Jul 22 20:18:52 volumio systemd[1]: Started User Manager for UID 1000. Jul 22 20:18:52 volumio systemd[1]: Started Session 1 of user volumio. Jul 22 20:18:52 volumio mpd[2020]: exception: failed to open log file "/var/log/mpd.log" (config line 8): Permission denied Jul 22 20:18:52 volumio systemd[2009]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jul 22 20:18:52 volumio systemd[2009]: mpd.service: Failed with result 'exit-code'. Jul 22 20:18:52 volumio systemd[2009]: Failed to start Music Player Daemon. Jul 22 20:18:52 volumio systemd[2009]: Reached target Default. Jul 22 20:18:52 volumio systemd[2009]: Startup finished in 875ms. Jul 22 20:19:02 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 22 20:19:02 volumio volumio[931]: info: CURURI: music-library/USB/music/Gerber_Raspberry_5_car_ssd_2.zip Jul 22 20:19:02 volumio volumio[931]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jul 22 20:19:02 volumio volumio[931]: info: Preload queue cleared Jul 22 20:19:04 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 22 20:19:04 volumio volumio[931]: info: CURURI: music-library/USB/music/System Volume Information Jul 22 20:19:04 volumio volumio[931]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jul 22 20:19:04 volumio volumio[931]: info: Preload queue cleared Jul 22 20:19:09 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 22 20:19:09 volumio volumio[931]: info: CURURI: music-library/USB/music Jul 22 20:19:09 volumio volumio[931]: info: Preload queue cleared Jul 22 20:19:10 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 22 20:19:10 volumio volumio[931]: info: CURURI: music-library/USB/music/惠威试音碟 (人声篇) Jul 22 20:19:10 volumio volumio[931]: info: Preload queue cleared Jul 22 20:19:10 volumio volumio[931]: info: Preloading song: music-library/USB/music/惠威试音碟 (人声篇)/01.月光小夜曲.wav Jul 22 20:19:10 volumio volumio[931]: info: Preloading song: music-library/USB/music/惠威试音碟 (人声篇)/02.初次尝到寂寞.wav Jul 22 20:19:10 volumio volumio[931]: info: Preloading song: music-library/USB/music/惠威试音碟 (人声篇)/03.ONE I LOVE.wav Jul 22 20:19:10 volumio volumio[931]: info: Preloading song: music-library/USB/music/惠威试音碟 (人声篇)/04.往事如昔.wav Jul 22 20:19:10 volumio volumio[931]: info: Preloading song: music-library/USB/music/惠威试音碟 (人声篇)/05.夜色.wav Jul 22 20:19:10 volumio volumio[931]: info: Preloading song: music-library/USB/music/惠威试音碟 (人声篇)/06.情人的眼泪.wav Jul 22 20:19:10 volumio volumio[931]: info: Preloading song: music-library/USB/music/惠威试音碟 (人声篇)/07.月亮代表我的心.wav Jul 22 20:19:10 volumio volumio[931]: info: Preloading song: music-library/USB/music/惠威试音碟 (人声篇)/08.眼神骗不过.wav Jul 22 20:19:10 volumio volumio[931]: info: Preloading song: music-library/USB/music/惠威试音碟 (人声篇)/09.风继续吹.wav Jul 22 20:19:10 volumio volumio[931]: info: Preloading song: music-library/USB/music/惠威试音碟 (人声篇)/10.挥着翅膀的女孩.wav Jul 22 20:19:10 volumio volumio[931]: info: Preloading song: music-library/USB/music/惠威试音碟 (人声篇)/11.泪海.wav Jul 22 20:19:10 volumio volumio[931]: info: Preloading song: music-library/USB/music/惠威试音碟 (人声篇)/12.相思河畔.wav Jul 22 20:19:10 volumio volumio[931]: info: Preloading song: music-library/USB/music/惠威试音碟 (人声篇)/13.天空.wav Jul 22 20:19:10 volumio volumio[931]: info: Exploding uri music-library/USB/music/惠威试音碟 (人声篇)/01.月光小夜曲.wav in service mpd Jul 22 20:19:10 volumio volumio[931]: info: ALBUMART /albumart?cacheid=71&web=//extralarge&path=%2Fmnt%2FUSB%2Fmusic%2F%E6%83%A0%E5%A8%81%E8%AF%95%E9%9F%B3%E7%A2%9F%20(%E4%BA%BA%E5%A3%B0%E7%AF%87)%2F01.%E6%9C%88%E5%85%89%E5%B0%8F%E5%A4%9C%E6%9B%B2.wav&metadata=false Jul 22 20:19:10 volumio volumio[931]: info: URI /mnt/USB/music/惠威试音碟 (人声篇)/01.月光小夜曲.wav Jul 22 20:19:10 volumio volumio[931]: info: Exploding uri music-library/USB/music/惠威试音碟 (人声篇)/02.初次尝到寂寞.wav in service mpd Jul 22 20:19:10 volumio volumio[931]: info: ALBUMART /albumart?cacheid=71&web=//extralarge&path=%2Fmnt%2FUSB%2Fmusic%2F%E6%83%A0%E5%A8%81%E8%AF%95%E9%9F%B3%E7%A2%9F%20(%E4%BA%BA%E5%A3%B0%E7%AF%87)%2F02.%E5%88%9D%E6%AC%A1%E5%B0%9D%E5%88%B0%E5%AF%82%E5%AF%9E.wav&metadata=false Jul 22 20:19:10 volumio volumio[931]: info: URI /mnt/USB/music/惠威试音碟 (人声篇)/02.初次尝到寂寞.wav Jul 22 20:19:10 volumio volumio[931]: info: Exploding uri music-library/USB/music/惠威试音碟 (人声篇)/03.ONE I LOVE.wav in service mpd Jul 22 20:19:10 volumio volumio[931]: info: ALBUMART /albumart?cacheid=71&web=//extralarge&path=%2Fmnt%2FUSB%2Fmusic%2F%E6%83%A0%E5%A8%81%E8%AF%95%E9%9F%B3%E7%A2%9F%20(%E4%BA%BA%E5%A3%B0%E7%AF%87)%2F03.ONE%20I%20LOVE.wav&metadata=false Jul 22 20:19:10 volumio volumio[931]: info: URI /mnt/USB/music/惠威试音碟 (人声篇)/03.ONE I LOVE.wav Jul 22 20:19:10 volumio volumio[931]: info: Exploding uri music-library/USB/music/惠威试音碟 (人声篇)/04.往事如昔.wav in service mpd Jul 22 20:19:10 volumio volumio[931]: info: ALBUMART /albumart?cacheid=71&web=//extralarge&path=%2Fmnt%2FUSB%2Fmusic%2F%E6%83%A0%E5%A8%81%E8%AF%95%E9%9F%B3%E7%A2%9F%20(%E4%BA%BA%E5%A3%B0%E7%AF%87)%2F04.%E5%BE%80%E4%BA%8B%E5%A6%82%E6%98%94.wav&metadata=false Jul 22 20:19:10 volumio volumio[931]: info: URI /mnt/USB/music/惠威试音碟 (人声篇)/04.往事如昔.wav Jul 22 20:19:10 volumio volumio[931]: info: Exploding uri music-library/USB/music/惠威试音碟 (人声篇)/05.夜色.wav in service mpd Jul 22 20:19:10 volumio volumio[931]: info: ALBUMART /albumart?cacheid=71&web=//extralarge&path=%2Fmnt%2FUSB%2Fmusic%2F%E6%83%A0%E5%A8%81%E8%AF%95%E9%9F%B3%E7%A2%9F%20(%E4%BA%BA%E5%A3%B0%E7%AF%87)%2F05.%E5%A4%9C%E8%89%B2.wav&metadata=false Jul 22 20:19:10 volumio volumio[931]: info: URI /mnt/USB/music/惠威试音碟 (人声篇)/05.夜色.wav Jul 22 20:19:10 volumio volumio[931]: info: Exploding uri music-library/USB/music/惠威试音碟 (人声篇)/06.情人的眼泪.wav in service mpd Jul 22 20:19:10 volumio volumio[931]: info: ALBUMART /albumart?cacheid=71&web=//extralarge&path=%2Fmnt%2FUSB%2Fmusic%2F%E6%83%A0%E5%A8%81%E8%AF%95%E9%9F%B3%E7%A2%9F%20(%E4%BA%BA%E5%A3%B0%E7%AF%87)%2F06.%E6%83%85%E4%BA%BA%E7%9A%84%E7%9C%BC%E6%B3%AA.wav&metadata=false Jul 22 20:19:10 volumio volumio[931]: info: URI /mnt/USB/music/惠威试音碟 (人声篇)/06.情人的眼泪.wav Jul 22 20:19:10 volumio volumio[931]: info: Exploding uri music-library/USB/music/惠威试音碟 (人声篇)/07.月亮代表我的心.wav in service mpd Jul 22 20:19:10 volumio volumio[931]: info: ALBUMART /albumart?cacheid=71&web=//extralarge&path=%2Fmnt%2FUSB%2Fmusic%2F%E6%83%A0%E5%A8%81%E8%AF%95%E9%9F%B3%E7%A2%9F%20(%E4%BA%BA%E5%A3%B0%E7%AF%87)%2F07.%E6%9C%88%E4%BA%AE%E4%BB%A3%E8%A1%A8%E6%88%91%E7%9A%84%E5%BF%83.wav&metadata=false Jul 22 20:19:10 volumio volumio[931]: info: URI /mnt/USB/music/惠威试音碟 (人声篇)/07.月亮代表我的心.wav Jul 22 20:19:10 volumio volumio[931]: info: Exploding uri music-library/USB/music/惠威试音碟 (人声篇)/08.眼神骗不过.wav in service mpd Jul 22 20:19:10 volumio volumio[931]: info: ALBUMART /albumart?cacheid=71&web=//extralarge&path=%2Fmnt%2FUSB%2Fmusic%2F%E6%83%A0%E5%A8%81%E8%AF%95%E9%9F%B3%E7%A2%9F%20(%E4%BA%BA%E5%A3%B0%E7%AF%87)%2F08.%E7%9C%BC%E7%A5%9E%E9%AA%97%E4%B8%8D%E8%BF%87.wav&metadata=false Jul 22 20:19:10 volumio volumio[931]: info: URI /mnt/USB/music/惠威试音碟 (人声篇)/08.眼神骗不过.wav Jul 22 20:19:10 volumio volumio[931]: info: Exploding uri music-library/USB/music/惠威试音碟 (人声篇)/09.风继续吹.wav in service mpd Jul 22 20:19:10 volumio volumio[931]: info: ALBUMART /albumart?cacheid=71&web=//extralarge&path=%2Fmnt%2FUSB%2Fmusic%2F%E6%83%A0%E5%A8%81%E8%AF%95%E9%9F%B3%E7%A2%9F%20(%E4%BA%BA%E5%A3%B0%E7%AF%87)%2F09.%E9%A3%8E%E7%BB%A7%E7%BB%AD%E5%90%B9.wav&metadata=false Jul 22 20:19:10 volumio volumio[931]: info: URI /mnt/USB/music/惠威试音碟 (人声篇)/09.风继续吹.wav Jul 22 20:19:10 volumio volumio[931]: info: Exploding uri music-library/USB/music/惠威试音碟 (人声篇)/10.挥着翅膀的女孩.wav in service mpd Jul 22 20:19:10 volumio volumio[931]: info: ALBUMART /albumart?cacheid=71&web=//extralarge&path=%2Fmnt%2FUSB%2Fmusic%2F%E6%83%A0%E5%A8%81%E8%AF%95%E9%9F%B3%E7%A2%9F%20(%E4%BA%BA%E5%A3%B0%E7%AF%87)%2F10.%E6%8C%A5%E7%9D%80%E7%BF%85%E8%86%80%E7%9A%84%E5%A5%B3%E5%AD%A9.wav&metadata=false Jul 22 20:19:10 volumio volumio[931]: info: URI /mnt/USB/music/惠威试音碟 (人声篇)/10.挥着翅膀的女孩.wav Jul 22 20:19:10 volumio volumio[931]: info: Exploding uri music-library/USB/music/惠威试音碟 (人声篇)/11.泪海.wav in service mpd Jul 22 20:19:10 volumio volumio[931]: info: ALBUMART /albumart?cacheid=71&web=//extralarge&path=%2Fmnt%2FUSB%2Fmusic%2F%E6%83%A0%E5%A8%81%E8%AF%95%E9%9F%B3%E7%A2%9F%20(%E4%BA%BA%E5%A3%B0%E7%AF%87)%2F11.%E6%B3%AA%E6%B5%B7.wav&metadata=false Jul 22 20:19:10 volumio volumio[931]: info: URI /mnt/USB/music/惠威试音碟 (人声篇)/11.泪海.wav Jul 22 20:19:10 volumio volumio[931]: info: Exploding uri music-library/USB/music/惠威试音碟 (人声篇)/12.相思河畔.wav in service mpd Jul 22 20:19:10 volumio volumio[931]: info: ALBUMART /albumart?cacheid=71&web=//extralarge&path=%2Fmnt%2FUSB%2Fmusic%2F%E6%83%A0%E5%A8%81%E8%AF%95%E9%9F%B3%E7%A2%9F%20(%E4%BA%BA%E5%A3%B0%E7%AF%87)%2F12.%E7%9B%B8%E6%80%9D%E6%B2%B3%E7%95%94.wav&metadata=false Jul 22 20:19:10 volumio volumio[931]: info: URI /mnt/USB/music/惠威试音碟 (人声篇)/12.相思河畔.wav Jul 22 20:19:11 volumio volumio[931]: info: Exploding uri music-library/USB/music/惠威试音碟 (人声篇)/13.天空.wav in service mpd Jul 22 20:19:11 volumio volumio[931]: info: ALBUMART /albumart?cacheid=71&web=//extralarge&path=%2Fmnt%2FUSB%2Fmusic%2F%E6%83%A0%E5%A8%81%E8%AF%95%E9%9F%B3%E7%A2%9F%20(%E4%BA%BA%E5%A3%B0%E7%AF%87)%2F13.%E5%A4%A9%E7%A9%BA.wav&metadata=false Jul 22 20:19:11 volumio volumio[931]: info: URI /mnt/USB/music/惠威试音碟 (人声篇)/13.天空.wav Jul 22 20:19:11 volumio volumio[931]: info: Preload queue cleared Jul 22 20:19:11 volumio volumio[931]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 22 20:19:11 volumio volumio[931]: info: CoreStateMachine::ClearQueue Jul 22 20:19:11 volumio volumio[931]: info: CoreStateMachine::stop Jul 22 20:19:11 volumio volumio[931]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 22 20:19:11 volumio volumio[931]: info: CoreStateMachine::stPlaybackTimer Jul 22 20:19:11 volumio volumio[931]: info: CoreStateMachine::updateTrackBlock Jul 22 20:19:11 volumio volumio[931]: info: CorePlayQueue::getTrackBlock Jul 22 20:19:11 volumio volumio[931]: info: CoreStateMachine::pushState Jul 22 20:19:11 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Jul 22 20:19:11 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 22 20:19:11 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Jul 22 20:19:11 volumio volumio[931]: info: CoreStateMachine::serviceStop Jul 22 20:19:11 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Jul 22 20:19:11 volumio volumio[931]: info: CoreCommandRouter::serviceStop Jul 22 20:19:11 volumio volumio[931]: info: ControllerMpd::stop Jul 22 20:19:11 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand stop Jul 22 20:19:11 volumio volumio[931]: info: CorePlayQueue::clearPlayQueue Jul 22 20:19:11 volumio volumio[931]: info: CorePlayQueue::saveQueue Jul 22 20:19:11 volumio volumio[931]: info: CoreCommandRouter::volumioPushQueue Jul 22 20:19:11 volumio volumio[931]: info: CoreStateMachine::addQueueItems Jul 22 20:19:11 volumio volumio[931]: info: CorePlayQueue::addQueueItems Jul 22 20:19:11 volumio volumio[931]: info: Preload queue cleared Jul 22 20:19:11 volumio volumio[931]: info: Adding Item to queue: music-library/USB/music/惠威试音碟 (人声篇)/01.月光小夜曲.wav Jul 22 20:19:11 volumio volumio[931]: info: Using cached record of: music-library/USB/music/惠威试音碟 (人声篇)/01.月光小夜曲.wav Jul 22 20:19:11 volumio volumio[931]: info: Adding Item to queue: music-library/USB/music/惠威试音碟 (人声篇)/02.初次尝到寂寞.wav Jul 22 20:19:11 volumio volumio[931]: info: Using cached record of: music-library/USB/music/惠威试音碟 (人声篇)/02.初次尝到寂寞.wav Jul 22 20:19:11 volumio volumio[931]: info: Adding Item to queue: music-library/USB/music/惠威试音碟 (人声篇)/03.ONE I LOVE.wav Jul 22 20:19:11 volumio volumio[931]: info: Using cached record of: music-library/USB/music/惠威试音碟 (人声篇)/03.ONE I LOVE.wav Jul 22 20:19:11 volumio volumio[931]: info: Adding Item to queue: music-library/USB/music/惠威试音碟 (人声篇)/04.往事如昔.wav Jul 22 20:19:11 volumio volumio[931]: info: Using cached record of: music-library/USB/music/惠威试音碟 (人声篇)/04.往事如昔.wav Jul 22 20:19:11 volumio volumio[931]: info: Adding Item to queue: music-library/USB/music/惠威试音碟 (人声篇)/05.夜色.wav Jul 22 20:19:11 volumio volumio[931]: info: Using cached record of: music-library/USB/music/惠威试音碟 (人声篇)/05.夜色.wav Jul 22 20:19:11 volumio volumio[931]: info: Adding Item to queue: music-library/USB/music/惠威试音碟 (人声篇)/06.情人的眼泪.wav Jul 22 20:19:11 volumio volumio[931]: info: Using cached record of: music-library/USB/music/惠威试音碟 (人声篇)/06.情人的眼泪.wav Jul 22 20:19:11 volumio volumio[931]: info: Adding Item to queue: music-library/USB/music/惠威试音碟 (人声篇)/07.月亮代表我的心.wav Jul 22 20:19:11 volumio volumio[931]: info: Using cached record of: music-library/USB/music/惠威试音碟 (人声篇)/07.月亮代表我的心.wav Jul 22 20:19:11 volumio volumio[931]: info: Adding Item to queue: music-library/USB/music/惠威试音碟 (人声篇)/08.眼神骗不过.wav Jul 22 20:19:11 volumio volumio[931]: info: Using cached record of: music-library/USB/music/惠威试音碟 (人声篇)/08.眼神骗不过.wav Jul 22 20:19:11 volumio volumio[931]: info: Adding Item to queue: music-library/USB/music/惠威试音碟 (人声篇)/09.风继续吹.wav Jul 22 20:19:11 volumio volumio[931]: info: Using cached record of: music-library/USB/music/惠威试音碟 (人声篇)/09.风继续吹.wav Jul 22 20:19:11 volumio volumio[931]: info: Adding Item to queue: music-library/USB/music/惠威试音碟 (人声篇)/10.挥着翅膀的女孩.wav Jul 22 20:19:11 volumio volumio[931]: info: Using cached record of: music-library/USB/music/惠威试音碟 (人声篇)/10.挥着翅膀的女孩.wav Jul 22 20:19:11 volumio volumio[931]: info: Adding Item to queue: music-library/USB/music/惠威试音碟 (人声篇)/11.泪海.wav Jul 22 20:19:11 volumio volumio[931]: info: Using cached record of: music-library/USB/music/惠威试音碟 (人声篇)/11.泪海.wav Jul 22 20:19:11 volumio volumio[931]: info: Adding Item to queue: music-library/USB/music/惠威试音碟 (人声篇)/12.相思河畔.wav Jul 22 20:19:11 volumio volumio[931]: info: Using cached record of: music-library/USB/music/惠威试音碟 (人声篇)/12.相思河畔.wav Jul 22 20:19:11 volumio volumio[931]: info: Adding Item to queue: music-library/USB/music/惠威试音碟 (人声篇)/13.天空.wav Jul 22 20:19:11 volumio volumio[931]: info: Using cached record of: music-library/USB/music/惠威试音碟 (人声篇)/13.天空.wav Jul 22 20:19:11 volumio volumio[931]: info: CoreCommandRouter::volumioPushQueue Jul 22 20:19:11 volumio volumio[931]: info: CorePlayQueue::saveQueue Jul 22 20:19:11 volumio volumio[931]: info: CoreStateMachine::updateTrackBlock Jul 22 20:19:11 volumio volumio[931]: info: CorePlayQueue::getTrackBlock Jul 22 20:19:11 volumio volumio[931]: info: CoreCommandRouter::volumioPlay Jul 22 20:19:11 volumio volumio[931]: info: CoreStateMachine::play index 0 Jul 22 20:19:11 volumio volumio[931]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 22 20:19:11 volumio volumio[931]: info: CoreStateMachine::stop Jul 22 20:19:11 volumio volumio[931]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 22 20:19:11 volumio volumio[931]: info: CoreStateMachine::play index undefined Jul 22 20:19:11 volumio volumio[931]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 22 20:19:11 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Jul 22 20:19:11 volumio volumio[931]: info: CoreStateMachine::startPlaybackTimer Jul 22 20:19:11 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Jul 22 20:19:11 volumio volumio[931]: verbose: ControllerMpd::clearAddPlayTracks USB/music/惠威试音碟 (人声篇)/01.月光小夜曲.wav Jul 22 20:19:11 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand stop Jul 22 20:19:11 volumio volumio[931]: info: Jul 22 20:19:11 volumio volumio[931]: ---------------------------- MPD announces state update: player Jul 22 20:19:11 volumio volumio[931]: info: sendMpdCommand stop took 62 milliseconds Jul 22 20:19:11 volumio volumio[931]: info: ControllerMpd::getState Jul 22 20:19:11 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand status Jul 22 20:19:11 volumio volumio[931]: info: sendMpdCommand stop took 21 milliseconds Jul 22 20:19:11 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand clear Jul 22 20:19:11 volumio volumio[931]: info: Jul 22 20:19:11 volumio volumio[931]: ---------------------------- MPD announces system playlist update Jul 22 20:19:11 volumio volumio[931]: info: Ignoring MPD Status Update Jul 22 20:19:11 volumio volumio[931]: info: sendMpdCommand status took 7 milliseconds Jul 22 20:19:11 volumio volumio[931]: info: sendMpdCommand clear took 6 milliseconds Jul 22 20:19:11 volumio volumio[931]: verbose: ControllerMpd::parseState Jul 22 20:19:11 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 22 20:19:11 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand add "USB/music/惠威试音碟 (人声篇)/01.月光小夜曲.wav" Jul 22 20:19:11 volumio volumio[931]: error: updateQueue error: null Jul 22 20:19:11 volumio volumio[931]: info: Jul 22 20:19:11 volumio volumio[931]: ---------------------------- MPD announces system playlist update Jul 22 20:19:11 volumio volumio[931]: info: Ignoring MPD Status Update Jul 22 20:19:11 volumio volumio[931]: info: ------------------------------ 7ms Jul 22 20:19:11 volumio volumio[931]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 22 20:19:11 volumio volumio[931]: info: sendMpdCommand add "USB/music/惠威试音碟 (人声篇)/01.月光小夜曲.wav" took 4 milliseconds Jul 22 20:19:11 volumio volumio[931]: verbose: ControllerMpd::parseTrackInfo Jul 22 20:19:11 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand play Jul 22 20:19:11 volumio volumio[931]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 22 20:19:11 volumio volumio[931]: info: ------------------------------ 17ms Jul 22 20:19:11 volumio volumio[931]: info: ------------------------------ 11ms Jul 22 20:19:11 volumio volumio[931]: info: sendMpdCommand play took 9 milliseconds Jul 22 20:19:11 volumio volumio[931]: info: Jul 22 20:19:11 volumio volumio[931]: ---------------------------- MPD announces state update: player Jul 22 20:19:11 volumio volumio[931]: info: ControllerMpd::getState Jul 22 20:19:11 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand status Jul 22 20:19:11 volumio volumio[931]: info: Jul 22 20:19:11 volumio volumio[931]: ---------------------------- MPD announces state update: player Jul 22 20:19:11 volumio volumio[931]: info: ControllerMpd::getState Jul 22 20:19:11 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand status Jul 22 20:19:11 volumio volumio[931]: info: sendMpdCommand status took 7 milliseconds Jul 22 20:19:11 volumio volumio[931]: verbose: ControllerMpd::parseState Jul 22 20:19:11 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 22 20:19:11 volumio volumio[931]: info: sendMpdCommand status took 2 milliseconds Jul 22 20:19:11 volumio volumio[931]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 22 20:19:11 volumio volumio[931]: verbose: ControllerMpd::parseState Jul 22 20:19:11 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 22 20:19:11 volumio volumio[931]: verbose: ControllerMpd::parseTrackInfo Jul 22 20:19:11 volumio volumio[931]: info: ControllerMpd::pushState Jul 22 20:19:11 volumio volumio[931]: info: CoreCommandRouter::servicePushState Jul 22 20:19:11 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Jul 22 20:19:11 volumio volumio[931]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":250,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01.月光小夜曲.wav","artist":null,"album":null,"uri":"USB/music/惠威试音碟 (人声篇)/01.月光小夜曲.wav","trackType":"wav"} Jul 22 20:19:11 volumio volumio[931]: verbose: CURRENT POSITION 0 Jul 22 20:19:11 volumio volumio[931]: info: CoreStateMachine::syncState stateService play Jul 22 20:19:11 volumio volumio[931]: info: CoreStateMachine::syncState currentStatus stop Jul 22 20:19:11 volumio volumio[931]: info: ------------------------------ 13ms Jul 22 20:19:11 volumio volumio[931]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 22 20:19:11 volumio volumio[931]: verbose: ControllerMpd::parseTrackInfo Jul 22 20:19:11 volumio volumio[931]: info: ControllerMpd::pushState Jul 22 20:19:11 volumio volumio[931]: info: CoreCommandRouter::servicePushState Jul 22 20:19:11 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Jul 22 20:19:11 volumio volumio[931]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":250,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01.月光小夜曲.wav","artist":null,"album":null,"uri":"USB/music/惠威试音碟 (人声篇)/01.月光小夜曲.wav","trackType":"wav"} Jul 22 20:19:11 volumio volumio[931]: verbose: CURRENT POSITION 0 Jul 22 20:19:11 volumio volumio[931]: info: CoreStateMachine::syncState stateService play Jul 22 20:19:11 volumio volumio[931]: info: CoreStateMachine::syncState currentStatus play Jul 22 20:19:11 volumio volumio[931]: info: Received an update from plugin. extracting info from payload Jul 22 20:19:11 volumio volumio[931]: info: CoreStateMachine::pushState Jul 22 20:19:11 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Jul 22 20:19:11 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 22 20:19:11 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Jul 22 20:19:11 volumio volumio[931]: info: CoreStateMachine::pushState Jul 22 20:19:11 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Jul 22 20:19:11 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Jul 22 20:19:11 volumio volumio[931]: info: ------------------------------ 35ms Jul 22 20:19:13 volumio volumio[931]: info: CoreCommandRouter::volumioGetState Jul 22 20:19:13 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Jul 22 20:19:14 volumio volumio[931]: info: CoreCommandRouter::volumioNext Jul 22 20:19:14 volumio volumio[931]: info: CoreStateMachine::next Jul 22 20:19:14 volumio volumio[931]: info: CoreStateMachine::stop Jul 22 20:19:14 volumio volumio[931]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 22 20:19:14 volumio volumio[931]: info: CoreStateMachine::stPlaybackTimer Jul 22 20:19:14 volumio volumio[931]: info: CoreStateMachine::updateTrackBlock Jul 22 20:19:14 volumio volumio[931]: info: CorePlayQueue::getTrackBlock Jul 22 20:19:14 volumio volumio[931]: info: CoreStateMachine::pushState Jul 22 20:19:14 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Jul 22 20:19:14 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 22 20:19:14 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Jul 22 20:19:14 volumio volumio[931]: info: CoreStateMachine::serviceStop Jul 22 20:19:14 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Jul 22 20:19:14 volumio volumio[931]: info: CoreCommandRouter::serviceStop Jul 22 20:19:14 volumio volumio[931]: info: ControllerMpd::stop Jul 22 20:19:14 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand stop Jul 22 20:19:14 volumio volumio[931]: info: Jul 22 20:19:14 volumio volumio[931]: ---------------------------- MPD announces state update: player Jul 22 20:19:14 volumio volumio[931]: info: sendMpdCommand stop took 25 milliseconds Jul 22 20:19:14 volumio volumio[931]: info: ControllerMpd::getState Jul 22 20:19:14 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand status Jul 22 20:19:14 volumio volumio[931]: info: CoreStateMachine::play index undefined Jul 22 20:19:14 volumio volumio[931]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 22 20:19:14 volumio volumio[931]: info: CorePlayQueue::getTrack 1 Jul 22 20:19:14 volumio volumio[931]: info: CoreStateMachine::startPlaybackTimer Jul 22 20:19:14 volumio volumio[931]: info: CorePlayQueue::getTrack 1 Jul 22 20:19:14 volumio volumio[931]: verbose: ControllerMpd::clearAddPlayTracks USB/music/惠威试音碟 (人声篇)/02.初次尝到寂寞.wav Jul 22 20:19:14 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand stop Jul 22 20:19:14 volumio volumio[931]: info: CoreStateMachine::updateTrackBlock Jul 22 20:19:14 volumio volumio[931]: info: CorePlayQueue::getTrackBlock Jul 22 20:19:14 volumio volumio[931]: info: sendMpdCommand status took 4 milliseconds Jul 22 20:19:14 volumio volumio[931]: info: sendMpdCommand stop took 2 milliseconds Jul 22 20:19:14 volumio volumio[931]: verbose: ControllerMpd::parseState Jul 22 20:19:14 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 22 20:19:14 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand clear Jul 22 20:19:14 volumio volumio[931]: info: Jul 22 20:19:14 volumio volumio[931]: ---------------------------- MPD announces system playlist update Jul 22 20:19:14 volumio volumio[931]: info: Ignoring MPD Status Update Jul 22 20:19:14 volumio volumio[931]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 22 20:19:14 volumio volumio[931]: info: sendMpdCommand clear took 2 milliseconds Jul 22 20:19:14 volumio volumio[931]: verbose: ControllerMpd::parseTrackInfo Jul 22 20:19:14 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand add "USB/music/惠威试音碟 (人声篇)/02.初次尝到寂寞.wav" Jul 22 20:19:14 volumio volumio[931]: info: ControllerMpd::pushState Jul 22 20:19:14 volumio volumio[931]: info: CoreCommandRouter::servicePushState Jul 22 20:19:14 volumio volumio[931]: info: CoreStateMachine::pushState Jul 22 20:19:14 volumio volumio[931]: info: CorePlayQueue::getTrack 1 Jul 22 20:19:14 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 22 20:19:14 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Jul 22 20:19:14 volumio volumio[931]: info: CorePlayQueue::getTrack 1 Jul 22 20:19:14 volumio volumio[931]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01.月光小夜曲.wav","artist":null,"album":null,"uri":"USB/music/惠威试音碟 (人声篇)/01.月光小夜曲.wav","trackType":"wav"} Jul 22 20:19:14 volumio volumio[931]: verbose: CURRENT POSITION 1 Jul 22 20:19:14 volumio volumio[931]: info: CoreStateMachine::syncState stateService stop Jul 22 20:19:14 volumio volumio[931]: info: CoreStateMachine::syncState currentStatus stop Jul 22 20:19:14 volumio volumio[931]: info: CoreStateMachine::pushState Jul 22 20:19:14 volumio volumio[931]: info: CorePlayQueue::getTrack 1 Jul 22 20:19:14 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Jul 22 20:19:14 volumio volumio[931]: info: No code Jul 22 20:19:14 volumio volumio[931]: info: CoreStateMachine::pushState Jul 22 20:19:14 volumio volumio[931]: info: CorePlayQueue::getTrack 1 Jul 22 20:19:14 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Jul 22 20:19:14 volumio volumio[931]: info: ------------------------------ 43ms Jul 22 20:19:14 volumio volumio[931]: error: updateQueue error: null Jul 22 20:19:14 volumio volumio[931]: info: Jul 22 20:19:14 volumio volumio[931]: ---------------------------- MPD announces system playlist update Jul 22 20:19:14 volumio volumio[931]: info: Ignoring MPD Status Update Jul 22 20:19:14 volumio volumio[931]: info: ------------------------------ 38ms Jul 22 20:19:14 volumio volumio[931]: info: sendMpdCommand add "USB/music/惠威试音碟 (人声篇)/02.初次尝到寂寞.wav" took 36 milliseconds Jul 22 20:19:14 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand play Jul 22 20:19:14 volumio volumio[931]: info: Jul 22 20:19:14 volumio volumio[931]: ---------------------------- MPD announces state update: player Jul 22 20:19:14 volumio volumio[931]: info: ------------------------------ 14ms Jul 22 20:19:14 volumio volumio[931]: info: sendMpdCommand play took 12 milliseconds Jul 22 20:19:14 volumio volumio[931]: info: ControllerMpd::getState Jul 22 20:19:14 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand status Jul 22 20:19:14 volumio volumio[931]: info: Jul 22 20:19:14 volumio volumio[931]: ---------------------------- MPD announces state update: player Jul 22 20:19:14 volumio volumio[931]: info: ControllerMpd::getState Jul 22 20:19:14 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand status Jul 22 20:19:14 volumio volumio[931]: info: sendMpdCommand status took 5 milliseconds Jul 22 20:19:14 volumio volumio[931]: verbose: ControllerMpd::parseState Jul 22 20:19:14 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 22 20:19:14 volumio volumio[931]: info: sendMpdCommand status took 3 milliseconds Jul 22 20:19:14 volumio volumio[931]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 22 20:19:14 volumio volumio[931]: verbose: ControllerMpd::parseState Jul 22 20:19:14 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 22 20:19:14 volumio volumio[931]: verbose: ControllerMpd::parseTrackInfo Jul 22 20:19:14 volumio volumio[931]: info: ControllerMpd::pushState Jul 22 20:19:14 volumio volumio[931]: info: CoreCommandRouter::servicePushState Jul 22 20:19:14 volumio volumio[931]: info: CorePlayQueue::getTrack 1 Jul 22 20:19:14 volumio volumio[931]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":270,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02.初次尝到寂寞.wav","artist":null,"album":null,"uri":"USB/music/惠威试音碟 (人声篇)/02.初次尝到寂寞.wav","trackType":"wav"} Jul 22 20:19:14 volumio volumio[931]: verbose: CURRENT POSITION 1 Jul 22 20:19:14 volumio volumio[931]: info: CoreStateMachine::syncState stateService play Jul 22 20:19:14 volumio volumio[931]: info: CoreStateMachine::syncState currentStatus stop Jul 22 20:19:14 volumio volumio[931]: info: ------------------------------ 13ms Jul 22 20:19:14 volumio volumio[931]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 22 20:19:14 volumio volumio[931]: verbose: ControllerMpd::parseTrackInfo Jul 22 20:19:14 volumio volumio[931]: info: ControllerMpd::pushState Jul 22 20:19:14 volumio volumio[931]: info: CoreCommandRouter::servicePushState Jul 22 20:19:14 volumio volumio[931]: info: CorePlayQueue::getTrack 1 Jul 22 20:19:14 volumio volumio[931]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":270,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02.初次尝到寂寞.wav","artist":null,"album":null,"uri":"USB/music/惠威试音碟 (人声篇)/02.初次尝到寂寞.wav","trackType":"wav"} Jul 22 20:19:14 volumio volumio[931]: verbose: CURRENT POSITION 1 Jul 22 20:19:14 volumio volumio[931]: info: CoreStateMachine::syncState stateService play Jul 22 20:19:14 volumio volumio[931]: info: CoreStateMachine::syncState currentStatus play Jul 22 20:19:14 volumio volumio[931]: info: Received an update from plugin. extracting info from payload Jul 22 20:19:14 volumio volumio[931]: info: CoreStateMachine::pushState Jul 22 20:19:14 volumio volumio[931]: info: CorePlayQueue::getTrack 1 Jul 22 20:19:14 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 22 20:19:14 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Jul 22 20:19:14 volumio volumio[931]: info: CoreStateMachine::pushState Jul 22 20:19:14 volumio volumio[931]: info: CorePlayQueue::getTrack 1 Jul 22 20:19:14 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Jul 22 20:19:14 volumio volumio[931]: info: ------------------------------ 46ms Jul 22 20:19:15 volumio volumio[931]: info: CoreCommandRouter::volumioPause Jul 22 20:19:15 volumio volumio[931]: info: CoreStateMachine::pause Jul 22 20:19:15 volumio volumio[931]: info: CoreStateMachine::stPlaybackTimer Jul 22 20:19:15 volumio volumio[931]: info: CoreStateMachine::servicePause Jul 22 20:19:15 volumio volumio[931]: info: CorePlayQueue::getTrack 1 Jul 22 20:19:15 volumio volumio[931]: info: CoreCommandRouter::servicePause Jul 22 20:19:15 volumio volumio[931]: info: ControllerMpd::pause Jul 22 20:19:15 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand pause Jul 22 20:19:15 volumio volumio[931]: info: sendMpdCommand pause took 1 milliseconds Jul 22 20:19:15 volumio volumio[931]: info: Jul 22 20:19:15 volumio volumio[931]: ---------------------------- MPD announces state update: player Jul 22 20:19:15 volumio volumio[931]: info: ControllerMpd::getState Jul 22 20:19:15 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand status Jul 22 20:19:15 volumio volumio[931]: info: sendMpdCommand status took 0 milliseconds Jul 22 20:19:15 volumio volumio[931]: verbose: ControllerMpd::parseState Jul 22 20:19:15 volumio volumio[931]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 22 20:19:15 volumio volumio[931]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 22 20:19:15 volumio volumio[931]: verbose: ControllerMpd::parseTrackInfo Jul 22 20:19:15 volumio volumio[931]: info: ControllerMpd::pushState Jul 22 20:19:15 volumio volumio[931]: info: CoreCommandRouter::servicePushState Jul 22 20:19:15 volumio volumio[931]: info: CorePlayQueue::getTrack 1 Jul 22 20:19:15 volumio volumio[931]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":1457,"duration":270,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02.初次尝到寂寞.wav","artist":null,"album":null,"uri":"USB/music/惠威试音碟 (人声篇)/02.初次尝到寂寞.wav","trackType":"wav"} Jul 22 20:19:15 volumio volumio[931]: verbose: CURRENT POSITION 1 Jul 22 20:19:15 volumio volumio[931]: info: CoreStateMachine::syncState stateService pause Jul 22 20:19:15 volumio volumio[931]: info: CoreStateMachine::syncState currentStatus pause Jul 22 20:19:15 volumio volumio[931]: info: CoreStateMachine::pushState Jul 22 20:19:15 volumio volumio[931]: info: CorePlayQueue::getTrack 1 Jul 22 20:19:15 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 22 20:19:15 volumio volumio[931]: info: CoreCommandRouter::volumioPushState Jul 22 20:19:15 volumio volumio[931]: info: CoreStateMachine::stPlaybackTimer Jul 22 20:19:15 volumio volumio[931]: info: ------------------------------ 15ms Jul 22 20:19:15 volumio volumio[931]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 22 20:19:15 volumio volumio[931]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' Jul 22 20:19:15 volumio volumio[931]: at Object.readdirSync (fs.js:1048:3) Jul 22 20:19:15 volumio volumio[931]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11) Jul 22 20:19:15 volumio volumio[931]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7) Jul 22 20:19:15 volumio volumio[931]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62) Jul 22 20:19:15 volumio volumio[931]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17) Jul 22 20:19:15 volumio volumio[931]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48) Jul 22 20:19:15 volumio volumio[931]: at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Jul 22 20:19:15 volumio volumio[931]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10) Jul 22 20:19:15 volumio volumio[931]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12) Jul 22 20:19:15 volumio volumio[931]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Jul 22 20:19:15 volumio volumio[931]: at Manager.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Jul 22 20:19:15 volumio volumio[931]: at Manager.ondecoded (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:349:8) Jul 22 20:19:15 volumio volumio[931]: at Decoder. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Jul 22 20:19:15 volumio volumio[931]: at Decoder.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Jul 22 20:19:15 volumio volumio[931]: at Decoder.add (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-parser/index.js:251:12) Jul 22 20:19:15 volumio volumio[931]: at Manager.ondata (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:339:16) { Jul 22 20:19:15 volumio volumio[931]: errno: -20, Jul 22 20:19:15 volumio volumio[931]: syscall: 'scandir', Jul 22 20:19:15 volumio volumio[931]: code: 'ENOTDIR', Jul 22 20:19:15 volumio volumio[931]: path: '/tmp/peppyrunning' Jul 22 20:19:15 volumio volumio[931]: } Jul 22 20:19:15 volumio volumio[931]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 22 20:19:15 volumio sudo[2157]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-22 20:18 Jul 22 20:19:15 volumio sudo[2157]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 09:13:11 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ac782621640fcc70c07e91a81b6d958b"