SATA 1 AND 4 of Quad SATA HAT not working

#1

Hi all

I have one Quad SATA connected to Raspberry Pi4 4GB and I’m having some problems with it.
When I try to format the disk, if connected to the Sata1 or Sata4 port I always get errors and I’m unable to format the disk. If I connect the disk to the Sata2 or Sata3 port everything works fine.

I already changed the Raspberry Pi and the problem is the same. I also updated the firmware but it had no effect.

I’m powering the Sata Hat + Raspberry Pi by ATX Power supply (Hat 12v connector), 2TB WD HDD

Any ideas or suggestions?

Thanks,
Best regards

#2

This is somehow magic :wink:
It would be interesting to know the version of the hat (does it have a 12V plug and converter to 5V?).

Maybe it would help finding the error when you attach 4 disks simultaneously.
Maybe you could try with a different hat?

#3

Hi Lin,

The Hat is the v1.2, it has the 12V plug.

I will try it with 4 HDD, they will be of different sizes. I’m waiting for a new 2TB HDD to perform the test.
Unfortunately, I don’t have any other hat to test.

Thanks,
Best Regards,

#4

I’d suggest that you also try applying power at the 12V plug because my measurements showed that ATX connection looks suspicious.
You also could try 3 disks because this also sheds some light on the problem.

#5

Hi Lin,

OK, sorry I didn’t understand that the first time. Yes, it has the 12V DC plug. I haven’t tested with it yet.
I will try to get a 12V Power supply to test it.

Thanks,
Best Regards,

#6

Hi Lin,

Just want to give an update on this issue.
I tested with the 12VDC power supply and it improved the performance of the system, now only SATA 1 port is giving errors.
I think this might be a power issue, the test that I run was with a 12VCD 2A Power supply, I know that the recommended is 5A but I didn’t get one yet.

Also, I tested with 3 x 3.5 HDD (500GB, 1TB, 2TB) on SATA ports 2, 3, and 4, and the system is working fine and stable. If I connect any of these HDDs to the SATA 1 port, it starts to give errors.

I will try to get a 5A 12VCD Power Supply to run the tests again and I will post some updates on this matter.

Thanks,
Best regards,
Happy New Year to everybody

#7

@gerapam
Although many publications cry for high current, I do not think that the problem you encounter is due to that issue.
If the same 3 disks work on all ports except #1 there must be a problem with that port.

You might also try each of the 3 disks alone on port #1. That way current is limited and most probably the problem will still occur.

It might help if you post the detailed messages (from dmesg) from the moment a disk gets connected to #1.
IMHO the most probable culprit is the USB-SATA bridge chip. If this one shows glitches you will need a replacement board of the sata-hat board.

#8

Hi Lin,

Yes, the is not power, I tried with a 12VCD 5A Power supply and the problem remains.

This is the dmesg details right after the system starts up with 2TB HDD:

[ 0.000000] Booting Linux on physical CPU 0x0
[ 0.000000] Linux version 5.4.79-v7l+ (dom@buildbot) (gcc version 8.4.0 (Ubuntu/Linaro 8.4.0-3ubuntu1)) #1373 SMP Mon Nov 23 13:27:40 GMT 2020
[ 0.000000] CPU: ARMv7 Processor [410fd083] revision 3 (ARMv7), cr=30c5383d
[ 0.000000] CPU: div instructions available: patching division code
[ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache
[ 0.000000] OF: fdt: Machine model: Raspberry Pi 4 Model B Rev 1.2
[ 0.000000] Memory policy: Data cache writealloc
[ 0.000000] Reserved memory: created CMA memory pool at 0x000000001ec00000, size 256 MiB
[ 0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool
[ 0.000000] On node 0 totalpages: 1012736
[ 0.000000] DMA zone: 2304 pages used for memmap
[ 0.000000] DMA zone: 0 pages reserved
[ 0.000000] DMA zone: 196608 pages, LIFO batch:63
[ 0.000000] HighMem zone: 816128 pages, LIFO batch:63
[ 0.000000] percpu: Embedded 20 pages/cpu s49804 r8192 d23924 u81920
[ 0.000000] pcpu-alloc: s49804 r8192 d23924 u81920 alloc=20*4096
[ 0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
[ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1010432
[ 0.000000] Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 video=HDMI-A-1:1280x720M@60 smsc95xx.macaddr=DC:A6:32:7D:9A:04 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 console=ttyS0,115200 console=tty1 root=PARTUUID=d93ce22a-02 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait quiet splash plymouth.ignore-serial-consoles
[ 0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear)
[ 0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear)
[ 0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
[ 0.000000] software IO TLB: mapped [mem 0x17cc0000-0x1bcc0000] (64MB)
[ 0.000000] Memory: 3655600K/4050944K available (10240K kernel code, 739K rwdata, 2816K rodata, 2048K init, 854K bss, 133200K reserved, 262144K cma-reserved, 3264512K highmem)
[ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[ 0.000000] ftrace: allocating 31111 entries in 61 pages
[ 0.000000] rcu: Hierarchical RCU implementation.
[ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies.
[ 0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
[ 0.000000] GIC: Using split EOI/Deactivate mode
[ 0.000000] random: get_random_bytes called from start_kernel+0x344/0x518 with crng_init=0
[ 0.000008] sched_clock: 32 bits at 1000kHz, resolution 1000ns, wraps every 2147483647500ns
[ 0.000023] clocksource: timer: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275 ns
[ 0.000100] bcm2835: system timer (irq = 17)
[ 0.000749] arch_timer: cp15 timer(s) running at 54.00MHz (phys).
[ 0.000764] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns
[ 0.000781] sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns
[ 0.000794] Switching to timer-based delay loop, resolution 18ns
[ 0.001036] Console: colour dummy device 80x30
[ 0.001069] printk: console [tty1] enabled
[ 0.001122] Calibrating delay loop (skipped), value calculated using timer frequency… 108.00 BogoMIPS (lpj=540000)
[ 0.001143] pid_max: default: 32768 minimum: 301
[ 0.001292] LSM: Security Framework initializing
[ 0.001485] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
[ 0.001517] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
[ 0.002869] Disabling memory control group subsystem
[ 0.002993] CPU: Testing write buffer coherency: ok
[ 0.003506] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[ 0.004427] Setting up static identity map for 0x200000 - 0x20003c
[ 0.004633] rcu: Hierarchical SRCU implementation.
[ 0.005325] smp: Bringing up secondary CPUs …
[ 0.006527] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[ 0.007873] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
[ 0.009158] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
[ 0.009314] smp: Brought up 1 node, 4 CPUs
[ 0.009330] SMP: Total of 4 processors activated (432.00 BogoMIPS).
[ 0.009343] CPU: All CPU(s) started in HYP mode.
[ 0.009355] CPU: Virtualization extensions available.
[ 0.010186] devtmpfs: initialized
[ 0.024184] VFP support v0.3: implementor 41 architecture 3 part 40 variant 8 rev 0
[ 0.024463] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[ 0.024491] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
[ 0.031372] pinctrl core: initialized pinctrl subsystem
[ 0.032375] NET: Registered protocol family 16
[ 0.036353] DMA: preallocated 1024 KiB pool for atomic coherent allocations
[ 0.036950] audit: initializing netlink subsys (disabled)
[ 0.037205] audit: type=2000 audit(0.030:1): state=initialized audit_enabled=0 res=1
[ 0.038386] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[ 0.038400] hw-breakpoint: maximum watchpoint size is 8 bytes.
[ 0.038731] Serial: AMBA PL011 UART driver
[ 0.042619] bcm2835-mbox fe00b880.mailbox: mailbox enabled
[ 0.060827] raspberrypi-firmware soc:firmware: Attached to firmware from 2020-11-30 22:12, variant start
[ 0.070842] raspberrypi-firmware soc:firmware: Firmware hash is ab1181cc0cb6df52bfae3b1d3fef0ce7c325166c
[ 0.126117] bcm2835-dma fe007000.dma: DMA legacy API manager, dmachans=0x1
[ 0.131283] vgaarb: loaded
[ 0.131756] SCSI subsystem initialized
[ 0.131983] usbcore: registered new interface driver usbfs
[ 0.132053] usbcore: registered new interface driver hub
[ 0.132177] usbcore: registered new device driver usb
[ 0.132516] usb_phy_generic phy: phy supply vcc not found, using dummy regulator
[ 0.134412] clocksource: Switched to clocksource arch_sys_counter
[ 0.894184] VFS: Disk quotas dquot_6.6.0
[ 0.894285] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[ 0.894507] FS-Cache: Loaded
[ 0.894708] CacheFiles: Loaded
[ 0.895653] simple-framebuffer 3e843000.framebuffer: framebuffer at 0x3e843000, 0x384000 bytes, mapped to 0x(ptrval)
[ 0.895673] simple-framebuffer 3e843000.framebuffer: format=a8r8g8b8, mode=1280x720x32, linelength=5120
[ 0.896124] Console: switching to colour frame buffer device 160x45
[ 0.900682] simple-framebuffer 3e843000.framebuffer: fb0: simplefb registered!
[ 0.911186] thermal_sys: Registered thermal governor ‘step_wise’
[ 0.911541] NET: Registered protocol family 2
[ 0.912281] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear)
[ 0.912313] TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear)
[ 0.912372] TCP bind hash table entries: 8192 (order: 4, 65536 bytes, linear)
[ 0.912441] TCP: Hash tables configured (established 8192 bind 8192)
[ 0.912599] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
[ 0.912631] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
[ 0.912893] NET: Registered protocol family 1
[ 0.913653] RPC: Registered named UNIX socket transport module.
[ 0.913666] RPC: Registered udp transport module.
[ 0.913679] RPC: Registered tcp transport module.
[ 0.913691] RPC: Registered tcp NFSv4.1 backchannel transport module.
[ 0.913713] PCI: CLS 0 bytes, default 64
[ 0.915761] hw perfevents: enabled with armv7_cortex_a15 PMU driver, 7 counters available
[ 0.918009] Initialise system trusted keyrings
[ 0.918253] workingset: timestamp_bits=14 max_order=20 bucket_order=6
[ 0.929159] FS-Cache: Netfs ‘nfs’ registered for caching
[ 0.929894] NFS: Registering the id_resolver key type
[ 0.929925] Key type id_resolver registered
[ 0.929939] Key type id_legacy registered
[ 0.929962] nfs4filelayout_init: NFSv4 File Layout Driver Registering…
[ 0.931128] Key type asymmetric registered
[ 0.931143] Asymmetric key parser ‘x509’ registered
[ 0.931336] bounce: pool size: 64 pages
[ 0.931390] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
[ 0.931617] io scheduler mq-deadline registered
[ 0.931632] io scheduler kyber registered
[ 0.935832] brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges:
[ 0.935856] brcm-pcie fd500000.pcie: No bus range found for /scb/pcie@7d500000, using [bus 00-ff]
[ 0.935929] brcm-pcie fd500000.pcie: MEM 0x0600000000…0x0603ffffff -> 0x00f8000000
[ 0.936000] brcm-pcie fd500000.pcie: IB MEM 0x0000000000…0x00bfffffff -> 0x0000000000
[ 0.996534] brcm-pcie fd500000.pcie: link up, 5 GT/s x1 (SSC)
[ 0.996860] brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00
[ 0.996880] pci_bus 0000:00: root bus resource [bus 00-ff]
[ 0.996901] pci_bus 0000:00: root bus resource [mem 0x600000000-0x603ffffff] (bus address [0xf8000000-0xfbffffff])
[ 0.996960] pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400
[ 0.997232] pci 0000:00:00.0: PME# supported from D0 D3hot
[ 1.000588] PCI: bus0: Fast back to back transfers disabled
[ 1.000817] pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330
[ 1.000965] pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit]
[ 1.001398] pci 0000:01:00.0: PME# supported from D0 D3cold
[ 1.004792] PCI: bus1: Fast back to back transfers disabled
[ 1.004852] pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff]
[ 1.004876] pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit]
[ 1.004959] pci 0000:00:00.0: PCI bridge to [bus 01]
[ 1.004984] pci 0000:00:00.0: bridge window [mem 0x600000000-0x6000fffff]
[ 1.005302] pcieport 0000:00:00.0: enabling device (0140 -> 0142)
[ 1.005543] pcieport 0000:00:00.0: PME: Signaling with IRQ 56
[ 1.005945] pcieport 0000:00:00.0: AER: enabled with IRQ 56
[ 1.006301] pci 0000:01:00.0: enabling device (0140 -> 0142)
[ 1.013627] iproc-rng200 fe104000.rng: hwrng registered
[ 1.013977] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
[ 1.014737] vc-sm: Videocore shared memory driver
[ 1.015341] gpiomem-bcm2835 fe200000.gpiomem: Initialised: Registers at 0xfe200000
[ 1.027712] brd: module loaded
[ 1.040313] loop: module loaded
[ 1.041782] Loading iSCSI transport class v2.0-870.
[ 1.043884] libphy: Fixed MDIO Bus: probed
[ 1.044592] bcmgenet fd580000.ethernet: failed to get enet clock
[ 1.044613] bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000
[ 1.044636] bcmgenet fd580000.ethernet: failed to get enet-wol clock
[ 1.044658] bcmgenet fd580000.ethernet: failed to get enet-eee clock
[ 1.044686] bcmgenet: Skipping UMAC reset
[ 1.064494] libphy: bcmgenet MII bus: probed
[ 1.144531] unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus
[ 1.145573] usbcore: registered new interface driver r8152
[ 1.145655] usbcore: registered new interface driver lan78xx
[ 1.145726] usbcore: registered new interface driver smsc95xx
[ 1.146157] xhci_hcd 0000:01:00.0: xHCI Host Controller
[ 1.146192] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1
[ 1.148661] xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x0000003000000890
[ 1.149968] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.04
[ 1.149986] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 1.150002] usb usb1: Product: xHCI Host Controller
[ 1.150017] usb usb1: Manufacturer: Linux 5.4.79-v7l+ xhci-hcd
[ 1.150032] usb usb1: SerialNumber: 0000:01:00.0
[ 1.150665] hub 1-0:1.0: USB hub found
[ 1.150762] hub 1-0:1.0: 1 port detected
[ 1.151334] xhci_hcd 0000:01:00.0: xHCI Host Controller
[ 1.151361] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2
[ 1.151384] xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed
[ 1.151917] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.04
[ 1.151934] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 1.151950] usb usb2: Product: xHCI Host Controller
[ 1.151965] usb usb2: Manufacturer: Linux 5.4.79-v7l+ xhci-hcd
[ 1.151979] usb usb2: SerialNumber: 0000:01:00.0
[ 1.152562] hub 2-0:1.0: USB hub found
[ 1.152644] hub 2-0:1.0: 4 ports detected
[ 1.154193] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[ 1.154553] dwc_otg: FIQ enabled
[ 1.154565] dwc_otg: NAK holdoff enabled
[ 1.154578] dwc_otg: FIQ split-transaction FSM enabled
[ 1.154593] Module dwc_common_port init
[ 1.155044] usbcore: registered new interface driver uas
[ 1.155149] usbcore: registered new interface driver usb-storage
[ 1.155361] mousedev: PS/2 mouse device common for all mice
[ 1.157311] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
[ 1.160895] sdhci: Secure Digital Host Controller Interface driver
[ 1.160908] sdhci: Copyright© Pierre Ossman
[ 1.161552] mmc-bcm2835 fe300000.mmcnr: could not get clk, deferring probe
[ 1.162207] sdhci-pltfm: SDHCI platform and OF driver helper
[ 1.166096] ledtrig-cpu: registered to indicate activity on CPUs
[ 1.166392] hidraw: raw HID events driver © Jiri Kosina
[ 1.166611] usbcore: registered new interface driver usbhid
[ 1.166623] usbhid: USB HID core driver
[ 1.167707] vchiq: vchiq_init_state: slot_zero = (ptrval)
[ 1.169718] [vc_sm_connected_init]: start
[ 1.182501] [vc_sm_connected_init]: end - returning 0
[ 1.184759] Initializing XFRM netlink socket
[ 1.184798] NET: Registered protocol family 17
[ 1.184912] Key type dns_resolver registered
[ 1.185351] Registering SWP/SWPB emulation handler
[ 1.185712] registered taskstats version 1
[ 1.185732] Loading compiled-in X.509 certificates
[ 1.186243] Key type ._fscrypt registered
[ 1.186257] Key type .fscrypt registered
[ 1.197104] uart-pl011 fe201000.serial: cts_event_workaround enabled
[ 1.197181] fe201000.serial: ttyAMA0 at MMIO 0xfe201000 (irq = 29, base_baud = 0) is a PL011 rev2
[ 1.203339] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
[ 1.204354] mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0
[ 1.204370] mmc-bcm2835 fe300000.mmcnr: DMA channel allocated
[ 1.257263] mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
[ 1.258941] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[ 1.260618] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[ 1.263621] mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
[ 1.265313] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[ 1.271663] mmc0: SDHCI controller on fe340000.emmc2 [fe340000.emmc2] using ADMA
[ 1.274700] of_cfs_init
[ 1.274843] of_cfs_init: OK
[ 1.276018] Waiting for root device PARTUUID=d93ce22a-02…
[ 1.312036] random: fast init done
[ 1.334752] mmc1: new high speed SDIO card at address 0001
[ 1.384511] mmc0: new ultra high speed DDR50 SDHC card at address 0001
[ 1.385462] mmcblk0: mmc0:0001 00000 29.8 GiB
[ 1.388237] mmcblk0: p1 p2
[ 1.403101] EXT4-fs (mmcblk0p2): INFO: recovery required on readonly filesystem
[ 1.403117] EXT4-fs (mmcblk0p2): write access will be enabled during recovery
[ 1.524524] usb 1-1: new high-speed USB device number 2 using xhci_hcd
[ 1.707105] usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21
[ 1.707124] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[ 1.707141] usb 1-1: Product: USB2.0 Hub
[ 1.709100] hub 1-1:1.0: USB hub found
[ 1.709407] hub 1-1:1.0: 4 ports detected
[ 2.249858] EXT4-fs (mmcblk0p2): recovery complete
[ 2.259993] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
[ 2.260069] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
[ 2.261684] devtmpfs: mounted
[ 2.270350] Freeing unused kernel memory: 2048K
[ 2.270647] Run /sbin/init as init process
[ 2.897301] systemd[1]: System time before build time, advancing clock.
[ 3.034775] NET: Registered protocol family 10
[ 3.036164] Segment Routing with IPv6
[ 3.084761] systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid)
[ 3.085545] systemd[1]: Detected architecture arm.
[ 3.138958] systemd[1]: Set hostname to .
[ 3.864560] random: lvmconfig: uninitialized urandom read (4 bytes read)
[ 4.487555] systemd[1]: /lib/systemd/system/rpc-statd.service:13: PIDFile= references path below legacy directory /var/run/, updating /var/run/rpc.statd.pid → /run/rpc.statd.pid; please update the unit file accordingly.
[ 4.812179] random: systemd: uninitialized urandom read (16 bytes read)
[ 4.839506] random: systemd: uninitialized urandom read (16 bytes read)
[ 4.840230] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[ 4.840862] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[ 4.841346] systemd[1]: Listening on Device-mapper event daemon FIFOs.
[ 4.841490] systemd[1]: Reached target System Time Synchronized.
[ 4.842215] systemd[1]: Listening on fsck to fsckd communication Socket.
[ 4.843002] systemd[1]: Listening on Journal Socket (/dev/log).
[ 5.075382] i2c /dev entries driver
[ 5.146362] Installing knfsd (copyright © 1996 okir@monad.swb.de).
[ 5.679122] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
[ 5.784590] systemd-journald[118]: Received request to flush runtime journal from PID 1
[ 6.643139] rpivid-mem feb00000.hevc-decoder: rpivid-hevcmem initialised: Registers at 0xfeb00000 length 0x00010000
[ 6.647475] Driver for 1-wire Dallas network protocol.
[ 6.655257] rpivid-mem feb10000.rpivid-local-intc: rpivid-intcmem initialised: Registers at 0xfeb10000 length 0x00001000
[ 6.657889] rpivid-mem feb20000.h264-decoder: rpivid-h264mem initialised: Registers at 0xfeb20000 length 0x00010000
[ 6.662245] rpivid-mem feb30000.vp9-decoder: rpivid-vp9mem initialised: Registers at 0xfeb30000 length 0x00010000
[ 6.681162] gpio-4 (onewire@0): enforced open drain please flag it properly in DT/ACPI DSDT/board file
[ 6.726093] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
[ 6.729041] bcm2835_vc_sm_cma_probe: Videocore shared memory driver
[ 6.729063] [vc_sm_connected_init]: start
[ 6.751683] [vc_sm_connected_init]: installed successfully
[ 6.768539] mc: Linux media interface: v0.10
[ 6.810306] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
[ 6.851487] videodev: Linux video capture interface: v2.00
[ 6.862199] bcm2835_audio bcm2835_audio: card created with 4 channels
[ 6.939841] bcm2835_audio bcm2835_audio: card created with 4 channels
[ 6.942421] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[ 6.951336] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[ 6.993344] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
[ 6.995841] bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned.
[ 7.034591] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13
[ 7.045807] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14
[ 7.050317] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15
[ 7.050836] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16
[ 7.050865] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
[ 7.050908] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
[ 7.050928] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
[ 7.050949] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
[ 7.051470] bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp
[ 7.165206] [drm] Initialized v3d 1.0.0 20180419 for fec00000.v3d on minor 0
[ 7.174666] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
[ 7.242300] bcm2835-codec bcm2835-codec: Device registered as /dev/video10
[ 7.242343] bcm2835-codec bcm2835-codec: Loaded V4L2 decode
[ 7.242443] vc4-drm gpu: bound fe600000.firmwarekms (ops vc4_fkms_ops [vc4])
[ 7.242466] checking generic (3e843000 384000) vs hw (0 ffffffffffffffff)
[ 7.242480] fb0: switching to vc4drmfb from simple
[ 7.243504] Console: switching to colour dummy device 80x30
[ 7.243747] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
[ 7.243761] [drm] No driver support for vblank timestamp query.
[ 7.243774] [drm] Setting vblank_disable_immediate to false because get_vblank_timestamp == NULL
[ 7.252381] [drm] Initialized vc4 0.0.0 20140616 for gpu on minor 1
[ 7.253008] bcm2835-codec bcm2835-codec: Device registered as /dev/video11
[ 7.253049] bcm2835-codec bcm2835-codec: Loaded V4L2 encode
[ 7.260694] vc4-drm gpu: HDMI-A-1: EDID is invalid:
[ 7.260715] [00] ZERO 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 7.260728] [00] ZERO 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 7.260749] [00] ZERO 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 7.260763] [00] ZERO 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 7.260776] [00] ZERO 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 7.260788] [00] ZERO 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 7.260801] [00] ZERO 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 7.260815] [00] ZERO 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 7.262377] bcm2835-codec bcm2835-codec: Device registered as /dev/video12
[ 7.262428] bcm2835-codec bcm2835-codec: Loaded V4L2 isp
[ 7.379371] bcmgenet: Skipping UMAC reset
[ 7.382204] bcmgenet fd580000.ethernet: configuring instance for external RGMII
[ 7.382677] bcmgenet fd580000.ethernet eth0: Link is Down
[ 7.422910] cfg80211: Loading compiled-in X.509 certificates for regulatory database
[ 7.461424] Console: switching to colour frame buffer device 160x45
[ 7.482727] vc4-drm gpu: fb0: vc4drmfb frame buffer device
[ 7.611823] cfg80211: Loaded X.509 cert ‘sforshee: 00b28ddf47aef9cea7’
[ 7.704822] brcmfmac: F1 signature read @0x18000000=0x15264345
[ 7.720910] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[ 7.721618] usbcore: registered new interface driver brcmfmac
[ 7.749188] brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43455-sdio.raspberrypi,4-model-b.txt failed with error -2
[ 7.993543] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[ 8.005349] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Mar 23 2020 02:19:54 version 7.45.206 (r725000 CY) FWID 01-88ee44ea
[ 10.474735] bcmgenet fd580000.ethernet eth0: Link is Up - 100Mbps/Full - flow control rx/tx
[ 10.474792] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[ 12.556930] uart-pl011 fe201000.serial: no DMA platform data
[ 13.165022] Adding 102396k swap on /var/swap. Priority:-2 extents:1 across:102396k SSFS
[ 14.145171] random: crng init done
[ 14.145186] random: 7 urandom warning(s) missed due to ratelimiting
[ 15.326375] NFSD: Using UMH upcall client tracking operations.
[ 15.326396] NFSD: starting 90-second grace period (net f0000049)
[ 19.515808] Bluetooth: Core ver 2.22
[ 19.515906] NET: Registered protocol family 31
[ 19.515920] Bluetooth: HCI device and connection manager initialized
[ 19.515949] Bluetooth: HCI socket layer initialized
[ 19.515970] Bluetooth: L2CAP socket layer initialized
[ 19.516004] Bluetooth: SCO socket layer initialized
[ 19.534689] Bluetooth: HCI UART driver ver 2.3
[ 19.534710] Bluetooth: HCI UART protocol H4 registered
[ 19.534814] Bluetooth: HCI UART protocol Three-wire (H5) registered
[ 19.535142] Bluetooth: HCI UART protocol Broadcom registered
[ 19.934447] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[ 19.934534] Bluetooth: BNEP filters: protocol multicast
[ 19.934568] Bluetooth: BNEP socket layer initialized
[ 20.941489] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
[ 20.948924] Bridge firewalling registered
[ 24.375009] usb 2-2: new SuperSpeed Gen 1 USB device number 2 using xhci_hcd
[ 24.406677] usb 2-2: New USB device found, idVendor=1058, idProduct=0a10, bcdDevice=81.36
[ 24.406702] usb 2-2: New USB device strings: Mfr=1, Product=2, SerialNumber=5
[ 24.406720] usb 2-2: Product: JMS56x Series
[ 24.406736] usb 2-2: Manufacturer: JMicron
[ 24.406752] usb 2-2: SerialNumber: 1234567890123
[ 24.436041] scsi host0: uas
[ 24.437910] scsi 0:0:0:0: Direct-Access WDC WD20 PURZ-85AKKY0 8136 PQ: 0 ANSI: 6
[ 24.440940] sd 0:0:0:0: [sda] 3907029168 512-byte logical blocks: (2.00 TB/1.82 TiB)
[ 24.441253] sd 0:0:0:0: [sda] Write Protect is off
[ 24.441272] sd 0:0:0:0: [sda] Mode Sense: 67 00 10 08
[ 24.441816] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, supports DPO and FUA
[ 24.442736] sd 0:0:0:0: [sda] Optimal transfer size 33553920 bytes
[ 24.569041] sda: sda1
[ 24.572966] sd 0:0:0:0: [sda] Attached SCSI disk
[ 24.668060] sd 0:0:0:0: Attached scsi generic sg0 type 0
[ 24.699170] docker0: port 1(veth99d2ace) entered blocking state
[ 24.699187] docker0: port 1(veth99d2ace) entered disabled state
[ 24.699609] device veth99d2ace entered promiscuous mode
[ 25.974341] warning: process `colord-sane’ used the deprecated sysctl system call with 8.1.2.
[ 26.987776] cgroup: cgroup: disabling cgroup2 socket matching due to net_prio or net_cls activation
[ 28.292428] eth0: renamed from vethefdcbd1
[ 28.337612] IPv6: ADDRCONF(NETDEV_CHANGE): veth99d2ace: link becomes ready
[ 28.337851] docker0: port 1(veth99d2ace) entered blocking state
[ 28.337866] docker0: port 1(veth99d2ace) entered forwarding state
[ 28.338178] IPv6: ADDRCONF(NETDEV_CHANGE): docker0: link becomes ready
[ 30.382448] EXT4-fs (sda1): mounted filesystem with ordered data mode. Opts: user_xattr,usrjquota=aquota.user,grpjquota=aquota.group,jqfmt=vfsv0,acl
[ 30.707992] EXT4-fs (sda1): re-mounted. Opts: (null)
[ 30.766354] EXT4-fs (sda1): re-mounted. Opts: (null)
[ 33.512182] broken atomic modeset userspace detected, disabling atomic
[ 37.048406] fuse: init (API version 7.31)
[ 39.440220] Bluetooth: RFCOMM TTY layer initialized
[ 39.440236] Bluetooth: RFCOMM socket layer initialized
[ 39.440253] Bluetooth: RFCOMM ver 1.11
[ 68.974899] sd 0:0:0:0: [sda] tag#7 uas_eh_abort_handler 0 uas-tag 4 inflight: CMD OUT
[ 68.974916] sd 0:0:0:0: [sda] tag#7 CDB: opcode=0x2a 2a 00 06 c0 15 00 00 04 00 00
[ 69.024913] scsi host0: uas_eh_device_reset_handler start
[ 69.175927] usb 2-2: reset SuperSpeed Gen 1 USB device number 2 using xhci_hcd
[ 69.209870] scsi host0: uas_eh_device_reset_handler success
[ 69.585776] xhci_hcd 0000:01:00.0: ERROR Transfer event for unknown stream ring slot 2 ep 7
[ 69.585791] xhci_hcd 0000:01:00.0: @000000001ed08ec0 00000000 00000000 05000000 02088001

I haven’t look into it to try to understand it.

Thanks for the help,
Best regards,

#9

@gerapam:
This dmesg looks healthy and shows that the required UAS driver has been loaded and detected a single disk WDC WD20 PURZ-85AKKY0 8136.
I suggest to place that disk in the slots SATA 1-4 and have a look at the additional dmesg generated and check if it can be used as expected.

#10

Hi Lin,

Here is dmesg after a few minutes working, still in SATA 1 and the same disk, this mesage is in two parts:

[ 0.000000] Booting Linux on physical CPU 0x0
[ 0.000000] Linux version 5.4.79-v7l+ (dom@buildbot) (gcc version 8.4.0 (Ubuntu/Linaro 8.4.0-3ubuntu1)) #1373 SMP Mon Nov 23 13:27:40 GMT 2020
[ 0.000000] CPU: ARMv7 Processor [410fd083] revision 3 (ARMv7), cr=30c5383d
[ 0.000000] CPU: div instructions available: patching division code
[ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache
[ 0.000000] OF: fdt: Machine model: Raspberry Pi 4 Model B Rev 1.2
[ 0.000000] Memory policy: Data cache writealloc
[ 0.000000] Reserved memory: created CMA memory pool at 0x000000001ec00000, size 256 MiB
[ 0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool
[ 0.000000] On node 0 totalpages: 1012736
[ 0.000000] DMA zone: 2304 pages used for memmap
[ 0.000000] DMA zone: 0 pages reserved
[ 0.000000] DMA zone: 196608 pages, LIFO batch:63
[ 0.000000] HighMem zone: 816128 pages, LIFO batch:63
[ 0.000000] percpu: Embedded 20 pages/cpu s49804 r8192 d23924 u81920
[ 0.000000] pcpu-alloc: s49804 r8192 d23924 u81920 alloc=20*4096
[ 0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
[ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1010432
[ 0.000000] Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 video=HDMI-A-1:1280x720M@60 smsc95xx.macaddr=DC:A6:32:7D:9A:04 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 console=ttyS0,115200 console=tty1 root=PARTUUID=d93ce22a-02 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait quiet splash plymouth.ignore-serial-consoles
[ 0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear)
[ 0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear)
[ 0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
[ 0.000000] software IO TLB: mapped [mem 0x17cc0000-0x1bcc0000] (64MB)
[ 0.000000] Memory: 3655600K/4050944K available (10240K kernel code, 739K rwdata, 2816K rodata, 2048K init, 854K bss, 133200K reserved, 262144K cma-reserved, 3264512K highmem)
[ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[ 0.000000] ftrace: allocating 31111 entries in 61 pages
[ 0.000000] rcu: Hierarchical RCU implementation.
[ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies.
[ 0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
[ 0.000000] GIC: Using split EOI/Deactivate mode
[ 0.000000] random: get_random_bytes called from start_kernel+0x344/0x518 with crng_init=0
[ 0.000008] sched_clock: 32 bits at 1000kHz, resolution 1000ns, wraps every 2147483647500ns
[ 0.000023] clocksource: timer: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275 ns
[ 0.000100] bcm2835: system timer (irq = 17)
[ 0.000751] arch_timer: cp15 timer(s) running at 54.00MHz (phys).
[ 0.000767] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns
[ 0.000783] sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns
[ 0.000796] Switching to timer-based delay loop, resolution 18ns
[ 0.001039] Console: colour dummy device 80x30
[ 0.001070] printk: console [tty1] enabled
[ 0.001122] Calibrating delay loop (skipped), value calculated using timer frequency… 108.00 BogoMIPS (lpj=540000)
[ 0.001144] pid_max: default: 32768 minimum: 301
[ 0.001293] LSM: Security Framework initializing
[ 0.001489] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
[ 0.001513] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
[ 0.002878] Disabling memory control group subsystem
[ 0.003001] CPU: Testing write buffer coherency: ok
[ 0.003510] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[ 0.004428] Setting up static identity map for 0x200000 - 0x20003c
[ 0.004634] rcu: Hierarchical SRCU implementation.
[ 0.005323] smp: Bringing up secondary CPUs …
[ 0.006532] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[ 0.007873] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
[ 0.009155] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
[ 0.009312] smp: Brought up 1 node, 4 CPUs
[ 0.009328] SMP: Total of 4 processors activated (432.00 BogoMIPS).
[ 0.009341] CPU: All CPU(s) started in HYP mode.
[ 0.009353] CPU: Virtualization extensions available.
[ 0.010171] devtmpfs: initialized
[ 0.024176] VFP support v0.3: implementor 41 architecture 3 part 40 variant 8 rev 0
[ 0.024451] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[ 0.024481] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
[ 0.031371] pinctrl core: initialized pinctrl subsystem
[ 0.032380] NET: Registered protocol family 16
[ 0.036363] DMA: preallocated 1024 KiB pool for atomic coherent allocations
[ 0.036961] audit: initializing netlink subsys (disabled)
[ 0.037217] audit: type=2000 audit(0.030:1): state=initialized audit_enabled=0 res=1
[ 0.038394] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[ 0.038409] hw-breakpoint: maximum watchpoint size is 8 bytes.
[ 0.038743] Serial: AMBA PL011 UART driver
[ 0.042648] bcm2835-mbox fe00b880.mailbox: mailbox enabled
[ 0.060827] raspberrypi-firmware soc:firmware: Attached to firmware from 2020-11-30 22:12, variant start
[ 0.070844] raspberrypi-firmware soc:firmware: Firmware hash is ab1181cc0cb6df52bfae3b1d3fef0ce7c325166c
[ 0.126008] bcm2835-dma fe007000.dma: DMA legacy API manager, dmachans=0x1
[ 0.131191] vgaarb: loaded
[ 0.131668] SCSI subsystem initialized
[ 0.131897] usbcore: registered new interface driver usbfs
[ 0.131968] usbcore: registered new interface driver hub
[ 0.132091] usbcore: registered new device driver usb
[ 0.132426] usb_phy_generic phy: phy supply vcc not found, using dummy regulator
[ 0.134332] clocksource: Switched to clocksource arch_sys_counter
[ 0.894510] VFS: Disk quotas dquot_6.6.0
[ 0.894611] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[ 0.894792] FS-Cache: Loaded
[ 0.894994] CacheFiles: Loaded
[ 0.895937] simple-framebuffer 3e843000.framebuffer: framebuffer at 0x3e843000, 0x384000 bytes, mapped to 0x(ptrval)
[ 0.895956] simple-framebuffer 3e843000.framebuffer: format=a8r8g8b8, mode=1280x720x32, linelength=5120
[ 0.896410] Console: switching to colour frame buffer device 160x45
[ 0.900971] simple-framebuffer 3e843000.framebuffer: fb0: simplefb registered!
[ 0.911462] thermal_sys: Registered thermal governor ‘step_wise’
[ 0.911818] NET: Registered protocol family 2
[ 0.912561] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear)
[ 0.912593] TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear)
[ 0.912651] TCP bind hash table entries: 8192 (order: 4, 65536 bytes, linear)
[ 0.912722] TCP: Hash tables configured (established 8192 bind 8192)
[ 0.912875] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
[ 0.912910] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
[ 0.913171] NET: Registered protocol family 1
[ 0.913936] RPC: Registered named UNIX socket transport module.
[ 0.913949] RPC: Registered udp transport module.
[ 0.913962] RPC: Registered tcp transport module.
[ 0.913974] RPC: Registered tcp NFSv4.1 backchannel transport module.
[ 0.913996] PCI: CLS 0 bytes, default 64
[ 0.916051] hw perfevents: enabled with armv7_cortex_a15 PMU driver, 7 counters available
[ 0.918302] Initialise system trusted keyrings
[ 0.918541] workingset: timestamp_bits=14 max_order=20 bucket_order=6
[ 0.929445] FS-Cache: Netfs ‘nfs’ registered for caching
[ 0.930189] NFS: Registering the id_resolver key type
[ 0.930223] Key type id_resolver registered
[ 0.930236] Key type id_legacy registered
[ 0.930259] nfs4filelayout_init: NFSv4 File Layout Driver Registering…
[ 0.931414] Key type asymmetric registered
[ 0.931428] Asymmetric key parser ‘x509’ registered
[ 0.931621] bounce: pool size: 64 pages
[ 0.931674] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
[ 0.931908] io scheduler mq-deadline registered
[ 0.931923] io scheduler kyber registered
[ 0.936111] brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges:
[ 0.936135] brcm-pcie fd500000.pcie: No bus range found for /scb/pcie@7d500000, using [bus 00-ff]
[ 0.936206] brcm-pcie fd500000.pcie: MEM 0x0600000000…0x0603ffffff -> 0x00f8000000
[ 0.936277] brcm-pcie fd500000.pcie: IB MEM 0x0000000000…0x00bfffffff -> 0x0000000000
[ 0.996452] brcm-pcie fd500000.pcie: link up, 5 GT/s x1 (SSC)
[ 0.996782] brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00
[ 0.996800] pci_bus 0000:00: root bus resource [bus 00-ff]
[ 0.996820] pci_bus 0000:00: root bus resource [mem 0x600000000-0x603ffffff] (bus address [0xf8000000-0xfbffffff])
[ 0.996879] pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400
[ 0.997154] pci 0000:00:00.0: PME# supported from D0 D3hot
[ 1.000490] PCI: bus0: Fast back to back transfers disabled
[ 1.000722] pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330
[ 1.000873] pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit]
[ 1.001306] pci 0000:01:00.0: PME# supported from D0 D3cold
[ 1.004684] PCI: bus1: Fast back to back transfers disabled
[ 1.004746] pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff]
[ 1.004770] pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit]
[ 1.004852] pci 0000:00:00.0: PCI bridge to [bus 01]
[ 1.004877] pci 0000:00:00.0: bridge window [mem 0x600000000-0x6000fffff]
[ 1.005195] pcieport 0000:00:00.0: enabling device (0140 -> 0142)
[ 1.005442] pcieport 0000:00:00.0: PME: Signaling with IRQ 56
[ 1.005844] pcieport 0000:00:00.0: AER: enabled with IRQ 56
[ 1.006198] pci 0000:01:00.0: enabling device (0140 -> 0142)
[ 1.013537] iproc-rng200 fe104000.rng: hwrng registered
[ 1.013888] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
[ 1.014634] vc-sm: Videocore shared memory driver
[ 1.015236] gpiomem-bcm2835 fe200000.gpiomem: Initialised: Registers at 0xfe200000
[ 1.027619] brd: module loaded
[ 1.040245] loop: module loaded
[ 1.041720] Loading iSCSI transport class v2.0-870.
[ 1.043825] libphy: Fixed MDIO Bus: probed
[ 1.044523] bcmgenet fd580000.ethernet: failed to get enet clock
[ 1.044543] bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000
[ 1.044565] bcmgenet fd580000.ethernet: failed to get enet-wol clock
[ 1.044587] bcmgenet fd580000.ethernet: failed to get enet-eee clock
[ 1.044615] bcmgenet: Skipping UMAC reset
[ 1.064414] libphy: bcmgenet MII bus: probed
[ 1.144450] unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus
[ 1.145493] usbcore: registered new interface driver r8152
[ 1.145577] usbcore: registered new interface driver lan78xx
[ 1.145649] usbcore: registered new interface driver smsc95xx
[ 1.146080] xhci_hcd 0000:01:00.0: xHCI Host Controller
[ 1.146116] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1
[ 1.148585] xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x0000003000000890
[ 1.149893] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.04
[ 1.149911] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 1.149926] usb usb1: Product: xHCI Host Controller
[ 1.149941] usb usb1: Manufacturer: Linux 5.4.79-v7l+ xhci-hcd
[ 1.149956] usb usb1: SerialNumber: 0000:01:00.0
[ 1.150591] hub 1-0:1.0: USB hub found
[ 1.150689] hub 1-0:1.0: 1 port detected
[ 1.151268] xhci_hcd 0000:01:00.0: xHCI Host Controller
[ 1.151293] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2
[ 1.151317] xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed
[ 1.151846] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.04
[ 1.151864] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 1.151879] usb usb2: Product: xHCI Host Controller
[ 1.151894] usb usb2: Manufacturer: Linux 5.4.79-v7l+ xhci-hcd
[ 1.151909] usb usb2: SerialNumber: 0000:01:00.0
[ 1.152490] hub 2-0:1.0: USB hub found
[ 1.152579] hub 2-0:1.0: 4 ports detected
[ 1.154127] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[ 1.154489] dwc_otg: FIQ enabled
[ 1.154502] dwc_otg: NAK holdoff enabled
[ 1.154515] dwc_otg: FIQ split-transaction FSM enabled
[ 1.154531] Module dwc_common_port init
[ 1.154982] usbcore: registered new interface driver uas
[ 1.155086] usbcore: registered new interface driver usb-storage
[ 1.155302] mousedev: PS/2 mouse device common for all mice
[ 1.157248] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
[ 1.160826] sdhci: Secure Digital Host Controller Interface driver
[ 1.160839] sdhci: Copyright© Pierre Ossman
[ 1.161485] mmc-bcm2835 fe300000.mmcnr: could not get clk, deferring probe
[ 1.162139] sdhci-pltfm: SDHCI platform and OF driver helper
[ 1.166028] ledtrig-cpu: registered to indicate activity on CPUs
[ 1.166325] hidraw: raw HID events driver © Jiri Kosina
[ 1.166547] usbcore: registered new interface driver usbhid
[ 1.166559] usbhid: USB HID core driver
[ 1.167637] vchiq: vchiq_init_state: slot_zero = (ptrval)
[ 1.169631] [vc_sm_connected_init]: start
[ 1.179096] [vc_sm_connected_init]: end - returning 0
[ 1.181310] Initializing XFRM netlink socket
[ 1.181349] NET: Registered protocol family 17
[ 1.181458] Key type dns_resolver registered
[ 1.181897] Registering SWP/SWPB emulation handler
[ 1.182251] registered taskstats version 1
[ 1.182271] Loading compiled-in X.509 certificates
[ 1.182788] Key type ._fscrypt registered
[ 1.182802] Key type .fscrypt registered
[ 1.194222] uart-pl011 fe201000.serial: cts_event_workaround enabled
[ 1.194300] fe201000.serial: ttyAMA0 at MMIO 0xfe201000 (irq = 29, base_baud = 0) is a PL011 rev2
[ 1.200509] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
[ 1.201520] mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0
[ 1.201536] mmc-bcm2835 fe300000.mmcnr: DMA channel allocated
[ 1.253212] mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
[ 1.255898] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[ 1.257575] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[ 1.260570] mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
[ 1.262242] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[ 1.268268] mmc0: SDHCI controller on fe340000.emmc2 [fe340000.emmc2] using ADMA
[ 1.271218] of_cfs_init
[ 1.271345] of_cfs_init: OK
[ 1.272258] Waiting for root device PARTUUID=d93ce22a-02…
[ 1.312264] random: fast init done
[ 1.334835] mmc1: new high speed SDIO card at address 0001
[ 1.394427] mmc0: new ultra high speed DDR50 SDHC card at address 0001
[ 1.395359] mmcblk0: mmc0:0001 00000 29.8 GiB
[ 1.398121] mmcblk0: p1 p2
[ 1.426158] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
[ 1.426233] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
[ 1.432865] devtmpfs: mounted
[ 1.441704] Freeing unused kernel memory: 2048K
[ 1.454650] Run /sbin/init as init process
[ 1.514389] usb 1-1: new high-speed USB device number 2 using xhci_hcd
[ 1.697360] usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21
[ 1.697379] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[ 1.697396] usb 1-1: Product: USB2.0 Hub
[ 1.699444] hub 1-1:1.0: USB hub found
[ 1.699746] hub 1-1:1.0: 4 ports detected
[ 2.079863] systemd[1]: System time before build time, advancing clock.
[ 2.217001] NET: Registered protocol family 10
[ 2.218360] Segment Routing with IPv6
[ 2.266607] systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid)
[ 2.267373] systemd[1]: Detected architecture arm.
[ 2.320113] systemd[1]: Set hostname to .
[ 3.052282] random: lvmconfig: uninitialized urandom read (4 bytes read)
[ 3.675605] systemd[1]: /lib/systemd/system/rpc-statd.service:13: PIDFile= references path below legacy directory /var/run/, updating /var/run/rpc.statd.pid → /run/rpc.statd.pid; please update the unit file accordingly.
[ 3.998012] random: systemd: uninitialized urandom read (16 bytes read)
[ 4.024704] random: systemd: uninitialized urandom read (16 bytes read)
[ 4.029145] systemd[1]: Created slice system-getty.slice.
[ 4.065610] systemd[1]: Listening on RPCbind Server Activation Socket.
[ 4.065926] urandom_read: 1 callbacks suppressed
[ 4.065934] random: systemd: uninitialized urandom read (16 bytes read)
[ 4.068250] systemd[1]: Created slice system-systemd\x2dfsck.slice.
[ 4.068787] random: systemd: uninitialized urandom read (16 bytes read)
[ 4.069476] systemd[1]: Listening on Journal Audit Socket.
[ 4.069584] random: systemd: uninitialized urandom read (16 bytes read)
[ 4.069982] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[ 4.070816] systemd[1]: Listening on LVM2 poll daemon socket.
[ 4.315624] i2c /dev entries driver
[ 4.315997] Installing knfsd (copyright © 1996 okir@monad.swb.de).
[ 4.860402] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
[ 4.965608] systemd-journald[125]: Received request to flush runtime journal from PID 1
[ 5.768551] rpivid-mem feb00000.hevc-decoder: rpivid-hevcmem initialised: Registers at 0xfeb00000 length 0x00010000
[ 5.769004] rpivid-mem feb10000.rpivid-local-intc: rpivid-intcmem initialised: Registers at 0xfeb10000 length 0x00001000
[ 5.769459] rpivid-mem feb20000.h264-decoder: rpivid-h264mem initialised: Registers at 0xfeb20000 length 0x00010000
[ 5.769905] rpivid-mem feb30000.vp9-decoder: rpivid-vp9mem initialised: Registers at 0xfeb30000 length 0x00010000
[ 5.775384] Driver for 1-wire Dallas network protocol.
[ 5.779960] gpio-4 (onewire@0): enforced open drain please flag it properly in DT/ACPI DSDT/board file
[ 5.850546] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
[ 5.852705] bcm2835_vc_sm_cma_probe: Videocore shared memory driver
[ 5.852727] [vc_sm_connected_init]: start
[ 5.856144] [vc_sm_connected_init]: installed successfully
[ 5.874074] mc: Linux media interface: v0.10
[ 5.918546] videodev: Linux video capture interface: v2.00
[ 5.967370] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
[ 6.000079] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[ 6.002113] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[ 6.021703] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
[ 6.025882] bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned.
[ 6.041231] bcm2835_audio bcm2835_audio: card created with 4 channels
[ 6.046799] bcm2835_audio bcm2835_audio: card created with 4 channels
[ 6.060615] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
[ 6.093266] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13
[ 6.093846] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14
[ 6.095818] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15
[ 6.098328] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16
[ 6.098395] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
[ 6.098457] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
[ 6.098524] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
[ 6.098627] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
[ 6.103207] bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp
[ 6.108059] bcm2835-codec bcm2835-codec: Device registered as /dev/video10
[ 6.108106] bcm2835-codec bcm2835-codec: Loaded V4L2 decode
[ 6.131305] bcm2835-codec bcm2835-codec: Device registered as /dev/video11
[ 6.131362] bcm2835-codec bcm2835-codec: Loaded V4L2 encode
[ 6.231030] bcm2835-codec bcm2835-codec: Device registered as /dev/video12
[ 6.231080] bcm2835-codec bcm2835-codec: Loaded V4L2 isp
[ 6.232806] [drm] Initialized v3d 1.0.0 20180419 for fec00000.v3d on minor 0
[ 6.519495] vc4-drm gpu: bound fe600000.firmwarekms (ops vc4_fkms_ops [vc4])
[ 6.519518] checking generic (3e843000 384000) vs hw (0 ffffffffffffffff)
[ 6.519533] fb0: switching to vc4drmfb from simple
[ 6.520177] Console: switching to colour dummy device 80x30
[ 6.520411] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
[ 6.520426] [drm] No driver support for vblank timestamp query.
[ 6.520438] [drm] Setting vblank_disable_immediate to false because get_vblank_timestamp == NULL
[ 6.521041] [drm] Initialized vc4 0.0.0 20140616 for gpu on minor 1
[ 6.522979] vc4-drm gpu: HDMI-A-1: EDID is invalid:
[ 6.523001] [00] ZERO 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 6.523015] [00] ZERO 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 6.523028] [00] ZERO 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 6.523041] [00] ZERO 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 6.523054] [00] ZERO 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 6.523067] [00] ZERO 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 6.523079] [00] ZERO 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 6.523093] [00] ZERO 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 6.529770] cfg80211: Loading compiled-in X.509 certificates for regulatory database
[ 6.530466] Console: switching to colour frame buffer device 160x45
[ 6.530508] vc4-drm gpu: fb0: vc4drmfb frame buffer device
[ 6.649026] bcmgenet: Skipping UMAC reset
[ 6.652040] bcmgenet fd580000.ethernet: configuring instance for external RGMII
[ 6.652455] bcmgenet fd580000.ethernet eth0: Link is Down
[ 6.667047] cfg80211: Loaded X.509 cert ‘sforshee: 00b28ddf47aef9cea7’
[ 6.734851] brcmfmac: F1 signature read @0x18000000=0x15264345
[ 6.745759] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[ 6.746204] usbcore: registered new interface driver brcmfmac
[ 6.775961] brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43455-sdio.raspberrypi,4-model-b.txt failed with error -2
[ 7.018921] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[ 7.030355] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Mar 23 2020 02:19:54 version 7.45.206 (r725000 CY) FWID 01-88ee44ea
[ 9.754978] bcmgenet fd580000.ethernet eth0: Link is Up - 100Mbps/Full - flow control rx/tx
[ 9.755035] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[ 11.556365] uart-pl011 fe201000.serial: no DMA platform data
[ 12.604536] Adding 102396k swap on /var/swap. Priority:-2 extents:1 across:102396k SSFS
[ 13.582234] random: crng init done
[ 13.582247] random: 3 urandom warning(s) missed due to ratelimiting
[ 14.716152] NFSD: Using UMH upcall client tracking operations.
[ 14.716174] NFSD: starting 90-second grace period (net f0000049)
[ 18.533302] Bluetooth: Core ver 2.22
[ 18.533379] NET: Registered protocol family 31
[ 18.533393] Bluetooth: HCI device and connection manager initialized
[ 18.533432] Bluetooth: HCI socket layer initialized
[ 18.533455] Bluetooth: L2CAP socket layer initialized
[ 18.533487] Bluetooth: SCO socket layer initialized
[ 18.559789] Bluetooth: HCI UART driver ver 2.3
[ 18.559808] Bluetooth: HCI UART protocol H4 registered
[ 18.559900] Bluetooth: HCI UART protocol Three-wire (H5) registered
[ 18.560230] Bluetooth: HCI UART protocol Broadcom registered
[ 18.972790] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[ 18.972806] Bluetooth: BNEP filters: protocol multicast
[ 18.972833] Bluetooth: BNEP socket layer initialized
[ 20.621536] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
[ 20.631473] Bridge firewalling registered
[ 22.984891] usb 2-2: new SuperSpeed Gen 1 USB device number 2 using xhci_hcd
[ 23.016142] usb 2-2: New USB device found, idVendor=1058, idProduct=0a10, bcdDevice=81.36
[ 23.016163] usb 2-2: New USB device strings: Mfr=1, Product=2, SerialNumber=5
[ 23.016180] usb 2-2: Product: JMS56x Series
[ 23.016196] usb 2-2: Manufacturer: JMicron
[ 23.016212] usb 2-2: SerialNumber: 1234567890123
[ 23.045742] scsi host0: uas
[ 23.047642] scsi 0:0:0:0: Direct-Access WDC WD20 PURZ-85AKKY0 8136 PQ: 0 ANSI: 6
[ 23.050467] sd 0:0:0:0: [sda] 3907029168 512-byte logical blocks: (2.00 TB/1.82 TiB)
[ 23.050753] sd 0:0:0:0: [sda] Write Protect is off
[ 23.050773] sd 0:0:0:0: [sda] Mode Sense: 67 00 10 08
[ 23.051287] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, supports DPO and FUA
[ 23.052121] sd 0:0:0:0: [sda] Optimal transfer size 33553920 bytes
[ 23.184527] sda: sda1
[ 23.188338] sd 0:0:0:0: [sda] Attached SCSI disk
[ 23.221231] sd 0:0:0:0: Attached scsi generic sg0 type 0
[ 24.408203] docker0: port 1(veth6649c43) entered blocking state
[ 24.408221] docker0: port 1(veth6649c43) entered disabled state
[ 24.408716] device veth6649c43 entered promiscuous mode
[ 26.555194] cgroup: cgroup: disabling cgroup2 socket matching due to net_prio or net_cls activation
[ 27.837480] eth0: renamed from veth07333ca
[ 27.888965] IPv6: ADDRCONF(NETDEV_CHANGE): veth6649c43: link becomes ready
[ 27.889204] docker0: port 1(veth6649c43) entered blocking state
[ 27.889221] docker0: port 1(veth6649c43) entered forwarding state
[ 27.889510] IPv6: ADDRCONF(NETDEV_CHANGE): docker0: link becomes ready
[ 32.823815] EXT4-fs (sda1): mounted filesystem with ordered data mode. Opts: user_xattr,usrjquota=aquota.user,grpjquota=aquota.group,jqfmt=vfsv0,acl
[ 35.645555] broken atomic modeset userspace detected, disabling atomic
[ 37.305004] xhci_hcd 0000:01:00.0: ERROR Transfer event for unknown stream ring slot 2 ep 7
[ 37.305011] xhci_hcd 0000:01:00.0: @000000001ed087d0 00000000 00000000 05000000 02088001
[ 67.691445] sd 0:0:0:0: [sda] tag#3 uas_eh_abort_handler 0 uas-tag 4 inflight: CMD OUT
[ 67.691461] sd 0:0:0:0: [sda] tag#3 CDB: opcode=0x2a 2a 00 07 80 a5 00 00 04 00 00
[ 67.691683] sd 0:0:0:0: [sda] tag#2 uas_eh_abort_handler 0 uas-tag 3 inflight: CMD OUT
[ 67.691695] sd 0:0:0:0: [sda] tag#2 CDB: opcode=0x2a 2a 00 07 80 a1 00 00 04 00 00
[ 67.691699] xhci_hcd 0000:01:00.0: WARN Set TR Deq Ptr cmd failed due to incorrect slot or ep state.
[ 67.691867] xhci_hcd 0000:01:00.0: WARN Set TR Deq Ptr cmd failed due to incorrect slot or ep state.
[ 67.691893] sd 0:0:0:0: [sda] tag#0 uas_eh_abort_handler 0 uas-tag 1 inflight: CMD OUT
[ 67.691906] sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x2a 2a 00 07 80 99 00 00 04 00 00
[ 67.692083] xhci_hcd 0000:01:00.0: WARN Set TR Deq Ptr cmd failed due to incorrect slot or ep state.
[ 67.731451] scsi host0: uas_eh_device_reset_handler start
[ 67.882370] usb 2-2: reset SuperSpeed Gen 1 USB device number 2 using xhci_hcd
[ 67.916222] scsi host0: uas_eh_device_reset_handler success
[ 68.342146] xhci_hcd 0000:01:00.0: ERROR Transfer event for unknown stream ring slot 2 ep 7
[ 68.342161] xhci_hcd 0000:01:00.0: (@)000000001ed08ad0 00000000 00000000 05000000 02088001
[ 98.479945] scsi host0: uas_eh_device_reset_handler start
[ 98.480576] xhci_hcd 0000:01:00.0: WARN Set TR Deq Ptr cmd failed due to incorrect slot or ep state.
[ 98.480737] xhci_hcd 0000:01:00.0: WARN Set TR Deq Ptr cmd failed due to incorrect slot or ep state.
[ 98.480898] xhci_hcd 0000:01:00.0: WARN Set TR Deq Ptr cmd failed due to incorrect slot or ep state.
[ 98.480917] sd 0:0:0:0: [sda] tag#1 uas_zap_pending 0 uas-tag 1 inflight: CMD
[ 98.480932] sd 0:0:0:0: [sda] tag#1 CDB: opcode=0x2a 2a 00 07 80 99 00 00 04 00 00
[ 98.480947] sd 0:0:0:0: [sda] tag#0 uas_zap_pending 0 uas-tag 2 inflight: CMD
[ 98.480959] sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x2a 2a 00 07 80 a1 00 00 04 00 00
[ 98.480974] sd 0:0:0:0: [sda] tag#2 uas_zap_pending 0 uas-tag 3 inflight: CMD
[ 98.480985] sd 0:0:0:0: [sda] tag#2 CDB: opcode=0x2a 2a 00 07 80 a5 00 00 04 00 00
[ 98.630896] usb 2-2: reset SuperSpeed Gen 1 USB device number 2 using xhci_hcd
[ 98.664875] scsi host0: uas_eh_device_reset_handler success
[ 99.850792] xhci_hcd 0000:01:00.0: ERROR Transfer event for unknown stream ring slot 2 ep 7
[ 99.850807] xhci_hcd 0000:01:00.0: @000000001ed08dc0 00000000 00000000 05000000 02088001
[ 130.433966] scsi host0: uas_eh_device_reset_handler start
[ 130.434630] xhci_hcd 0000:01:00.0: WARN Set TR Deq Ptr cmd failed due to incorrect slot or ep state.
[ 130.434789] xhci_hcd 0000:01:00.0: WARN Set TR Deq Ptr cmd failed due to incorrect slot or ep state.
[ 130.434910] xhci_hcd 0000:01:00.0: WARN Set TR Deq Ptr cmd failed due to incorrect slot or ep state.
[ 130.434936] sd 0:0:0:0: [sda] tag#3 uas_zap_pending 0 uas-tag 1 inflight: CMD
[ 130.434952] sd 0:0:0:0: [sda] tag#3 CDB: opcode=0x2a 2a 00 07 80 a5 00 00 04 00 00
[ 130.434971] sd 0:0:0:0: [sda] tag#2 uas_zap_pending 0 uas-tag 2 inflight: CMD
[ 130.434984] sd 0:0:0:0: [sda] tag#2 CDB: opcode=0x2a 2a 00 07 80 99 00 00 04 00 00
[ 130.434997] sd 0:0:0:0: [sda] tag#4 uas_zap_pending 0 uas-tag 3 inflight: CMD
[ 130.435008] sd 0:0:0:0: [sda] tag#4 CDB: opcode=0x2a 2a 00 07 80 a1 00 00 04 00 00
[ 130.584913] usb 2-2: reset SuperSpeed Gen 1 USB device number 2 using xhci_hcd
[ 130.618916] scsi host0: uas_eh_device_reset_handler success
[ 131.855164] xhci_hcd 0000:01:00.0: ERROR Transfer event for unknown stream ring slot 2 ep 7
[ 131.855179] xhci_hcd 0000:01:00.0: (@)000000001ed090b0 00000000 00000000 05000000 02088001

#11

Here is the rest:

[ 162.436562] scsi host0: uas_eh_device_reset_handler start
[ 162.437195] xhci_hcd 0000:01:00.0: WARN Set TR Deq Ptr cmd failed due to incorrect slot or ep state.
[ 162.437353] xhci_hcd 0000:01:00.0: WARN Set TR Deq Ptr cmd failed due to incorrect slot or ep state.
[ 162.437478] xhci_hcd 0000:01:00.0: WARN Set TR Deq Ptr cmd failed due to incorrect slot or ep state.
[ 162.437505] sd 0:0:0:0: [sda] tag#5 uas_zap_pending 0 uas-tag 1 inflight: CMD
[ 162.437520] sd 0:0:0:0: [sda] tag#5 CDB: opcode=0x2a 2a 00 07 80 a1 00 00 04 00 00
[ 162.437535] sd 0:0:0:0: [sda] tag#4 uas_zap_pending 0 uas-tag 2 inflight: CMD
[ 162.437547] sd 0:0:0:0: [sda] tag#4 CDB: opcode=0x2a 2a 00 07 80 a5 00 00 04 00 00
[ 162.437561] sd 0:0:0:0: [sda] tag#6 uas_zap_pending 0 uas-tag 3 inflight: CMD
[ 162.437577] sd 0:0:0:0: [sda] tag#6 CDB: opcode=0x2a 2a 00 07 80 99 00 00 04 00 00
[ 162.587489] usb 2-2: reset SuperSpeed Gen 1 USB device number 2 using xhci_hcd
[ 162.621516] scsi host0: uas_eh_device_reset_handler success
[ 163.817384] xhci_hcd 0000:01:00.0: ERROR Transfer event for unknown stream ring slot 2 ep 7
[ 163.817398] xhci_hcd 0000:01:00.0: @000000001ed093a0 00000000 00000000 05000000 02088001
[ 194.436728] scsi host0: uas_eh_device_reset_handler start
[ 194.437385] xhci_hcd 0000:01:00.0: WARN Set TR Deq Ptr cmd failed due to incorrect slot or ep state.
[ 194.437541] xhci_hcd 0000:01:00.0: WARN Set TR Deq Ptr cmd failed due to incorrect slot or ep state.
[ 194.437673] xhci_hcd 0000:01:00.0: WARN Set TR Deq Ptr cmd failed due to incorrect slot or ep state.
[ 194.437699] sd 0:0:0:0: [sda] tag#7 uas_zap_pending 0 uas-tag 1 inflight: CMD
[ 194.437716] sd 0:0:0:0: [sda] tag#7 CDB: opcode=0x2a 2a 00 07 80 99 00 00 04 00 00
[ 194.437730] sd 0:0:0:0: [sda] tag#6 uas_zap_pending 0 uas-tag 2 inflight: CMD
[ 194.437746] sd 0:0:0:0: [sda] tag#6 CDB: opcode=0x2a 2a 00 07 80 a1 00 00 04 00 00
[ 194.437759] sd 0:0:0:0: [sda] tag#8 uas_zap_pending 0 uas-tag 3 inflight: CMD
[ 194.437771] sd 0:0:0:0: [sda] tag#8 CDB: opcode=0x2a 2a 00 07 80 a5 00 00 04 00 00
[ 194.587663] usb 2-2: reset SuperSpeed Gen 1 USB device number 2 using xhci_hcd
[ 194.621524] scsi host0: uas_eh_device_reset_handler success
[ 195.857550] xhci_hcd 0000:01:00.0: ERROR Transfer event for unknown stream ring slot 2 ep 7
[ 195.857565] xhci_hcd 0000:01:00.0: @000000001ed09690 00000000 00000000 05000000 02088001
[ 226.436810] scsi host0: uas_eh_device_reset_handler start
[ 226.437436] xhci_hcd 0000:01:00.0: WARN Set TR Deq Ptr cmd failed due to incorrect slot or ep state.
[ 226.437595] xhci_hcd 0000:01:00.0: WARN Set TR Deq Ptr cmd failed due to incorrect slot or ep state.
[ 226.437721] xhci_hcd 0000:01:00.0: WARN Set TR Deq Ptr cmd failed due to incorrect slot or ep state.
[ 226.437749] sd 0:0:0:0: [sda] tag#9 uas_zap_pending 0 uas-tag 1 inflight: CMD
[ 226.437767] sd 0:0:0:0: [sda] tag#9 CDB: opcode=0x2a 2a 00 07 80 a5 00 00 04 00 00
[ 226.437783] sd 0:0:0:0: [sda] tag#8 uas_zap_pending 0 uas-tag 2 inflight: CMD
[ 226.437795] sd 0:0:0:0: [sda] tag#8 CDB: opcode=0x2a 2a 00 07 80 99 00 00 04 00 00
[ 226.437810] sd 0:0:0:0: [sda] tag#10 uas_zap_pending 0 uas-tag 3 inflight: CMD
[ 226.437821] sd 0:0:0:0: [sda] tag#10 CDB: opcode=0x2a 2a 00 07 80 a1 00 00 04 00 00
[ 226.587753] usb 2-2: reset SuperSpeed Gen 1 USB device number 2 using xhci_hcd
[ 226.621740] scsi host0: uas_eh_device_reset_handler success
[ 227.779550] sd 0:0:0:0: [sda] tag#8 UNKNOWN(0x2003) Result: hostbyte=0x08 driverbyte=0x00
[ 227.779570] sd 0:0:0:0: [sda] tag#8 CDB: opcode=0x2a 2a 00 07 80 99 00 00 04 00 00
[ 227.779590] blk_update_request: I/O error, dev sda, sector 125868288 op 0x1:(WRITE) flags 0x4000 phys_seg 128 prio class 0
[ 227.779668] sd 0:0:0:0: [sda] tag#9 UNKNOWN(0x2003) Result: hostbyte=0x08 driverbyte=0x00
[ 227.779684] sd 0:0:0:0: [sda] tag#9 CDB: opcode=0x2a 2a 00 07 80 a5 00 00 04 00 00
[ 227.779702] blk_update_request: I/O error, dev sda, sector 125871360 op 0x1:(WRITE) flags 0x800 phys_seg 128 prio class 0
[ 227.779749] sd 0:0:0:0: [sda] tag#10 UNKNOWN(0x2003) Result: hostbyte=0x08 driverbyte=0x00
[ 227.779765] sd 0:0:0:0: [sda] tag#10 CDB: opcode=0x2a 2a 00 07 80 a1 00 00 04 00 00
[ 227.779783] blk_update_request: I/O error, dev sda, sector 125870336 op 0x1:(WRITE) flags 0x4800 phys_seg 128 prio class 0
[ 243.676904] INFO: task sync:1794 blocked for more than 122 seconds.
[ 243.676919] Tainted: G C 5.4.79-v7l+ #1373
[ 243.676930] “echo 0 > /proc/sys/kernel/hung_task_timeout_secs” disables this message.
[ 243.676943] sync D 0 1794 1755 0x00000000
[ 243.676962] Backtrace:
[ 243.676991] [] (__schedule) from [] (schedule+0x58/0xd4)
[ 243.677010] r10:d4466c44 r9:c1204fc8 r8:d4bee854 r7:d4bee848 r6:00000033 r5:d4619e80
[ 243.677021] r4:ffffe000
[ 243.677042] [] (schedule) from [] (jbd2_log_wait_commit+0xd8/0x15c)
[ 243.677054] r5:d4bee814 r4:d4bee800
[ 243.677074] [] (jbd2_log_wait_commit) from [] (ext4_sync_fs+0x1dc/0x21c)
[ 243.677090] r9:d4bee814 r8:00000001 r7:00000001 r6:d4466400 r5:d4466c00 r4:c1204fc8
[ 243.677109] [] (ext4_sync_fs) from [] (sync_fs_one_sb+0x38/0x3c)
[ 243.677124] r9:d4613f78 r8:c133834c r7:c1226ff4 r6:c133834c r5:d4ab0c00 r4:d4466c00
[ 243.677144] [] (sync_fs_one_sb) from [] (iterate_supers+0xb4/0x118)
[ 243.677161] [] (iterate_supers) from [] (ksys_sync+0x70/0xc8)
[ 243.677177] r10:00000024 r9:d4612000 r8:c02011c4 r7:00000024 r6:00000000 r5:00000000
[ 243.677188] r4:c1204fc8
[ 243.677203] [] (ksys_sync) from [] (sys_sync+0x18/0x20)
[ 243.677215] r5:00000001 r4:be984a94
[ 243.677232] [] (sys_sync) from [] (ret_fast_syscall+0x0/0x28)
[ 243.677245] Exception stack(0xd4613fa8 to 0xd4613ff0)
[ 243.677259] 3fa0: be984a94 00000001 ffffffff b6ee1198 00000000 00000001
[ 243.677275] 3fc0: be984a94 00000001 00000000 00000024 00000000 00026130 000148b0 00000000
[ 243.677287] 3fe0: b6e64930 be984904 00011160 b6e6493c
[ 258.416944] sd 0:0:0:0: [sda] tag#13 uas_eh_abort_handler 0 uas-tag 1 inflight: CMD OUT
[ 258.416961] sd 0:0:0:0: [sda] tag#13 CDB: opcode=0x2a 2a 00 74 44 08 38 00 00 18 00
[ 258.466953] scsi host0: uas_eh_device_reset_handler start
[ 258.617889] usb 2-2: reset SuperSpeed Gen 1 USB device number 2 using xhci_hcd
[ 258.651784] scsi host0: uas_eh_device_reset_handler success
[ 290.457195] scsi host0: uas_eh_device_reset_handler start
[ 290.457754] sd 0:0:0:0: [sda] tag#11 uas_zap_pending 0 uas-tag 1 inflight: CMD
[ 290.457769] sd 0:0:0:0: [sda] tag#11 CDB: opcode=0x2a 2a 00 74 44 08 38 00 00 18 00
[ 290.608114] usb 2-2: reset SuperSpeed Gen 1 USB device number 2 using xhci_hcd
[ 290.642110] scsi host0: uas_eh_device_reset_handler success
[ 322.437472] scsi host0: uas_eh_device_reset_handler start
[ 322.438033] sd 0:0:0:0: [sda] tag#10 uas_zap_pending 0 uas-tag 1 inflight: CMD
[ 322.438049] sd 0:0:0:0: [sda] tag#10 CDB: opcode=0x2a 2a 00 74 44 08 38 00 00 18 00
[ 322.588397] usb 2-2: reset SuperSpeed Gen 1 USB device number 2 using xhci_hcd
[ 322.622269] scsi host0: uas_eh_device_reset_handler success
[ 354.437796] scsi host0: uas_eh_device_reset_handler start
[ 354.438434] sd 0:0:0:0: [sda] tag#11 uas_zap_pending 0 uas-tag 1 inflight: CMD
[ 354.438450] sd 0:0:0:0: [sda] tag#11 CDB: opcode=0x2a 2a 00 74 44 08 38 00 00 18 00
[ 354.588745] usb 2-2: reset SuperSpeed Gen 1 USB device number 2 using xhci_hcd
[ 354.622724] scsi host0: uas_eh_device_reset_handler success
[ 366.557957] INFO: task jbd2/sda1-8:1614 blocked for more than 122 seconds.
[ 366.557965] Tainted: G C 5.4.79-v7l+ #1373
[ 366.557971] “echo 0 > /proc/sys/kernel/hung_task_timeout_secs” disables this message.
[ 366.557977] jbd2/sda1-8 D 0 1614 2 0x00000000
[ 366.557986] Backtrace:
[ 366.558004] [] (__schedule) from [] (schedule+0x58/0xd4)
[ 366.558012] r10:d49bdea4 r9:d43e2bc0 r8:c0a904fc r7:c1203760 r6:d49bddd8 r5:d44c3d00
[ 366.558017] r4:ffffe000
[ 366.558026] [] (schedule) from [] (io_schedule+0x20/0x40)
[ 366.558032] r5:00000002 r4:00000000
[ 366.558050] [] (io_schedule) from [] (bit_wait_io+0x1c/0x6c)
[ 366.558055] r5:00000002 r4:00000002
[ 366.558063] [] (bit_wait_io) from [] (__wait_on_bit+0x70/0xc8)
[ 366.558069] r5:00000002 r4:d49bddcc
[ 366.558077] [] (__wait_on_bit) from [] (out_of_line_wait_on_bit+0x94/0xb0)
[ 366.558084] r9:d43e2bc0 r8:00000000 r7:d43e2bec r6:00000000 r5:c27a8730 r4:c1204fc8
[ 366.558096] [] (out_of_line_wait_on_bit) from [] (__wait_on_buffer+0x40/0x44)
[ 366.558102] r5:d5613300 r4:d43e2bc0
[ 366.558113] [] (__wait_on_buffer) from [] (jbd2_journal_commit_transaction+0xe84/0x1a38)
[ 366.558119] r5:d5613300 r4:d4bee800
[ 366.558128] [] (jbd2_journal_commit_transaction) from [] (kjournald2+0x10c/0x2f0)
[ 366.558135] r10:c12dfb10 r9:c1203d00 r8:d4beea28 r7:00000000 r6:d4bee814 r5:00000000
[ 366.558140] r4:d4bee800
[ 366.558149] [] (kjournald2) from [] (kthread+0x170/0x174)
[ 366.558156] r10:d440fc74 r9:c053099c r8:d4bee800 r7:d49bc000 r6:00000000 r5:d4f98940
[ 366.558161] r4:d4f98900
[ 366.558169] [] (kthread) from [] (ret_from_fork+0x14/0x28)
[ 366.558175] Exception stack(0xd49bdfb0 to 0xd49bdff8)
[ 366.558182] dfa0: 00000000 00000000 00000000 00000000
[ 366.558189] dfc0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[ 366.558195] dfe0: 00000000 00000000 00000000 00000000 00000013 00000000
[ 366.558202] r10:00000000 r9:00000000 r8:00000000 r7:00000000 r6:00000000 r5:c0245e60
[ 366.558207] r4:d4f98940
[ 366.558218] INFO: task sync:1794 blocked for more than 245 seconds.
[ 366.558224] Tainted: G C 5.4.79-v7l+ #1373
[ 366.558229] “echo 0 > /proc/sys/kernel/hung_task_timeout_secs” disables this message.
[ 366.558234] sync D 0 1794 1755 0x00000000
[ 366.558242] Backtrace:
[ 366.558252] [] (__schedule) from [] (schedule+0x58/0xd4)
[ 366.558259] r10:d4466c44 r9:c1204fc8 r8:d4bee854 r7:d4bee848 r6:00000033 r5:d4619e80
[ 366.558264] r4:ffffe000
[ 366.558272] [] (schedule) from [] (jbd2_log_wait_commit+0xd8/0x15c)
[ 366.558277] r5:d4bee814 r4:d4bee800
[ 366.558286] [] (jbd2_log_wait_commit) from [] (ext4_sync_fs+0x1dc/0x21c)
[ 366.558293] r9:d4bee814 r8:00000001 r7:00000001 r6:d4466400 r5:d4466c00 r4:c1204fc8
[ 366.558302] [] (ext4_sync_fs) from [] (sync_fs_one_sb+0x38/0x3c)
[ 366.558309] r9:d4613f78 r8:c133834c r7:c1226ff4 r6:c133834c r5:d4ab0c00 r4:d4466c00
[ 366.558319] [] (sync_fs_one_sb) from [] (iterate_supers+0xb4/0x118)
[ 366.558327] [] (iterate_supers) from [] (ksys_sync+0x70/0xc8)
[ 366.558334] r10:00000024 r9:d4612000 r8:c02011c4 r7:00000024 r6:00000000 r5:00000000
[ 366.558339] r4:c1204fc8
[ 366.558346] [] (ksys_sync) from [] (sys_sync+0x18/0x20)
[ 366.558352] r5:00000001 r4:be984a94
[ 366.558359] [] (sys_sync) from [] (ret_fast_syscall+0x0/0x28)
[ 366.558365] Exception stack(0xd4613fa8 to 0xd4613ff0)
[ 366.558371] 3fa0: be984a94 00000001 ffffffff b6ee1198 00000000 00000001
[ 366.558378] 3fc0: be984a94 00000001 00000000 00000024 00000000 00026130 000148b0 00000000
[ 366.558384] 3fe0: b6e64930 be984904 00011160 b6e6493c

Quad SATA Hat Disconnects
#12

@gerapam:
Now a lot of errors appear after 98 sec:

[ 98.479945] scsi host0: uas_eh_device_reset_handler start
[ 98.480576] xhci_hcd 0000:01:00.0: WARN Set TR Deq Ptr cmd failed due to incorrect slot or ep state.
[ 98.480737] xhci_hcd 0000:01:00.0: WARN Set TR Deq Ptr cmd failed due to incorrect slot or ep state.
[ 98.480898] xhci_hcd 0000:01:00.0: WARN Set TR Deq Ptr cmd failed due to incorrect slot or ep state.
[ 98.480917] sd 0:0:0:0: [sda] tag#1 uas_zap_pending 0 uas-tag 1 inflight: CMD
[ 98.480932] sd 0:0:0:0: [sda] tag#1 CDB: opcode=0x2a 2a 00 07 80 99 00 00 04 00 00
[ 98.480947] sd 0:0:0:0: [sda] tag#0 uas_zap_pending 0 uas-tag 2 inflight: CMD
[ 98.480959] sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x2a 2a 00 07 80 a1 00 00 04 00 00
[ 98.480974] sd 0:0:0:0: [sda] tag#2 uas_zap_pending 0 uas-tag 3 inflight: CMD
[ 98.480985] sd 0:0:0:0: [sda] tag#2 CDB: opcode=0x2a 2a 00 07 80 a5 00 00 04 00 00
[ 98.630896] usb 2-2: reset SuperSpeed Gen 1 USB device number 2 using xhci_hcd
[ 98.664875] scsi host0: uas_eh_device_reset_handler success
[ 99.850792] xhci_hcd 0000:01:00.0: ERROR Transfer event for unknown stream ring slot 2 ep 7
[ 99.850807] xhci_hcd 0000:01:00.0: @000000001ed08dc0 00000000 00000000 05000000 02088001

This is a clear proof of some defect(s) on your sata hat.
I suggest you request a replacement because this is a defect covered by warranty.
Good luck!

#13

Hi Lin,

Thanks for your fast reply. I will talk with the supplier and ask for a replacement.

Thank you very much for all your help.
Best regards,