search

raspberrypi / linux

Kernel source tree for Raspberry Pi-provided kernel builds. Issues unrelated to the linux kernel should be posted on the community forum at https://forums.raspberrypi.com/
Other
10.87k stars 4.89k forks source link

RPI5: usb x-x: cmd cmplt err -71 Error with different USB Disks #6055

Open RolandFelnhofer opened 3 months ago

RolandFelnhofer commented 3 months ago

Describe the bug

After as short time (20-60 minutes) USB-disks (a Seagate HDD and a JMicron SSD) run into errors and disconnect. First entry in journalctl is: Mar 20 15:11:18 server kernel: usb 4-1: cmd cmplt err -71 more detail later in the Log-section

Steps to reproduce the behaviour

I don't know if I/O is relevant. So I just ran the following fio command in a loop: fio --name=random-write --ioengine=posixaio --rw=randwrite --bs=4k --size=1g --numjobs=10 --runtime=60 --time_based --end_fsync=1 After, at most 60 minutes, the error occurs.

Device (s)

Raspberry Pi 5

System

cat /etc/rpi-issue
Raspberry Pi reference 2023-12-11
Generated using pi-gen, https://github.com/RPi-Distro/pi-gen, 2acf7afcba7d11500313a7b93bb55a2aae20b2d6, stage2
vcgencmd version
2024/02/16 15:28:41
Copyright (c) 2012 Broadcom
version 4c845bd3 (release) (embedded)
uname -a
Linux server 6.6.22-v8-16k+ #1745 SMP PREEMPT Tue Mar 19 17:57:20 GMT 2024 aarch64 GNU/Linux

Logs

journalctl
...
Mar 20 16:10:51 server kernel: usb 4-1: cmd cmplt err -71
Mar 20 16:10:56 server kernel: scsi host0: uas_pre_reset: timed out
Mar 20 16:10:56 server kernel: sd 0:0:0:0: [sda] tag#0 uas_zap_pending 0 uas-tag 1 inflight: CMD
Mar 20 16:10:56 server kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 08 00 00 00 01 00
Mar 20 16:10:56 server kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=DRIVER_OK cmd_age=5s
Mar 20 16:10:56 server kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 08 00 00 00 01 00
Mar 20 16:10:56 server kernel: I/O error, dev sda, sector 2048 op 0x0:(READ) flags 0x1000 phys_seg 1 prio class 2
Mar 20 16:10:56 server kernel: XFS (sda1): SB validate failed with error -5.
Mar 20 16:10:56 server kernel: device offline error, dev sda, sector 0 op 0x1:(WRITE) flags 0x800 phys_seg 0 prio class 2
Mar 20 16:10:56 server kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache
Mar 20 16:10:57 server kernel: sd 0:0:0:0: [sda] Synchronize Cache(10) failed: Result: hostbyte=0x07 driverbyte=DRIVER_OK
Mar 20 16:10:57 server kernel: usb 4-1: Disable of device-initiated U1 failed.
Mar 20 16:10:57 server kernel: usb 4-1: Disable of device-initiated U2 failed.
Mar 20 16:10:57 server kernel: usb 4-1: Enable of device-initiated U1 failed.
Mar 20 16:10:57 server kernel: usb 4-1: Enable of device-initiated U2 failed.
Mar 20 16:10:57 server kernel: usb 4-1: reset SuperSpeed USB device number 4 using xhci-hcd
Mar 20 16:10:57 server kernel: scsi host0: uas
Mar 20 16:10:57 server kernel: scsi 0:0:0:0: Direct-Access     Intenso  Portable SSD     0506 PQ: 0 ANSI: 6
Mar 20 16:10:57 server kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0
Mar 20 16:10:57 server kernel: sd 0:0:0:0: [sda] 2000409264 512-byte logical blocks: (1.02 TB/954 GiB)
Mar 20 16:10:57 server kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks
Mar 20 16:10:57 server kernel: sd 0:0:0:0: [sda] Write Protect is off
Mar 20 16:10:57 server kernel: sd 0:0:0:0: [sda] Mode Sense: 53 00 00 08
Mar 20 16:10:57 server kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Mar 20 16:10:57 server kernel: sd 0:0:0:0: [sda] Preferred minimum I/O size 4096 bytes
Mar 20 16:10:57 server kernel: sd 0:0:0:0: [sda] Optimal transfer size 33553920 bytes not a multiple of preferred minimum block size (4096 bytes)
Mar 20 16:10:57 server kernel:  sda: sda1
Mar 20 16:10:57 server kernel: sd 0:0:0:0: [sda] Attached SCSI disk
...
lsusb --verbose

Bus 004 Device 004: ID 152d:0579 JMicron Technology Corp. / JMicron USA Technology Corp. Portable SSD
Device Descriptor:
  bLength                18
  bDescriptorType         1
  bcdUSB               3.00
  bDeviceClass            0
  bDeviceSubClass         0
  bDeviceProtocol         0
  bMaxPacketSize0         9
  idVendor           0x152d JMicron Technology Corp. / JMicron USA Technology Corp.
  idProduct          0x0579
  bcdDevice            5.06
  iManufacturer           1 Intenso
  iProduct                2 Portable SSD
  iSerial                 3 2023061830057
  bNumConfigurations      1
  Configuration Descriptor:
    bLength                 9
    bDescriptorType         2
    wTotalLength       0x0079
    bNumInterfaces          1
    bConfigurationValue     1
    iConfiguration          0
    bmAttributes         0x80
      (Bus Powered)
    MaxPower              896mA
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        0
      bAlternateSetting       0
      bNumEndpoints           2
      bInterfaceClass         8 Mass Storage
      bInterfaceSubClass      6 SCSI
      bInterfaceProtocol     80 Bulk-Only
      iInterface              0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x81  EP 1 IN
        bmAttributes            2
          Transfer Type            Bulk
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0400  1x 1024 bytes
        bInterval               0
        bMaxBurst              15
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x02  EP 2 OUT
        bmAttributes            2
          Transfer Type            Bulk
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0400  1x 1024 bytes
        bInterval               0
        bMaxBurst              15
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        0
      bAlternateSetting       1
      bNumEndpoints           4
      bInterfaceClass         8 Mass Storage
      bInterfaceSubClass      6 SCSI
      bInterfaceProtocol     98
      iInterface              0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x01  EP 1 OUT
        bmAttributes            2
          Transfer Type            Bulk
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0400  1x 1024 bytes
        bInterval               0
        bMaxBurst               0
        Command pipe (0x01)
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x82  EP 2 IN
        bmAttributes            2
          Transfer Type            Bulk
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0400  1x 1024 bytes
        bInterval               0
        bMaxBurst               0
        MaxStreams             32
        Status pipe (0x02)
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x83  EP 3 IN
        bmAttributes            2
          Transfer Type            Bulk
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0400  1x 1024 bytes
        bInterval               0
        bMaxBurst              15
        MaxStreams             32
        Data-in pipe (0x03)
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x04  EP 4 OUT
        bmAttributes            2
          Transfer Type            Bulk
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0400  1x 1024 bytes
        bInterval               0
        bMaxBurst               7
        MaxStreams             32
        Data-out pipe (0x04)
Binary Object Store Descriptor:
  bLength                 5
  bDescriptorType        15
  wTotalLength       0x0016
  bNumDeviceCaps          2
  USB 2.0 Extension Device Capability:
    bLength                 7
    bDescriptorType        16
    bDevCapabilityType      2
    bmAttributes   0x00000f0e
      BESL Link Power Management (LPM) Supported
    BESL value     3840 us
  SuperSpeed USB Device Capability:
    bLength                10
    bDescriptorType        16
    bDevCapabilityType      3
    bmAttributes         0x00
    wSpeedsSupported   0x000e
      Device can operate at Full Speed (12Mbps)
      Device can operate at High Speed (480Mbps)
      Device can operate at SuperSpeed (5Gbps)
    bFunctionalitySupport   1
      Lowest fully-functional device speed is Full Speed (12Mbps)
    bU1DevExitLat          10 micro seconds
    bU2DevExitLat          32 micro seconds
Device Status:     0x000c
  (Bus Powered)
  U1 Enabled
  U2 Enabled

Bus 004 Device 001: ID 1d6b:0003 Linux Foundation 3.0 root hub
Device Descriptor:
  bLength                18
  bDescriptorType         1
  bcdUSB               3.00
  bDeviceClass            9 Hub
  bDeviceSubClass         0
  bDeviceProtocol         3
  bMaxPacketSize0         9
  idVendor           0x1d6b Linux Foundation
  idProduct          0x0003 3.0 root hub
  bcdDevice            6.06
  iManufacturer           3 Linux 6.6.22-v8-16k+ xhci-hcd
  iProduct                2 xHCI Host Controller
  iSerial                 1 xhci-hcd.1
  bNumConfigurations      1
  Configuration Descriptor:
    bLength                 9
    bDescriptorType         2
    wTotalLength       0x001f
    bNumInterfaces          1
    bConfigurationValue     1
    iConfiguration          0
    bmAttributes         0xe0
      Self Powered
      Remote Wakeup
    MaxPower                0mA
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        0
      bAlternateSetting       0
      bNumEndpoints           1
      bInterfaceClass         9 Hub
      bInterfaceSubClass      0
      bInterfaceProtocol      0 Full speed (or root) hub
      iInterface              0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x81  EP 1 IN
        bmAttributes            3
          Transfer Type            Interrupt
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0004  1x 4 bytes
        bInterval              12
        bMaxBurst               0
Hub Descriptor:
  bLength              12
  bDescriptorType      42
  nNbrPorts             1
  wHubCharacteristic 0x0009
    Per-port power switching
    Per-port overcurrent protection
  bPwrOn2PwrGood       50 * 2 milli seconds
  bHubContrCurrent      0 milli Ampere
  bHubDecLat          0.0 micro seconds
  wHubDelay             0 nano seconds
  DeviceRemovable    0x00
 Hub Port Status:
   Port 1: 0000.0223 5Gbps power U1 enable connect
Binary Object Store Descriptor:
  bLength                 5
  bDescriptorType        15
  wTotalLength       0x000f
  bNumDeviceCaps          1
  SuperSpeed USB Device Capability:
    bLength                10
    bDescriptorType        16
    bDevCapabilityType      3
    bmAttributes         0x02
      Latency Tolerance Messages (LTM) Supported
    wSpeedsSupported   0x0008
      Device can operate at SuperSpeed (5Gbps)
    bFunctionalitySupport   1
      Lowest fully-functional device speed is Full Speed (12Mbps)
    bU1DevExitLat          10 micro seconds
    bU2DevExitLat        2047 micro seconds
Device Status:     0x0001
  Self Powered

Bus 003 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Device Descriptor:
  bLength                18
  bDescriptorType         1
  bcdUSB               2.00
  bDeviceClass            9 Hub
  bDeviceSubClass         0
  bDeviceProtocol         1 Single TT
  bMaxPacketSize0        64
  idVendor           0x1d6b Linux Foundation
  idProduct          0x0002 2.0 root hub
  bcdDevice            6.06
  iManufacturer           3 Linux 6.6.22-v8-16k+ xhci-hcd
  iProduct                2 xHCI Host Controller
  iSerial                 1 xhci-hcd.1
  bNumConfigurations      1
  Configuration Descriptor:
    bLength                 9
    bDescriptorType         2
    wTotalLength       0x0019
    bNumInterfaces          1
    bConfigurationValue     1
    iConfiguration          0
    bmAttributes         0xe0
      Self Powered
      Remote Wakeup
    MaxPower                0mA
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        0
      bAlternateSetting       0
      bNumEndpoints           1
      bInterfaceClass         9 Hub
      bInterfaceSubClass      0
      bInterfaceProtocol      0 Full speed (or root) hub
      iInterface              0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x81  EP 1 IN
        bmAttributes            3
          Transfer Type            Interrupt
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0004  1x 4 bytes
        bInterval              12
Hub Descriptor:
  bLength               9
  bDescriptorType      41
  nNbrPorts             2
  wHubCharacteristic 0x0009
    Per-port power switching
    Per-port overcurrent protection
    TT think time 8 FS bits
  bPwrOn2PwrGood       10 * 2 milli seconds
  bHubContrCurrent      0 milli Ampere
  DeviceRemovable    0x00
  PortPwrCtrlMask    0xff
 Hub Port Status:
   Port 1: 0000.0100 power
   Port 2: 0000.0100 power
Device Status:     0x0001
  Self Powered

Bus 002 Device 002: ID 0bda:8153 Realtek Semiconductor Corp. RTL8153 Gigabit Ethernet Adapter
Device Descriptor:
  bLength                18
  bDescriptorType         1
  bcdUSB               3.00
  bDeviceClass            0
  bDeviceSubClass         0
  bDeviceProtocol         0
  bMaxPacketSize0         9
  idVendor           0x0bda Realtek Semiconductor Corp.
  idProduct          0x8153 RTL8153 Gigabit Ethernet Adapter
  bcdDevice           31.00
  iManufacturer           1 Realtek
  iProduct                2 USB 10/100/1000 LAN
  iSerial                 6 001000001
  bNumConfigurations      2
  Configuration Descriptor:
    bLength                 9
    bDescriptorType         2
    wTotalLength       0x0039
    bNumInterfaces          1
    bConfigurationValue     1
    iConfiguration          0
    bmAttributes         0xa0
      (Bus Powered)
      Remote Wakeup
    MaxPower              288mA
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        0
      bAlternateSetting       0
      bNumEndpoints           3
      bInterfaceClass       255 Vendor Specific Class
      bInterfaceSubClass    255 Vendor Specific Subclass
      bInterfaceProtocol      0
      iInterface              0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x81  EP 1 IN
        bmAttributes            2
          Transfer Type            Bulk
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0400  1x 1024 bytes
        bInterval               0
        bMaxBurst               3
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x02  EP 2 OUT
        bmAttributes            2
          Transfer Type            Bulk
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0400  1x 1024 bytes
        bInterval               0
        bMaxBurst               3
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x83  EP 3 IN
        bmAttributes            3
          Transfer Type            Interrupt
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0002  1x 2 bytes
        bInterval               8
        bMaxBurst               0
  Configuration Descriptor:
    bLength                 9
    bDescriptorType         2
    wTotalLength       0x0062
    bNumInterfaces          2
    bConfigurationValue     2
    iConfiguration          0
    bmAttributes         0xa0
      (Bus Powered)
      Remote Wakeup
    MaxPower              288mA
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        0
      bAlternateSetting       0
      bNumEndpoints           1
      bInterfaceClass         2 Communications
      bInterfaceSubClass      6 Ethernet Networking
      bInterfaceProtocol      0
      iInterface              5 CDC Communications Control
      CDC Header:
        bcdCDC               1.10
      CDC Union:
        bMasterInterface        0
        bSlaveInterface         1
      CDC Ethernet:
        iMacAddress                      3 C84D442888A1
        bmEthernetStatistics    0x00000000
        wMaxSegmentSize               1514
        wNumberMCFilters            0x0000
        bNumberPowerFilters              0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x83  EP 3 IN
        bmAttributes            3
          Transfer Type            Interrupt
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0010  1x 16 bytes
        bInterval               8
        bMaxBurst               0
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        1
      bAlternateSetting       0
      bNumEndpoints           0
      bInterfaceClass        10 CDC Data
      bInterfaceSubClass      0
      bInterfaceProtocol      0
      iInterface              0
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        1
      bAlternateSetting       1
      bNumEndpoints           2
      bInterfaceClass        10 CDC Data
      bInterfaceSubClass      0
      bInterfaceProtocol      0
      iInterface              4 Ethernet Data
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x81  EP 1 IN
        bmAttributes            2
          Transfer Type            Bulk
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0400  1x 1024 bytes
        bInterval               0
        bMaxBurst               3
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x02  EP 2 OUT
        bmAttributes            2
          Transfer Type            Bulk
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0400  1x 1024 bytes
        bInterval               0
        bMaxBurst               3
Binary Object Store Descriptor:
  bLength                 5
  bDescriptorType        15
  wTotalLength       0x0016
  bNumDeviceCaps          2
  USB 2.0 Extension Device Capability:
    bLength                 7
    bDescriptorType        16
    bDevCapabilityType      2
    bmAttributes   0x00000002
      HIRD Link Power Management (LPM) Supported
  SuperSpeed USB Device Capability:
    bLength                10
    bDescriptorType        16
    bDevCapabilityType      3
    bmAttributes         0x02
      Latency Tolerance Messages (LTM) Supported
    wSpeedsSupported   0x000e
      Device can operate at Full Speed (12Mbps)
      Device can operate at High Speed (480Mbps)
      Device can operate at SuperSpeed (5Gbps)
    bFunctionalitySupport   2
      Lowest fully-functional device speed is High Speed (480Mbps)
    bU1DevExitLat          10 micro seconds
    bU2DevExitLat        2047 micro seconds
Device Status:     0x0010
  (Bus Powered)
  Latency Tolerance Messaging (LTM) Enabled

Bus 002 Device 001: ID 1d6b:0003 Linux Foundation 3.0 root hub
Device Descriptor:
  bLength                18
  bDescriptorType         1
  bcdUSB               3.00
  bDeviceClass            9 Hub
  bDeviceSubClass         0
  bDeviceProtocol         3
  bMaxPacketSize0         9
  idVendor           0x1d6b Linux Foundation
  idProduct          0x0003 3.0 root hub
  bcdDevice            6.06
  iManufacturer           3 Linux 6.6.22-v8-16k+ xhci-hcd
  iProduct                2 xHCI Host Controller
  iSerial                 1 xhci-hcd.0
  bNumConfigurations      1
  Configuration Descriptor:
    bLength                 9
    bDescriptorType         2
    wTotalLength       0x001f
    bNumInterfaces          1
    bConfigurationValue     1
    iConfiguration          0
    bmAttributes         0xe0
      Self Powered
      Remote Wakeup
    MaxPower                0mA
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        0
      bAlternateSetting       0
      bNumEndpoints           1
      bInterfaceClass         9 Hub
      bInterfaceSubClass      0
      bInterfaceProtocol      0 Full speed (or root) hub
      iInterface              0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x81  EP 1 IN
        bmAttributes            3
          Transfer Type            Interrupt
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0004  1x 4 bytes
        bInterval              12
        bMaxBurst               0
Hub Descriptor:
  bLength              12
  bDescriptorType      42
  nNbrPorts             1
  wHubCharacteristic 0x0009
    Per-port power switching
    Per-port overcurrent protection
  bPwrOn2PwrGood       50 * 2 milli seconds
  bHubContrCurrent      0 milli Ampere
  bHubDecLat          0.0 micro seconds
  wHubDelay             0 nano seconds
  DeviceRemovable    0x00
 Hub Port Status:
   Port 1: 0000.0203 5Gbps power U0 enable connect
Binary Object Store Descriptor:
  bLength                 5
  bDescriptorType        15
  wTotalLength       0x000f
  bNumDeviceCaps          1
  SuperSpeed USB Device Capability:
    bLength                10
    bDescriptorType        16
    bDevCapabilityType      3
    bmAttributes         0x02
      Latency Tolerance Messages (LTM) Supported
    wSpeedsSupported   0x0008
      Device can operate at SuperSpeed (5Gbps)
    bFunctionalitySupport   1
      Lowest fully-functional device speed is Full Speed (12Mbps)
    bU1DevExitLat          10 micro seconds
    bU2DevExitLat        2047 micro seconds
Device Status:     0x0001
  Self Powered

Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Device Descriptor:
  bLength                18
  bDescriptorType         1
  bcdUSB               2.00
  bDeviceClass            9 Hub
  bDeviceSubClass         0
  bDeviceProtocol         1 Single TT
  bMaxPacketSize0        64
  idVendor           0x1d6b Linux Foundation
  idProduct          0x0002 2.0 root hub
  bcdDevice            6.06
  iManufacturer           3 Linux 6.6.22-v8-16k+ xhci-hcd
  iProduct                2 xHCI Host Controller
  iSerial                 1 xhci-hcd.0
  bNumConfigurations      1
  Configuration Descriptor:
    bLength                 9
    bDescriptorType         2
    wTotalLength       0x0019
    bNumInterfaces          1
    bConfigurationValue     1
    iConfiguration          0
    bmAttributes         0xe0
      Self Powered
      Remote Wakeup
    MaxPower                0mA
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        0
      bAlternateSetting       0
      bNumEndpoints           1
      bInterfaceClass         9 Hub
      bInterfaceSubClass      0
      bInterfaceProtocol      0 Full speed (or root) hub
      iInterface              0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x81  EP 1 IN
        bmAttributes            3
          Transfer Type            Interrupt
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0004  1x 4 bytes
        bInterval              12
Hub Descriptor:
  bLength               9
  bDescriptorType      41
  nNbrPorts             2
  wHubCharacteristic 0x0009
    Per-port power switching
    Per-port overcurrent protection
    TT think time 8 FS bits
  bPwrOn2PwrGood       10 * 2 milli seconds
  bHubContrCurrent      0 milli Ampere
  DeviceRemovable    0x00
  PortPwrCtrlMask    0xff
 Hub Port Status:
   Port 1: 0000.0100 power
   Port 2: 0000.0100 power
Device Status:     0x0001
  Self Powered

(only information for Seagate HDD)

lsusb --verbose 
...
Bus 004 Device 003: ID 0bc2:ab21 Seagate RSS LLC Backup Plus Slim
Device Descriptor:
  bLength                18
  bDescriptorType         1
  bcdUSB               3.00
  bDeviceClass            0
  bDeviceSubClass         0
  bDeviceProtocol         0
  bMaxPacketSize0         9
  idVendor           0x0bc2 Seagate RSS LLC
  idProduct          0xab21 Backup Plus Slim
  bcdDevice            1.43
  iManufacturer           1 Seagate
  iProduct                2 BUP Slim BL
  iSerial                 3 NA7JGQYJ
  bNumConfigurations      1
  Configuration Descriptor:
    bLength                 9
    bDescriptorType         2
    wTotalLength       0x0079
    bNumInterfaces          1
    bConfigurationValue     1
    iConfiguration          0
    bmAttributes         0x80
      (Bus Powered)
    MaxPower              144mA
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        0
      bAlternateSetting       0
      bNumEndpoints           2
      bInterfaceClass         8 Mass Storage
      bInterfaceSubClass      6 SCSI
      bInterfaceProtocol     80 Bulk-Only
      iInterface              0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x83  EP 3 IN
        bmAttributes            2
          Transfer Type            Bulk
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0400  1x 1024 bytes
        bInterval               0
        bMaxBurst               7
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x0a  EP 10 OUT
        bmAttributes            2
          Transfer Type            Bulk
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0400  1x 1024 bytes
        bInterval               0
        bMaxBurst               7
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        0
      bAlternateSetting       1
      bNumEndpoints           4
      bInterfaceClass         8 Mass Storage
      bInterfaceSubClass      6 SCSI
      bInterfaceProtocol     98
      iInterface              0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x08  EP 8 OUT
        bmAttributes            2
          Transfer Type            Bulk
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0400  1x 1024 bytes
        bInterval               0
        bMaxBurst               0
        Command pipe (0x01)
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x81  EP 1 IN
        bmAttributes            2
          Transfer Type            Bulk
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0400  1x 1024 bytes
        bInterval               0
        bMaxBurst               0
        MaxStreams             32
        Status pipe (0x02)
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x0a  EP 10 OUT
        bmAttributes            2
          Transfer Type            Bulk
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0400  1x 1024 bytes
        bInterval               0
        bMaxBurst               7
        MaxStreams             32
        Data-out pipe (0x04)
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x83  EP 3 IN
        bmAttributes            2
          Transfer Type            Bulk
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0400  1x 1024 bytes
        bInterval               0
        bMaxBurst               7
        MaxStreams             32
        Data-in pipe (0x03)
Binary Object Store Descriptor:
  bLength                 5
  bDescriptorType        15
  wTotalLength       0x0016
  bNumDeviceCaps          2
  USB 2.0 Extension Device Capability:
    bLength                 7
    bDescriptorType        16
    bDevCapabilityType      2
    bmAttributes   0x00000002
      HIRD Link Power Management (LPM) Supported
  SuperSpeed USB Device Capability:
    bLength                10
    bDescriptorType        16
    bDevCapabilityType      3
    bmAttributes         0x00
    wSpeedsSupported   0x000e
      Device can operate at Full Speed (12Mbps)
      Device can operate at High Speed (480Mbps)
      Device can operate at SuperSpeed (5Gbps)
    bFunctionalitySupport   1
      Lowest fully-functional device speed is Full Speed (12Mbps)
    bU1DevExitLat          10 micro seconds
    bU2DevExitLat         512 micro seconds
Device Status:     0x000c
  (Bus Powered)
  U1 Enabled
  U2 Enabled
...

Additional context

I'm using the official Pi5 27W PSU (Barcode: 5 056561 803416)

vcgencmd get_config usb_max_current_enable
usb_max_current_enable=1

It seems that adding usb-storage.quirks=152d:0579:u to /boot/firmware/cmdline.txt prevent this error from occuring but disk performance decreases significantly! From [w=33.0k IOPS] to [w=6912 IOPS]

if this realy prevents the issue from occuring I can report after some hours/days.

ThomasKaiser commented 3 months ago

It seems that adding usb-storage.quirks=152d:0579:u to /boot/firmware/cmdline.txt prevent this error

Can't 'help' with your 0bc2:ab21 Seagate HDD and after blacklisting UAS when the real reason was either data integrity or power issues then error messages just will be different but symptoms pretty much the same.

matthias480 commented 2 months ago

I have similar problems since switching from the Raspberry Pi 4 to the Raspberry Pi 5. Similar things were also discussed in #5753. Not sure if it's the same thing, but there still seems to be an issue.

I use two stacked 4-Port USB 3.1 Hubs with PPPS (Per-Port Power Switching) functionality. The problem happens with every used drive (SSD/HDD).

Sometimes errors occurs when I want to mount the partition after turning the USB port on:

mount: /mnt/ssd4: /dev/sdf1 is not a valid block device.
       dmesg(1) may have more information after failed mount system call.

This probem already occured on my Raspberry Pi 4. I thought, maybe it happens if the drive isn't ready when the mount happens. A second mount was successful. Still so is now.

Raspberry Pi 4: Linux raspberrypi 5.10.63-v7l+ #1496 SMP Wed Dec 1 15:58:56 GMT 2021 armv7l

[Fri Mar 8 19:37:35 2024] usb 2-2.3: cmd cmplt err -71
[Fri Mar 8 19:37:53 2024] usb 2-2.3: USB disconnect, device number 17
[Fri Mar 8 19:37:53 2024] sd 6:0:0:0: [sdg] tag#16 uas_zap_pending 0 uas-tag 1 inflight: CMD
[Fri Mar 8 19:37:53 2024] sd 6:0:0:0: [sdg] tag#16 CDB: opcode=0x88 88 00 00 00 00 01 d1 c0 b7 80 00 00 00 08 00 00
[Fri Mar 8 19:37:53 2024] sd 6:0:0:0: [sdg] tag#16 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=0x00 cmd_age=18s
[Fri Mar 8 19:37:53 2024] sd 6:0:0:0: [sdg] tag#16 CDB: opcode=0x88 88 00 00 00 00 01 d1 c0 b7 80 00 00 00 08 00 00
[Fri Mar 8 19:37:53 2024] blk_update_request: I/O error, dev sdg, sector 7814035328 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 0
[Fri Mar 8 19:37:53 2024] blk_update_request: I/O error, dev sdg, sector 7814035328 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 0
[Fri Mar 8 19:37:53 2024] Buffer I/O error on dev sdg1, logical block 976754160, async page read
[Fri Mar 8 19:37:53 2024] sd 6:0:0:0: [sdg] Synchronizing SCSI cache
[Fri Mar 8 19:37:54 2024] sd 6:0:0:0: [sdg] Synchronize Cache(10) failed: Result: hostbyte=0x07 driverbyte=0x00
[Fri Mar 8 19:37:54 2024] usb 2-2.3: new SuperSpeed Gen 1 USB device number 23 using xhci_hcd
[Fri Mar 8 19:37:54 2024] usb 2-2.3: New USB device found, idVendor=174c, idProduct=55aa, bcdDevice= 1.00
[Fri Mar 8 19:37:54 2024] usb 2-2.3: New USB device strings: Mfr=2, Product=3, SerialNumber=1
[Fri Mar 8 19:37:54 2024] usb 2-2.3: Product: ASM105x
[Fri Mar 8 19:37:54 2024] usb 2-2.3: Manufacturer: ASMT
[Fri Mar 8 19:37:54 2024] usb 2-2.3: SerialNumber: 123...
[Fri Mar 8 19:37:54 2024] scsi host6: uas
[Fri Mar 8 19:37:54 2024] scsi 6:0:0:0: Direct-Access     Samsung  SSD 860 EVO 4TB  0    PQ: 0 ANSI: 6
[Fri Mar 8 19:37:54 2024] sd 6:0:0:0: Attached scsi generic sg9 type 0
[Fri Mar 8 19:37:54 2024] sd 6:0:0:0: [sdg] 7814037168 512-byte logical blocks: (4.00 TB/3.64 TiB)
[Fri Mar 8 19:37:54 2024] sd 6:0:0:0: [sdg] Write Protect is off
[Fri Mar 8 19:37:54 2024] sd 6:0:0:0: [sdg] Mode Sense: 43 00 00 00
[Fri Mar 8 19:37:54 2024] sd 6:0:0:0: [sdg] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[Fri Mar 8 19:37:54 2024] sd 6:0:0:0: [sdg] Optimal transfer size 33553920 bytes
[Fri Mar 8 19:37:54 2024]  sdg: sdg1
[Fri Mar 8 19:37:54 2024] sd 6:0:0:0: [sdg] Attached SCSI disk

Raspberry Pi 5: Linux raspberrypi 6.6.20+rpt-rpi-2712 #1 SMP PREEMPT Debian 1:6.6.20-1+rpt1 (2024-03-07) aarch64

[Mon Apr 15 16:55:50 2024] usb 2-1.3: cmd cmplt err -71
[Mon Apr 15 16:56:21 2024] sd 4:0:0:0: [sdf] tag#1 uas_eh_abort_handler 0 uas-tag 1 inflight: CMD IN
[Mon Apr 15 16:56:21 2024] sd 4:0:0:0: [sdf] tag#1 CDB: opcode=0x88 88 00 00 00 00 03 a3 81 29 ff 00 00 00 20 00 00
[Mon Apr 15 16:56:21 2024] scsi host4: uas_eh_device_reset_handler start
[Mon Apr 15 16:56:22 2024] scsi host4: uas_eh_device_reset_handler FAILED err -19
[Mon Apr 15 16:56:22 2024] sd 4:0:0:0: Device offlined - not ready after error recovery
[Mon Apr 15 16:56:22 2024] sd 4:0:0:0: [sdf] tag#1 UNKNOWN(0x2003) Result: hostbyte=0x03 driverbyte=DRIVER_OK cmd_age=31s
[Mon Apr 15 16:56:22 2024] sd 4:0:0:0: [sdf] tag#1 CDB: opcode=0x88 88 00 00 00 00 03 a3 81 29 ff 00 00 00 20 00 00
[Mon Apr 15 16:56:22 2024] I/O error, dev sdf, sector 15628052991 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2
[Mon Apr 15 16:56:22 2024] sd 4:0:0:0: rejecting I/O to offline device
[Mon Apr 15 16:56:22 2024] I/O error, dev sdf, sector 15628052991 op 0x0:(READ) flags 0x0 phys_seg 2 prio class 2
[Mon Apr 15 16:56:22 2024] buffer_io_error: 55 callbacks suppressed
[Mon Apr 15 16:56:22 2024] Buffer I/O error on dev sdf1, logical block 976749216, async page read
[Mon Apr 15 16:56:22 2024] Buffer I/O error on dev sdf1, logical block 976749217, async page read
[Mon Apr 15 16:56:22 2024] /dev/sdf1: Can't open blockdev
[Mon Apr 15 16:56:22 2024] usb 2-1.3: USB disconnect, device number 27
[Mon Apr 15 16:56:22 2024] sd 4:0:0:0: [sdf] Synchronizing SCSI cache
[Mon Apr 15 16:56:22 2024] sd 4:0:0:0: [sdf] Synchronize Cache(10) failed: Result: hostbyte=0x07 driverbyte=DRIVER_OK
[Mon Apr 15 16:56:47 2024] usb 2-1.3: new SuperSpeed USB device number 35 using xhci-hcd
[Mon Apr 15 16:56:47 2024] usb 2-1.3: New USB device found, idVendor=174c, idProduct=55aa, bcdDevice= 1.00
[Mon Apr 15 16:56:47 2024] usb 2-1.3: New USB device strings: Mfr=2, Product=3, SerialNumber=1
[Mon Apr 15 16:56:47 2024] usb 2-1.3: Product: ASM105x
[Mon Apr 15 16:56:47 2024] usb 2-1.3: Manufacturer: ASMT
[Mon Apr 15 16:56:47 2024] usb 2-1.3: SerialNumber: 123...
[Mon Apr 15 16:56:47 2024] scsi host4: uas
[Mon Apr 15 16:56:47 2024] scsi 4:0:0:0: Direct-Access     Samsung  SSD 870 QVO 8TB  0    PQ: 0 ANSI: 6
[Mon Apr 15 16:56:47 2024] sd 4:0:0:0: Attached scsi generic sg9 type 0
[Mon Apr 15 16:56:47 2024] sd 4:0:0:0: [sdf] 15628053168 512-byte logical blocks: (8.00 TB/7.28 TiB)
[Mon Apr 15 16:56:47 2024] sd 4:0:0:0: [sdf] Write Protect is off
[Mon Apr 15 16:56:47 2024] sd 4:0:0:0: [sdf] Mode Sense: 43 00 00 00
[Mon Apr 15 16:56:47 2024] sd 4:0:0:0: [sdf] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[Mon Apr 15 16:56:47 2024] sd 4:0:0:0: [sdf] Preferred minimum I/O size 512 bytes
[Mon Apr 15 16:56:47 2024] sd 4:0:0:0: [sdf] Optimal transfer size 33553920 bytes
[Mon Apr 15 16:56:47 2024]  sdf: sdf1
[Mon Apr 15 16:56:47 2024] sd 4:0:0:0: [sdf] Attached SCSI disk

Like mentioned, a second mount is still successful, so that's not the big problem for me. However, the new thing on the Raspberry Pi 5 is, that after the drive has been running for a while (I don't know exactly when it happens, maybe only after a certain amount of data has been transferred) the drive isn't accessible anymore when being used the next time. I get an I/O error (e.g. on ls). Need to check the log next time it happens.

I also get a lot of uas_eh_abort_handler, which, on the Raspberry Pi 4, I only had with incompatible SATA adapters, but not with common external drives (Samsung Portable SSD T5).

Apr 15 21:37:29 raspberrypi kernel: usb 2-1.2: cmd cmplt err -71
Apr 15 21:37:32 raspberrypi kernel: usb 2-1.2: USB disconnect, device number 28
Apr 15 21:37:32 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#4 uas_zap_pending 0 uas-tag 1 inflight: CMD
Apr 15 21:37:32 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#4 CDB: opcode=0x28 28 00 e8 e0 87 80 00 00 20 00
Apr 15 21:37:32 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#4 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=DRIVER_OK cmd_age=3s
Apr 15 21:37:32 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#4 CDB: opcode=0x28 28 00 e8 e0 87 80 00 00 20 00
Apr 15 21:37:32 raspberrypi kernel: I/O error, dev sdb, sector 3907028864 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2
Apr 15 21:37:32 raspberrypi kernel: device offline error, dev sdb, sector 3907028864 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2
Apr 15 21:37:32 raspberrypi kernel: Buffer I/O error on dev sdb1, logical block 122094588, async page read
Apr 15 21:37:32 raspberrypi kernel: /dev/sdb1: Can't open blockdev
Apr 15 21:37:32 raspberrypi kernel: sd 2:0:0:0: [sdb] Synchronizing SCSI cache
Apr 15 21:37:32 raspberrypi kernel: sd 2:0:0:0: [sdb] Synchronize Cache(10) failed: Result: hostbyte=0x07 driverbyte=DRIVER_OK
Apr 15 21:37:33 raspberrypi kernel: usb 2-1.2: new SuperSpeed USB device number 36 using xhci-hcd
Apr 15 21:37:33 raspberrypi kernel: usb 2-1.2: New USB device found, idVendor=04e8, idProduct=61f5, bcdDevice= 1.00
Apr 15 21:37:33 raspberrypi kernel: usb 2-1.2: New USB device strings: Mfr=2, Product=3, SerialNumber=1
Apr 15 21:37:33 raspberrypi kernel: usb 2-1.2: Product: Portable SSD T5
Apr 15 21:37:33 raspberrypi kernel: usb 2-1.2: Manufacturer: Samsung
Apr 15 21:37:33 raspberrypi kernel: usb 2-1.2: SerialNumber: 123...
Apr 15 21:37:33 raspberrypi kernel: scsi host2: uas
Apr 15 21:37:33 raspberrypi kernel: scsi 2:0:0:0: Direct-Access     Samsung  Portable SSD T5  0    PQ: 0 ANSI: 6
Apr 15 21:37:33 raspberrypi kernel: sd 2:0:0:0: Attached scsi generic sg1 type 0
Apr 15 21:37:33 raspberrypi kernel: sd 2:0:0:0: [sdb] 3907029168 512-byte logical blocks: (2.00 TB/1.82 TiB)
Apr 15 21:37:33 raspberrypi kernel: sd 2:0:0:0: [sdb] Write Protect is off
Apr 15 21:37:33 raspberrypi kernel: sd 2:0:0:0: [sdb] Mode Sense: 43 00 00 00
Apr 15 21:37:33 raspberrypi kernel: sd 2:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Apr 15 21:37:33 raspberrypi kernel: sd 2:0:0:0: [sdb] Preferred minimum I/O size 512 bytes
Apr 15 21:37:33 raspberrypi kernel: sd 2:0:0:0: [sdb] Optimal transfer size 33553920 bytes
Apr 15 21:37:33 raspberrypi kernel:  sdb: sdb1
Apr 15 21:37:33 raspberrypi kernel: sd 2:0:0:0: [sdb] Attached SCSI disk
Apr 15 21:38:07 raspberrypi kernel: EXT4-fs (sdb1): mounted filesystem ebb4b458-5574-49eb-916b-598026a3f1fe r/w with ordered data mode. Quota mode: none.
Apr 15 21:47:08 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#15 uas_eh_abort_handler 0 uas-tag 3 inflight: CMD
Apr 15 21:47:08 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#15 CDB: opcode=0x28 28 00 03 de 42 58 00 01 00 00
Apr 15 21:47:08 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#14 uas_eh_abort_handler 0 uas-tag 1 inflight: CMD
Apr 15 21:47:08 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#14 CDB: opcode=0x28 28 00 02 26 00 68 00 01 00 00
Apr 15 21:47:08 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#13 uas_eh_abort_handler 0 uas-tag 9 inflight: CMD
Apr 15 21:47:08 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#13 CDB: opcode=0x28 28 00 02 c0 02 70 00 01 00 00
Apr 15 21:47:08 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#12 uas_eh_abort_handler 0 uas-tag 6 inflight: CMD
Apr 15 21:47:08 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#12 CDB: opcode=0x28 28 00 03 f2 05 68 00 01 00 00
Apr 15 21:47:08 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#6 uas_eh_abort_handler 0 uas-tag 7 inflight: CMD
Apr 15 21:47:08 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#6 CDB: opcode=0x28 28 00 02 89 f0 58 00 01 00 00
Apr 15 21:47:08 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#5 uas_eh_abort_handler 0 uas-tag 2 inflight: CMD
Apr 15 21:47:08 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#5 CDB: opcode=0x28 28 00 05 cb fc 38 00 01 00 00
Apr 15 21:47:08 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#2 uas_eh_abort_handler 0 uas-tag 8 inflight: CMD
Apr 15 21:47:08 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#2 CDB: opcode=0x28 28 00 02 04 02 60 00 01 00 00
Apr 15 21:47:08 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#1 uas_eh_abort_handler 0 uas-tag 5 inflight: CMD
Apr 15 21:47:08 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#1 CDB: opcode=0x28 28 00 05 dc 00 d8 00 01 00 00
Apr 15 21:47:08 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#0 uas_eh_abort_handler 0 uas-tag 4 inflight: CMD
Apr 15 21:47:08 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#0 CDB: opcode=0x28 28 00 05 bb fe 48 00 01 00 00
Apr 15 21:47:08 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#7 uas_eh_abort_handler 0 uas-tag 10 inflight: CMD
Apr 15 21:47:08 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#7 CDB: opcode=0x28 28 00 05 a3 25 c0 00 01 00 00
Apr 15 21:47:45 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#3 uas_eh_abort_handler 0 uas-tag 11 inflight: CMD
Apr 15 21:47:45 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#3 CDB: opcode=0x35 35 00 00 00 00 00 00 00 00 00
Apr 15 21:47:45 raspberrypi kernel: scsi host2: uas_eh_device_reset_handler start
Apr 15 21:47:45 raspberrypi kernel: usb 2-1.2: reset SuperSpeed USB device number 36 using xhci-hcd
Apr 15 21:47:45 raspberrypi kernel: scsi host2: uas_eh_device_reset_handler success
Apr 15 21:49:07 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#6 uas_eh_abort_handler 0 uas-tag 3 inflight: CMD
Apr 15 21:49:07 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#6 CDB: opcode=0x2a 2a 00 b3 81 19 c0 00 00 08 00
Apr 15 21:49:07 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#5 uas_eh_abort_handler 0 uas-tag 2 inflight: CMD
Apr 15 21:49:07 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#5 CDB: opcode=0x2a 2a 00 b3 80 31 d8 00 00 08 00
Apr 15 21:49:07 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#4 uas_eh_abort_handler 0 uas-tag 1 inflight: CMD
Apr 15 21:49:07 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#4 CDB: opcode=0x2a 2a 00 b3 80 31 c8 00 00 08 00
Apr 15 21:49:31 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#21 uas_eh_abort_handler 0 uas-tag 4 inflight: CMD
Apr 15 21:49:31 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#21 CDB: opcode=0x28 28 00 03 8a 3f 18 00 01 00 00
Apr 15 21:50:08 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#7 uas_eh_abort_handler 0 uas-tag 5 inflight: CMD
Apr 15 21:50:08 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#7 CDB: opcode=0x35 35 00 00 00 00 00 00 00 00 00
Apr 15 21:50:08 raspberrypi kernel: scsi host2: uas_eh_device_reset_handler start
Apr 15 21:50:08 raspberrypi kernel: usb 2-1.2: reset SuperSpeed USB device number 36 using xhci-hcd
Apr 15 21:50:08 raspberrypi kernel: scsi host2: uas_eh_device_reset_handler success
Apr 15 21:55:01 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#18 uas_eh_abort_handler 0 uas-tag 10 inflight: CMD
Apr 15 21:55:01 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#18 CDB: opcode=0x28 28 00 04 b6 1f e8 00 01 00 00
Apr 15 21:55:01 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#17 uas_eh_abort_handler 0 uas-tag 6 inflight: CMD
Apr 15 21:55:01 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#17 CDB: opcode=0x28 28 00 04 b4 8e 88 00 01 00 00
Apr 15 21:55:01 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#16 uas_eh_abort_handler 0 uas-tag 4 inflight: CMD
Apr 15 21:55:01 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#16 CDB: opcode=0x28 28 00 04 b4 63 f8 00 01 00 00
Apr 15 21:55:01 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#11 uas_eh_abort_handler 0 uas-tag 8 inflight: CMD
Apr 15 21:55:01 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#11 CDB: opcode=0x28 28 00 04 b4 e6 f0 00 01 00 00
Apr 15 21:55:01 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#10 uas_eh_abort_handler 0 uas-tag 5 inflight: CMD
Apr 15 21:55:01 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#10 CDB: opcode=0x28 28 00 04 b4 bb 78 00 01 00 00
Apr 15 21:55:01 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#9 uas_eh_abort_handler 0 uas-tag 3 inflight: CMD
Apr 15 21:55:01 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#9 CDB: opcode=0x28 28 00 04 b4 78 18 00 01 00 00
Apr 15 21:55:01 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#8 uas_eh_abort_handler 0 uas-tag 1 inflight: CMD
Apr 15 21:55:01 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#8 CDB: opcode=0x28 28 00 04 b4 d1 98 00 01 00 00
Apr 15 21:55:01 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#7 uas_eh_abort_handler 0 uas-tag 2 inflight: CMD
Apr 15 21:55:01 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#7 CDB: opcode=0x28 28 00 04 b4 a4 60 00 01 00 00
Apr 15 21:55:01 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#5 uas_eh_abort_handler 0 uas-tag 9 inflight: CMD
Apr 15 21:55:01 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#5 CDB: opcode=0x28 28 00 04 b6 08 00 00 01 00 00
Apr 15 21:55:01 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#4 uas_eh_abort_handler 0 uas-tag 7 inflight: CMD
Apr 15 21:55:01 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#4 CDB: opcode=0x28 28 00 04 ed f3 b0 00 01 00 00
Apr 15 21:55:01 raspberrypi kernel: scsi host2: uas_eh_device_reset_handler start
Apr 15 21:55:01 raspberrypi kernel: usb 2-1.2: reset SuperSpeed USB device number 36 using xhci-hcd
Apr 15 21:55:01 raspberrypi kernel: scsi host2: uas_eh_device_reset_handler success
Apr 15 21:55:33 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#12 uas_eh_abort_handler 0 uas-tag 9 inflight: CMD
Apr 15 21:55:33 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#12 CDB: opcode=0x28 28 00 04 b6 b8 18 00 00 80 00
Apr 15 21:55:33 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#11 uas_eh_abort_handler 0 uas-tag 8 inflight: CMD
Apr 15 21:55:33 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#11 CDB: opcode=0x28 28 00 04 b6 b4 18 00 04 00 00
Apr 15 21:55:33 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#10 uas_eh_abort_handler 0 uas-tag 7 inflight: CMD
Apr 15 21:55:33 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#10 CDB: opcode=0x28 28 00 04 b6 b0 18 00 04 00 00
Apr 15 21:55:33 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#9 uas_eh_abort_handler 0 uas-tag 6 inflight: CMD
Apr 15 21:55:33 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#9 CDB: opcode=0x28 28 00 04 b6 ac 18 00 04 00 00
Apr 15 21:55:33 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#8 uas_eh_abort_handler 0 uas-tag 5 inflight: CMD
Apr 15 21:55:33 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#8 CDB: opcode=0x28 28 00 04 b6 a8 18 00 04 00 00
Apr 15 21:55:33 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#3 uas_eh_abort_handler 0 uas-tag 2 inflight: CMD
Apr 15 21:55:33 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#3 CDB: opcode=0x28 28 00 04 b6 c2 90 00 04 00 00
Apr 15 21:55:33 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#2 uas_eh_abort_handler 0 uas-tag 1 inflight: CMD
Apr 15 21:55:33 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#2 CDB: opcode=0x28 28 00 04 b6 be 90 00 04 00 00
Apr 15 21:55:33 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#1 uas_eh_abort_handler 0 uas-tag 4 inflight: CMD
Apr 15 21:55:33 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#1 CDB: opcode=0x28 28 00 04 b6 ca 90 00 03 60 00
Apr 15 21:55:33 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#0 uas_eh_abort_handler 0 uas-tag 3 inflight: CMD
Apr 15 21:55:33 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#0 CDB: opcode=0x28 28 00 04 b6 c6 90 00 04 00 00
Apr 15 21:55:35 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#18 uas_eh_abort_handler 0 uas-tag 10 inflight: CMD
Apr 15 21:55:35 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#18 CDB: opcode=0x28 28 00 04 b6 e4 50 00 01 00 00
Apr 15 21:55:35 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#4 uas_eh_abort_handler 0 uas-tag 11 inflight: CMD
Apr 15 21:55:35 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#4 CDB: opcode=0x28 28 00 04 b4 fe f0 00 01 00 00
Apr 15 21:55:35 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#21 uas_eh_abort_handler 0 uas-tag 12 inflight: CMD
Apr 15 21:55:35 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#21 CDB: opcode=0x28 28 00 04 b5 3d 00 00 01 00 00
Apr 15 21:55:36 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#22 uas_eh_abort_handler 0 uas-tag 15 inflight: CMD
Apr 15 21:55:36 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#22 CDB: opcode=0x28 28 00 04 b5 27 78 00 01 00 00
Apr 15 21:55:36 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#19 uas_eh_abort_handler 0 uas-tag 13 inflight: CMD
Apr 15 21:55:36 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#19 CDB: opcode=0x28 28 00 04 b5 4e c8 00 01 00 00
Apr 15 21:55:36 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#5 uas_eh_abort_handler 0 uas-tag 14 inflight: CMD
Apr 15 21:55:36 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#5 CDB: opcode=0x28 28 00 04 b5 14 b0 00 01 00 00
Apr 15 21:55:45 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#6 uas_eh_abort_handler 0 uas-tag 16 inflight: CMD
Apr 15 21:55:45 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#6 CDB: opcode=0x28 28 00 04 b5 7a 50 00 01 00 00
Apr 15 21:55:45 raspberrypi kernel: scsi host2: uas_eh_device_reset_handler start
Apr 15 21:55:45 raspberrypi kernel: usb 2-1.2: reset SuperSpeed USB device number 36 using xhci-hcd
Apr 15 21:55:45 raspberrypi kernel: scsi host2: uas_eh_device_reset_handler success
Apr 15 21:56:17 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#3 uas_eh_abort_handler 0 uas-tag 4 inflight: CMD
Apr 15 21:56:17 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#3 CDB: opcode=0x28 28 00 04 b5 25 b0 00 01 80 00
Apr 15 21:56:17 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#2 uas_eh_abort_handler 0 uas-tag 3 inflight: CMD
Apr 15 21:56:17 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#2 CDB: opcode=0x28 28 00 04 b5 21 b0 00 04 00 00
Apr 15 21:56:17 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#1 uas_eh_abort_handler 0 uas-tag 2 inflight: CMD
Apr 15 21:56:17 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#1 CDB: opcode=0x28 28 00 04 b5 1d b0 00 04 00 00
Apr 15 21:56:17 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#0 uas_eh_abort_handler 0 uas-tag 1 inflight: CMD
Apr 15 21:56:17 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#0 CDB: opcode=0x28 28 00 04 b5 19 b0 00 04 00 00
Apr 15 21:56:17 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#7 uas_eh_abort_handler 0 uas-tag 8 inflight: CMD
Apr 15 21:56:17 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#7 CDB: opcode=0x28 28 00 04 b6 fb 00 00 01 00 00
Apr 15 21:56:17 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#6 uas_eh_abort_handler 0 uas-tag 7 inflight: CMD
Apr 15 21:56:17 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#6 CDB: opcode=0x28 28 00 04 b5 d6 d0 00 01 00 00
Apr 15 21:56:17 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#5 uas_eh_abort_handler 0 uas-tag 6 inflight: CMD
Apr 15 21:56:17 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#5 CDB: opcode=0x28 28 00 04 b5 be a8 00 01 00 00
Apr 15 21:56:17 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#4 uas_eh_abort_handler 0 uas-tag 5 inflight: CMD
Apr 15 21:56:17 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#4 CDB: opcode=0x28 28 00 04 b5 e7 a8 00 01 00 00
Apr 15 21:56:17 raspberrypi kernel: scsi host2: uas_eh_device_reset_handler start
Apr 15 21:56:17 raspberrypi kernel: usb 2-1.2: reset SuperSpeed USB device number 36 using xhci-hcd
Apr 15 21:56:17 raspberrypi kernel: scsi host2: uas_eh_device_reset_handler success
Apr 15 21:56:50 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#25 uas_eh_abort_handler 0 uas-tag 6 inflight: CMD
Apr 15 21:56:50 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#25 CDB: opcode=0x28 28 00 04 b5 79 58 00 00 a0 00
Apr 15 21:56:50 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#24 uas_eh_abort_handler 0 uas-tag 5 inflight: CMD
Apr 15 21:56:50 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#24 CDB: opcode=0x28 28 00 04 b5 78 d8 00 00 80 00
Apr 15 21:56:50 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#23 uas_eh_abort_handler 0 uas-tag 2 inflight: CMD
Apr 15 21:56:50 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#23 CDB: opcode=0x28 28 00 04 b5 6c d8 00 04 00 00
Apr 15 21:56:50 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#22 uas_eh_abort_handler 0 uas-tag 1 inflight: CMD
Apr 15 21:56:50 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#22 CDB: opcode=0x28 28 00 04 b5 68 d8 00 04 00 00
Apr 15 21:56:50 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#21 uas_eh_abort_handler 0 uas-tag 4 inflight: CMD
Apr 15 21:56:50 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#21 CDB: opcode=0x28 28 00 04 b5 74 d8 00 04 00 00
Apr 15 21:56:50 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#20 uas_eh_abort_handler 0 uas-tag 3 inflight: CMD
Apr 15 21:56:50 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#20 CDB: opcode=0x28 28 00 04 b5 70 d8 00 04 00 00
Apr 15 21:56:50 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#26 uas_eh_abort_handler 0 uas-tag 7 inflight: CMD
Apr 15 21:56:50 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#26 CDB: opcode=0x28 28 00 04 b7 41 68 00 01 00 00
Apr 15 21:56:50 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#15 uas_eh_abort_handler 0 uas-tag 10 inflight: CMD
Apr 15 21:56:50 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#15 CDB: opcode=0x28 28 00 04 b7 12 70 00 01 00 00
Apr 15 21:56:50 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#14 uas_eh_abort_handler 0 uas-tag 8 inflight: CMD
Apr 15 21:56:50 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#14 CDB: opcode=0x28 28 00 04 b7 57 98 00 01 00 00
Apr 15 21:56:50 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#9 uas_eh_abort_handler 0 uas-tag 11 inflight: CMD
Apr 15 21:56:50 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#9 CDB: opcode=0x28 28 00 04 b7 87 68 00 01 00 00
Apr 15 21:56:50 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#8 uas_eh_abort_handler 0 uas-tag 9 inflight: CMD
Apr 15 21:56:50 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#8 CDB: opcode=0x28 28 00 04 b7 6f 80 00 01 00 00
Apr 15 21:56:50 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#29 uas_eh_abort_handler 0 uas-tag 13 inflight: CMD
Apr 15 21:56:50 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#29 CDB: opcode=0x28 28 00 04 b7 9e d0 00 01 00 00
Apr 15 21:56:50 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#12 uas_eh_abort_handler 0 uas-tag 14 inflight: CMD
Apr 15 21:56:50 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#12 CDB: opcode=0x28 28 00 04 b7 2a 30 00 01 00 00
Apr 15 21:56:50 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#10 uas_eh_abort_handler 0 uas-tag 12 inflight: CMD
Apr 15 21:56:50 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#10 CDB: opcode=0x28 28 00 04 b7 b6 60 00 01 00 00
Apr 15 21:56:50 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#13 uas_eh_abort_handler 0 uas-tag 15 inflight: CMD
Apr 15 21:56:50 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#13 CDB: opcode=0x28 28 00 04 b7 c6 88 00 01 00 00
Apr 15 21:56:50 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#11 uas_eh_abort_handler 0 uas-tag 16 inflight: CMD
Apr 15 21:56:50 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#11 CDB: opcode=0x28 28 00 04 b7 d7 18 00 01 00 00
Apr 15 21:56:50 raspberrypi kernel: scsi host2: uas_eh_device_reset_handler start
Apr 15 21:56:50 raspberrypi kernel: usb 2-1.2: reset SuperSpeed USB device number 36 using xhci-hcd
Apr 15 21:56:50 raspberrypi kernel: scsi host2: uas_eh_device_reset_handler success
Apr 15 21:57:22 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#29 uas_eh_abort_handler 0 uas-tag 6 inflight: CMD
Apr 15 21:57:22 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#29 CDB: opcode=0x28 28 00 04 b5 a5 b0 00 01 40 00
Apr 15 21:57:22 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#28 uas_eh_abort_handler 0 uas-tag 5 inflight: CMD
Apr 15 21:57:22 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#28 CDB: opcode=0x28 28 00 04 b5 a5 30 00 00 80 00
Apr 15 21:57:22 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#27 uas_eh_abort_handler 0 uas-tag 2 inflight: CMD
Apr 15 21:57:22 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#27 CDB: opcode=0x28 28 00 04 b5 99 30 00 04 00 00
Apr 15 21:57:22 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#26 uas_eh_abort_handler 0 uas-tag 1 inflight: CMD
Apr 15 21:57:22 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#26 CDB: opcode=0x28 28 00 04 b5 95 30 00 04 00 00
Apr 15 21:57:22 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#25 uas_eh_abort_handler 0 uas-tag 4 inflight: CMD
Apr 15 21:57:22 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#25 CDB: opcode=0x28 28 00 04 b5 a1 30 00 04 00 00
Apr 15 21:57:22 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#24 uas_eh_abort_handler 0 uas-tag 3 inflight: CMD
Apr 15 21:57:22 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#24 CDB: opcode=0x28 28 00 04 b5 9d 30 00 04 00 00
Apr 15 21:57:22 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#22 uas_eh_abort_handler 0 uas-tag 12 inflight: CMD
Apr 15 21:57:22 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#22 CDB: opcode=0x28 28 00 04 b5 fe a8 00 00 20 00
Apr 15 21:57:22 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#4 uas_eh_abort_handler 0 uas-tag 11 inflight: CMD
Apr 15 21:57:22 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#4 CDB: opcode=0x28 28 00 04 b5 fc a8 00 02 00 00
Apr 15 21:57:22 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#3 uas_eh_abort_handler 0 uas-tag 10 inflight: CMD
Apr 15 21:57:22 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#3 CDB: opcode=0x28 28 00 04 b5 f8 a8 00 04 00 00
Apr 15 21:57:22 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#2 uas_eh_abort_handler 0 uas-tag 9 inflight: CMD
Apr 15 21:57:22 raspberrypi kernel: sd 2:0:0:0: [sdb] tag#2 CDB: opcode=0x28 28 00 04 b5 f4 a8 00 04 00 00
...
Apr 15 22:29:09 raspberrypi kernel: usb 2-1.2: stat urb: status -71
float32top commented 2 months ago

I'm getting the same issue here

[ 149.147364] sd 0:0:0:0: [sda] tag#28 uas_eh_abort_handler 0 uas-tag 12 inflight: CMD OUT [ 149.147372] sd 0:0:0:0: [sda] tag#28 CDB: opcode=0x2a 2a 00 3a 0e f9 08 00 00 08 00 [ 154.239493] xhci-hcd xhci-hcd.0: xHCI host not responding to stop endpoint command [ 154.259666] xhci-hcd xhci-hcd.0: xHCI host controller not responding, assume dead [ 154.259707] xhci-hcd xhci-hcd.0: HC died; cleaning up [ 154.259792] usb 2-1: cmd cmplt err -108

Bus 004 Device 003: ID 0bda:9210 Realtek Semiconductor Corp. RTL9210 M.2 NVME Adapter

Orico M.2 nvme SSD - usb 3 adapter Tried rpiupdate, rebooted, same issue, Tried on a second rpi5 , same issue.

Rpi5 8gb

j3ich0 commented 1 month ago

i've posted at https://forums.raspberrypi.com/viewtopic.php?p=2224397#p2224397 about this issue.

Pi5, 27w official Pi power supply, Sabrent USB to SATA adapter, 5TB Seagate HDD HDD is formatted to NTFS (don't judge) and shared out using Samba. Pi5 is essentially a NAS box. I have also made the Pi boot from USB stick. The USB HDD is connected to the 2nd blue USB3 port, and the USB boot disk is on the 2nd (lower) USB 2 port. The USB HDD is identified as sda to the system, and the usb stick is sdb. This replaced a previous Pi4 setup with the same HDD. Only change is the PI4 was replaced with a Pi5.

Issue: After some time left to idle, the HDD appears to spin down. (i have not been able to time it) when this happens, and you hit the share via SMB, the share takes a brief moment to respond. When the share will report as no items available. When you look at lsblk, the drive that was previously identified as sda, is now sdc. When this happens, dmesg says [60651.191211] usb 2-1: cmd cmplt err -71 [60656.378942] scsi host1: uas_pre_reset: timed out [60656.379169] sd 1:0:0:0: [sda] tag#1 uas_zap_pending 0 uas-tag 1 inflight: CMD [60656.379174] sd 1:0:0:0: [sda] tag#1 CDB: opcode=0x28 28 00 00 04 3a 64 00 00 04 00 [60656.379187] sd 1:0:0:0: [sda] tag#1 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=DRIVER_OK cmd_age=5s [60656.379191] sd 1:0:0:0: [sda] tag#1 CDB: opcode=0x28 28 00 00 04 3a 64 00 00 04 00 [60656.379193] I/O error, dev sda, sector 2216736 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 [60656.388475] Buffer I/O error on dev sda1, logical block 276836, async page read [60656.388485] Buffer I/O error on dev sda1, logical block 276837, async page read [60656.388487] Buffer I/O error on dev sda1, logical block 276838, async page read [60656.388490] Buffer I/O error on dev sda1, logical block 276839, async page read [60656.389441] Buffer I/O error on dev sda1, logical block 276836, async page read [60656.389448] Buffer I/O error on dev sda1, logical block 276837, async page read [60656.389451] Buffer I/O error on dev sda1, logical block 276838, async page read [60656.389453] Buffer I/O error on dev sda1, logical block 276839, async page read [60656.389524] Buffer I/O error on dev sda1, logical block 276920, async page read [60656.389528] Buffer I/O error on dev sda1, logical block 276921, async page read [60656.435027] sd 1:0:0:0: [sda] Synchronizing SCSI cache [60656.674944] sd 1:0:0:0: [sda] Synchronize Cache(10) failed: Result: hostbyte=0x07 driverbyte=DRIVER_OK [60656.675300] usb 2-1: Disable of device-initiated U1 failed. [60656.675314] usb 2-1: Disable of device-initiated U2 failed. [60656.675678] usb 2-1: Enable of device-initiated U1 failed. [60656.675899] usb 2-1: Enable of device-initiated U2 failed. [60656.875059] usb 2-1: reset SuperSpeed USB device number 2 using xhci-hcd [60656.901913] scsi host2: uas [60656.903389] scsi 2:0:0:0: Direct-Access SABRENT 0204 PQ: 0 ANSI: 6 [60656.907585] sd 2:0:0:0: Attached scsi generic sg0 type 0 [60661.612014] sd 2:0:0:0: [sdc] 1220942646 4096-byte logical blocks: (5.00 TB/4.55 TiB) [60661.612197] sd 2:0:0:0: [sdc] Write Protect is off [60661.612200] sd 2:0:0:0: [sdc] Mode Sense: 53 00 00 08 [60661.612516] sd 2:0:0:0: [sdc] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA [60661.612709] sd 2:0:0:0: [sdc] Preferred minimum I/O size 4096 bytes [60661.612711] sd 2:0:0:0: [sdc] Optimal transfer size 33550336 bytes [60661.616025] sdc: sdc1 [60661.616145] sd 2:0:0:0: [sdc] Attached SCSI disk I've updated and rebooted several times, save for one instance where it seem to work for no reason (also documented in the thread above), it has consistently done this. The Pi4 did not do this before. Also note that if i leave it after it changed the hdd to sdc, and let the HDD idle again, and ping the share, it would do the same thing, and reconnect as sdd, e, f etc....

this is super frustrating. Some folks have recommended turning off UAS. not sure if this is the issue.

please look into this!!

masseselsev commented 4 days ago

Same here with RPI5\8 with original 27W PSU and a Crucial 1TB SSD in some default UBS 3.0 case on a 3.0 port of the rpi.

Reboot helps for some hours, and then again i get same errors as in the original bug. Please address the issue asap

[82749.494239] usb 2-1: cmd cmplt err -71 [82754.788343] scsi host0: uas_pre_reset: timed out [82754.788579] sd 0:0:0:0: [sda] tag#11 uas_zap_pending 0 uas-tag 1 inflight: CMD [82754.788584] sd 0:0:0:0: [sda] tag#11 CDB: opcode=0x28 28 00 00 01 2d 08 00 00 08 00 [82754.788599] sd 0:0:0:0: [sda] tag#11 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=DRIVER_OK cmd_age=5s [82754.788610] sd 0:0:0:0: [sda] tag#11 CDB: opcode=0x28 28 00 00 01 2d 08 00 00 08 00 [82754.788614] I/O error, dev sda, sector 77064 op 0x0:(READ) flags 0x3000 phys_seg 1 prio class 2 [82754.788667] EXT4-fs error (device sda1): __ext4_find_entry:1682: inode #2: comm smbd: reading directory lblock 0 [82754.799117] device offline error, dev sda, sector 935617952 op 0x1:(WRITE) flags 0x9800 phys_seg 2 prio class 2 [82754.799130] Aborting journal on device sda1-8. [82754.799140] device offline error, dev sda, sector 935593984 op 0x1:(WRITE) flags 0x9800 phys_seg 1 prio class 2 [82754.799145] device offline error, dev sda, sector 935593984 op 0x1:(WRITE) flags 0x9800 phys_seg 1 prio class 2 [82754.799148] Buffer I/O error on dev sda1, logical block 116948992, lost sync page write [82754.799156] JBD2: I/O error when updating journal superblock for sda1-8. [82754.799225] EXT4-fs (sda1): shut down requested (2) [82754.868355] sd 0:0:0:0: [sda] Synchronizing SCSI cache [82755.108377] sd 0:0:0:0: [sda] Synchronize Cache(10) failed: Result: hostbyte=0x07 driverbyte=DRIVER_OK [82755.108656] usb 2-1: Disable of device-initiated U1 failed. [82755.108688] usb 2-1: Disable of device-initiated U2 failed. [82755.109036] usb 2-1: Enable of device-initiated U1 failed. [82755.109142] usb 2-1: Enable of device-initiated U2 failed. [82755.304479] usb 2-1: reset SuperSpeed USB device number 2 using xhci-hcd [82755.331487] scsi host1: uas [82755.332078] scsi 1:0:0:0: Direct-Access DM HD001 8101 PQ: 0 ANSI: 6 [82755.335347] sd 1:0:0:0: Attached scsi generic sg0 type 0 [82755.826652] sd 1:0:0:0: [sdb] 1875385008 512-byte logical blocks: (960 GB/894 GiB) [82755.826660] sd 1:0:0:0: [sdb] 4096-byte physical blocks [82755.826855] sd 1:0:0:0: [sdb] Write Protect is off [82755.826859] sd 1:0:0:0: [sdb] Mode Sense: 53 00 00 08 [82755.827196] sd 1:0:0:0: [sdb] Disabling FUA [82755.827200] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA [82755.827400] sd 1:0:0:0: [sdb] Preferred minimum I/O size 4096 bytes [82755.827404] sd 1:0:0:0: [sdb] Optimal transfer size 33553920 bytes not a multiple of preferred minimum block size (4096 bytes) [82755.831323] sdb: sdb1 [82755.831463] sd 1:0:0:0: [sdb] Attached SCSI disk [89757.283149] macb 1f00100000.ethernet eth0: Link is Down [89767.523635] macb 1f00100000.ethernet eth0: Link is Up - 1Gbps/Full - flow control tx [89800.290956] macb 1f00100000.ethernet eth0: Link is Down [89803.363465] macb 1f00100000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off [169292.476517] EXT4-fs warning (device sda1): htree_dirblock_to_tree:1082: inode #2: lblock 0: comm smbd: error -5 reading directory block [176460.663658] EXT4-fs (sda1): error count since last fsck: 1 [176460.663669] EXT4-fs (sda1): initial error at time 1719834514: __ext4_find_entry:1682: inode 2 [176460.663678] EXT4-fs (sda1): last error at time 1719834514: __ext4_find_entry:1682: inode 2 [267825.571710] EXT4-fs warning (device sda1): htree_dirblock_to_tree:1082: inode #2: lblock 0: comm smbd: error -5 reading directory block [267830.275901] EXT4-fs warning (device sda1): htree_dirblock_to_tree:1082: inode #2: lblock 0: comm smbd: error -5 reading directory block [267836.333662] EXT4-fs warning (device sda1): htree_dirblock_to_tree:1082: inode #2: lblock 0: comm smbd: error -5 reading directory block [267861.592015] EXT4-fs warning (device sda1): htree_dirblock_to_tree:1082: inode #2: lblock 0: comm smbd: error -5 reading directory block