-- Logs begin at Wed 2024-08-14 06:06:49 CST, end at Sat 2025-01-18 22:07:55 CST. -- Jan 18 22:06:02 volumio volumio[853]: info: CoreCommandRouter::volumioPause Jan 18 22:06:02 volumio volumio[853]: info: CoreStateMachine::pause Jan 18 22:06:02 volumio volumio[853]: info: CoreStateMachine::stPlaybackTimer Jan 18 22:06:02 volumio volumio[853]: info: CoreStateMachine::servicePause Jan 18 22:06:02 volumio volumio[853]: info: CorePlayQueue::getTrack 63 Jan 18 22:06:02 volumio volumio[853]: info: CoreCommandRouter::servicePause Jan 18 22:06:02 volumio volumio[853]: info: ControllerMpd::pause Jan 18 22:06:02 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand pause Jan 18 22:06:02 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jan 18 22:06:02 volumio volumio[853]: info: Jan 18 22:06:02 volumio volumio[853]: ---------------------------- MPD announces state update: player Jan 18 22:06:02 volumio volumio[853]: info: sendMpdCommand pause took 6 milliseconds Jan 18 22:06:02 volumio volumio[853]: info: ControllerMpd::getState Jan 18 22:06:02 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand status Jan 18 22:06:02 volumio volumio[853]: info: Jan 18 22:06:02 volumio volumio[853]: ---------------------------- MPD announces state update: player Jan 18 22:06:02 volumio volumio[853]: info: ControllerMpd::getState Jan 18 22:06:02 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand status Jan 18 22:06:02 volumio volumio[853]: info: Jan 18 22:06:02 volumio volumio[853]: ---------------------------- MPD announces state update: player Jan 18 22:06:02 volumio volumio[853]: info: ControllerMpd::getState Jan 18 22:06:02 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand status Jan 18 22:06:02 volumio volumio[853]: info: sendMpdCommand status took 3 milliseconds Jan 18 22:06:02 volumio volumio[853]: info: sendMpdCommand status took 3 milliseconds Jan 18 22:06:02 volumio volumio[853]: info: sendMpdCommand status took 2 milliseconds Jan 18 22:06:02 volumio volumio[853]: verbose: ControllerMpd::parseState Jan 18 22:06:02 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 18 22:06:02 volumio volumio[853]: verbose: ControllerMpd::parseState Jan 18 22:06:02 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 18 22:06:02 volumio volumio[853]: verbose: ControllerMpd::parseState Jan 18 22:06:02 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 18 22:06:02 volumio volumio[853]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 18 22:06:02 volumio volumio[853]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 18 22:06:02 volumio volumio[853]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 18 22:06:02 volumio volumio[853]: verbose: ControllerMpd::parseTrackInfo Jan 18 22:06:02 volumio volumio[853]: verbose: ControllerMpd::parseTrackInfo Jan 18 22:06:02 volumio volumio[853]: verbose: ControllerMpd::parseTrackInfo Jan 18 22:06:02 volumio volumio[853]: info: ControllerMpd::pushState Jan 18 22:06:02 volumio volumio[853]: info: CoreCommandRouter::servicePushState Jan 18 22:06:02 volumio volumio[853]: info: CorePlayQueue::getTrack 63 Jan 18 22:06:02 volumio volumio[853]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":12858,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??-????","artist":"??","album":"????","uri":"USB/NAS-4T/选曲/选曲/14. 车站－女声测试.wav","trackType":"wav"} Jan 18 22:06:02 volumio volumio[853]: verbose: CURRENT POSITION 63 Jan 18 22:06:02 volumio volumio[853]: info: CoreStateMachine::syncState stateService pause Jan 18 22:06:02 volumio volumio[853]: info: CoreStateMachine::syncState currentStatus pause Jan 18 22:06:02 volumio volumio[853]: info: CoreStateMachine::pushState Jan 18 22:06:02 volumio volumio[853]: info: CorePlayQueue::getTrack 63 Jan 18 22:06:02 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 18 22:06:02 volumio volumio[853]: info: CoreCommandRouter::volumioPushState Jan 18 22:06:02 volumio volumio[853]: info: CoreStateMachine::stPlaybackTimer Jan 18 22:06:02 volumio volumio[853]: info: ControllerMpd::pushState Jan 18 22:06:02 volumio volumio[853]: info: CoreCommandRouter::servicePushState Jan 18 22:06:02 volumio volumio[853]: info: CorePlayQueue::getTrack 63 Jan 18 22:06:02 volumio volumio[853]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":12858,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??-????","artist":"??","album":"????","uri":"USB/NAS-4T/选曲/选曲/14. 车站－女声测试.wav","trackType":"wav"} Jan 18 22:06:02 volumio volumio[853]: verbose: CURRENT POSITION 63 Jan 18 22:06:02 volumio volumio[853]: info: CoreStateMachine::syncState stateService pause Jan 18 22:06:02 volumio volumio[853]: info: CoreStateMachine::syncState currentStatus pause Jan 18 22:06:02 volumio volumio[853]: info: CoreStateMachine::pushState Jan 18 22:06:02 volumio volumio[853]: info: CorePlayQueue::getTrack 63 Jan 18 22:06:02 volumio volumio[853]: info: CoreCommandRouter::volumioPushState Jan 18 22:06:02 volumio volumio[853]: info: CoreStateMachine::stPlaybackTimer Jan 18 22:06:02 volumio volumio[853]: info: ControllerMpd::pushState Jan 18 22:06:02 volumio volumio[853]: info: CoreCommandRouter::servicePushState Jan 18 22:06:02 volumio volumio[853]: info: CorePlayQueue::getTrack 63 Jan 18 22:06:02 volumio volumio[853]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":12858,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??-????","artist":"??","album":"????","uri":"USB/NAS-4T/选曲/选曲/14. 车站－女声测试.wav","trackType":"wav"} Jan 18 22:06:02 volumio volumio[853]: verbose: CURRENT POSITION 63 Jan 18 22:06:02 volumio volumio[853]: info: CoreStateMachine::syncState stateService pause Jan 18 22:06:02 volumio volumio[853]: info: CoreStateMachine::syncState currentStatus pause Jan 18 22:06:02 volumio volumio[853]: info: CoreStateMachine::pushState Jan 18 22:06:02 volumio volumio[853]: info: CorePlayQueue::getTrack 63 Jan 18 22:06:02 volumio volumio[853]: info: CoreCommandRouter::volumioPushState Jan 18 22:06:02 volumio volumio[853]: info: CoreStateMachine::stPlaybackTimer Jan 18 22:06:02 volumio volumio[853]: info: ------------------------------ 20ms Jan 18 22:06:02 volumio volumio[853]: info: ------------------------------ 18ms Jan 18 22:06:02 volumio volumio[853]: info: ------------------------------ 18ms Jan 18 22:06:13 volumio kernel: hwmon hwmon1: Undervoltage detected! Jan 18 22:06:16 volumio kernel: usb 1-1.5: USB disconnect, device number 4 Jan 18 22:06:16 volumio volumio[853]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.5/1-1.5:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","DEVLINKS":"/dev/disk/by-id/ata-ST4000VN000-1H4168_W300ZVDX-part1 /dev/disk/by-uuid/67E3-17ED /dev/disk/by-partlabel/EFI\\x20System\\x20Partition /dev/disk/by-path/platform-3f980000.usb-usb-0:1.5:1.0-scsi-0:0:0:0-part1 /dev/disk/by-label/EFI /dev/disk/by-id/wwn-0x5000c5008bbff247-part1 /dev/disk/by-partuuid/9f585dd8-5c28-4637-9089-99e8aed1d5f8","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.5/1-1.5:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"27","ID_ATA":"1","ID_ATA_DOWNLOAD_MICROCODE":"1","ID_ATA_FEATURE_SET_APM":"1","ID_ATA_FEATURE_SET_APM_CURRENT_VALUE":"192","ID_ATA_FEATURE_SET_APM_ENABLED":"1","ID_ATA_FEATURE_SET_HPA":"1","ID_ATA_FEATURE_SET_HPA_ENABLED":"1","ID_ATA_FEATURE_SET_PM":"1","ID_ATA_FEATURE_SET_PM_ENABLED":"1","ID_ATA_FEATURE_SET_PUIS":"1","ID_ATA_FEATURE_SET_PUIS_ENABLED":"0","ID_ATA_FEATURE_SET_SECURITY":"1","ID_ATA_FEATURE_SET_SECURITY_ENABLED":"0","ID_ATA_FEATURE_SET_SECURITY_ENHANCED_ERASE_UNIT_MIN":"470","ID_ATA_FEATURE_SET_SECURITY_ERASE_UNIT_MIN":"470","ID_ATA_FEATURE_SET_SMART":"1","ID_ATA_FEATURE_SET_SMART_ENABLED":"1","ID_ATA_ROTATION_RATE_RPM":"5900","ID_ATA_SATA":"1","ID_ATA_SATA_SIGNAL_RATE_GEN1":"1","ID_ATA_SATA_SIGNAL_RATE_GEN2":"1","ID_ATA_WRITE_CACHE":"1","ID_ATA_WRITE_CACHE_ENABLED":"1","ID_BUS":"ata","ID_FS_LABEL":"EFI","ID_FS_LABEL_ENC":"EFI","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"67E3-17ED","ID_FS_UUID_ENC":"67E3-17ED","ID_FS_VERSION":"FAT32","ID_MODEL":"ST4000VN000-1H4168","ID_MODEL_ENC":"ST4000VN000-1H4168\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_NAME":"EFI\\x20System\\x20Partition","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"40","ID_PART_ENTRY_SCHEME":"gpt","ID_PART_ENTRY_SIZE":"409600","ID_PART_ENTRY_TYPE":"c12a7328-f81f-11d2-ba4b-00a0c93ec93b","ID_PART_ENTRY_UUID":"9f585dd8-5c28-4637-9089-99e8aed1d5f8","ID_PART_TABLE_TYPE":"gpt","ID_PART_TABLE_UUID":"f12d25fd-1a62-4c7d-b030-fc1ad8c3bdca","ID_PATH":"platform-3f980000.usb-usb-0:1.5:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-3f980000_usb-usb-0_1_5_1_0-scsi-0_0_0_0","ID_REVISION":"SC46","ID_SERIAL":"ST4000VN000-1H4168_W300ZVDX","ID_SERIAL_SHORT":"W300ZVDX","ID_TYPE":"disk","ID_WWN":"0x5000c5008bbff247","ID_WWN_WITH_EXTENSION":"0x5000c5008bbff247","MAJOR":"8","MINOR":"1","PARTN":"1","PARTNAME":"EFI System Partition","SEQNUM":"1931","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"105332925"} Jan 18 22:06:16 volumio sudo[15002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda1 Jan 18 22:06:16 volumio sudo[15002]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 22:06:16 volumio systemd[1]: media-EFI.mount: Succeeded. Jan 18 22:06:18 volumio sudo[15002]: pam_unix(sudo:session): session closed for user root Jan 18 22:06:18 volumio volumio[853]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.5/1-1.5:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","ACTION":"remove","DEVLINKS":"/dev/disk/by-uuid/7E63C058775C24A6 /dev/disk/by-id/wwn-0x5000c5008bbff247-part2 /dev/disk/by-path/platform-3f980000.usb-usb-0:1.5:1.0-scsi-0:0:0:0-part2 /dev/disk/by-label/NAS-4T /dev/disk/by-partuuid/a8eedc49-a1d7-4fc9-a0d3-80a2c9460e51 /dev/disk/by-id/ata-ST4000VN000-1H4168_W300ZVDX-part2","DEVNAME":"/dev/sda2","DEVPATH":"/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.5/1-1.5:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","DEVTYPE":"partition","DISKSEQ":"27","ID_ATA":"1","ID_ATA_DOWNLOAD_MICROCODE":"1","ID_ATA_FEATURE_SET_APM":"1","ID_ATA_FEATURE_SET_APM_CURRENT_VALUE":"192","ID_ATA_FEATURE_SET_APM_ENABLED":"1","ID_ATA_FEATURE_SET_HPA":"1","ID_ATA_FEATURE_SET_HPA_ENABLED":"1","ID_ATA_FEATURE_SET_PM":"1","ID_ATA_FEATURE_SET_PM_ENABLED":"1","ID_ATA_FEATURE_SET_PUIS":"1","ID_ATA_FEATURE_SET_PUIS_ENABLED":"0","ID_ATA_FEATURE_SET_SECURITY":"1","ID_ATA_FEATURE_SET_SECURITY_ENABLED":"0","ID_ATA_FEATURE_SET_SECURITY_ENHANCED_ERASE_UNIT_MIN":"470","ID_ATA_FEATURE_SET_SECURITY_ERASE_UNIT_MIN":"470","ID_ATA_FEATURE_SET_SMART":"1","ID_ATA_FEATURE_SET_SMART_ENABLED":"1","ID_ATA_ROTATION_RATE_RPM":"5900","ID_ATA_SATA":"1","ID_ATA_SATA_SIGNAL_RATE_GEN1":"1","ID_ATA_SATA_SIGNAL_RATE_GEN2":"1","ID_ATA_WRITE_CACHE":"1","ID_ATA_WRITE_CACHE_ENABLED":"1","ID_BUS":"ata","ID_FS_LABEL":"NAS-4T","ID_FS_LABEL_ENC":"NAS-4T","ID_FS_TYPE":"ntfs","ID_FS_USAGE":"filesystem","ID_FS_UUID":"7E63C058775C24A6","ID_FS_UUID_ENC":"7E63C058775C24A6","ID_MODEL":"ST4000VN000-1H4168","ID_MODEL_ENC":"ST4000VN000-1H4168\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_NUMBER":"2","ID_PART_ENTRY_OFFSET":"411648","ID_PART_ENTRY_SCHEME":"gpt","ID_PART_ENTRY_SIZE":"7813623808","ID_PART_ENTRY_TYPE":"ebd0a0a2-b9e5-4433-87c0-68b6b72699c7","ID_PART_ENTRY_UUID":"a8eedc49-a1d7-4fc9-a0d3-80a2c9460e51","ID_PART_TABLE_TYPE":"atari","ID_PART_TABLE_UUID":"f12d25fd-1a62-4c7d-b030-fc1ad8c3bdca","ID_PATH":"platform-3f980000.usb-usb-0:1.5:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-3f980000_usb-usb-0_1_5_1_0-scsi-0_0_0_0","ID_REVISION":"SC46","ID_SERIAL":"ST4000VN000-1H4168_W300ZVDX","ID_SERIAL_SHORT":"W300ZVDX","ID_TYPE":"disk","ID_WWN":"0x5000c5008bbff247","ID_WWN_WITH_EXTENSION":"0x5000c5008bbff247","MAJOR":"8","MINOR":"2","PARTN":"2","SEQNUM":"1932","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"105332774"} Jan 18 22:06:18 volumio sudo[15007]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda2 Jan 18 22:06:18 volumio sudo[15007]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 18 22:06:18 volumio sudo[15007]: pam_unix(sudo:session): session closed for user root Jan 18 22:06:18 volumio volumio[853]: umount: /media/NAS-4T: target is busy. Jan 18 22:06:18 volumio volumio[853]: error: Failed to umount NAS-4T: Error: Command failed: /usr/bin/sudo /bin/umount -f "/dev/sda2" Jan 18 22:06:18 volumio volumio[853]: umount: /media/NAS-4T: target is busy. Jan 18 22:06:18 volumio kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache Jan 18 22:06:18 volumio kernel: sd 0:0:0:0: [sda] Synchronize Cache(10) failed: Result: hostbyte=0x01 driverbyte=DRIVER_OK Jan 18 22:06:18 volumio ntfs-3g[1350]: Unmounting /dev/sda2 (NAS-4T) Jan 18 22:06:18 volumio ntfs-3g[1350]: Failed to sync device /dev/sda2: Input/output error Jan 18 22:06:18 volumio ntfs-3g[1350]: Failed to close volume /dev/sda2: Input/output error Jan 18 22:06:22 volumio volumio[853]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/EFI Jan 18 22:06:23 volumio volumio[853]: info: Scanning removed location : "USB/EFI" Jan 18 22:06:23 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand status Jan 18 22:06:23 volumio volumio[853]: info: Jan 18 22:06:23 volumio volumio[853]: ---------------------------- MPD announces state update: update Jan 18 22:06:23 volumio volumio[853]: info: ControllerMpd::getState Jan 18 22:06:23 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand status Jan 18 22:06:23 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand status Jan 18 22:06:23 volumio volumio[853]: info: Jan 18 22:06:23 volumio volumio[853]: ---------------------------- MPD announces state update: update Jan 18 22:06:23 volumio volumio[853]: info: ControllerMpd::getState Jan 18 22:06:23 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand status Jan 18 22:06:23 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand status Jan 18 22:06:23 volumio volumio[853]: info: Jan 18 22:06:23 volumio volumio[853]: ---------------------------- MPD announces state update: update Jan 18 22:06:23 volumio volumio[853]: info: ControllerMpd::getState Jan 18 22:06:23 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand status Jan 18 22:06:23 volumio volumio[853]: info: sendMpdCommand status took 6 milliseconds Jan 18 22:06:23 volumio volumio[853]: info: sendMpdCommand status took 6 milliseconds Jan 18 22:06:23 volumio volumio[853]: info: sendMpdCommand status took 5 milliseconds Jan 18 22:06:23 volumio volumio[853]: info: sendMpdCommand status took 4 milliseconds Jan 18 22:06:23 volumio volumio[853]: info: sendMpdCommand status took 4 milliseconds Jan 18 22:06:23 volumio volumio[853]: info: sendMpdCommand status took 4 milliseconds Jan 18 22:06:23 volumio volumio[853]: verbose: ControllerMpd::parseState Jan 18 22:06:23 volumio volumio[853]: info: Command Router : Notfying DB Updatetrue Jan 18 22:06:23 volumio volumio[853]: info: CoreCommandRouter::Close All Modals sent Jan 18 22:06:23 volumio volumio[853]: verbose: ControllerMpd::parseState Jan 18 22:06:23 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 18 22:06:23 volumio volumio[853]: verbose: ControllerMpd::parseState Jan 18 22:06:23 volumio volumio[853]: info: Command Router : Notfying DB Updatetrue Jan 18 22:06:23 volumio volumio[853]: info: CoreCommandRouter::Close All Modals sent Jan 18 22:06:23 volumio volumio[853]: verbose: ControllerMpd::parseState Jan 18 22:06:23 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 18 22:06:23 volumio volumio[853]: verbose: ControllerMpd::parseState Jan 18 22:06:23 volumio volumio[853]: info: Command Router : Notfying DB Updatetrue Jan 18 22:06:23 volumio volumio[853]: info: CoreCommandRouter::Close All Modals sent Jan 18 22:06:23 volumio volumio[853]: verbose: ControllerMpd::parseState Jan 18 22:06:23 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 18 22:06:23 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand status Jan 18 22:06:23 volumio volumio[853]: info: Jan 18 22:06:23 volumio volumio[853]: ---------------------------- MPD announces state update: update Jan 18 22:06:23 volumio volumio[853]: info: ControllerMpd::getState Jan 18 22:06:23 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand status Jan 18 22:06:23 volumio volumio[853]: info: sendMpdCommand playlistinfo took 102 milliseconds Jan 18 22:06:23 volumio volumio[853]: info: sendMpdCommand playlistinfo took 56 milliseconds Jan 18 22:06:23 volumio volumio[853]: info: sendMpdCommand playlistinfo took 10 milliseconds Jan 18 22:06:23 volumio volumio[853]: verbose: ControllerMpd::parseTrackInfo Jan 18 22:06:23 volumio volumio[853]: verbose: ControllerMpd::parseTrackInfo Jan 18 22:06:23 volumio volumio[853]: verbose: ControllerMpd::parseTrackInfo Jan 18 22:06:23 volumio volumio[853]: info: ControllerMpd::pushState Jan 18 22:06:23 volumio volumio[853]: info: CoreCommandRouter::servicePushState Jan 18 22:06:23 volumio volumio[853]: info: CorePlayQueue::getTrack 63 Jan 18 22:06:23 volumio volumio[853]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":12858,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??-????","artist":"??","album":"????","uri":"USB/NAS-4T/选曲/选曲/14. 车站－女声测试.wav","trackType":"wav"} Jan 18 22:06:23 volumio volumio[853]: verbose: CURRENT POSITION 63 Jan 18 22:06:23 volumio volumio[853]: info: CoreStateMachine::syncState stateService pause Jan 18 22:06:23 volumio volumio[853]: info: CoreStateMachine::syncState currentStatus pause Jan 18 22:06:23 volumio volumio[853]: info: CoreStateMachine::pushState Jan 18 22:06:23 volumio volumio[853]: info: CorePlayQueue::getTrack 63 Jan 18 22:06:23 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 18 22:06:23 volumio volumio[853]: info: CoreCommandRouter::volumioPushState Jan 18 22:06:23 volumio volumio[853]: info: CoreStateMachine::stPlaybackTimer Jan 18 22:06:23 volumio volumio[853]: info: ControllerMpd::pushState Jan 18 22:06:23 volumio volumio[853]: info: CoreCommandRouter::servicePushState Jan 18 22:06:23 volumio volumio[853]: info: CorePlayQueue::getTrack 63 Jan 18 22:06:23 volumio volumio[853]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":12858,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??-????","artist":"??","album":"????","uri":"USB/NAS-4T/选曲/选曲/14. 车站－女声测试.wav","trackType":"wav"} Jan 18 22:06:23 volumio volumio[853]: verbose: CURRENT POSITION 63 Jan 18 22:06:23 volumio volumio[853]: info: CoreStateMachine::syncState stateService pause Jan 18 22:06:23 volumio volumio[853]: info: CoreStateMachine::syncState currentStatus pause Jan 18 22:06:23 volumio volumio[853]: info: CoreStateMachine::pushState Jan 18 22:06:23 volumio volumio[853]: info: CorePlayQueue::getTrack 63 Jan 18 22:06:23 volumio volumio[853]: info: CoreCommandRouter::volumioPushState Jan 18 22:06:23 volumio volumio[853]: info: CoreStateMachine::stPlaybackTimer Jan 18 22:06:23 volumio volumio[853]: info: ControllerMpd::pushState Jan 18 22:06:23 volumio volumio[853]: info: CoreCommandRouter::servicePushState Jan 18 22:06:23 volumio volumio[853]: info: CorePlayQueue::getTrack 63 Jan 18 22:06:23 volumio volumio[853]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":12858,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??-????","artist":"??","album":"????","uri":"USB/NAS-4T/选曲/选曲/14. 车站－女声测试.wav","trackType":"wav"} Jan 18 22:06:23 volumio volumio[853]: verbose: CURRENT POSITION 63 Jan 18 22:06:23 volumio volumio[853]: info: CoreStateMachine::syncState stateService pause Jan 18 22:06:23 volumio volumio[853]: info: CoreStateMachine::syncState currentStatus pause Jan 18 22:06:23 volumio volumio[853]: info: CoreStateMachine::pushState Jan 18 22:06:23 volumio volumio[853]: info: CorePlayQueue::getTrack 63 Jan 18 22:06:23 volumio volumio[853]: info: CoreCommandRouter::volumioPushState Jan 18 22:06:23 volumio volumio[853]: info: CoreStateMachine::stPlaybackTimer Jan 18 22:06:23 volumio volumio[853]: info: ------------------------------ 186ms Jan 18 22:06:23 volumio volumio[853]: info: ------------------------------ 185ms Jan 18 22:06:23 volumio volumio[853]: info: ------------------------------ 183ms Jan 18 22:06:23 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand status Jan 18 22:06:23 volumio volumio[853]: info: Jan 18 22:06:23 volumio volumio[853]: ---------------------------- MPD announces state update: update Jan 18 22:06:23 volumio volumio[853]: info: ControllerMpd::getState Jan 18 22:06:23 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand status Jan 18 22:06:23 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand status Jan 18 22:06:23 volumio volumio[853]: info: Jan 18 22:06:23 volumio volumio[853]: ---------------------------- MPD announces state update: update Jan 18 22:06:23 volumio volumio[853]: info: ControllerMpd::getState Jan 18 22:06:23 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand status Jan 18 22:06:23 volumio volumio[853]: info: sendMpdCommand status took 45 milliseconds Jan 18 22:06:23 volumio volumio[853]: info: sendMpdCommand status took 43 milliseconds Jan 18 22:06:23 volumio volumio[853]: info: sendMpdCommand status took 21 milliseconds Jan 18 22:06:23 volumio volumio[853]: info: sendMpdCommand status took 20 milliseconds Jan 18 22:06:23 volumio volumio[853]: info: sendMpdCommand status took 19 milliseconds Jan 18 22:06:23 volumio volumio[853]: info: sendMpdCommand status took 18 milliseconds Jan 18 22:06:23 volumio volumio[853]: verbose: ControllerMpd::parseState Jan 18 22:06:23 volumio volumio[853]: info: Command Router : Notfying DB Updatefalse Jan 18 22:06:23 volumio volumio[853]: info: CoreCommandRouter::Close All Modals sent Jan 18 22:06:23 volumio volumio[853]: verbose: ControllerMpd::parseState Jan 18 22:06:23 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 18 22:06:23 volumio volumio[853]: verbose: ControllerMpd::parseState Jan 18 22:06:23 volumio volumio[853]: info: Command Router : Notfying DB Updatefalse Jan 18 22:06:23 volumio volumio[853]: info: CoreCommandRouter::Close All Modals sent Jan 18 22:06:23 volumio volumio[853]: verbose: ControllerMpd::parseState Jan 18 22:06:23 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 18 22:06:23 volumio volumio[853]: verbose: ControllerMpd::parseState Jan 18 22:06:23 volumio volumio[853]: info: Command Router : Notfying DB Updatefalse Jan 18 22:06:23 volumio volumio[853]: info: CoreCommandRouter::Close All Modals sent Jan 18 22:06:23 volumio volumio[853]: verbose: ControllerMpd::parseState Jan 18 22:06:23 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 18 22:06:23 volumio volumio[853]: info: sendMpdCommand playlistinfo took 103 milliseconds Jan 18 22:06:23 volumio volumio[853]: info: sendMpdCommand playlistinfo took 58 milliseconds Jan 18 22:06:23 volumio volumio[853]: info: sendMpdCommand playlistinfo took 11 milliseconds Jan 18 22:06:23 volumio volumio[853]: verbose: ControllerMpd::parseTrackInfo Jan 18 22:06:23 volumio volumio[853]: verbose: ControllerMpd::parseTrackInfo Jan 18 22:06:23 volumio volumio[853]: verbose: ControllerMpd::parseTrackInfo Jan 18 22:06:23 volumio volumio[853]: info: ControllerMpd::pushState Jan 18 22:06:23 volumio volumio[853]: info: CoreCommandRouter::servicePushState Jan 18 22:06:23 volumio volumio[853]: info: CorePlayQueue::getTrack 63 Jan 18 22:06:23 volumio volumio[853]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":12858,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??-????","artist":"??","album":"????","uri":"USB/NAS-4T/选曲/选曲/14. 车站－女声测试.wav","trackType":"wav"} Jan 18 22:06:23 volumio volumio[853]: verbose: CURRENT POSITION 63 Jan 18 22:06:23 volumio volumio[853]: info: CoreStateMachine::syncState stateService pause Jan 18 22:06:23 volumio volumio[853]: info: CoreStateMachine::syncState currentStatus pause Jan 18 22:06:23 volumio volumio[853]: info: CoreStateMachine::pushState Jan 18 22:06:23 volumio volumio[853]: info: CorePlayQueue::getTrack 63 Jan 18 22:06:23 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 18 22:06:23 volumio volumio[853]: info: CoreCommandRouter::volumioPushState Jan 18 22:06:23 volumio volumio[853]: info: CoreStateMachine::stPlaybackTimer Jan 18 22:06:23 volumio volumio[853]: info: ControllerMpd::pushState Jan 18 22:06:23 volumio volumio[853]: info: CoreCommandRouter::servicePushState Jan 18 22:06:23 volumio volumio[853]: info: CorePlayQueue::getTrack 63 Jan 18 22:06:23 volumio volumio[853]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":12858,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??-????","artist":"??","album":"????","uri":"USB/NAS-4T/选曲/选曲/14. 车站－女声测试.wav","trackType":"wav"} Jan 18 22:06:23 volumio volumio[853]: verbose: CURRENT POSITION 63 Jan 18 22:06:23 volumio volumio[853]: info: CoreStateMachine::syncState stateService pause Jan 18 22:06:23 volumio volumio[853]: info: CoreStateMachine::syncState currentStatus pause Jan 18 22:06:23 volumio volumio[853]: info: CoreStateMachine::pushState Jan 18 22:06:23 volumio volumio[853]: info: CorePlayQueue::getTrack 63 Jan 18 22:06:23 volumio volumio[853]: info: CoreCommandRouter::volumioPushState Jan 18 22:06:23 volumio volumio[853]: info: CoreStateMachine::stPlaybackTimer Jan 18 22:06:23 volumio volumio[853]: info: ControllerMpd::pushState Jan 18 22:06:23 volumio volumio[853]: info: CoreCommandRouter::servicePushState Jan 18 22:06:23 volumio volumio[853]: info: CorePlayQueue::getTrack 63 Jan 18 22:06:23 volumio volumio[853]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":12858,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??-????","artist":"??","album":"????","uri":"USB/NAS-4T/选曲/选曲/14. 车站－女声测试.wav","trackType":"wav"} Jan 18 22:06:23 volumio volumio[853]: verbose: CURRENT POSITION 63 Jan 18 22:06:23 volumio volumio[853]: info: CoreStateMachine::syncState stateService pause Jan 18 22:06:23 volumio volumio[853]: info: CoreStateMachine::syncState currentStatus pause Jan 18 22:06:23 volumio volumio[853]: info: CoreStateMachine::pushState Jan 18 22:06:23 volumio volumio[853]: info: CorePlayQueue::getTrack 63 Jan 18 22:06:23 volumio volumio[853]: info: CoreCommandRouter::volumioPushState Jan 18 22:06:23 volumio volumio[853]: info: CoreStateMachine::stPlaybackTimer Jan 18 22:06:23 volumio volumio[853]: info: ------------------------------ 217ms Jan 18 22:06:23 volumio volumio[853]: info: ------------------------------ 193ms Jan 18 22:06:23 volumio volumio[853]: info: ------------------------------ 192ms Jan 18 22:06:23 volumio volumio[853]: info: Scanning removed location : "USB/NAS-4T" Jan 18 22:06:23 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand status Jan 18 22:06:23 volumio volumio[853]: info: Jan 18 22:06:23 volumio volumio[853]: ---------------------------- MPD announces state update: update Jan 18 22:06:23 volumio volumio[853]: info: ControllerMpd::getState Jan 18 22:06:23 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand status Jan 18 22:06:23 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand status Jan 18 22:06:23 volumio volumio[853]: info: Jan 18 22:06:23 volumio volumio[853]: ---------------------------- MPD announces state update: update Jan 18 22:06:23 volumio volumio[853]: info: ControllerMpd::getState Jan 18 22:06:23 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand status Jan 18 22:06:23 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand status Jan 18 22:06:23 volumio volumio[853]: info: Jan 18 22:06:23 volumio volumio[853]: ---------------------------- MPD announces state update: update Jan 18 22:06:23 volumio volumio[853]: info: ControllerMpd::getState Jan 18 22:06:23 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand status Jan 18 22:06:25 volumio volumio[853]: info: MPD Database updated - AlbumList cache refreshed Jan 18 22:06:25 volumio volumio[853]: info: Jan 18 22:06:25 volumio volumio[853]: ---------------------------- MPD announces state update: database Jan 18 22:06:25 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand status Jan 18 22:06:25 volumio volumio[853]: info: Jan 18 22:06:25 volumio volumio[853]: ---------------------------- MPD announces state update: update Jan 18 22:06:25 volumio volumio[853]: info: ControllerMpd::getState Jan 18 22:06:25 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand status Jan 18 22:06:25 volumio volumio[853]: info: ControllerMpd::getState Jan 18 22:06:25 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand status Jan 18 22:06:25 volumio volumio[853]: info: MPD Database updated - AlbumList cache refreshed Jan 18 22:06:25 volumio volumio[853]: info: Jan 18 22:06:25 volumio volumio[853]: ---------------------------- MPD announces state update: database Jan 18 22:06:25 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand status Jan 18 22:06:25 volumio volumio[853]: info: Jan 18 22:06:25 volumio volumio[853]: ---------------------------- MPD announces state update: update Jan 18 22:06:25 volumio volumio[853]: info: ControllerMpd::getState Jan 18 22:06:25 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand status Jan 18 22:06:25 volumio volumio[853]: info: ControllerMpd::getState Jan 18 22:06:25 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand status Jan 18 22:06:25 volumio volumio[853]: info: MPD Database updated - AlbumList cache refreshed Jan 18 22:06:25 volumio volumio[853]: info: Jan 18 22:06:25 volumio volumio[853]: ---------------------------- MPD announces state update: database Jan 18 22:06:25 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand status Jan 18 22:06:25 volumio volumio[853]: info: Jan 18 22:06:25 volumio volumio[853]: ---------------------------- MPD announces state update: update Jan 18 22:06:25 volumio volumio[853]: info: ControllerMpd::getState Jan 18 22:06:25 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand status Jan 18 22:06:25 volumio volumio[853]: info: ControllerMpd::getState Jan 18 22:06:25 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand status Jan 18 22:06:25 volumio volumio[853]: info: sendMpdCommand status took 2201 milliseconds Jan 18 22:06:25 volumio volumio[853]: info: sendMpdCommand status took 2200 milliseconds Jan 18 22:06:25 volumio volumio[853]: info: sendMpdCommand status took 2199 milliseconds Jan 18 22:06:25 volumio volumio[853]: info: sendMpdCommand status took 2199 milliseconds Jan 18 22:06:25 volumio volumio[853]: info: sendMpdCommand status took 2198 milliseconds Jan 18 22:06:25 volumio volumio[853]: info: sendMpdCommand status took 2197 milliseconds Jan 18 22:06:25 volumio volumio[853]: info: sendMpdCommand status took 31 milliseconds Jan 18 22:06:25 volumio volumio[853]: info: sendMpdCommand status took 28 milliseconds Jan 18 22:06:25 volumio volumio[853]: info: sendMpdCommand status took 28 milliseconds Jan 18 22:06:25 volumio volumio[853]: info: sendMpdCommand status took 26 milliseconds Jan 18 22:06:25 volumio volumio[853]: info: sendMpdCommand status took 26 milliseconds Jan 18 22:06:25 volumio volumio[853]: info: sendMpdCommand status took 25 milliseconds Jan 18 22:06:25 volumio volumio[853]: verbose: ControllerMpd::parseState Jan 18 22:06:25 volumio volumio[853]: info: Command Router : Notfying DB Updatefalse Jan 18 22:06:25 volumio volumio[853]: info: CoreCommandRouter::Close All Modals sent Jan 18 22:06:25 volumio volumio[853]: verbose: ControllerMpd::parseState Jan 18 22:06:25 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 18 22:06:25 volumio volumio[853]: verbose: ControllerMpd::parseState Jan 18 22:06:26 volumio volumio[853]: info: Command Router : Notfying DB Updatefalse Jan 18 22:06:26 volumio volumio[853]: info: CoreCommandRouter::Close All Modals sent Jan 18 22:06:26 volumio volumio[853]: verbose: ControllerMpd::parseState Jan 18 22:06:26 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 18 22:06:26 volumio volumio[853]: verbose: ControllerMpd::parseState Jan 18 22:06:26 volumio volumio[853]: info: Command Router : Notfying DB Updatefalse Jan 18 22:06:26 volumio volumio[853]: info: CoreCommandRouter::Close All Modals sent Jan 18 22:06:26 volumio volumio[853]: verbose: ControllerMpd::parseState Jan 18 22:06:26 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 18 22:06:26 volumio volumio[853]: verbose: ControllerMpd::parseState Jan 18 22:06:26 volumio volumio[853]: info: Command Router : Notfying DB Updatefalse Jan 18 22:06:26 volumio volumio[853]: info: CoreCommandRouter::Close All Modals sent Jan 18 22:06:26 volumio volumio[853]: verbose: ControllerMpd::parseState Jan 18 22:06:26 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 18 22:06:26 volumio volumio[853]: verbose: ControllerMpd::parseState Jan 18 22:06:26 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 18 22:06:26 volumio volumio[853]: verbose: ControllerMpd::parseState Jan 18 22:06:26 volumio volumio[853]: info: Command Router : Notfying DB Updatefalse Jan 18 22:06:26 volumio volumio[853]: info: CoreCommandRouter::Close All Modals sent Jan 18 22:06:26 volumio volumio[853]: verbose: ControllerMpd::parseState Jan 18 22:06:26 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 18 22:06:26 volumio volumio[853]: verbose: ControllerMpd::parseState Jan 18 22:06:26 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 18 22:06:26 volumio volumio[853]: info: sendMpdCommand status took 286 milliseconds Jan 18 22:06:26 volumio volumio[853]: info: sendMpdCommand status took 270 milliseconds Jan 18 22:06:26 volumio volumio[853]: info: sendMpdCommand status took 269 milliseconds Jan 18 22:06:26 volumio volumio[853]: info: sendMpdCommand playlistinfo took 202 milliseconds Jan 18 22:06:26 volumio volumio[853]: info: sendMpdCommand playlistinfo took 156 milliseconds Jan 18 22:06:26 volumio volumio[853]: info: sendMpdCommand playlistinfo took 110 milliseconds Jan 18 22:06:26 volumio volumio[853]: info: sendMpdCommand playlistinfo took 64 milliseconds Jan 18 22:06:26 volumio volumio[853]: info: sendMpdCommand playlistinfo took 64 milliseconds Jan 18 22:06:26 volumio volumio[853]: info: sendMpdCommand playlistinfo took 18 milliseconds Jan 18 22:06:26 volumio volumio[853]: info: sendMpdCommand playlistinfo took 17 milliseconds Jan 18 22:06:26 volumio volumio[853]: verbose: ControllerMpd::parseState Jan 18 22:06:26 volumio volumio[853]: info: Command Router : Notfying DB Updatefalse Jan 18 22:06:26 volumio volumio[853]: info: CoreCommandRouter::Close All Modals sent Jan 18 22:06:26 volumio volumio[853]: verbose: ControllerMpd::parseState Jan 18 22:06:26 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 18 22:06:26 volumio volumio[853]: verbose: ControllerMpd::parseState Jan 18 22:06:26 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 18 22:06:26 volumio volumio[853]: verbose: ControllerMpd::parseTrackInfo Jan 18 22:06:26 volumio volumio[853]: verbose: ControllerMpd::parseTrackInfo Jan 18 22:06:26 volumio volumio[853]: verbose: ControllerMpd::parseTrackInfo Jan 18 22:06:26 volumio volumio[853]: verbose: ControllerMpd::parseTrackInfo Jan 18 22:06:26 volumio volumio[853]: verbose: ControllerMpd::parseTrackInfo Jan 18 22:06:26 volumio volumio[853]: verbose: ControllerMpd::parseTrackInfo Jan 18 22:06:26 volumio volumio[853]: verbose: ControllerMpd::parseTrackInfo Jan 18 22:06:26 volumio volumio[853]: info: ControllerMpd::pushState Jan 18 22:06:26 volumio volumio[853]: info: CoreCommandRouter::servicePushState Jan 18 22:06:26 volumio volumio[853]: info: CorePlayQueue::getTrack 63 Jan 18 22:06:26 volumio volumio[853]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":12858,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??-????","artist":"??","album":"????","uri":"USB/NAS-4T/选曲/选曲/14. 车站－女声测试.wav","trackType":"wav"} Jan 18 22:06:26 volumio volumio[853]: verbose: CURRENT POSITION 63 Jan 18 22:06:26 volumio volumio[853]: info: CoreStateMachine::syncState stateService pause Jan 18 22:06:26 volumio volumio[853]: info: CoreStateMachine::syncState currentStatus pause Jan 18 22:06:26 volumio volumio[853]: info: CoreStateMachine::pushState Jan 18 22:06:26 volumio volumio[853]: info: CorePlayQueue::getTrack 63 Jan 18 22:06:26 volumio volumio[853]: info: CoreCommandRouter::volumioPushState Jan 18 22:06:26 volumio volumio[853]: info: CoreStateMachine::stPlaybackTimer Jan 18 22:06:26 volumio volumio[853]: info: ControllerMpd::pushState Jan 18 22:06:26 volumio volumio[853]: info: CoreCommandRouter::servicePushState Jan 18 22:06:26 volumio volumio[853]: info: CorePlayQueue::getTrack 63 Jan 18 22:06:26 volumio volumio[853]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":12858,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??-????","artist":"??","album":"????","uri":"USB/NAS-4T/选曲/选曲/14. 车站－女声测试.wav","trackType":"wav"} Jan 18 22:06:26 volumio volumio[853]: verbose: CURRENT POSITION 63 Jan 18 22:06:26 volumio volumio[853]: info: CoreStateMachine::syncState stateService pause Jan 18 22:06:26 volumio volumio[853]: info: CoreStateMachine::syncState currentStatus pause Jan 18 22:06:26 volumio volumio[853]: info: CoreStateMachine::pushState Jan 18 22:06:26 volumio volumio[853]: info: CorePlayQueue::getTrack 63 Jan 18 22:06:26 volumio volumio[853]: info: CoreCommandRouter::volumioPushState Jan 18 22:06:26 volumio volumio[853]: info: CoreStateMachine::stPlaybackTimer Jan 18 22:06:26 volumio volumio[853]: info: ControllerMpd::pushState Jan 18 22:06:26 volumio volumio[853]: info: CoreCommandRouter::servicePushState Jan 18 22:06:26 volumio volumio[853]: info: CorePlayQueue::getTrack 63 Jan 18 22:06:26 volumio volumio[853]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":12858,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??-????","artist":"??","album":"????","uri":"USB/NAS-4T/选曲/选曲/14. 车站－女声测试.wav","trackType":"wav"} Jan 18 22:06:26 volumio volumio[853]: verbose: CURRENT POSITION 63 Jan 18 22:06:26 volumio volumio[853]: info: CoreStateMachine::syncState stateService pause Jan 18 22:06:26 volumio volumio[853]: info: CoreStateMachine::syncState currentStatus pause Jan 18 22:06:26 volumio volumio[853]: info: CoreStateMachine::pushState Jan 18 22:06:26 volumio volumio[853]: info: CorePlayQueue::getTrack 63 Jan 18 22:06:26 volumio volumio[853]: info: CoreCommandRouter::volumioPushState Jan 18 22:06:26 volumio volumio[853]: info: CoreStateMachine::stPlaybackTimer Jan 18 22:06:26 volumio volumio[853]: info: ControllerMpd::pushState Jan 18 22:06:26 volumio volumio[853]: info: CoreCommandRouter::servicePushState Jan 18 22:06:26 volumio volumio[853]: info: CorePlayQueue::getTrack 63 Jan 18 22:06:26 volumio volumio[853]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":12858,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??-????","artist":"??","album":"????","uri":"USB/NAS-4T/选曲/选曲/14. 车站－女声测试.wav","trackType":"wav"} Jan 18 22:06:26 volumio volumio[853]: verbose: CURRENT POSITION 63 Jan 18 22:06:26 volumio volumio[853]: info: CoreStateMachine::syncState stateService pause Jan 18 22:06:26 volumio volumio[853]: info: CoreStateMachine::syncState currentStatus pause Jan 18 22:06:26 volumio volumio[853]: info: CoreStateMachine::pushState Jan 18 22:06:26 volumio volumio[853]: info: CorePlayQueue::getTrack 63 Jan 18 22:06:26 volumio volumio[853]: info: CoreCommandRouter::volumioPushState Jan 18 22:06:26 volumio volumio[853]: info: CoreStateMachine::stPlaybackTimer Jan 18 22:06:26 volumio volumio[853]: info: ControllerMpd::pushState Jan 18 22:06:26 volumio volumio[853]: info: CoreCommandRouter::servicePushState Jan 18 22:06:26 volumio volumio[853]: info: CorePlayQueue::getTrack 63 Jan 18 22:06:26 volumio volumio[853]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":12858,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??-????","artist":"??","album":"????","uri":"USB/NAS-4T/选曲/选曲/14. 车站－女声测试.wav","trackType":"wav"} Jan 18 22:06:26 volumio volumio[853]: verbose: CURRENT POSITION 63 Jan 18 22:06:26 volumio volumio[853]: info: CoreStateMachine::syncState stateService pause Jan 18 22:06:26 volumio volumio[853]: info: CoreStateMachine::syncState currentStatus pause Jan 18 22:06:26 volumio volumio[853]: info: CoreStateMachine::pushState Jan 18 22:06:26 volumio volumio[853]: info: CorePlayQueue::getTrack 63 Jan 18 22:06:26 volumio volumio[853]: info: CoreCommandRouter::volumioPushState Jan 18 22:06:26 volumio volumio[853]: info: CoreStateMachine::stPlaybackTimer Jan 18 22:06:26 volumio volumio[853]: info: ControllerMpd::pushState Jan 18 22:06:26 volumio volumio[853]: info: CoreCommandRouter::servicePushState Jan 18 22:06:26 volumio volumio[853]: info: CorePlayQueue::getTrack 63 Jan 18 22:06:26 volumio volumio[853]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":12858,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??-????","artist":"??","album":"????","uri":"USB/NAS-4T/选曲/选曲/14. 车站－女声测试.wav","trackType":"wav"} Jan 18 22:06:26 volumio volumio[853]: verbose: CURRENT POSITION 63 Jan 18 22:06:26 volumio volumio[853]: info: CoreStateMachine::syncState stateService pause Jan 18 22:06:26 volumio volumio[853]: info: CoreStateMachine::syncState currentStatus pause Jan 18 22:06:26 volumio volumio[853]: info: CoreStateMachine::pushState Jan 18 22:06:26 volumio volumio[853]: info: CorePlayQueue::getTrack 63 Jan 18 22:06:26 volumio volumio[853]: info: CoreCommandRouter::volumioPushState Jan 18 22:06:26 volumio volumio[853]: info: CoreStateMachine::stPlaybackTimer Jan 18 22:06:26 volumio volumio[853]: info: ControllerMpd::pushState Jan 18 22:06:26 volumio volumio[853]: info: CoreCommandRouter::servicePushState Jan 18 22:06:26 volumio volumio[853]: info: CorePlayQueue::getTrack 63 Jan 18 22:06:26 volumio volumio[853]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":12858,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??-????","artist":"??","album":"????","uri":"USB/NAS-4T/选曲/选曲/14. 车站－女声测试.wav","trackType":"wav"} Jan 18 22:06:26 volumio volumio[853]: verbose: CURRENT POSITION 63 Jan 18 22:06:26 volumio volumio[853]: info: CoreStateMachine::syncState stateService pause Jan 18 22:06:26 volumio volumio[853]: info: CoreStateMachine::syncState currentStatus pause Jan 18 22:06:26 volumio volumio[853]: info: CoreStateMachine::pushState Jan 18 22:06:26 volumio volumio[853]: info: CorePlayQueue::getTrack 63 Jan 18 22:06:26 volumio volumio[853]: info: CoreCommandRouter::volumioPushState Jan 18 22:06:26 volumio volumio[853]: info: CoreStateMachine::stPlaybackTimer Jan 18 22:06:26 volumio volumio[853]: info: ------------------------------ 2574ms Jan 18 22:06:26 volumio volumio[853]: info: ------------------------------ 2572ms Jan 18 22:06:26 volumio volumio[853]: info: ------------------------------ 2571ms Jan 18 22:06:26 volumio volumio[853]: info: ------------------------------ 404ms Jan 18 22:06:26 volumio volumio[853]: info: ------------------------------ 404ms Jan 18 22:06:26 volumio volumio[853]: info: ------------------------------ 399ms Jan 18 22:06:26 volumio volumio[853]: info: ------------------------------ 400ms Jan 18 22:06:26 volumio volumio[853]: info: sendMpdCommand playlistinfo took 72 milliseconds Jan 18 22:06:26 volumio volumio[853]: info: sendMpdCommand playlistinfo took 73 milliseconds Jan 18 22:06:26 volumio volumio[853]: verbose: ControllerMpd::parseTrackInfo Jan 18 22:06:26 volumio volumio[853]: verbose: ControllerMpd::parseTrackInfo Jan 18 22:06:26 volumio volumio[853]: info: ControllerMpd::pushState Jan 18 22:06:26 volumio volumio[853]: info: CoreCommandRouter::servicePushState Jan 18 22:06:26 volumio volumio[853]: info: CorePlayQueue::getTrack 63 Jan 18 22:06:26 volumio volumio[853]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":12858,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??-????","artist":"??","album":"????","uri":"USB/NAS-4T/选曲/选曲/14. 车站－女声测试.wav","trackType":"wav"} Jan 18 22:06:26 volumio volumio[853]: verbose: CURRENT POSITION 63 Jan 18 22:06:26 volumio volumio[853]: info: CoreStateMachine::syncState stateService pause Jan 18 22:06:26 volumio volumio[853]: info: CoreStateMachine::syncState currentStatus pause Jan 18 22:06:26 volumio volumio[853]: info: CoreStateMachine::pushState Jan 18 22:06:26 volumio volumio[853]: info: CorePlayQueue::getTrack 63 Jan 18 22:06:26 volumio volumio[853]: info: CoreCommandRouter::volumioPushState Jan 18 22:06:26 volumio volumio[853]: info: CoreStateMachine::stPlaybackTimer Jan 18 22:06:26 volumio volumio[853]: info: ControllerMpd::pushState Jan 18 22:06:26 volumio volumio[853]: info: CoreCommandRouter::servicePushState Jan 18 22:06:26 volumio volumio[853]: info: CorePlayQueue::getTrack 63 Jan 18 22:06:26 volumio volumio[853]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":12858,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??-????","artist":"??","album":"????","uri":"USB/NAS-4T/选曲/选曲/14. 车站－女声测试.wav","trackType":"wav"} Jan 18 22:06:26 volumio volumio[853]: verbose: CURRENT POSITION 63 Jan 18 22:06:26 volumio volumio[853]: info: CoreStateMachine::syncState stateService pause Jan 18 22:06:26 volumio volumio[853]: info: CoreStateMachine::syncState currentStatus pause Jan 18 22:06:26 volumio volumio[853]: info: CoreStateMachine::pushState Jan 18 22:06:26 volumio volumio[853]: info: CorePlayQueue::getTrack 63 Jan 18 22:06:26 volumio volumio[853]: info: CoreCommandRouter::volumioPushState Jan 18 22:06:26 volumio volumio[853]: info: CoreStateMachine::stPlaybackTimer Jan 18 22:06:26 volumio volumio[853]: info: ------------------------------ 432ms Jan 18 22:06:26 volumio volumio[853]: info: ------------------------------ 433ms Jan 18 22:06:30 volumio kernel: hwmon hwmon1: Voltage normalised Jan 18 22:07:54 volumio volumio[853]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 18 22:07:54 volumio volumio[853]: Error: connect ETIMEDOUT 154.85.102.32:443 Jan 18 22:07:54 volumio volumio[853]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Jan 18 22:07:54 volumio volumio[853]: errno: -110, Jan 18 22:07:54 volumio volumio[853]: code: 'ETIMEDOUT', Jan 18 22:07:54 volumio volumio[853]: syscall: 'connect', Jan 18 22:07:54 volumio volumio[853]: address: '154.85.102.32', Jan 18 22:07:54 volumio volumio[853]: port: 443 Jan 18 22:07:54 volumio volumio[853]: } Jan 18 22:07:54 volumio volumio[853]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 18 22:07:55 volumio sudo[15132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-18 22:06 Jan 18 22:07:55 volumio sudo[15132]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"