-- Logs begin at Mon 2025-02-17 18:06:07 CST, end at Mon 2025-02-17 18:11:16 CST. -- Feb 17 18:10:00 volumio volumio[874]: info: Preload queue cleared Feb 17 18:10:00 volumio volumio[874]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 17 18:10:00 volumio volumio[874]: info: CoreStateMachine::ClearQueue Feb 17 18:10:00 volumio volumio[874]: info: CoreStateMachine::stop Feb 17 18:10:00 volumio volumio[874]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 18:10:00 volumio volumio[874]: info: CorePlayQueue::clearPlayQueue Feb 17 18:10:00 volumio volumio[874]: info: CorePlayQueue::saveQueue Feb 17 18:10:00 volumio volumio[874]: info: CoreCommandRouter::volumioPushQueue Feb 17 18:10:00 volumio volumio[874]: info: CoreStateMachine::addQueueItems Feb 17 18:10:00 volumio volumio[874]: info: CorePlayQueue::addQueueItems Feb 17 18:10:00 volumio volumio[874]: info: Preload queue cleared Feb 17 18:10:00 volumio volumio[874]: info: Adding Item to queue: music-library/USB/music/《步步高_试机碟》/步步高_试机碟.wav Feb 17 18:10:00 volumio volumio[874]: info: Using cached record of: music-library/USB/music/《步步高_试机碟》/步步高_试机碟.wav Feb 17 18:10:00 volumio volumio[874]: info: CoreCommandRouter::volumioPushQueue Feb 17 18:10:00 volumio volumio[874]: info: CorePlayQueue::saveQueue Feb 17 18:10:00 volumio volumio[874]: info: CoreStateMachine::updateTrackBlock Feb 17 18:10:00 volumio volumio[874]: info: CorePlayQueue::getTrackBlock Feb 17 18:10:00 volumio volumio[874]: info: CoreCommandRouter::volumioPlay Feb 17 18:10:00 volumio volumio[874]: info: CoreStateMachine::play index 0 Feb 17 18:10:00 volumio volumio[874]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 18:10:00 volumio volumio[874]: info: CoreStateMachine::stop Feb 17 18:10:00 volumio volumio[874]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 18:10:00 volumio volumio[874]: info: CoreStateMachine::play index undefined Feb 17 18:10:00 volumio volumio[874]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 18:10:00 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:10:00 volumio volumio[874]: info: Preload queue cleared Feb 17 18:10:00 volumio volumio[874]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 17 18:10:00 volumio volumio[874]: info: CoreStateMachine::ClearQueue Feb 17 18:10:00 volumio volumio[874]: info: CoreStateMachine::stop Feb 17 18:10:00 volumio volumio[874]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 18:10:00 volumio volumio[874]: info: CorePlayQueue::clearPlayQueue Feb 17 18:10:00 volumio volumio[874]: info: CorePlayQueue::saveQueue Feb 17 18:10:00 volumio volumio[874]: info: CoreCommandRouter::volumioPushQueue Feb 17 18:10:00 volumio volumio[874]: info: CoreStateMachine::addQueueItems Feb 17 18:10:00 volumio volumio[874]: info: CorePlayQueue::addQueueItems Feb 17 18:10:00 volumio volumio[874]: info: Preload queue cleared Feb 17 18:10:00 volumio volumio[874]: info: Adding Item to queue: music-library/USB/music/《步步高_试机碟》/步步高_试机碟.wav Feb 17 18:10:00 volumio volumio[874]: info: Using cached record of: music-library/USB/music/《步步高_试机碟》/步步高_试机碟.wav Feb 17 18:10:00 volumio volumio[874]: info: CoreCommandRouter::volumioPushQueue Feb 17 18:10:00 volumio volumio[874]: info: CorePlayQueue::saveQueue Feb 17 18:10:00 volumio volumio[874]: info: CoreStateMachine::updateTrackBlock Feb 17 18:10:00 volumio volumio[874]: info: CorePlayQueue::getTrackBlock Feb 17 18:10:00 volumio volumio[874]: info: CoreCommandRouter::volumioPlay Feb 17 18:10:00 volumio volumio[874]: info: CoreStateMachine::play index 0 Feb 17 18:10:00 volumio volumio[874]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 18:10:00 volumio volumio[874]: info: CoreStateMachine::stop Feb 17 18:10:00 volumio volumio[874]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 18:10:00 volumio volumio[874]: info: CoreStateMachine::play index undefined Feb 17 18:10:00 volumio volumio[874]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 18:10:00 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:10:02 volumio volumio[874]: info: CoreCommandRouter::volumioGetState Feb 17 18:10:02 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:10:03 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 17 18:10:03 volumio volumio[874]: info: CURURI: music-library/USB Feb 17 18:10:04 volumio volumio[874]: info: Preload queue cleared Feb 17 18:10:06 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 17 18:10:06 volumio volumio[874]: info: CURURI: music-library/USB Feb 17 18:10:06 volumio volumio[874]: info: Preload queue cleared Feb 17 18:10:08 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 17 18:10:08 volumio volumio[874]: info: CURURI: music-library/USB/music Feb 17 18:10:08 volumio volumio[874]: info: Preload queue cleared Feb 17 18:10:13 volumio volumio[874]: info: CoreCommandRouter::volumioGetState Feb 17 18:10:13 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:10:24 volumio volumio[874]: info: CoreCommandRouter::volumioGetState Feb 17 18:10:24 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:10:33 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 17 18:10:33 volumio volumio[874]: info: CURURI: music-library Feb 17 18:10:33 volumio volumio[874]: info: Preload queue cleared Feb 17 18:10:35 volumio volumio[874]: info: CoreCommandRouter::volumioGetState Feb 17 18:10:35 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:10:36 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 17 18:10:36 volumio volumio[874]: info: CURURI: music-library/USB Feb 17 18:10:36 volumio volumio[874]: info: Preload queue cleared Feb 17 18:10:37 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 17 18:10:37 volumio volumio[874]: info: CURURI: music-library/USB/music Feb 17 18:10:37 volumio volumio[874]: info: Preload queue cleared Feb 17 18:10:37 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:10:37 volumio volumio[874]: info: CorePlayQueue::getTrack 1 Feb 17 18:10:46 volumio volumio[874]: info: CoreCommandRouter::volumioGetState Feb 17 18:10:46 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:10:50 volumio kernel: usb 3-1.3: USB disconnect, device number 3 Feb 17 18:10:50 volumio volumio[874]: info: Partition removed: {"syspath":"/sys/devices/pci0000:00/0000:00:1a.0/usb3/3-1/3-1.3/3-1.3:1.0/host4/target4:0:0/4:0:0:0/block/sdb/sdb1","ACTION":"remove","DEVLINKS":"/dev/disk/by-partuuid/f3daff6b-01 /dev/disk/by-id/usb-JMicron_Tech_DD564198838A1-0:0-part1 /dev/disk/by-uuid/0004-5355 /dev/disk/by-label/ϵͳ /dev/disk/by-path/pci-0000:00:1a.0-usb-0:1.3:1.0-scsi-0:0:0:0-part1","DEVNAME":"/dev/sdb1","DEVPATH":"/devices/pci0000:00/0000:00:1a.0/usb3/3-1/3-1.3/3-1.3:1.0/host4/target4:0:0/4:0:0:0/block/sdb/sdb1","DEVTYPE":"partition","DISKSEQ":"11","ID_BUS":"usb","ID_FS_LABEL":"ϵͳ","ID_FS_LABEL_ENC":"ϵͳ","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"0004-5355","ID_FS_UUID_ENC":"0004-5355","ID_FS_VERSION":"FAT32","ID_INSTANCE":"0:0","ID_MODEL":"Tech","ID_MODEL_ENC":"Tech\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20","ID_MODEL_ID":"0583","ID_PART_ENTRY_DISK":"8:16","ID_PART_ENTRY_FLAGS":"0x80","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"63","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"234436482","ID_PART_ENTRY_TYPE":"0xc","ID_PART_ENTRY_UUID":"f3daff6b-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"f3daff6b","ID_PATH":"pci-0000:00:1a.0-usb-0:1.3:1.0-scsi-0:0:0:0","ID_PATH_TAG":"pci-0000_00_1a_0-usb-0_1_3_1_0-scsi-0_0_0_0","ID_REVISION":"3202","ID_SERIAL":"JMicron_Tech_DD564198838A1-0:0","ID_SERIAL_SHORT":"DD564198838A1","ID_TYPE":"disk","ID_USB_DRIVER":"uas","ID_USB_INTERFACES":":080650:080662:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"JMicron","ID_VENDOR_ENC":"JMicron\\x20","ID_VENDOR_ID":"152d","MAJOR":"8","MINOR":"17","PARTN":"1","SEQNUM":"2224","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"27391017"} Feb 17 18:10:50 volumio sudo[2046]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sdb1 Feb 17 18:10:50 volumio sudo[2046]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 18:10:50 volumio kernel: sd 4:0:0:0: [sdb] Synchronizing SCSI cache Feb 17 18:10:50 volumio sudo[2046]: pam_unix(sudo:session): session closed for user root Feb 17 18:10:50 volumio systemd[1]: media-\xcf\xb5\xcd\xb3.mount: Succeeded. Feb 17 18:10:50 volumio kernel: sd 4:0:0:0: [sdb] Synchronize Cache(10) failed: Result: hostbyte=DID_ERROR driverbyte=DRIVER_OK Feb 17 18:10:53 volumio kernel: usb 3-1.1: new high-speed USB device number 4 using ehci-pci Feb 17 18:10:54 volumio kernel: usb 3-1.1: New USB device found, idVendor=152d, idProduct=0583, bcdDevice=32.02 Feb 17 18:10:54 volumio kernel: usb 3-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Feb 17 18:10:54 volumio kernel: usb 3-1.1: Product: JMicron Feb 17 18:10:54 volumio kernel: usb 3-1.1: Manufacturer: JMicron Feb 17 18:10:54 volumio kernel: usb 3-1.1: SerialNumber: DD564198838A1 Feb 17 18:10:54 volumio kernel: scsi host4: uas Feb 17 18:10:54 volumio kernel: scsi 4:0:0:0: Direct-Access JMicron Tech 3202 PQ: 0 ANSI: 6 Feb 17 18:10:54 volumio kernel: sd 4:0:0:0: Attached scsi generic sg1 type 0 Feb 17 18:10:54 volumio kernel: sd 4:0:0:0: [sdb] 234441648 512-byte logical blocks: (120 GB/112 GiB) Feb 17 18:10:54 volumio kernel: sd 4:0:0:0: [sdb] 4096-byte physical blocks Feb 17 18:10:54 volumio kernel: sd 4:0:0:0: [sdb] Write Protect is off Feb 17 18:10:54 volumio kernel: sd 4:0:0:0: [sdb] Mode Sense: 5f 00 00 08 Feb 17 18:10:54 volumio kernel: sd 4:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Feb 17 18:10:54 volumio kernel: sd 4:0:0:0: [sdb] Preferred minimum I/O size 4096 bytes Feb 17 18:10:54 volumio kernel: sd 4:0:0:0: [sdb] Optimal transfer size 33553920 bytes not a multiple of preferred minimum block size (4096 bytes) Feb 17 18:10:54 volumio kernel: sdb: sdb1 Feb 17 18:10:54 volumio kernel: sd 4:0:0:0: [sdb] Attached SCSI disk Feb 17 18:10:54 volumio volumio[874]: info: Mounting Device ϵͳ Feb 17 18:10:54 volumio sudo[2056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/ϵͳ -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Feb 17 18:10:54 volumio sudo[2056]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 18:10:54 volumio kernel: FAT-fs (sdb1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Feb 17 18:10:54 volumio kernel: FAT-fs (sdb1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Feb 17 18:10:54 volumio sudo[2056]: pam_unix(sudo:session): session closed for user root Feb 17 18:10:54 volumio volumio[874]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/ϵͳ Feb 17 18:10:55 volumio volumio[874]: info: Scanning removed location : "USB/ϵͳ" Feb 17 18:10:55 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:10:55 volumio volumio[874]: info: Feb 17 18:10:55 volumio volumio[874]: ---------------------------- MPD announces state update: update Feb 17 18:10:55 volumio volumio[874]: info: ControllerMpd::getState Feb 17 18:10:55 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:10:55 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:10:55 volumio volumio[874]: info: Feb 17 18:10:55 volumio volumio[874]: ---------------------------- MPD announces state update: update Feb 17 18:10:55 volumio volumio[874]: info: ControllerMpd::getState Feb 17 18:10:55 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:10:55 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:10:55 volumio volumio[874]: info: Feb 17 18:10:55 volumio volumio[874]: ---------------------------- MPD announces state update: update Feb 17 18:10:55 volumio volumio[874]: info: ControllerMpd::getState Feb 17 18:10:55 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:10:55 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:10:55 volumio volumio[874]: info: Feb 17 18:10:55 volumio volumio[874]: ---------------------------- MPD announces state update: update Feb 17 18:10:55 volumio volumio[874]: info: ControllerMpd::getState Feb 17 18:10:55 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:10:55 volumio volumio[874]: info: sendMpdCommand status took 8 milliseconds Feb 17 18:10:55 volumio volumio[874]: info: sendMpdCommand status took 4 milliseconds Feb 17 18:10:55 volumio volumio[874]: info: sendMpdCommand status took 5 milliseconds Feb 17 18:10:55 volumio volumio[874]: info: sendMpdCommand status took 4 milliseconds Feb 17 18:10:55 volumio volumio[874]: info: sendMpdCommand status took 4 milliseconds Feb 17 18:10:55 volumio volumio[874]: info: sendMpdCommand status took 3 milliseconds Feb 17 18:10:55 volumio volumio[874]: info: sendMpdCommand status took 3 milliseconds Feb 17 18:10:55 volumio volumio[874]: info: sendMpdCommand status took 2 milliseconds Feb 17 18:10:55 volumio volumio[874]: verbose: ControllerMpd::parseState Feb 17 18:10:55 volumio volumio[874]: info: Command Router : Notfying DB Updatetrue Feb 17 18:10:55 volumio volumio[874]: verbose: ControllerMpd::parseState Feb 17 18:10:55 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 18:10:55 volumio volumio[874]: verbose: ControllerMpd::parseState Feb 17 18:10:55 volumio volumio[874]: info: Command Router : Notfying DB Updatetrue Feb 17 18:10:55 volumio volumio[874]: info: CoreCommandRouter::Close All Modals sent Feb 17 18:10:55 volumio volumio[874]: verbose: ControllerMpd::parseState Feb 17 18:10:55 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 18:10:55 volumio volumio[874]: verbose: ControllerMpd::parseState Feb 17 18:10:55 volumio volumio[874]: info: Command Router : Notfying DB Updatetrue Feb 17 18:10:55 volumio volumio[874]: info: CoreCommandRouter::Close All Modals sent Feb 17 18:10:55 volumio volumio[874]: verbose: ControllerMpd::parseState Feb 17 18:10:55 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 18:10:55 volumio volumio[874]: verbose: ControllerMpd::parseState Feb 17 18:10:55 volumio volumio[874]: info: Command Router : Notfying DB Updatetrue Feb 17 18:10:55 volumio volumio[874]: info: CoreCommandRouter::Close All Modals sent Feb 17 18:10:55 volumio volumio[874]: verbose: ControllerMpd::parseState Feb 17 18:10:55 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 18:10:55 volumio volumio[874]: info: sendMpdCommand playlistinfo took 86 milliseconds Feb 17 18:10:55 volumio volumio[874]: info: sendMpdCommand playlistinfo took 74 milliseconds Feb 17 18:10:55 volumio volumio[874]: info: sendMpdCommand playlistinfo took 61 milliseconds Feb 17 18:10:55 volumio volumio[874]: info: sendMpdCommand playlistinfo took 47 milliseconds Feb 17 18:10:55 volumio volumio[874]: verbose: ControllerMpd::parseTrackInfo Feb 17 18:10:55 volumio volumio[874]: verbose: ControllerMpd::parseTrackInfo Feb 17 18:10:55 volumio volumio[874]: verbose: ControllerMpd::parseTrackInfo Feb 17 18:10:55 volumio volumio[874]: verbose: ControllerMpd::parseTrackInfo Feb 17 18:10:55 volumio volumio[874]: info: ControllerMpd::pushState Feb 17 18:10:55 volumio volumio[874]: info: CoreCommandRouter::servicePushState Feb 17 18:10:55 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:10:55 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:10:55 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 18:10:55 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:10:55 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:10:55 volumio volumio[874]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01.月光小夜曲.wav","artist":null,"album":null,"uri":"USB/music/惠威试音碟 (人声篇)/01.月光小夜曲.wav","trackType":"wav"} Feb 17 18:10:55 volumio volumio[874]: verbose: CURRENT POSITION 0 Feb 17 18:10:55 volumio volumio[874]: info: CoreStateMachine::syncState stateService stop Feb 17 18:10:55 volumio volumio[874]: info: CoreStateMachine::syncState currentStatus stop Feb 17 18:10:55 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:10:55 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:10:55 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 18:10:55 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:10:55 volumio volumio[874]: info: No code Feb 17 18:10:55 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:10:55 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:10:55 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:10:55 volumio volumio[874]: info: ControllerMpd::pushState Feb 17 18:10:55 volumio volumio[874]: info: CoreCommandRouter::servicePushState Feb 17 18:10:55 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:10:55 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:10:55 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:10:55 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:10:55 volumio volumio[874]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01.月光小夜曲.wav","artist":null,"album":null,"uri":"USB/music/惠威试音碟 (人声篇)/01.月光小夜曲.wav","trackType":"wav"} Feb 17 18:10:55 volumio volumio[874]: verbose: CURRENT POSITION 0 Feb 17 18:10:55 volumio volumio[874]: info: CoreStateMachine::syncState stateService stop Feb 17 18:10:55 volumio volumio[874]: info: CoreStateMachine::syncState currentStatus stop Feb 17 18:10:55 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:10:55 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:10:55 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:10:55 volumio volumio[874]: info: No code Feb 17 18:10:55 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:10:55 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:10:55 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:10:55 volumio volumio[874]: info: ControllerMpd::pushState Feb 17 18:10:55 volumio volumio[874]: info: CoreCommandRouter::servicePushState Feb 17 18:10:55 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:10:55 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:10:55 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:10:55 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:10:55 volumio volumio[874]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01.月光小夜曲.wav","artist":null,"album":null,"uri":"USB/music/惠威试音碟 (人声篇)/01.月光小夜曲.wav","trackType":"wav"} Feb 17 18:10:55 volumio volumio[874]: verbose: CURRENT POSITION 0 Feb 17 18:10:55 volumio volumio[874]: info: CoreStateMachine::syncState stateService stop Feb 17 18:10:55 volumio volumio[874]: info: CoreStateMachine::syncState currentStatus stop Feb 17 18:10:55 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:10:55 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:10:55 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:10:55 volumio volumio[874]: info: No code Feb 17 18:10:55 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:10:55 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:10:55 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:10:55 volumio volumio[874]: info: ControllerMpd::pushState Feb 17 18:10:55 volumio volumio[874]: info: CoreCommandRouter::servicePushState Feb 17 18:10:55 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:10:55 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:10:55 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:10:55 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:10:55 volumio volumio[874]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01.月光小夜曲.wav","artist":null,"album":null,"uri":"USB/music/惠威试音碟 (人声篇)/01.月光小夜曲.wav","trackType":"wav"} Feb 17 18:10:55 volumio volumio[874]: verbose: CURRENT POSITION 0 Feb 17 18:10:55 volumio volumio[874]: info: CoreStateMachine::syncState stateService stop Feb 17 18:10:55 volumio volumio[874]: info: CoreStateMachine::syncState currentStatus stop Feb 17 18:10:55 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:10:55 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:10:55 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:10:55 volumio volumio[874]: info: No code Feb 17 18:10:55 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:10:55 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:10:55 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:10:55 volumio volumio[874]: info: ------------------------------ 200ms Feb 17 18:10:55 volumio volumio[874]: info: ------------------------------ 197ms Feb 17 18:10:55 volumio volumio[874]: info: ------------------------------ 196ms Feb 17 18:10:55 volumio volumio[874]: info: ------------------------------ 196ms Feb 17 18:10:57 volumio volumio[874]: info: CoreCommandRouter::volumioGetState Feb 17 18:10:57 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:10:59 volumio volumio[874]: info: MPD Database updated - AlbumList cache refreshed Feb 17 18:10:59 volumio volumio[874]: info: Feb 17 18:10:59 volumio volumio[874]: ---------------------------- MPD announces state update: database Feb 17 18:10:59 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:10:59 volumio volumio[874]: info: Feb 17 18:10:59 volumio volumio[874]: ---------------------------- MPD announces state update: update Feb 17 18:10:59 volumio volumio[874]: info: ControllerMpd::getState Feb 17 18:10:59 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:10:59 volumio volumio[874]: info: ControllerMpd::getState Feb 17 18:10:59 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:10:59 volumio volumio[874]: info: MPD Database updated - AlbumList cache refreshed Feb 17 18:10:59 volumio volumio[874]: info: Feb 17 18:10:59 volumio volumio[874]: ---------------------------- MPD announces state update: database Feb 17 18:10:59 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:10:59 volumio volumio[874]: info: Feb 17 18:10:59 volumio volumio[874]: ---------------------------- MPD announces state update: update Feb 17 18:10:59 volumio volumio[874]: info: ControllerMpd::getState Feb 17 18:10:59 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:10:59 volumio volumio[874]: info: ControllerMpd::getState Feb 17 18:10:59 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:10:59 volumio volumio[874]: info: MPD Database updated - AlbumList cache refreshed Feb 17 18:10:59 volumio volumio[874]: info: Feb 17 18:10:59 volumio volumio[874]: ---------------------------- MPD announces state update: database Feb 17 18:10:59 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:10:59 volumio volumio[874]: info: Feb 17 18:10:59 volumio volumio[874]: ---------------------------- MPD announces state update: update Feb 17 18:10:59 volumio volumio[874]: info: ControllerMpd::getState Feb 17 18:10:59 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:10:59 volumio volumio[874]: info: ControllerMpd::getState Feb 17 18:10:59 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:10:59 volumio volumio[874]: info: MPD Database updated - AlbumList cache refreshed Feb 17 18:10:59 volumio volumio[874]: info: Feb 17 18:10:59 volumio volumio[874]: ---------------------------- MPD announces state update: database Feb 17 18:10:59 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:10:59 volumio volumio[874]: info: Feb 17 18:10:59 volumio volumio[874]: ---------------------------- MPD announces state update: update Feb 17 18:10:59 volumio volumio[874]: info: ControllerMpd::getState Feb 17 18:10:59 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:10:59 volumio volumio[874]: info: ControllerMpd::getState Feb 17 18:10:59 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:10:59 volumio volumio[874]: info: sendMpdCommand status took 26 milliseconds Feb 17 18:10:59 volumio volumio[874]: info: sendMpdCommand status took 26 milliseconds Feb 17 18:10:59 volumio volumio[874]: info: sendMpdCommand status took 26 milliseconds Feb 17 18:10:59 volumio volumio[874]: verbose: ControllerMpd::parseState Feb 17 18:11:00 volumio volumio[874]: info: Command Router : Notfying DB Updatefalse Feb 17 18:11:00 volumio volumio[874]: info: CoreCommandRouter::Close All Modals sent Feb 17 18:11:00 volumio volumio[874]: verbose: ControllerMpd::parseState Feb 17 18:11:00 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 18:11:00 volumio volumio[874]: verbose: ControllerMpd::parseState Feb 17 18:11:00 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 18:11:00 volumio volumio[874]: info: sendMpdCommand status took 71 milliseconds Feb 17 18:11:00 volumio volumio[874]: info: sendMpdCommand status took 71 milliseconds Feb 17 18:11:00 volumio volumio[874]: info: sendMpdCommand status took 70 milliseconds Feb 17 18:11:00 volumio volumio[874]: verbose: ControllerMpd::parseState Feb 17 18:11:00 volumio volumio[874]: info: Command Router : Notfying DB Updatefalse Feb 17 18:11:00 volumio volumio[874]: info: CoreCommandRouter::Close All Modals sent Feb 17 18:11:00 volumio volumio[874]: verbose: ControllerMpd::parseState Feb 17 18:11:00 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 18:11:00 volumio volumio[874]: verbose: ControllerMpd::parseState Feb 17 18:11:00 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 18:11:00 volumio volumio[874]: info: sendMpdCommand status took 102 milliseconds Feb 17 18:11:00 volumio volumio[874]: info: sendMpdCommand status took 100 milliseconds Feb 17 18:11:00 volumio volumio[874]: info: sendMpdCommand status took 99 milliseconds Feb 17 18:11:00 volumio volumio[874]: verbose: ControllerMpd::parseState Feb 17 18:11:00 volumio volumio[874]: info: Command Router : Notfying DB Updatefalse Feb 17 18:11:00 volumio volumio[874]: info: CoreCommandRouter::Close All Modals sent Feb 17 18:11:00 volumio volumio[874]: verbose: ControllerMpd::parseState Feb 17 18:11:00 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 18:11:00 volumio volumio[874]: verbose: ControllerMpd::parseState Feb 17 18:11:00 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 18:11:00 volumio volumio[874]: info: sendMpdCommand status took 135 milliseconds Feb 17 18:11:00 volumio volumio[874]: info: sendMpdCommand status took 135 milliseconds Feb 17 18:11:00 volumio volumio[874]: info: sendMpdCommand status took 135 milliseconds Feb 17 18:11:00 volumio volumio[874]: info: sendMpdCommand playlistinfo took 80 milliseconds Feb 17 18:11:00 volumio volumio[874]: info: sendMpdCommand playlistinfo took 80 milliseconds Feb 17 18:11:00 volumio volumio[874]: info: sendMpdCommand playlistinfo took 51 milliseconds Feb 17 18:11:00 volumio volumio[874]: info: sendMpdCommand playlistinfo took 52 milliseconds Feb 17 18:11:00 volumio volumio[874]: info: sendMpdCommand playlistinfo took 15 milliseconds Feb 17 18:11:00 volumio volumio[874]: info: sendMpdCommand playlistinfo took 15 milliseconds Feb 17 18:11:00 volumio volumio[874]: verbose: ControllerMpd::parseState Feb 17 18:11:00 volumio volumio[874]: info: Command Router : Notfying DB Updatefalse Feb 17 18:11:00 volumio volumio[874]: info: CoreCommandRouter::Close All Modals sent Feb 17 18:11:00 volumio volumio[874]: verbose: ControllerMpd::parseState Feb 17 18:11:00 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 18:11:00 volumio volumio[874]: verbose: ControllerMpd::parseState Feb 17 18:11:00 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 18:11:00 volumio volumio[874]: verbose: ControllerMpd::parseTrackInfo Feb 17 18:11:00 volumio volumio[874]: verbose: ControllerMpd::parseTrackInfo Feb 17 18:11:00 volumio volumio[874]: info: ControllerMpd::pushState Feb 17 18:11:00 volumio volumio[874]: info: CoreCommandRouter::servicePushState Feb 17 18:11:00 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:11:00 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:00 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:11:00 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:00 volumio volumio[874]: 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"} Feb 17 18:11:00 volumio volumio[874]: verbose: CURRENT POSITION 0 Feb 17 18:11:00 volumio volumio[874]: info: CoreStateMachine::syncState stateService stop Feb 17 18:11:00 volumio volumio[874]: info: CoreStateMachine::syncState currentStatus stop Feb 17 18:11:00 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:11:00 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:00 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 18:11:00 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:11:00 volumio volumio[874]: info: No code Feb 17 18:11:00 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:11:00 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:00 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:11:00 volumio volumio[874]: info: ControllerMpd::pushState Feb 17 18:11:00 volumio volumio[874]: info: CoreCommandRouter::servicePushState Feb 17 18:11:00 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:11:00 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:00 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:11:00 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:00 volumio volumio[874]: 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"} Feb 17 18:11:00 volumio volumio[874]: verbose: CURRENT POSITION 0 Feb 17 18:11:00 volumio volumio[874]: info: CoreStateMachine::syncState stateService stop Feb 17 18:11:00 volumio volumio[874]: info: CoreStateMachine::syncState currentStatus stop Feb 17 18:11:00 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:11:00 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:00 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:11:00 volumio volumio[874]: info: No code Feb 17 18:11:00 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:11:00 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:00 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:11:00 volumio volumio[874]: info: ControllerMpd::pushState Feb 17 18:11:00 volumio volumio[874]: info: CoreCommandRouter::servicePushState Feb 17 18:11:00 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:11:00 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:00 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:11:00 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:00 volumio volumio[874]: 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"} Feb 17 18:11:00 volumio volumio[874]: verbose: CURRENT POSITION 0 Feb 17 18:11:00 volumio volumio[874]: info: CoreStateMachine::syncState stateService stop Feb 17 18:11:00 volumio volumio[874]: info: CoreStateMachine::syncState currentStatus stop Feb 17 18:11:00 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:11:00 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:00 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:11:00 volumio volumio[874]: info: No code Feb 17 18:11:00 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:11:00 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:00 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:11:00 volumio volumio[874]: info: ControllerMpd::pushState Feb 17 18:11:00 volumio volumio[874]: info: CoreCommandRouter::servicePushState Feb 17 18:11:00 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:11:00 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:00 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:11:00 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:00 volumio volumio[874]: 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"} Feb 17 18:11:00 volumio volumio[874]: verbose: CURRENT POSITION 0 Feb 17 18:11:00 volumio volumio[874]: info: CoreStateMachine::syncState stateService stop Feb 17 18:11:00 volumio volumio[874]: info: CoreStateMachine::syncState currentStatus stop Feb 17 18:11:00 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:11:00 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:00 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:11:00 volumio volumio[874]: info: No code Feb 17 18:11:00 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:11:00 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:00 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:11:00 volumio volumio[874]: info: ControllerMpd::pushState Feb 17 18:11:00 volumio volumio[874]: info: CoreCommandRouter::servicePushState Feb 17 18:11:00 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:11:00 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:00 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:11:00 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:00 volumio volumio[874]: 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"} Feb 17 18:11:00 volumio volumio[874]: verbose: CURRENT POSITION 0 Feb 17 18:11:00 volumio volumio[874]: info: CoreStateMachine::syncState stateService stop Feb 17 18:11:00 volumio volumio[874]: info: CoreStateMachine::syncState currentStatus stop Feb 17 18:11:00 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:11:00 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:00 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:11:00 volumio volumio[874]: info: No code Feb 17 18:11:00 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:11:00 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:00 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:11:00 volumio volumio[874]: info: ControllerMpd::pushState Feb 17 18:11:00 volumio volumio[874]: info: CoreCommandRouter::servicePushState Feb 17 18:11:00 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:11:00 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:00 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:11:00 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:00 volumio volumio[874]: 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"} Feb 17 18:11:00 volumio volumio[874]: verbose: CURRENT POSITION 0 Feb 17 18:11:00 volumio volumio[874]: info: CoreStateMachine::syncState stateService stop Feb 17 18:11:00 volumio volumio[874]: info: CoreStateMachine::syncState currentStatus stop Feb 17 18:11:00 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:11:00 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:00 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:11:00 volumio volumio[874]: info: No code Feb 17 18:11:00 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:11:00 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:00 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:11:00 volumio volumio[874]: info: ------------------------------ 313ms Feb 17 18:11:00 volumio volumio[874]: info: ------------------------------ 314ms Feb 17 18:11:00 volumio volumio[874]: info: ------------------------------ 313ms Feb 17 18:11:00 volumio volumio[874]: info: ------------------------------ 313ms Feb 17 18:11:00 volumio volumio[874]: info: ------------------------------ 313ms Feb 17 18:11:00 volumio volumio[874]: info: ------------------------------ 316ms Feb 17 18:11:00 volumio volumio[874]: info: sendMpdCommand playlistinfo took 204 milliseconds Feb 17 18:11:00 volumio volumio[874]: info: sendMpdCommand playlistinfo took 204 milliseconds Feb 17 18:11:00 volumio volumio[874]: verbose: ControllerMpd::parseTrackInfo Feb 17 18:11:00 volumio volumio[874]: verbose: ControllerMpd::parseTrackInfo Feb 17 18:11:00 volumio volumio[874]: info: ControllerMpd::pushState Feb 17 18:11:00 volumio volumio[874]: info: CoreCommandRouter::servicePushState Feb 17 18:11:00 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:11:00 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:00 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:11:00 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:00 volumio volumio[874]: 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"} Feb 17 18:11:00 volumio volumio[874]: verbose: CURRENT POSITION 0 Feb 17 18:11:00 volumio volumio[874]: info: CoreStateMachine::syncState stateService stop Feb 17 18:11:00 volumio volumio[874]: info: CoreStateMachine::syncState currentStatus stop Feb 17 18:11:00 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:11:00 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:00 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:11:00 volumio volumio[874]: info: No code Feb 17 18:11:00 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:11:00 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:00 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:11:00 volumio volumio[874]: info: ControllerMpd::pushState Feb 17 18:11:00 volumio volumio[874]: info: CoreCommandRouter::servicePushState Feb 17 18:11:00 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:11:00 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:00 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:11:00 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:00 volumio volumio[874]: 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"} Feb 17 18:11:00 volumio volumio[874]: verbose: CURRENT POSITION 0 Feb 17 18:11:00 volumio volumio[874]: info: CoreStateMachine::syncState stateService stop Feb 17 18:11:00 volumio volumio[874]: info: CoreStateMachine::syncState currentStatus stop Feb 17 18:11:00 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:11:00 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:00 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:11:00 volumio volumio[874]: info: No code Feb 17 18:11:00 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:11:00 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:00 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:11:00 volumio volumio[874]: info: ------------------------------ 395ms Feb 17 18:11:00 volumio volumio[874]: info: ------------------------------ 396ms Feb 17 18:11:04 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 17 18:11:04 volumio volumio[874]: info: CURURI: music-library/USB Feb 17 18:11:04 volumio volumio[874]: info: Preload queue cleared Feb 17 18:11:05 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 17 18:11:05 volumio volumio[874]: info: CURURI: music-library/USB/ϵͳ Feb 17 18:11:05 volumio volumio[874]: info: Preload queue cleared Feb 17 18:11:09 volumio volumio[874]: info: CoreCommandRouter::volumioGetState Feb 17 18:11:09 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:13 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 17 18:11:13 volumio volumio[874]: info: CURURI: music-library/USB/ϵͳ/试机一号 Feb 17 18:11:13 volumio volumio[874]: info: Preload queue cleared Feb 17 18:11:13 volumio volumio[874]: info: Preloading song: music-library/USB/ϵͳ/试机一号/CDImage.wav Feb 17 18:11:13 volumio volumio[874]: info: Exploding uri music-library/USB/ϵͳ/试机一号/CDImage.wav in service mpd Feb 17 18:11:13 volumio volumio[874]: info: ALBUMART /albumart?cacheid=943&web=//extralarge&path=%2Fmnt%2FUSB%2F%CF%B5%CD%B3%2F%E8%AF%95%E6%9C%BA%E4%B8%80%E5%8F%B7%2FCDImage.wav&metadata=false Feb 17 18:11:13 volumio volumio[874]: info: URI /mnt/USB/ϵͳ/试机一号/CDImage.wav Feb 17 18:11:14 volumio volumio[874]: info: Preload queue cleared Feb 17 18:11:14 volumio volumio[874]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 17 18:11:14 volumio volumio[874]: info: CoreStateMachine::ClearQueue Feb 17 18:11:14 volumio volumio[874]: info: CoreStateMachine::stop Feb 17 18:11:14 volumio volumio[874]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 18:11:14 volumio volumio[874]: info: CorePlayQueue::clearPlayQueue Feb 17 18:11:14 volumio volumio[874]: info: CorePlayQueue::saveQueue Feb 17 18:11:14 volumio volumio[874]: info: CoreCommandRouter::volumioPushQueue Feb 17 18:11:14 volumio volumio[874]: info: CoreStateMachine::addQueueItems Feb 17 18:11:14 volumio volumio[874]: info: CorePlayQueue::addQueueItems Feb 17 18:11:14 volumio volumio[874]: info: Preload queue cleared Feb 17 18:11:14 volumio volumio[874]: info: Adding Item to queue: music-library/USB/ϵͳ/试机一号/CDImage.wav Feb 17 18:11:14 volumio volumio[874]: info: Using cached record of: music-library/USB/ϵͳ/试机一号/CDImage.wav Feb 17 18:11:14 volumio volumio[874]: info: CoreCommandRouter::volumioPushQueue Feb 17 18:11:14 volumio volumio[874]: info: CorePlayQueue::saveQueue Feb 17 18:11:14 volumio volumio[874]: info: CoreStateMachine::updateTrackBlock Feb 17 18:11:14 volumio volumio[874]: info: CorePlayQueue::getTrackBlock Feb 17 18:11:14 volumio volumio[874]: info: CoreCommandRouter::volumioPlay Feb 17 18:11:14 volumio volumio[874]: info: CoreStateMachine::play index 0 Feb 17 18:11:14 volumio volumio[874]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 18:11:14 volumio volumio[874]: info: CoreStateMachine::stop Feb 17 18:11:14 volumio volumio[874]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 18:11:14 volumio volumio[874]: info: CoreStateMachine::play index undefined Feb 17 18:11:14 volumio volumio[874]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 18:11:14 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:14 volumio volumio[874]: info: CoreStateMachine::startPlaybackTimer Feb 17 18:11:14 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:14 volumio volumio[874]: verbose: ControllerMpd::clearAddPlayTracks USB/ϵͳ/试机一号/CDImage.wav Feb 17 18:11:14 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand stop Feb 17 18:11:14 volumio volumio[874]: info: sendMpdCommand stop took 3 milliseconds Feb 17 18:11:14 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand clear Feb 17 18:11:14 volumio volumio[874]: info: sendMpdCommand clear took 1 milliseconds Feb 17 18:11:14 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand add "USB/ϵͳ/试机一号/CDImage.wav" Feb 17 18:11:14 volumio volumio[874]: info: Feb 17 18:11:14 volumio volumio[874]: ---------------------------- MPD announces system playlist update Feb 17 18:11:14 volumio volumio[874]: info: Ignoring MPD Status Update Feb 17 18:11:14 volumio volumio[874]: info: Feb 17 18:11:14 volumio volumio[874]: ---------------------------- MPD announces system playlist update Feb 17 18:11:14 volumio volumio[874]: info: Ignoring MPD Status Update Feb 17 18:11:14 volumio volumio[874]: info: sendMpdCommand add "USB/ϵͳ/试机一号/CDImage.wav" took 1 milliseconds Feb 17 18:11:14 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand play Feb 17 18:11:14 volumio volumio[874]: info: Feb 17 18:11:14 volumio volumio[874]: ---------------------------- MPD announces system playlist update Feb 17 18:11:14 volumio volumio[874]: info: Ignoring MPD Status Update Feb 17 18:11:14 volumio volumio[874]: info: Feb 17 18:11:14 volumio volumio[874]: ---------------------------- MPD announces system playlist update Feb 17 18:11:14 volumio volumio[874]: info: Ignoring MPD Status Update Feb 17 18:11:14 volumio volumio[874]: info: Feb 17 18:11:14 volumio volumio[874]: ---------------------------- MPD announces system playlist update Feb 17 18:11:14 volumio volumio[874]: info: Ignoring MPD Status Update Feb 17 18:11:14 volumio volumio[874]: info: Feb 17 18:11:14 volumio volumio[874]: ---------------------------- MPD announces state update: player Feb 17 18:11:14 volumio volumio[874]: info: ------------------------------ 5ms Feb 17 18:11:14 volumio volumio[874]: info: ------------------------------ 4ms Feb 17 18:11:14 volumio volumio[874]: info: sendMpdCommand play took 3 milliseconds Feb 17 18:11:14 volumio volumio[874]: info: ControllerMpd::getState Feb 17 18:11:14 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:11:14 volumio volumio[874]: info: ------------------------------ 4ms Feb 17 18:11:14 volumio volumio[874]: info: ------------------------------ 3ms Feb 17 18:11:14 volumio volumio[874]: info: Feb 17 18:11:14 volumio volumio[874]: ---------------------------- MPD announces system playlist update Feb 17 18:11:14 volumio volumio[874]: info: Ignoring MPD Status Update Feb 17 18:11:14 volumio volumio[874]: info: Feb 17 18:11:14 volumio volumio[874]: ---------------------------- MPD announces state update: player Feb 17 18:11:14 volumio volumio[874]: info: ControllerMpd::getState Feb 17 18:11:14 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:11:14 volumio volumio[874]: info: Feb 17 18:11:14 volumio volumio[874]: ---------------------------- MPD announces system playlist update Feb 17 18:11:14 volumio volumio[874]: info: Ignoring MPD Status Update Feb 17 18:11:14 volumio volumio[874]: info: Feb 17 18:11:14 volumio volumio[874]: ---------------------------- MPD announces state update: player Feb 17 18:11:14 volumio volumio[874]: info: ControllerMpd::getState Feb 17 18:11:14 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:11:14 volumio volumio[874]: info: Feb 17 18:11:14 volumio volumio[874]: ---------------------------- MPD announces system playlist update Feb 17 18:11:14 volumio volumio[874]: info: Ignoring MPD Status Update Feb 17 18:11:14 volumio volumio[874]: info: Feb 17 18:11:14 volumio volumio[874]: ---------------------------- MPD announces state update: player Feb 17 18:11:14 volumio volumio[874]: info: ControllerMpd::getState Feb 17 18:11:14 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:11:14 volumio volumio[874]: error: Upnp client error: Error: This socket has been ended by the other party Feb 17 18:11:14 volumio volumio[874]: info: sendMpdCommand status took 30 milliseconds Feb 17 18:11:14 volumio volumio[874]: verbose: ControllerMpd::parseState Feb 17 18:11:14 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 18:11:14 volumio volumio[874]: info: Feb 17 18:11:14 volumio volumio[874]: ---------------------------- MPD announces state update: player Feb 17 18:11:14 volumio volumio[874]: info: ControllerMpd::getState Feb 17 18:11:14 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:11:14 volumio volumio[874]: info: ------------------------------ 32ms Feb 17 18:11:14 volumio volumio[874]: info: sendMpdCommand status took 31 milliseconds Feb 17 18:11:14 volumio volumio[874]: info: ------------------------------ 31ms Feb 17 18:11:14 volumio volumio[874]: info: sendMpdCommand status took 32 milliseconds Feb 17 18:11:14 volumio volumio[874]: info: ------------------------------ 32ms Feb 17 18:11:14 volumio volumio[874]: info: sendMpdCommand status took 32 milliseconds Feb 17 18:11:14 volumio volumio[874]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 17 18:11:14 volumio volumio[874]: verbose: ControllerMpd::parseState Feb 17 18:11:14 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 18:11:14 volumio volumio[874]: verbose: ControllerMpd::parseState Feb 17 18:11:14 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 18:11:14 volumio volumio[874]: verbose: ControllerMpd::parseState Feb 17 18:11:14 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 18:11:14 volumio volumio[874]: verbose: ControllerMpd::parseTrackInfo Feb 17 18:11:14 volumio volumio[874]: info: ControllerMpd::pushState Feb 17 18:11:14 volumio volumio[874]: info: CoreCommandRouter::servicePushState Feb 17 18:11:14 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:14 volumio volumio[874]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3839,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.wav","artist":null,"album":null,"uri":"USB/ϵͳ/试机一号/CDImage.wav","trackType":"wav"} Feb 17 18:11:14 volumio volumio[874]: verbose: CURRENT POSITION 0 Feb 17 18:11:14 volumio volumio[874]: info: CoreStateMachine::syncState stateService play Feb 17 18:11:14 volumio volumio[874]: info: CoreStateMachine::syncState currentStatus stop Feb 17 18:11:14 volumio volumio[874]: info: ------------------------------ 39ms Feb 17 18:11:14 volumio volumio[874]: info: Feb 17 18:11:14 volumio volumio[874]: ---------------------------- MPD announces state update: player Feb 17 18:11:14 volumio volumio[874]: info: ControllerMpd::getState Feb 17 18:11:14 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:11:14 volumio volumio[874]: info: Feb 17 18:11:14 volumio volumio[874]: ---------------------------- MPD announces state update: player Feb 17 18:11:14 volumio volumio[874]: info: ControllerMpd::getState Feb 17 18:11:14 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:11:14 volumio volumio[874]: info: Feb 17 18:11:14 volumio volumio[874]: ---------------------------- MPD announces state update: player Feb 17 18:11:14 volumio volumio[874]: info: ControllerMpd::getState Feb 17 18:11:14 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:11:14 volumio volumio[874]: info: sendMpdCommand status took 9 milliseconds Feb 17 18:11:14 volumio volumio[874]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 17 18:11:14 volumio volumio[874]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 17 18:11:14 volumio volumio[874]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 17 18:11:14 volumio volumio[874]: info: sendMpdCommand status took 3 milliseconds Feb 17 18:11:14 volumio volumio[874]: verbose: ControllerMpd::parseState Feb 17 18:11:14 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 18:11:14 volumio volumio[874]: verbose: ControllerMpd::parseTrackInfo Feb 17 18:11:14 volumio volumio[874]: verbose: ControllerMpd::parseState Feb 17 18:11:14 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 18:11:14 volumio volumio[874]: info: ControllerMpd::pushState Feb 17 18:11:14 volumio volumio[874]: info: CoreCommandRouter::servicePushState Feb 17 18:11:14 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:14 volumio volumio[874]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":3839,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.wav","artist":null,"album":null,"uri":"USB/ϵͳ/试机一号/CDImage.wav","trackType":"wav"} Feb 17 18:11:14 volumio volumio[874]: verbose: CURRENT POSITION 0 Feb 17 18:11:14 volumio volumio[874]: info: CoreStateMachine::syncState stateService play Feb 17 18:11:14 volumio volumio[874]: info: CoreStateMachine::syncState currentStatus play Feb 17 18:11:14 volumio volumio[874]: info: Received an update from plugin. extracting info from payload Feb 17 18:11:14 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:11:14 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:14 volumio volumio[874]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 18:11:14 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:11:14 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:11:14 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:14 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:11:14 volumio volumio[874]: info: ControllerMpd::pushState Feb 17 18:11:14 volumio volumio[874]: info: CoreCommandRouter::servicePushState Feb 17 18:11:14 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:14 volumio volumio[874]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":3839,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.wav","artist":null,"album":null,"uri":"USB/ϵͳ/试机一号/CDImage.wav","trackType":"wav"} Feb 17 18:11:14 volumio volumio[874]: verbose: CURRENT POSITION 0 Feb 17 18:11:14 volumio volumio[874]: info: CoreStateMachine::syncState stateService play Feb 17 18:11:14 volumio volumio[874]: info: CoreStateMachine::syncState currentStatus play Feb 17 18:11:14 volumio volumio[874]: info: Received an update from plugin. extracting info from payload Feb 17 18:11:14 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:11:14 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:14 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:11:14 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:11:14 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:14 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:11:14 volumio volumio[874]: info: ControllerMpd::pushState Feb 17 18:11:14 volumio volumio[874]: info: CoreCommandRouter::servicePushState Feb 17 18:11:14 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:14 volumio volumio[874]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":3839,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.wav","artist":null,"album":null,"uri":"USB/ϵͳ/试机一号/CDImage.wav","trackType":"wav"} Feb 17 18:11:14 volumio volumio[874]: verbose: CURRENT POSITION 0 Feb 17 18:11:14 volumio volumio[874]: info: CoreStateMachine::syncState stateService play Feb 17 18:11:14 volumio volumio[874]: info: CoreStateMachine::syncState currentStatus play Feb 17 18:11:14 volumio volumio[874]: info: Received an update from plugin. extracting info from payload Feb 17 18:11:14 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:11:14 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:14 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:11:14 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:11:14 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:14 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:11:14 volumio volumio[874]: info: ------------------------------ 75ms Feb 17 18:11:14 volumio volumio[874]: info: ------------------------------ 74ms Feb 17 18:11:14 volumio volumio[874]: info: sendMpdCommand status took 38 milliseconds Feb 17 18:11:14 volumio volumio[874]: info: sendMpdCommand status took 37 milliseconds Feb 17 18:11:14 volumio volumio[874]: info: sendMpdCommand playlistinfo took 35 milliseconds Feb 17 18:11:14 volumio volumio[874]: info: sendMpdCommand playlistinfo took 35 milliseconds Feb 17 18:11:14 volumio volumio[874]: verbose: ControllerMpd::parseState Feb 17 18:11:14 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 18:11:14 volumio volumio[874]: verbose: ControllerMpd::parseState Feb 17 18:11:14 volumio volumio[874]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 18:11:14 volumio volumio[874]: verbose: ControllerMpd::parseTrackInfo Feb 17 18:11:14 volumio volumio[874]: verbose: ControllerMpd::parseTrackInfo Feb 17 18:11:14 volumio volumio[874]: info: ControllerMpd::pushState Feb 17 18:11:14 volumio volumio[874]: info: CoreCommandRouter::servicePushState Feb 17 18:11:14 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:14 volumio volumio[874]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":3839,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.wav","artist":null,"album":null,"uri":"USB/ϵͳ/试机一号/CDImage.wav","trackType":"wav"} Feb 17 18:11:14 volumio volumio[874]: verbose: CURRENT POSITION 0 Feb 17 18:11:14 volumio volumio[874]: info: CoreStateMachine::syncState stateService play Feb 17 18:11:14 volumio volumio[874]: info: CoreStateMachine::syncState currentStatus play Feb 17 18:11:14 volumio volumio[874]: info: Received an update from plugin. extracting info from payload Feb 17 18:11:14 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:11:14 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:14 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:11:14 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:11:14 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:14 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:11:14 volumio volumio[874]: info: ControllerMpd::pushState Feb 17 18:11:14 volumio volumio[874]: info: CoreCommandRouter::servicePushState Feb 17 18:11:14 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:14 volumio volumio[874]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":3839,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.wav","artist":null,"album":null,"uri":"USB/ϵͳ/试机一号/CDImage.wav","trackType":"wav"} Feb 17 18:11:14 volumio volumio[874]: verbose: CURRENT POSITION 0 Feb 17 18:11:14 volumio volumio[874]: info: CoreStateMachine::syncState stateService play Feb 17 18:11:14 volumio volumio[874]: info: CoreStateMachine::syncState currentStatus play Feb 17 18:11:14 volumio volumio[874]: info: Received an update from plugin. extracting info from payload Feb 17 18:11:14 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:11:14 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:14 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:11:14 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:11:14 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:14 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:11:14 volumio volumio[874]: info: ------------------------------ 72ms Feb 17 18:11:14 volumio volumio[874]: info: ------------------------------ 66ms Feb 17 18:11:14 volumio volumio[874]: info: sendMpdCommand playlistinfo took 63 milliseconds Feb 17 18:11:14 volumio volumio[874]: info: sendMpdCommand playlistinfo took 63 milliseconds Feb 17 18:11:14 volumio volumio[874]: verbose: ControllerMpd::parseTrackInfo Feb 17 18:11:14 volumio volumio[874]: verbose: ControllerMpd::parseTrackInfo Feb 17 18:11:14 volumio volumio[874]: info: ControllerMpd::pushState Feb 17 18:11:14 volumio volumio[874]: info: CoreCommandRouter::servicePushState Feb 17 18:11:14 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:14 volumio volumio[874]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":3839,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.wav","artist":null,"album":null,"uri":"USB/ϵͳ/试机一号/CDImage.wav","trackType":"wav"} Feb 17 18:11:14 volumio volumio[874]: verbose: CURRENT POSITION 0 Feb 17 18:11:14 volumio volumio[874]: info: CoreStateMachine::syncState stateService play Feb 17 18:11:14 volumio volumio[874]: info: CoreStateMachine::syncState currentStatus play Feb 17 18:11:14 volumio volumio[874]: info: Received an update from plugin. extracting info from payload Feb 17 18:11:14 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:11:14 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:14 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:11:14 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:11:14 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:14 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:11:14 volumio volumio[874]: info: ControllerMpd::pushState Feb 17 18:11:14 volumio volumio[874]: info: CoreCommandRouter::servicePushState Feb 17 18:11:14 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:14 volumio volumio[874]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":3839,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.wav","artist":null,"album":null,"uri":"USB/ϵͳ/试机一号/CDImage.wav","trackType":"wav"} Feb 17 18:11:14 volumio volumio[874]: verbose: CURRENT POSITION 0 Feb 17 18:11:14 volumio volumio[874]: info: CoreStateMachine::syncState stateService play Feb 17 18:11:14 volumio volumio[874]: info: CoreStateMachine::syncState currentStatus play Feb 17 18:11:14 volumio volumio[874]: info: Received an update from plugin. extracting info from payload Feb 17 18:11:14 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:11:14 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:14 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:11:14 volumio volumio[874]: info: CoreStateMachine::pushState Feb 17 18:11:14 volumio volumio[874]: info: CorePlayQueue::getTrack 0 Feb 17 18:11:14 volumio volumio[874]: info: CoreCommandRouter::volumioPushState Feb 17 18:11:14 volumio volumio[874]: info: ------------------------------ 139ms Feb 17 18:11:14 volumio volumio[874]: info: ------------------------------ 142ms Feb 17 18:11:16 volumio volumio[874]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 18:11:16 volumio volumio[874]: Error: connect ETIMEDOUT 128.121.243.77:80 Feb 17 18:11:16 volumio volumio[874]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Feb 17 18:11:16 volumio volumio[874]: errno: -110, Feb 17 18:11:16 volumio volumio[874]: code: 'ETIMEDOUT', Feb 17 18:11:16 volumio volumio[874]: syscall: 'connect', Feb 17 18:11:16 volumio volumio[874]: address: '128.121.243.77', Feb 17 18:11:16 volumio volumio[874]: port: 80 Feb 17 18:11:16 volumio volumio[874]: } Feb 17 18:11:16 volumio volumio[874]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 18:11:16 volumio sudo[2170]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-17 18:10 Feb 17 18:11:16 volumio sudo[2170]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:42:03 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="c434411fe8754b648dc7feb1d8f43761"