-- Logs begin at Thu 2019-02-14 18:11:58 HKT, end at Sun 2025-02-23 16:36:46 HKT. -- Feb 23 16:35:22 volumio kernel: usb 2-1: USB disconnect, device number 2 Feb 23 16:35:23 volumio kernel: sd 1:0:0:0: [sdb] Synchronizing SCSI cache Feb 23 16:35:23 volumio kernel: sd 1:0:0:0: [sdb] Synchronize Cache(10) failed: Result: hostbyte=0x07 driverbyte=DRIVER_OK Feb 23 16:35:22 volumio sudo[3866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sdb1 Feb 23 16:35:23 volumio volumio[2649]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb1","ACTION":"remove","DEVLINKS":"/dev/disk/by-path/platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1:1.0-scsi-0:0:0:0-part1 /dev/disk/by-id/wwn-0x5000c50033062a2f-part1 /dev/disk/by-id/ata-ST9500325AS_6VEN9Q5L-part1 /dev/disk/by-label/新加卷 /dev/disk/by-partuuid/13ac16bf-01 /dev/disk/by-uuid/04868005867FF590","DEVNAME":"/dev/sdb1","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb1","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":"128","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_SECURITY":"1","ID_ATA_FEATURE_SET_SECURITY_ENABLED":"0","ID_ATA_FEATURE_SET_SECURITY_ENHANCED_ERASE_UNIT_MIN":"140","ID_ATA_FEATURE_SET_SECURITY_ERASE_UNIT_MIN":"140","ID_ATA_FEATURE_SET_SMART":"1","ID_ATA_FEATURE_SET_SMART_ENABLED":"1","ID_ATA_ROTATION_RATE_RPM":"5400","ID_ATA_SATA":"1","ID_ATA_SATA_SIGNAL_RATE_GEN1":"1","ID_ATA_WRITE_CACHE":"1","ID_ATA_WRITE_CACHE_ENABLED":"1","ID_BUS":"ata","ID_FS_LABEL":"新加卷","ID_FS_LABEL_ENC":"新加卷","ID_FS_TYPE":"ntfs","ID_FS_USAGE":"filesystem","ID_FS_UUID":"04868005867FF590","ID_FS_UUID_ENC":"04868005867FF590","ID_MODEL":"ST9500325AS","ID_MODEL_ENC":"ST9500325AS\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\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:16","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"2048","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"485246976","ID_PART_ENTRY_TYPE":"0x7","ID_PART_ENTRY_UUID":"13ac16bf-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"13ac16bf","ID_PATH":"platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-fd500000_pcie-pci-0000_01_00_0-usb-0_1_1_0-scsi-0_0_0_0","ID_REVISION":"0002BSM1","ID_SERIAL":"ST9500325AS_6VEN9Q5L","ID_SERIAL_SHORT":"6VEN9Q5L","ID_TYPE":"disk","ID_WWN":"0x5000c50033062a2f","ID_WWN_WITH_EXTENSION":"0x5000c50033062a2f","MAJOR":"8","MINOR":"17","PARTN":"1","SEQNUM":"2638","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"56721311"} Feb 23 16:35:23 volumio volumio[2649]: umount: /dev/sdb1: umount failed: Invalid argument. Feb 23 16:35:23 volumio volumio[2649]: error: Failed to umount 新加卷: Error: Command failed: /usr/bin/sudo /bin/umount -f "/dev/sdb1" Feb 23 16:35:23 volumio volumio[2649]: umount: /dev/sdb1: umount failed: Invalid argument. Feb 23 16:35:23 volumio volumio[2649]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb2","ACTION":"remove","DEVLINKS":"/dev/disk/by-uuid/4CD68E20D68E0A82 /dev/disk/by-partuuid/13ac16bf-02 /dev/disk/by-id/ata-ST9500325AS_6VEN9Q5L-part2 /dev/disk/by-path/platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1:1.0-scsi-0:0:0:0-part2 /dev/disk/by-label/新加卷 /dev/disk/by-id/wwn-0x5000c50033062a2f-part2","DEVNAME":"/dev/sdb2","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb2","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":"128","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_SECURITY":"1","ID_ATA_FEATURE_SET_SECURITY_ENABLED":"0","ID_ATA_FEATURE_SET_SECURITY_ENHANCED_ERASE_UNIT_MIN":"140","ID_ATA_FEATURE_SET_SECURITY_ERASE_UNIT_MIN":"140","ID_ATA_FEATURE_SET_SMART":"1","ID_ATA_FEATURE_SET_SMART_ENABLED":"1","ID_ATA_ROTATION_RATE_RPM":"5400","ID_ATA_SATA":"1","ID_ATA_SATA_SIGNAL_RATE_GEN1":"1","ID_ATA_WRITE_CACHE":"1","ID_ATA_WRITE_CACHE_ENABLED":"1","ID_BUS":"ata","ID_FS_LABEL":"新加卷","ID_FS_LABEL_ENC":"新加卷","ID_FS_TYPE":"ntfs","ID_FS_USAGE":"filesystem","ID_FS_UUID":"4CD68E20D68E0A82","ID_FS_UUID_ENC":"4CD68E20D68E0A82","ID_MODEL":"ST9500325AS","ID_MODEL_ENC":"ST9500325AS\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\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:16","ID_PART_ENTRY_NUMBER":"2","ID_PART_ENTRY_OFFSET":"485249024","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"491520000","ID_PART_ENTRY_TYPE":"0x7","ID_PART_ENTRY_UUID":"13ac16bf-02","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"13ac16bf","ID_PATH":"platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-fd500000_pcie-pci-0000_01_00_0-usb-0_1_1_0-scsi-0_0_0_0","ID_REVISION":"0002BSM1","ID_SERIAL":"ST9500325AS_6VEN9Q5L","ID_SERIAL_SHORT":"6VEN9Q5L","ID_TYPE":"disk","ID_WWN":"0x5000c50033062a2f","ID_WWN_WITH_EXTENSION":"0x5000c50033062a2f","MAJOR":"8","MINOR":"18","PARTN":"2","SEQNUM":"2639","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"56704779"} Feb 23 16:35:22 volumio sudo[3866]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 16:35:22 volumio sudo[3866]: pam_unix(sudo:session): session closed for user root Feb 23 16:35:22 volumio sudo[3869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sdb2 Feb 23 16:35:22 volumio sudo[3869]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 16:35:22 volumio ntfs-3g[2722]: Unmounting /dev/sdb2 (新加卷) Feb 23 16:35:22 volumio ntfs-3g[2722]: Failed to sync device /dev/sdb2: Input/output error Feb 23 16:35:22 volumio ntfs-3g[2722]: Failed to close volume /dev/sdb2: Input/output error Feb 23 16:35:22 volumio sudo[3869]: pam_unix(sudo:session): session closed for user root Feb 23 16:35:26 volumio volumio[2649]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/新加卷 Feb 23 16:35:27 volumio ntfs-3g[2713]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error Feb 23 16:35:27 volumio ntfs-3g[2713]: Failed to read vcn 0x2 from inode 5: Input/output error Feb 23 16:35:27 volumio volumio[2649]: info: Scanning removed location : "USB/新加卷" Feb 23 16:35:27 volumio ntfs-3g[2713]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error Feb 23 16:35:27 volumio ntfs-3g[2713]: Failed to read index block: Input/output error Feb 23 16:35:27 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand status Feb 23 16:35:27 volumio volumio[2649]: info: Feb 23 16:35:27 volumio volumio[2649]: ---------------------------- MPD announces state update: update Feb 23 16:35:27 volumio volumio[2649]: info: ControllerMpd::getState Feb 23 16:35:27 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand status Feb 23 16:35:27 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand status Feb 23 16:35:27 volumio volumio[2649]: info: Feb 23 16:35:27 volumio volumio[2649]: ---------------------------- MPD announces state update: update Feb 23 16:35:27 volumio volumio[2649]: info: ControllerMpd::getState Feb 23 16:35:27 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand status Feb 23 16:35:27 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand status Feb 23 16:35:27 volumio volumio[2649]: info: Feb 23 16:35:27 volumio volumio[2649]: ---------------------------- MPD announces state update: update Feb 23 16:35:27 volumio volumio[2649]: info: ControllerMpd::getState Feb 23 16:35:27 volumio kernel: Buffer I/O error on dev sdb1, logical block 8566826, async page read Feb 23 16:35:27 volumio kernel: Buffer I/O error on dev sdb1, logical block 8566824, async page read Feb 23 16:35:27 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand status Feb 23 16:35:27 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand status Feb 23 16:35:27 volumio volumio[2649]: info: Feb 23 16:35:27 volumio volumio[2649]: ---------------------------- MPD announces state update: update Feb 23 16:35:27 volumio volumio[2649]: info: ControllerMpd::getState Feb 23 16:35:27 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand status Feb 23 16:35:27 volumio volumio[2649]: info: sendMpdCommand status took 4 milliseconds Feb 23 16:35:27 volumio volumio[2649]: info: sendMpdCommand status took 4 milliseconds Feb 23 16:35:27 volumio volumio[2649]: info: sendMpdCommand status took 4 milliseconds Feb 23 16:35:27 volumio volumio[2649]: info: sendMpdCommand status took 4 milliseconds Feb 23 16:35:27 volumio volumio[2649]: info: sendMpdCommand status took 3 milliseconds Feb 23 16:35:27 volumio volumio[2649]: info: sendMpdCommand status took 2 milliseconds Feb 23 16:35:27 volumio volumio[2649]: verbose: ControllerMpd::parseState Feb 23 16:35:28 volumio volumio[2649]: info: Command Router : Notfying DB Updatefalse Feb 23 16:35:28 volumio volumio[2649]: info: CoreCommandRouter::Close All Modals sent Feb 23 16:35:28 volumio volumio[2649]: verbose: ControllerMpd::parseState Feb 23 16:35:28 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 16:35:28 volumio volumio[2649]: verbose: ControllerMpd::parseState Feb 23 16:35:28 volumio volumio[2649]: info: Command Router : Notfying DB Updatefalse Feb 23 16:35:28 volumio volumio[2649]: info: CoreCommandRouter::Close All Modals sent Feb 23 16:35:28 volumio volumio[2649]: verbose: ControllerMpd::parseState Feb 23 16:35:28 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 16:35:28 volumio volumio[2649]: verbose: ControllerMpd::parseState Feb 23 16:35:28 volumio volumio[2649]: info: Command Router : Notfying DB Updatefalse Feb 23 16:35:28 volumio volumio[2649]: info: CoreCommandRouter::Close All Modals sent Feb 23 16:35:28 volumio volumio[2649]: verbose: ControllerMpd::parseState Feb 23 16:35:28 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 16:35:28 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand status Feb 23 16:35:28 volumio volumio[2649]: info: Feb 23 16:35:28 volumio volumio[2649]: ---------------------------- MPD announces state update: update Feb 23 16:35:28 volumio volumio[2649]: info: ControllerMpd::getState Feb 23 16:35:28 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand status Feb 23 16:35:28 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand status Feb 23 16:35:28 volumio volumio[2649]: info: Feb 23 16:35:28 volumio volumio[2649]: ---------------------------- MPD announces state update: update Feb 23 16:35:28 volumio volumio[2649]: info: ControllerMpd::getState Feb 23 16:35:28 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand status Feb 23 16:35:28 volumio volumio[2649]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Feb 23 16:35:28 volumio volumio[2649]: info: Retrying Login Due to Network Error Feb 23 16:35:28 volumio volumio[2649]: info: sendMpdCommand status took 1279 milliseconds Feb 23 16:35:28 volumio volumio[2649]: info: sendMpdCommand status took 1279 milliseconds Feb 23 16:35:28 volumio volumio[2649]: info: sendMpdCommand playlistinfo took 45 milliseconds Feb 23 16:35:28 volumio volumio[2649]: info: sendMpdCommand playlistinfo took 28 milliseconds Feb 23 16:35:28 volumio volumio[2649]: info: sendMpdCommand playlistinfo took 11 milliseconds Feb 23 16:35:28 volumio volumio[2649]: info: sendMpdCommand status took 5 milliseconds Feb 23 16:35:28 volumio volumio[2649]: info: sendMpdCommand status took 5 milliseconds Feb 23 16:35:28 volumio volumio[2649]: info: sendMpdCommand status took 5 milliseconds Feb 23 16:35:28 volumio volumio[2649]: info: sendMpdCommand status took 4 milliseconds Feb 23 16:35:28 volumio volumio[2649]: verbose: ControllerMpd::parseState Feb 23 16:35:28 volumio volumio[2649]: info: Command Router : Notfying DB Updatefalse Feb 23 16:35:28 volumio volumio[2649]: info: CoreCommandRouter::Close All Modals sent Feb 23 16:35:28 volumio volumio[2649]: verbose: ControllerMpd::parseState Feb 23 16:35:28 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 16:35:28 volumio volumio[2649]: verbose: ControllerMpd::parseTrackInfo Feb 23 16:35:28 volumio volumio[2649]: verbose: ControllerMpd::parseTrackInfo Feb 23 16:35:28 volumio volumio[2649]: verbose: ControllerMpd::parseTrackInfo Feb 23 16:35:28 volumio volumio[2649]: verbose: ControllerMpd::parseState Feb 23 16:35:28 volumio volumio[2649]: info: Command Router : Notfying DB Updatefalse Feb 23 16:35:28 volumio volumio[2649]: info: CoreCommandRouter::Close All Modals sent Feb 23 16:35:28 volumio volumio[2649]: verbose: ControllerMpd::parseState Feb 23 16:35:28 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 16:35:28 volumio volumio[2649]: verbose: ControllerMpd::parseState Feb 23 16:35:29 volumio volumio[2649]: info: Command Router : Notfying DB Updatefalse Feb 23 16:35:29 volumio volumio[2649]: info: CoreCommandRouter::Close All Modals sent Feb 23 16:35:29 volumio volumio[2649]: verbose: ControllerMpd::parseState Feb 23 16:35:29 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 16:35:29 volumio volumio[2649]: info: ControllerMpd::pushState Feb 23 16:35:29 volumio volumio[2649]: info: CoreCommandRouter::servicePushState Feb 23 16:35:29 volumio volumio[2649]: info: CorePlayQueue::getTrack 0 Feb 23 16:35:29 volumio volumio[2649]: verbose: STATE SERVICE {"status":"play","position":0,"seek":47474,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Irene Yeh · I Think (1987)","artist":"City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Feb 23 16:35:29 volumio volumio[2649]: verbose: CURRENT POSITION 0 Feb 23 16:35:29 volumio volumio[2649]: info: CoreStateMachine::syncState stateService play Feb 23 16:35:29 volumio volumio[2649]: info: CoreStateMachine::syncState currentStatus play Feb 23 16:35:29 volumio volumio[2649]: info: Received an update from plugin. extracting info from payload Feb 23 16:35:29 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 16:35:29 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:35:29 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 16:35:29 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:35:29 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:35:29 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:35:29 volumio volumio[2649]: info: ControllerMpd::pushState Feb 23 16:35:29 volumio volumio[2649]: info: CoreCommandRouter::servicePushState Feb 23 16:35:29 volumio volumio[2649]: info: CorePlayQueue::getTrack 0 Feb 23 16:35:29 volumio volumio[2649]: verbose: STATE SERVICE {"status":"play","position":0,"seek":47599,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Irene Yeh · I Think (1987)","artist":"City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Feb 23 16:35:29 volumio volumio[2649]: verbose: CURRENT POSITION 0 Feb 23 16:35:29 volumio volumio[2649]: info: CoreStateMachine::syncState stateService play Feb 23 16:35:29 volumio volumio[2649]: info: CoreStateMachine::syncState currentStatus play Feb 23 16:35:29 volumio volumio[2649]: info: Received an update from plugin. extracting info from payload Feb 23 16:35:29 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 16:35:29 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:35:29 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:35:29 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:35:29 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:35:29 volumio volumio[2649]: info: ControllerMpd::pushState Feb 23 16:35:29 volumio volumio[2649]: info: CoreCommandRouter::servicePushState Feb 23 16:35:29 volumio volumio[2649]: info: CorePlayQueue::getTrack 0 Feb 23 16:35:29 volumio volumio[2649]: verbose: STATE SERVICE {"status":"play","position":0,"seek":47599,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Irene Yeh · I Think (1987)","artist":"City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Feb 23 16:35:29 volumio volumio[2649]: verbose: CURRENT POSITION 0 Feb 23 16:35:29 volumio volumio[2649]: info: CoreStateMachine::syncState stateService play Feb 23 16:35:29 volumio volumio[2649]: info: CoreStateMachine::syncState currentStatus play Feb 23 16:35:29 volumio volumio[2649]: info: Received an update from plugin. extracting info from payload Feb 23 16:35:29 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 16:35:29 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:35:29 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:35:29 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:35:29 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:35:29 volumio volumio[2649]: info: ------------------------------ 1366ms Feb 23 16:35:29 volumio volumio[2649]: info: ------------------------------ 1365ms Feb 23 16:35:29 volumio volumio[2649]: info: ------------------------------ 1365ms Feb 23 16:35:29 volumio volumio[2649]: info: sendMpdCommand playlistinfo took 70 milliseconds Feb 23 16:35:29 volumio volumio[2649]: info: sendMpdCommand playlistinfo took 52 milliseconds Feb 23 16:35:29 volumio volumio[2649]: info: sendMpdCommand playlistinfo took 35 milliseconds Feb 23 16:35:29 volumio volumio[2649]: verbose: ControllerMpd::parseTrackInfo Feb 23 16:35:29 volumio volumio[2649]: verbose: ControllerMpd::parseTrackInfo Feb 23 16:35:29 volumio volumio[2649]: verbose: ControllerMpd::parseTrackInfo Feb 23 16:35:29 volumio volumio[2649]: info: ControllerMpd::pushState Feb 23 16:35:29 volumio volumio[2649]: info: CoreCommandRouter::servicePushState Feb 23 16:35:29 volumio volumio[2649]: info: CorePlayQueue::getTrack 0 Feb 23 16:35:29 volumio volumio[2649]: verbose: STATE SERVICE {"status":"play","position":0,"seek":47599,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Irene Yeh · I Think (1987)","artist":"City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Feb 23 16:35:29 volumio volumio[2649]: verbose: CURRENT POSITION 0 Feb 23 16:35:29 volumio volumio[2649]: info: CoreStateMachine::syncState stateService play Feb 23 16:35:29 volumio volumio[2649]: info: CoreStateMachine::syncState currentStatus play Feb 23 16:35:29 volumio volumio[2649]: info: Received an update from plugin. extracting info from payload Feb 23 16:35:29 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 16:35:29 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:35:29 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:35:29 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:35:29 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:35:29 volumio volumio[2649]: info: ControllerMpd::pushState Feb 23 16:35:29 volumio volumio[2649]: info: CoreCommandRouter::servicePushState Feb 23 16:35:29 volumio volumio[2649]: info: CorePlayQueue::getTrack 0 Feb 23 16:35:29 volumio volumio[2649]: verbose: STATE SERVICE {"status":"play","position":0,"seek":48857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Irene Yeh · I Think (1987)","artist":"City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Feb 23 16:35:29 volumio volumio[2649]: verbose: CURRENT POSITION 0 Feb 23 16:35:29 volumio volumio[2649]: info: CoreStateMachine::syncState stateService play Feb 23 16:35:29 volumio volumio[2649]: info: CoreStateMachine::syncState currentStatus play Feb 23 16:35:29 volumio volumio[2649]: info: Received an update from plugin. extracting info from payload Feb 23 16:35:29 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 16:35:29 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:35:29 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:35:29 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:35:29 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:35:29 volumio volumio[2649]: info: ControllerMpd::pushState Feb 23 16:35:29 volumio volumio[2649]: info: CoreCommandRouter::servicePushState Feb 23 16:35:29 volumio volumio[2649]: info: CorePlayQueue::getTrack 0 Feb 23 16:35:29 volumio volumio[2649]: verbose: STATE SERVICE {"status":"play","position":0,"seek":48857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Irene Yeh · I Think (1987)","artist":"City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Feb 23 16:35:29 volumio volumio[2649]: verbose: CURRENT POSITION 0 Feb 23 16:35:29 volumio volumio[2649]: info: CoreStateMachine::syncState stateService play Feb 23 16:35:29 volumio volumio[2649]: info: CoreStateMachine::syncState currentStatus play Feb 23 16:35:29 volumio volumio[2649]: info: Received an update from plugin. extracting info from payload Feb 23 16:35:29 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 16:35:29 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:35:29 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:35:29 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:35:29 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:35:29 volumio volumio[2649]: info: ------------------------------ 1389ms Feb 23 16:35:29 volumio volumio[2649]: info: ------------------------------ 114ms Feb 23 16:35:29 volumio volumio[2649]: info: ------------------------------ 114ms Feb 23 16:35:35 volumio kernel: usb 1-1.1: new high-speed USB device number 6 using xhci_hcd Feb 23 16:35:35 volumio kernel: usb 1-1.1: New USB device found, idVendor=048d, idProduct=1234, bcdDevice= 2.00 Feb 23 16:35:35 volumio kernel: usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Feb 23 16:35:35 volumio kernel: usb 1-1.1: Product: Disk 3.0 Feb 23 16:35:35 volumio kernel: usb 1-1.1: Manufacturer: USB Feb 23 16:35:35 volumio kernel: usb 1-1.1: SerialNumber: 4486651221452244741 Feb 23 16:35:35 volumio kernel: usb-storage 1-1.1:1.0: USB Mass Storage device detected Feb 23 16:35:35 volumio kernel: scsi host2: usb-storage 1-1.1:1.0 Feb 23 16:35:36 volumio kernel: scsi 2:0:0:0: Direct-Access Flash Disk 3.0 2.00 PQ: 0 ANSI: 4 Feb 23 16:35:36 volumio kernel: sd 2:0:0:0: Attached scsi generic sg1 type 0 Feb 23 16:35:36 volumio kernel: sd 2:0:0:0: [sdc] 1048576000 512-byte logical blocks: (537 GB/500 GiB) Feb 23 16:35:36 volumio kernel: sd 2:0:0:0: [sdc] Write Protect is off Feb 23 16:35:36 volumio kernel: sd 2:0:0:0: [sdc] Mode Sense: 03 00 00 00 Feb 23 16:35:36 volumio kernel: sd 2:0:0:0: [sdc] No Caching mode page found Feb 23 16:35:36 volumio kernel: sd 2:0:0:0: [sdc] Assuming drive cache: write through Feb 23 16:35:36 volumio kernel: sdc: sdc1 Feb 23 16:35:36 volumio kernel: sd 2:0:0:0: [sdc] Attached SCSI removable disk Feb 23 16:35:36 volumio volumio[2649]: info: Mounting Device E0E5-95E2 Feb 23 16:35:36 volumio sudo[3959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdc1 /mnt/USB/E0E5-95E2 -o noatime Feb 23 16:35:36 volumio sudo[3959]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 16:35:36 volumio sudo[3959]: pam_unix(sudo:session): session closed for user root Feb 23 16:35:36 volumio volumio[2649]: info: Scanning new location : "USB/E0E5-95E2" Feb 23 16:35:36 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand status Feb 23 16:35:36 volumio volumio[2649]: info: Feb 23 16:35:36 volumio volumio[2649]: ---------------------------- MPD announces state update: update Feb 23 16:35:36 volumio volumio[2649]: info: ControllerMpd::getState Feb 23 16:35:36 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand status Feb 23 16:35:36 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand status Feb 23 16:35:36 volumio volumio[2649]: info: Feb 23 16:35:36 volumio volumio[2649]: ---------------------------- MPD announces state update: update Feb 23 16:35:36 volumio volumio[2649]: info: ControllerMpd::getState Feb 23 16:35:36 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand status Feb 23 16:35:36 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand status Feb 23 16:35:36 volumio volumio[2649]: info: Feb 23 16:35:36 volumio volumio[2649]: ---------------------------- MPD announces state update: update Feb 23 16:35:36 volumio volumio[2649]: info: ControllerMpd::getState Feb 23 16:35:36 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand status Feb 23 16:35:36 volumio volumio[2649]: info: sendMpdCommand status took 4 milliseconds Feb 23 16:35:36 volumio volumio[2649]: info: sendMpdCommand status took 3 milliseconds Feb 23 16:35:36 volumio volumio[2649]: info: sendMpdCommand status took 3 milliseconds Feb 23 16:35:36 volumio volumio[2649]: info: sendMpdCommand status took 2 milliseconds Feb 23 16:35:36 volumio volumio[2649]: info: sendMpdCommand status took 3 milliseconds Feb 23 16:35:36 volumio volumio[2649]: info: sendMpdCommand status took 2 milliseconds Feb 23 16:35:36 volumio volumio[2649]: verbose: ControllerMpd::parseState Feb 23 16:35:38 volumio volumio[2649]: info: Command Router : Notfying DB Updatetrue Feb 23 16:35:38 volumio volumio[2649]: verbose: ControllerMpd::parseState Feb 23 16:35:38 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 16:35:38 volumio volumio[2649]: verbose: ControllerMpd::parseState Feb 23 16:35:38 volumio volumio[2649]: info: Command Router : Notfying DB Updatetrue Feb 23 16:35:38 volumio volumio[2649]: info: CoreCommandRouter::Close All Modals sent Feb 23 16:35:38 volumio volumio[2649]: verbose: ControllerMpd::parseState Feb 23 16:35:38 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 16:35:38 volumio volumio[2649]: verbose: ControllerMpd::parseState Feb 23 16:35:38 volumio volumio[2649]: info: Command Router : Notfying DB Updatetrue Feb 23 16:35:38 volumio volumio[2649]: info: CoreCommandRouter::Close All Modals sent Feb 23 16:35:38 volumio volumio[2649]: verbose: ControllerMpd::parseState Feb 23 16:35:38 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 16:35:38 volumio volumio[2649]: info: sendMpdCommand playlistinfo took 47 milliseconds Feb 23 16:35:38 volumio volumio[2649]: info: sendMpdCommand playlistinfo took 27 milliseconds Feb 23 16:35:38 volumio volumio[2649]: info: sendMpdCommand playlistinfo took 9 milliseconds Feb 23 16:35:38 volumio volumio[2649]: verbose: ControllerMpd::parseTrackInfo Feb 23 16:35:38 volumio volumio[2649]: verbose: ControllerMpd::parseTrackInfo Feb 23 16:35:38 volumio volumio[2649]: verbose: ControllerMpd::parseTrackInfo Feb 23 16:35:38 volumio volumio[2649]: info: ControllerMpd::pushState Feb 23 16:35:38 volumio volumio[2649]: info: CoreCommandRouter::servicePushState Feb 23 16:35:38 volumio volumio[2649]: info: CorePlayQueue::getTrack 0 Feb 23 16:35:38 volumio volumio[2649]: verbose: STATE SERVICE {"status":"play","position":0,"seek":56605,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Irene Yeh · I Think (1987)","artist":"City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Feb 23 16:35:38 volumio volumio[2649]: verbose: CURRENT POSITION 0 Feb 23 16:35:38 volumio volumio[2649]: info: CoreStateMachine::syncState stateService play Feb 23 16:35:38 volumio volumio[2649]: info: CoreStateMachine::syncState currentStatus play Feb 23 16:35:38 volumio volumio[2649]: info: Received an update from plugin. extracting info from payload Feb 23 16:35:38 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 16:35:38 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:35:38 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 16:35:38 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:35:38 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:35:38 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:35:38 volumio volumio[2649]: info: ControllerMpd::pushState Feb 23 16:35:38 volumio volumio[2649]: info: CoreCommandRouter::servicePushState Feb 23 16:35:38 volumio volumio[2649]: info: CorePlayQueue::getTrack 0 Feb 23 16:35:38 volumio volumio[2649]: verbose: STATE SERVICE {"status":"play","position":0,"seek":56605,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Irene Yeh · I Think (1987)","artist":"City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Feb 23 16:35:38 volumio volumio[2649]: verbose: CURRENT POSITION 0 Feb 23 16:35:38 volumio volumio[2649]: info: CoreStateMachine::syncState stateService play Feb 23 16:35:38 volumio volumio[2649]: info: CoreStateMachine::syncState currentStatus play Feb 23 16:35:38 volumio volumio[2649]: info: Received an update from plugin. extracting info from payload Feb 23 16:35:38 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 16:35:38 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:35:38 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:35:38 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:35:38 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:35:38 volumio volumio[2649]: info: ControllerMpd::pushState Feb 23 16:35:38 volumio volumio[2649]: info: CoreCommandRouter::servicePushState Feb 23 16:35:38 volumio volumio[2649]: info: CorePlayQueue::getTrack 0 Feb 23 16:35:38 volumio volumio[2649]: verbose: STATE SERVICE {"status":"play","position":0,"seek":56605,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Irene Yeh · I Think (1987)","artist":"City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Feb 23 16:35:38 volumio volumio[2649]: verbose: CURRENT POSITION 0 Feb 23 16:35:38 volumio volumio[2649]: info: CoreStateMachine::syncState stateService play Feb 23 16:35:38 volumio volumio[2649]: info: CoreStateMachine::syncState currentStatus play Feb 23 16:35:38 volumio volumio[2649]: info: Received an update from plugin. extracting info from payload Feb 23 16:35:38 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 16:35:38 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:35:38 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:35:38 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:35:38 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:35:38 volumio volumio[2649]: info: ------------------------------ 1338ms Feb 23 16:35:38 volumio volumio[2649]: info: ------------------------------ 1337ms Feb 23 16:35:38 volumio volumio[2649]: info: ------------------------------ 1337ms Feb 23 16:35:38 volumio volumio[2649]: info: MyVolumio login type: Token Feb 23 16:35:46 volumio wpa_supplicant[3146]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="NETGEAR50" Feb 23 16:35:46 volumio wpa_supplicant[3146]: wlan0: Trying to associate with SSID 'NETGEAR50' Feb 23 16:35:46 volumio wpa_supplicant[3146]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=3c:37:86:f3:e9:16 status_code=16 Feb 23 16:35:46 volumio wpa_supplicant[3146]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="NETGEAR50" auth_failures=5 duration=75 reason=CONN_FAILED Feb 23 16:36:08 volumio volumio[2649]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Feb 23 16:36:08 volumio volumio[2649]: info: Retrying Login Due to Network Error Feb 23 16:36:13 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 16:36:13 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 16:36:13 volumio volumio[2649]: info: Discovery: Getting this device information Feb 23 16:36:13 volumio volumio[2649]: info: CoreCommandRouter::volumioGetState Feb 23 16:36:13 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 16:36:13 volumio volumio[2649]: verbose: New Socket.io Connection to 192.168.0.15:3000 from 192.168.0.2 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Feb 23 16:36:13 volumio volumio[2649]: info: CoreCommandRouter::volumioGetState Feb 23 16:36:13 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 23 16:36:13 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 23 16:36:16 volumio sudo[4161]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 23 16:36:16 volumio sudo[4161]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 16:36:16 volumio sudo[4161]: pam_unix(sudo:session): session closed for user root Feb 23 16:36:16 volumio sudo[4164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 23 16:36:16 volumio sudo[4164]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 16:36:16 volumio sudo[4164]: pam_unix(sudo:session): session closed for user root Feb 23 16:36:16 volumio volumio[2649]: verbose: New Socket.io Connection to 192.168.0.15 from 192.168.0.2 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_2_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 5 Feb 23 16:36:17 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 16:36:17 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 16:36:17 volumio volumio[2649]: info: Discovery: Getting this device information Feb 23 16:36:17 volumio volumio[2649]: info: CoreCommandRouter::volumioGetState Feb 23 16:36:17 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 16:36:17 volumio sudo[4167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 23 16:36:17 volumio sudo[4167]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 16:36:17 volumio sudo[4167]: pam_unix(sudo:session): session closed for user root Feb 23 16:36:17 volumio sudo[4170]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 23 16:36:17 volumio sudo[4170]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 16:36:17 volumio sudo[4170]: pam_unix(sudo:session): session closed for user root Feb 23 16:36:17 volumio volumio[2649]: verbose: New Socket.io Connection to 192.168.0.15 from 192.168.0.2 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_2_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Feb 23 16:36:17 volumio volumio[2649]: info: CoreCommandRouter::volumioGetState Feb 23 16:36:17 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 23 16:36:17 volumio volumio[2649]: info: Listing playlists Feb 23 16:36:17 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 23 16:36:17 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 23 16:36:17 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 23 16:36:17 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 23 16:36:18 volumio volumio[2649]: info: MyVolumio login type: Token Feb 23 16:36:19 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 23 16:36:19 volumio volumio[2649]: info: Received Get System Info Feb 23 16:36:19 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 16:36:19 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 16:36:19 volumio volumio[2649]: info: Discovery: Getting this device information Feb 23 16:36:19 volumio volumio[2649]: info: CoreCommandRouter::volumioGetState Feb 23 16:36:19 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 16:36:22 volumio volumio[2649]: info: Feb 23 16:36:22 volumio volumio[2649]: ---------------------------- MPD announces system playlist update Feb 23 16:36:22 volumio volumio[2649]: info: Ignoring MPD Status Update Feb 23 16:36:22 volumio volumio[2649]: info: Feb 23 16:36:22 volumio volumio[2649]: ---------------------------- MPD announces state update: player Feb 23 16:36:22 volumio volumio[2649]: info: ControllerMpd::getState Feb 23 16:36:22 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand status Feb 23 16:36:22 volumio volumio[2649]: info: Feb 23 16:36:22 volumio volumio[2649]: ---------------------------- MPD announces system playlist update Feb 23 16:36:22 volumio volumio[2649]: info: Ignoring MPD Status Update Feb 23 16:36:22 volumio volumio[2649]: info: Feb 23 16:36:22 volumio volumio[2649]: ---------------------------- MPD announces state update: player Feb 23 16:36:22 volumio volumio[2649]: info: ControllerMpd::getState Feb 23 16:36:22 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand status Feb 23 16:36:22 volumio volumio[2649]: info: Feb 23 16:36:22 volumio volumio[2649]: ---------------------------- MPD announces system playlist update Feb 23 16:36:22 volumio volumio[2649]: info: Ignoring MPD Status Update Feb 23 16:36:22 volumio volumio[2649]: info: Feb 23 16:36:22 volumio volumio[2649]: ---------------------------- MPD announces state update: player Feb 23 16:36:22 volumio volumio[2649]: info: ControllerMpd::getState Feb 23 16:36:22 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand status Feb 23 16:36:22 volumio volumio[2649]: info: ------------------------------ 5ms Feb 23 16:36:22 volumio volumio[2649]: info: sendMpdCommand status took 5 milliseconds Feb 23 16:36:22 volumio volumio[2649]: info: ------------------------------ 4ms Feb 23 16:36:22 volumio volumio[2649]: info: sendMpdCommand status took 4 milliseconds Feb 23 16:36:22 volumio volumio[2649]: verbose: ControllerMpd::parseState Feb 23 16:36:22 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 16:36:22 volumio volumio[2649]: verbose: ControllerMpd::parseState Feb 23 16:36:22 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 16:36:22 volumio volumio[2649]: info: ------------------------------ 4ms Feb 23 16:36:22 volumio volumio[2649]: info: sendMpdCommand status took 4 milliseconds Feb 23 16:36:22 volumio volumio[2649]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 23 16:36:22 volumio volumio[2649]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 23 16:36:22 volumio volumio[2649]: verbose: ControllerMpd::parseState Feb 23 16:36:22 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 16:36:22 volumio volumio[2649]: verbose: ControllerMpd::parseTrackInfo Feb 23 16:36:22 volumio volumio[2649]: verbose: ControllerMpd::parseTrackInfo Feb 23 16:36:22 volumio volumio[2649]: info: ControllerMpd::pushState Feb 23 16:36:22 volumio volumio[2649]: info: CoreCommandRouter::servicePushState Feb 23 16:36:22 volumio volumio[2649]: info: CorePlayQueue::getTrack 0 Feb 23 16:36:22 volumio volumio[2649]: verbose: STATE SERVICE {"status":"play","position":0,"seek":102594,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Billie · Don't Have To Say Anything (1984)","artist":"City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Feb 23 16:36:22 volumio volumio[2649]: verbose: CURRENT POSITION 0 Feb 23 16:36:22 volumio volumio[2649]: info: CoreStateMachine::syncState stateService play Feb 23 16:36:22 volumio volumio[2649]: info: CoreStateMachine::syncState currentStatus play Feb 23 16:36:22 volumio volumio[2649]: info: Received an update from plugin. extracting info from payload Feb 23 16:36:22 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 16:36:22 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:36:22 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 16:36:22 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:36:22 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:36:22 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:36:22 volumio volumio[2649]: info: ControllerMpd::pushState Feb 23 16:36:22 volumio volumio[2649]: info: CoreCommandRouter::servicePushState Feb 23 16:36:22 volumio volumio[2649]: info: CorePlayQueue::getTrack 0 Feb 23 16:36:22 volumio volumio[2649]: verbose: STATE SERVICE {"status":"play","position":0,"seek":102594,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Billie · Don't Have To Say Anything (1984)","artist":"City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Feb 23 16:36:22 volumio volumio[2649]: verbose: CURRENT POSITION 0 Feb 23 16:36:22 volumio volumio[2649]: info: CoreStateMachine::syncState stateService play Feb 23 16:36:22 volumio volumio[2649]: info: CoreStateMachine::syncState currentStatus play Feb 23 16:36:22 volumio volumio[2649]: info: Received an update from plugin. extracting info from payload Feb 23 16:36:22 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 16:36:22 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:36:22 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:36:22 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:36:22 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:36:22 volumio volumio[2649]: info: ------------------------------ 40ms Feb 23 16:36:22 volumio volumio[2649]: info: ------------------------------ 39ms Feb 23 16:36:22 volumio volumio[2649]: info: sendMpdCommand playlistinfo took 32 milliseconds Feb 23 16:36:22 volumio volumio[2649]: verbose: ControllerMpd::parseTrackInfo Feb 23 16:36:22 volumio volumio[2649]: info: ControllerMpd::pushState Feb 23 16:36:22 volumio volumio[2649]: info: CoreCommandRouter::servicePushState Feb 23 16:36:22 volumio volumio[2649]: info: CorePlayQueue::getTrack 0 Feb 23 16:36:22 volumio volumio[2649]: verbose: STATE SERVICE {"status":"play","position":0,"seek":102594,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Billie · Don't Have To Say Anything (1984)","artist":"City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Feb 23 16:36:22 volumio volumio[2649]: verbose: CURRENT POSITION 0 Feb 23 16:36:22 volumio volumio[2649]: info: CoreStateMachine::syncState stateService play Feb 23 16:36:22 volumio volumio[2649]: info: CoreStateMachine::syncState currentStatus play Feb 23 16:36:22 volumio volumio[2649]: info: Received an update from plugin. extracting info from payload Feb 23 16:36:22 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 16:36:22 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:36:22 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:36:22 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:36:22 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:36:22 volumio volumio[2649]: info: ------------------------------ 52ms Feb 23 16:36:32 volumio volumio[2649]: info: VolumeController::SetAlsaVolume+ Feb 23 16:36:32 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:36:32 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 16:36:32 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:36:33 volumio volumio[2649]: info: Feb 23 16:36:33 volumio volumio[2649]: ---------------------------- MPD announces state update: mixer Feb 23 16:36:33 volumio volumio[2649]: info: ControllerMpd::getState Feb 23 16:36:33 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand status Feb 23 16:36:33 volumio volumio[2649]: info: Feb 23 16:36:33 volumio volumio[2649]: ---------------------------- MPD announces state update: mixer Feb 23 16:36:33 volumio volumio[2649]: info: ControllerMpd::getState Feb 23 16:36:33 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand status Feb 23 16:36:33 volumio volumio[2649]: info: Feb 23 16:36:33 volumio volumio[2649]: ---------------------------- MPD announces state update: mixer Feb 23 16:36:33 volumio volumio[2649]: info: ControllerMpd::getState Feb 23 16:36:33 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand status Feb 23 16:36:33 volumio volumio[2649]: info: sendMpdCommand status took 3 milliseconds Feb 23 16:36:33 volumio volumio[2649]: info: sendMpdCommand status took 2 milliseconds Feb 23 16:36:33 volumio volumio[2649]: verbose: ControllerMpd::parseState Feb 23 16:36:33 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 16:36:33 volumio volumio[2649]: verbose: ControllerMpd::parseState Feb 23 16:36:33 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 16:36:33 volumio volumio[2649]: info: sendMpdCommand status took 3 milliseconds Feb 23 16:36:33 volumio volumio[2649]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 23 16:36:33 volumio volumio[2649]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 23 16:36:33 volumio volumio[2649]: verbose: ControllerMpd::parseState Feb 23 16:36:33 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 16:36:33 volumio volumio[2649]: verbose: ControllerMpd::parseTrackInfo Feb 23 16:36:33 volumio volumio[2649]: verbose: ControllerMpd::parseTrackInfo Feb 23 16:36:33 volumio volumio[2649]: info: ControllerMpd::pushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::servicePushState Feb 23 16:36:33 volumio volumio[2649]: info: CorePlayQueue::getTrack 0 Feb 23 16:36:33 volumio volumio[2649]: verbose: STATE SERVICE {"status":"play","position":0,"seek":112842,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Billie · Don't Have To Say Anything (1984)","artist":"City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Feb 23 16:36:33 volumio volumio[2649]: verbose: CURRENT POSITION 0 Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::syncState stateService play Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::syncState currentStatus play Feb 23 16:36:33 volumio volumio[2649]: info: Received an update from plugin. extracting info from payload Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:36:33 volumio volumio[2649]: info: ControllerMpd::pushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::servicePushState Feb 23 16:36:33 volumio volumio[2649]: info: CorePlayQueue::getTrack 0 Feb 23 16:36:33 volumio volumio[2649]: verbose: STATE SERVICE {"status":"play","position":0,"seek":112842,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Billie · Don't Have To Say Anything (1984)","artist":"City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Feb 23 16:36:33 volumio volumio[2649]: verbose: CURRENT POSITION 0 Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::syncState stateService play Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::syncState currentStatus play Feb 23 16:36:33 volumio volumio[2649]: info: Received an update from plugin. extracting info from payload Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:36:33 volumio volumio[2649]: info: ------------------------------ 28ms Feb 23 16:36:33 volumio volumio[2649]: info: ------------------------------ 27ms Feb 23 16:36:33 volumio volumio[2649]: info: sendMpdCommand playlistinfo took 23 milliseconds Feb 23 16:36:33 volumio volumio[2649]: verbose: ControllerMpd::parseTrackInfo Feb 23 16:36:33 volumio volumio[2649]: info: ControllerMpd::pushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::servicePushState Feb 23 16:36:33 volumio volumio[2649]: info: CorePlayQueue::getTrack 0 Feb 23 16:36:33 volumio volumio[2649]: verbose: STATE SERVICE {"status":"play","position":0,"seek":112842,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Billie · Don't Have To Say Anything (1984)","artist":"City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Feb 23 16:36:33 volumio volumio[2649]: verbose: CURRENT POSITION 0 Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::syncState stateService play Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::syncState currentStatus play Feb 23 16:36:33 volumio volumio[2649]: info: Received an update from plugin. extracting info from payload Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:36:33 volumio volumio[2649]: info: ------------------------------ 41ms Feb 23 16:36:33 volumio volumio[2649]: info: VolumeController::SetAlsaVolume+ Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:36:33 volumio volumio[2649]: info: Feb 23 16:36:33 volumio volumio[2649]: ---------------------------- MPD announces state update: mixer Feb 23 16:36:33 volumio volumio[2649]: info: ControllerMpd::getState Feb 23 16:36:33 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand status Feb 23 16:36:33 volumio volumio[2649]: info: Feb 23 16:36:33 volumio volumio[2649]: ---------------------------- MPD announces state update: mixer Feb 23 16:36:33 volumio volumio[2649]: info: ControllerMpd::getState Feb 23 16:36:33 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand status Feb 23 16:36:33 volumio volumio[2649]: info: sendMpdCommand status took 1 milliseconds Feb 23 16:36:33 volumio volumio[2649]: verbose: ControllerMpd::parseState Feb 23 16:36:33 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 16:36:33 volumio volumio[2649]: info: Feb 23 16:36:33 volumio volumio[2649]: ---------------------------- MPD announces state update: mixer Feb 23 16:36:33 volumio volumio[2649]: info: ControllerMpd::getState Feb 23 16:36:33 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand status Feb 23 16:36:33 volumio volumio[2649]: info: sendMpdCommand status took 3 milliseconds Feb 23 16:36:33 volumio volumio[2649]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 23 16:36:33 volumio volumio[2649]: info: sendMpdCommand status took 1 milliseconds Feb 23 16:36:33 volumio volumio[2649]: verbose: ControllerMpd::parseState Feb 23 16:36:33 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 16:36:33 volumio volumio[2649]: verbose: ControllerMpd::parseTrackInfo Feb 23 16:36:33 volumio volumio[2649]: verbose: ControllerMpd::parseState Feb 23 16:36:33 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 16:36:33 volumio volumio[2649]: info: ControllerMpd::pushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::servicePushState Feb 23 16:36:33 volumio volumio[2649]: info: CorePlayQueue::getTrack 0 Feb 23 16:36:33 volumio volumio[2649]: verbose: STATE SERVICE {"status":"play","position":0,"seek":113093,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Billie · Don't Have To Say Anything (1984)","artist":"City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Feb 23 16:36:33 volumio volumio[2649]: verbose: CURRENT POSITION 0 Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::syncState stateService play Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::syncState currentStatus play Feb 23 16:36:33 volumio volumio[2649]: info: Received an update from plugin. extracting info from payload Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:36:33 volumio volumio[2649]: info: ------------------------------ 17ms Feb 23 16:36:33 volumio volumio[2649]: info: sendMpdCommand playlistinfo took 13 milliseconds Feb 23 16:36:33 volumio volumio[2649]: info: sendMpdCommand playlistinfo took 12 milliseconds Feb 23 16:36:33 volumio volumio[2649]: verbose: ControllerMpd::parseTrackInfo Feb 23 16:36:33 volumio volumio[2649]: verbose: ControllerMpd::parseTrackInfo Feb 23 16:36:33 volumio volumio[2649]: info: ControllerMpd::pushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::servicePushState Feb 23 16:36:33 volumio volumio[2649]: info: CorePlayQueue::getTrack 0 Feb 23 16:36:33 volumio volumio[2649]: verbose: STATE SERVICE {"status":"play","position":0,"seek":113093,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Billie · Don't Have To Say Anything (1984)","artist":"City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Feb 23 16:36:33 volumio volumio[2649]: verbose: CURRENT POSITION 0 Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::syncState stateService play Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::syncState currentStatus play Feb 23 16:36:33 volumio volumio[2649]: info: Received an update from plugin. extracting info from payload Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:36:33 volumio volumio[2649]: info: ControllerMpd::pushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::servicePushState Feb 23 16:36:33 volumio volumio[2649]: info: CorePlayQueue::getTrack 0 Feb 23 16:36:33 volumio volumio[2649]: verbose: STATE SERVICE {"status":"play","position":0,"seek":113093,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Billie · Don't Have To Say Anything (1984)","artist":"City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Feb 23 16:36:33 volumio volumio[2649]: verbose: CURRENT POSITION 0 Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::syncState stateService play Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::syncState currentStatus play Feb 23 16:36:33 volumio volumio[2649]: info: Received an update from plugin. extracting info from payload Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:36:33 volumio volumio[2649]: info: ------------------------------ 37ms Feb 23 16:36:33 volumio volumio[2649]: info: ------------------------------ 35ms Feb 23 16:36:33 volumio volumio[2649]: info: VolumeController::SetAlsaVolume+ Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:36:33 volumio volumio[2649]: info: Feb 23 16:36:33 volumio volumio[2649]: ---------------------------- MPD announces state update: mixer Feb 23 16:36:33 volumio volumio[2649]: info: ControllerMpd::getState Feb 23 16:36:33 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand status Feb 23 16:36:33 volumio volumio[2649]: info: Feb 23 16:36:33 volumio volumio[2649]: ---------------------------- MPD announces state update: mixer Feb 23 16:36:33 volumio volumio[2649]: info: ControllerMpd::getState Feb 23 16:36:33 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand status Feb 23 16:36:33 volumio volumio[2649]: info: Feb 23 16:36:33 volumio volumio[2649]: ---------------------------- MPD announces state update: mixer Feb 23 16:36:33 volumio volumio[2649]: info: ControllerMpd::getState Feb 23 16:36:33 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand status Feb 23 16:36:33 volumio volumio[2649]: info: sendMpdCommand status took 2 milliseconds Feb 23 16:36:33 volumio volumio[2649]: info: sendMpdCommand status took 2 milliseconds Feb 23 16:36:33 volumio volumio[2649]: info: sendMpdCommand status took 1 milliseconds Feb 23 16:36:33 volumio volumio[2649]: verbose: ControllerMpd::parseState Feb 23 16:36:33 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 16:36:33 volumio volumio[2649]: verbose: ControllerMpd::parseState Feb 23 16:36:33 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 16:36:33 volumio volumio[2649]: verbose: ControllerMpd::parseState Feb 23 16:36:33 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 16:36:33 volumio volumio[2649]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 23 16:36:33 volumio volumio[2649]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 23 16:36:33 volumio volumio[2649]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 23 16:36:33 volumio volumio[2649]: verbose: ControllerMpd::parseTrackInfo Feb 23 16:36:33 volumio volumio[2649]: verbose: ControllerMpd::parseTrackInfo Feb 23 16:36:33 volumio volumio[2649]: verbose: ControllerMpd::parseTrackInfo Feb 23 16:36:33 volumio volumio[2649]: info: ControllerMpd::pushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::servicePushState Feb 23 16:36:33 volumio volumio[2649]: info: CorePlayQueue::getTrack 0 Feb 23 16:36:33 volumio volumio[2649]: verbose: STATE SERVICE {"status":"play","position":0,"seek":113219,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Billie · Don't Have To Say Anything (1984)","artist":"City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Feb 23 16:36:33 volumio volumio[2649]: verbose: CURRENT POSITION 0 Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::syncState stateService play Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::syncState currentStatus play Feb 23 16:36:33 volumio volumio[2649]: info: Received an update from plugin. extracting info from payload Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:36:33 volumio volumio[2649]: info: ControllerMpd::pushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::servicePushState Feb 23 16:36:33 volumio volumio[2649]: info: CorePlayQueue::getTrack 0 Feb 23 16:36:33 volumio volumio[2649]: verbose: STATE SERVICE {"status":"play","position":0,"seek":113219,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Billie · Don't Have To Say Anything (1984)","artist":"City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Feb 23 16:36:33 volumio volumio[2649]: verbose: CURRENT POSITION 0 Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::syncState stateService play Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::syncState currentStatus play Feb 23 16:36:33 volumio volumio[2649]: info: Received an update from plugin. extracting info from payload Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:36:33 volumio volumio[2649]: info: ControllerMpd::pushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::servicePushState Feb 23 16:36:33 volumio volumio[2649]: info: CorePlayQueue::getTrack 0 Feb 23 16:36:33 volumio volumio[2649]: verbose: STATE SERVICE {"status":"play","position":0,"seek":113219,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Billie · Don't Have To Say Anything (1984)","artist":"City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Feb 23 16:36:33 volumio volumio[2649]: verbose: CURRENT POSITION 0 Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::syncState stateService play Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::syncState currentStatus play Feb 23 16:36:33 volumio volumio[2649]: info: Received an update from plugin. extracting info from payload Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:36:33 volumio volumio[2649]: info: ------------------------------ 36ms Feb 23 16:36:33 volumio volumio[2649]: info: ------------------------------ 36ms Feb 23 16:36:33 volumio volumio[2649]: info: ------------------------------ 35ms Feb 23 16:36:33 volumio volumio[2649]: info: VolumeController::SetAlsaVolume+ Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:36:33 volumio volumio[2649]: info: Feb 23 16:36:33 volumio volumio[2649]: ---------------------------- MPD announces state update: mixer Feb 23 16:36:33 volumio volumio[2649]: info: ControllerMpd::getState Feb 23 16:36:33 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand status Feb 23 16:36:33 volumio volumio[2649]: info: Feb 23 16:36:33 volumio volumio[2649]: ---------------------------- MPD announces state update: mixer Feb 23 16:36:33 volumio volumio[2649]: info: ControllerMpd::getState Feb 23 16:36:33 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand status Feb 23 16:36:33 volumio volumio[2649]: info: sendMpdCommand status took 2 milliseconds Feb 23 16:36:33 volumio volumio[2649]: info: sendMpdCommand status took 1 milliseconds Feb 23 16:36:33 volumio volumio[2649]: verbose: ControllerMpd::parseState Feb 23 16:36:33 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 16:36:33 volumio volumio[2649]: verbose: ControllerMpd::parseState Feb 23 16:36:33 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 16:36:33 volumio volumio[2649]: info: Feb 23 16:36:33 volumio volumio[2649]: ---------------------------- MPD announces state update: mixer Feb 23 16:36:33 volumio volumio[2649]: info: ControllerMpd::getState Feb 23 16:36:33 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand status Feb 23 16:36:33 volumio volumio[2649]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 23 16:36:33 volumio volumio[2649]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 23 16:36:33 volumio volumio[2649]: info: sendMpdCommand status took 2 milliseconds Feb 23 16:36:33 volumio volumio[2649]: verbose: ControllerMpd::parseTrackInfo Feb 23 16:36:33 volumio volumio[2649]: verbose: ControllerMpd::parseTrackInfo Feb 23 16:36:33 volumio volumio[2649]: verbose: ControllerMpd::parseState Feb 23 16:36:33 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 16:36:33 volumio volumio[2649]: info: ControllerMpd::pushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::servicePushState Feb 23 16:36:33 volumio volumio[2649]: info: CorePlayQueue::getTrack 0 Feb 23 16:36:33 volumio volumio[2649]: verbose: STATE SERVICE {"status":"play","position":0,"seek":113345,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Billie · Don't Have To Say Anything (1984)","artist":"City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Feb 23 16:36:33 volumio volumio[2649]: verbose: CURRENT POSITION 0 Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::syncState stateService play Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::syncState currentStatus play Feb 23 16:36:33 volumio volumio[2649]: info: Received an update from plugin. extracting info from payload Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:36:33 volumio volumio[2649]: info: ControllerMpd::pushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::servicePushState Feb 23 16:36:33 volumio volumio[2649]: info: CorePlayQueue::getTrack 0 Feb 23 16:36:33 volumio volumio[2649]: verbose: STATE SERVICE {"status":"play","position":0,"seek":113345,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Billie · Don't Have To Say Anything (1984)","artist":"City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Feb 23 16:36:33 volumio volumio[2649]: verbose: CURRENT POSITION 0 Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::syncState stateService play Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::syncState currentStatus play Feb 23 16:36:33 volumio volumio[2649]: info: Received an update from plugin. extracting info from payload Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:36:33 volumio volumio[2649]: info: ------------------------------ 32ms Feb 23 16:36:33 volumio volumio[2649]: info: ------------------------------ 31ms Feb 23 16:36:33 volumio volumio[2649]: info: sendMpdCommand playlistinfo took 28 milliseconds Feb 23 16:36:33 volumio volumio[2649]: verbose: ControllerMpd::parseTrackInfo Feb 23 16:36:33 volumio volumio[2649]: info: ControllerMpd::pushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::servicePushState Feb 23 16:36:33 volumio volumio[2649]: info: CorePlayQueue::getTrack 0 Feb 23 16:36:33 volumio volumio[2649]: verbose: STATE SERVICE {"status":"play","position":0,"seek":113345,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Billie · Don't Have To Say Anything (1984)","artist":"City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Feb 23 16:36:33 volumio volumio[2649]: verbose: CURRENT POSITION 0 Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::syncState stateService play Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::syncState currentStatus play Feb 23 16:36:33 volumio volumio[2649]: info: Received an update from plugin. extracting info from payload Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:36:33 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:36:33 volumio volumio[2649]: info: ------------------------------ 41ms Feb 23 16:36:34 volumio volumio[2649]: info: VolumeController::SetAlsaVolume+ Feb 23 16:36:34 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:36:34 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 16:36:34 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:36:34 volumio volumio[2649]: info: Feb 23 16:36:34 volumio volumio[2649]: ---------------------------- MPD announces state update: mixer Feb 23 16:36:34 volumio volumio[2649]: info: ControllerMpd::getState Feb 23 16:36:34 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand status Feb 23 16:36:34 volumio volumio[2649]: info: Feb 23 16:36:34 volumio volumio[2649]: ---------------------------- MPD announces state update: mixer Feb 23 16:36:34 volumio volumio[2649]: info: ControllerMpd::getState Feb 23 16:36:34 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand status Feb 23 16:36:34 volumio volumio[2649]: info: Feb 23 16:36:34 volumio volumio[2649]: ---------------------------- MPD announces state update: mixer Feb 23 16:36:34 volumio volumio[2649]: info: ControllerMpd::getState Feb 23 16:36:34 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand status Feb 23 16:36:34 volumio volumio[2649]: info: sendMpdCommand status took 3 milliseconds Feb 23 16:36:34 volumio volumio[2649]: info: sendMpdCommand status took 2 milliseconds Feb 23 16:36:34 volumio volumio[2649]: info: sendMpdCommand status took 1 milliseconds Feb 23 16:36:34 volumio volumio[2649]: verbose: ControllerMpd::parseState Feb 23 16:36:34 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 16:36:34 volumio volumio[2649]: verbose: ControllerMpd::parseState Feb 23 16:36:34 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 16:36:34 volumio volumio[2649]: verbose: ControllerMpd::parseState Feb 23 16:36:34 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 16:36:34 volumio volumio[2649]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 23 16:36:34 volumio volumio[2649]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 23 16:36:34 volumio volumio[2649]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 23 16:36:34 volumio volumio[2649]: verbose: ControllerMpd::parseTrackInfo Feb 23 16:36:34 volumio volumio[2649]: verbose: ControllerMpd::parseTrackInfo Feb 23 16:36:34 volumio volumio[2649]: verbose: ControllerMpd::parseTrackInfo Feb 23 16:36:34 volumio volumio[2649]: info: ControllerMpd::pushState Feb 23 16:36:34 volumio volumio[2649]: info: CoreCommandRouter::servicePushState Feb 23 16:36:34 volumio volumio[2649]: info: CorePlayQueue::getTrack 0 Feb 23 16:36:34 volumio volumio[2649]: verbose: STATE SERVICE {"status":"play","position":0,"seek":114602,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Billie · Don't Have To Say Anything (1984)","artist":"City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Feb 23 16:36:34 volumio volumio[2649]: verbose: CURRENT POSITION 0 Feb 23 16:36:34 volumio volumio[2649]: info: CoreStateMachine::syncState stateService play Feb 23 16:36:34 volumio volumio[2649]: info: CoreStateMachine::syncState currentStatus play Feb 23 16:36:34 volumio volumio[2649]: info: Received an update from plugin. extracting info from payload Feb 23 16:36:34 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 16:36:34 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:36:34 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 16:36:34 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:36:34 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:36:34 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:36:34 volumio volumio[2649]: info: ControllerMpd::pushState Feb 23 16:36:34 volumio volumio[2649]: info: CoreCommandRouter::servicePushState Feb 23 16:36:34 volumio volumio[2649]: info: CorePlayQueue::getTrack 0 Feb 23 16:36:34 volumio volumio[2649]: verbose: STATE SERVICE {"status":"play","position":0,"seek":114602,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Billie · Don't Have To Say Anything (1984)","artist":"City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Feb 23 16:36:34 volumio volumio[2649]: verbose: CURRENT POSITION 0 Feb 23 16:36:34 volumio volumio[2649]: info: CoreStateMachine::syncState stateService play Feb 23 16:36:34 volumio volumio[2649]: info: CoreStateMachine::syncState currentStatus play Feb 23 16:36:34 volumio volumio[2649]: info: Received an update from plugin. extracting info from payload Feb 23 16:36:34 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 16:36:34 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:36:34 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:36:34 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:36:34 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:36:34 volumio volumio[2649]: info: ControllerMpd::pushState Feb 23 16:36:34 volumio volumio[2649]: info: CoreCommandRouter::servicePushState Feb 23 16:36:34 volumio volumio[2649]: info: CorePlayQueue::getTrack 0 Feb 23 16:36:34 volumio volumio[2649]: verbose: STATE SERVICE {"status":"play","position":0,"seek":114602,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Billie · Don't Have To Say Anything (1984)","artist":"City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Feb 23 16:36:34 volumio volumio[2649]: verbose: CURRENT POSITION 0 Feb 23 16:36:34 volumio volumio[2649]: info: CoreStateMachine::syncState stateService play Feb 23 16:36:34 volumio volumio[2649]: info: CoreStateMachine::syncState currentStatus play Feb 23 16:36:34 volumio volumio[2649]: info: Received an update from plugin. extracting info from payload Feb 23 16:36:34 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 16:36:34 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:36:34 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:36:34 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:36:34 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:36:34 volumio volumio[2649]: info: ------------------------------ 42ms Feb 23 16:36:34 volumio volumio[2649]: info: ------------------------------ 42ms Feb 23 16:36:34 volumio volumio[2649]: info: ------------------------------ 41ms Feb 23 16:36:34 volumio volumio[2649]: info: VolumeController::SetAlsaVolume+ Feb 23 16:36:34 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:36:34 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 16:36:34 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:36:34 volumio volumio[2649]: info: Feb 23 16:36:34 volumio volumio[2649]: ---------------------------- MPD announces state update: mixer Feb 23 16:36:34 volumio volumio[2649]: info: ControllerMpd::getState Feb 23 16:36:34 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand status Feb 23 16:36:34 volumio volumio[2649]: info: Feb 23 16:36:34 volumio volumio[2649]: ---------------------------- MPD announces state update: mixer Feb 23 16:36:34 volumio volumio[2649]: info: ControllerMpd::getState Feb 23 16:36:34 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand status Feb 23 16:36:34 volumio volumio[2649]: info: sendMpdCommand status took 2 milliseconds Feb 23 16:36:34 volumio volumio[2649]: info: sendMpdCommand status took 1 milliseconds Feb 23 16:36:34 volumio volumio[2649]: verbose: ControllerMpd::parseState Feb 23 16:36:34 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 16:36:34 volumio volumio[2649]: verbose: ControllerMpd::parseState Feb 23 16:36:34 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 16:36:34 volumio volumio[2649]: info: Feb 23 16:36:34 volumio volumio[2649]: ---------------------------- MPD announces state update: mixer Feb 23 16:36:34 volumio volumio[2649]: info: ControllerMpd::getState Feb 23 16:36:34 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand status Feb 23 16:36:34 volumio volumio[2649]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 23 16:36:34 volumio volumio[2649]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 23 16:36:34 volumio volumio[2649]: info: sendMpdCommand status took 1 milliseconds Feb 23 16:36:34 volumio volumio[2649]: verbose: ControllerMpd::parseTrackInfo Feb 23 16:36:34 volumio volumio[2649]: verbose: ControllerMpd::parseTrackInfo Feb 23 16:36:34 volumio volumio[2649]: verbose: ControllerMpd::parseState Feb 23 16:36:34 volumio volumio[2649]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 23 16:36:34 volumio volumio[2649]: info: ControllerMpd::pushState Feb 23 16:36:34 volumio volumio[2649]: info: CoreCommandRouter::servicePushState Feb 23 16:36:34 volumio volumio[2649]: info: CorePlayQueue::getTrack 0 Feb 23 16:36:34 volumio volumio[2649]: verbose: STATE SERVICE {"status":"play","position":0,"seek":114842,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Billie · Don't Have To Say Anything (1984)","artist":"City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Feb 23 16:36:34 volumio volumio[2649]: verbose: CURRENT POSITION 0 Feb 23 16:36:34 volumio volumio[2649]: info: CoreStateMachine::syncState stateService play Feb 23 16:36:34 volumio volumio[2649]: info: CoreStateMachine::syncState currentStatus play Feb 23 16:36:34 volumio volumio[2649]: info: Received an update from plugin. extracting info from payload Feb 23 16:36:34 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 16:36:34 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:36:34 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 16:36:34 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:36:34 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:36:34 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:36:34 volumio volumio[2649]: info: ControllerMpd::pushState Feb 23 16:36:34 volumio volumio[2649]: info: CoreCommandRouter::servicePushState Feb 23 16:36:34 volumio volumio[2649]: info: CorePlayQueue::getTrack 0 Feb 23 16:36:34 volumio volumio[2649]: verbose: STATE SERVICE {"status":"play","position":0,"seek":114842,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Billie · Don't Have To Say Anything (1984)","artist":"City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Feb 23 16:36:34 volumio volumio[2649]: verbose: CURRENT POSITION 0 Feb 23 16:36:34 volumio volumio[2649]: info: CoreStateMachine::syncState stateService play Feb 23 16:36:34 volumio volumio[2649]: info: CoreStateMachine::syncState currentStatus play Feb 23 16:36:34 volumio volumio[2649]: info: Received an update from plugin. extracting info from payload Feb 23 16:36:34 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 16:36:34 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:36:34 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:36:34 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:36:34 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:36:34 volumio volumio[2649]: info: ------------------------------ 26ms Feb 23 16:36:34 volumio volumio[2649]: info: ------------------------------ 27ms Feb 23 16:36:34 volumio volumio[2649]: info: sendMpdCommand playlistinfo took 22 milliseconds Feb 23 16:36:34 volumio volumio[2649]: verbose: ControllerMpd::parseTrackInfo Feb 23 16:36:34 volumio volumio[2649]: info: ControllerMpd::pushState Feb 23 16:36:34 volumio volumio[2649]: info: CoreCommandRouter::servicePushState Feb 23 16:36:34 volumio volumio[2649]: info: CorePlayQueue::getTrack 0 Feb 23 16:36:34 volumio volumio[2649]: verbose: STATE SERVICE {"status":"play","position":0,"seek":114842,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Billie · Don't Have To Say Anything (1984)","artist":"City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Feb 23 16:36:34 volumio volumio[2649]: verbose: CURRENT POSITION 0 Feb 23 16:36:34 volumio volumio[2649]: info: CoreStateMachine::syncState stateService play Feb 23 16:36:34 volumio volumio[2649]: info: CoreStateMachine::syncState currentStatus play Feb 23 16:36:34 volumio volumio[2649]: info: Received an update from plugin. extracting info from payload Feb 23 16:36:34 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 23 16:36:34 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:36:34 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:36:34 volumio volumio[2649]: info: CoreStateMachine::pushState Feb 23 16:36:34 volumio volumio[2649]: info: CoreCommandRouter::volumioPushState Feb 23 16:36:34 volumio volumio[2649]: info: ------------------------------ 36ms Feb 23 16:36:41 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 16:36:41 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 16:36:41 volumio volumio[2649]: info: Discovery: Getting this device information Feb 23 16:36:41 volumio volumio[2649]: info: CoreCommandRouter::volumioGetState Feb 23 16:36:41 volumio volumio[2649]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 16:36:45 volumio volumio[2649]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 16:36:45 volumio volumio[2649]: Error: connect ETIMEDOUT 69.162.134.178:80 Feb 23 16:36:45 volumio volumio[2649]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Feb 23 16:36:45 volumio volumio[2649]: errno: -110, Feb 23 16:36:45 volumio volumio[2649]: code: 'ETIMEDOUT', Feb 23 16:36:45 volumio volumio[2649]: syscall: 'connect', Feb 23 16:36:45 volumio volumio[2649]: address: '69.162.134.178', Feb 23 16:36:45 volumio volumio[2649]: port: 80 Feb 23 16:36:45 volumio volumio[2649]: } Feb 23 16:36:45 volumio volumio[2649]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 16:36:46 volumio sudo[4342]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-23 16:35 Feb 23 16:36:46 volumio sudo[4342]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"