-- Logs begin at Thu 2019-02-14 05:11:58 EST, end at Fri 2024-05-03 11:26:23 EDT. -- May 03 11:25:05 rivo kernel: usb 1-1.2: USB disconnect, device number 7 May 03 11:25:05 rivo kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache May 03 11:25:05 rivo kernel: sd 0:0:0:0: [sda] Synchronize Cache(10) failed: Result: hostbyte=0x01 driverbyte=0x00 May 03 11:25:05 rivo volumio[5861]: info: Partition removed: {"syspath":"/sys/devices/platform/ff500000.dwc3/xhci-hcd.0.auto/usb1/1-1/1-1.2/1-1.2:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","DEVLINKS":"/dev/disk/by-uuid/67E3-17ED /dev/disk/by-partlabel/EFI\\x20System\\x20Partition /dev/disk/by-label/EFI /dev/disk/by-id/ata-CT2000MX500SSD1_1843E1D3CBDC-part1 /dev/disk/by-id/wwn-0x500a0751e1d3cbdc-part1 /dev/disk/by-partuuid/41811dc7-2677-4fd1-905b-1c43176778fd /dev/disk/by-path/platform-xhci-hcd.0.auto-usb-0:1.2:1.0-scsi-0:0:0:0-part1","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/ff500000.dwc3/xhci-hcd.0.auto/usb1/1-1/1-1.2/1-1.2:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","ID_ATA":"1","ID_ATA_DOWNLOAD_MICROCODE":"1","ID_ATA_FEATURE_SET_APM":"1","ID_ATA_FEATURE_SET_APM_CURRENT_VALUE":"254","ID_ATA_FEATURE_SET_APM_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":"2","ID_ATA_FEATURE_SET_SECURITY_ERASE_UNIT_MIN":"2","ID_ATA_FEATURE_SET_SMART":"1","ID_ATA_FEATURE_SET_SMART_ENABLED":"1","ID_ATA_ROTATION_RATE_RPM":"0","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":"CT2000MX500SSD1","ID_MODEL_ENC":"CT2000MX500SSD1\\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: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":"41811dc7-2677-4fd1-905b-1c43176778fd","ID_PART_TABLE_TYPE":"gpt","ID_PART_TABLE_UUID":"f766daa2-28f2-4e5c-b990-1b2151820270","ID_PATH":"platform-xhci-hcd.0.auto-usb-0:1.2:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-xhci-hcd_0_auto-usb-0_1_2_1_0-scsi-0_0_0_0","ID_REVISION":"M3CR022","ID_SERIAL":"CT2000MX500SSD1_1843E1D3CBDC","ID_SERIAL_SHORT":"1843E1D3CBDC","ID_TYPE":"disk","ID_WWN":"0x500a0751e1d3cbdc","ID_WWN_WITH_EXTENSION":"0x500a0751e1d3cbdc","MAJOR":"8","MINOR":"1","PARTN":"1","PARTNAME":"EFI System Partition","SEQNUM":"5014","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"249725720"} May 03 11:25:05 rivo sudo[7441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda1 May 03 11:25:05 rivo sudo[7441]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 11:25:05 rivo systemd[1]: media-EFI.mount: Succeeded. May 03 11:25:05 rivo sudo[7441]: pam_unix(sudo:session): session closed for user root May 03 11:25:05 rivo volumio[5861]: info: Partition removed: {"syspath":"/sys/devices/platform/ff500000.dwc3/xhci-hcd.0.auto/usb1/1-1/1-1.2/1-1.2:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","ACTION":"remove","DEVLINKS":"/dev/disk/by-partlabel/MusiqueNew /dev/disk/by-id/ata-CT2000MX500SSD1_1843E1D3CBDC-part2 /dev/disk/by-path/platform-xhci-hcd.0.auto-usb-0:1.2:1.0-scsi-0:0:0:0-part2 /dev/disk/by-uuid/d38ca18c-6f0e-36e7-8bb1-4e3a5ec1a081 /dev/disk/by-label/MusiqueNew /dev/disk/by-id/wwn-0x500a0751e1d3cbdc-part2 /dev/disk/by-partuuid/69407635-7d73-451a-bfad-ac53a38b9ad1","DEVNAME":"/dev/sda2","DEVPATH":"/devices/platform/ff500000.dwc3/xhci-hcd.0.auto/usb1/1-1/1-1.2/1-1.2:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","DEVTYPE":"partition","ID_ATA":"1","ID_ATA_DOWNLOAD_MICROCODE":"1","ID_ATA_FEATURE_SET_APM":"1","ID_ATA_FEATURE_SET_APM_CURRENT_VALUE":"254","ID_ATA_FEATURE_SET_APM_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":"2","ID_ATA_FEATURE_SET_SECURITY_ERASE_UNIT_MIN":"2","ID_ATA_FEATURE_SET_SMART":"1","ID_ATA_FEATURE_SET_SMART_ENABLED":"1","ID_ATA_ROTATION_RATE_RPM":"0","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":"MusiqueNew","ID_FS_LABEL_ENC":"MusiqueNew","ID_FS_TYPE":"hfsplus","ID_FS_USAGE":"filesystem","ID_FS_UUID":"d38ca18c-6f0e-36e7-8bb1-4e3a5ec1a081","ID_FS_UUID_ENC":"d38ca18c-6f0e-36e7-8bb1-4e3a5ec1a081","ID_MODEL":"CT2000MX500SSD1","ID_MODEL_ENC":"CT2000MX500SSD1\\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:0","ID_PART_ENTRY_NAME":"MusiqueNew","ID_PART_ENTRY_NUMBER":"2","ID_PART_ENTRY_OFFSET":"409640","ID_PART_ENTRY_SCHEME":"gpt","ID_PART_ENTRY_SIZE":"3906357344","ID_PART_ENTRY_TYPE":"48465300-0000-11aa-aa11-00306543ecac","ID_PART_ENTRY_UUID":"69407635-7d73-451a-bfad-ac53a38b9ad1","ID_PART_TABLE_TYPE":"gpt","ID_PART_TABLE_UUID":"f766daa2-28f2-4e5c-b990-1b2151820270","ID_PATH":"platform-xhci-hcd.0.auto-usb-0:1.2:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-xhci-hcd_0_auto-usb-0_1_2_1_0-scsi-0_0_0_0","ID_REVISION":"M3CR022","ID_SERIAL":"CT2000MX500SSD1_1843E1D3CBDC","ID_SERIAL_SHORT":"1843E1D3CBDC","ID_TYPE":"disk","ID_WWN":"0x500a0751e1d3cbdc","ID_WWN_WITH_EXTENSION":"0x500a0751e1d3cbdc","MAJOR":"8","MINOR":"2","PARTN":"2","PARTNAME":"MusiqueNew","SEQNUM":"5013","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"249726454"} May 03 11:25:05 rivo sudo[7444]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda2 May 03 11:25:05 rivo sudo[7444]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 11:25:05 rivo sudo[7444]: pam_unix(sudo:session): session closed for user root May 03 11:25:05 rivo volumio[5861]: umount: /dev/sda2: no mount point specified. May 03 11:25:05 rivo volumio[5861]: error: Failed to umount MusiqueNew: Error: Command failed: /usr/bin/sudo /bin/umount -f "/dev/sda2" May 03 11:25:05 rivo volumio[5861]: umount: /dev/sda2: no mount point specified. May 03 11:25:06 rivo ntpd[5849]: 142.4.192.253 local addr 192.168.0.101 -> May 03 11:25:08 rivo kernel: usb 1-1.1: new high-speed USB device number 8 using xhci-hcd May 03 11:25:08 rivo kernel: usb 1-1.1: New USB device found, idVendor=059f, idProduct=1075 May 03 11:25:08 rivo kernel: usb 1-1.1: New USB device strings: Mfr=2, Product=3, SerialNumber=1 May 03 11:25:08 rivo kernel: usb 1-1.1: Product: Rugged THB USB 3.0 May 03 11:25:08 rivo kernel: usb 1-1.1: Manufacturer: LaCie May 03 11:25:08 rivo kernel: usb 1-1.1: SerialNumber: 0000NL31DSKC May 03 11:25:08 rivo kernel: usb-storage 1-1.1:1.0: USB Mass Storage device detected May 03 11:25:08 rivo kernel: scsi host0: usb-storage 1-1.1:1.0 May 03 11:25:09 rivo volumio[5861]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/EFI May 03 11:25:09 rivo kernel: scsi 0:0:0:0: Direct-Access LaCie Rugged THB USB3 1153 PQ: 0 ANSI: 6 May 03 11:25:09 rivo kernel: sd 0:0:0:0: [sda] 3907029168 512-byte logical blocks: (2.00 TB/1.82 TiB) May 03 11:25:09 rivo kernel: sd 0:0:0:0: [sda] Write Protect is off May 03 11:25:09 rivo kernel: sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00 May 03 11:25:09 rivo kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA May 03 11:25:09 rivo kernel: sda: sda1 sda2 May 03 11:25:09 rivo kernel: sd 0:0:0:0: [sda] Attached SCSI disk May 03 11:25:10 rivo volumio[5861]: info: Mounting Device EFI May 03 11:25:10 rivo sudo[7476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/EFI -o noatime,dmask=0000,fmask=0000,iocharset=utf8 May 03 11:25:10 rivo sudo[7476]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 11:25:10 rivo kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! May 03 11:25:10 rivo sudo[7476]: pam_unix(sudo:session): session closed for user root May 03 11:25:10 rivo kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. May 03 11:25:10 rivo volumio[5861]: info: Mounting Device MusiqueNew May 03 11:25:10 rivo sudo[7482]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda2 /mnt/USB/MusiqueNew -o noatime May 03 11:25:10 rivo sudo[7482]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 11:25:10 rivo sudo[7482]: pam_unix(sudo:session): session closed for user root May 03 11:25:10 rivo volumio[5861]: mount: /media/MusiqueNew: unknown filesystem type 'hfsplus'. May 03 11:25:10 rivo volumio[5861]: error: Failed to mount MusiqueNew: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda2" "/mnt/USB/MusiqueNew" -o noatime May 03 11:25:10 rivo volumio[5861]: mount: /media/MusiqueNew: unknown filesystem type 'hfsplus'. May 03 11:25:10 rivo volumio[5861]: info: Scanning new location : "USB/EFI" May 03 11:25:10 rivo volumio[5861]: verbose: ControllerMpd::sendMpdCommand status May 03 11:25:10 rivo volumio[5861]: info: May 03 11:25:10 rivo volumio[5861]: ---------------------------- MPD announces state update: update May 03 11:25:10 rivo volumio[5861]: info: ControllerMpd::getState May 03 11:25:10 rivo volumio[5861]: verbose: ControllerMpd::sendMpdCommand status May 03 11:25:10 rivo volumio[5861]: verbose: ControllerMpd::sendMpdCommand status May 03 11:25:10 rivo volumio[5861]: info: May 03 11:25:10 rivo volumio[5861]: ---------------------------- MPD announces state update: update May 03 11:25:10 rivo volumio[5861]: info: ControllerMpd::getState May 03 11:25:10 rivo volumio[5861]: verbose: ControllerMpd::sendMpdCommand status May 03 11:25:10 rivo volumio[5861]: verbose: ControllerMpd::sendMpdCommand status May 03 11:25:10 rivo volumio[5861]: info: May 03 11:25:10 rivo volumio[5861]: ---------------------------- MPD announces state update: update May 03 11:25:10 rivo volumio[5861]: info: ControllerMpd::getState May 03 11:25:10 rivo volumio[5861]: verbose: ControllerMpd::sendMpdCommand status May 03 11:25:10 rivo volumio[5861]: verbose: ControllerMpd::sendMpdCommand status May 03 11:25:10 rivo volumio[5861]: info: May 03 11:25:10 rivo volumio[5861]: ---------------------------- MPD announces state update: update May 03 11:25:10 rivo volumio[5861]: info: ControllerMpd::getState May 03 11:25:10 rivo volumio[5861]: verbose: ControllerMpd::sendMpdCommand status May 03 11:25:10 rivo volumio[5861]: info: sendMpdCommand status took 21 milliseconds May 03 11:25:10 rivo volumio[5861]: info: sendMpdCommand status took 19 milliseconds May 03 11:25:10 rivo volumio[5861]: info: sendMpdCommand status took 18 milliseconds May 03 11:25:10 rivo volumio[5861]: info: sendMpdCommand status took 17 milliseconds May 03 11:25:10 rivo volumio[5861]: verbose: ControllerMpd::parseState May 03 11:25:10 rivo volumio[5861]: info: Command Router : Notfying DB Updatefalse May 03 11:25:10 rivo volumio[5861]: info: CoreCommandRouter::Close All Modals sent May 03 11:25:10 rivo volumio[5861]: verbose: ControllerMpd::parseState May 03 11:25:10 rivo volumio[5861]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 11:25:10 rivo volumio[5861]: verbose: ControllerMpd::parseState May 03 11:25:10 rivo volumio[5861]: info: Command Router : Notfying DB Updatefalse May 03 11:25:10 rivo volumio[5861]: info: CoreCommandRouter::Close All Modals sent May 03 11:25:10 rivo volumio[5861]: verbose: ControllerMpd::parseState May 03 11:25:10 rivo volumio[5861]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 11:25:10 rivo volumio[5861]: verbose: ControllerMpd::sendMpdCommand status May 03 11:25:10 rivo volumio[5861]: info: May 03 11:25:10 rivo volumio[5861]: ---------------------------- MPD announces state update: update May 03 11:25:10 rivo volumio[5861]: info: ControllerMpd::getState May 03 11:25:10 rivo volumio[5861]: verbose: ControllerMpd::sendMpdCommand status May 03 11:25:10 rivo volumio[5861]: info: sendMpdCommand status took 109 milliseconds May 03 11:25:10 rivo volumio[5861]: info: sendMpdCommand status took 100 milliseconds May 03 11:25:10 rivo volumio[5861]: info: sendMpdCommand status took 98 milliseconds May 03 11:25:10 rivo volumio[5861]: info: sendMpdCommand status took 97 milliseconds May 03 11:25:10 rivo volumio[5861]: info: sendMpdCommand playlistinfo took 52 milliseconds May 03 11:25:10 rivo volumio[5861]: info: sendMpdCommand playlistinfo took 18 milliseconds May 03 11:25:10 rivo volumio[5861]: info: sendMpdCommand status took 8 milliseconds May 03 11:25:10 rivo volumio[5861]: info: sendMpdCommand status took 6 milliseconds May 03 11:25:10 rivo volumio[5861]: verbose: ControllerMpd::parseState May 03 11:25:10 rivo volumio[5861]: info: Command Router : Notfying DB Updatefalse May 03 11:25:10 rivo volumio[5861]: info: CoreCommandRouter::Close All Modals sent May 03 11:25:10 rivo volumio[5861]: verbose: ControllerMpd::parseState May 03 11:25:10 rivo volumio[5861]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 11:25:10 rivo volumio[5861]: verbose: ControllerMpd::parseState May 03 11:25:10 rivo volumio[5861]: info: Command Router : Notfying DB Updatefalse May 03 11:25:10 rivo volumio[5861]: info: CoreCommandRouter::Close All Modals sent May 03 11:25:10 rivo volumio[5861]: verbose: ControllerMpd::parseState May 03 11:25:10 rivo volumio[5861]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 11:25:10 rivo volumio[5861]: verbose: ControllerMpd::parseTrackInfo May 03 11:25:10 rivo volumio[5861]: verbose: ControllerMpd::parseTrackInfo May 03 11:25:10 rivo volumio[5861]: verbose: ControllerMpd::parseState May 03 11:25:10 rivo volumio[5861]: info: Command Router : Notfying DB Updatefalse May 03 11:25:10 rivo volumio[5861]: info: CoreCommandRouter::Close All Modals sent May 03 11:25:10 rivo volumio[5861]: verbose: ControllerMpd::parseState May 03 11:25:10 rivo volumio[5861]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 11:25:10 rivo volumio[5861]: info: ControllerMpd::pushState May 03 11:25:10 rivo volumio[5861]: info: CoreCommandRouter::servicePushState May 03 11:25:10 rivo volumio[5861]: info: CoreStateMachine::pushState May 03 11:25:10 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:25:10 rivo volumio[5861]: info: CoreCommandRouter::volumioPushState May 03 11:25:10 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:25:10 rivo volumio[5861]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"27258510","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/27258510","trackType":"qobuz"} May 03 11:25:10 rivo volumio[5861]: verbose: CURRENT POSITION 0 May 03 11:25:10 rivo volumio[5861]: info: CoreStateMachine::syncState stateService stop May 03 11:25:10 rivo volumio[5861]: info: CoreStateMachine::syncState currentStatus stop May 03 11:25:10 rivo volumio[5861]: info: CoreStateMachine::pushState May 03 11:25:10 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:25:10 rivo volumio[5861]: info: CoreCommandRouter::volumioPushState May 03 11:25:10 rivo volumio[5861]: info: No code May 03 11:25:10 rivo volumio[5861]: info: CoreStateMachine::pushState May 03 11:25:10 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:25:10 rivo volumio[5861]: info: CoreCommandRouter::volumioPushState May 03 11:25:10 rivo volumio[5861]: info: ControllerMpd::pushState May 03 11:25:10 rivo volumio[5861]: info: CoreCommandRouter::servicePushState May 03 11:25:10 rivo volumio[5861]: info: CoreStateMachine::pushState May 03 11:25:10 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:25:10 rivo volumio[5861]: info: CoreCommandRouter::volumioPushState May 03 11:25:10 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:25:10 rivo volumio[5861]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"27258510","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/27258510","trackType":"qobuz"} May 03 11:25:10 rivo volumio[5861]: verbose: CURRENT POSITION 0 May 03 11:25:10 rivo volumio[5861]: info: CoreStateMachine::syncState stateService stop May 03 11:25:10 rivo volumio[5861]: info: CoreStateMachine::syncState currentStatus stop May 03 11:25:10 rivo volumio[5861]: info: CoreStateMachine::pushState May 03 11:25:10 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:25:10 rivo volumio[5861]: info: CoreCommandRouter::volumioPushState May 03 11:25:10 rivo volumio[5861]: info: No code May 03 11:25:10 rivo volumio[5861]: info: CoreStateMachine::pushState May 03 11:25:10 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:25:10 rivo volumio[5861]: info: CoreCommandRouter::volumioPushState May 03 11:25:10 rivo volumio[5861]: info: ------------------------------ 289ms May 03 11:25:10 rivo volumio[5861]: info: ------------------------------ 287ms May 03 11:25:10 rivo volumio[5861]: verbose: ControllerMpd::sendMpdCommand status May 03 11:25:10 rivo volumio[5861]: info: May 03 11:25:10 rivo volumio[5861]: ---------------------------- MPD announces state update: update May 03 11:25:10 rivo volumio[5861]: info: ControllerMpd::getState May 03 11:25:10 rivo volumio[5861]: verbose: ControllerMpd::sendMpdCommand status May 03 11:25:10 rivo volumio[5861]: info: sendMpdCommand playlistinfo took 190 milliseconds May 03 11:25:10 rivo volumio[5861]: info: sendMpdCommand playlistinfo took 158 milliseconds May 03 11:25:10 rivo volumio[5861]: info: sendMpdCommand playlistinfo took 125 milliseconds May 03 11:25:10 rivo volumio[5861]: info: sendMpdCommand status took 55 milliseconds May 03 11:25:10 rivo volumio[5861]: info: sendMpdCommand status took 53 milliseconds May 03 11:25:10 rivo volumio[5861]: verbose: ControllerMpd::parseTrackInfo May 03 11:25:10 rivo volumio[5861]: verbose: ControllerMpd::parseTrackInfo May 03 11:25:10 rivo volumio[5861]: verbose: ControllerMpd::parseTrackInfo May 03 11:25:10 rivo volumio[5861]: verbose: ControllerMpd::parseState May 03 11:25:10 rivo volumio[5861]: info: Command Router : Notfying DB Updatefalse May 03 11:25:10 rivo volumio[5861]: info: CoreCommandRouter::Close All Modals sent May 03 11:25:10 rivo volumio[5861]: verbose: ControllerMpd::parseState May 03 11:25:10 rivo volumio[5861]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 11:25:10 rivo volumio[5861]: info: ControllerMpd::pushState May 03 11:25:10 rivo volumio[5861]: info: CoreCommandRouter::servicePushState May 03 11:25:10 rivo volumio[5861]: info: CoreStateMachine::pushState May 03 11:25:10 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:25:10 rivo volumio[5861]: info: CoreCommandRouter::volumioPushState May 03 11:25:10 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:25:10 rivo volumio[5861]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"27258510","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/27258510","trackType":"qobuz"} May 03 11:25:10 rivo volumio[5861]: verbose: CURRENT POSITION 0 May 03 11:25:10 rivo volumio[5861]: info: CoreStateMachine::syncState stateService stop May 03 11:25:10 rivo volumio[5861]: info: CoreStateMachine::syncState currentStatus stop May 03 11:25:10 rivo volumio[5861]: info: CoreStateMachine::pushState May 03 11:25:10 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:25:10 rivo volumio[5861]: info: CoreCommandRouter::volumioPushState May 03 11:25:10 rivo volumio[5861]: info: No code May 03 11:25:10 rivo volumio[5861]: info: CoreStateMachine::pushState May 03 11:25:10 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:25:10 rivo volumio[5861]: info: CoreCommandRouter::volumioPushState May 03 11:25:10 rivo volumio[5861]: info: ControllerMpd::pushState May 03 11:25:10 rivo volumio[5861]: info: CoreCommandRouter::servicePushState May 03 11:25:10 rivo volumio[5861]: info: CoreStateMachine::pushState May 03 11:25:10 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:25:10 rivo volumio[5861]: info: CoreCommandRouter::volumioPushState May 03 11:25:10 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:25:10 rivo volumio[5861]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"27258510","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/27258510","trackType":"qobuz"} May 03 11:25:10 rivo volumio[5861]: verbose: CURRENT POSITION 0 May 03 11:25:10 rivo volumio[5861]: info: CoreStateMachine::syncState stateService stop May 03 11:25:10 rivo volumio[5861]: info: CoreStateMachine::syncState currentStatus stop May 03 11:25:10 rivo volumio[5861]: info: CoreStateMachine::pushState May 03 11:25:10 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:25:10 rivo volumio[5861]: info: CoreCommandRouter::volumioPushState May 03 11:25:10 rivo volumio[5861]: info: No code May 03 11:25:10 rivo volumio[5861]: info: CoreStateMachine::pushState May 03 11:25:10 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:25:10 rivo volumio[5861]: info: CoreCommandRouter::volumioPushState May 03 11:25:10 rivo volumio[5861]: info: ControllerMpd::pushState May 03 11:25:10 rivo volumio[5861]: info: CoreCommandRouter::servicePushState May 03 11:25:10 rivo volumio[5861]: info: CoreStateMachine::pushState May 03 11:25:10 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:25:10 rivo volumio[5861]: info: CoreCommandRouter::volumioPushState May 03 11:25:10 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:25:10 rivo volumio[5861]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"27258510","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/27258510","trackType":"qobuz"} May 03 11:25:10 rivo volumio[5861]: verbose: CURRENT POSITION 0 May 03 11:25:10 rivo volumio[5861]: info: CoreStateMachine::syncState stateService stop May 03 11:25:10 rivo volumio[5861]: info: CoreStateMachine::syncState currentStatus stop May 03 11:25:10 rivo volumio[5861]: info: CoreStateMachine::pushState May 03 11:25:10 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:25:10 rivo volumio[5861]: info: CoreCommandRouter::volumioPushState May 03 11:25:10 rivo volumio[5861]: info: No code May 03 11:25:10 rivo volumio[5861]: info: CoreStateMachine::pushState May 03 11:25:10 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:25:10 rivo volumio[5861]: info: CoreCommandRouter::volumioPushState May 03 11:25:11 rivo volumio[5861]: info: ------------------------------ 481ms May 03 11:25:11 rivo volumio[5861]: info: ------------------------------ 470ms May 03 11:25:11 rivo volumio[5861]: info: ------------------------------ 379ms May 03 11:25:11 rivo volumio[5861]: info: sendMpdCommand playlistinfo took 106 milliseconds May 03 11:25:11 rivo volumio[5861]: verbose: ControllerMpd::parseTrackInfo May 03 11:25:11 rivo volumio[5861]: info: ControllerMpd::pushState May 03 11:25:11 rivo volumio[5861]: info: CoreCommandRouter::servicePushState May 03 11:25:11 rivo volumio[5861]: info: CoreStateMachine::pushState May 03 11:25:11 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:25:11 rivo volumio[5861]: info: CoreCommandRouter::volumioPushState May 03 11:25:11 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:25:11 rivo volumio[5861]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"27258510","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/27258510","trackType":"qobuz"} May 03 11:25:11 rivo volumio[5861]: verbose: CURRENT POSITION 0 May 03 11:25:11 rivo volumio[5861]: info: CoreStateMachine::syncState stateService stop May 03 11:25:11 rivo volumio[5861]: info: CoreStateMachine::syncState currentStatus stop May 03 11:25:11 rivo volumio[5861]: info: CoreStateMachine::pushState May 03 11:25:11 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:25:11 rivo volumio[5861]: info: CoreCommandRouter::volumioPushState May 03 11:25:11 rivo volumio[5861]: info: No code May 03 11:25:11 rivo volumio[5861]: info: CoreStateMachine::pushState May 03 11:25:11 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:25:11 rivo volumio[5861]: info: CoreCommandRouter::volumioPushState May 03 11:25:11 rivo volumio[5861]: info: ------------------------------ 234ms May 03 11:25:12 rivo ntpd[5849]: 158.69.20.38 local addr 192.168.0.101 -> May 03 11:25:16 rivo volumio[5861]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 03 11:25:16 rivo volumio[5861]: info: CURURI: music-library/USB May 03 11:25:17 rivo volumio[5861]: info: Preload queue cleared May 03 11:25:18 rivo volumio[5861]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 03 11:25:18 rivo volumio[5861]: info: CURURI: music-library/USB/EFI May 03 11:25:18 rivo volumio[5861]: error: Failed LSINFO: null May 03 11:25:18 rivo volumio[5861]: info: Preload queue cleared May 03 11:25:21 rivo volumio[5861]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 03 11:25:21 rivo volumio[5861]: info: CURURI: music-library May 03 11:25:21 rivo volumio[5861]: info: Preload queue cleared May 03 11:25:28 rivo volumio[5861]: info: CoreCommandRouter::volumioAddQueueItems May 03 11:25:28 rivo volumio[5861]: info: CoreStateMachine::addQueueItems May 03 11:25:28 rivo volumio[5861]: info: CorePlayQueue::addQueueItems May 03 11:25:28 rivo volumio[5861]: info: Preload queue cleared May 03 11:25:28 rivo volumio[5861]: info: Adding Item to queue: music-library/USB May 03 11:25:28 rivo volumio[5861]: info: Using cached record of: music-library/USB May 03 11:25:28 rivo volumio[5861]: info: CoreCommandRouter::volumioPushQueue May 03 11:25:28 rivo volumio[5861]: info: CorePlayQueue::saveQueue May 03 11:25:28 rivo volumio[5861]: info: CoreStateMachine::updateTrackBlock May 03 11:25:28 rivo volumio[5861]: info: CorePlayQueue::getTrackBlock May 03 11:25:35 rivo volumio[5861]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 03 11:25:35 rivo volumio[5861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 03 11:25:35 rivo volumio[5861]: info: Discovery: Getting this device information May 03 11:25:35 rivo volumio[5861]: info: CoreCommandRouter::volumioGetState May 03 11:25:35 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:25:35 rivo volumio[5861]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 03 11:25:35 rivo volumio[5861]: verbose: New Socket.io Connection to 192.168.0.101 from 192.168.0.149 UA: unknown Total Clients: 8 May 03 11:25:35 rivo volumio[5861]: info: CoreCommandRouter::volumioGetState May 03 11:25:35 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:25:35 rivo volumio[5861]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 03 11:25:35 rivo volumio[5861]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 03 11:25:39 rivo volumio[5861]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 03 11:25:39 rivo volumio[5861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 03 11:25:39 rivo volumio[5861]: info: Discovery: Getting this device information May 03 11:25:39 rivo volumio[5861]: info: CoreCommandRouter::volumioGetState May 03 11:25:39 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:25:39 rivo volumio[5861]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 03 11:25:41 rivo volumio[5861]: verbose: New Socket.io Connection to 192.168.0.166 from 192.168.0.149 UA: unknown Total Clients: 9 May 03 11:25:41 rivo volumio[5861]: info: CoreCommandRouter::volumioGetState May 03 11:25:41 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:25:41 rivo volumio[5861]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 03 11:25:41 rivo volumio[5861]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 03 11:25:46 rivo volumio[5861]: info: Preload queue cleared May 03 11:25:50 rivo volumio[5861]: info: CoreCommandRouter::volumioPlay May 03 11:25:50 rivo volumio[5861]: info: CoreStateMachine::play index undefined May 03 11:25:50 rivo volumio[5861]: info: CoreStateMachine::setConsumeUpdateService undefined May 03 11:25:50 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:26:00 rivo volumio[5861]: info: CoreCommandRouter::getUIConfigOnPlugin May 03 11:26:00 rivo volumio[5861]: info: CoreCommandRouter::getUIConfigOnPlugin May 03 11:26:00 rivo volumio[5861]: info: Retrieving Cloud Streaming UI May 03 11:26:00 rivo volumio[5861]: info: Getting Tidal Cloud Configuration May 03 11:26:00 rivo volumio[5861]: info: CoreCommandRouter::getUIConfigOnPlugin May 03 11:26:00 rivo volumio[5861]: info: Getting Qobuz Cloud Configuration May 03 11:26:00 rivo volumio[5861]: info: Asking plugin for UI Config May 03 11:26:00 rivo volumio[5861]: info: CoreCommandRouter::getUIConfigOnPlugin May 03 11:26:00 rivo volumio[5861]: info: Getting Spotify Cloud Configuration May 03 11:26:00 rivo volumio[5861]: info: Asking plugin for UI Config May 03 11:26:00 rivo volumio[5861]: info: CoreCommandRouter::getUIConfigOnPlugin May 03 11:26:00 rivo volumio[5861]: info: Saving Spotify Acccount May 03 11:26:00 rivo volumio[5861]: info: Got it May 03 11:26:00 rivo volumio[5861]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 03 11:26:00 rivo volumio[5861]: info: Got Tidal Cloud Configuration May 03 11:26:00 rivo volumio[5861]: info: Got it May 03 11:26:00 rivo volumio[5861]: info: CoreCommandRouter::getUIConfigOnPlugin May 03 11:26:00 rivo volumio[5861]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 03 11:26:00 rivo volumio[5861]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 03 11:26:00 rivo volumio[5861]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 03 11:26:00 rivo volumio[5861]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 03 11:26:00 rivo volumio[5861]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 03 11:26:00 rivo volumio[5861]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 03 11:26:00 rivo volumio[5861]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 03 11:26:00 rivo volumio[5861]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 03 11:26:00 rivo volumio[5861]: info: CoreCommandRouter::volumioGetBrowseSources May 03 11:26:00 rivo volumio[5861]: info: CoreCommandRouter::volumioGetBrowseSources May 03 11:26:00 rivo volumio[5861]: info: CoreCommandRouter::volumioGetBrowseSources May 03 11:26:00 rivo volumio[5861]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 03 11:26:00 rivo volumio[5861]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 03 11:26:04 rivo volumio[5861]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 03 11:26:05 rivo volumio[5861]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb May 03 11:26:05 rivo volumio[5861]: verbose: ControllerMpd::sendMpdCommand update May 03 11:26:05 rivo volumio[5861]: verbose: ControllerMpd::sendMpdCommand status May 03 11:26:05 rivo volumio[5861]: info: May 03 11:26:05 rivo volumio[5861]: ---------------------------- MPD announces state update: update May 03 11:26:05 rivo volumio[5861]: info: sendMpdCommand update took 7 milliseconds May 03 11:26:05 rivo volumio[5861]: info: ControllerMpd::getState May 03 11:26:05 rivo volumio[5861]: verbose: ControllerMpd::sendMpdCommand status May 03 11:26:05 rivo volumio[5861]: verbose: ControllerMpd::sendMpdCommand status May 03 11:26:05 rivo volumio[5861]: info: May 03 11:26:05 rivo volumio[5861]: ---------------------------- MPD announces state update: update May 03 11:26:05 rivo volumio[5861]: info: ControllerMpd::getState May 03 11:26:05 rivo volumio[5861]: verbose: ControllerMpd::sendMpdCommand status May 03 11:26:05 rivo volumio[5861]: verbose: ControllerMpd::sendMpdCommand status May 03 11:26:05 rivo volumio[5861]: info: May 03 11:26:05 rivo volumio[5861]: ---------------------------- MPD announces state update: update May 03 11:26:05 rivo volumio[5861]: info: ControllerMpd::getState May 03 11:26:05 rivo volumio[5861]: verbose: ControllerMpd::sendMpdCommand status May 03 11:26:05 rivo volumio[5861]: info: sendMpdCommand status took 12 milliseconds May 03 11:26:05 rivo volumio[5861]: info: sendMpdCommand status took 10 milliseconds May 03 11:26:05 rivo volumio[5861]: info: sendMpdCommand status took 10 milliseconds May 03 11:26:05 rivo volumio[5861]: info: sendMpdCommand status took 7 milliseconds May 03 11:26:05 rivo volumio[5861]: info: sendMpdCommand status took 7 milliseconds May 03 11:26:05 rivo volumio[5861]: info: sendMpdCommand status took 5 milliseconds May 03 11:26:05 rivo volumio[5861]: verbose: ControllerMpd::parseState May 03 11:26:05 rivo volumio[5861]: info: Command Router : Notfying DB Updatetrue May 03 11:26:05 rivo volumio[5861]: info: CoreCommandRouter::Close All Modals sent May 03 11:26:05 rivo volumio[5861]: verbose: ControllerMpd::parseState May 03 11:26:05 rivo volumio[5861]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 11:26:05 rivo volumio[5861]: verbose: ControllerMpd::parseState May 03 11:26:05 rivo volumio[5861]: info: Command Router : Notfying DB Updatetrue May 03 11:26:05 rivo volumio[5861]: info: CoreCommandRouter::Close All Modals sent May 03 11:26:05 rivo volumio[5861]: verbose: ControllerMpd::parseState May 03 11:26:05 rivo volumio[5861]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 11:26:05 rivo volumio[5861]: verbose: ControllerMpd::parseState May 03 11:26:05 rivo volumio[5861]: info: Command Router : Notfying DB Updatetrue May 03 11:26:05 rivo volumio[5861]: info: CoreCommandRouter::Close All Modals sent May 03 11:26:05 rivo volumio[5861]: verbose: ControllerMpd::parseState May 03 11:26:05 rivo volumio[5861]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 11:26:05 rivo volumio[5861]: verbose: ControllerMpd::sendMpdCommand status May 03 11:26:05 rivo volumio[5861]: info: May 03 11:26:05 rivo volumio[5861]: ---------------------------- MPD announces state update: update May 03 11:26:05 rivo volumio[5861]: info: ControllerMpd::getState May 03 11:26:05 rivo volumio[5861]: verbose: ControllerMpd::sendMpdCommand status May 03 11:26:05 rivo volumio[5861]: info: sendMpdCommand playlistinfo took 89 milliseconds May 03 11:26:05 rivo volumio[5861]: info: sendMpdCommand playlistinfo took 53 milliseconds May 03 11:26:05 rivo volumio[5861]: info: sendMpdCommand playlistinfo took 22 milliseconds May 03 11:26:05 rivo volumio[5861]: verbose: ControllerMpd::parseTrackInfo May 03 11:26:05 rivo volumio[5861]: verbose: ControllerMpd::parseTrackInfo May 03 11:26:05 rivo volumio[5861]: verbose: ControllerMpd::parseTrackInfo May 03 11:26:05 rivo volumio[5861]: info: ControllerMpd::pushState May 03 11:26:05 rivo volumio[5861]: info: CoreCommandRouter::servicePushState May 03 11:26:05 rivo volumio[5861]: info: CoreStateMachine::pushState May 03 11:26:05 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:26:05 rivo volumio[5861]: info: CoreCommandRouter::volumioPushState May 03 11:26:05 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:26:05 rivo volumio[5861]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"27258510","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/27258510","trackType":"qobuz"} May 03 11:26:05 rivo volumio[5861]: verbose: CURRENT POSITION 0 May 03 11:26:05 rivo volumio[5861]: info: CoreStateMachine::syncState stateService stop May 03 11:26:05 rivo volumio[5861]: info: CoreStateMachine::syncState currentStatus stop May 03 11:26:05 rivo volumio[5861]: info: CoreStateMachine::pushState May 03 11:26:05 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:26:05 rivo volumio[5861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 03 11:26:05 rivo volumio[5861]: info: CoreCommandRouter::volumioPushState May 03 11:26:05 rivo volumio[5861]: info: No code May 03 11:26:05 rivo volumio[5861]: info: CoreStateMachine::pushState May 03 11:26:05 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:26:05 rivo volumio[5861]: info: CoreCommandRouter::volumioPushState May 03 11:26:05 rivo volumio[5861]: info: ControllerMpd::pushState May 03 11:26:05 rivo volumio[5861]: info: CoreCommandRouter::servicePushState May 03 11:26:05 rivo volumio[5861]: info: CoreStateMachine::pushState May 03 11:26:05 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:26:05 rivo volumio[5861]: info: CoreCommandRouter::volumioPushState May 03 11:26:05 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:26:05 rivo volumio[5861]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"27258510","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/27258510","trackType":"qobuz"} May 03 11:26:05 rivo volumio[5861]: verbose: CURRENT POSITION 0 May 03 11:26:05 rivo volumio[5861]: info: CoreStateMachine::syncState stateService stop May 03 11:26:05 rivo volumio[5861]: info: CoreStateMachine::syncState currentStatus stop May 03 11:26:05 rivo volumio[5861]: info: CoreStateMachine::pushState May 03 11:26:05 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:26:05 rivo volumio[5861]: info: CoreCommandRouter::volumioPushState May 03 11:26:05 rivo volumio[5861]: info: No code May 03 11:26:05 rivo volumio[5861]: info: CoreStateMachine::pushState May 03 11:26:05 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:26:05 rivo volumio[5861]: info: CoreCommandRouter::volumioPushState May 03 11:26:05 rivo volumio[5861]: info: ControllerMpd::pushState May 03 11:26:05 rivo volumio[5861]: info: CoreCommandRouter::servicePushState May 03 11:26:05 rivo volumio[5861]: info: CoreStateMachine::pushState May 03 11:26:05 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:26:05 rivo volumio[5861]: info: CoreCommandRouter::volumioPushState May 03 11:26:05 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:26:05 rivo volumio[5861]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"27258510","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/27258510","trackType":"qobuz"} May 03 11:26:05 rivo volumio[5861]: verbose: CURRENT POSITION 0 May 03 11:26:05 rivo volumio[5861]: info: CoreStateMachine::syncState stateService stop May 03 11:26:05 rivo volumio[5861]: info: CoreStateMachine::syncState currentStatus stop May 03 11:26:05 rivo volumio[5861]: info: CoreStateMachine::pushState May 03 11:26:05 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:26:05 rivo volumio[5861]: info: CoreCommandRouter::volumioPushState May 03 11:26:05 rivo volumio[5861]: info: No code May 03 11:26:05 rivo volumio[5861]: info: CoreStateMachine::pushState May 03 11:26:05 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:26:05 rivo volumio[5861]: info: CoreCommandRouter::volumioPushState May 03 11:26:05 rivo volumio[5861]: info: ------------------------------ 239ms May 03 11:26:05 rivo volumio[5861]: info: ------------------------------ 236ms May 03 11:26:05 rivo volumio[5861]: info: ------------------------------ 234ms May 03 11:26:05 rivo volumio[5861]: verbose: ControllerMpd::sendMpdCommand status May 03 11:26:05 rivo volumio[5861]: info: May 03 11:26:05 rivo volumio[5861]: ---------------------------- MPD announces state update: update May 03 11:26:05 rivo volumio[5861]: info: ControllerMpd::getState May 03 11:26:05 rivo volumio[5861]: verbose: ControllerMpd::sendMpdCommand status May 03 11:26:05 rivo volumio[5861]: verbose: ControllerMpd::sendMpdCommand status May 03 11:26:05 rivo volumio[5861]: info: May 03 11:26:05 rivo volumio[5861]: ---------------------------- MPD announces state update: update May 03 11:26:05 rivo volumio[5861]: info: ControllerMpd::getState May 03 11:26:05 rivo volumio[5861]: verbose: ControllerMpd::sendMpdCommand status May 03 11:26:05 rivo volumio[5861]: info: sendMpdCommand status took 154 milliseconds May 03 11:26:05 rivo volumio[5861]: info: sendMpdCommand status took 153 milliseconds May 03 11:26:05 rivo volumio[5861]: info: sendMpdCommand status took 56 milliseconds May 03 11:26:05 rivo volumio[5861]: info: sendMpdCommand status took 54 milliseconds May 03 11:26:05 rivo volumio[5861]: info: sendMpdCommand status took 54 milliseconds May 03 11:26:05 rivo volumio[5861]: info: sendMpdCommand status took 52 milliseconds May 03 11:26:05 rivo volumio[5861]: verbose: ControllerMpd::parseState May 03 11:26:05 rivo volumio[5861]: info: Command Router : Notfying DB Updatefalse May 03 11:26:05 rivo volumio[5861]: info: CoreCommandRouter::Close All Modals sent May 03 11:26:05 rivo volumio[5861]: verbose: ControllerMpd::parseState May 03 11:26:05 rivo volumio[5861]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 11:26:05 rivo volumio[5861]: verbose: ControllerMpd::parseState May 03 11:26:05 rivo volumio[5861]: info: Command Router : Notfying DB Updatefalse May 03 11:26:05 rivo volumio[5861]: info: CoreCommandRouter::Close All Modals sent May 03 11:26:05 rivo volumio[5861]: verbose: ControllerMpd::parseState May 03 11:26:05 rivo volumio[5861]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 11:26:05 rivo volumio[5861]: verbose: ControllerMpd::parseState May 03 11:26:06 rivo volumio[5861]: info: Command Router : Notfying DB Updatefalse May 03 11:26:06 rivo volumio[5861]: info: CoreCommandRouter::Close All Modals sent May 03 11:26:06 rivo volumio[5861]: verbose: ControllerMpd::parseState May 03 11:26:06 rivo volumio[5861]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 11:26:06 rivo volumio[5861]: info: sendMpdCommand playlistinfo took 93 milliseconds May 03 11:26:06 rivo volumio[5861]: info: sendMpdCommand playlistinfo took 58 milliseconds May 03 11:26:06 rivo volumio[5861]: info: sendMpdCommand playlistinfo took 20 milliseconds May 03 11:26:06 rivo volumio[5861]: verbose: ControllerMpd::parseTrackInfo May 03 11:26:06 rivo volumio[5861]: verbose: ControllerMpd::parseTrackInfo May 03 11:26:06 rivo volumio[5861]: verbose: ControllerMpd::parseTrackInfo May 03 11:26:06 rivo volumio[5861]: info: ControllerMpd::pushState May 03 11:26:06 rivo volumio[5861]: info: CoreCommandRouter::servicePushState May 03 11:26:06 rivo volumio[5861]: info: CoreStateMachine::pushState May 03 11:26:06 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:26:06 rivo volumio[5861]: info: CoreCommandRouter::volumioPushState May 03 11:26:06 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:26:06 rivo volumio[5861]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"27258510","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/27258510","trackType":"qobuz"} May 03 11:26:06 rivo volumio[5861]: verbose: CURRENT POSITION 0 May 03 11:26:06 rivo volumio[5861]: info: CoreStateMachine::syncState stateService stop May 03 11:26:06 rivo volumio[5861]: info: CoreStateMachine::syncState currentStatus stop May 03 11:26:06 rivo volumio[5861]: info: CoreStateMachine::pushState May 03 11:26:06 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:26:06 rivo volumio[5861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 03 11:26:06 rivo volumio[5861]: info: CoreCommandRouter::volumioPushState May 03 11:26:06 rivo volumio[5861]: info: No code May 03 11:26:06 rivo volumio[5861]: info: CoreStateMachine::pushState May 03 11:26:06 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:26:06 rivo volumio[5861]: info: CoreCommandRouter::volumioPushState May 03 11:26:06 rivo volumio[5861]: info: ControllerMpd::pushState May 03 11:26:06 rivo volumio[5861]: info: CoreCommandRouter::servicePushState May 03 11:26:06 rivo volumio[5861]: info: CoreStateMachine::pushState May 03 11:26:06 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:26:06 rivo volumio[5861]: info: CoreCommandRouter::volumioPushState May 03 11:26:06 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:26:06 rivo volumio[5861]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"27258510","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/27258510","trackType":"qobuz"} May 03 11:26:06 rivo volumio[5861]: verbose: CURRENT POSITION 0 May 03 11:26:06 rivo volumio[5861]: info: CoreStateMachine::syncState stateService stop May 03 11:26:06 rivo volumio[5861]: info: CoreStateMachine::syncState currentStatus stop May 03 11:26:06 rivo volumio[5861]: info: CoreStateMachine::pushState May 03 11:26:06 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:26:06 rivo volumio[5861]: info: CoreCommandRouter::volumioPushState May 03 11:26:06 rivo volumio[5861]: info: No code May 03 11:26:06 rivo volumio[5861]: info: CoreStateMachine::pushState May 03 11:26:06 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:26:06 rivo volumio[5861]: info: CoreCommandRouter::volumioPushState May 03 11:26:06 rivo volumio[5861]: info: ControllerMpd::pushState May 03 11:26:06 rivo volumio[5861]: info: CoreCommandRouter::servicePushState May 03 11:26:06 rivo volumio[5861]: info: CoreStateMachine::pushState May 03 11:26:06 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:26:06 rivo volumio[5861]: info: CoreCommandRouter::volumioPushState May 03 11:26:06 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:26:06 rivo volumio[5861]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"27258510","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/27258510","trackType":"qobuz"} May 03 11:26:06 rivo volumio[5861]: verbose: CURRENT POSITION 0 May 03 11:26:06 rivo volumio[5861]: info: CoreStateMachine::syncState stateService stop May 03 11:26:06 rivo volumio[5861]: info: CoreStateMachine::syncState currentStatus stop May 03 11:26:06 rivo volumio[5861]: info: CoreStateMachine::pushState May 03 11:26:06 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:26:06 rivo volumio[5861]: info: CoreCommandRouter::volumioPushState May 03 11:26:06 rivo volumio[5861]: info: No code May 03 11:26:06 rivo volumio[5861]: info: CoreStateMachine::pushState May 03 11:26:06 rivo volumio[5861]: info: CorePlayQueue::getTrack 0 May 03 11:26:06 rivo volumio[5861]: info: CoreCommandRouter::volumioPushState May 03 11:26:06 rivo volumio[5861]: info: ------------------------------ 374ms May 03 11:26:06 rivo volumio[5861]: info: ------------------------------ 280ms May 03 11:26:06 rivo volumio[5861]: info: ------------------------------ 279ms May 03 11:26:08 rivo volumio[5861]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 03 11:26:12 rivo volumio[5861]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 03 11:26:13 rivo ntpd[5849]: 216.232.132.102 local addr 192.168.0.101 -> May 03 11:26:16 rivo volumio[5861]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 03 11:26:18 rivo volumio[5861]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares May 03 11:26:18 rivo volumio[5861]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService May 03 11:26:20 rivo volumio[5861]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 03 11:26:23 rivo volumio[5861]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 03 11:26:23 rivo volumio[5861]: Error: dns service error: unknown May 03 11:26:23 rivo volumio[5861]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) May 03 11:26:23 rivo volumio[5861]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { May 03 11:26:23 rivo volumio[5861]: errorCode: -65537 May 03 11:26:23 rivo volumio[5861]: } May 03 11:26:23 rivo volumio[5861]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 03 11:26:23 rivo sudo[7572]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-03 11:25 May 03 11:26:23 rivo sudo[7572]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:56:04 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="4aa444b01074e57a72574e89142e3734"