-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Thu 2025-05-08 21:14:52 CEST. -- May 08 21:13:12 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 08 21:13:12 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 08 21:13:12 volumio volumio[907]: info: Discovery: Getting this device information May 08 21:13:12 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:12 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:12 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 08 21:13:12 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 08 21:13:12 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 08 21:13:12 volumio volumio[907]: info: Discovery: Getting this device information May 08 21:13:12 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:12 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:12 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 08 21:13:13 volumio volumio[907]: verbose: New Socket.io Connection to 192.168.2.92:3000 from 192.168.2.79 UA: Dart/3.7 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 May 08 21:13:20 volumio kernel: usb 1-1: USB disconnect, device number 14 May 08 21:13:21 volumio kernel: usb 1-1.3: USB disconnect, device number 15 May 08 21:13:21 volumio kernel: device offline error, dev sda, sector 456679152 op 0x0:(READ) flags 0x80700 phys_seg 2 prio class 2 May 08 21:13:21 volumio kernel: device offline error, dev sda, sector 456679152 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 08 21:13:21 volumio kernel: Buffer I/O error on dev sda1, logical block 57084638, async page read May 08 21:13:21 volumio kernel: device offline error, dev sda, sector 456679152 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 08 21:13:21 volumio kernel: Buffer I/O error on dev sda1, logical block 57084638, async page read May 08 21:13:21 volumio kernel: device offline error, dev sda, sector 456679152 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 08 21:13:21 volumio kernel: Buffer I/O error on dev sda1, logical block 57084638, async page read May 08 21:13:21 volumio kernel: device offline error, dev sda, sector 456679208 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 May 08 21:13:21 volumio kernel: device offline error, dev sda, sector 456679208 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 08 21:13:21 volumio kernel: Buffer I/O error on dev sda1, logical block 57084645, async page read May 08 21:13:21 volumio kernel: device offline error, dev sda, sector 456679208 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 08 21:13:21 volumio kernel: Buffer I/O error on dev sda1, logical block 57084645, async page read May 08 21:13:21 volumio kernel: device offline error, dev sda, sector 456679208 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 08 21:13:21 volumio kernel: Buffer I/O error on dev sda1, logical block 57084645, async page read May 08 21:13:21 volumio kernel: device offline error, dev sda, sector 456679208 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 08 21:13:21 volumio kernel: Buffer I/O error on dev sda1, logical block 57084645, async page read May 08 21:13:21 volumio kernel: device offline error, dev sda, sector 6309192 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 May 08 21:13:21 volumio kernel: Buffer I/O error on dev sda1, logical block 788393, async page read May 08 21:13:21 volumio kernel: Buffer I/O error on dev sda1, logical block 788390, async page read May 08 21:13:21 volumio kernel: Buffer I/O error on dev sda1, logical block 57622169, async page read May 08 21:13:21 volumio kernel: usb 1-1.4: USB disconnect, device number 16 May 08 21:13:21 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 08 21:13:21 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 08 21:13:21 volumio volumio[907]: info: Discovery: Getting this device information May 08 21:13:21 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:21 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:21 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 08 21:13:21 volumio volumio[907]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fe9c0000.xhci/usb1/1-1/1-1.3/1-1.3:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","DEVLINKS":"/dev/disk/by-id/wwn-0x50014ee6ade68b66-part1 /dev/disk/by-id/ata-WDC_WD30EZRX-00DC0B0_WD-WMC1T2946075-part1 /dev/disk/by-path/platform-fe9c0000.xhci-usb-0:1.3:1.0-scsi-0:0:0:0-part1 /dev/disk/by-label/Elements /dev/disk/by-uuid/0E2E45FA2E45DB7B","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/scb/fe9c0000.xhci/usb1/1-1/1-1.3/1-1.3:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"31","ID_ATA":"1","ID_ATA_DOWNLOAD_MICROCODE":"1","ID_ATA_FEATURE_SET_HPA":"1","ID_ATA_FEATURE_SET_HPA_ENABLED":"1","ID_ATA_FEATURE_SET_PM":"1","ID_ATA_FEATURE_SET_PM_ENABLED":"1","ID_ATA_FEATURE_SET_PUIS":"1","ID_ATA_FEATURE_SET_PUIS_ENABLED":"0","ID_ATA_FEATURE_SET_SECURITY":"1","ID_ATA_FEATURE_SET_SECURITY_ENABLED":"0","ID_ATA_FEATURE_SET_SECURITY_ENHANCED_ERASE_UNIT_MIN":"424","ID_ATA_FEATURE_SET_SECURITY_ERASE_UNIT_MIN":"424","ID_ATA_FEATURE_SET_SMART":"1","ID_ATA_FEATURE_SET_SMART_ENABLED":"1","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":"Elements","ID_FS_LABEL_ENC":"Elements","ID_FS_TYPE":"ntfs","ID_FS_USAGE":"filesystem","ID_FS_UUID":"0E2E45FA2E45DB7B","ID_FS_UUID_ENC":"0E2E45FA2E45DB7B","ID_MODEL":"WDC_WD30EZRX-00DC0B0","ID_MODEL_ENC":"WDC\\x20WD30EZRX-00DC0B0\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"00036164","ID_PATH":"platform-fe9c0000.xhci-usb-0:1.3:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-fe9c0000_xhci-usb-0_1_3_1_0-scsi-0_0_0_0","ID_REVISION":"80.00A80","ID_SERIAL":"WDC_WD30EZRX-00DC0B0_WD-WMC1T2946075","ID_SERIAL_SHORT":"WD-WMC1T2946075","ID_TYPE":"disk","ID_WWN":"0x50014ee6ade68b66","ID_WWN_WITH_EXTENSION":"0x50014ee6ade68b66","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"2667","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"3320192426"} May 08 21:13:21 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=6/ABRT May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio systemd[1]: mpd.service: Failed with result 'signal'. May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Edge of outside.mkv' at offset 450252800: 12288 <> -1: Input/output error May 08 21:13:21 volumio systemd[1]: media-Elements.mount: Succeeded. May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Edge of outside.mkv' at offset 450252800: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Edge of outside.mkv' at offset 450252800: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Edge of outside.mkv' at offset 450281472: 3991 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Edge of outside.mkv' at offset 450281472: 3991 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Edge of outside.mkv' at offset 450281472: 3991 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Edge of outside.mkv' at offset 450281472: 3991 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: Failed to read of MFT, mft=1961 count=1 br=-1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: Failed to read of MFT, mft=1958 count=1 br=-1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Fair Game.avi' at offset 1467469824: 12288 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Fair Game.avi' at offset 1467469824: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Fair Game.avi' at offset 1467469824: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Fair Game.avi' at offset 98304: 131072 <> 90112: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Fair Game.avi' at offset 188416: 40960 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Fair Game.avi' at offset 1472409600: 1078 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Fair Game.avi' at offset 1472409600: 1078 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Fair Game.avi' at offset 1467469824: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Fair Game.avi' at offset 1472409600: 1078 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Fair Game.avi' at offset 1472409600: 1078 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Fast Food Nation 1.avi' at offset 0: 32768 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Fast Food Nation 1.avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Fast Food Nation 1.avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Fast Food Nation 1.avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Fast Food Nation 1.avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Fast Food Nation 1.avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Fast Food Nation 1.avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Fast Food Nation 1.avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Fast Food Nation 2.avi' at offset 0: 32768 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Fast Food Nation 2.avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Fast Food Nation 2.avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Fast Food Nation 2.avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Fast Food Nation 2.avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Fast Food Nation 2.avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Fast Food Nation 2.avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Fast Food Nation 2.avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: Failed to read of MFT, mft=1492 count=1 br=-1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Four Lions.avi' at offset 0: 32768 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Four Lions.avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Four Lions.avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Four Lions.avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Four Lions.avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Four Lions.avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Four Lions.avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Four Lions.avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: Failed to read of MFT, mft=1951 count=1 br=-1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Futureworld.avi' at offset 0: 32768 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Futureworld.avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Futureworld.avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Futureworld.avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Futureworld.avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Futureworld.avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Futureworld.avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Futureworld.avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: Failed to read of MFT, mft=1947 count=1 br=-1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: Failed to read of MFT, mft=1943 count=1 br=-1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: Failed to read of MFT, mft=1886 count=1 br=-1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/He Was A Quiet Man.avi' at offset 730054656: 12288 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/He Was A Quiet Man.avi' at offset 730054656: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/He Was A Quiet Man.avi' at offset 730054656: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/He Was A Quiet Man.avi' at offset 736059392: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/He Was A Quiet Man.avi' at offset 736059392: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/He Was A Quiet Man.avi' at offset 730054656: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/He Was A Quiet Man.avi' at offset 736059392: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/He Was A Quiet Man.avi' at offset 736059392: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Hearts of Darkness.avi' at offset 0: 32768 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Hearts of Darkness.avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Hearts of Darkness.avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Hearts of Darkness.avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Hearts of Darkness.avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Hearts of Darkness.avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Hearts of Darkness.avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Hearts of Darkness.avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: Failed to read of MFT, mft=1876 count=1 br=-1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/In.The.Valley.Of.Elah.2007.DVDRip.x264.NhaNc3.mkv' at offset 0: 32768 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/In.The.Valley.Of.Elah.2007.DVDRip.x264.NhaNc3.mkv' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/In.The.Valley.Of.Elah.2007.DVDRip.x264.NhaNc3.mkv' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/In.The.Valley.Of.Elah.2007.DVDRip.x264.NhaNc3.mkv' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/In.The.Valley.Of.Elah.2007.DVDRip.x264.NhaNc3.mkv' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/In.The.Valley.Of.Elah.2007.DVDRip.x264.NhaNc3.mkv' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/In.The.Valley.Of.Elah.2007.DVDRip.x264.NhaNc3.mkv' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Inglourious Basterds.avi' at offset 0: 32768 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Inglourious Basterds.avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Inglourious Basterds.avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Inglourious Basterds.avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Inglourious Basterds.avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Inglourious Basterds.avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Inglourious Basterds.avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Inglourious Basterds.avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Jabberwocky (AC3-6ch) (1of2).avi' at offset 0: 32768 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Jabberwocky (AC3-6ch) (1of2).avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Jabberwocky (AC3-6ch) (1of2).avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Jabberwocky (AC3-6ch) (1of2).avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Jabberwocky (AC3-6ch) (1of2).avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Jabberwocky (AC3-6ch) (1of2).avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Jabberwocky (AC3-6ch) (1of2).avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Jabberwocky (AC3-6ch) (1of2).avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Jabberwocky (AC3-6ch) (2of2).avi' at offset 0: 32768 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Jabberwocky (AC3-6ch) (2of2).avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Jabberwocky (AC3-6ch) (2of2).avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Jabberwocky (AC3-6ch) (2of2).avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Jabberwocky (AC3-6ch) (2of2).avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Jabberwocky (AC3-6ch) (2of2).avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Jabberwocky (AC3-6ch) (2of2).avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Jabberwocky (AC3-6ch) (2of2).avi' at offset 0: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: Failed to read of MFT, mft=1872 count=1 br=-1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: Failed to read of MFT, mft=1866 count=1 br=-1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Killing Ariel.avi' at offset 725291008: 12288 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Killing Ariel.avi' at offset 725291008: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Killing Ariel.avi' at offset 725291008: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Killing Ariel.avi' at offset 730787840: 2048 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Killing Ariel.avi' at offset 730787840: 2048 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Killing Ariel.avi' at offset 725291008: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Killing Ariel.avi' at offset 730787840: 2048 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Killing Ariel.avi' at offset 730787840: 2048 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Lars.And.The.Real.Girl.2007.LIMITED.PAL.MULTISUBS.DVDR-AFTERMATH/am-latrg.iso' at offset 196608: 131072 <> 20480: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Lars.And.The.Real.Girl.2007.LIMITED.PAL.MULTISUBS.DVDR-AFTERMATH/am-latrg.iso' at offset 217088: 110592 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Lars.And.The.Real.Girl.2007.LIMITED.PAL.MULTISUBS.DVDR-AFTERMATH/am-latrg.iso' at offset 524288: 131072 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Lars.And.The.Real.Girl.2007.LIMITED.PAL.MULTISUBS.DVDR-AFTERMATH/am-latrg.iso' at offset 524288: 4096 <> -1: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:21 volumio ntfs-3g[11046]: ntfs_attr_pread error reading '/Prive/Films/_Films/Lars.And.The.Real.Girl.2007.LIMITED.PAL.MULTISUBS.DVDR-AFTERMATH/am-latrg.iso' at offset 626688: 4096 <> -1: Input/output error May 08 21:13:21 volumio sudo[11768]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda1 May 08 21:13:21 volumio sudo[11768]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:13:21 volumio sudo[11768]: pam_unix(sudo:session): session closed for user root May 08 21:13:21 volumio ntfs-3g[11046]: Unmounting /dev/sda1 (Elements) May 08 21:13:21 volumio volumio[907]: info: May 08 21:13:21 volumio volumio[907]: ---------------------------- USB Audio Device Detached May 08 21:13:21 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach May 08 21:13:21 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 08 21:13:21 volumio volumio[907]: xcb_connection_has_error() returned true May 08 21:13:21 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: raat , onStop May 08 21:13:22 volumio kernel: usb 1-1: new high-speed USB device number 17 using xhci-hcd May 08 21:13:22 volumio kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=2504, bcdDevice= 0.01 May 08 21:13:22 volumio kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 May 08 21:13:22 volumio kernel: hub 1-1:1.0: USB hub found May 08 21:13:22 volumio kernel: hub 1-1:1.0: 4 ports detected May 08 21:13:22 volumio kernel: usb 1-1.3: new high-speed USB device number 18 using xhci-hcd May 08 21:13:22 volumio kernel: usb 1-1.3: New USB device found, idVendor=1058, idProduct=1021, bcdDevice=20.21 May 08 21:13:22 volumio kernel: usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3 May 08 21:13:22 volumio kernel: usb 1-1.3: Product: Ext HDD 1021 May 08 21:13:22 volumio kernel: usb 1-1.3: Manufacturer: Western Digital May 08 21:13:22 volumio kernel: usb 1-1.3: SerialNumber: 574D43315432393436303735 May 08 21:13:22 volumio kernel: usb-storage 1-1.3:1.0: USB Mass Storage device detected May 08 21:13:22 volumio kernel: scsi host0: usb-storage 1-1.3:1.0 May 08 21:13:22 volumio kernel: usb 1-1.4: new high-speed USB device number 19 using xhci-hcd May 08 21:13:23 volumio kernel: usb 1-1.4: New USB device found, idVendor=152a, idProduct=86fc, bcdDevice= 1.00 May 08 21:13:23 volumio kernel: usb 1-1.4: New USB device strings: Mfr=1, Product=3, SerialNumber=0 May 08 21:13:23 volumio kernel: usb 1-1.4: Product: HIFI DSD May 08 21:13:23 volumio kernel: usb 1-1.4: Manufacturer: Yunyue Audio May 08 21:13:23 volumio kernel: usb 1-1.4: 1:3 : unsupported format bits 0x100000000 May 08 21:13:23 volumio volumio[907]: info: May 08 21:13:23 volumio volumio[907]: ---------------------------- USB Audio Device Attached May 08 21:13:23 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach May 08 21:13:23 volumio volumio[907]: xcb_connection_has_error() returned true May 08 21:13:23 volumio volumio[907]: info: CoreCommandRouter::Close All Modals sent May 08 21:13:23 volumio volumio[907]: info: Preparing to save Alsa Options, stopping services first May 08 21:13:23 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:23 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:23 volumio volumio[907]: info: CoreCommandRouter::volumioPause May 08 21:13:23 volumio volumio[907]: info: CoreStateMachine::pause May 08 21:13:23 volumio volumio[907]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"HIFI DSD","alsacard":"DSD"},"i2s":false} May 08 21:13:23 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 08 21:13:23 volumio volumio[907]: xcb_connection_has_error() returned true May 08 21:13:23 volumio volumio[907]: xcb_connection_has_error() returned true May 08 21:13:23 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 08 21:13:23 volumio volumio[907]: xcb_connection_has_error() returned true May 08 21:13:23 volumio volumio[907]: info: Setting mixer HIFI DSD for card HIFI DSD May 08 21:13:23 volumio volumio[907]: info: QobuzConnect: setDeactiveState invoked May 08 21:13:23 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:23 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:23 volumio vtcs[11073]: [2025-05-08 21:13:23.496] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE May 08 21:13:23 volumio vtcs[11073]: [2025-05-08 21:13:23.496] [tisoc] [error] [SpkconServer.cpp:473] socket write error. fd=3 errorno=32 error=Broken pipe May 08 21:13:23 volumio vtcs[11073]: [2025-05-08 21:13:23.496] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected May 08 21:13:23 volumio sudo[11791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 08 21:13:23 volumio sudo[11791]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:13:23 volumio systemd[1]: Stopping Volumio Tidal Connect Service... May 08 21:13:23 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM May 08 21:13:23 volumio systemd[1]: vtcs.service: Succeeded. May 08 21:13:23 volumio systemd[1]: Stopped Volumio Tidal Connect Service. May 08 21:13:23 volumio sudo[11791]: pam_unix(sudo:session): session closed for user root May 08 21:13:23 volumio volumio[907]: xcb_connection_has_error() returned true May 08 21:13:23 volumio volumio[907]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 08 21:13:23 volumio volumio[907]: info: Updating Volume Controller Parameters: Device: 5 Name: HIFI DSD Mixer: HIFI DSD Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 08 21:13:23 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 08 21:13:23 volumio volumio[907]: info: Disabling external Volume Control May 08 21:13:23 volumio volumio[907]: info: CoreCommandRouter::getUIConfigOnPlugin May 08 21:13:23 volumio volumio[907]: info: Preparing to generate the ALSA configuration file May 08 21:13:23 volumio sudo[11798]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 08 21:13:23 volumio sudo[11798]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:13:23 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 21:13:23 volumio volumio[907]: info: QobuzConnect: Setting Qobuz Connect card as outputdevice to 5 May 08 21:13:23 volumio sudo[11798]: pam_unix(sudo:session): session closed for user root May 08 21:13:23 volumio volumio[907]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 08 21:13:23 volumio volumio[907]: info: Reading ALSA contributions from plugins. May 08 21:13:23 volumio volumio[907]: ------------------------------------ BT MESSAGE: BT STATUS: running May 08 21:13:23 volumio sudo[11805]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect May 08 21:13:23 volumio sudo[11805]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:13:23 volumio systemd[1]: Stopping Volumio Qobuz Connect Service... May 08 21:13:23 volumio qobuz-connect[11007]: 20250508 21:13:23.694 [11007.11007] INFO SampleApp: Stopping Local configuration server May 08 21:13:23 volumio volumio[907]: ------------------------------------ BT MESSAGE: BT STATUS: running May 08 21:13:23 volumio qobuz-connect[11007]: 20250508 21:13:23.704 [11007.11007] INFO SampleApp: shat down connection on UNIX socket May 08 21:13:23 volumio systemd[1]: qobuz-connect.service: Succeeded. May 08 21:13:23 volumio systemd[1]: Stopped Volumio Qobuz Connect Service. May 08 21:13:23 volumio volumio[907]: xcb_connection_has_error() returned true May 08 21:13:23 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 08 21:13:23 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 21:13:23 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 08 21:13:23 volumio volumio[907]: xcb_connection_has_error() returned true May 08 21:13:23 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 21:13:23 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 21:13:23 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 21:13:23 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 21:13:23 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 21:13:23 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 21:13:23 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 21:13:23 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 08 21:13:23 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 08 21:13:23 volumio systemd[1]: Started Volumio Qobuz Connect Service. May 08 21:13:23 volumio sudo[11805]: pam_unix(sudo:session): session closed for user root May 08 21:13:23 volumio volumio[907]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 08 21:13:23 volumio volumio[907]: info: VolumeController:: Volume=100 Mute =false May 08 21:13:23 volumio volumio[907]: info: CoreStateMachine::pushState May 08 21:13:23 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:23 volumio volumio[907]: info: CoreCommandRouter::volumioPushState May 08 21:13:23 volumio volumio[907]: info: MRS: Pushing multiroomSync output update for this device May 08 21:13:23 volumio volumio[907]: info: MRS: Pushing multiroomSync output May 08 21:13:23 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:23 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:23 volumio volumio[907]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected May 08 21:13:23 volumio volumio[907]: info: QobuzConnect: setDeactiveState invoked May 08 21:13:23 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:23 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:23 volumio volumio[907]: info: Executing endpoint qc_getconfig May 08 21:13:23 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 08 21:13:23 volumio qobuz-connect[11810]: 20250508 21:13:23.830 [11810.11810] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 08 21:13:23 volumio volumio[907]: info: Asound.conf file unchanged, so no further update is needed May 08 21:13:23 volumio volumio[907]: info: Output device has changed, restarting MPD May 08 21:13:23 volumio qobuz-connect[11810]: 20250508 21:13:23.834 [11810.11810] INFO VolumeManager: [0xa9fa20]: Setting new playback volume: 75 May 08 21:13:23 volumio qobuz-connect[11810]: 20250508 21:13:23.834 [11810.11810] INFO VolumeManager: [0xa9fa20]: Setting new mute state: 0 May 08 21:13:23 volumio qobuz-connect[11810]: 20250508 21:13:23.834 [11810.11810] INFO QobuzConnect: [0xaa03f0]: Client initialized! May 08 21:13:23 volumio qobuz-connect[11810]: 20250508 21:13:23.834 [11810.11810] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp May 08 21:13:23 volumio qobuz-connect[11810]: 20250508 21:13:23.841 [11810.11810] INFO LocalConfigManager: [0xa9f300]: Starting Local Configuration server May 08 21:13:23 volumio qobuz-connect[11810]: 20250508 21:13:23.841 [11810.11810] INFO SampleApp: Starting Local configuration server May 08 21:13:23 volumio qobuz-connect[11810]: 20250508 21:13:23.842 [11810.11810] INFO SampleApp: Connected to UNIX socket client 0xa94f60 May 08 21:13:23 volumio kernel: scsi 0:0:0:0: Direct-Access WD Ext HDD 1021 2021 PQ: 0 ANSI: 4 May 08 21:13:23 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 May 08 21:13:23 volumio kernel: sd 0:0:0:0: [sda] 732566016 4096-byte logical blocks: (3.00 TB/2.73 TiB) May 08 21:13:23 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off May 08 21:13:23 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 17 00 10 08 May 08 21:13:23 volumio kernel: sd 0:0:0:0: [sda] No Caching mode page found May 08 21:13:23 volumio kernel: sd 0:0:0:0: [sda] Assuming drive cache: write through May 08 21:13:23 volumio kernel: sda: sda1 May 08 21:13:23 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI disk May 08 21:13:23 volumio volumio[907]: info: Output device has changed, restarting Shairport Sync May 08 21:13:23 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 21:13:23 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 21:13:23 volumio sudo[11817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 08 21:13:23 volumio sudo[11817]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:13:23 volumio sudo[11817]: pam_unix(sudo:session): session closed for user root May 08 21:13:23 volumio sudo[11820]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 08 21:13:23 volumio sudo[11820]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:13:23 volumio systemd[1]: Starting Music Player Daemon... May 08 21:13:23 volumio volumio[907]: xcb_connection_has_error() returned true May 08 21:13:23 volumio volumio[907]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 08 21:13:23 volumio volumio[907]: xcb_connection_has_error() returned true May 08 21:13:23 volumio volumio[907]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 08 21:13:23 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 21:13:23 volumio volumio[907]: info: QobuzConnect: setDeactiveState invoked May 08 21:13:23 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:23 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:23 volumio sudo[11830]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 08 21:13:23 volumio sudo[11830]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:13:23 volumio sudo[11826]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 08 21:13:23 volumio sudo[11826]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:13:23 volumio qobuz-connect[11810]: 20250508 21:13:23.986 [11810.11810] INFO SampleApp: Playback volume changed: 75 May 08 21:13:23 volumio sudo[11826]: pam_unix(sudo:session): session closed for user root May 08 21:13:23 volumio sudo[11832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 08 21:13:23 volumio sudo[11832]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:13:23 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 21:13:23 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 21:13:23 volumio volumio[907]: info: QobuzConnect: Setting Qobuz Connect card as outputdevice to 5 May 08 21:13:24 volumio volumio[907]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 08 21:13:24 volumio volumio[907]: info: QobuzConnect: QOBUZ Connect daemon connected May 08 21:13:24 volumio volumio[907]: ------------------------------------ BT MESSAGE: BT STATUS: running May 08 21:13:24 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:24 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:24 volumio volumio[907]: info: MPD Permissions set May 08 21:13:24 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 21:13:24 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 21:13:24 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 21:13:24 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 21:13:24 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 21:13:24 volumio sudo[11841]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect May 08 21:13:24 volumio sudo[11841]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:13:24 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 21:13:24 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 21:13:24 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 21:13:24 volumio systemd[1]: Stopping Volumio Qobuz Connect Service... May 08 21:13:24 volumio qobuz-connect[11810]: 20250508 21:13:24.061 [11810.11810] INFO SampleApp: Stopping Local configuration server May 08 21:13:24 volumio volumio[907]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 08 21:13:24 volumio volumio[907]: info: Starting Shairport Sync May 08 21:13:24 volumio qobuz-connect[11810]: 20250508 21:13:24.071 [11810.11810] INFO SampleApp: shat down connection on UNIX socket May 08 21:13:24 volumio sudo[11830]: pam_unix(sudo:session): session closed for user root May 08 21:13:24 volumio systemd[1]: qobuz-connect.service: Succeeded. May 08 21:13:24 volumio systemd[1]: Stopped Volumio Qobuz Connect Service. May 08 21:13:24 volumio sudo[11832]: pam_unix(sudo:session): session closed for user root May 08 21:13:24 volumio systemd[1]: Started Volumio Qobuz Connect Service. May 08 21:13:24 volumio volumio[907]: ------------------------------------ BT MESSAGE: BT STATUS: running May 08 21:13:24 volumio volumio[907]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 08 21:13:24 volumio sudo[11841]: pam_unix(sudo:session): session closed for user root May 08 21:13:24 volumio sudo[11851]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 08 21:13:24 volumio sudo[11851]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:13:24 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat May 08 21:13:24 volumio volumio[907]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected May 08 21:13:24 volumio volumio[907]: info: QobuzConnect: setDeactiveState invoked May 08 21:13:24 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:24 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:24 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 08 21:13:24 volumio volumio[907]: info: Executing endpoint qc_getconfig May 08 21:13:24 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 08 21:13:24 volumio systemd[1]: shairport-sync.service: Succeeded. May 08 21:13:24 volumio volumio[907]: ------------------------------------ BT MESSAGE: BT STATUS: running May 08 21:13:24 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 08 21:13:24 volumio volumio[907]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 08 21:13:24 volumio qobuz-connect[11850]: 20250508 21:13:24.146 [11850.11850] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 08 21:13:24 volumio qobuz-connect[11850]: 20250508 21:13:24.150 [11850.11850] INFO VolumeManager: [0x1378a20]: Setting new playback volume: 75 May 08 21:13:24 volumio qobuz-connect[11850]: 20250508 21:13:24.150 [11850.11850] INFO VolumeManager: [0x1378a20]: Setting new mute state: 0 May 08 21:13:24 volumio qobuz-connect[11850]: 20250508 21:13:24.150 [11850.11850] INFO QobuzConnect: [0x13793f0]: Client initialized! May 08 21:13:24 volumio qobuz-connect[11850]: 20250508 21:13:24.150 [11850.11850] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp May 08 21:13:24 volumio volumio[907]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 08 21:13:24 volumio volumio[907]: info: QobuzConnect: QOBUZ Connect daemon connected May 08 21:13:24 volumio qobuz-connect[11850]: 20250508 21:13:24.160 [11850.11850] INFO LocalConfigManager: [0x1378300]: Starting Local Configuration server May 08 21:13:24 volumio qobuz-connect[11850]: 20250508 21:13:24.160 [11850.11850] INFO SampleApp: Starting Local configuration server May 08 21:13:24 volumio qobuz-connect[11850]: 20250508 21:13:24.160 [11850.11850] INFO SampleApp: Connected to UNIX socket client 0x136df60 May 08 21:13:24 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 08 21:13:24 volumio sudo[11851]: pam_unix(sudo:session): session closed for user root May 08 21:13:24 volumio volumio[907]: info: Mounting Device Elements May 08 21:13:24 volumio sudo[11863]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/Elements -o noatime,dmask=0000,fmask=0000,iocharset=utf8 May 08 21:13:24 volumio sudo[11863]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:13:24 volumio qobuz-connect[11850]: 20250508 21:13:24.300 [11850.11850] INFO SampleApp: Playback volume changed: 75 May 08 21:13:24 volumio mpd[11839]: May 08 21:13 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 08 21:13:24 volumio systemd[1]: Started Music Player Daemon. May 08 21:13:24 volumio sudo[11820]: pam_unix(sudo:session): session closed for user root May 08 21:13:27 volumio ntfs-3g[11883]: Version 2017.3.23AR.3 integrated FUSE 28 May 08 21:13:27 volumio ntfs-3g[11883]: Mounted /dev/sda1 (Read-Write, label "Elements", NTFS 3.1) May 08 21:13:27 volumio ntfs-3g[11883]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 May 08 21:13:27 volumio ntfs-3g[11883]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 May 08 21:13:27 volumio ntfs-3g[11883]: Global ownership and permissions enforced, configuration type 7 May 08 21:13:27 volumio sudo[11863]: pam_unix(sudo:session): session closed for user root May 08 21:13:27 volumio volumio[907]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/Elements May 08 21:13:27 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:27 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:27 volumio volumio[907]: info: TidalConnect service stoped! May 08 21:13:27 volumio volumio[907]: info: TidalConnect service stoped! May 08 21:13:27 volumio volumio[907]: info: TidalConnect service stoped! May 08 21:13:27 volumio volumio[907]: info: Shairport-Sync Started May 08 21:13:27 volumio volumio[907]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 08 21:13:27 volumio volumio[907]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 08 21:13:27 volumio volumio[907]: info: Scanning new location : "USB/Elements" May 08 21:13:27 volumio volumio[907]: error: updateQueue error: null May 08 21:13:27 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand status May 08 21:13:27 volumio volumio[907]: info: May 08 21:13:27 volumio volumio[907]: ---------------------------- MPD announces state update: update May 08 21:13:27 volumio volumio[907]: info: ControllerMpd::getState May 08 21:13:27 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand status May 08 21:13:27 volumio volumio[907]: info: sendMpdCommand status took 2 milliseconds May 08 21:13:27 volumio volumio[907]: info: sendMpdCommand status took 2 milliseconds May 08 21:13:27 volumio volumio[907]: verbose: ControllerMpd::parseState May 08 21:13:27 volumio volumio[907]: info: Command Router : Notfying DB Updatetrue May 08 21:13:27 volumio volumio[907]: info: CoreCommandRouter::Close All Modals sent May 08 21:13:27 volumio volumio[907]: verbose: ControllerMpd::parseState May 08 21:13:27 volumio volumio[907]: info: ControllerMpd::pushState May 08 21:13:27 volumio volumio[907]: info: CoreCommandRouter::servicePushState May 08 21:13:27 volumio volumio[907]: info: CoreStateMachine::pushState May 08 21:13:27 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:27 volumio volumio[907]: info: CoreCommandRouter::volumioPushState May 08 21:13:27 volumio volumio[907]: info: MRS: Pushing multiroomSync output update for this device May 08 21:13:27 volumio volumio[907]: info: MRS: Pushing multiroomSync output May 08 21:13:27 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:27 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:27 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:27 volumio volumio[907]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 08 21:13:27 volumio volumio[907]: verbose: CURRENT POSITION 0 May 08 21:13:27 volumio volumio[907]: info: CoreStateMachine::syncState stateService stop May 08 21:13:27 volumio volumio[907]: info: CoreStateMachine::syncState currentStatus stop May 08 21:13:27 volumio volumio[907]: info: CoreStateMachine::pushState May 08 21:13:27 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:27 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 21:13:27 volumio volumio[907]: info: CoreCommandRouter::volumioPushState May 08 21:13:27 volumio volumio[907]: info: MRS: Pushing multiroomSync output update for this device May 08 21:13:27 volumio volumio[907]: info: MRS: Pushing multiroomSync output May 08 21:13:27 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:27 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:27 volumio volumio[907]: info: No code May 08 21:13:27 volumio volumio[907]: info: CoreStateMachine::pushState May 08 21:13:27 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:27 volumio volumio[907]: info: CoreCommandRouter::volumioPushState May 08 21:13:27 volumio volumio[907]: info: MRS: Pushing multiroomSync output update for this device May 08 21:13:27 volumio volumio[907]: info: MRS: Pushing multiroomSync output May 08 21:13:27 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:27 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:27 volumio volumio[907]: info: ------------------------------ 103ms May 08 21:13:27 volumio volumio[907]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 08 21:13:27 volumio volumio[907]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 08 21:13:27 volumio sudo[11906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 08 21:13:27 volumio sudo[11906]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:13:27 volumio volumio[907]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 08 21:13:27 volumio volumio[907]: info: CoreStateMachine::getcurrentVolume May 08 21:13:27 volumio volumio[907]: info: CoreCommandRouter::volumioRetrievevolume May 08 21:13:27 volumio sudo[11910]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 08 21:13:27 volumio sudo[11910]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:13:27 volumio systemd[1]: Started Volumio Tidal Connect Service. May 08 21:13:27 volumio sudo[11906]: pam_unix(sudo:session): session closed for user root May 08 21:13:27 volumio sudo[11910]: pam_unix(sudo:session): session closed for user root May 08 21:13:27 volumio volumio[907]: info: VolumeController:: Volume=100 Mute =false May 08 21:13:27 volumio volumio[907]: info: CoreStateMachine::pushState May 08 21:13:27 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:27 volumio volumio[907]: info: CoreCommandRouter::volumioPushState May 08 21:13:27 volumio volumio[907]: info: MRS: Pushing multiroomSync output update for this device May 08 21:13:27 volumio volumio[907]: info: MRS: Pushing multiroomSync output May 08 21:13:27 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:27 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:27 volumio volumio[907]: info: CoreStateMachine::updateTrackBlock May 08 21:13:27 volumio volumio[907]: info: CorePlayQueue::getTrackBlock May 08 21:13:27 volumio volumio[907]: info: CoreCommandRouter::volumioRetrievevolume May 08 21:13:27 volumio volumio[907]: info: Executing endpoint tc_getconfig May 08 21:13:27 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 08 21:13:27 volumio vtcs[11908]: STARTING TidalConnect services, version: 1.4.0.34 May 08 21:13:27 volumio volumio[907]: info: VolumeController:: Volume=100 Mute =false May 08 21:13:27 volumio volumio[907]: info: CoreStateMachine::pushState May 08 21:13:27 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:27 volumio volumio[907]: info: CoreCommandRouter::volumioPushState May 08 21:13:27 volumio volumio[907]: info: MRS: Pushing multiroomSync output update for this device May 08 21:13:27 volumio volumio[907]: info: MRS: Pushing multiroomSync output May 08 21:13:27 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:27 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:27 volumio vtcs[11908]: STARTED TidalConnect services. May 08 21:13:27 volumio volumio[907]: info: Executing endpoint tc_connect May 08 21:13:27 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 08 21:13:27 volumio volumio[907]: info: Connecting to TidalConnect May 08 21:13:27 volumio volumio[907]: info: CoreCommandRouter::servicePushState May 08 21:13:27 volumio volumio[907]: info: CoreStateMachine::pushState May 08 21:13:27 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:27 volumio volumio[907]: info: CoreCommandRouter::volumioPushState May 08 21:13:27 volumio volumio[907]: info: MRS: Pushing multiroomSync output update for this device May 08 21:13:27 volumio volumio[907]: info: MRS: Pushing multiroomSync output May 08 21:13:27 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:27 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:27 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:27 volumio volumio[907]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} May 08 21:13:27 volumio volumio[907]: verbose: CURRENT POSITION 0 May 08 21:13:27 volumio volumio[907]: info: CoreStateMachine::syncState stateService stop May 08 21:13:27 volumio volumio[907]: info: CoreStateMachine::syncState currentStatus stop May 08 21:13:27 volumio volumio[907]: info: CoreStateMachine::pushState May 08 21:13:27 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:27 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 21:13:27 volumio volumio[907]: info: CoreCommandRouter::volumioPushState May 08 21:13:27 volumio volumio[907]: info: MRS: Pushing multiroomSync output update for this device May 08 21:13:27 volumio volumio[907]: info: MRS: Pushing multiroomSync output May 08 21:13:27 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:27 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:27 volumio volumio[907]: info: No code May 08 21:13:27 volumio volumio[907]: info: CoreStateMachine::pushState May 08 21:13:27 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:27 volumio volumio[907]: info: CoreCommandRouter::volumioPushState May 08 21:13:27 volumio volumio[907]: info: MRS: Pushing multiroomSync output update for this device May 08 21:13:27 volumio volumio[907]: info: MRS: Pushing multiroomSync output May 08 21:13:27 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:27 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:27 volumio volumio[907]: info: CoreCommandRouter::servicePushState May 08 21:13:27 volumio volumio[907]: info: CoreStateMachine::pushState May 08 21:13:27 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:27 volumio volumio[907]: info: CoreCommandRouter::volumioPushState May 08 21:13:27 volumio volumio[907]: info: MRS: Pushing multiroomSync output update for this device May 08 21:13:27 volumio volumio[907]: info: MRS: Pushing multiroomSync output May 08 21:13:27 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:27 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:27 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:27 volumio volumio[907]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} May 08 21:13:27 volumio volumio[907]: verbose: CURRENT POSITION 0 May 08 21:13:27 volumio volumio[907]: info: CoreStateMachine::syncState stateService stop May 08 21:13:27 volumio volumio[907]: info: CoreStateMachine::syncState currentStatus stop May 08 21:13:27 volumio volumio[907]: info: CoreStateMachine::pushState May 08 21:13:27 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:27 volumio volumio[907]: info: CoreCommandRouter::volumioPushState May 08 21:13:27 volumio volumio[907]: info: MRS: Pushing multiroomSync output update for this device May 08 21:13:27 volumio volumio[907]: info: MRS: Pushing multiroomSync output May 08 21:13:27 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:27 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:27 volumio volumio[907]: info: No code May 08 21:13:27 volumio volumio[907]: info: CoreStateMachine::pushState May 08 21:13:27 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:27 volumio volumio[907]: info: CoreCommandRouter::volumioPushState May 08 21:13:27 volumio volumio[907]: info: MRS: Pushing multiroomSync output update for this device May 08 21:13:27 volumio volumio[907]: info: MRS: Pushing multiroomSync output May 08 21:13:27 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:27 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:30 volumio volumio[907]: info: TidalConnect service started! May 08 21:13:30 volumio volumio[907]: info: TidalConnect service started! May 08 21:13:34 volumio kernel: usb 1-1: USB disconnect, device number 17 May 08 21:13:34 volumio kernel: usb 1-1.3: USB disconnect, device number 18 May 08 21:13:34 volumio kernel: blk_print_req_error: 142 callbacks suppressed May 08 21:13:34 volumio kernel: device offline error, dev sda, sector 1639924416 op 0x0:(READ) flags 0x84700 phys_seg 2 prio class 2 May 08 21:13:34 volumio kernel: device offline error, dev sda, sector 1639924656 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 May 08 21:13:34 volumio kernel: device offline error, dev sda, sector 1639924672 op 0x0:(READ) flags 0x84700 phys_seg 2 prio class 2 May 08 21:13:34 volumio kernel: device offline error, dev sda, sector 1639924912 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 May 08 21:13:34 volumio kernel: device offline error, dev sda, sector 1639924416 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 08 21:13:34 volumio kernel: buffer_io_error: 111 callbacks suppressed May 08 21:13:34 volumio kernel: Buffer I/O error on dev sda1, logical block 204990296, async page read May 08 21:13:34 volumio kernel: device offline error, dev sda, sector 1639924416 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 08 21:13:34 volumio kernel: Buffer I/O error on dev sda1, logical block 204990296, async page read May 08 21:13:34 volumio kernel: device offline error, dev sda, sector 1639924416 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 08 21:13:34 volumio kernel: Buffer I/O error on dev sda1, logical block 204990296, async page read May 08 21:13:34 volumio kernel: device offline error, dev sda, sector 1639924416 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 08 21:13:34 volumio kernel: Buffer I/O error on dev sda1, logical block 204990296, async page read May 08 21:13:34 volumio kernel: Buffer I/O error on dev sda1, logical block 204990304, async page read May 08 21:13:34 volumio kernel: Buffer I/O error on dev sda1, logical block 204990336, async page read May 08 21:13:34 volumio kernel: Buffer I/O error on dev sda1, logical block 204990272, async page read May 08 21:13:34 volumio kernel: Buffer I/O error on dev sda1, logical block 204990272, async page read May 08 21:13:34 volumio kernel: Buffer I/O error on dev sda1, logical block 204990272, async page read May 08 21:13:34 volumio kernel: Buffer I/O error on dev sda1, logical block 204990272, async page read May 08 21:13:34 volumio kernel: usb 1-1.4: USB disconnect, device number 19 May 08 21:13:34 volumio volumio[907]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fe9c0000.xhci/usb1/1-1/1-1.3/1-1.3:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","DEVLINKS":"/dev/disk/by-id/wwn-0x50014ee6ade68b66-part1 /dev/disk/by-id/ata-WDC_WD30EZRX-00DC0B0_WD-WMC1T2946075-part1 /dev/disk/by-path/platform-fe9c0000.xhci-usb-0:1.3:1.0-scsi-0:0:0:0-part1 /dev/disk/by-label/Elements /dev/disk/by-uuid/0E2E45FA2E45DB7B","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/scb/fe9c0000.xhci/usb1/1-1/1-1.3/1-1.3:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"32","ID_ATA":"1","ID_ATA_DOWNLOAD_MICROCODE":"1","ID_ATA_FEATURE_SET_HPA":"1","ID_ATA_FEATURE_SET_HPA_ENABLED":"1","ID_ATA_FEATURE_SET_PM":"1","ID_ATA_FEATURE_SET_PM_ENABLED":"1","ID_ATA_FEATURE_SET_PUIS":"1","ID_ATA_FEATURE_SET_PUIS_ENABLED":"0","ID_ATA_FEATURE_SET_SECURITY":"1","ID_ATA_FEATURE_SET_SECURITY_ENABLED":"0","ID_ATA_FEATURE_SET_SECURITY_ENHANCED_ERASE_UNIT_MIN":"424","ID_ATA_FEATURE_SET_SECURITY_ERASE_UNIT_MIN":"424","ID_ATA_FEATURE_SET_SMART":"1","ID_ATA_FEATURE_SET_SMART_ENABLED":"1","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":"Elements","ID_FS_LABEL_ENC":"Elements","ID_FS_TYPE":"ntfs","ID_FS_USAGE":"filesystem","ID_FS_UUID":"0E2E45FA2E45DB7B","ID_FS_UUID_ENC":"0E2E45FA2E45DB7B","ID_MODEL":"WDC_WD30EZRX-00DC0B0","ID_MODEL_ENC":"WDC\\x20WD30EZRX-00DC0B0\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"00036164","ID_PATH":"platform-fe9c0000.xhci-usb-0:1.3:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-fe9c0000_xhci-usb-0_1_3_1_0-scsi-0_0_0_0","ID_REVISION":"80.00A80","ID_SERIAL":"WDC_WD30EZRX-00DC0B0_WD-WMC1T2946075","ID_SERIAL_SHORT":"WD-WMC1T2946075","ID_TYPE":"disk","ID_WWN":"0x50014ee6ade68b66","ID_WWN_WITH_EXTENSION":"0x50014ee6ade68b66","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"2732","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"3542919095"} May 08 21:13:34 volumio volumio[907]: umount: /media/Elements: target is busy. May 08 21:13:34 volumio volumio[907]: error: Failed to umount Elements: Error: Command failed: /usr/bin/sudo /bin/umount -f "/dev/sda1" May 08 21:13:34 volumio volumio[907]: umount: /media/Elements: target is busy. May 08 21:13:34 volumio ntfs-3g[11883]: ntfs_attr_pread error reading '/Prive/1TB disk/John/Holy Flying Circus.avi' at offset 728801280: 131072 <> 98304: Input/output error May 08 21:13:34 volumio ntfs-3g[11883]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:34 volumio ntfs-3g[11883]: ntfs_attr_pread error reading '/Prive/1TB disk/John/Holy Flying Circus.avi' at offset 728899584: 32768 <> -1: Input/output error May 08 21:13:34 volumio ntfs-3g[11883]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:34 volumio ntfs-3g[11883]: ntfs_attr_pread error reading '/Prive/1TB disk/John/Holy Flying Circus.avi' at offset 728932352: 131072 <> -1: Input/output error May 08 21:13:34 volumio ntfs-3g[11883]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:34 volumio ntfs-3g[11883]: ntfs_attr_pread error reading '/Prive/1TB disk/John/Holy Flying Circus.avi' at offset 729063424: 131072 <> -1: Input/output error May 08 21:13:34 volumio ntfs-3g[11883]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:34 volumio ntfs-3g[11883]: ntfs_attr_pread error reading '/Prive/1TB disk/John/Holy Flying Circus.avi' at offset 728801280: 4096 <> -1: Input/output error May 08 21:13:34 volumio ntfs-3g[11883]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:34 volumio ntfs-3g[11883]: ntfs_attr_pread error reading '/Prive/1TB disk/John/Holy Flying Circus.avi' at offset 728801280: 4096 <> -1: Input/output error May 08 21:13:34 volumio ntfs-3g[11883]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:34 volumio ntfs-3g[11883]: ntfs_attr_pread error reading '/Prive/1TB disk/John/Holy Flying Circus.avi' at offset 728801280: 4096 <> -1: Input/output error May 08 21:13:34 volumio ntfs-3g[11883]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:34 volumio ntfs-3g[11883]: ntfs_attr_pread error reading '/Prive/1TB disk/John/Holy Flying Circus.avi' at offset 728801280: 4096 <> -1: Input/output error May 08 21:13:34 volumio ntfs-3g[11883]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:34 volumio ntfs-3g[11883]: ntfs_attr_pread error reading '/Prive/1TB disk/John/Holy Flying Circus.avi' at offset 732827648: 4096 <> -1: Input/output error May 08 21:13:34 volumio ntfs-3g[11883]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:34 volumio ntfs-3g[11883]: ntfs_attr_pread error reading '/Prive/1TB disk/John/Holy Flying Circus.avi' at offset 732827648: 4096 <> -1: Input/output error May 08 21:13:34 volumio ntfs-3g[11883]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 08 21:13:34 volumio ntfs-3g[11883]: ntfs_attr_pread error reading '/Prive/1TB disk/John/Holy Flying Circus.avi' at offset 732827648: 4096 <> -1: Input/output error May 08 21:13:34 volumio sudo[11943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda1 May 08 21:13:34 volumio sudo[11943]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:13:34 volumio ntfs-3g[11883]: Unmounting /dev/sda1 (Elements) May 08 21:13:34 volumio sudo[11943]: pam_unix(sudo:session): session closed for user root May 08 21:13:34 volumio volumio[907]: info: May 08 21:13:34 volumio volumio[907]: ---------------------------- USB Audio Device Detached May 08 21:13:34 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach May 08 21:13:34 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 08 21:13:34 volumio volumio[907]: xcb_connection_has_error() returned true May 08 21:13:34 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: raat , onStop May 08 21:13:34 volumio volumio[907]: info: MPD Database updated - AlbumList cache refreshed May 08 21:13:34 volumio volumio[907]: info: May 08 21:13:34 volumio volumio[907]: ---------------------------- MPD announces state update: database May 08 21:13:34 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand status May 08 21:13:34 volumio volumio[907]: info: May 08 21:13:34 volumio volumio[907]: ---------------------------- MPD announces state update: update May 08 21:13:34 volumio volumio[907]: info: ControllerMpd::getState May 08 21:13:34 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand status May 08 21:13:34 volumio volumio[907]: info: ControllerMpd::getState May 08 21:13:34 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand status May 08 21:13:34 volumio volumio[907]: info: sendMpdCommand status took 16 milliseconds May 08 21:13:34 volumio volumio[907]: info: sendMpdCommand status took 15 milliseconds May 08 21:13:34 volumio volumio[907]: info: sendMpdCommand status took 15 milliseconds May 08 21:13:34 volumio volumio[907]: verbose: ControllerMpd::parseState May 08 21:13:34 volumio volumio[907]: info: Command Router : Notfying DB Updatefalse May 08 21:13:34 volumio volumio[907]: info: CoreCommandRouter::Close All Modals sent May 08 21:13:34 volumio volumio[907]: verbose: ControllerMpd::parseState May 08 21:13:34 volumio volumio[907]: verbose: ControllerMpd::parseState May 08 21:13:34 volumio volumio[907]: info: ControllerMpd::pushState May 08 21:13:34 volumio volumio[907]: info: CoreCommandRouter::servicePushState May 08 21:13:34 volumio volumio[907]: info: CoreStateMachine::pushState May 08 21:13:34 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:34 volumio volumio[907]: info: CoreCommandRouter::volumioPushState May 08 21:13:34 volumio volumio[907]: info: MRS: Pushing multiroomSync output update for this device May 08 21:13:34 volumio volumio[907]: info: MRS: Pushing multiroomSync output May 08 21:13:34 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:34 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:34 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:34 volumio volumio[907]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 08 21:13:34 volumio volumio[907]: verbose: CURRENT POSITION 0 May 08 21:13:34 volumio volumio[907]: info: CoreStateMachine::syncState stateService stop May 08 21:13:34 volumio volumio[907]: info: CoreStateMachine::syncState currentStatus stop May 08 21:13:34 volumio volumio[907]: info: CoreStateMachine::pushState May 08 21:13:34 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:34 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 21:13:34 volumio volumio[907]: info: CoreCommandRouter::volumioPushState May 08 21:13:34 volumio volumio[907]: info: MRS: Pushing multiroomSync output update for this device May 08 21:13:34 volumio volumio[907]: info: MRS: Pushing multiroomSync output May 08 21:13:34 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:34 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:34 volumio volumio[907]: info: No code May 08 21:13:34 volumio volumio[907]: info: CoreStateMachine::pushState May 08 21:13:34 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:34 volumio volumio[907]: info: CoreCommandRouter::volumioPushState May 08 21:13:34 volumio volumio[907]: info: MRS: Pushing multiroomSync output update for this device May 08 21:13:34 volumio volumio[907]: info: MRS: Pushing multiroomSync output May 08 21:13:34 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:34 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:34 volumio volumio[907]: info: ControllerMpd::pushState May 08 21:13:34 volumio volumio[907]: info: CoreCommandRouter::servicePushState May 08 21:13:34 volumio volumio[907]: info: CoreStateMachine::pushState May 08 21:13:34 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:34 volumio volumio[907]: info: CoreCommandRouter::volumioPushState May 08 21:13:34 volumio volumio[907]: info: MRS: Pushing multiroomSync output update for this device May 08 21:13:34 volumio volumio[907]: info: MRS: Pushing multiroomSync output May 08 21:13:34 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:34 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:34 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:34 volumio volumio[907]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 08 21:13:34 volumio volumio[907]: verbose: CURRENT POSITION 0 May 08 21:13:34 volumio volumio[907]: info: CoreStateMachine::syncState stateService stop May 08 21:13:34 volumio volumio[907]: info: CoreStateMachine::syncState currentStatus stop May 08 21:13:34 volumio volumio[907]: info: CoreStateMachine::pushState May 08 21:13:34 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:34 volumio volumio[907]: info: CoreCommandRouter::volumioPushState May 08 21:13:34 volumio volumio[907]: info: MRS: Pushing multiroomSync output update for this device May 08 21:13:34 volumio volumio[907]: info: MRS: Pushing multiroomSync output May 08 21:13:34 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:34 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:34 volumio volumio[907]: info: No code May 08 21:13:34 volumio volumio[907]: info: CoreStateMachine::pushState May 08 21:13:34 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:34 volumio volumio[907]: info: CoreCommandRouter::volumioPushState May 08 21:13:34 volumio volumio[907]: info: MRS: Pushing multiroomSync output update for this device May 08 21:13:34 volumio volumio[907]: info: MRS: Pushing multiroomSync output May 08 21:13:34 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:34 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:34 volumio volumio[907]: info: ------------------------------ 110ms May 08 21:13:34 volumio volumio[907]: info: ------------------------------ 110ms May 08 21:13:34 volumio kernel: usb 1-1: new high-speed USB device number 20 using xhci-hcd May 08 21:13:34 volumio kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=2504, bcdDevice= 0.01 May 08 21:13:35 volumio kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 May 08 21:13:35 volumio kernel: hub 1-1:1.0: USB hub found May 08 21:13:35 volumio kernel: hub 1-1:1.0: 4 ports detected May 08 21:13:35 volumio kernel: usb 1-1.3: new high-speed USB device number 21 using xhci-hcd May 08 21:13:35 volumio kernel: usb 1-1.3: New USB device found, idVendor=1058, idProduct=1021, bcdDevice=20.21 May 08 21:13:35 volumio kernel: usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3 May 08 21:13:35 volumio kernel: usb 1-1.3: Product: Ext HDD 1021 May 08 21:13:35 volumio kernel: usb 1-1.3: Manufacturer: Western Digital May 08 21:13:35 volumio kernel: usb 1-1.3: SerialNumber: 574D43315432393436303735 May 08 21:13:35 volumio kernel: usb-storage 1-1.3:1.0: USB Mass Storage device detected May 08 21:13:35 volumio kernel: scsi host1: usb-storage 1-1.3:1.0 May 08 21:13:35 volumio kernel: usb 1-1.4: new high-speed USB device number 22 using xhci-hcd May 08 21:13:35 volumio kernel: usb 1-1.4: New USB device found, idVendor=152a, idProduct=86fc, bcdDevice= 1.00 May 08 21:13:35 volumio kernel: usb 1-1.4: New USB device strings: Mfr=1, Product=3, SerialNumber=0 May 08 21:13:35 volumio kernel: usb 1-1.4: Product: HIFI DSD May 08 21:13:35 volumio kernel: usb 1-1.4: Manufacturer: Yunyue Audio May 08 21:13:35 volumio kernel: usb 1-1.4: 1:3 : unsupported format bits 0x100000000 May 08 21:13:35 volumio volumio[907]: info: May 08 21:13:35 volumio volumio[907]: ---------------------------- USB Audio Device Attached May 08 21:13:35 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach May 08 21:13:36 volumio volumio[907]: xcb_connection_has_error() returned true May 08 21:13:36 volumio volumio[907]: info: CoreCommandRouter::Close All Modals sent May 08 21:13:36 volumio volumio[907]: info: Preparing to save Alsa Options, stopping services first May 08 21:13:36 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:36 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:36 volumio volumio[907]: info: CoreCommandRouter::volumioPause May 08 21:13:36 volumio volumio[907]: info: CoreStateMachine::pause May 08 21:13:36 volumio volumio[907]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"HIFI DSD","alsacard":"DSD"},"i2s":false} May 08 21:13:36 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 08 21:13:36 volumio volumio[907]: xcb_connection_has_error() returned true May 08 21:13:36 volumio volumio[907]: xcb_connection_has_error() returned true May 08 21:13:36 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 08 21:13:36 volumio volumio[907]: xcb_connection_has_error() returned true May 08 21:13:36 volumio volumio[907]: info: Setting mixer HIFI DSD for card HIFI DSD May 08 21:13:36 volumio volumio[907]: info: QobuzConnect: setDeactiveState invoked May 08 21:13:36 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:36 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:36 volumio vtcs[11908]: [2025-05-08 21:13:36.187] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE May 08 21:13:36 volumio vtcs[11908]: [2025-05-08 21:13:36.187] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected May 08 21:13:36 volumio sudo[11981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 08 21:13:36 volumio sudo[11981]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:13:36 volumio systemd[1]: Stopping Volumio Tidal Connect Service... May 08 21:13:36 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM May 08 21:13:36 volumio systemd[1]: vtcs.service: Succeeded. May 08 21:13:36 volumio systemd[1]: Stopped Volumio Tidal Connect Service. May 08 21:13:36 volumio sudo[11981]: pam_unix(sudo:session): session closed for user root May 08 21:13:36 volumio volumio[907]: xcb_connection_has_error() returned true May 08 21:13:36 volumio volumio[907]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 08 21:13:36 volumio volumio[907]: info: Updating Volume Controller Parameters: Device: 5 Name: HIFI DSD Mixer: HIFI DSD Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 08 21:13:36 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 08 21:13:36 volumio volumio[907]: info: Disabling external Volume Control May 08 21:13:36 volumio volumio[907]: info: CoreCommandRouter::getUIConfigOnPlugin May 08 21:13:36 volumio volumio[907]: info: Preparing to generate the ALSA configuration file May 08 21:13:36 volumio sudo[11988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 08 21:13:36 volumio sudo[11988]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:13:36 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 21:13:36 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 21:13:36 volumio volumio[907]: info: QobuzConnect: Setting Qobuz Connect card as outputdevice to 5 May 08 21:13:36 volumio sudo[11988]: pam_unix(sudo:session): session closed for user root May 08 21:13:36 volumio volumio[907]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 08 21:13:36 volumio volumio[907]: info: Reading ALSA contributions from plugins. May 08 21:13:36 volumio volumio[907]: ------------------------------------ BT MESSAGE: BT STATUS: running May 08 21:13:36 volumio sudo[11995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect May 08 21:13:36 volumio sudo[11995]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:13:36 volumio systemd[1]: Stopping Volumio Qobuz Connect Service... May 08 21:13:36 volumio qobuz-connect[11850]: 20250508 21:13:36.414 [11850.11850] INFO SampleApp: Stopping Local configuration server May 08 21:13:36 volumio volumio[907]: ------------------------------------ BT MESSAGE: BT STATUS: running May 08 21:13:36 volumio qobuz-connect[11850]: 20250508 21:13:36.424 [11850.11850] INFO SampleApp: shat down connection on UNIX socket May 08 21:13:36 volumio systemd[1]: qobuz-connect.service: Succeeded. May 08 21:13:36 volumio systemd[1]: Stopped Volumio Qobuz Connect Service. May 08 21:13:36 volumio volumio[907]: xcb_connection_has_error() returned true May 08 21:13:36 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 08 21:13:36 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 21:13:36 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 08 21:13:36 volumio systemd[1]: Started Volumio Qobuz Connect Service. May 08 21:13:36 volumio sudo[11995]: pam_unix(sudo:session): session closed for user root May 08 21:13:36 volumio kernel: scsi 1:0:0:0: Direct-Access WD Ext HDD 1021 2021 PQ: 0 ANSI: 4 May 08 21:13:36 volumio kernel: sd 1:0:0:0: Attached scsi generic sg0 type 0 May 08 21:13:36 volumio kernel: sd 1:0:0:0: [sdb] 732566016 4096-byte logical blocks: (3.00 TB/2.73 TiB) May 08 21:13:36 volumio kernel: sd 1:0:0:0: [sdb] Write Protect is off May 08 21:13:36 volumio kernel: sd 1:0:0:0: [sdb] Mode Sense: 17 00 10 08 May 08 21:13:36 volumio kernel: sd 1:0:0:0: [sdb] No Caching mode page found May 08 21:13:36 volumio kernel: sd 1:0:0:0: [sdb] Assuming drive cache: write through May 08 21:13:36 volumio kernel: sdb: sdb1 May 08 21:13:36 volumio kernel: sd 1:0:0:0: [sdb] Attached SCSI disk May 08 21:13:36 volumio volumio[907]: xcb_connection_has_error() returned true May 08 21:13:36 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 21:13:36 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 08 21:13:36 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 08 21:13:36 volumio volumio[907]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 08 21:13:36 volumio volumio[907]: info: VolumeController:: Volume=100 Mute =false May 08 21:13:36 volumio volumio[907]: info: CoreStateMachine::pushState May 08 21:13:36 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:36 volumio volumio[907]: info: CoreCommandRouter::volumioPushState May 08 21:13:36 volumio volumio[907]: info: MRS: Pushing multiroomSync output update for this device May 08 21:13:36 volumio volumio[907]: info: MRS: Pushing multiroomSync output May 08 21:13:36 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:36 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:36 volumio volumio[907]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected May 08 21:13:36 volumio volumio[907]: info: QobuzConnect: setDeactiveState invoked May 08 21:13:36 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:36 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:36 volumio volumio[907]: info: Executing endpoint qc_getconfig May 08 21:13:36 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 08 21:13:36 volumio qobuz-connect[11998]: 20250508 21:13:36.543 [11998.11998] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 08 21:13:36 volumio volumio[907]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 08 21:13:36 volumio volumio[907]: info: QobuzConnect: QOBUZ Connect daemon connected May 08 21:13:36 volumio qobuz-connect[11998]: 20250508 21:13:36.547 [11998.11998] INFO VolumeManager: [0x1262a20]: Setting new playback volume: 75 May 08 21:13:36 volumio qobuz-connect[11998]: 20250508 21:13:36.548 [11998.11998] INFO VolumeManager: [0x1262a20]: Setting new mute state: 0 May 08 21:13:36 volumio qobuz-connect[11998]: 20250508 21:13:36.548 [11998.11998] INFO QobuzConnect: [0x12633f0]: Client initialized! May 08 21:13:36 volumio qobuz-connect[11998]: 20250508 21:13:36.548 [11998.11998] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp May 08 21:13:36 volumio volumio[907]: info: Asound.conf file unchanged, so no further update is needed May 08 21:13:36 volumio volumio[907]: info: Output device has changed, restarting MPD May 08 21:13:36 volumio qobuz-connect[11998]: 20250508 21:13:36.555 [11998.11998] INFO LocalConfigManager: [0x1262300]: Starting Local Configuration server May 08 21:13:36 volumio qobuz-connect[11998]: 20250508 21:13:36.555 [11998.11998] INFO SampleApp: Starting Local configuration server May 08 21:13:36 volumio qobuz-connect[11998]: 20250508 21:13:36.555 [11998.11998] INFO SampleApp: Connected to UNIX socket client 0x1257f60 May 08 21:13:36 volumio sudo[12008]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 08 21:13:36 volumio sudo[12008]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:13:36 volumio sudo[12008]: pam_unix(sudo:session): session closed for user root May 08 21:13:36 volumio volumio[907]: info: Output device has changed, restarting Shairport Sync May 08 21:13:36 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 21:13:36 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 21:13:36 volumio sudo[12011]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 08 21:13:36 volumio sudo[12011]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:13:36 volumio systemd[1]: Stopping Music Player Daemon... May 08 21:13:36 volumio volumio[907]: xcb_connection_has_error() returned true May 08 21:13:36 volumio volumio[907]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 08 21:13:36 volumio volumio[907]: xcb_connection_has_error() returned true May 08 21:13:36 volumio volumio[907]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 08 21:13:36 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 21:13:36 volumio systemd[1]: mpd.service: Succeeded. May 08 21:13:36 volumio systemd[1]: Stopped Music Player Daemon. May 08 21:13:36 volumio volumio[907]: info: QobuzConnect: setDeactiveState invoked May 08 21:13:36 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:36 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:36 volumio systemd[1]: Starting Music Player Daemon... May 08 21:13:36 volumio sudo[12020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 08 21:13:36 volumio sudo[12020]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:13:36 volumio qobuz-connect[11998]: 20250508 21:13:36.694 [11998.11998] INFO SampleApp: Playback volume changed: 75 May 08 21:13:36 volumio sudo[12023]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 08 21:13:36 volumio sudo[12023]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:13:36 volumio sudo[12020]: pam_unix(sudo:session): session closed for user root May 08 21:13:36 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 21:13:36 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 21:13:36 volumio volumio[907]: info: QobuzConnect: Setting Qobuz Connect card as outputdevice to 5 May 08 21:13:36 volumio sudo[12023]: pam_unix(sudo:session): session closed for user root May 08 21:13:36 volumio sudo[12017]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 08 21:13:36 volumio volumio[907]: ------------------------------------ BT MESSAGE: BT STATUS: running May 08 21:13:36 volumio sudo[12017]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:13:36 volumio volumio[907]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 08 21:13:36 volumio sudo[12017]: pam_unix(sudo:session): session closed for user root May 08 21:13:36 volumio sudo[12031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect May 08 21:13:36 volumio volumio[907]: ------------------------------------ BT MESSAGE: BT STATUS: running May 08 21:13:36 volumio sudo[12031]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:13:36 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:36 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:36 volumio volumio[907]: info: MPD Permissions set May 08 21:13:36 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 21:13:36 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 21:13:36 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 21:13:36 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 21:13:36 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 21:13:36 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 21:13:36 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 21:13:36 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 21:13:36 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 21:13:36 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 21:13:36 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat May 08 21:13:36 volumio volumio[907]: info: Starting Shairport Sync May 08 21:13:36 volumio sudo[12041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 08 21:13:36 volumio sudo[12041]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:13:36 volumio volumio[907]: info: Mounting Device Elements May 08 21:13:36 volumio volumio[907]: /bin/mkdir: cannot create directory ‘/mnt/USB/Elements’: File exists May 08 21:13:36 volumio volumio[907]: error: Failed to create folder Error: Command failed: /bin/mkdir -m 777 "/mnt/USB/Elements" May 08 21:13:36 volumio volumio[907]: /bin/mkdir: cannot create directory ‘/mnt/USB/Elements’: File exists May 08 21:13:36 volumio qobuz-connect[11998]: 20250508 21:13:36.917 [11998.11998] INFO SampleApp: Stopping Local configuration server May 08 21:13:36 volumio systemd[1]: Stopping Volumio Qobuz Connect Service... May 08 21:13:36 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 08 21:13:36 volumio systemd[1]: shairport-sync.service: Succeeded. May 08 21:13:36 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 08 21:13:36 volumio qobuz-connect[11998]: 20250508 21:13:36.927 [11998.11998] INFO SampleApp: shat down connection on UNIX socket May 08 21:13:36 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 08 21:13:36 volumio sudo[12041]: pam_unix(sudo:session): session closed for user root May 08 21:13:36 volumio systemd[1]: qobuz-connect.service: Succeeded. May 08 21:13:36 volumio systemd[1]: Stopped Volumio Qobuz Connect Service. May 08 21:13:36 volumio sudo[12049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/Elements -o noatime,dmask=0000,fmask=0000,iocharset=utf8 May 08 21:13:36 volumio sudo[12049]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:13:36 volumio systemd[1]: Started Volumio Qobuz Connect Service. May 08 21:13:36 volumio sudo[12031]: pam_unix(sudo:session): session closed for user root May 08 21:13:37 volumio mpd[12035]: May 08 21:13 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 08 21:13:37 volumio systemd[1]: Started Music Player Daemon. May 08 21:13:37 volumio sudo[12011]: pam_unix(sudo:session): session closed for user root May 08 21:13:39 volumio sudo[12049]: pam_unix(sudo:session): session closed for user root May 08 21:13:39 volumio volumio[907]: ntfs-3g-mount: failed to access mountpoint /media/Elements: Transport endpoint is not connected May 08 21:13:39 volumio volumio[907]: error: Failed to mount Elements: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sdb1" "/mnt/USB/Elements" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 May 08 21:13:39 volumio volumio[907]: ntfs-3g-mount: failed to access mountpoint /media/Elements: Transport endpoint is not connected May 08 21:13:39 volumio volumio[907]: ------------------------------------ BT MESSAGE: BT STATUS: running May 08 21:13:39 volumio volumio[907]: ------------------------------------ BT MESSAGE: BT STATUS: running May 08 21:13:39 volumio volumio[907]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 08 21:13:39 volumio volumio[907]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 08 21:13:39 volumio volumio[907]: info: TidalConnect service stoped! May 08 21:13:39 volumio volumio[907]: info: TidalConnect service stoped! May 08 21:13:39 volumio volumio[907]: info: TidalConnect service stoped! May 08 21:13:39 volumio volumio[907]: info: TidalConnect service stoped! May 08 21:13:39 volumio volumio[907]: info: Shairport-Sync Started May 08 21:13:39 volumio volumio[907]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected May 08 21:13:39 volumio volumio[907]: info: QobuzConnect: setDeactiveState invoked May 08 21:13:39 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:39 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:39 volumio volumio[907]: info: Executing endpoint qc_getconfig May 08 21:13:39 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 08 21:13:39 volumio qobuz-connect[12052]: 20250508 21:13:39.994 [12052.12052] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 08 21:13:40 volumio qobuz-connect[12052]: 20250508 21:13:40.000 [12052.12052] INFO VolumeManager: [0x1dcaa20]: Setting new playback volume: 75 May 08 21:13:40 volumio qobuz-connect[12052]: 20250508 21:13:40.001 [12052.12052] INFO VolumeManager: [0x1dcaa20]: Setting new mute state: 0 May 08 21:13:40 volumio qobuz-connect[12052]: 20250508 21:13:40.001 [12052.12052] INFO QobuzConnect: [0x1dcb3f0]: Client initialized! May 08 21:13:40 volumio qobuz-connect[12052]: 20250508 21:13:40.001 [12052.12052] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp May 08 21:13:40 volumio qobuz-connect[12052]: 20250508 21:13:40.010 [12052.12052] INFO LocalConfigManager: [0x1dca300]: Starting Local Configuration server May 08 21:13:40 volumio qobuz-connect[12052]: 20250508 21:13:40.010 [12052.12052] INFO SampleApp: Starting Local configuration server May 08 21:13:40 volumio qobuz-connect[12052]: 20250508 21:13:40.011 [12052.12052] INFO SampleApp: Connected to UNIX socket client 0x1dbff60 May 08 21:13:40 volumio dnsmasq-dhcp[895]: no address range available for DHCP request via wlan0 May 08 21:13:40 volumio volumio[907]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 08 21:13:40 volumio volumio[907]: info: QobuzConnect: QOBUZ Connect daemon connected May 08 21:13:40 volumio volumio[907]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 08 21:13:40 volumio volumio[907]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 08 21:13:40 volumio dnsmasq-dhcp[895]: no address range available for DHCP request via wlan0 May 08 21:13:40 volumio volumio[907]: info: Scanning removed location : "USB/Elements" May 08 21:13:40 volumio volumio[907]: error: updateQueue error: null May 08 21:13:40 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand status May 08 21:13:40 volumio volumio[907]: info: May 08 21:13:40 volumio volumio[907]: ---------------------------- MPD announces state update: update May 08 21:13:40 volumio volumio[907]: info: ControllerMpd::getState May 08 21:13:40 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand status May 08 21:13:40 volumio volumio[907]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 08 21:13:40 volumio volumio[907]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 08 21:13:40 volumio sudo[12080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 08 21:13:40 volumio sudo[12080]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:13:40 volumio volumio[907]: info: MPD Database updated - AlbumList cache refreshed May 08 21:13:40 volumio volumio[907]: info: May 08 21:13:40 volumio volumio[907]: ---------------------------- MPD announces state update: database May 08 21:13:40 volumio qobuz-connect[12052]: 20250508 21:13:40.144 [12052.12052] INFO SampleApp: Playback volume changed: 75 May 08 21:13:40 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand status May 08 21:13:40 volumio volumio[907]: info: May 08 21:13:40 volumio volumio[907]: ---------------------------- MPD announces state update: update May 08 21:13:40 volumio volumio[907]: info: ControllerMpd::getState May 08 21:13:40 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand status May 08 21:13:40 volumio volumio[907]: info: ControllerMpd::getState May 08 21:13:40 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand status May 08 21:13:40 volumio volumio[907]: info: sendMpdCommand status took 59 milliseconds May 08 21:13:40 volumio volumio[907]: info: sendMpdCommand status took 58 milliseconds May 08 21:13:40 volumio volumio[907]: verbose: ControllerMpd::parseState May 08 21:13:40 volumio sudo[12083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 08 21:13:40 volumio sudo[12083]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:13:40 volumio volumio[907]: info: Command Router : Notfying DB Updatefalse May 08 21:13:40 volumio volumio[907]: info: CoreCommandRouter::Close All Modals sent May 08 21:13:40 volumio volumio[907]: verbose: ControllerMpd::parseState May 08 21:13:40 volumio volumio[907]: info: ControllerMpd::pushState May 08 21:13:40 volumio volumio[907]: info: CoreCommandRouter::servicePushState May 08 21:13:40 volumio volumio[907]: info: CoreStateMachine::pushState May 08 21:13:40 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:40 volumio volumio[907]: info: CoreCommandRouter::volumioPushState May 08 21:13:40 volumio volumio[907]: info: MRS: Pushing multiroomSync output update for this device May 08 21:13:40 volumio volumio[907]: info: MRS: Pushing multiroomSync output May 08 21:13:40 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:40 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:40 volumio systemd[1]: Started Volumio Tidal Connect Service. May 08 21:13:40 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:40 volumio volumio[907]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 08 21:13:40 volumio volumio[907]: verbose: CURRENT POSITION 0 May 08 21:13:40 volumio volumio[907]: info: CoreStateMachine::syncState stateService stop May 08 21:13:40 volumio volumio[907]: info: CoreStateMachine::syncState currentStatus stop May 08 21:13:40 volumio volumio[907]: info: CoreStateMachine::pushState May 08 21:13:40 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:40 volumio volumio[907]: info: CoreCommandRouter::volumioPushState May 08 21:13:40 volumio volumio[907]: info: MRS: Pushing multiroomSync output update for this device May 08 21:13:40 volumio volumio[907]: info: MRS: Pushing multiroomSync output May 08 21:13:40 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:40 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:40 volumio volumio[907]: info: No code May 08 21:13:40 volumio volumio[907]: info: CoreStateMachine::pushState May 08 21:13:40 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:40 volumio volumio[907]: info: CoreCommandRouter::volumioPushState May 08 21:13:40 volumio volumio[907]: info: MRS: Pushing multiroomSync output update for this device May 08 21:13:40 volumio volumio[907]: info: MRS: Pushing multiroomSync output May 08 21:13:40 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:40 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:40 volumio sudo[12080]: pam_unix(sudo:session): session closed for user root May 08 21:13:40 volumio sudo[12083]: pam_unix(sudo:session): session closed for user root May 08 21:13:40 volumio volumio[907]: info: ------------------------------ 153ms May 08 21:13:40 volumio volumio[907]: info: sendMpdCommand status took 98 milliseconds May 08 21:13:40 volumio volumio[907]: info: sendMpdCommand status took 97 milliseconds May 08 21:13:40 volumio volumio[907]: info: sendMpdCommand status took 96 milliseconds May 08 21:13:40 volumio volumio[907]: verbose: ControllerMpd::parseState May 08 21:13:40 volumio volumio[907]: info: Command Router : Notfying DB Updatefalse May 08 21:13:40 volumio volumio[907]: info: CoreCommandRouter::Close All Modals sent May 08 21:13:40 volumio volumio[907]: verbose: ControllerMpd::parseState May 08 21:13:40 volumio volumio[907]: verbose: ControllerMpd::parseState May 08 21:13:40 volumio volumio[907]: info: ControllerMpd::pushState May 08 21:13:40 volumio volumio[907]: info: CoreCommandRouter::servicePushState May 08 21:13:40 volumio volumio[907]: info: CoreStateMachine::pushState May 08 21:13:40 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:40 volumio volumio[907]: info: CoreCommandRouter::volumioPushState May 08 21:13:40 volumio volumio[907]: info: MRS: Pushing multiroomSync output update for this device May 08 21:13:40 volumio volumio[907]: info: MRS: Pushing multiroomSync output May 08 21:13:40 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:40 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:40 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:40 volumio volumio[907]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 08 21:13:40 volumio volumio[907]: verbose: CURRENT POSITION 0 May 08 21:13:40 volumio volumio[907]: info: CoreStateMachine::syncState stateService stop May 08 21:13:40 volumio volumio[907]: info: CoreStateMachine::syncState currentStatus stop May 08 21:13:40 volumio volumio[907]: info: CoreStateMachine::pushState May 08 21:13:40 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:40 volumio volumio[907]: info: CoreCommandRouter::volumioPushState May 08 21:13:40 volumio volumio[907]: info: MRS: Pushing multiroomSync output update for this device May 08 21:13:40 volumio volumio[907]: info: MRS: Pushing multiroomSync output May 08 21:13:40 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:40 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:40 volumio volumio[907]: info: No code May 08 21:13:40 volumio volumio[907]: info: CoreStateMachine::pushState May 08 21:13:40 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:40 volumio volumio[907]: info: CoreCommandRouter::volumioPushState May 08 21:13:40 volumio volumio[907]: info: MRS: Pushing multiroomSync output update for this device May 08 21:13:40 volumio volumio[907]: info: MRS: Pushing multiroomSync output May 08 21:13:40 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:40 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:40 volumio volumio[907]: info: ControllerMpd::pushState May 08 21:13:40 volumio volumio[907]: info: CoreCommandRouter::servicePushState May 08 21:13:40 volumio volumio[907]: info: CoreStateMachine::pushState May 08 21:13:40 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:40 volumio volumio[907]: info: CoreCommandRouter::volumioPushState May 08 21:13:40 volumio volumio[907]: info: MRS: Pushing multiroomSync output update for this device May 08 21:13:40 volumio volumio[907]: info: MRS: Pushing multiroomSync output May 08 21:13:40 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:40 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:40 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:40 volumio volumio[907]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 08 21:13:40 volumio volumio[907]: verbose: CURRENT POSITION 0 May 08 21:13:40 volumio volumio[907]: info: CoreStateMachine::syncState stateService stop May 08 21:13:40 volumio volumio[907]: info: CoreStateMachine::syncState currentStatus stop May 08 21:13:40 volumio volumio[907]: info: CoreStateMachine::pushState May 08 21:13:40 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:40 volumio volumio[907]: info: CoreCommandRouter::volumioPushState May 08 21:13:40 volumio volumio[907]: info: MRS: Pushing multiroomSync output update for this device May 08 21:13:40 volumio volumio[907]: info: MRS: Pushing multiroomSync output May 08 21:13:40 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:40 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:40 volumio volumio[907]: info: No code May 08 21:13:40 volumio volumio[907]: info: CoreStateMachine::pushState May 08 21:13:40 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:40 volumio volumio[907]: info: CoreCommandRouter::volumioPushState May 08 21:13:40 volumio volumio[907]: info: MRS: Pushing multiroomSync output update for this device May 08 21:13:40 volumio volumio[907]: info: MRS: Pushing multiroomSync output May 08 21:13:40 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:40 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:40 volumio volumio[907]: info: ------------------------------ 195ms May 08 21:13:40 volumio volumio[907]: info: ------------------------------ 189ms May 08 21:13:40 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:40 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:40 volumio volumio[907]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 08 21:13:40 volumio volumio[907]: info: CoreStateMachine::getcurrentVolume May 08 21:13:40 volumio volumio[907]: info: CoreCommandRouter::volumioRetrievevolume May 08 21:13:40 volumio volumio[907]: info: Executing endpoint tc_getconfig May 08 21:13:40 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 08 21:13:40 volumio vtcs[12084]: STARTING TidalConnect services, version: 1.4.0.34 May 08 21:13:40 volumio volumio[907]: info: VolumeController:: Volume=100 Mute =false May 08 21:13:40 volumio volumio[907]: info: CoreStateMachine::pushState May 08 21:13:40 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:40 volumio volumio[907]: info: CoreCommandRouter::volumioPushState May 08 21:13:40 volumio volumio[907]: info: MRS: Pushing multiroomSync output update for this device May 08 21:13:40 volumio volumio[907]: info: MRS: Pushing multiroomSync output May 08 21:13:40 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:40 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:40 volumio volumio[907]: info: CoreStateMachine::updateTrackBlock May 08 21:13:40 volumio volumio[907]: info: CorePlayQueue::getTrackBlock May 08 21:13:40 volumio volumio[907]: info: CoreCommandRouter::volumioRetrievevolume May 08 21:13:40 volumio vtcs[12084]: STARTED TidalConnect services. May 08 21:13:40 volumio volumio[907]: info: Executing endpoint tc_connect May 08 21:13:40 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 08 21:13:40 volumio volumio[907]: info: Connecting to TidalConnect May 08 21:13:40 volumio volumio[907]: info: CoreCommandRouter::servicePushState May 08 21:13:40 volumio volumio[907]: info: CoreStateMachine::pushState May 08 21:13:40 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:40 volumio volumio[907]: info: CoreCommandRouter::volumioPushState May 08 21:13:40 volumio volumio[907]: info: MRS: Pushing multiroomSync output update for this device May 08 21:13:40 volumio volumio[907]: info: MRS: Pushing multiroomSync output May 08 21:13:40 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:40 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:40 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:40 volumio volumio[907]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} May 08 21:13:40 volumio volumio[907]: verbose: CURRENT POSITION 0 May 08 21:13:40 volumio volumio[907]: info: CoreStateMachine::syncState stateService stop May 08 21:13:40 volumio volumio[907]: info: CoreStateMachine::syncState currentStatus stop May 08 21:13:40 volumio volumio[907]: info: CoreStateMachine::pushState May 08 21:13:40 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:40 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 21:13:40 volumio volumio[907]: info: CoreCommandRouter::volumioPushState May 08 21:13:40 volumio volumio[907]: info: MRS: Pushing multiroomSync output update for this device May 08 21:13:40 volumio volumio[907]: info: MRS: Pushing multiroomSync output May 08 21:13:40 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:40 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:40 volumio volumio[907]: info: No code May 08 21:13:40 volumio volumio[907]: info: CoreStateMachine::pushState May 08 21:13:40 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:40 volumio volumio[907]: info: CoreCommandRouter::volumioPushState May 08 21:13:40 volumio volumio[907]: info: MRS: Pushing multiroomSync output update for this device May 08 21:13:40 volumio volumio[907]: info: MRS: Pushing multiroomSync output May 08 21:13:40 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:40 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:40 volumio volumio[907]: info: CoreCommandRouter::servicePushState May 08 21:13:40 volumio volumio[907]: info: CoreStateMachine::pushState May 08 21:13:40 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:40 volumio volumio[907]: info: CoreCommandRouter::volumioPushState May 08 21:13:40 volumio volumio[907]: info: MRS: Pushing multiroomSync output update for this device May 08 21:13:40 volumio volumio[907]: info: MRS: Pushing multiroomSync output May 08 21:13:40 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:40 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:40 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:40 volumio volumio[907]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} May 08 21:13:40 volumio volumio[907]: verbose: CURRENT POSITION 0 May 08 21:13:40 volumio volumio[907]: info: CoreStateMachine::syncState stateService stop May 08 21:13:40 volumio volumio[907]: info: CoreStateMachine::syncState currentStatus stop May 08 21:13:40 volumio volumio[907]: info: CoreStateMachine::pushState May 08 21:13:40 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:40 volumio volumio[907]: info: CoreCommandRouter::volumioPushState May 08 21:13:40 volumio volumio[907]: info: MRS: Pushing multiroomSync output update for this device May 08 21:13:40 volumio volumio[907]: info: MRS: Pushing multiroomSync output May 08 21:13:40 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:40 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:40 volumio volumio[907]: info: No code May 08 21:13:40 volumio volumio[907]: info: CoreStateMachine::pushState May 08 21:13:40 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:40 volumio volumio[907]: info: CoreCommandRouter::volumioPushState May 08 21:13:40 volumio volumio[907]: info: MRS: Pushing multiroomSync output update for this device May 08 21:13:40 volumio volumio[907]: info: MRS: Pushing multiroomSync output May 08 21:13:40 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:40 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:40 volumio volumio[907]: info: VolumeController:: Volume=100 Mute =false May 08 21:13:40 volumio volumio[907]: info: CoreStateMachine::pushState May 08 21:13:40 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:40 volumio volumio[907]: info: CoreCommandRouter::volumioPushState May 08 21:13:40 volumio volumio[907]: info: MRS: Pushing multiroomSync output update for this device May 08 21:13:40 volumio volumio[907]: info: MRS: Pushing multiroomSync output May 08 21:13:40 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:40 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:43 volumio volumio[907]: info: TidalConnect service started! May 08 21:13:43 volumio volumio[907]: info: TidalConnect service started! May 08 21:13:51 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 08 21:13:51 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 08 21:13:51 volumio volumio[907]: info: Discovery: Getting this device information May 08 21:13:51 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:13:51 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:13:51 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 08 21:14:18 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 08 21:14:18 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 08 21:14:18 volumio volumio[907]: info: Discovery: Getting this device information May 08 21:14:18 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:14:18 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:14:18 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 08 21:14:18 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 08 21:14:18 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 08 21:14:18 volumio volumio[907]: info: Discovery: Getting this device information May 08 21:14:18 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:14:18 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:14:18 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 08 21:14:18 volumio volumio[907]: verbose: New Socket.io Connection to 192.168.2.92:3000 from 192.168.2.79 UA: Dart/3.7 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 May 08 21:14:18 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:14:18 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:14:18 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 08 21:14:18 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 08 21:14:19 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 08 21:14:19 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 08 21:14:19 volumio volumio[907]: info: Discovery: Getting this device information May 08 21:14:19 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:14:19 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:14:19 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 08 21:14:19 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 08 21:14:19 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 08 21:14:19 volumio volumio[907]: info: Discovery: Getting this device information May 08 21:14:19 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:14:19 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:14:19 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 08 21:14:20 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:14:20 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:14:21 volumio sudo[12219]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 08 21:14:21 volumio sudo[12219]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:14:21 volumio sudo[12219]: pam_unix(sudo:session): session closed for user root May 08 21:14:21 volumio sudo[12222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 08 21:14:21 volumio sudo[12222]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:14:21 volumio sudo[12222]: pam_unix(sudo:session): session closed for user root May 08 21:14:21 volumio volumio[907]: verbose: New Socket.io Connection to 192.168.2.92 from 192.168.2.79 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 May 08 21:14:21 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 08 21:14:21 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 08 21:14:21 volumio sudo[12225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 08 21:14:21 volumio sudo[12225]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:14:21 volumio sudo[12225]: pam_unix(sudo:session): session closed for user root May 08 21:14:21 volumio sudo[12228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 08 21:14:21 volumio sudo[12228]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:14:21 volumio sudo[12228]: pam_unix(sudo:session): session closed for user root May 08 21:14:21 volumio volumio[907]: verbose: New Socket.io Connection to 192.168.2.92 from 192.168.2.79 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 May 08 21:14:21 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 08 21:14:21 volumio volumio[907]: info: CoreCommandRouter::volumioGetVisibleSources May 08 21:14:21 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 08 21:14:21 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:14:21 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:14:21 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 08 21:14:21 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 08 21:14:21 volumio volumio[907]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 08 21:14:21 volumio volumio[907]: info: Received Get System Info May 08 21:14:21 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 08 21:14:21 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 08 21:14:21 volumio volumio[907]: info: Discovery: Getting this device information May 08 21:14:21 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:14:21 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:14:21 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 08 21:14:21 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:14:21 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:14:21 volumio volumio[907]: info: Listing playlists May 08 21:14:21 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 08 21:14:21 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 08 21:14:21 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 08 21:14:23 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 08 21:14:23 volumio volumio[907]: info: CoreCommandRouter::volumioGetQueue May 08 21:14:23 volumio volumio[907]: info: CoreStateMachine::getQueue May 08 21:14:23 volumio volumio[907]: info: CorePlayQueue::getQueue May 08 21:14:23 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 08 21:14:23 volumio volumio[907]: info: Received Get System Info May 08 21:14:23 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 08 21:14:23 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 08 21:14:23 volumio volumio[907]: info: Discovery: Getting this device information May 08 21:14:23 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:14:23 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:14:23 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 08 21:14:24 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 08 21:14:24 volumio volumio[907]: info: Received Get System Info May 08 21:14:24 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 08 21:14:24 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 08 21:14:24 volumio volumio[907]: info: Discovery: Getting this device information May 08 21:14:24 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:14:24 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:14:24 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 08 21:14:31 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 08 21:14:31 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 08 21:14:31 volumio volumio[907]: info: CURURI: music-library May 08 21:14:31 volumio volumio[907]: error: Failed LSINFO: null May 08 21:14:31 volumio volumio[907]: info: Preload queue cleared May 08 21:14:38 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 08 21:14:38 volumio volumio[907]: info: CURURI: genres:// May 08 21:14:38 volumio volumio[907]: info: Preload queue cleared May 08 21:14:39 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 08 21:14:39 volumio volumio[907]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 08 21:14:39 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 08 21:14:39 volumio volumio[907]: info: Received Get System Version May 08 21:14:39 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 08 21:14:39 volumio volumio[907]: info: Received Get System Info May 08 21:14:39 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 08 21:14:39 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 08 21:14:39 volumio volumio[907]: info: Discovery: Getting this device information May 08 21:14:39 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:14:39 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:14:39 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 08 21:14:43 volumio volumio[907]: info: CoreCommandRouter::getUIConfigOnPlugin May 08 21:14:43 volumio volumio[907]: info: CoreCommandRouter::getUIConfigOnPlugin May 08 21:14:43 volumio volumio[907]: info: Retrieving Cloud Streaming UI May 08 21:14:43 volumio volumio[907]: info: Getting Tidal Cloud Configuration May 08 21:14:43 volumio volumio[907]: info: CoreCommandRouter::getUIConfigOnPlugin May 08 21:14:43 volumio volumio[907]: info: Getting Qobuz Cloud Configuration May 08 21:14:43 volumio volumio[907]: info: Asking plugin for UI Config May 08 21:14:43 volumio volumio[907]: info: CoreCommandRouter::getUIConfigOnPlugin May 08 21:14:43 volumio volumio[907]: info: Getting Spotify Cloud Configuration May 08 21:14:43 volumio volumio[907]: info: Asking plugin for UI Config May 08 21:14:43 volumio volumio[907]: info: CoreCommandRouter::getUIConfigOnPlugin May 08 21:14:43 volumio volumio[907]: info: Saving Spotify Acccount May 08 21:14:43 volumio volumio[907]: info: Got it May 08 21:14:43 volumio volumio[907]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 08 21:14:43 volumio volumio[907]: info: Got Tidal Cloud Configuration May 08 21:14:43 volumio volumio[907]: info: Got it May 08 21:14:43 volumio volumio[907]: info: CoreCommandRouter::getUIConfigOnPlugin May 08 21:14:43 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 08 21:14:43 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 08 21:14:43 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 08 21:14:43 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 08 21:14:43 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 21:14:43 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 21:14:43 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 21:14:43 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 21:14:43 volumio volumio[907]: info: CoreCommandRouter::volumioGetBrowseSources May 08 21:14:43 volumio volumio[907]: info: CoreCommandRouter::volumioGetBrowseSources May 08 21:14:43 volumio volumio[907]: info: CoreCommandRouter::volumioGetBrowseSources May 08 21:14:43 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 21:14:43 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 08 21:14:45 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb May 08 21:14:45 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand rescan May 08 21:14:45 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand status May 08 21:14:45 volumio volumio[907]: info: May 08 21:14:45 volumio volumio[907]: ---------------------------- MPD announces state update: update May 08 21:14:45 volumio volumio[907]: info: sendMpdCommand rescan took 10 milliseconds May 08 21:14:45 volumio volumio[907]: info: ControllerMpd::getState May 08 21:14:45 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand status May 08 21:14:45 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand status May 08 21:14:45 volumio volumio[907]: info: May 08 21:14:45 volumio volumio[907]: ---------------------------- MPD announces state update: update May 08 21:14:45 volumio volumio[907]: info: ControllerMpd::getState May 08 21:14:45 volumio volumio[907]: verbose: ControllerMpd::sendMpdCommand status May 08 21:14:45 volumio volumio[907]: info: sendMpdCommand status took 4 milliseconds May 08 21:14:45 volumio volumio[907]: info: sendMpdCommand status took 3 milliseconds May 08 21:14:45 volumio volumio[907]: verbose: ControllerMpd::parseState May 08 21:14:45 volumio volumio[907]: info: Command Router : Notfying DB Updatefalse May 08 21:14:45 volumio volumio[907]: info: CoreCommandRouter::Close All Modals sent May 08 21:14:45 volumio volumio[907]: verbose: ControllerMpd::parseState May 08 21:14:45 volumio volumio[907]: info: ControllerMpd::pushState May 08 21:14:45 volumio volumio[907]: info: CoreCommandRouter::servicePushState May 08 21:14:45 volumio volumio[907]: info: CoreStateMachine::pushState May 08 21:14:45 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:14:45 volumio volumio[907]: info: CoreCommandRouter::volumioPushState May 08 21:14:45 volumio volumio[907]: info: MRS: Pushing multiroomSync output update for this device May 08 21:14:45 volumio volumio[907]: info: MRS: Pushing multiroomSync output May 08 21:14:45 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:14:45 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:14:45 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:14:45 volumio volumio[907]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 08 21:14:45 volumio volumio[907]: verbose: CURRENT POSITION 0 May 08 21:14:45 volumio volumio[907]: info: CoreStateMachine::syncState stateService stop May 08 21:14:45 volumio volumio[907]: info: CoreStateMachine::syncState currentStatus stop May 08 21:14:45 volumio volumio[907]: info: CoreStateMachine::pushState May 08 21:14:45 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:14:45 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 21:14:45 volumio volumio[907]: info: CoreCommandRouter::volumioPushState May 08 21:14:45 volumio volumio[907]: info: MRS: Pushing multiroomSync output update for this device May 08 21:14:45 volumio volumio[907]: info: MRS: Pushing multiroomSync output May 08 21:14:45 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:14:45 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:14:45 volumio volumio[907]: info: No code May 08 21:14:45 volumio volumio[907]: info: CoreStateMachine::pushState May 08 21:14:45 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:14:45 volumio volumio[907]: info: CoreCommandRouter::volumioPushState May 08 21:14:45 volumio volumio[907]: info: MRS: Pushing multiroomSync output update for this device May 08 21:14:45 volumio volumio[907]: info: MRS: Pushing multiroomSync output May 08 21:14:45 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:14:45 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:14:45 volumio volumio[907]: info: ------------------------------ 121ms May 08 21:14:45 volumio volumio[907]: info: sendMpdCommand status took 120 milliseconds May 08 21:14:45 volumio volumio[907]: info: sendMpdCommand status took 119 milliseconds May 08 21:14:45 volumio volumio[907]: verbose: ControllerMpd::parseState May 08 21:14:45 volumio volumio[907]: info: Command Router : Notfying DB Updatefalse May 08 21:14:45 volumio volumio[907]: info: CoreCommandRouter::Close All Modals sent May 08 21:14:45 volumio volumio[907]: verbose: ControllerMpd::parseState May 08 21:14:45 volumio volumio[907]: info: ControllerMpd::pushState May 08 21:14:45 volumio volumio[907]: info: CoreCommandRouter::servicePushState May 08 21:14:45 volumio volumio[907]: info: CoreStateMachine::pushState May 08 21:14:45 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:14:45 volumio volumio[907]: info: CoreCommandRouter::volumioPushState May 08 21:14:45 volumio volumio[907]: info: MRS: Pushing multiroomSync output update for this device May 08 21:14:45 volumio volumio[907]: info: MRS: Pushing multiroomSync output May 08 21:14:45 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:14:45 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:14:45 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:14:45 volumio volumio[907]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 08 21:14:45 volumio volumio[907]: verbose: CURRENT POSITION 0 May 08 21:14:45 volumio volumio[907]: info: CoreStateMachine::syncState stateService stop May 08 21:14:45 volumio volumio[907]: info: CoreStateMachine::syncState currentStatus stop May 08 21:14:45 volumio volumio[907]: info: CoreStateMachine::pushState May 08 21:14:45 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:14:45 volumio volumio[907]: info: CoreCommandRouter::volumioPushState May 08 21:14:45 volumio volumio[907]: info: MRS: Pushing multiroomSync output update for this device May 08 21:14:45 volumio volumio[907]: info: MRS: Pushing multiroomSync output May 08 21:14:45 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:14:45 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:14:45 volumio volumio[907]: info: No code May 08 21:14:45 volumio volumio[907]: info: CoreStateMachine::pushState May 08 21:14:45 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:14:45 volumio volumio[907]: info: CoreCommandRouter::volumioPushState May 08 21:14:45 volumio volumio[907]: info: MRS: Pushing multiroomSync output update for this device May 08 21:14:45 volumio volumio[907]: info: MRS: Pushing multiroomSync output May 08 21:14:45 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:14:45 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:14:45 volumio volumio[907]: info: ------------------------------ 216ms May 08 21:14:47 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 21:14:47 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares May 08 21:14:47 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService May 08 21:14:48 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 08 21:14:48 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 08 21:14:48 volumio volumio[907]: info: Discovery: Getting this device information May 08 21:14:48 volumio volumio[907]: info: CoreCommandRouter::volumioGetState May 08 21:14:48 volumio volumio[907]: info: CorePlayQueue::getTrack 0 May 08 21:14:48 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 08 21:14:49 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares May 08 21:14:49 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService May 08 21:14:51 volumio volumio[907]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 21:14:52 volumio volumio[907]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 08 21:14:52 volumio volumio[907]: Error: dns service error: unknown May 08 21:14:52 volumio volumio[907]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) May 08 21:14:52 volumio volumio[907]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { May 08 21:14:52 volumio volumio[907]: errorCode: -65537 May 08 21:14:52 volumio volumio[907]: } May 08 21:14:52 volumio volumio[907]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 08 21:14:52 volumio sudo[12303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-08 21:13 May 08 21:14:52 volumio sudo[12303]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 30 Apr 2025 11:47:57 AM CEST" VOLUMIO_VERSION="3.807" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fddd10196af80fdc784c726185a6abe4"