I’ve setup a NAS running on a RPI5 and bought the USB3 powered hub from RPI to connect and power a Crucial BX500 SSD. However I keep getting IO errors with the SSD, regardless of whether I use the UAS or USB-STORAGE driver. The hub is powered using a RPI 5V3A power supply, so that should be sufficient. I’m also using a SATA-USB adapter that I purchased from you. Are there any known issues?
At the moment, I’ve worked around the problem by using a mains powered SATA docking bay with no issues.
Might be worth trying adding the following to /boot/firmware/config.txt to make sure the USB ports are working at full power?
usb_max_current_enable=1
Tried that already. No joy.
Is the HUB plugged into one of the Pi 5’s Blue USB Jacks?
Can you post the relevant log entries? Not only the error messages, but also the messages during boot/plugin when the system detects the device. Maybe this could tell us more about the cause.
The device is rated at 5V/1.7A, so this would be way above USB3-specs which allows only 0.9A (regardless of having a powered hub or not). But what is printed on the device is usually not the real power draw. I have read about max 2.4W, so this would be even ok with USB2-power.
Thank you for helping with this issue. I can send you the logs exported from journalctl, but the important messages are summarised below.
I have two SSDs mounted on the system, one for the OS directly connected to a USB3 port on the Pi (/dev/sda1) and another for the media (/dev/sdb1) connected to the USB3 Hub.
My son reported issues accessing our media drive (/dev/sdb1) on 11 Aug in the evening, so I spent some time investigating the issue.
I rebooted the system, and lines 382-425 show the USB3 Hub and SATA devices being detected and mounted using uas:
Aug 11 21:13:30 homebridge kernel: usb 1-1: New USB device found, idVendor=2e8a, idProduct=000d, bcdDevice=50.00
Aug 11 21:13:30 homebridge kernel: usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Aug 11 21:13:30 homebridge kernel: usb 1-1: Product: USB3 HUB
Aug 11 21:13:30 homebridge kernel: usb 1-1: Manufacturer: Raspberry Pi
Aug 11 21:13:30 homebridge kernel: usb 1-1: SerialNumber: 000TESTING
Aug 11 21:13:30 homebridge kernel: hub 1-1:1.0: USB hub found
Aug 11 21:13:30 homebridge kernel: hub 1-1:1.0: 4 ports detected
Aug 11 21:13:30 homebridge kernel: usb 4-1: new SuperSpeed USB device number 2 using xhci-hcd
Aug 11 21:13:30 homebridge kernel: usb 4-1: New USB device found, idVendor=174c, idProduct=55aa, bcdDevice= 1.00
Aug 11 21:13:30 homebridge kernel: usb 4-1: New USB device strings: Mfr=2, Product=3, SerialNumber=1
Aug 11 21:13:30 homebridge kernel: usb 2-1: new SuperSpeed USB device number 2 using xhci-hcd
Aug 11 21:13:30 homebridge kernel: usb 4-1: Product: ASM1153USB3.0TOSATA
Aug 11 21:13:30 homebridge kernel: usb 4-1: Manufacturer: ASM1153USB3.0TOSATA
Aug 11 21:13:30 homebridge kernel: usb 4-1: SerialNumber: 123456789019
Aug 11 21:13:30 homebridge kernel: usb 2-1: New USB device found, idVendor=2e8a, idProduct=000e, bcdDevice=50.00
Aug 11 21:13:30 homebridge kernel: usb 2-1:New USB device strings: Mfr=1, Product=2, SerialNumber=3
Aug 11 21:13:30 homebridge kernel: scsi host0: uas
Aug 11 21:13:30 homebridge kernel: usb 2-1: Product: USB3 HUB
Aug 11 21:13:30 homebridge kernel: scsi 0:0:0:0: Direct-Access ASM1153U ASM1153USB3.0TOS 0 PQ: 0 ANSI: 6
Aug 11 21:13:30 homebridge kernel: usb 2-1: Manufacturer: Raspberry Pi
Aug 11 21:13:30 homebridge kernel: sd 0:0:0:0: [sda] 976773168 512-byte logical blocks: (500 GB/466 GiB)
Aug 11 21:13:30 homebridge kernel: usb 2-1: SerialNumber: 000TESTING
Aug 11 21:13:30 homebridge kernel: sd 0:0:0:0: [sda] Write Protect is off
Aug 11 21:13:30 homebridge kernel: sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00
Aug 11 21:13:30 homebridge kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Aug 11 21:13:30 homebridge kernel: hub 2-1:1.0: USB hub found
Aug 11 21:13:30 homebridge kernel: hub 2-1:1.0: 4 ports detected
Aug 11 21:13:30 homebridge kernel: sd 0:0:0:0: [sda] Preferred minimum I/O size 512 bytes
Aug 11 21:13:30 homebridge kernel: sd 0:0:0:0: [sda] Optimal transfer size 33553920 bytes
Aug 11 21:13:30 homebridge kernel: sda: sda1 sda2
Aug 11 21:13:30 homebridge kernel: sd 0:0:0:0: [sda] Attached SCSI disk
Aug 11 21:13:30 homebridge kernel: EXT4-fs (sda2): mounted filesystem d4cc7d63-da78-48ad-9bdd-64ffbba449a8 ro with ordered data mode. Quota mode: none.
Aug 11 21:13:30 homebridge kernel: usb 2-1.1: new SuperSpeed USB device number 3 using xhci-hcd
Aug 11 21:13:30 homebridge kernel: usb 2-1.1: New USB device found, idVendor=174c, idProduct=55aa, bcdDevice= 1.00
Aug 11 21:13:30 homebridge kernel: usb 2-1.1: New USB device strings: Mfr=2, Product=3, SerialNumber=1
Aug 11 21:13:30 homebridge kernel: usb 2-1.1: Product: ASM1153USB3.0TOSATA
Aug 11 21:13:30 homebridge kernel: usb 2-1.1: Manufacturer: ASM1153USB3.0TOSATA
Aug 11 21:13:30 homebridge kernel: usb 2-1.1: SerialNumber: 123456789019
Aug 11 21:13:30 homebridge kernel: NET: Registered PF_INET6 protocol family
Aug 11 21:13:30 homebridge kernel: Segment Routing with IPv6
Aug 11 21:13:30 homebridge kernel: In-situ OAM (IOAM) with IPv6
Aug 11 21:13:30 homebridge kernel: scsi host1: uas
Aug 11 21:13:30 homebridge kernel: scsi 1:0:0:0: Direct-Access ASM1153U ASM1153USB3.0TOS 0 PQ: 0 ANSI: 6
Aug 11 21:13:30 homebridge systemd[1]: systemd 252.38-1~deb12u1 running in system mode (+PAM +AUDIT +SELINUX +APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified)
Following reboot, I observed disk issues from line 1258:
Aug 11 21:56:29 homebridge kernel: EXT4-fs error (device sdb1): ext4_validate_inode_bitmap:104: comm hdhomerun_recor: Corrupt inode bitmap - block_group = 17568, inode_bitmap = 575668240
Aug 11 21:56:29 homebridge kernel: EXT4-fs error (device sdb1): ext4_lookup:1815: inode #143917057: comm hdhomerun_recor: deleted inode referenced: 143917064
Aug 11 21:56:29 homebridge kernel: EXT4-fs error (device sdb1): ext4_lookup:1813: inode #143917069: comm hdhomerun_recor: iget: checksum invalid
...
Aug 11 21:56:43 homebridge kernel: EXT4-fs error (device sdb1): ext4_lookup:1815: inode #143917057: comm smbd: deleted inode referenced: 143917064
Aug 11 21:56:43 homebridge kernel: EXT4-fs error (device sdb1): ext4_lookup:1815: inode #143917057: comm smbd: deleted inode referenced: 143917064
And also `Buffer I/O error’s from line 1519:
Aug 11 23:24:11 homebridge kernel: Buffer I/O error on dev sdb1, logical block 488144896, lost sync page write
Aug 11 23:24:11 homebridge kernel: JBD2: I/O error when updating journal superblock for sdb1-8.
Aug 11 23:24:11 homebridge kernel: EXT4-fs warning (device sdb1): ext4_end_bio:353: I/O error 16 writing to inode 143925250 starting block 99395328)
Aug 11 23:24:11 homebridge kernel: EXT4-fs warning (device sdb1): ext4_end_bio:353: I/O error 16 writing to inode 143925250 starting block 99390878)
...
Aug 11 23:24:11 homebridge kernel: EXT4-fs (sdb1): failed to convert unwritten extents to written extents -- potential data loss! (inode 143925250, error -5)
Aug 11 23:24:11 homebridge kernel: Buffer I/O error on device sdb1, logical block 99390622
...
Aug 11 23:24:11 homebridge kernel: Buffer I/O error on device sdb1, logical block 99392512
...
Aug 11 23:24:11 homebridge kernel: Buffer I/O error on device sdb1, logical block 99392519
Aug 11 23:24:11 homebridge kernel: EXT4-fs (sdb1): failed to convert unwritten extents to written extents -- potential data loss! (inode 143925250, error -5)
Aug 11 23:24:11 homebridge kernel: EXT4-fs error (device sdb1) in ext4_do_writepages:2743: Journal has aborted
Aug 11 23:24:11 homebridge kernel: Buffer I/O error on dev sdb1, logical block 0, lost sync page write
Aug 11 23:24:11 homebridge kernel: EXT4-fs (sdb1): I/O error while writing superblock
Aug 11 23:24:11 homebridge kernel: EXT4-fs error (device sdb1): ext4_journal_check_start:84: comm kworker/u17:2: Detected aborted journal
Aug 11 23:24:11 homebridge kernel: Buffer I/O error on dev sdb1, logical block 0, lost sync page write
Aug 11 23:24:11 homebridge kernel: EXT4-fs (sdb1): I/O error while writing superblock
Aug 11 23:24:11 homebridge kernel: EXT4-fs (sdb1): Remounting filesystem read-only
Aug 11 23:24:11 homebridge kernel: EXT4-fs (sdb1): ext4_do_writepages: jbd2_start: 9223372036854775807 pages, ino 143925250; err -30
Aug 11 23:24:11 homebridge kernel: sd 1:0:0:0: [sdb] Synchronizing SCSI cache
Aug 11 23:24:11 homebridge kernel: sd 1:0:0:0: [sdb] Synchronize Cache(10) failed: Result: hostbyte=0x07 driverbyte=DRIVER_OK
Subsequent, I disabled the services (smbd and hdhomerun) that use the media device (/dev/sdb1) and rebooted the system.
Once the system restarted, I unmounted the media device (/dev/sdb1) and then ran fsck on the device.
Lines 382-433 again show the two SSDs being mounted using UAS:
Aug 11 23:45:04 homebridge kernel: usb 1-1: New USB device found, idVendor=2e8a, idProduct=000d, bcdDevice=50.00
Aug 11 23:45:04 homebridge kernel: usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Aug 11 23:45:04 homebridge kernel: usb 1-1: Product: USB3 HUB
Aug 11 23:45:04 homebridge kernel: usb 1-1: Manufacturer: Raspberry Pi
Aug 11 23:45:04 homebridge kernel: usb 1-1: SerialNumber: 000TESTING
Aug 11 23:45:04 homebridge kernel: hub 1-1:1.0: USB hub found
Aug 11 23:45:04 homebridge kernel: hub 1-1:1.0: 4 ports detected
Aug 11 23:45:04 homebridge kernel: usb 4-1: new SuperSpeed USB device number 2 using xhci-hcd
Aug 11 23:45:04 homebridge kernel: usb 4-1: New USB device found, idVendor=174c, idProduct=55aa, bcdDevice= 1.00
Aug 11 23:45:04 homebridge kernel: usb 4-1: New USB device strings: Mfr=2, Product=3, SerialNumber=1
Aug 11 23:45:04 homebridge kernel: usb 2-1: new SuperSpeed USB device number 2 using xhci-hcd
Aug 11 23:45:04 homebridge kernel: usb 4-1: Product: ASM1153USB3.0TOSATA
Aug 11 23:45:04 homebridge kernel: usb 4-1: Manufacturer: ASM1153USB3.0TOSATA
Aug 11 23:45:04 homebridge kernel: usb 4-1: SerialNumber: 123456789019
Aug 11 23:45:04 homebridge kernel: scsi host0: uas
Aug 11 23:45:04 homebridge kernel: usb 2-1: New USB device found, idVendor=2e8a, idProduct=000e, bcdDevice=50.00
Aug 11 23:45:04 homebridge kernel: usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Aug 11 23:45:04 homebridge kernel: usb 2-1: Product: USB3 HUB
Aug 11 23:45:04 homebridge kernel: scsi 0:0:0:0: Direct-Access ASM1153U ASM1153USB3.0TOS 0 PQ: 0 ANSI: 6
Aug 11 23:45:04 homebridge kernel: usb 2-1: Manufacturer: Raspberry Pi
Aug 11 23:45:04 homebridge kernel: usb 2-1: SerialNumber: 000TESTING
Aug 11 23:45:04 homebridge kernel: sd 0:0:0:0: [sda] 976773168 512-byte logical blocks: (500 GB/466 GiB)
Aug 11 23:45:04 homebridge kernel: sd 0:0:0:0: [sda] Write Protect is off
Aug 11 23:45:04 homebridge kernel: sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00
Aug 11 23:45:04 homebridge kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Aug 11 23:45:04 homebridge kernel: hub 2-1:1.0: USB hub found
Aug 11 23:45:04 homebridge kernel: hub 2-1:1.0: 4 ports detected
Aug 11 23:45:04 homebridge kernel: sd 0:0:0:0: [sda] Preferred minimum I/O size 512 bytes
Aug 11 23:45:04 homebridge kernel: sd 0:0:0:0: [sda] Optimal transfer size 33553920 bytes
Aug 11 23:45:04 homebridge kernel: sda: sda1 sda2
Aug 11 23:45:04 homebridge kernel: sd 0:0:0:0: [sda] Attached SCSI disk
Aug 11 23:45:04 homebridge kernel: EXT4-fs (sda2): mounted filesystem d4cc7d63-da78-48ad-9bdd-64ffbba449a8 ro with ordered data mode. Quota mode: none.
Aug 11 23:45:04 homebridge kernel: usb 2-1.1: new SuperSpeed USB device number 3 using xhci-hcd
Aug 11 23:45:04 homebridge kernel: usb 2-1.1: New USB device found, idVendor=174c, idProduct=55aa, bcdDevice= 1.00
Aug 11 23:45:04 homebridge kernel: usb 2-1.1: New USB device strings: Mfr=2, Product=3, SerialNumber=1
Aug 11 23:45:04 homebridge kernel: usb 2-1.1: Product: ASM1153USB3.0TOSATA
Aug 11 23:45:04 homebridge kernel: usb 2-1.1: Manufacturer: ASM1153USB3.0TOSATA
Aug 11 23:45:04 homebridge kernel: NET: Registered PF_INET6 protocol family
Aug 11 23:45:04 homebridge kernel: usb 2-1.1: SerialNumber: 123456789019
Aug 11 23:45:04 homebridge kernel: scsi host1: uas
fsck reported clean, so I rebooted the system.
Once the system rebooted, lines 382-432 show the SSDs again connecting using UAS:
Aug 11 23:48:07 homebridge kernel: usb 1-1: New USB device found, idVendor=2e8a, idProduct=000d, bcdDevice=50.00
Aug 11 23:48:07 homebridge kernel: usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Aug 11 23:48:07 homebridge kernel: usb 1-1: Product: USB3 HUB
...
Aug 11 23:48:07 homebridge kernel: scsi host0: uas
Aug 11 23:48:07 homebridge kernel: scsi 0:0:0:0: Direct-Access ASM1153U ASM1153USB3.0TOS 0 PQ: 0 ANSI: 6
Aug 11 23:48:07 homebridge kernel: sd 0:0:0:0: [sda] 976773168 512-byte logical blocks: (500 GB/466 GiB)
Aug 11 23:48:07 homebridge kernel: usb 2-1: New USB device found, idVendor=2e8a, idProduct=000e, bcdDevice=50.00
Aug 11 23:48:07 homebridge kernel: usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Aug 11 23:48:07 homebridge kernel: sd 0:0:0:0: [sda] Write Protect is off
Aug 11 23:48:07 homebridge kernel: usb 2-1: Product: USB3 HUB
Aug 11 23:48:07 homebridge kernel: sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00
Aug 11 23:48:07 homebridge kernel: usb 2-1: Manufacturer: Raspberry Pi
Aug 11 23:48:07 homebridge kernel: usb 2-1: SerialNumber: 000TESTING
Aug 11 23:48:07 homebridge kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Aug 11 23:48:07 homebridge kernel: hub 2-1:1.0: USB hub found
Aug 11 23:48:07 homebridge kernel: sd 0:0:0:0: [sda] Preferred minimum I/O size 512 bytes
Aug 11 23:48:07 homebridge kernel: hub 2-1:1.0: 4 ports detected
Aug 11 23:48:07 homebridge kernel: sd 0:0:0:0: [sda] Optimal transfer size 33553920 bytes
Aug 11 23:48:07 homebridge kernel: sda: sda1 sda2
Aug 11 23:48:07 homebridge kernel: sd 0:0:0:0: [sda] Attached SCSI disk
Aug 11 23:48:07 homebridge kernel: EXT4-fs (sda2): mounted filesystem d4cc7d63-da78-48ad-9bdd-64ffbba449a8 ro with ordered data mode. Quota mode: none.
Aug 11 23:48:07 homebridge kernel: usb 2-1.1: new SuperSpeed USB device number 3 using xhci-hcd
Aug 11 23:48:07 homebridge kernel: usb 2-1.1: New USB device found, idVendor=174c, idProduct=55aa, bcdDevice= 1.00
Aug 11 23:48:07 homebridge kernel: usb 2-1.1: New USB device strings: Mfr=2, Product=3, SerialNumber=1
Aug 11 23:48:07 homebridge kernel: usb 2-1.1: Product: ASM1153USB3.0TOSATA
Aug 11 23:48:07 homebridge kernel: usb 2-1.1: Manufacturer: ASM1153USB3.0TOSATA
Aug 11 23:48:07 homebridge kernel: usb 2-1.1: SerialNumber: 123456789019
Aug 11 23:48:07 homebridge kernel: NET: Registered PF_INET6 protocol family
Aug 11 23:48:07 homebridge kernel: scsi host1: uas
Aug 11 23:48:07 homebridge kernel: Segment Routing with IPv6
Aug 11 23:48:07 homebridge kernel: scsi 1:0:0:0: Direct-Access ASM1153U ASM1153USB3.0TOS 0 PQ: 0 ANSI: 6
Aug 11 23:48:07 homebridge kernel: In-situ OAM (IOAM) with IPv6
Aug 11 23:48:07 homebridge kernel: sd 1:0:0:0: [sdb] 7814037168 512-byte logical blocks: (4.00 TB/3.64 TiB)
Aug 11 23:48:07 homebridge kernel: sd 1:0:0:0: [sdb] Write Protect is off
Aug 11 23:48:07 homebridge kernel: sd 1:0:0:0: [sdb] Mode Sense: 43 00 00 00
Aug 11 23:48:07 homebridge kernel: sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Aug 11 23:48:07 homebridge kernel: sd 1:0:0:0: [sdb] Preferred minimum I/O size 512 bytes
Aug 11 23:48:07 homebridge kernel: sd 1:0:0:0: [sdb] Optimal transfer size 33553920 bytes
Aug 11 23:48:07 homebridge kernel: sdb: sdb1
Aug 11 23:48:07 homebridge kernel: sd 1:0:0:0: [sdb] Attached SCSI disk
I then re-enabled and started the services that use the media drive, but from line 1096 I get uas_eh_abort_handler errors:
Aug 11 23:51:12 homebridge kernel: sd 1:0:0:0: [sdb] tag#29 uas_eh_abort_handler 0 uas-tag 2 inflight: CMD IN
Aug 11 23:51:12 homebridge kernel: sd 1:0:0:0: [sdb] tag#29 CDB: opcode=0x88 88 00 00 00 00 00 12 a7 e3 80 00 00 04 00 00 00
Aug 11 23:51:12 homebridge kernel: sd 1:0:0:0: [sdb] tag#28 uas_eh_abort_handler 0 uas-tag 1 inflight: CMD IN
Aug 11 23:51:12 homebridge kernel: sd 1:0:0:0: [sdb] tag#28 CDB: opcode=0x88 88 00 00 00 00 00 12 a8 57 80 00 00 04 00 00 00
Once more I disabled the services using the media drive, and this time added quirks to /boot/firmware/cmdline.txt to disable uas and rebooted the system.
Lines 382-426 show the SSDs connected using usb-storage instead of uas:
Aug 12 07:42:34 homebridge kernel: scsi host0: usb-storage 4-1:1.0
Aug 12 07:42:34 homebridge kernel: usb 2-1: Manufacturer: Raspberry Pi
Aug 12 07:42:34 homebridge kernel: usb 2-1: SerialNumber: 000TESTING
Aug 12 07:42:34 homebridge kernel: hub 2-1:1.0: USB hub found
Aug 12 07:42:34 homebridge kernel: hub 2-1:1.0: 4 ports detected
Aug 12 07:42:34 homebridge kernel: usb 2-1.1: new SuperSpeed USB device number 3 using xhci-hcd
Aug 12 07:42:34 homebridge kernel: usb 2-1.1: New USB device found, idVendor=174c, idProduct=55aa, bcdDevice= 1.00
Aug 12 07:42:34 homebridge kernel: usb 2-1.1: New USB device strings: Mfr=2, Product=3, SerialNumber=1
Aug 12 07:42:34 homebridge kernel: usb 2-1.1: Product: ASM1153USB3.0TOSATA
Aug 12 07:42:34 homebridge kernel: usb 2-1.1: Manufacturer: ASM1153USB3.0TOSATA
Aug 12 07:42:34 homebridge kernel: usb 2-1.1: SerialNumber: 123456789019
Aug 12 07:42:34 homebridge kernel: usb 2-1.1: UAS is ignored for this device, using usb-storage instead
Aug 12 07:42:34 homebridge kernel: usb 2-1.1: UAS is ignored for this device, using usb-storage instead
Aug 12 07:42:34 homebridge kernel: usb-storage 2-1.1:1.0: USB Mass Storage device detected
Aug 12 07:42:34 homebridge kernel: usb-storage 2-1.1:1.0: Quirks match for vid 174c pid 55aa: c00000
Aug 12 07:42:34 homebridge kernel: scsi host1: usb-storage 2-1.1:1.0
Aug 12 07:42:34 homebridge kernel: scsi 0:0:0:0: Direct-Access ASM1153U ASM1153USB3.0TOS 0 PQ: 0 ANSI: 6
Aug 12 07:42:34 homebridge kernel: sd 0:0:0:0: [sda] 976773168 512-byte logical blocks: (500 GB/466 GiB)
Aug 12 07:42:34 homebridge kernel: sd 0:0:0:0: [sda] Write Protect is off
Aug 12 07:42:34 homebridge kernel: sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00
Aug 12 07:42:34 homebridge kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Aug 12 07:42:34 homebridge kernel: sda: sda1 sda2
Aug 12 07:42:34 homebridge kernel: sd 0:0:0:0: [sda] Attached SCSI disk
Aug 12 07:42:34 homebridge kernel: EXT4-fs (sda2): mounted filesystem d4cc7d63-da78-48ad-9bdd-64ffbba449a8 ro with ordered data mode. Quota mode: none.
I then ran fsck again and rebooted after it reported clean.
Lines 382-426 again show the SSDs connecting using usb-storage:
Aug 12 07:50:25 homebridge kernel: scsi host0: usb-storage 4-1:1.0
Aug 12 07:50:25 homebridge kernel: usb 2-1: Manufacturer: Raspberry Pi
Aug 12 07:50:25 homebridge kernel: usb 2-1: SerialNumber: 000TESTING
Aug 12 07:50:25 homebridge kernel: hub 2-1:1.0: USB hub found
Aug 12 07:50:25 homebridge kernel: hub 2-1:1.0: 4 ports detected
Aug 12 07:50:25 homebridge kernel: usb 2-1.1: new SuperSpeed USB device number 3 using xhci-hcd
Aug 12 07:50:25 homebridge kernel: usb 2-1.1: New USB device found, idVendor=174c, idProduct=55aa, bcdDevice= 1.00
Aug 12 07:50:25 homebridge kernel: usb 2-1.1: New USB device strings: Mfr=2, Product=3, SerialNumber=1
Aug 12 07:50:25 homebridge kernel: usb 2-1.1: Product: ASM1153USB3.0TOSATA
Aug 12 07:50:25 homebridge kernel: usb 2-1.1: Manufacturer: ASM1153USB3.0TOSATA
Aug 12 07:50:25 homebridge kernel: usb 2-1.1: SerialNumber: 123456789019
Aug 12 07:50:25 homebridge kernel: usb 2-1.1: UAS is ignored for this device, using usb-storage instead
Aug 12 07:50:25 homebridge kernel: usb 2-1.1: UAS is ignored for this device, using usb-storage instead
Aug 12 07:50:25 homebridge kernel: usb-storage 2-1.1:1.0: USB Mass Storage device detected
Aug 12 07:50:25 homebridge kernel: usb-storage 2-1.1:1.0: Quirks match for vid 174c pid 55aa: c00000
Aug 12 07:50:25 homebridge kernel: scsi host1: usb-storage 2-1.1:1.0
Aug 12 07:50:25 homebridge kernel: scsi 0:0:0:0: Direct-Access ASM1153U ASM1153USB3.0TOS 0 PQ: 0 ANSI: 6
Aug 12 07:50:25 homebridge kernel: sd 0:0:0:0: [sda] 976773168 512-byte logical blocks: (500 GB/466 GiB)
Aug 12 07:50:25 homebridge kernel: sd 0:0:0:0: [sda] Write Protect is off
Aug 12 07:50:25 homebridge kernel: sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00
Aug 12 07:50:25 homebridge kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Aug 12 07:50:25 homebridge kernel: sda: sda1 sda2
Aug 12 07:50:25 homebridge kernel: sd 0:0:0:0: [sda] Attached SCSI disk
Aug 12 07:50:25 homebridge kernel: EXT4-fs (sda2): mounted filesystem d4cc7d63-da78-48ad-9bdd-64ffbba449a8 ro with ordered data mode. Quota mode: none.
I reenabled and started the disabled services once more, but I started seeing reset SuperSpeed USB device number 3 using xhci-hcd errors from line 1297:
Aug 12 10:40:02 homebridge kernel: usb 2-1.1: reset SuperSpeed USB device number 3 using xhci-hcd
...
Aug 12 10:58:19 homebridge kernel: usb 2-1.1: reset SuperSpeed USB device number 3 using xhci-hcd
Aug 12 10:58:24 homebridge kernel: hub 2-1:1.0: hub_ext_port_status failed (err = -110)
Aug 12 10:58:25 homebridge kernel: usb 2-1-port1: cannot reset (err = -110)
Aug 12 10:58:26 homebridge kernel: usb 2-1-port1: cannot reset (err = -110)
Aug 12 10:58:27 homebridge kernel: usb 2-1-port1: cannot reset (err = -110)
...
Aug 12 10:58:29 homebridge kernel: usb 2-1-port1: Cannot enable. Maybe the USB cable is bad?
Aug 12 10:58:30 homebridge kernel: usb 2-1-port1: cannot disable (err = -110)
Aug 12 10:58:35 homebridge kernel: hub 2-1:1.0: hub_ext_port_status failed (err = -110)
...
Aug 12 10:59:05 homebridge kernel: usb 2-1-port1: cannot disable (err = -110)
Aug 12 10:59:05 homebridge kernel: sd 1:0:0:0: [sdb] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=46s
Aug 12 10:59:05 homebridge kernel: sd 1:0:0:0: [sdb] tag#0 CDB: opcode=0x8a 8a 00 00 00 00 00 30 05 60 00 00 00 08 00 00 00
Aug 12 10:59:05 homebridge kernel: I/O error, dev sdb, sector 805658624 op 0x1:(WRITE) flags 0x0 phys_seg 64 prio class 0
Aug 12 10:59:05 homebridge kernel: EXT4-fs warning (device sdb1): ext4_end_bio:353: I/O error 10 writing to inode 143917061 starting block 100707328)
Aug 12 10:59:05 homebridge kernel: sd 1:0:0:0: [sdb] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=0s
...
Aug 12 10:59:05 homebridge kernel: I/O error, dev sdb, sector 3905171496 op 0x1:(WRITE) flags 0x9800 phys_seg 5 prio class 2
Aug 12 10:59:05 homebridge kernel: Aborting journal on device sdb1-8.
Aug 12 10:59:05 homebridge kernel: EXT4-fs error (device sdb1) in ext4_mb_clear_bb:6550: Journal has aborted
Aug 12 10:59:05 homebridge kernel: EXT4-fs (sdb1): Delayed block allocation failed for inode 143917061 at logical offset 181820 with max blocks 766 with error 30
Aug 12 10:59:05 homebridge kernel: EXT4-fs (sdb1): This should not happen!! Data will be lost
Aug 12 10:59:05 homebridge kernel: EXT4-fs error (device sdb1) in ext4_do_writepages:2743: Journal has aborted
Aug 12 10:59:05 homebridge kernel: sd 1:0:0:0: [sdb] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=0s
Aug 12 10:59:05 homebridge kernel: sd 1:0:0:0: [sdb] tag#0 CDB: opcode=0x8a 8a 00 00 00 00 00 30 05 78 00 00 00 01 e0 00 00
Aug 12 10:59:05 homebridge kernel: I/O error, dev sdb, sector 805664768 op 0x1:(WRITE) flags 0x0 phys_seg 15 prio class 0
Aug 12 10:59:05 homebridge kernel: EXT4-fs warning (device sdb1): ext4_end_bio:353: I/O error 10 writing to inode 143917061 starting block 100708096)
Aug 12 10:59:05 homebridge kernel: EXT4-fs (sdb1): failed to convert unwritten extents to written extents -- potential data loss! (inode 143917061, error -5)
Aug 12 10:59:05 homebridge kernel: Buffer I/O error on device sdb1, logical block 100707328
Aug 12 10:59:05 homebridge kernel: Buffer I/O error on device sdb1, logical block 100707329
Aug 12 10:59:05 homebridge kernel: Buffer I/O error on device sdb1, logical block 100707330
...
Aug 12 10:59:05 homebridge kernel: sd 1:0:0:0: [sdb] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=0s
Aug 12 10:59:05 homebridge kernel: sd 1:0:0:0: [sdb] tag#0 CDB: opcode=0x8a 8a 00 00 00 00 00 e8 c4 08 00 00 00 00 08 00 00
Aug 12 10:59:05 homebridge kernel: I/O error, dev sdb, sector 3905161216 op 0x1:(WRITE) flags 0x9800 phys_seg 1 prio class 2
Aug 12 10:59:05 homebridge kernel: I/O error, dev sdb, sector 3905161216 op 0x1:(WRITE) flags 0x9800 phys_seg 1 prio class 2
Aug 12 10:59:05 homebridge kernel: Buffer I/O error on dev sdb1, logical block 488144896, lost sync page write
Aug 12 10:59:05 homebridge kernel: JBD2: I/O error when updating journal superblock for sdb1-8.
Aug 12 10:59:05 homebridge kernel: sd 1:0:0:0: [sdb] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=0s
Aug 12 10:59:05 homebridge kernel: sd 1:0:0:0: [sdb] tag#0 CDB: opcode=0x8a 8a 00 00 00 00 00 00 00 08 00 00 00 00 08 00 00
Aug 12 10:59:05 homebridge kernel: I/O error, dev sdb, sector 2048 op 0x1:(WRITE) flags 0x3800 phys_seg 1 prio class 0
Aug 12 10:59:05 homebridge kernel: I/O error, dev sdb, sector 2048 op 0x1:(WRITE) flags 0x3800 phys_seg 1 prio class 0
Aug 12 10:59:05 homebridge kernel: Buffer I/O error on dev sdb1, logical block 0, lost sync page write
Aug 12 10:59:05 homebridge kernel: EXT4-fs (sdb1): I/O error while writing superblock
Aug 12 10:59:06 homebridge kernel: sd 1:0:0:0: [sdb] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=0s
Aug 12 10:59:06 homebridge kernel: sd 1:0:0:0: [sdb] tag#0 CDB: opcode=0x8a 8a 00 00 00 00 00 00 00 08 00 00 00 00 08 00 00
Aug 12 10:59:06 homebridge kernel: I/O error, dev sdb, sector 2048 op 0x1:(WRITE) flags 0x3800 phys_seg 1 prio class 0
Aug 12 10:59:06 homebridge kernel: Buffer I/O error on dev sdb1, logical block 0, lost sync page write
Aug 12 10:59:06 homebridge kernel: EXT4-fs (sdb1): I/O error while writing superblock
Aug 12 10:59:10 homebridge kernel: hub 2-1:1.0: hub_ext_port_status failed (err = -110)
Aug 12 10:59:21 homebridge kernel: EXT4-fs error (device sdb1): ext4_journal_check_start:84: comm kworker/u19:2: Detected aborted journal
Aug 12 10:59:21 homebridge kernel: sd 1:0:0:0: [sdb] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=0s
Aug 12 10:59:21 homebridge kernel: sd 1:0:0:0: [sdb] tag#0 CDB: opcode=0x8a 8a 00 00 00 00 00 00 00 08 00 00 00 00 08 00 00
Aug 12 10:59:21 homebridge kernel: blk_print_req_error: 1 callbacks suppressed
Aug 12 10:59:21 homebridge kernel: I/O error, dev sdb, sector 2048 op 0x1:(WRITE) flags 0x3800 phys_seg 1 prio class 0
Aug 12 10:59:21 homebridge kernel: I/O error, dev sdb, sector 2048 op 0x1:(WRITE) flags 0x3800 phys_seg 1 prio class 0
Aug 12 10:59:21 homebridge kernel: Buffer I/O error on dev sdb1, logical block 0, lost sync page write
Aug 12 10:59:21 homebridge kernel: EXT4-fs (sdb1): I/O error while writing superblock
Aug 12 10:59:21 homebridge kernel: EXT4-fs (sdb1): Remounting filesystem read-only
Aug 12 10:59:21 homebridge kernel: EXT4-fs (sdb1): ext4_do_writepages: jbd2_start: 256 pages, ino 143917061; err -30
At this point I stopped and disabled the services using the media drives and shutdown the system. I removed the RPI USB3 Hub and also the USB3-SATA adapter, and switched to a mains powered USB3 SATA docking station that I use for HDDs. I also adjusted the boot/firmware/cmdline.txt file to disable the quirks.
Lines 382-413 now show the device connecting the two SSDs using uas:
Aug 12 13:52:53 homebridge kernel: usb 2-1: New USB device found, idVendor=174c, idProduct=55aa, bcdDevice= 1.00
Aug 12 13:52:53 homebridge kernel: usb 2-1: New USB device strings: Mfr=2, Product=3, SerialNumber=1
Aug 12 13:52:53 homebridge kernel: usb 2-1: Product: ASMT105x
Aug 12 13:52:53 homebridge kernel: usb 2-1: Manufacturer: ASMT
Aug 12 13:52:53 homebridge kernel: usb 2-1: SerialNumber: 23456789014B
Aug 12 13:52:53 homebridge kernel: scsi host0: uas
Aug 12 13:52:53 homebridge kernel: scsi 0:0:0:0: Direct-Access ASMT ASMT105x 0 PQ: 0 ANSI: 6
Aug 12 13:52:53 homebridge kernel: sd 0:0:0:0: [sda] 7814037168 512-byte logical blocks: (4.00 TB/3.64 TiB)
Aug 12 13:52:53 homebridge kernel: sd 0:0:0:0: [sda] Write Protect is off
Aug 12 13:52:53 homebridge kernel: sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00
Aug 12 13:52:53 homebridge kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Aug 12 13:52:53 homebridge kernel: usb 4-1: new SuperSpeed USB device number 2 using xhci-hcd
Aug 12 13:52:53 homebridge kernel: sd 0:0:0:0: [sda] Preferred minimum I/O size 512 bytes
Aug 12 13:52:53 homebridge kernel: sd 0:0:0:0: [sda] Optimal transfer size 33553920 bytes
Aug 12 13:52:53 homebridge kernel: usb 4-1: New USB device found, idVendor=174c, idProduct=55aa, bcdDevice= 1.00
Aug 12 13:52:53 homebridge kernel: usb 4-1: New USB device strings: Mfr=2, Product=3, SerialNumber=1
Aug 12 13:52:53 homebridge kernel: sda: sda1
Aug 12 13:52:53 homebridge kernel: usb 4-1: Product: ASM1153USB3.0TOSATA
Aug 12 13:52:53 homebridge kernel: usb 4-1: Manufacturer: ASM1153USB3.0TOSATA
Aug 12 13:52:53 homebridge kernel: sd 0:0:0:0: [sda] Attached SCSI disk
Aug 12 13:52:53 homebridge kernel: usb 4-1: SerialNumber: 123456789019
Aug 12 13:52:53 homebridge kernel: scsi host1: uas
Aug 12 13:52:53 homebridge kernel: scsi 1:0:0:0: Direct-Access ASM1153U ASM1153USB3.0TOS 0 PQ: 0 ANSI: 6
Aug 12 13:52:53 homebridge kernel: sd 1:0:0:0: [sdb] 976773168 512-byte logical blocks: (500 GB/466 GiB)
Aug 12 13:52:53 homebridge kernel: sd 1:0:0:0: [sdb] Write Protect is off
Aug 12 13:52:53 homebridge kernel: sd 1:0:0:0: [sdb] Mode Sense: 43 00 00 00
Aug 12 13:52:53 homebridge kernel: sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Aug 12 13:52:53 homebridge kernel: sd 1:0:0:0: [sdb] Preferred minimum I/O size 512 bytes
Aug 12 13:52:53 homebridge kernel: sd 1:0:0:0: [sdb] Optimal transfer size 33553920 bytes
Aug 12 13:52:53 homebridge kernel: sdb: sdb1 sdb2
Aug 12 13:52:53 homebridge kernel: sd 1:0:0:0: [sdb] Attached SCSI disk
Aug 12 13:52:53 homebridge kernel: EXT4-fs (sdb2): mounted filesystem d4cc7d63-da78-48ad-9bdd-64ffbba449a8 ro with ordered data mode. Quota mode: none.
I then ran fsck to fix /dev/sdb1 and re-enabled and started the disabled services and it’s been working fine with no issues since then.
Strange, but this does not seem new: drivers - failing ASMedia ASM1153 on USB 3.0 - Ask Ubuntu (this question was asked 3 years ago).
No solution, but one suggestion was to try USB2 (which is of course not what you really want).
One thing that is strange is that both your SSDs have the same ASMedia chip and both have the same serial number. No idea if this confuses Linux.
Of course, it could also be a cable issue.
Good find on SE but as you say it is no answer. I find it odd that the NAS is stable using the mains powered docking station, so my suspicions are a faulty cable on the USB hub or sata adapter.
Would it be possible to exchange them and try again?
This is the time to ask Pimoroni support. Send them an e-mail with a link to this thread and ask for a replacement. But it could still just be that the ASMedia is very picky.
It also has an ASMedia chip and is very minimalist. It is not powered and I never had any problems. But I did not put a hub in between, so there is one cable less and I also don’t operate that in 24/7. In this series, there are tecnically similar adapters with an enclosure which might be more suitable.
If the problem is not the cable, but two identical ASMedia chips, then a second adapter might not help either. So I would certainly go for a replacement and try again first.