-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Fri 2025-05-16 17:00:48 CEST. -- May 16 16:59:12 volumio volumio[989]: info: CALLMETHOD: music_service cd_controller ripCD [object Object] May 16 16:59:12 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: cd_controller , ripCD May 16 16:59:12 volumio volumio[989]: info: Set CD Speed to 0X May 16 16:59:12 volumio volumio[989]: info: Starting CD RIP TO DESTINATION: /mnt/USB/T7 May 16 16:59:14 volumio kernel: usb usb2-port1: over-current change #1 May 16 16:59:14 volumio kernel: usb 1-1-port1: over-current change #1 May 16 16:59:14 volumio kernel: usb 2-1: cmd cmplt err -71 May 16 16:59:14 volumio ntfs-3g[10968]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 16 16:59:14 volumio ntfs-3g[10968]: Reading $BITMAP failed: Input/output error May 16 16:59:14 volumio ntfs-3g[10968]: Failed to allocate clusters: Input/output error May 16 16:59:14 volumio ntfs-3g[10968]: Cluster allocation failed (1): Input/output error May 16 16:59:14 volumio ntfs-3g[10968]: Failed to enlarge attribute: Input/output error May 16 16:59:14 volumio ntfs-3g[10968]: ntfs_attr_mst_pwrite: written=-1: Input/output error May 16 16:59:14 volumio ntfs-3g[10968]: Failed to write index block 0, inode 1284: Input/output error May 16 16:59:14 volumio ntfs-3g[10968]: Failed to nodify INDEX_ROOT: Input/output error May 16 16:59:14 volumio ntfs-3g[10968]: Failed to add entry to the index: Input/output error May 16 16:59:14 volumio kernel: usb 2-1: USB disconnect, device number 4 May 16 16:59:15 volumio kernel: usb 1-1-port2: over-current change #1 May 16 16:59:15 volumio kernel: sd 1:0:0:0: [sda] tag#4 uas_zap_pending 0 uas-tag 1 inflight: CMD May 16 16:59:15 volumio kernel: sd 1:0:0:0: [sda] tag#4 CDB: opcode=0x28 28 00 00 5f 3c e8 00 00 10 00 May 16 16:59:15 volumio kernel: sd 1:0:0:0: [sda] tag#4 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=DRIVER_OK cmd_age=0s May 16 16:59:15 volumio kernel: sd 1:0:0:0: [sda] tag#4 CDB: opcode=0x28 28 00 00 5f 3c e8 00 00 10 00 May 16 16:59:15 volumio kernel: I/O error, dev sda, sector 6241512 op 0x0:(READ) flags 0x80700 phys_seg 2 prio class 2 May 16 16:59:15 volumio kernel: device offline error, dev sda, sector 6241512 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 16 16:59:15 volumio kernel: Buffer I/O error on dev sda1, logical block 779933, async page read May 16 16:59:15 volumio kernel: device offline error, dev sda, sector 6293512 op 0x1:(WRITE) flags 0x0 phys_seg 1 prio class 2 May 16 16:59:15 volumio kernel: Buffer I/O error on dev sda1, logical block 786433, lost async page write May 16 16:59:15 volumio kernel: device offline error, dev sda, sector 6293744 op 0x1:(WRITE) flags 0x0 phys_seg 1 prio class 2 May 16 16:59:15 volumio kernel: Buffer I/O error on dev sda1, logical block 786462, lost async page write May 16 16:59:15 volumio kernel: device offline error, dev sda, sector 6296072 op 0x1:(WRITE) flags 0x0 phys_seg 1 prio class 2 May 16 16:59:15 volumio kernel: Buffer I/O error on dev sda1, logical block 786753, lost async page write May 16 16:59:15 volumio kernel: device offline error, dev sda, sector 6296096 op 0x1:(WRITE) flags 0x0 phys_seg 1 prio class 2 May 16 16:59:15 volumio kernel: Buffer I/O error on dev sda1, logical block 786756, lost async page write May 16 16:59:15 volumio kernel: device offline error, dev sda, sector 131088 op 0x1:(WRITE) flags 0x0 phys_seg 1 prio class 2 May 16 16:59:15 volumio kernel: Buffer I/O error on dev sda1, logical block 16130, lost async page write May 16 16:59:15 volumio kernel: device offline error, dev sda, sector 193680 op 0x1:(WRITE) flags 0x0 phys_seg 1 prio class 2 May 16 16:59:15 volumio kernel: Buffer I/O error on dev sda1, logical block 23954, lost async page write May 16 16:59:15 volumio kernel: device offline error, dev sda, sector 6296072 op 0x1:(WRITE) flags 0x800 phys_seg 1 prio class 2 May 16 16:59:15 volumio kernel: Buffer I/O error on dev sda1, logical block 786753, lost async page write May 16 16:59:15 volumio kernel: device offline error, dev sda, sector 131088 op 0x1:(WRITE) flags 0x800 phys_seg 1 prio class 2 May 16 16:59:15 volumio kernel: Buffer I/O error on dev sda1, logical block 16130, lost async page write May 16 16:59:15 volumio kernel: Buffer I/O error on dev sda1, logical block 779933, async page read May 16 16:59:15 volumio kernel: usb 1-1-port3: over-current change #1 May 16 16:59:15 volumio kernel: sd 1:0:0:0: [sda] Synchronizing SCSI cache May 16 16:59:15 volumio kernel: usb 1-1-port4: over-current change #1 May 16 16:59:15 volumio kernel: hwmon hwmon1: Undervoltage detected! May 16 16:59:15 volumio kernel: usb 1-1.2: reset high-speed USB device number 4 using xhci_hcd May 16 16:59:15 volumio kernel: sd 1:0:0:0: [sda] Synchronize Cache(10) failed: Result: hostbyte=0x07 driverbyte=DRIVER_OK May 16 16:59:14 volumio ntfs-3g[10968]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 16 16:59:15 volumio volumio[989]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host1/target1:0:0/1:0:0:0/block/sda/sda1","ACTION":"remove","DEVLINKS":"/dev/disk/by-uuid/5296746596744B8B /dev/disk/by-partuuid/badacb7f-01 /dev/disk/by-label/T7 /dev/disk/by-id/usb-Samsung_PSSD_T7_S6U9NS0RC28546M-0:0-part1 /dev/disk/by-path/platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1:1.0-scsi-0:0:0:0-part1","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host1/target1:0:0/1:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"33","ID_BUS":"usb","ID_FS_LABEL":"T7","ID_FS_LABEL_ENC":"T7","ID_FS_TYPE":"ntfs","ID_FS_USAGE":"filesystem","ID_FS_UUID":"5296746596744B8B","ID_FS_UUID_ENC":"5296746596744B8B","ID_INSTANCE":"0:0","ID_MODEL":"PSSD_T7","ID_MODEL_ENC":"PSSD\\x20T7\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20","ID_MODEL_ID":"4001","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"2048","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"1953520065","ID_PART_ENTRY_TYPE":"0x7","ID_PART_ENTRY_UUID":"badacb7f-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"badacb7f","ID_PATH":"platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-fd500000_pcie-pci-0000_01_00_0-usb-0_1_1_0-scsi-0_0_0_0","ID_REVISION":"0","ID_SERIAL":"Samsung_PSSD_T7_S6U9NS0RC28546M-0:0","ID_SERIAL_SHORT":"S6U9NS0RC28546M","ID_TYPE":"disk","ID_USB_DRIVER":"uas","ID_USB_INTERFACES":":080650:080662:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"Samsung","ID_VENDOR_ENC":"Samsung\\x20","ID_VENDOR_ID":"04e8","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"2572","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"4129762950"} May 16 16:59:15 volumio volumio[989]: umount: /media/T7: target is busy. May 16 16:59:15 volumio volumio[989]: error: Failed to umount T7: Error: Command failed: /usr/bin/sudo /bin/umount -f "/dev/sda1" May 16 16:59:15 volumio volumio[989]: umount: /media/T7: target is busy. May 16 16:59:14 volumio ntfs-3g[10968]: Reading $BITMAP failed: Input/output error May 16 16:59:14 volumio ntfs-3g[10968]: Failed to allocate clusters: Input/output error May 16 16:59:14 volumio ntfs-3g[10968]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 16 16:59:14 volumio ntfs-3g[10968]: Reading $BITMAP failed: Input/output error May 16 16:59:14 volumio ntfs-3g[10968]: Failed to allocate clusters: Input/output error May 16 16:59:14 volumio ntfs-3g[10968]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 16 16:59:14 volumio ntfs-3g[10968]: Reading $BITMAP failed: Input/output error May 16 16:59:14 volumio ntfs-3g[10968]: Failed to allocate clusters: Input/output error May 16 16:59:14 volumio ntfs-3g[10968]: Cluster allocation failed (1): Input/output error May 16 16:59:14 volumio ntfs-3g[10968]: Failed to resize just added $ATTRIBUTE_LIST: Input/output error May 16 16:59:14 volumio ntfs-3g[10968]: Failed to truncate INDEX_ROOT: Input/output error May 16 16:59:14 volumio ntfs-3g[10968]: Failed to add entry to the index: Input/output error May 16 16:59:14 volumio ntfs-3g[10968]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error May 16 16:59:14 volumio ntfs-3g[10968]: Reading $BITMAP failed: Input/output error May 16 16:59:14 volumio ntfs-3g[10968]: Failed to allocate clusters: Input/output error May 16 16:59:14 volumio ntfs-3g[10968]: Inode already has attribute list: File exists May 16 16:59:14 volumio ntfs-3g[10968]: Failed to truncate INDEX_ROOT: File exists May 16 16:59:14 volumio ntfs-3g[10968]: Failed to add entry to the index: File exists May 16 16:59:15 volumio sudo[12935]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda1 May 16 16:59:15 volumio sudo[12935]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 16:59:15 volumio ntfs-3g[10968]: Unmounting /dev/sda1 (T7) May 16 16:59:15 volumio ntfs-3g[10968]: Failed to sync device /dev/sda1: Input/output error May 16 16:59:15 volumio sudo[12935]: pam_unix(sudo:session): session closed for user root May 16 16:59:15 volumio ntfs-3g[10968]: Failed to fsync device /dev/sda1: No such device May 16 16:59:15 volumio ntfs-3g[10968]: Failed to close volume /dev/sda1: Device or resource busy May 16 16:59:15 volumio kernel: sr 0:0:0:0: Power-on or device reset occurred May 16 16:59:15 volumio kernel: usb usb2-port2: over-current change #1 May 16 16:59:15 volumio volumio[989]: info: CoreCommandRouter::volumioGetState May 16 16:59:15 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:15 volumio volumio[989]: info: CoreCommandRouter::volumioStop May 16 16:59:15 volumio volumio[989]: info: CoreStateMachine::stop May 16 16:59:15 volumio volumio[989]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 16:59:15 volumio volumio[989]: info: CoreStateMachine::updateTrackBlock May 16 16:59:15 volumio volumio[989]: info: CorePlayQueue::getTrackBlock May 16 16:59:15 volumio volumio[989]: info: CoreStateMachine::stPlaybackTimer May 16 16:59:15 volumio volumio[989]: info: CoreStateMachine::pushState May 16 16:59:15 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:15 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 16:59:15 volumio volumio[989]: info: CoreCommandRouter::volumioPushState May 16 16:59:15 volumio volumio[989]: info: MRS: Pushing multiroomSync output update for this device May 16 16:59:15 volumio volumio[989]: info: MRS: Pushing multiroomSync output May 16 16:59:15 volumio volumio[989]: info: CoreCommandRouter::volumioGetState May 16 16:59:15 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:16 volumio volumio[989]: info: CoreStateMachine::serviceStop May 16 16:59:16 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:16 volumio volumio[989]: info: CoreCommandRouter::serviceStop May 16 16:59:16 volumio volumio[989]: info: ControllerMpd::stop May 16 16:59:16 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand stop May 16 16:59:16 volumio volumio[989]: info: CoreCommandRouter::volumioResetState May 16 16:59:16 volumio volumio[989]: info: Clearing queue from CD entries May 16 16:59:16 volumio volumio[989]: info: CoreCommandRouter::volumioPushQueue May 16 16:59:16 volumio volumio[989]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesAudio CD May 16 16:59:16 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 16 16:59:16 volumio volumio[989]: Cannot find translation for source QOBUZ May 16 16:59:16 volumio volumio[989]: info: CoreStateMachine::resetVolumioState May 16 16:59:16 volumio volumio[989]: info: CoreStateMachine::getcurrentVolume May 16 16:59:16 volumio volumio[989]: info: CoreCommandRouter::volumioRetrievevolume May 16 16:59:16 volumio kernel: usb usb2-port3: over-current change #1 May 16 16:59:16 volumio volumio[989]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 May 16 16:59:16 volumio volumio[989]: info: May 16 16:59:16 volumio volumio[989]: ---------------------------- MPD announces state update: player May 16 16:59:16 volumio volumio[989]: info: sendMpdCommand stop took 143 milliseconds May 16 16:59:16 volumio volumio[989]: info: ControllerMpd::getState May 16 16:59:16 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand status May 16 16:59:16 volumio volumio[989]: info: sendMpdCommand status took 18 milliseconds May 16 16:59:16 volumio volumio[989]: verbose: ControllerMpd::parseState May 16 16:59:16 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 16:59:16 volumio volumio[989]: info: CoreCommandRouter::volumioGetState May 16 16:59:16 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:16 volumio volumio[989]: info: Clearing queue from CD entries May 16 16:59:16 volumio volumio[989]: info: CoreCommandRouter::volumioPushQueue May 16 16:59:16 volumio volumio[989]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesAudio CD May 16 16:59:16 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 16 16:59:16 volumio volumio[989]: Cannot find translation for source QOBUZ May 16 16:59:16 volumio volumio[989]: info: sendMpdCommand playlistinfo took 41 milliseconds May 16 16:59:16 volumio volumio[989]: verbose: ControllerMpd::parseTrackInfo May 16 16:59:16 volumio volumio[989]: info: ControllerMpd::pushState May 16 16:59:16 volumio volumio[989]: info: CoreCommandRouter::servicePushState May 16 16:59:16 volumio volumio[989]: info: CoreStateMachine::pushState May 16 16:59:16 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:16 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 16:59:16 volumio volumio[989]: info: CoreCommandRouter::volumioPushState May 16 16:59:16 volumio volumio[989]: info: MRS: Pushing multiroomSync output update for this device May 16 16:59:16 volumio volumio[989]: info: MRS: Pushing multiroomSync output May 16 16:59:16 volumio volumio[989]: info: CoreCommandRouter::volumioGetState May 16 16:59:16 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:16 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:16 volumio volumio[989]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1","artist":null,"album":null,"uri":"cdda:///1","trackType":"CD Audio"} May 16 16:59:16 volumio volumio[989]: verbose: CURRENT POSITION 0 May 16 16:59:16 volumio volumio[989]: info: CoreStateMachine::syncState stateService stop May 16 16:59:16 volumio volumio[989]: info: CoreStateMachine::syncState currentStatus stop May 16 16:59:16 volumio volumio[989]: info: CoreStateMachine::pushState May 16 16:59:16 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:16 volumio volumio[989]: info: CoreCommandRouter::volumioPushState May 16 16:59:16 volumio volumio[989]: info: MRS: Pushing multiroomSync output update for this device May 16 16:59:16 volumio volumio[989]: info: MRS: Pushing multiroomSync output May 16 16:59:16 volumio volumio[989]: info: CoreCommandRouter::volumioGetState May 16 16:59:16 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:16 volumio volumio[989]: info: No code May 16 16:59:16 volumio volumio[989]: info: CoreStateMachine::pushState May 16 16:59:16 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:16 volumio volumio[989]: info: CoreCommandRouter::volumioPushState May 16 16:59:16 volumio volumio[989]: info: MRS: Pushing multiroomSync output update for this device May 16 16:59:16 volumio volumio[989]: info: MRS: Pushing multiroomSync output May 16 16:59:16 volumio volumio[989]: info: CoreCommandRouter::volumioGetState May 16 16:59:16 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:16 volumio volumio[989]: info: ------------------------------ 144ms May 16 16:59:16 volumio kernel: usb usb2-port4: over-current change #1 May 16 16:59:16 volumio volumio[989]: info: CoreStateMachine::pushState May 16 16:59:16 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:16 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 16:59:16 volumio volumio[989]: info: CoreCommandRouter::volumioPushState May 16 16:59:16 volumio volumio[989]: info: MRS: Pushing multiroomSync output update for this device May 16 16:59:16 volumio volumio[989]: info: MRS: Pushing multiroomSync output May 16 16:59:16 volumio volumio[989]: info: CoreCommandRouter::volumioGetState May 16 16:59:16 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:16 volumio volumio[989]: info: CoreStateMachine::updateTrackBlock May 16 16:59:16 volumio volumio[989]: info: CorePlayQueue::getTrackBlock May 16 16:59:16 volumio volumio[989]: info: CoreCommandRouter::volumioRetrievevolume May 16 16:59:16 volumio volumio[989]: info: Executing endpoint cdPostRip May 16 16:59:16 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: cd_controller , notifyPostRip May 16 16:59:16 volumio volumio[989]: info: CD RIP Terminated May 16 16:59:16 volumio kernel: usb usb2-port1: over-current change #2 May 16 16:59:16 volumio kernel: sr 0:0:0:0: [sr0] CDROM not ready yet. May 16 16:59:17 volumio kernel: usb 2-1: new SuperSpeed USB device number 5 using xhci_hcd May 16 16:59:17 volumio kernel: usb 2-1: New USB device found, idVendor=04e8, idProduct=4001, bcdDevice= 1.00 May 16 16:59:17 volumio kernel: usb 2-1: New USB device strings: Mfr=2, Product=3, SerialNumber=1 May 16 16:59:17 volumio kernel: usb 2-1: Product: PSSD T7 May 16 16:59:17 volumio kernel: usb 2-1: Manufacturer: Samsung May 16 16:59:17 volumio kernel: usb 2-1: SerialNumber: S6U9NS0RC28546M May 16 16:59:17 volumio kernel: scsi host2: uas May 16 16:59:17 volumio kernel: scsi 2:0:0:0: Direct-Access Samsung PSSD T7 0 PQ: 0 ANSI: 6 May 16 16:59:17 volumio kernel: sd 2:0:0:0: Attached scsi generic sg1 type 0 May 16 16:59:17 volumio kernel: sd 2:0:0:0: [sdb] 1953525168 512-byte logical blocks: (1.00 TB/932 GiB) May 16 16:59:17 volumio kernel: sd 2:0:0:0: [sdb] Write Protect is off May 16 16:59:17 volumio kernel: sd 2:0:0:0: [sdb] Mode Sense: 43 00 00 00 May 16 16:59:17 volumio kernel: sd 2:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA May 16 16:59:17 volumio kernel: usb usb2-port1: over-current change #3 May 16 16:59:17 volumio kernel: usb 2-1: cmd cmplt err -71 May 16 16:59:18 volumio kernel: usb 1-1-port1: over-current change #2 May 16 16:59:18 volumio kernel: usb 2-1: USB disconnect, device number 5 May 16 16:59:18 volumio kernel: sd 2:0:0:0: [sdb] tag#24 uas_zap_pending 0 uas-tag 1 inflight: CMD May 16 16:59:18 volumio kernel: sd 2:0:0:0: [sdb] tag#24 CDB: opcode=0xa3, sa=0xc a3 0c 01 12 00 00 00 00 00 0a 00 00 May 16 16:59:18 volumio kernel: sd 2:0:0:0: [sdb] Preferred minimum I/O size 512 bytes May 16 16:59:18 volumio kernel: sd 2:0:0:0: [sdb] Optimal transfer size 33553920 bytes May 16 16:59:18 volumio kernel: sd 2:0:0:0: [sdb] Attached SCSI disk May 16 16:59:18 volumio kernel: usb 1-1-port2: over-current change #2 May 16 16:59:18 volumio kernel: sd 2:0:0:0: [sdb] Synchronizing SCSI cache May 16 16:59:18 volumio kernel: usb 1-1-port3: over-current change #2 May 16 16:59:18 volumio kernel: usb 1-1-port4: over-current change #2 May 16 16:59:18 volumio kernel: sd 2:0:0:0: [sdb] Synchronize Cache(10) failed: Result: hostbyte=0x07 driverbyte=DRIVER_OK May 16 16:59:18 volumio kernel: usb 1-1.2: USB disconnect, device number 4 May 16 16:59:18 volumio kernel: usb usb2-port2: over-current change #2 May 16 16:59:18 volumio volumio[989]: /usr/bin/eject: CD-ROM select speed command failed: Input/output error May 16 16:59:18 volumio volumio[989]: error: Cannot set CD Speed May 16 16:59:18 volumio volumio[989]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 May 16 16:59:18 volumio volumio[989]: SPOTIFY: SPOTIFY VOLUME 90 May 16 16:59:18 volumio volumio[989]: SPOTIFY: VOLUMIO VOLUME 90 May 16 16:59:18 volumio volumio[989]: SPOTIFY: DELTA VOLUME ENOUGH: false May 16 16:59:18 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand status May 16 16:59:18 volumio volumio[989]: info: May 16 16:59:18 volumio volumio[989]: ---------------------------- MPD announces state update: update May 16 16:59:18 volumio volumio[989]: info: ControllerMpd::getState May 16 16:59:18 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand status May 16 16:59:18 volumio volumio[989]: info: Updating MPD DB after CD rip May 16 16:59:18 volumio volumio[989]: info: Syncing changes to disk May 16 16:59:18 volumio volumio[989]: info: Cleaning abcde folders after CD rip May 16 16:59:18 volumio volumio[989]: error: Cannot Clean after rip: Error: Command failed: /bin/rm -rf /mnt/USB/T7/abcde.* May 16 16:59:18 volumio volumio[989]: /bin/rm: cannot remove '/mnt/USB/T7/abcde.*': Transport endpoint is not connected May 16 16:59:18 volumio volumio[989]: info: CoreStateMachine::pushState May 16 16:59:18 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:18 volumio volumio[989]: info: CoreCommandRouter::volumioPushState May 16 16:59:18 volumio volumio[989]: info: MRS: Pushing multiroomSync output update for this device May 16 16:59:18 volumio volumio[989]: info: MRS: Pushing multiroomSync output May 16 16:59:18 volumio volumio[989]: info: CoreCommandRouter::volumioGetState May 16 16:59:18 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:18 volumio volumio[989]: info: MPD Database updated - AlbumList cache refreshed May 16 16:59:18 volumio volumio[989]: info: May 16 16:59:18 volumio volumio[989]: ---------------------------- MPD announces state update: database May 16 16:59:18 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand status May 16 16:59:18 volumio volumio[989]: info: May 16 16:59:18 volumio volumio[989]: ---------------------------- MPD announces state update: update May 16 16:59:18 volumio volumio[989]: info: ControllerMpd::getState May 16 16:59:18 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand status May 16 16:59:18 volumio volumio[989]: info: ControllerMpd::getState May 16 16:59:18 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand status May 16 16:59:18 volumio volumio[989]: info: sendMpdCommand status took 50 milliseconds May 16 16:59:18 volumio volumio[989]: info: sendMpdCommand status took 49 milliseconds May 16 16:59:18 volumio volumio[989]: verbose: ControllerMpd::parseState May 16 16:59:18 volumio kernel: usb 1-1.2: new high-speed USB device number 5 using xhci_hcd May 16 16:59:18 volumio volumio[989]: info: Command Router : Notfying DB Updatefalse May 16 16:59:18 volumio volumio[989]: info: CoreCommandRouter::Close All Modals sent May 16 16:59:18 volumio volumio[989]: verbose: ControllerMpd::parseState May 16 16:59:18 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 16:59:18 volumio volumio[989]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 May 16 16:59:18 volumio volumio[989]: SPOTIFY: SPOTIFY VOLUME 90 May 16 16:59:18 volumio volumio[989]: SPOTIFY: VOLUMIO VOLUME 90 May 16 16:59:18 volumio volumio[989]: SPOTIFY: DELTA VOLUME ENOUGH: false May 16 16:59:18 volumio volumio[989]: info: sendMpdCommand status took 107 milliseconds May 16 16:59:18 volumio volumio[989]: info: sendMpdCommand status took 106 milliseconds May 16 16:59:18 volumio volumio[989]: info: sendMpdCommand status took 105 milliseconds May 16 16:59:18 volumio volumio[989]: info: sendMpdCommand playlistinfo took 38 milliseconds May 16 16:59:18 volumio volumio[989]: verbose: ControllerMpd::parseState May 16 16:59:18 volumio kernel: usb usb2-port3: over-current change #2 May 16 16:59:18 volumio volumio[989]: info: Command Router : Notfying DB Updatefalse May 16 16:59:18 volumio volumio[989]: info: CoreCommandRouter::Close All Modals sent May 16 16:59:18 volumio kernel: usb 1-1.2: New USB device found, idVendor=0e8d, idProduct=1887, bcdDevice= 0.00 May 16 16:59:18 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 May 16 16:59:18 volumio kernel: usb 1-1.2: Product: Portable Super Multi Drive May 16 16:59:18 volumio kernel: usb 1-1.2: Manufacturer: Hitachi-LG Data Storage Inc May 16 16:59:18 volumio kernel: usb 1-1.2: SerialNumber: K0IO8TD1509 May 16 16:59:18 volumio kernel: usb-storage 1-1.2:1.0: USB Mass Storage device detected May 16 16:59:18 volumio kernel: scsi host0: usb-storage 1-1.2:1.0 May 16 16:59:18 volumio volumio[989]: verbose: ControllerMpd::parseState May 16 16:59:18 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 16:59:18 volumio volumio[989]: verbose: ControllerMpd::parseState May 16 16:59:18 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 16:59:18 volumio volumio[989]: verbose: ControllerMpd::parseTrackInfo May 16 16:59:18 volumio volumio[989]: info: ControllerMpd::pushState May 16 16:59:18 volumio volumio[989]: info: CoreCommandRouter::servicePushState May 16 16:59:18 volumio volumio[989]: info: CoreStateMachine::pushState May 16 16:59:18 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:18 volumio volumio[989]: info: CoreCommandRouter::volumioPushState May 16 16:59:18 volumio volumio[989]: info: MRS: Pushing multiroomSync output update for this device May 16 16:59:18 volumio volumio[989]: info: MRS: Pushing multiroomSync output May 16 16:59:18 volumio volumio[989]: info: CoreCommandRouter::volumioGetState May 16 16:59:18 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:18 volumio volumio[989]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1","artist":null,"album":null,"uri":"cdda:///1","trackType":"CD Audio"} May 16 16:59:18 volumio volumio[989]: verbose: CURRENT POSITION 0 May 16 16:59:18 volumio volumio[989]: info: CoreStateMachine::syncState stateService stop May 16 16:59:18 volumio volumio[989]: info: CoreStateMachine::syncState currentStatus stop May 16 16:59:18 volumio volumio[989]: info: CoreStateMachine::pushState May 16 16:59:18 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:18 volumio volumio[989]: info: CoreCommandRouter::volumioPushState May 16 16:59:18 volumio volumio[989]: info: MRS: Pushing multiroomSync output update for this device May 16 16:59:18 volumio volumio[989]: info: MRS: Pushing multiroomSync output May 16 16:59:18 volumio volumio[989]: info: CoreCommandRouter::volumioGetState May 16 16:59:18 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:18 volumio volumio[989]: info: No code May 16 16:59:18 volumio volumio[989]: info: CoreStateMachine::pushState May 16 16:59:18 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:18 volumio volumio[989]: info: CoreCommandRouter::volumioPushState May 16 16:59:18 volumio volumio[989]: info: MRS: Pushing multiroomSync output update for this device May 16 16:59:18 volumio volumio[989]: info: MRS: Pushing multiroomSync output May 16 16:59:18 volumio volumio[989]: info: CoreCommandRouter::volumioGetState May 16 16:59:18 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:18 volumio volumio[989]: info: ------------------------------ 285ms May 16 16:59:18 volumio volumio[989]: info: sendMpdCommand playlistinfo took 87 milliseconds May 16 16:59:18 volumio volumio[989]: info: sendMpdCommand playlistinfo took 86 milliseconds May 16 16:59:18 volumio volumio[989]: verbose: ControllerMpd::parseTrackInfo May 16 16:59:18 volumio volumio[989]: verbose: ControllerMpd::parseTrackInfo May 16 16:59:18 volumio volumio[989]: info: ControllerMpd::pushState May 16 16:59:18 volumio volumio[989]: info: CoreCommandRouter::servicePushState May 16 16:59:18 volumio volumio[989]: info: CoreStateMachine::pushState May 16 16:59:18 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:18 volumio volumio[989]: info: CoreCommandRouter::volumioPushState May 16 16:59:18 volumio volumio[989]: info: MRS: Pushing multiroomSync output update for this device May 16 16:59:18 volumio volumio[989]: info: MRS: Pushing multiroomSync output May 16 16:59:18 volumio volumio[989]: info: CoreCommandRouter::volumioGetState May 16 16:59:18 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:18 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:18 volumio volumio[989]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1","artist":null,"album":null,"uri":"cdda:///1","trackType":"CD Audio"} May 16 16:59:18 volumio volumio[989]: verbose: CURRENT POSITION 0 May 16 16:59:18 volumio volumio[989]: info: CoreStateMachine::syncState stateService stop May 16 16:59:18 volumio volumio[989]: info: CoreStateMachine::syncState currentStatus stop May 16 16:59:18 volumio volumio[989]: info: CoreStateMachine::pushState May 16 16:59:18 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:18 volumio volumio[989]: info: CoreCommandRouter::volumioPushState May 16 16:59:18 volumio volumio[989]: info: MRS: Pushing multiroomSync output update for this device May 16 16:59:18 volumio volumio[989]: info: MRS: Pushing multiroomSync output May 16 16:59:18 volumio volumio[989]: info: CoreCommandRouter::volumioGetState May 16 16:59:18 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:18 volumio volumio[989]: info: No code May 16 16:59:18 volumio volumio[989]: info: CoreStateMachine::pushState May 16 16:59:18 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:18 volumio volumio[989]: info: CoreCommandRouter::volumioPushState May 16 16:59:18 volumio volumio[989]: info: MRS: Pushing multiroomSync output update for this device May 16 16:59:18 volumio volumio[989]: info: MRS: Pushing multiroomSync output May 16 16:59:18 volumio volumio[989]: info: CoreCommandRouter::volumioGetState May 16 16:59:18 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:18 volumio volumio[989]: info: ControllerMpd::pushState May 16 16:59:18 volumio volumio[989]: info: CoreCommandRouter::servicePushState May 16 16:59:18 volumio volumio[989]: info: CoreStateMachine::pushState May 16 16:59:18 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:18 volumio volumio[989]: info: CoreCommandRouter::volumioPushState May 16 16:59:18 volumio volumio[989]: info: MRS: Pushing multiroomSync output update for this device May 16 16:59:18 volumio volumio[989]: info: MRS: Pushing multiroomSync output May 16 16:59:18 volumio volumio[989]: info: CoreCommandRouter::volumioGetState May 16 16:59:18 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:19 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:19 volumio volumio[989]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1","artist":null,"album":null,"uri":"cdda:///1","trackType":"CD Audio"} May 16 16:59:19 volumio volumio[989]: verbose: CURRENT POSITION 0 May 16 16:59:19 volumio volumio[989]: info: CoreStateMachine::syncState stateService stop May 16 16:59:19 volumio volumio[989]: info: CoreStateMachine::syncState currentStatus stop May 16 16:59:19 volumio volumio[989]: info: CoreStateMachine::pushState May 16 16:59:19 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:19 volumio volumio[989]: info: CoreCommandRouter::volumioPushState May 16 16:59:19 volumio volumio[989]: info: MRS: Pushing multiroomSync output update for this device May 16 16:59:19 volumio volumio[989]: info: MRS: Pushing multiroomSync output May 16 16:59:19 volumio volumio[989]: info: CoreCommandRouter::volumioGetState May 16 16:59:19 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:19 volumio volumio[989]: info: No code May 16 16:59:19 volumio volumio[989]: info: CoreStateMachine::pushState May 16 16:59:19 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:19 volumio volumio[989]: info: CoreCommandRouter::volumioPushState May 16 16:59:19 volumio volumio[989]: info: MRS: Pushing multiroomSync output update for this device May 16 16:59:19 volumio volumio[989]: info: MRS: Pushing multiroomSync output May 16 16:59:19 volumio volumio[989]: info: CoreCommandRouter::volumioGetState May 16 16:59:19 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:19 volumio volumio[989]: info: ------------------------------ 365ms May 16 16:59:19 volumio volumio[989]: info: ------------------------------ 366ms May 16 16:59:19 volumio volumio[989]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 May 16 16:59:19 volumio kernel: usb usb2-port4: over-current change #2 May 16 16:59:19 volumio volumio[989]: SPOTIFY: SPOTIFY VOLUME 90 May 16 16:59:19 volumio volumio[989]: SPOTIFY: VOLUMIO VOLUME 90 May 16 16:59:19 volumio volumio[989]: SPOTIFY: DELTA VOLUME ENOUGH: false May 16 16:59:19 volumio volumio[989]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 May 16 16:59:19 volumio volumio[989]: SPOTIFY: SPOTIFY VOLUME 90 May 16 16:59:19 volumio volumio[989]: SPOTIFY: VOLUMIO VOLUME 90 May 16 16:59:19 volumio volumio[989]: SPOTIFY: DELTA VOLUME ENOUGH: false May 16 16:59:19 volumio volumio[989]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 May 16 16:59:19 volumio volumio[989]: SPOTIFY: SPOTIFY VOLUME 90 May 16 16:59:19 volumio volumio[989]: SPOTIFY: VOLUMIO VOLUME 90 May 16 16:59:19 volumio volumio[989]: SPOTIFY: DELTA VOLUME ENOUGH: false May 16 16:59:19 volumio volumio[989]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 May 16 16:59:19 volumio volumio[989]: SPOTIFY: SPOTIFY VOLUME 90 May 16 16:59:19 volumio volumio[989]: SPOTIFY: VOLUMIO VOLUME 90 May 16 16:59:19 volumio volumio[989]: SPOTIFY: DELTA VOLUME ENOUGH: false May 16 16:59:19 volumio volumio[989]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 May 16 16:59:19 volumio volumio[989]: SPOTIFY: SPOTIFY VOLUME 90 May 16 16:59:19 volumio volumio[989]: SPOTIFY: VOLUMIO VOLUME 90 May 16 16:59:19 volumio volumio[989]: SPOTIFY: DELTA VOLUME ENOUGH: false May 16 16:59:19 volumio volumio[989]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 May 16 16:59:19 volumio volumio[989]: SPOTIFY: SPOTIFY VOLUME 90 May 16 16:59:19 volumio volumio[989]: SPOTIFY: VOLUMIO VOLUME 90 May 16 16:59:19 volumio volumio[989]: SPOTIFY: DELTA VOLUME ENOUGH: false May 16 16:59:19 volumio volumio[989]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 May 16 16:59:19 volumio volumio[989]: SPOTIFY: SPOTIFY VOLUME 90 May 16 16:59:19 volumio volumio[989]: SPOTIFY: VOLUMIO VOLUME 90 May 16 16:59:19 volumio volumio[989]: SPOTIFY: DELTA VOLUME ENOUGH: false May 16 16:59:19 volumio volumio[989]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 May 16 16:59:19 volumio volumio[989]: SPOTIFY: SPOTIFY VOLUME 90 May 16 16:59:19 volumio volumio[989]: SPOTIFY: VOLUMIO VOLUME 90 May 16 16:59:19 volumio volumio[989]: SPOTIFY: DELTA VOLUME ENOUGH: false May 16 16:59:19 volumio volumio[989]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 May 16 16:59:19 volumio volumio[989]: SPOTIFY: SPOTIFY VOLUME 90 May 16 16:59:19 volumio volumio[989]: SPOTIFY: VOLUMIO VOLUME 90 May 16 16:59:19 volumio volumio[989]: SPOTIFY: DELTA VOLUME ENOUGH: false May 16 16:59:19 volumio kernel: usb usb2-port1: over-current change #4 May 16 16:59:19 volumio kernel: usb 2-1: new SuperSpeed USB device number 6 using xhci_hcd May 16 16:59:19 volumio kernel: scsi 0:0:0:0: CD-ROM HL-DT-ST DVDRAM GP60NW60 RF02 PQ: 0 ANSI: 0 May 16 16:59:19 volumio kernel: usb 2-1: New USB device found, idVendor=04e8, idProduct=4001, bcdDevice= 1.00 May 16 16:59:19 volumio kernel: usb 2-1: New USB device strings: Mfr=2, Product=3, SerialNumber=1 May 16 16:59:19 volumio kernel: usb 2-1: Product: PSSD T7 May 16 16:59:19 volumio kernel: usb 2-1: Manufacturer: Samsung May 16 16:59:19 volumio kernel: usb 2-1: SerialNumber: S6U9NS0RC28546M May 16 16:59:19 volumio kernel: sr 0:0:0:0: Power-on or device reset occurred May 16 16:59:19 volumio kernel: scsi host2: uas May 16 16:59:19 volumio kernel: scsi 2:0:0:0: Direct-Access Samsung PSSD T7 0 PQ: 0 ANSI: 6 May 16 16:59:20 volumio kernel: sd 2:0:0:0: Attached scsi generic sg0 type 0 May 16 16:59:20 volumio kernel: sr 0:0:0:0: [sr0] scsi3-mmc drive: 24x/24x writer dvd-ram cd/rw xa/form2 cdda tray May 16 16:59:20 volumio kernel: sr 0:0:0:0: Attached scsi CD-ROM sr0 May 16 16:59:20 volumio kernel: sr 0:0:0:0: Attached scsi generic sg1 type 5 May 16 16:59:20 volumio kernel: usb usb2-port1: over-current change #5 May 16 16:59:20 volumio kernel: usb 1-1-port1: over-current change #3 May 16 16:59:20 volumio kernel: usb 2-1: USB disconnect, device number 6 May 16 16:59:21 volumio kernel: sd 2:0:0:0: [sdb] tag#11 uas_zap_pending 0 uas-tag 1 inflight: CMD May 16 16:59:21 volumio kernel: sd 2:0:0:0: [sdb] tag#11 CDB: opcode=0x28 28 00 00 00 00 00 00 00 01 00 May 16 16:59:21 volumio kernel: sd 2:0:0:0: [sdb] tag#8 uas_zap_pending 0 uas-tag 2 inflight: CMD May 16 16:59:21 volumio kernel: sd 2:0:0:0: [sdb] tag#8 CDB: opcode=0xa0 a0 00 00 00 00 00 00 00 10 00 00 00 May 16 16:59:21 volumio kernel: sd 2:0:0:0: [sdb] 1953525168 512-byte logical blocks: (1.00 TB/932 GiB) May 16 16:59:21 volumio kernel: sd 2:0:0:0: [sdb] Write Protect is off May 16 16:59:21 volumio kernel: sd 2:0:0:0: [sdb] Mode Sense: 00 00 00 00 May 16 16:59:21 volumio kernel: sd 2:0:0:0: [sdb] Asking for cache data failed May 16 16:59:21 volumio kernel: sd 2:0:0:0: [sdb] Assuming drive cache: write through May 16 16:59:21 volumio kernel: sd 2:0:0:0: [sdb] Preferred minimum I/O size 512 bytes May 16 16:59:21 volumio kernel: sd 2:0:0:0: [sdb] Optimal transfer size 33553920 bytes May 16 16:59:21 volumio kernel: usb 1-1.2: reset high-speed USB device number 5 using xhci_hcd May 16 16:59:21 volumio kernel: sd 2:0:0:0: [sdb] Attached SCSI disk May 16 16:59:21 volumio kernel: usb 1-1-port2: over-current change #3 May 16 16:59:21 volumio volumio[989]: info: Scanning removed location : "USB/T7" May 16 16:59:21 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand status May 16 16:59:21 volumio volumio[989]: info: May 16 16:59:21 volumio volumio[989]: ---------------------------- MPD announces state update: update May 16 16:59:21 volumio volumio[989]: info: ControllerMpd::getState May 16 16:59:21 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand status May 16 16:59:21 volumio volumio[989]: info: May 16 16:59:21 volumio volumio[989]: ---------------------------- MPD announces state update: update May 16 16:59:21 volumio volumio[989]: info: ControllerMpd::getState May 16 16:59:21 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand status May 16 16:59:21 volumio volumio[989]: info: sendMpdCommand status took 4 milliseconds May 16 16:59:21 volumio volumio[989]: info: sendMpdCommand status took 3 milliseconds May 16 16:59:21 volumio volumio[989]: verbose: ControllerMpd::parseState May 16 16:59:21 volumio volumio[989]: info: Command Router : Notfying DB Updatefalse May 16 16:59:21 volumio volumio[989]: info: CoreCommandRouter::Close All Modals sent May 16 16:59:21 volumio volumio[989]: verbose: ControllerMpd::parseState May 16 16:59:21 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 16:59:21 volumio volumio[989]: info: sendMpdCommand status took 73 milliseconds May 16 16:59:21 volumio volumio[989]: info: sendMpdCommand status took 72 milliseconds May 16 16:59:21 volumio volumio[989]: info: sendMpdCommand playlistinfo took 9 milliseconds May 16 16:59:21 volumio volumio[989]: verbose: ControllerMpd::parseState May 16 16:59:21 volumio volumio[989]: info: Command Router : Notfying DB Updatefalse May 16 16:59:21 volumio volumio[989]: info: CoreCommandRouter::Close All Modals sent May 16 16:59:21 volumio volumio[989]: verbose: ControllerMpd::parseState May 16 16:59:21 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 16:59:21 volumio volumio[989]: verbose: ControllerMpd::parseTrackInfo May 16 16:59:21 volumio volumio[989]: info: ControllerMpd::pushState May 16 16:59:21 volumio volumio[989]: info: CoreCommandRouter::servicePushState May 16 16:59:21 volumio volumio[989]: info: CoreStateMachine::pushState May 16 16:59:21 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:21 volumio volumio[989]: info: CoreCommandRouter::volumioPushState May 16 16:59:21 volumio volumio[989]: info: MRS: Pushing multiroomSync output update for this device May 16 16:59:21 volumio volumio[989]: info: MRS: Pushing multiroomSync output May 16 16:59:21 volumio volumio[989]: info: CoreCommandRouter::volumioGetState May 16 16:59:21 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:21 volumio volumio[989]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1","artist":null,"album":null,"uri":"cdda:///1","trackType":"CD Audio"} May 16 16:59:21 volumio volumio[989]: verbose: CURRENT POSITION 0 May 16 16:59:21 volumio volumio[989]: info: CoreStateMachine::syncState stateService stop May 16 16:59:21 volumio volumio[989]: info: CoreStateMachine::syncState currentStatus stop May 16 16:59:21 volumio volumio[989]: info: CoreStateMachine::pushState May 16 16:59:21 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:21 volumio volumio[989]: info: CoreCommandRouter::volumioPushState May 16 16:59:21 volumio volumio[989]: info: MRS: Pushing multiroomSync output update for this device May 16 16:59:21 volumio volumio[989]: info: MRS: Pushing multiroomSync output May 16 16:59:21 volumio volumio[989]: info: CoreCommandRouter::volumioGetState May 16 16:59:21 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:21 volumio volumio[989]: info: No code May 16 16:59:21 volumio volumio[989]: info: CoreStateMachine::pushState May 16 16:59:21 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:21 volumio volumio[989]: info: CoreCommandRouter::volumioPushState May 16 16:59:21 volumio volumio[989]: info: MRS: Pushing multiroomSync output update for this device May 16 16:59:21 volumio volumio[989]: info: MRS: Pushing multiroomSync output May 16 16:59:21 volumio volumio[989]: info: CoreCommandRouter::volumioGetState May 16 16:59:21 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:21 volumio volumio[989]: info: ------------------------------ 177ms May 16 16:59:21 volumio volumio[989]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 May 16 16:59:21 volumio volumio[989]: SPOTIFY: SPOTIFY VOLUME 90 May 16 16:59:21 volumio volumio[989]: SPOTIFY: VOLUMIO VOLUME 90 May 16 16:59:21 volumio volumio[989]: SPOTIFY: DELTA VOLUME ENOUGH: false May 16 16:59:21 volumio volumio[989]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 May 16 16:59:21 volumio volumio[989]: SPOTIFY: SPOTIFY VOLUME 90 May 16 16:59:21 volumio volumio[989]: SPOTIFY: VOLUMIO VOLUME 90 May 16 16:59:21 volumio volumio[989]: SPOTIFY: DELTA VOLUME ENOUGH: false May 16 16:59:21 volumio volumio[989]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 May 16 16:59:21 volumio volumio[989]: SPOTIFY: SPOTIFY VOLUME 90 May 16 16:59:21 volumio volumio[989]: SPOTIFY: VOLUMIO VOLUME 90 May 16 16:59:21 volumio volumio[989]: SPOTIFY: DELTA VOLUME ENOUGH: false May 16 16:59:21 volumio volumio[989]: info: sendMpdCommand playlistinfo took 91 milliseconds May 16 16:59:21 volumio volumio[989]: verbose: ControllerMpd::parseTrackInfo May 16 16:59:21 volumio volumio[989]: info: ControllerMpd::pushState May 16 16:59:21 volumio volumio[989]: info: CoreCommandRouter::servicePushState May 16 16:59:21 volumio volumio[989]: info: CoreStateMachine::pushState May 16 16:59:21 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:21 volumio volumio[989]: info: CoreCommandRouter::volumioPushState May 16 16:59:21 volumio volumio[989]: info: MRS: Pushing multiroomSync output update for this device May 16 16:59:21 volumio volumio[989]: info: MRS: Pushing multiroomSync output May 16 16:59:21 volumio volumio[989]: info: CoreCommandRouter::volumioGetState May 16 16:59:21 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:21 volumio volumio[989]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1","artist":null,"album":null,"uri":"cdda:///1","trackType":"CD Audio"} May 16 16:59:21 volumio volumio[989]: verbose: CURRENT POSITION 0 May 16 16:59:21 volumio volumio[989]: info: CoreStateMachine::syncState stateService stop May 16 16:59:21 volumio volumio[989]: info: CoreStateMachine::syncState currentStatus stop May 16 16:59:21 volumio volumio[989]: info: CoreStateMachine::pushState May 16 16:59:21 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:21 volumio volumio[989]: info: CoreCommandRouter::volumioPushState May 16 16:59:21 volumio volumio[989]: info: MRS: Pushing multiroomSync output update for this device May 16 16:59:21 volumio volumio[989]: info: MRS: Pushing multiroomSync output May 16 16:59:21 volumio volumio[989]: info: CoreCommandRouter::volumioGetState May 16 16:59:21 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:21 volumio volumio[989]: info: No code May 16 16:59:21 volumio volumio[989]: info: CoreStateMachine::pushState May 16 16:59:21 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:21 volumio volumio[989]: info: CoreCommandRouter::volumioPushState May 16 16:59:21 volumio volumio[989]: info: MRS: Pushing multiroomSync output update for this device May 16 16:59:21 volumio volumio[989]: info: MRS: Pushing multiroomSync output May 16 16:59:21 volumio volumio[989]: info: CoreCommandRouter::volumioGetState May 16 16:59:21 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:21 volumio volumio[989]: info: ------------------------------ 261ms May 16 16:59:21 volumio volumio[989]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 May 16 16:59:21 volumio volumio[989]: SPOTIFY: SPOTIFY VOLUME 90 May 16 16:59:21 volumio volumio[989]: SPOTIFY: VOLUMIO VOLUME 90 May 16 16:59:21 volumio volumio[989]: SPOTIFY: DELTA VOLUME ENOUGH: false May 16 16:59:21 volumio volumio[989]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 May 16 16:59:21 volumio volumio[989]: SPOTIFY: SPOTIFY VOLUME 90 May 16 16:59:21 volumio volumio[989]: SPOTIFY: VOLUMIO VOLUME 90 May 16 16:59:21 volumio volumio[989]: SPOTIFY: DELTA VOLUME ENOUGH: false May 16 16:59:21 volumio volumio[989]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 May 16 16:59:21 volumio volumio[989]: SPOTIFY: SPOTIFY VOLUME 90 May 16 16:59:21 volumio volumio[989]: SPOTIFY: VOLUMIO VOLUME 90 May 16 16:59:21 volumio volumio[989]: SPOTIFY: DELTA VOLUME ENOUGH: false May 16 16:59:21 volumio kernel: sr 0:0:0:0: Power-on or device reset occurred May 16 16:59:21 volumio kernel: usb 1-1-port3: over-current change #3 May 16 16:59:21 volumio volumio[989]: info: CoreCommandRouter::volumioGetState May 16 16:59:21 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:21 volumio volumio[989]: info: Clearing queue from CD entries May 16 16:59:21 volumio volumio[989]: info: CoreCommandRouter::volumioPushQueue May 16 16:59:21 volumio volumio[989]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesAudio CD May 16 16:59:21 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 16 16:59:21 volumio volumio[989]: Cannot find translation for source QOBUZ May 16 16:59:21 volumio kernel: usb 1-1-port4: over-current change #3 May 16 16:59:21 volumio volumio[989]: info: CoreCommandRouter::volumioGetState May 16 16:59:21 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:21 volumio volumio[989]: info: Clearing queue from CD entries May 16 16:59:21 volumio volumio[989]: info: CoreCommandRouter::volumioPushQueue May 16 16:59:21 volumio volumio[989]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesAudio CD May 16 16:59:21 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 16 16:59:21 volumio volumio[989]: Cannot find translation for source QOBUZ May 16 16:59:21 volumio kernel: usb 2-1: new SuperSpeed USB device number 7 using xhci_hcd May 16 16:59:21 volumio kernel: usb 2-1: New USB device found, idVendor=04e8, idProduct=4001, bcdDevice= 1.00 May 16 16:59:21 volumio kernel: usb 2-1: New USB device strings: Mfr=2, Product=3, SerialNumber=1 May 16 16:59:21 volumio kernel: usb 2-1: Product: PSSD T7 May 16 16:59:21 volumio kernel: usb 2-1: Manufacturer: Samsung May 16 16:59:21 volumio kernel: usb 2-1: SerialNumber: S6U9NS0RC28546M May 16 16:59:21 volumio kernel: scsi host2: uas May 16 16:59:21 volumio kernel: usb usb2-port2: over-current change #3 May 16 16:59:21 volumio kernel: scsi 2:0:0:0: Direct-Access Samsung PSSD T7 0 PQ: 0 ANSI: 6 May 16 16:59:21 volumio kernel: sd 2:0:0:0: Attached scsi generic sg0 type 0 May 16 16:59:21 volumio kernel: sd 2:0:0:0: [sdb] 1953525168 512-byte logical blocks: (1.00 TB/932 GiB) May 16 16:59:21 volumio kernel: sd 2:0:0:0: [sdb] Write Protect is off May 16 16:59:21 volumio kernel: sd 2:0:0:0: [sdb] Mode Sense: 43 00 00 00 May 16 16:59:21 volumio kernel: sd 2:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA May 16 16:59:21 volumio kernel: sd 2:0:0:0: [sdb] Preferred minimum I/O size 512 bytes May 16 16:59:21 volumio kernel: sd 2:0:0:0: [sdb] Optimal transfer size 33553920 bytes May 16 16:59:21 volumio kernel: sdb: sdb1 May 16 16:59:21 volumio kernel: sd 2:0:0:0: [sdb] Attached SCSI disk May 16 16:59:21 volumio volumio[989]: info: CoreCommandRouter::volumioGetState May 16 16:59:21 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:21 volumio volumio[989]: info: Clearing queue from CD entries May 16 16:59:21 volumio volumio[989]: info: CoreCommandRouter::volumioPushQueue May 16 16:59:21 volumio volumio[989]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesAudio CD May 16 16:59:21 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 16 16:59:21 volumio volumio[989]: Cannot find translation for source QOBUZ May 16 16:59:21 volumio kernel: usb usb2-port3: over-current change #3 May 16 16:59:22 volumio kernel: usb usb2-port4: over-current change #3 May 16 16:59:22 volumio kernel: usb usb2-port1: over-current change #6 May 16 16:59:22 volumio kernel: usb usb2-port1: over-current change #7 May 16 16:59:22 volumio kernel: usb 2-1: USB disconnect, device number 7 May 16 16:59:23 volumio kernel: sd 2:0:0:0: [sdb] tag#9 uas_zap_pending 0 uas-tag 1 inflight: CMD May 16 16:59:23 volumio kernel: sd 2:0:0:0: [sdb] tag#9 CDB: opcode=0x28 28 00 00 00 00 10 00 00 08 00 May 16 16:59:23 volumio kernel: sd 2:0:0:0: [sdb] tag#10 uas_zap_pending 0 uas-tag 2 inflight: CMD May 16 16:59:23 volumio kernel: sd 2:0:0:0: [sdb] tag#10 CDB: opcode=0x28 28 00 00 00 00 28 00 00 10 00 May 16 16:59:23 volumio kernel: sd 2:0:0:0: [sdb] tag#9 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=DRIVER_OK cmd_age=0s May 16 16:59:23 volumio kernel: sd 2:0:0:0: [sdb] tag#11 uas_zap_pending 0 uas-tag 3 inflight: CMD May 16 16:59:23 volumio kernel: sd 2:0:0:0: [sdb] tag#11 CDB: opcode=0x28 28 00 00 00 00 48 00 00 30 00 May 16 16:59:23 volumio kernel: sd 2:0:0:0: [sdb] tag#9 CDB: opcode=0x28 28 00 00 00 00 10 00 00 08 00 May 16 16:59:23 volumio kernel: sd 2:0:0:0: [sdb] tag#8 uas_zap_pending 0 uas-tag 4 inflight: CMD May 16 16:59:23 volumio kernel: blk_print_req_error: 4 callbacks suppressed May 16 16:59:23 volumio kernel: sd 2:0:0:0: [sdb] tag#8 CDB: opcode=0x28 28 00 00 00 00 88 00 00 78 00 May 16 16:59:23 volumio kernel: sd 2:0:0:0: [sdb] tag#12 uas_zap_pending 0 uas-tag 5 inflight: CMD May 16 16:59:23 volumio kernel: I/O error, dev sdb, sector 16 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 May 16 16:59:23 volumio kernel: sd 2:0:0:0: [sdb] tag#12 CDB: opcode=0x28 28 00 00 00 01 08 00 00 f8 00 May 16 16:59:23 volumio kernel: sd 2:0:0:0: [sdb] tag#10 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=DRIVER_OK cmd_age=0s May 16 16:59:23 volumio kernel: sd 2:0:0:0: [sdb] tag#10 CDB: opcode=0x28 28 00 00 00 00 28 00 00 10 00 May 16 16:59:23 volumio kernel: I/O error, dev sdb, sector 40 op 0x0:(READ) flags 0x80700 phys_seg 2 prio class 2 May 16 16:59:23 volumio kernel: sd 2:0:0:0: [sdb] tag#11 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=DRIVER_OK cmd_age=0s May 16 16:59:23 volumio kernel: sd 2:0:0:0: [sdb] tag#11 CDB: opcode=0x28 28 00 00 00 00 48 00 00 30 00 May 16 16:59:23 volumio kernel: I/O error, dev sdb, sector 72 op 0x0:(READ) flags 0x80700 phys_seg 6 prio class 2 May 16 16:59:23 volumio kernel: sd 2:0:0:0: [sdb] tag#8 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=DRIVER_OK cmd_age=0s May 16 16:59:23 volumio kernel: sd 2:0:0:0: [sdb] tag#8 CDB: opcode=0x28 28 00 00 00 00 88 00 00 78 00 May 16 16:59:23 volumio kernel: I/O error, dev sdb, sector 136 op 0x0:(READ) flags 0x80700 phys_seg 15 prio class 2 May 16 16:59:23 volumio kernel: sd 2:0:0:0: [sdb] tag#12 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=DRIVER_OK cmd_age=0s May 16 16:59:23 volumio kernel: sd 2:0:0:0: [sdb] tag#12 CDB: opcode=0x28 28 00 00 00 01 08 00 00 f8 00 May 16 16:59:23 volumio kernel: I/O error, dev sdb, sector 264 op 0x0:(READ) flags 0x80700 phys_seg 31 prio class 2 May 16 16:59:23 volumio kernel: device offline error, dev sdb, sector 16 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 16 16:59:23 volumio kernel: buffer_io_error: 9 callbacks suppressed May 16 16:59:23 volumio kernel: Buffer I/O error on dev sdb, logical block 2, async page read May 16 16:59:23 volumio kernel: device offline error, dev sdb, sector 16 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 May 16 16:59:23 volumio kernel: Buffer I/O error on dev sdb, logical block 2, async page read May 16 16:59:23 volumio kernel: sd 2:0:0:0: [sdb] Synchronizing SCSI cache May 16 16:59:23 volumio kernel: usb 1-1-port1: over-current change #4 May 16 16:59:23 volumio kernel: sd 2:0:0:0: [sdb] Synchronize Cache(10) failed: Result: hostbyte=0x07 driverbyte=DRIVER_OK May 16 16:59:23 volumio kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event TRB DMA ptr not part of current TD ep_index 3 comp_code 4 May 16 16:59:23 volumio kernel: xhci_hcd 0000:01:00.0: Looking for event-dma 0000000429718000 trb-start 00000004297184d0 trb-end 00000004297184d0 seg-start 0000000429718000 seg-end 0000000429718ff0 May 16 16:59:23 volumio volumio[989]: info: CD Rip Successfully started May 16 16:59:23 volumio kernel: usb usb2-port2: over-current change #4 May 16 16:59:24 volumio kernel: usb usb2-port3: over-current change #4 May 16 16:59:24 volumio kernel: usb usb2-port4: over-current change #4 May 16 16:59:24 volumio kernel: usb usb2-port1: over-current change #8 May 16 16:59:25 volumio kernel: usb 2-1: new SuperSpeed USB device number 8 using xhci_hcd May 16 16:59:25 volumio kernel: usb 2-1: New USB device found, idVendor=04e8, idProduct=4001, bcdDevice= 1.00 May 16 16:59:25 volumio kernel: usb 2-1: New USB device strings: Mfr=2, Product=3, SerialNumber=1 May 16 16:59:25 volumio kernel: usb 2-1: Product: PSSD T7 May 16 16:59:25 volumio kernel: usb 2-1: Manufacturer: Samsung May 16 16:59:25 volumio kernel: usb 2-1: SerialNumber: S6U9NS0RC28546M May 16 16:59:25 volumio kernel: scsi host2: uas May 16 16:59:25 volumio kernel: scsi 2:0:0:0: Direct-Access Samsung PSSD T7 0 PQ: 0 ANSI: 6 May 16 16:59:25 volumio volumio[989]: error: Cannot associate FS Label, not mounting May 16 16:59:25 volumio kernel: sd 2:0:0:0: Attached scsi generic sg0 type 0 May 16 16:59:25 volumio kernel: sd 2:0:0:0: [sdb] 1953525168 512-byte logical blocks: (1.00 TB/932 GiB) May 16 16:59:25 volumio kernel: sd 2:0:0:0: [sdb] Write Protect is off May 16 16:59:25 volumio kernel: sd 2:0:0:0: [sdb] Mode Sense: 43 00 00 00 May 16 16:59:25 volumio kernel: sd 2:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA May 16 16:59:25 volumio kernel: sd 2:0:0:0: [sdb] Preferred minimum I/O size 512 bytes May 16 16:59:25 volumio kernel: sd 2:0:0:0: [sdb] Optimal transfer size 33553920 bytes May 16 16:59:25 volumio kernel: sdb: sdb1 May 16 16:59:25 volumio kernel: sd 2:0:0:0: [sdb] Attached SCSI disk May 16 16:59:25 volumio volumio[989]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host2/target2:0:0/2:0:0:0/block/sdb/sdb1","ACTION":"remove","DEVLINKS":"/dev/disk/by-id/usb-Linux_6.6.62-v7l+_xhci-hcd_xHCI_Host_Controller_0000:01:00.0-part1 /dev/disk/by-path/platform-fd500000.pcie-pci-0000:01:00.0-part1","DEVNAME":"/dev/sdb1","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host2/target2:0:0/2:0:0:0/block/sdb/sdb1","DEVTYPE":"partition","DISKSEQ":"40","ID_BUS":"usb","ID_FOR_SEAT":"usb-platform-fd500000_pcie-pci-0000_01_00_0","ID_MODEL":"xHCI_Host_Controller","ID_MODEL_ENC":"xHCI\\x20Host\\x20Controller","ID_MODEL_FROM_DATABASE":"3.0 root hub","ID_MODEL_ID":"0003","ID_PATH":"platform-fd500000.pcie-pci-0000:01:00.0","ID_PATH_TAG":"platform-fd500000_pcie-pci-0000_01_00_0","ID_REVISION":"0606","ID_SERIAL":"Linux_6.6.62-v7l+_xhci-hcd_xHCI_Host_Controller_0000:01:00.0","ID_SERIAL_SHORT":"0000:01:00.0","ID_USB_INTERFACES":":090000:","ID_VENDOR":"Linux_6.6.62-v7l+_xhci-hcd","ID_VENDOR_ENC":"Linux\\x206.6.62-v7l+\\x20xhci-hcd","ID_VENDOR_FROM_DATABASE":"Linux Foundation","ID_VENDOR_ID":"1d6b","MAJOR":"8","MINOR":"17","PARTN":"1","SEQNUM":"2734","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"4460213672"} May 16 16:59:25 volumio volumio[989]: error: Cannot associate FS Label, not mounting May 16 16:59:25 volumio volumio[989]: info: Mounting Device T7 May 16 16:59:25 volumio volumio[989]: /bin/mkdir: cannot create directory ‘/mnt/USB/T7’: File exists May 16 16:59:25 volumio volumio[989]: error: Failed to create folder Error: Command failed: /bin/mkdir -m 777 "/mnt/USB/T7" May 16 16:59:25 volumio volumio[989]: /bin/mkdir: cannot create directory ‘/mnt/USB/T7’: File exists May 16 16:59:25 volumio sudo[13152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/T7 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 May 16 16:59:25 volumio sudo[13152]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 16:59:25 volumio kernel: usb 1-1-port2: over-current change #4 May 16 16:59:25 volumio kernel: usb 1-1.2: USB disconnect, device number 5 May 16 16:59:26 volumio volumio[989]: ntfs-3g-mount: failed to access mountpoint /media/T7: Transport endpoint is not connected May 16 16:59:26 volumio volumio[989]: error: Failed to mount T7: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sdb1" "/mnt/USB/T7" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 May 16 16:59:26 volumio volumio[989]: ntfs-3g-mount: failed to access mountpoint /media/T7: Transport endpoint is not connected May 16 16:59:25 volumio sudo[13152]: pam_unix(sudo:session): session closed for user root May 16 16:59:26 volumio kernel: usb 1-1.2: new high-speed USB device number 6 using xhci_hcd May 16 16:59:26 volumio kernel: usb 1-1.2: New USB device found, idVendor=0e8d, idProduct=1887, bcdDevice= 0.00 May 16 16:59:26 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 May 16 16:59:26 volumio kernel: usb 1-1.2: Product: Portable Super Multi Drive May 16 16:59:26 volumio kernel: usb 1-1.2: Manufacturer: Hitachi-LG Data Storage Inc May 16 16:59:26 volumio kernel: usb 1-1.2: SerialNumber: K0IO8TD1509 May 16 16:59:26 volumio kernel: usb-storage 1-1.2:1.0: USB Mass Storage device detected May 16 16:59:26 volumio kernel: scsi host0: usb-storage 1-1.2:1.0 May 16 16:59:26 volumio kernel: usb 1-1-port3: over-current change #4 May 16 16:59:26 volumio kernel: usb 1-1-port4: over-current change #4 May 16 16:59:26 volumio kernel: usb 1-1-port1: over-current change #5 May 16 16:59:27 volumio kernel: scsi 0:0:0:0: CD-ROM HL-DT-ST DVDRAM GP60NW60 RF02 PQ: 0 ANSI: 0 May 16 16:59:27 volumio kernel: sr 0:0:0:0: Power-on or device reset occurred May 16 16:59:27 volumio kernel: sr 0:0:0:0: [sr0] scsi3-mmc drive: 24x/24x writer dvd-ram cd/rw xa/form2 cdda tray May 16 16:59:27 volumio kernel: sr 0:0:0:0: Attached scsi CD-ROM sr0 May 16 16:59:27 volumio kernel: sr 0:0:0:0: Attached scsi generic sg1 type 5 May 16 16:59:27 volumio volumio[989]: info: CoreCommandRouter::volumioGetState May 16 16:59:27 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:27 volumio volumio[989]: info: Clearing queue from CD entries May 16 16:59:27 volumio volumio[989]: info: CoreCommandRouter::volumioPushQueue May 16 16:59:27 volumio volumio[989]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesAudio CD May 16 16:59:27 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 16 16:59:27 volumio volumio[989]: Cannot find translation for source QOBUZ May 16 16:59:28 volumio volumio[989]: info: CoreCommandRouter::volumioGetState May 16 16:59:28 volumio volumio[989]: info: CorePlayQueue::getTrack 0 May 16 16:59:28 volumio volumio[989]: info: Clearing queue from CD entries May 16 16:59:28 volumio volumio[989]: info: CoreCommandRouter::volumioPushQueue May 16 16:59:28 volumio volumio[989]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesAudio CD May 16 16:59:28 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 16 16:59:28 volumio volumio[989]: Cannot find translation for source QOBUZ May 16 16:59:29 volumio kernel: hwmon hwmon1: Voltage normalised May 16 16:59:36 volumio sudo[13256]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /dev/sr0 May 16 16:59:36 volumio sudo[13256]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 16:59:36 volumio sudo[13256]: pam_unix(sudo:session): session closed for user root May 16 16:59:36 volumio sudo[13259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /dev/sr1 May 16 16:59:36 volumio sudo[13259]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 16:59:36 volumio sudo[13259]: pam_unix(sudo:session): session closed for user root May 16 16:59:36 volumio volumio[989]: /bin/chmod: cannot access '/dev/sr1': No such file or directory May 16 16:59:36 volumio volumio[989]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 16 16:59:36 volumio volumio[989]: info: [1747407576479] CoreMusicLibrary::Adding element Audio CD May 16 16:59:36 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 16 16:59:36 volumio volumio[989]: Cannot find translation for source QOBUZ May 16 16:59:36 volumio volumio[989]: Cannot find translation for source Audio CD May 16 16:59:38 volumio volumio[989]: info: CDDB Entry Already exists May 16 16:59:38 volumio volumio[989]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 16 16:59:38 volumio volumio[989]: info: [1747407578268] CoreMusicLibrary::Adding element Audio CD May 16 16:59:38 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 16 16:59:38 volumio volumio[989]: Cannot find translation for source QOBUZ May 16 16:59:38 volumio volumio[989]: Cannot find translation for source Audio CD May 16 16:59:41 volumio volumio[989]: info: Set CD Speed to 4X May 16 16:59:44 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: cd_controller , handleBrowseUri May 16 16:59:44 volumio volumio[989]: info: Preload queue cleared May 16 16:59:44 volumio volumio[989]: info: Executing endpoint metavolumio May 16 16:59:44 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 16 16:59:44 volumio volumio[989]: info: Executing endpoint metavolumio May 16 16:59:44 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 16 17:00:48 volumio volumio[989]: info: CALLMETHOD: music_service cd_controller getRipInfo May 16 17:00:48 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: cd_controller , getRipInfo May 16 17:00:48 volumio volumio[989]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 16 17:00:48 volumio volumio[989]: Error: ENOTCONN: socket is not connected, lstat '/mnt/USB/T7' May 16 17:00:48 volumio volumio[989]: at Object.lstatSync (fs.js:1077:3) May 16 17:00:48 volumio volumio[989]: at Object.lstatSync (/volumio/node_modules/graceful-fs/polyfills.js:308:16) May 16 17:00:48 volumio volumio[989]: at /myvolumio/plugins/music_service/cd_controller/cd_controller_real:1:14222 May 16 17:00:48 volumio volumio[989]: at go$readdir$cb (/volumio/node_modules/graceful-fs/graceful-fs.js:195:14) May 16 17:00:48 volumio volumio[989]: at FSReqCallback.oncomplete (fs.js:171:23) { May 16 17:00:48 volumio volumio[989]: errno: -107, May 16 17:00:48 volumio volumio[989]: syscall: 'lstat', May 16 17:00:48 volumio volumio[989]: code: 'ENOTCONN', May 16 17:00:48 volumio volumio[989]: path: '/mnt/USB/T7' May 16 17:00:48 volumio volumio[989]: } May 16 17:00:48 volumio volumio[989]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 16 17:00:48 volumio sudo[13817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-16 16:59 May 16 17:00:48 volumio sudo[13817]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"