RAID 10 slow write speed

#1

I have set up the Quad Sata Hat with 4 x 2TB disks in RAID 10.

Before I create RAID 10 I have tested it by transfering large files to each one of the disks over Gbps Ethernet and I always got write/read speads between 90-100MB/s

Once I have set up the RAID 10 the writes of large files are not constant anymore. A large file that took a few seconds for the single disk in RAID 10 takes minutes. The write speed spikes from a few KB/s to 50MB/s all the time with no constant write spead.

The read speeds are fast though between 90-100MB/s.

Any idea why these spikes and slow write speeds and how to fix this?

Thanks

#2

RAID10 performance is lower than a single disk, which is normal.

#3

OK but why the transfer spikes all the time between a few KB/s and MB/s?

Sometime it hangs completely and continues after 10 or more seconds.

#4

Does it hang on continuous writes, or does it hang on initial?

#5

You can check dmesg when it hangs.

#6

Hi. I removed the RAID and I set up the Disks as single again. I tried to copy a file less than 1.5GB and I got a 60MB/s write speed. For a 4GB file I got 29MB/s. Are these write speeds normal?

My quad sata hat has been replaced because two of the sata slots didn’t work (see my post here). At least with the old quad sata hat I could get more than 80MB/s in the working sata slots with single disks.

Maybe this is a problem with the new quad sata hat I received?

#7

Maybe we need to look at dmesg first.

#8

Hi. This is the dmesg output:

pi@raspberrypi:~ $ dmesg
[ 0.000000] Booting Linux on physical CPU 0x0
[ 0.000000] Linux version 4.19.118-v7l+ (dom@buildbot) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611)) #1311 SMP Mon Apr 27 14:26:42 BST 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] cma: Reserved 256 MiB at 0x000000001ec00000
[ 0.000000] On node 0 totalpages: 1012736
[ 0.000000] DMA zone: 1728 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] random: get_random_bytes called from start_kernel+0xc0/0x4e8 with crng_init=0
[ 0.000000] percpu: Embedded 17 pages/cpu s36928 r8192 d24512 u69632
[ 0.000000] pcpu-alloc: s36928 r8192 d24512 u69632 alloc=17*4096
[ 0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
[ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1011008
[ 0.000000] Kernel command line: coherent_pool=1M 8250.nr_uarts=0 cma=64M snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 cma=256M smsc95xx.macaddr=DC:A6:32:83:31:44 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 console=ttyS0,115200 console=tty1 root=PARTUUID=771df6e1-02 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait
[ 0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
[ 0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
[ 0.000000] Memory: 3735552K/4050944K available (8192K kernel code, 690K rwdata, 2420K rodata, 2048K init, 860K bss, 53248K reserved, 262144K cma-reserved, 3264512K highmem)
[ 0.000000] Virtual kernel memory layout:
vector : 0xffff0000 - 0xffff1000 ( 4 kB)
fixmap : 0xffc00000 - 0xfff00000 (3072 kB)
vmalloc : 0xf0800000 - 0xff800000 ( 240 MB)
lowmem : 0xc0000000 - 0xf0000000 ( 768 MB)
pkmap : 0xbfe00000 - 0xc0000000 ( 2 MB)
modules : 0xbf000000 - 0xbfe00000 ( 14 MB)
.text : 0x(ptrval) - 0x(ptrval) (10208 kB)
.init : 0x(ptrval) - 0x(ptrval) (2048 kB)
.data : 0x(ptrval) - 0x(ptrval) ( 691 kB)
.bss : 0x(ptrval) - 0x(ptrval) ( 861 kB)
[ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[ 0.000000] ftrace: allocating 28928 entries in 85 pages
[ 0.000000] rcu: Hierarchical RCU implementation.
[ 0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
[ 0.000000] GIC: Using split EOI/Deactivate mode
[ 0.000000] arch_timer: cp15 timer(s) running at 54.00MHz (phys).
[ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns
[ 0.000005] sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns
[ 0.000021] Switching to timer-based delay loop, resolution 18ns
[ 0.000256] Console: colour dummy device 80x30
[ 0.000720] console [tty1] enabled
[ 0.000775] Calibrating delay loop (skipped), value calculated using timer frequency… 108.00 BogoMIPS (lpj=540000)
[ 0.000811] pid_max: default: 32768 minimum: 301
[ 0.001095] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
[ 0.001128] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
[ 0.001892] CPU: Testing write buffer coherency: ok
[ 0.002318] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[ 0.002972] Setting up static identity map for 0x200000 - 0x20003c
[ 0.003145] rcu: Hierarchical SRCU implementation.
[ 0.004015] smp: Bringing up secondary CPUs …
[ 0.004834] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[ 0.005760] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
[ 0.006638] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
[ 0.006757] smp: Brought up 1 node, 4 CPUs
[ 0.006823] SMP: Total of 4 processors activated (432.00 BogoMIPS).
[ 0.006846] CPU: All CPU(s) started in HYP mode.
[ 0.006866] CPU: Virtualization extensions available.
[ 0.007666] devtmpfs: initialized
[ 0.018266] VFP support v0.3: implementor 41 architecture 3 part 40 variant 8 rev 0
[ 0.018490] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[ 0.018532] futex hash table entries: 1024 (order: 4, 65536 bytes)
[ 0.025902] pinctrl core: initialized pinctrl subsystem
[ 0.026793] NET: Registered protocol family 16
[ 0.029788] DMA: preallocated 1024 KiB pool for atomic coherent allocations
[ 0.031305] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[ 0.031337] hw-breakpoint: maximum watchpoint size is 8 bytes.
[ 0.031540] Serial: AMBA PL011 UART driver
[ 0.034777] bcm2835-mbox fe00b880.mailbox: mailbox enabled
[ 0.050020] raspberrypi-firmware soc:firmware: Attached to firmware from 2020-06-01 13:23, variant start
[ 0.060036] raspberrypi-firmware soc:firmware: Firmware hash is 6379679d1ec6a8c746d7e77e015f5b56b939976f
[ 0.102841] bcm2835-dma fe007000.dma: DMA legacy API manager at (ptrval), dmachans=0x1
[ 0.105973] vgaarb: loaded
[ 0.106345] SCSI subsystem initialized
[ 0.106556] usbcore: registered new interface driver usbfs
[ 0.106622] usbcore: registered new interface driver hub
[ 0.106704] usbcore: registered new device driver usb
[ 0.108041] clocksource: Switched to clocksource arch_sys_counter
[ 0.186044] VFS: Disk quotas dquot_6.6.0
[ 0.186146] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[ 0.186337] FS-Cache: Loaded
[ 0.186528] CacheFiles: Loaded
[ 0.187054] simple-framebuffer: probe of 0.framebuffer failed with error -12
[ 0.196221] NET: Registered protocol family 2
[ 0.196884] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes)
[ 0.196929] TCP established hash table entries: 8192 (order: 3, 32768 bytes)
[ 0.197010] TCP bind hash table entries: 8192 (order: 4, 65536 bytes)
[ 0.197095] TCP: Hash tables configured (established 8192 bind 8192)
[ 0.197228] UDP hash table entries: 512 (order: 2, 16384 bytes)
[ 0.197272] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
[ 0.197490] NET: Registered protocol family 1
[ 0.198097] RPC: Registered named UNIX socket transport module.
[ 0.198124] RPC: Registered udp transport module.
[ 0.198146] RPC: Registered tcp transport module.
[ 0.198167] RPC: Registered tcp NFSv4.1 backchannel transport module.
[ 0.198195] PCI: CLS 0 bytes, default 64
[ 0.199988] hw perfevents: enabled with armv7_cortex_a15 PMU driver, 7 counters available
[ 0.202113] Initialise system trusted keyrings
[ 0.202323] workingset: timestamp_bits=14 max_order=20 bucket_order=6
[ 0.211346] FS-Cache: Netfs ‘nfs’ registered for caching
[ 0.211877] NFS: Registering the id_resolver key type
[ 0.211926] Key type id_resolver registered
[ 0.211948] Key type id_legacy registered
[ 0.211978] nfs4filelayout_init: NFSv4 File Layout Driver Registering…
[ 0.216525] Key type asymmetric registered
[ 0.216552] Asymmetric key parser ‘x509’ registered
[ 0.216676] bounce: pool size: 64 pages
[ 0.216730] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250)
[ 0.216917] io scheduler noop registered
[ 0.216941] io scheduler deadline registered (default)
[ 0.217114] io scheduler cfq registered
[ 0.217137] io scheduler mq-deadline registered (default)
[ 0.217160] io scheduler kyber registered
[ 0.247828] brcm-pcie fd500000.pcie: dmabounce: initialised - 32768 kB, threshold 0x00000000c0000000
[ 0.247875] brcm-pcie fd500000.pcie: could not get clock
[ 0.247949] brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges:
[ 0.248000] brcm-pcie fd500000.pcie: MEM 0x600000000…0x603ffffff -> 0xf8000000
[ 0.308070] brcm-pcie fd500000.pcie: link up, 5.0 Gbps x1 (!SSC)
[ 0.308383] brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00
[ 0.308415] pci_bus 0000:00: root bus resource [bus 00-01]
[ 0.308445] pci_bus 0000:00: root bus resource [mem 0x600000000-0x603ffffff] (bus address [0xf8000000-0xfbffffff])
[ 0.308508] pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400
[ 0.308648] pci 0000:00:00.0: PME# supported from D0 D3hot
[ 0.311404] PCI: bus0: Fast back to back transfers disabled
[ 0.311436] pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring
[ 0.311659] pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330
[ 0.311784] pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit]
[ 0.312101] pci 0000:01:00.0: PME# supported from D0 D3cold
[ 0.314843] PCI: bus1: Fast back to back transfers disabled
[ 0.314873] pci_bus 0000:01: busn_res: [bus 01] end is updated to 01
[ 0.314919] pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff]
[ 0.314953] pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit]
[ 0.315042] pci 0000:00:00.0: PCI bridge to [bus 01]
[ 0.315071] pci 0000:00:00.0: bridge window [mem 0x600000000-0x6000fffff]
[ 0.315295] pcieport 0000:00:00.0: enabling device (0140 -> 0142)
[ 0.315485] pcieport 0000:00:00.0: Signaling PME with IRQ 53
[ 0.315662] pcieport 0000:00:00.0: AER enabled with IRQ 53
[ 0.315848] pci 0000:01:00.0: enabling device (0140 -> 0142)
[ 0.319967] iproc-rng200 fe104000.rng: hwrng registered
[ 0.320203] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
[ 0.320715] vc-sm: Videocore shared memory driver
[ 0.321127] gpiomem-bcm2835 fe200000.gpiomem: Initialised: Registers at 0xfe200000
[ 0.331272] brd: module loaded
[ 0.340875] loop: module loaded
[ 0.341638] Loading iSCSI transport class v2.0-870.
[ 0.343340] libphy: Fixed MDIO Bus: probed
[ 0.343773] bcmgenet fd580000.genet: failed to get enet clock
[ 0.343804] bcmgenet fd580000.genet: GENET 5.0 EPHY: 0x0000
[ 0.343834] bcmgenet fd580000.genet: failed to get enet-wol clock
[ 0.343864] bcmgenet fd580000.genet: failed to get enet-eee clock
[ 0.343900] bcmgenet: Skipping UMAC reset
[ 0.344151] unimac-mdio unimac-mdio.-19: DMA mask not set
[ 0.358069] libphy: bcmgenet MII bus: probed
[ 0.398554] unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus at 0x(ptrval)
[ 0.399481] usbcore: registered new interface driver r8152
[ 0.399564] usbcore: registered new interface driver lan78xx
[ 0.399632] usbcore: registered new interface driver smsc95xx
[ 0.399945] xhci_hcd 0000:01:00.0: xHCI Host Controller
[ 0.399989] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1
[ 0.402225] xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x0000001000000890
[ 0.402560] genirq: irq_chip Brcm_MSI did not update eff. affinity mask of irq 54
[ 0.403440] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.19
[ 0.403475] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 0.403504] usb usb1: Product: xHCI Host Controller
[ 0.403528] usb usb1: Manufacturer: Linux 4.19.118-v7l+ xhci-hcd
[ 0.403552] usb usb1: SerialNumber: 0000:01:00.0
[ 0.404082] hub 1-0:1.0: USB hub found
[ 0.404175] hub 1-0:1.0: 1 port detected
[ 0.404658] xhci_hcd 0000:01:00.0: xHCI Host Controller
[ 0.404694] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2
[ 0.404737] xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed
[ 0.405156] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 4.19
[ 0.405189] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 0.405218] usb usb2: Product: xHCI Host Controller
[ 0.405241] usb usb2: Manufacturer: Linux 4.19.118-v7l+ xhci-hcd
[ 0.405265] usb usb2: SerialNumber: 0000:01:00.0
[ 0.405767] hub 2-0:1.0: USB hub found
[ 0.405835] hub 2-0:1.0: 4 ports detected
[ 0.407057] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[ 0.407266] dwc_otg: FIQ enabled
[ 0.407275] dwc_otg: NAK holdoff enabled
[ 0.407283] dwc_otg: FIQ split-transaction FSM enabled
[ 0.407295] Module dwc_common_port init
[ 0.407518] usbcore: registered new interface driver uas
[ 0.407628] usbcore: registered new interface driver usb-storage
[ 0.407817] mousedev: PS/2 mouse device common for all mice
[ 0.409043] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
[ 0.410671] sdhci: Secure Digital Host Controller Interface driver
[ 0.410697] sdhci: Copyright© Pierre Ossman
[ 0.411106] mmc-bcm2835 fe300000.mmcnr: could not get clk, deferring probe
[ 0.411518] sdhci-pltfm: SDHCI platform and OF driver helper
[ 0.414545] ledtrig-cpu: registered to indicate activity on CPUs
[ 0.414705] hidraw: raw HID events driver © Jiri Kosina
[ 0.414839] usbcore: registered new interface driver usbhid
[ 0.414861] usbhid: USB HID core driver
[ 0.415645] vchiq: vchiq_init_state: slot_zero = (ptrval), is_master = 0
[ 0.417366] [vc_sm_connected_init]: start
[ 0.426202] [vc_sm_connected_init]: end - returning 0
[ 0.427315] Initializing XFRM netlink socket
[ 0.427357] NET: Registered protocol family 17
[ 0.427460] Key type dns_resolver registered
[ 0.427846] Registering SWP/SWPB emulation handler
[ 0.428478] registered taskstats version 1
[ 0.428509] Loading compiled-in X.509 certificates
[ 0.435413] uart-pl011 fe201000.serial: cts_event_workaround enabled
[ 0.435495] fe201000.serial: ttyAMA0 at MMIO 0xfe201000 (irq = 34, base_baud = 0) is a PL011 rev2
[ 0.440631] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
[ 0.441171] brcmstb_thermal fd5d2200.thermal: registered AVS TMON of-sensor driver
[ 0.441838] mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0
[ 0.441866] mmc-bcm2835 fe300000.mmcnr: DMA channel allocated
[ 0.467904] sdhci-iproc fe340000.emmc2: Linked as a consumer to regulator.3
[ 0.468012] sdhci-iproc fe340000.emmc2: Linked as a consumer to regulator.4
[ 0.486286] mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
[ 0.487897] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[ 0.489504] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[ 0.492373] mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
[ 0.493976] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[ 0.500755] mmc0: SDHCI controller on fe340000.emmc2 [fe340000.emmc2] using ADMA
[ 0.503282] of_cfs_init
[ 0.503376] of_cfs_init: OK
[ 0.503968] Waiting for root device PARTUUID=771df6e1-02…
[ 0.549810] random: fast init done
[ 0.562357] mmc1: new high speed SDIO card at address 0001
[ 0.605212] mmc0: new ultra high speed DDR50 SDXC card at address aaaa
[ 0.606327] mmcblk0: mmc0:aaaa SC64G 59.5 GiB
[ 0.608485] mmcblk0: p1 p2
[ 0.632504] EXT4-fs (mmcblk0p2): INFO: recovery required on readonly filesystem
[ 0.632542] EXT4-fs (mmcblk0p2): write access will be enabled during recovery
[ 0.768099] usb 1-1: new high-speed USB device number 2 using xhci_hcd
[ 0.938964] EXT4-fs (mmcblk0p2): recovery complete
[ 0.940866] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
[ 0.940934] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
[ 0.946805] devtmpfs: mounted
[ 0.950719] usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21
[ 0.950755] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[ 0.950783] usb 1-1: Product: USB2.0 Hub
[ 0.952794] hub 1-1:1.0: USB hub found
[ 0.953111] hub 1-1:1.0: 4 ports detected
[ 0.954165] Freeing unused kernel memory: 2048K
[ 0.954425] Run /sbin/init as init process
[ 1.339474] systemd[1]: System time before build time, advancing clock.
[ 1.434653] NET: Registered protocol family 10
[ 1.435877] Segment Routing with IPv6
[ 1.466489] 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)
[ 1.467231] systemd[1]: Detected architecture arm.
[ 1.477653] systemd[1]: Set hostname to .
[ 1.871554] random: lvmconfig: uninitialized urandom read (4 bytes read)
[ 2.413454] 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.
[ 2.461763] random: systemd: uninitialized urandom read (16 bytes read)
[ 2.478369] systemd[1]: local-fs.target: Found ordering cycle on srv-dev\x2ddisk\x2dby\x2dlabel\x2dstorage.mount/start
[ 2.478425] systemd[1]: local-fs.target: Found dependency on rockpi-sata.service/start
[ 2.478466] systemd[1]: local-fs.target: Found dependency on sysinit.target/start
[ 2.478505] systemd[1]: local-fs.target: Found dependency on local-fs.target/start
[ 2.478547] systemd[1]: local-fs.target: Job srv-dev\x2ddisk\x2dby\x2dlabel\x2dstorage.mount/start deleted to break ordering cycle starting with local-fs.target/start
[ 2.482628] systemd[1]: Unnecessary job for /dev/disk/by-uuid/f1bfba39-9471-420a-ae4f-8b317b609b2d was removed.
[ 2.484077] random: systemd: uninitialized urandom read (16 bytes read)
[ 2.680587] Installing knfsd (copyright © 1996 okir@monad.swb.de).
[ 3.241799] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
[ 3.325057] systemd-journald[139]: Received request to flush runtime journal from PID 1
[ 3.830944] Driver for 1-wire Dallas network protocol.
[ 3.834137] gpio-4 (onewire@0): enforced open drain please flag it properly in DT/ACPI DSDT/board file
[ 3.851621] rpivid-mem feb00000.hevc-decoder: rpivid-hevcmem initialised: Registers at 0xfeb00000 length 0x0000ffff
[ 3.852255] rpivid-mem feb10000.rpivid-local-intc: rpivid-intcmem initialised: Registers at 0xfeb10000 length 0x00000fff
[ 3.855517] rpivid-mem feb20000.h264-decoder: rpivid-h264mem initialised: Registers at 0xfeb20000 length 0x0000ffff
[ 3.856701] rpivid-mem feb30000.vp9-decoder: rpivid-vp9mem initialised: Registers at 0xfeb30000 length 0x0000ffff
[ 3.884440] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
[ 3.886453] bcm2835_vc_sm_cma_probe: Videocore shared memory driver
[ 3.886468] [vc_sm_connected_init]: start
[ 3.892143] [vc_sm_connected_init]: installed successfully
[ 3.909187] media: Linux media interface: v0.10
[ 3.946198] videodev: Linux video capture interface: v2.00
[ 3.981749] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[ 3.995142] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
[ 4.022940] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
[ 4.081541] bcm2835-codec bcm2835-codec: Device registered as /dev/video10
[ 4.081583] bcm2835-codec bcm2835-codec: Loaded V4L2 decode
[ 4.091015] bcm2835-codec bcm2835-codec: Device registered as /dev/video11
[ 4.091050] bcm2835-codec bcm2835-codec: Loaded V4L2 encode
[ 4.110261] bcm2835-codec bcm2835-codec: Device registered as /dev/video12
[ 4.110296] bcm2835-codec bcm2835-codec: Loaded V4L2 isp
[ 4.119813] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
[ 4.120181] cfg80211: Loading compiled-in X.509 certificates for regulatory database
[ 4.157875] bcm2835_audio soc:audio: card created with 8 channels
[ 4.174464] cfg80211: Loaded X.509 cert ‘sforshee: 00b28ddf47aef9cea7’
[ 4.291215] brcmfmac: F1 signature read @0x18000000=0x15264345
[ 4.301872] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[ 4.302632] usbcore: registered new interface driver brcmfmac
[ 4.325838] [drm] Initialized v3d 1.0.0 20180419 for fec00000.v3d on minor 0
[ 4.360445] [drm] No displays found. Consider forcing hotplug if HDMI is attached
[ 4.360551] vc4-drm soc:gpu: bound fe600000.firmwarekms (ops vc4_fkms_ops [vc4])
[ 4.361072] [drm] Initialized vc4 0.0.0 20140616 for soc:gpu on minor 1
[ 4.361084] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
[ 4.361093] [drm] No driver support for vblank timestamp query.
[ 4.361102] [drm] Setting vblank_disable_immediate to false because get_vblank_timestamp == NULL
[ 4.367554] bcmgenet: Skipping UMAC reset
[ 4.468759] bcmgenet fd580000.genet: configuring instance for external RGMII (no delay)
[ 4.469027] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[ 4.540854] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[ 4.556332] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Mar 2 2020 23:30:41 version 7.45.202 (r724630 CY) FWID 01-72f6ece2
[ 5.528377] bcmgenet fd580000.genet eth0: Link is Down
[ 8.143379] uart-pl011 fe201000.serial: no DMA platform data
[ 8.514466] Adding 102396k swap on /var/swap. Priority:-2 extents:1 across:102396k SSFS
[ 8.998895] NFSD: starting 90-second grace period (net f0000041)
[ 9.184682] random: crng init done
[ 9.184697] random: 7 urandom warning(s) missed due to ratelimiting
[ 9.688201] bcmgenet fd580000.genet eth0: Link is Up - 1Gbps/Full - flow control rx/tx
[ 9.688225] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[ 9.884999] i2c /dev entries driver
[ 14.933979] Bluetooth: Core ver 2.22
[ 14.934052] NET: Registered protocol family 31
[ 14.934057] Bluetooth: HCI device and connection manager initialized
[ 14.935522] Bluetooth: HCI socket layer initialized
[ 14.935537] Bluetooth: L2CAP socket layer initialized
[ 14.935569] Bluetooth: SCO socket layer initialized
[ 14.954725] Bluetooth: HCI UART driver ver 2.3
[ 14.954732] Bluetooth: HCI UART protocol H4 registered
[ 14.954769] Bluetooth: HCI UART protocol Three-wire (H5) registered
[ 14.954879] Bluetooth: HCI UART protocol Broadcom registered
[ 15.096767] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[ 15.096773] Bluetooth: BNEP filters: protocol multicast
[ 15.096783] Bluetooth: BNEP socket layer initialized
[ 16.568494] usb 2-2: new SuperSpeed Gen 1 USB device number 2 using xhci_hcd
[ 16.599726] usb 2-2: New USB device found, idVendor=1058, idProduct=0a10, bcdDevice=81.36
[ 16.599734] usb 2-2: New USB device strings: Mfr=1, Product=2, SerialNumber=5
[ 16.599739] usb 2-2: Product: JMS56x Series
[ 16.599744] usb 2-2: Manufacturer: JMicron
[ 16.599749] usb 2-2: SerialNumber: 1234567890123
[ 16.610415] scsi host0: uas
[ 16.611493] scsi 0:0:0:0: Direct-Access ST2000LM 015-2E8174 8136 PQ: 0 ANSI: 6
[ 16.614185] scsi 0:0:0:1: Direct-Access ST2000LM 015-2E8174 8136 PQ: 0 ANSI: 6
[ 16.614290] sd 0:0:0:0: [sda] 3907029168 512-byte logical blocks: (2.00 TB/1.82 TiB)
[ 16.614576] sd 0:0:0:0: [sda] Write Protect is off
[ 16.614582] sd 0:0:0:0: [sda] Mode Sense: 67 00 10 08
[ 16.615118] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, supports DPO and FUA
[ 16.615894] sd 0:0:0:0: [sda] Optimal transfer size 33553920 bytes
[ 16.616680] sd 0:0:0:1: [sdb] 3907029168 512-byte logical blocks: (2.00 TB/1.82 TiB)
[ 16.616955] sd 0:0:0:1: [sdb] Write Protect is off
[ 16.616965] sd 0:0:0:1: [sdb] Mode Sense: 67 00 10 08
[ 16.617493] sd 0:0:0:1: [sdb] Write cache: enabled, read cache: enabled, supports DPO and FUA
[ 16.618242] sd 0:0:0:1: [sdb] Optimal transfer size 33553920 bytes
[ 16.672995] sd 0:0:0:0: Attached scsi generic sg0 type 0
[ 16.673316] sd 0:0:0:1: Attached scsi generic sg1 type 0
[ 16.682954] sd 0:0:0:0: [sda] Attached SCSI disk
[ 16.683783] sd 0:0:0:1: [sdb] Attached SCSI disk
[ 17.518483] usb 2-1: new SuperSpeed Gen 1 USB device number 3 using xhci_hcd
[ 17.559724] usb 2-1: New USB device found, idVendor=1058, idProduct=0a10, bcdDevice=81.36
[ 17.559731] usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=5
[ 17.559737] usb 2-1: Product: JMS56x Series
[ 17.559742] usb 2-1: Manufacturer: JMicron
[ 17.559746] usb 2-1: SerialNumber: 1234567890123
[ 17.574717] scsi host1: uas
[ 17.580607] scsi 1:0:0:0: Direct-Access ST2000LM 015-2E8174 8136 PQ: 0 ANSI: 6
[ 17.581689] sd 1:0:0:0: Attached scsi generic sg2 type 0
[ 17.584090] sd 1:0:0:0: [sdc] 3907029168 512-byte logical blocks: (2.00 TB/1.82 TiB)
[ 17.585587] sd 1:0:0:0: [sdc] Write Protect is off
[ 17.585596] sd 1:0:0:0: [sdc] Mode Sense: 67 00 10 08
[ 17.586070] sd 1:0:0:0: [sdc] Write cache: enabled, read cache: enabled, supports DPO and FUA
[ 17.586797] sd 1:0:0:0: [sdc] Optimal transfer size 33553920 bytes
[ 17.614732] scsi 1:0:0:1: Direct-Access ST2000LM 015-2E8174 8136 PQ: 0 ANSI: 6
[ 17.615943] sd 1:0:0:1: Attached scsi generic sg3 type 0
[ 17.617163] sd 1:0:0:1: [sdd] 3907029168 512-byte logical blocks: (2.00 TB/1.82 TiB)
[ 17.617469] sd 1:0:0:1: [sdd] Write Protect is off
[ 17.617476] sd 1:0:0:1: [sdd] Mode Sense: 67 00 10 08
[ 17.618020] sd 1:0:0:1: [sdd] Write cache: enabled, read cache: enabled, supports DPO and FUA
[ 17.618650] sd 1:0:0:0: [sdc] Attached SCSI disk
[ 17.618825] sd 1:0:0:1: [sdd] Optimal transfer size 33553920 bytes
[ 17.656948] sd 1:0:0:1: [sdd] Attached SCSI disk

#9

We need to look at the dmesg after some files has been written.

#10

Ok here it is. I will split it in two messages because I have reached the post’s character limits.
Part1:

root@raspberrypi:/home/pi# dmesg
[ 0.000000] Booting Linux on physical CPU 0x0
[ 0.000000] Linux version 4.19.118-v7l+ (dom@buildbot) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611)) #1311 SMP Mon Apr 27 14:26:42 BST 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] cma: Reserved 256 MiB at 0x000000001ec00000
[ 0.000000] On node 0 totalpages: 1012736
[ 0.000000] DMA zone: 1728 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] random: get_random_bytes called from start_kernel+0xc0/0x4e8 with crng_init=0
[ 0.000000] percpu: Embedded 17 pages/cpu s36928 r8192 d24512 u69632
[ 0.000000] pcpu-alloc: s36928 r8192 d24512 u69632 alloc=17*4096
[ 0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
[ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1011008
[ 0.000000] Kernel command line: coherent_pool=1M 8250.nr_uarts=0 cma=64M snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 cma=256M smsc95xx.macaddr=DC:A6:32:83:31:44 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 console=ttyS0,115200 console=tty1 root=PARTUUID=771df6e1-02 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait
[ 0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
[ 0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
[ 0.000000] Memory: 3735552K/4050944K available (8192K kernel code, 690K rwdata, 2420K rodata, 2048K init, 860K bss, 53248K reserved, 262144K cma-reserved, 3264512K highmem)
[ 0.000000] Virtual kernel memory layout:
vector : 0xffff0000 - 0xffff1000 ( 4 kB)
fixmap : 0xffc00000 - 0xfff00000 (3072 kB)
vmalloc : 0xf0800000 - 0xff800000 ( 240 MB)
lowmem : 0xc0000000 - 0xf0000000 ( 768 MB)
pkmap : 0xbfe00000 - 0xc0000000 ( 2 MB)
modules : 0xbf000000 - 0xbfe00000 ( 14 MB)
.text : 0x(ptrval) - 0x(ptrval) (10208 kB)
.init : 0x(ptrval) - 0x(ptrval) (2048 kB)
.data : 0x(ptrval) - 0x(ptrval) ( 691 kB)
.bss : 0x(ptrval) - 0x(ptrval) ( 861 kB)
[ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[ 0.000000] ftrace: allocating 28928 entries in 85 pages
[ 0.000000] rcu: Hierarchical RCU implementation.
[ 0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
[ 0.000000] GIC: Using split EOI/Deactivate mode
[ 0.000000] arch_timer: cp15 timer(s) running at 54.00MHz (phys).
[ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns
[ 0.000005] sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns
[ 0.000022] Switching to timer-based delay loop, resolution 18ns
[ 0.000255] Console: colour dummy device 80x30
[ 0.000721] console [tty1] enabled
[ 0.000776] Calibrating delay loop (skipped), value calculated using timer frequency… 108.00 BogoMIPS (lpj=540000)
[ 0.000813] pid_max: default: 32768 minimum: 301
[ 0.001093] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
[ 0.001125] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
[ 0.001890] CPU: Testing write buffer coherency: ok
[ 0.002316] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[ 0.002970] Setting up static identity map for 0x200000 - 0x20003c
[ 0.003143] rcu: Hierarchical SRCU implementation.
[ 0.004017] smp: Bringing up secondary CPUs …
[ 0.004840] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[ 0.005768] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
[ 0.006653] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
[ 0.006772] smp: Brought up 1 node, 4 CPUs
[ 0.006840] SMP: Total of 4 processors activated (432.00 BogoMIPS).
[ 0.006863] CPU: All CPU(s) started in HYP mode.
[ 0.006883] CPU: Virtualization extensions available.
[ 0.007685] devtmpfs: initialized
[ 0.018304] VFP support v0.3: implementor 41 architecture 3 part 40 variant 8 rev 0
[ 0.018528] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[ 0.018570] futex hash table entries: 1024 (order: 4, 65536 bytes)
[ 0.025936] pinctrl core: initialized pinctrl subsystem
[ 0.026825] NET: Registered protocol family 16
[ 0.029808] DMA: preallocated 1024 KiB pool for atomic coherent allocations
[ 0.031336] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[ 0.031368] hw-breakpoint: maximum watchpoint size is 8 bytes.
[ 0.031572] Serial: AMBA PL011 UART driver
[ 0.034815] bcm2835-mbox fe00b880.mailbox: mailbox enabled
[ 0.050022] raspberrypi-firmware soc:firmware: Attached to firmware from 2020-06-01 13:23, variant start
[ 0.060039] raspberrypi-firmware soc:firmware: Firmware hash is 6379679d1ec6a8c746d7e77e015f5b56b939976f
[ 0.102861] bcm2835-dma fe007000.dma: DMA legacy API manager at (ptrval), dmachans=0x1
[ 0.105993] vgaarb: loaded
[ 0.106370] SCSI subsystem initialized
[ 0.106582] usbcore: registered new interface driver usbfs
[ 0.106648] usbcore: registered new interface driver hub
[ 0.106730] usbcore: registered new device driver usb
[ 0.108063] clocksource: Switched to clocksource arch_sys_counter
[ 0.186161] VFS: Disk quotas dquot_6.6.0
[ 0.186262] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[ 0.186450] FS-Cache: Loaded
[ 0.186640] CacheFiles: Loaded
[ 0.187164] simple-framebuffer: probe of 0.framebuffer failed with error -12
[ 0.196432] NET: Registered protocol family 2
[ 0.197098] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes)
[ 0.197143] TCP established hash table entries: 8192 (order: 3, 32768 bytes)
[ 0.197224] TCP bind hash table entries: 8192 (order: 4, 65536 bytes)
[ 0.197309] TCP: Hash tables configured (established 8192 bind 8192)
[ 0.197445] UDP hash table entries: 512 (order: 2, 16384 bytes)
[ 0.197489] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
[ 0.197707] NET: Registered protocol family 1
[ 0.198318] RPC: Registered named UNIX socket transport module.
[ 0.198345] RPC: Registered udp transport module.
[ 0.198367] RPC: Registered tcp transport module.
[ 0.198388] RPC: Registered tcp NFSv4.1 backchannel transport module.
[ 0.198416] PCI: CLS 0 bytes, default 64
[ 0.200210] hw perfevents: enabled with armv7_cortex_a15 PMU driver, 7 counters available
[ 0.202336] Initialise system trusted keyrings
[ 0.202536] workingset: timestamp_bits=14 max_order=20 bucket_order=6
[ 0.211608] FS-Cache: Netfs ‘nfs’ registered for caching
[ 0.212140] NFS: Registering the id_resolver key type
[ 0.212190] Key type id_resolver registered
[ 0.212212] Key type id_legacy registered
[ 0.212243] nfs4filelayout_init: NFSv4 File Layout Driver Registering…
[ 0.216791] Key type asymmetric registered
[ 0.216819] Asymmetric key parser ‘x509’ registered
[ 0.216942] bounce: pool size: 64 pages
[ 0.216996] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250)
[ 0.217184] io scheduler noop registered
[ 0.217209] io scheduler deadline registered (default)
[ 0.217380] io scheduler cfq registered
[ 0.217403] io scheduler mq-deadline registered (default)
[ 0.217426] io scheduler kyber registered
[ 0.248721] brcm-pcie fd500000.pcie: dmabounce: initialised - 32768 kB, threshold 0x00000000c0000000
[ 0.248769] brcm-pcie fd500000.pcie: could not get clock
[ 0.248843] brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges:
[ 0.248895] brcm-pcie fd500000.pcie: MEM 0x600000000…0x603ffffff -> 0xf8000000
[ 0.308092] brcm-pcie fd500000.pcie: link up, 5.0 Gbps x1 (!SSC)
[ 0.308404] brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00
[ 0.308435] pci_bus 0000:00: root bus resource [bus 00-01]
[ 0.308465] pci_bus 0000:00: root bus resource [mem 0x600000000-0x603ffffff] (bus address [0xf8000000-0xfbffffff])
[ 0.308528] pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400
[ 0.308667] pci 0000:00:00.0: PME# supported from D0 D3hot
[ 0.311435] PCI: bus0: Fast back to back transfers disabled
[ 0.311468] pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring
[ 0.311688] pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330
[ 0.311812] pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit]
[ 0.312128] pci 0000:01:00.0: PME# supported from D0 D3cold
[ 0.314869] PCI: bus1: Fast back to back transfers disabled
[ 0.314899] pci_bus 0000:01: busn_res: [bus 01] end is updated to 01
[ 0.314945] pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff]
[ 0.314981] pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit]
[ 0.315069] pci 0000:00:00.0: PCI bridge to [bus 01]
[ 0.315099] pci 0000:00:00.0: bridge window [mem 0x600000000-0x6000fffff]
[ 0.315320] pcieport 0000:00:00.0: enabling device (0140 -> 0142)
[ 0.315510] pcieport 0000:00:00.0: Signaling PME with IRQ 53
[ 0.315688] pcieport 0000:00:00.0: AER enabled with IRQ 53
[ 0.315873] pci 0000:01:00.0: enabling device (0140 -> 0142)
[ 0.319984] iproc-rng200 fe104000.rng: hwrng registered
[ 0.320220] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
[ 0.320733] vc-sm: Videocore shared memory driver
[ 0.321144] gpiomem-bcm2835 fe200000.gpiomem: Initialised: Registers at 0xfe200000
[ 0.331334] brd: module loaded
[ 0.340959] loop: module loaded
[ 0.341721] Loading iSCSI transport class v2.0-870.
[ 0.343428] libphy: Fixed MDIO Bus: probed
[ 0.343862] bcmgenet fd580000.genet: failed to get enet clock
[ 0.343893] bcmgenet fd580000.genet: GENET 5.0 EPHY: 0x0000
[ 0.343923] bcmgenet fd580000.genet: failed to get enet-wol clock
[ 0.343953] bcmgenet fd580000.genet: failed to get enet-eee clock
[ 0.343990] bcmgenet: Skipping UMAC reset
[ 0.344245] unimac-mdio unimac-mdio.-19: DMA mask not set
[ 0.358092] libphy: bcmgenet MII bus: probed
[ 0.398577] unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus at 0x(ptrval)
[ 0.399497] usbcore: registered new interface driver r8152
[ 0.399579] usbcore: registered new interface driver lan78xx
[ 0.399648] usbcore: registered new interface driver smsc95xx
[ 0.399959] xhci_hcd 0000:01:00.0: xHCI Host Controller
[ 0.400004] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1
[ 0.402242] xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x0000001000000890
[ 0.402580] genirq: irq_chip Brcm_MSI did not update eff. affinity mask of irq 54
[ 0.403462] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.19
[ 0.403496] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 0.403525] usb usb1: Product: xHCI Host Controller
[ 0.403549] usb usb1: Manufacturer: Linux 4.19.118-v7l+ xhci-hcd
[ 0.403573] usb usb1: SerialNumber: 0000:01:00.0
[ 0.404103] hub 1-0:1.0: USB hub found
[ 0.404196] hub 1-0:1.0: 1 port detected
[ 0.404676] xhci_hcd 0000:01:00.0: xHCI Host Controller
[ 0.404712] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2
[ 0.404756] xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed
[ 0.405174] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 4.19
[ 0.405207] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 0.405235] usb usb2: Product: xHCI Host Controller
[ 0.405259] usb usb2: Manufacturer: Linux 4.19.118-v7l+ xhci-hcd
[ 0.405282] usb usb2: SerialNumber: 0000:01:00.0
[ 0.405785] hub 2-0:1.0: USB hub found
[ 0.405855] hub 2-0:1.0: 4 ports detected
[ 0.407035] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[ 0.407243] dwc_otg: FIQ enabled
[ 0.407252] dwc_otg: NAK holdoff enabled
[ 0.407260] dwc_otg: FIQ split-transaction FSM enabled
[ 0.407272] Module dwc_common_port init
[ 0.407495] usbcore: registered new interface driver uas
[ 0.407604] usbcore: registered new interface driver usb-storage
[ 0.407792] mousedev: PS/2 mouse device common for all mice
[ 0.409023] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
[ 0.410649] sdhci: Secure Digital Host Controller Interface driver
[ 0.410674] sdhci: Copyright© Pierre Ossman
[ 0.411082] mmc-bcm2835 fe300000.mmcnr: could not get clk, deferring probe
[ 0.411492] sdhci-pltfm: SDHCI platform and OF driver helper
[ 0.414530] ledtrig-cpu: registered to indicate activity on CPUs
[ 0.414689] hidraw: raw HID events driver © Jiri Kosina
[ 0.414824] usbcore: registered new interface driver usbhid
[ 0.414846] usbhid: USB HID core driver
[ 0.415629] vchiq: vchiq_init_state: slot_zero = (ptrval), is_master = 0
[ 0.417386] [vc_sm_connected_init]: start
[ 0.426209] [vc_sm_connected_init]: end - returning 0
[ 0.427317] Initializing XFRM netlink socket
[ 0.427360] NET: Registered protocol family 17
[ 0.427473] Key type dns_resolver registered
[ 0.427860] Registering SWP/SWPB emulation handler
[ 0.428497] registered taskstats version 1
[ 0.428527] Loading compiled-in X.509 certificates
[ 0.435438] uart-pl011 fe201000.serial: cts_event_workaround enabled
[ 0.435520] fe201000.serial: ttyAMA0 at MMIO 0xfe201000 (irq = 34, base_baud = 0) is a PL011 rev2
[ 0.440656] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
[ 0.441195] brcmstb_thermal fd5d2200.thermal: registered AVS TMON of-sensor driver
[ 0.441862] mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0
[ 0.441890] mmc-bcm2835 fe300000.mmcnr: DMA channel allocated
[ 0.467931] sdhci-iproc fe340000.emmc2: Linked as a consumer to regulator.3
[ 0.468039] sdhci-iproc fe340000.emmc2: Linked as a consumer to regulator.4
[ 0.485365] mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
[ 0.486976] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[ 0.488584] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[ 0.491452] mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
[ 0.493055] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[ 0.500781] mmc0: SDHCI controller on fe340000.emmc2 [fe340000.emmc2] using ADMA
[ 0.503309] of_cfs_init
[ 0.503403] of_cfs_init: OK
[ 0.503992] Waiting for root device PARTUUID=771df6e1-02…
[ 0.548022] random: fast init done
[ 0.560720] mmc1: new high speed SDIO card at address 0001
[ 0.604345] mmc0: new ultra high speed DDR50 SDXC card at address aaaa
[ 0.605478] mmcblk0: mmc0:aaaa SC64G 59.5 GiB
[ 0.607631] mmcblk0: p1 p2
[ 0.622447] EXT4-fs (mmcblk0p2): INFO: recovery required on readonly filesystem
[ 0.622483] EXT4-fs (mmcblk0p2): write access will be enabled during recovery
[ 0.731096] EXT4-fs (mmcblk0p2): recovery complete
[ 0.733117] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
[ 0.733187] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
[ 0.739197] devtmpfs: mounted
[ 0.746412] Freeing unused kernel memory: 2048K
[ 0.746668] Run /sbin/init as init process
[ 0.768116] usb 1-1: new high-speed USB device number 2 using xhci_hcd
[ 0.950733] usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21
[ 0.950801] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[ 0.950830] usb 1-1: Product: USB2.0 Hub
[ 0.952931] hub 1-1:1.0: USB hub found
[ 0.953268] hub 1-1:1.0: 4 ports detected
[ 1.142347] systemd[1]: System time before build time, advancing clock.
[ 1.236823] NET: Registered protocol family 10
[ 1.238104] Segment Routing with IPv6
[ 1.268676] 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)
[ 1.269395] systemd[1]: Detected architecture arm.
[ 1.279726] systemd[1]: Set hostname to .
[ 1.665066] random: lvmconfig: uninitialized urandom read (4 bytes read)
[ 2.224578] 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.
[ 2.273263] random: systemd: uninitialized urandom read (16 bytes read)
[ 2.288594] systemd[1]: sysinit.target: Found ordering cycle on local-fs.target/start
[ 2.288648] systemd[1]: sysinit.target: Found dependency on srv-dev\x2ddisk\x2dby\x2dlabel\x2dstorage.mount/start
[ 2.288691] systemd[1]: sysinit.target: Found dependency on rockpi-sata.service/start
[ 2.288731] systemd[1]: sysinit.target: Found dependency on basic.target/start
[ 2.288769] systemd[1]: sysinit.target: Found dependency on sockets.target/start
[ 2.288808] systemd[1]: sysinit.target: Found dependency on triggerhappy.socket/start
[ 2.294254] random: systemd: uninitialized urandom read (16 bytes read)
[ 2.382795] Installing knfsd (copyright © 1996 okir@monad.swb.de).
[ 3.242562] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
[ 3.387066] systemd-journald[151]: Received request to flush runtime journal from PID 1
[ 4.194729] Driver for 1-wire Dallas network protocol.

#11

Part 2:
[ 4.209432] gpio-4 (onewire@0): enforced open drain please flag it properly in DT/ACPI DSDT/board file
[ 4.242359] rpivid-mem feb00000.hevc-decoder: rpivid-hevcmem initialised: Registers at 0xfeb00000 length 0x0000ffff
[ 4.242933] rpivid-mem feb10000.rpivid-local-intc: rpivid-intcmem initialised: Registers at 0xfeb10000 length 0x00000fff
[ 4.243469] rpivid-mem feb20000.h264-decoder: rpivid-h264mem initialised: Registers at 0xfeb20000 length 0x0000ffff
[ 4.243989] rpivid-mem feb30000.vp9-decoder: rpivid-vp9mem initialised: Registers at 0xfeb30000 length 0x0000ffff
[ 4.260865] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
[ 4.262999] bcm2835_vc_sm_cma_probe: Videocore shared memory driver
[ 4.263020] [vc_sm_connected_init]: start
[ 4.263273] media: Linux media interface: v0.10
[ 4.296995] [vc_sm_connected_init]: installed successfully
[ 4.326163] videodev: Linux video capture interface: v2.00
[ 4.387453] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
[ 4.405761] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[ 4.408204] bcm2835_audio soc:audio: card created with 8 channels
[ 4.418601] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
[ 4.488415] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
[ 4.550016] bcm2835-codec bcm2835-codec: Device registered as /dev/video10
[ 4.550068] bcm2835-codec bcm2835-codec: Loaded V4L2 decode
[ 4.568363] bcm2835-codec bcm2835-codec: Device registered as /dev/video11
[ 4.568397] bcm2835-codec bcm2835-codec: Loaded V4L2 encode
[ 4.601201] bcm2835-codec bcm2835-codec: Device registered as /dev/video12
[ 4.601233] bcm2835-codec bcm2835-codec: Loaded V4L2 isp
[ 4.647129] bcmgenet: Skipping UMAC reset
[ 4.683544] [drm] Initialized v3d 1.0.0 20180419 for fec00000.v3d on minor 0
[ 4.720175] [drm] No displays found. Consider forcing hotplug if HDMI is attached
[ 4.720269] vc4-drm soc:gpu: bound fe600000.firmwarekms (ops vc4_fkms_ops [vc4])
[ 4.721198] [drm] Initialized vc4 0.0.0 20140616 for soc:gpu on minor 1
[ 4.721210] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
[ 4.721220] [drm] No driver support for vblank timestamp query.
[ 4.721229] [drm] Setting vblank_disable_immediate to false because get_vblank_timestamp == NULL
[ 4.748786] bcmgenet fd580000.genet: configuring instance for external RGMII (no delay)
[ 4.749089] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[ 4.757506] cfg80211: Loading compiled-in X.509 certificates for regulatory database
[ 4.850051] cfg80211: Loaded X.509 cert ‘sforshee: 00b28ddf47aef9cea7’
[ 4.908371] brcmfmac: F1 signature read @0x18000000=0x15264345
[ 4.916016] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[ 4.916745] usbcore: registered new interface driver brcmfmac
[ 5.164335] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[ 5.177587] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Mar 2 2020 23:30:41 version 7.45.202 (r724630 CY) FWID 01-72f6ece2
[ 5.780626] bcmgenet fd580000.genet eth0: Link is Down
[ 6.069348] uart-pl011 fe201000.serial: no DMA platform data
[ 6.491793] Adding 102396k swap on /var/swap. Priority:-2 extents:1 across:102396k SSFS
[ 7.298043] NFSD: starting 90-second grace period (net f0000041)
[ 7.492304] random: crng init done
[ 7.492321] random: 7 urandom warning(s) missed due to ratelimiting
[ 8.137870] i2c /dev entries driver
[ 9.928405] bcmgenet fd580000.genet eth0: Link is Up - 1Gbps/Full - flow control rx/tx
[ 9.928446] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[ 12.935565] Bluetooth: Core ver 2.22
[ 12.935626] NET: Registered protocol family 31
[ 12.935634] Bluetooth: HCI device and connection manager initialized
[ 12.935651] Bluetooth: HCI socket layer initialized
[ 12.935664] Bluetooth: L2CAP socket layer initialized
[ 12.935700] Bluetooth: SCO socket layer initialized
[ 12.949859] Bluetooth: HCI UART driver ver 2.3
[ 12.949871] Bluetooth: HCI UART protocol H4 registered
[ 12.949940] Bluetooth: HCI UART protocol Three-wire (H5) registered
[ 12.950137] Bluetooth: HCI UART protocol Broadcom registered
[ 13.108569] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[ 13.108575] Bluetooth: BNEP filters: protocol multicast
[ 13.108586] Bluetooth: BNEP socket layer initialized
[ 14.618547] usb 2-2: new SuperSpeed Gen 1 USB device number 2 using xhci_hcd
[ 14.649685] usb 2-2: New USB device found, idVendor=1058, idProduct=0a10, bcdDevice=81.36
[ 14.649692] usb 2-2: New USB device strings: Mfr=1, Product=2, SerialNumber=5
[ 14.649697] usb 2-2: Product: JMS56x Series
[ 14.649703] usb 2-2: Manufacturer: JMicron
[ 14.649708] usb 2-2: SerialNumber: 1234567890123
[ 14.665119] scsi host0: uas
[ 14.666246] scsi 0:0:0:0: Direct-Access ST2000LM 015-2E8174 8136 PQ: 0 ANSI: 6
[ 14.668267] scsi 0:0:0:1: Direct-Access ST2000LM 015-2E8174 8136 PQ: 0 ANSI: 6
[ 14.670535] sd 0:0:0:0: [sda] 3907029168 512-byte logical blocks: (2.00 TB/1.82 TiB)
[ 14.670789] sd 0:0:0:0: [sda] Write Protect is off
[ 14.670797] sd 0:0:0:0: [sda] Mode Sense: 67 00 10 08
[ 14.671232] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, supports DPO and FUA
[ 14.671986] sd 0:0:0:0: [sda] Optimal transfer size 33553920 bytes
[ 14.704401] sd 0:0:0:1: [sdb] 3907029168 512-byte logical blocks: (2.00 TB/1.82 TiB)
[ 14.704674] sd 0:0:0:1: [sdb] Write Protect is off
[ 14.704681] sd 0:0:0:1: [sdb] Mode Sense: 67 00 10 08
[ 14.705239] sd 0:0:0:1: [sdb] Write cache: enabled, read cache: enabled, supports DPO and FUA
[ 14.705888] sd 0:0:0:0: [sda] Attached SCSI disk
[ 14.706062] sd 0:0:0:1: [sdb] Optimal transfer size 33553920 bytes
[ 14.731044] sd 0:0:0:0: Attached scsi generic sg0 type 0
[ 14.731139] sd 0:0:0:1: Attached scsi generic sg1 type 0
[ 14.738122] sd 0:0:0:1: [sdb] Attached SCSI disk
[ 15.748535] usb 2-1: new SuperSpeed Gen 1 USB device number 3 using xhci_hcd
[ 15.779775] usb 2-1: New USB device found, idVendor=1058, idProduct=0a10, bcdDevice=81.36
[ 15.779782] usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=5
[ 15.779787] usb 2-1: Product: JMS56x Series
[ 15.779792] usb 2-1: Manufacturer: JMicron
[ 15.779797] usb 2-1: SerialNumber: 1234567890123
[ 15.791745] scsi host1: uas
[ 15.796813] scsi 1:0:0:0: Direct-Access ST2000LM 015-2E8174 8136 PQ: 0 ANSI: 6
[ 15.798123] sd 1:0:0:0: Attached scsi generic sg2 type 0
[ 15.798921] sd 1:0:0:0: [sdc] 3907029168 512-byte logical blocks: (2.00 TB/1.82 TiB)
[ 15.802739] sd 1:0:0:0: [sdc] Write Protect is off
[ 15.802747] sd 1:0:0:0: [sdc] Mode Sense: 67 00 10 08
[ 15.803269] sd 1:0:0:0: [sdc] Write cache: enabled, read cache: enabled, supports DPO and FUA
[ 15.804091] sd 1:0:0:0: [sdc] Optimal transfer size 33553920 bytes
[ 15.804284] scsi 1:0:0:1: Direct-Access ST2000LM 015-2E8174 8136 PQ: 0 ANSI: 6
[ 15.806441] sd 1:0:0:1: Attached scsi generic sg3 type 0
[ 15.837502] sd 1:0:0:1: [sdd] 3907029168 512-byte logical blocks: (2.00 TB/1.82 TiB)
[ 15.837736] sd 1:0:0:1: [sdd] Write Protect is off
[ 15.837744] sd 1:0:0:1: [sdd] Mode Sense: 67 00 10 08
[ 15.838355] sd 1:0:0:1: [sdd] Write cache: enabled, read cache: enabled, supports DPO and FUA
[ 15.839195] sd 1:0:0:1: [sdd] Optimal transfer size 33553920 bytes
[ 15.840400] sd 1:0:0:0: [sdc] Attached SCSI disk
[ 15.876180] sd 1:0:0:1: [sdd] Attached SCSI disk
[ 79.544216] sda: sda1
[ 79.817609] sda: sda1
[ 243.690855] INFO: task blkid:2167 blocked for more than 120 seconds.
[ 243.690861] Tainted: G C 4.19.118-v7l+ #1311
[ 243.690865] “echo 0 > /proc/sys/kernel/hung_task_timeout_secs” disables this message.
[ 243.690870] blkid D 0 2167 2166 0x00000000
[ 243.690894] [] (__schedule) from [] (schedule+0x50/0xa8)
[ 243.690904] [] (schedule) from [] (schedule_preempt_disabled+0x18/0x1c)
[ 243.690912] [] (schedule_preempt_disabled) from [] (__mutex_lock.constprop.5+0x1a8/0x590)
[ 243.690921] [] (__mutex_lock.constprop.5) from [] (__mutex_lock_slowpath+0x1c/0x20)
[ 243.690929] [] (__mutex_lock_slowpath) from [] (mutex_lock+0x5c/0x60)
[ 243.690940] [] (mutex_lock) from [] (__blkdev_get+0x140/0x528)
[ 243.690950] [] (__blkdev_get) from [] (blkdev_get+0x170/0x3c4)
[ 243.690957] [] (blkdev_get) from [] (blkdev_open+0x90/0x9c)
[ 243.690966] [] (blkdev_open) from [] (do_dentry_open+0x23c/0x3c0)
[ 243.690975] [] (do_dentry_open) from [] (vfs_open+0x3c/0x40)
[ 243.690984] [] (vfs_open) from [] (path_openat+0x1e4/0x1014)
[ 243.690992] [] (path_openat) from [] (do_filp_open+0x80/0xec)
[ 243.690999] [] (do_filp_open) from [] (do_sys_open+0x140/0x1f0)
[ 243.691006] [] (do_sys_open) from [] (sys_openat+0x1c/0x20)
[ 243.691015] [] (sys_openat) from [] (ret_fast_syscall+0x0/0x28)
[ 243.691019] Exception stack(0xd07a3fa8 to 0xd07a3ff0)
[ 243.691025] 3fa0: 01fd4c18 00035c50 ffffff9c 01fd5368 000a0000 00000000
[ 243.691031] 3fc0: 01fd4c18 00035c50 00035c50 00000142 5efa25d7 b6fa21e0 defa25d7 b6f89a10
[ 243.691036] 3fe0: b6f47000 bed57f70 b6f5cab8 b6ec2464
[ 243.691043] INFO: task blkid:2202 blocked for more than 120 seconds.
[ 243.691047] Tainted: G C 4.19.118-v7l+ #1311
[ 243.691051] “echo 0 > /proc/sys/kernel/hung_task_timeout_secs” disables this message.
[ 243.691055] blkid D 0 2202 2201 0x00000000
[ 243.691066] [] (__schedule) from [] (schedule+0x50/0xa8)
[ 243.691074] [] (schedule) from [] (schedule_preempt_disabled+0x18/0x1c)
[ 243.691082] [] (schedule_preempt_disabled) from [] (__mutex_lock.constprop.5+0x1a8/0x590)
[ 243.691090] [] (__mutex_lock.constprop.5) from [] (__mutex_lock_slowpath+0x1c/0x20)
[ 243.691097] [] (__mutex_lock_slowpath) from [] (mutex_lock+0x5c/0x60)
[ 243.691105] [] (mutex_lock) from [] (__blkdev_get+0x140/0x528)
[ 243.691113] [] (__blkdev_get) from [] (blkdev_get+0x170/0x3c4)
[ 243.691120] [] (blkdev_get) from [] (blkdev_open+0x90/0x9c)
[ 243.691127] [] (blkdev_open) from [] (do_dentry_open+0x23c/0x3c0)
[ 243.691134] [] (do_dentry_open) from [] (vfs_open+0x3c/0x40)
[ 243.691142] [] (vfs_open) from [] (path_openat+0x1e4/0x1014)
[ 243.691149] [] (path_openat) from [] (do_filp_open+0x80/0xec)
[ 243.691155] [] (do_filp_open) from [] (do_sys_open+0x140/0x1f0)
[ 243.691162] [] (do_sys_open) from [] (sys_openat+0x1c/0x20)
[ 243.691169] [] (sys_openat) from [] (ret_fast_syscall+0x0/0x28)
[ 243.691173] Exception stack(0xc319dfa8 to 0xc319dff0)
[ 243.691179] dfa0: 00f11c18 00035c50 ffffff9c 00f12368 000a0000 00000000
[ 243.691184] dfc0: 00f11c18 00035c50 00035c50 00000142 5efa25dc b6f601e0 defa25dc b6f47a10
[ 243.691189] dfe0: b6f05000 bede8f70 b6f1aab8 b6e80464
[ 243.691195] INFO: task blkid:2211 blocked for more than 120 seconds.
[ 243.691199] Tainted: G C 4.19.118-v7l+ #1311
[ 243.691203] “echo 0 > /proc/sys/kernel/hung_task_timeout_secs” disables this message.
[ 243.691206] blkid D 0 2211 2210 0x00000000
[ 243.691217] [] (__schedule) from [] (schedule+0x50/0xa8)
[ 243.691224] [] (schedule) from [] (schedule_preempt_disabled+0x18/0x1c)
[ 243.691232] [] (schedule_preempt_disabled) from [] (__mutex_lock.constprop.5+0x1a8/0x590)
[ 243.691240] [] (__mutex_lock.constprop.5) from [] (__mutex_lock_slowpath+0x1c/0x20)
[ 243.691248] [] (__mutex_lock_slowpath) from [] (mutex_lock+0x5c/0x60)
[ 243.691255] [] (mutex_lock) from [] (__blkdev_get+0x140/0x528)
[ 243.691263] [] (__blkdev_get) from [] (blkdev_get+0x170/0x3c4)
[ 243.691271] [] (blkdev_get) from [] (blkdev_open+0x90/0x9c)
[ 243.691278] [] (blkdev_open) from [] (do_dentry_open+0x23c/0x3c0)
[ 243.691284] [] (do_dentry_open) from [] (vfs_open+0x3c/0x40)
[ 243.691292] [] (vfs_open) from [] (path_openat+0x1e4/0x1014)
[ 243.691299] [] (path_openat) from [] (do_filp_open+0x80/0xec)
[ 243.691306] [] (do_filp_open) from [] (do_sys_open+0x140/0x1f0)
[ 243.691313] [] (do_sys_open) from [] (sys_openat+0x1c/0x20)
[ 243.691319] [] (sys_openat) from [] (ret_fast_syscall+0x0/0x28)
[ 243.691323] Exception stack(0xd501ffa8 to 0xd501fff0)
[ 243.691329] ffa0: 01768c18 00035c50 ffffff9c 01769368 000a0000 00000000
[ 243.691334] ffc0: 01768c18 00035c50 00035c50 00000142 5efa25dd b6ec81e0 defa25dd b6eafa10
[ 243.691339] ffe0: b6e6d000 becd2f70 b6e82ab8 b6de8464
[ 366.567950] INFO: task blkid:2167 blocked for more than 120 seconds.
[ 366.567957] Tainted: G C 4.19.118-v7l+ #1311
[ 366.567961] “echo 0 > /proc/sys/kernel/hung_task_timeout_secs” disables this message.
[ 366.567966] blkid D 0 2167 2166 0x00000000
[ 366.567987] [] (__schedule) from [] (schedule+0x50/0xa8)
[ 366.567996] [] (schedule) from [] (schedule_preempt_disabled+0x18/0x1c)
[ 366.568005] [] (schedule_preempt_disabled) from [] (__mutex_lock.constprop.5+0x1a8/0x590)
[ 366.568014] [] (__mutex_lock.constprop.5) from [] (__mutex_lock_slowpath+0x1c/0x20)
[ 366.568022] [] (__mutex_lock_slowpath) from [] (mutex_lock+0x5c/0x60)
[ 366.568032] [] (mutex_lock) from [] (__blkdev_get+0x140/0x528)
[ 366.568042] [] (__blkdev_get) from [] (blkdev_get+0x170/0x3c4)
[ 366.568049] [] (blkdev_get) from [] (blkdev_open+0x90/0x9c)
[ 366.568058] [] (blkdev_open) from [] (do_dentry_open+0x23c/0x3c0)
[ 366.568067] [] (do_dentry_open) from [] (vfs_open+0x3c/0x40)
[ 366.568075] [] (vfs_open) from [] (path_openat+0x1e4/0x1014)
[ 366.568084] [] (path_openat) from [] (do_filp_open+0x80/0xec)
[ 366.568090] [] (do_filp_open) from [] (do_sys_open+0x140/0x1f0)
[ 366.568097] [] (do_sys_open) from [] (sys_openat+0x1c/0x20)
[ 366.568106] [] (sys_openat) from [] (ret_fast_syscall+0x0/0x28)
[ 366.568110] Exception stack(0xd07a3fa8 to 0xd07a3ff0)
[ 366.568116] 3fa0: 01fd4c18 00035c50 ffffff9c 01fd5368 000a0000 00000000
[ 366.568122] 3fc0: 01fd4c18 00035c50 00035c50 00000142 5efa25d7 b6fa21e0 defa25d7 b6f89a10
[ 366.568127] 3fe0: b6f47000 bed57f70 b6f5cab8 b6ec2464
[ 366.568134] INFO: task blkid:2202 blocked for more than 120 seconds.
[ 366.568138] Tainted: G C 4.19.118-v7l+ #1311
[ 366.568142] “echo 0 > /proc/sys/kernel/hung_task_timeout_secs” disables this message.
[ 366.568146] blkid D 0 2202 2201 0x00000000
[ 366.568157] [] (__schedule) from [] (schedule+0x50/0xa8)
[ 366.568165] [] (schedule) from [] (schedule_preempt_disabled+0x18/0x1c)
[ 366.568173] [] (schedule_preempt_disabled) from [] (__mutex_lock.constprop.5+0x1a8/0x590)
[ 366.568181] [] (__mutex_lock.constprop.5) from [] (__mutex_lock_slowpath+0x1c/0x20)
[ 366.568188] [] (__mutex_lock_slowpath) from [] (mutex_lock+0x5c/0x60)
[ 366.568196] [] (mutex_lock) from [] (__blkdev_get+0x140/0x528)
[ 366.568204] [] (__blkdev_get) from [] (blkdev_get+0x170/0x3c4)
[ 366.568211] [] (blkdev_get) from [] (blkdev_open+0x90/0x9c)
[ 366.568218] [] (blkdev_open) from [] (do_dentry_open+0x23c/0x3c0)
[ 366.568225] [] (do_dentry_open) from [] (vfs_open+0x3c/0x40)
[ 366.568232] [] (vfs_open) from [] (path_openat+0x1e4/0x1014)
[ 366.568240] [] (path_openat) from [] (do_filp_open+0x80/0xec)
[ 366.568246] [] (do_filp_open) from [] (do_sys_open+0x140/0x1f0)
[ 366.568253] [] (do_sys_open) from [] (sys_openat+0x1c/0x20)
[ 366.568259] [] (sys_openat) from [] (ret_fast_syscall+0x0/0x28)
[ 366.568264] Exception stack(0xc319dfa8 to 0xc319dff0)
[ 366.568269] dfa0: 00f11c18 00035c50 ffffff9c 00f12368 000a0000 00000000
[ 366.568275] dfc0: 00f11c18 00035c50 00035c50 00000142 5efa25dc b6f601e0 defa25dc b6f47a10
[ 366.568280] dfe0: b6f05000 bede8f70 b6f1aab8 b6e80464
[ 366.568286] INFO: task blkid:2211 blocked for more than 120 seconds.
[ 366.568290] Tainted: G C 4.19.118-v7l+ #1311
[ 366.568293] “echo 0 > /proc/sys/kernel/hung_task_timeout_secs” disables this message.
[ 366.568297] blkid D 0 2211 2210 0x00000000
[ 366.568308] [] (__schedule) from [] (schedule+0x50/0xa8)
[ 366.568315] [] (schedule) from [] (schedule_preempt_disabled+0x18/0x1c)
[ 366.568323] [] (schedule_preempt_disabled) from [] (__mutex_lock.constprop.5+0x1a8/0x590)
[ 366.568331] [] (__mutex_lock.constprop.5) from [] (__mutex_lock_slowpath+0x1c/0x20)
[ 366.568339] [] (__mutex_lock_slowpath) from [] (mutex_lock+0x5c/0x60)
[ 366.568346] [] (mutex_lock) from [] (__blkdev_get+0x140/0x528)
[ 366.568354] [] (__blkdev_get) from [] (blkdev_get+0x170/0x3c4)
[ 366.568361] [] (blkdev_get) from [] (blkdev_open+0x90/0x9c)
[ 366.568368] [] (blkdev_open) from [] (do_dentry_open+0x23c/0x3c0)
[ 366.568375] [] (do_dentry_open) from [] (vfs_open+0x3c/0x40)
[ 366.568382] [] (vfs_open) from [] (path_openat+0x1e4/0x1014)
[ 366.568389] [] (path_openat) from [] (do_filp_open+0x80/0xec)
[ 366.568395] [] (do_filp_open) from [] (do_sys_open+0x140/0x1f0)
[ 366.568402] [] (do_sys_open) from [] (sys_openat+0x1c/0x20)
[ 366.568409] [] (sys_openat) from [] (ret_fast_syscall+0x0/0x28)
[ 366.568413] Exception stack(0xd501ffa8 to 0xd501fff0)
[ 366.568419] ffa0: 01768c18 00035c50 ffffff9c 01769368 000a0000 00000000
[ 366.568424] ffc0: 01768c18 00035c50 00035c50 00000142 5efa25dd b6ec81e0 defa25dd b6eafa10
[ 366.568429] ffe0: b6e6d000 becd2f70 b6e82ab8 b6de8464
[ 467.429538] sdb: sdb1
[ 469.095677] sdb: sdb1
[ 1131.752570] sdc: sdc1
[ 1131.980195] sdc: sdc1
[ 1349.637097] INFO: task blkid:13039 blocked for more than 120 seconds.
[ 1349.637103] Tainted: G C 4.19.118-v7l+ #1311
[ 1349.637108] “echo 0 > /proc/sys/kernel/hung_task_timeout_secs” disables this message.
[ 1349.637112] blkid D 0 13039 13038 0x00000000
[ 1349.637135] [] (__schedule) from [] (schedule+0x50/0xa8)
[ 1349.637145] [] (schedule) from [] (schedule_preempt_disabled+0x18/0x1c)
[ 1349.637154] [] (schedule_preempt_disabled) from [] (__mutex_lock.constprop.5+0x1a8/0x590)
[ 1349.637162] [] (__mutex_lock.constprop.5) from [] (__mutex_lock_slowpath+0x1c/0x20)
[ 1349.637170] [] (__mutex_lock_slowpath) from [] (mutex_lock+0x5c/0x60)
[ 1349.637181] [] (mutex_lock) from [] (blkdev_put+0x28/0x160)
[ 1349.637190] [] (blkdev_put) from [] (blkdev_close+0x28/0x30)
[ 1349.637200] [] (blkdev_close) from [] (__fput+0xa4/0x1e8)
[ 1349.637209] [] (__fput) from [] (____fput+0x18/0x1c)
[ 1349.637218] [] (____fput) from [] (task_work_run+0xbc/0xe0)
[ 1349.637228] [] (task_work_run) from [] (do_work_pending+0xf0/0xf4)
[ 1349.637235] [] (do_work_pending) from [] (slow_work_pending+0xc/0x20)
[ 1349.637240] Exception stack(0xd41b7fb0 to 0xd41b7ff8)
[ 1349.637245] 7fa0: 00000000 00000000 0000006c 00000000
[ 1349.637251] 7fc0: 00e032e0 00e032e0 00035c50 00000006 00000007 b6f0d1e0 befa886c 00dfdac8
[ 1349.637256] 7fe0: b6f0cf9c befa8818 b6ec7b98 b6e2e6bc 60000010 00000005
[ 1472.520071] INFO: task blkid:13039 blocked for more than 120 seconds.
[ 1472.520077] Tainted: G C 4.19.118-v7l+ #1311
[ 1472.520081] “echo 0 > /proc/sys/kernel/hung_task_timeout_secs” disables this message.
[ 1472.520086] blkid D 0 13039 13038 0x00000000
[ 1472.520107] [] (__schedule) from [] (schedule+0x50/0xa8)
[ 1472.520126] [] (schedule) from [] (schedule_preempt_disabled+0x18/0x1c)
[ 1472.520135] [] (schedule_preempt_disabled) from [] (__mutex_lock.constprop.5+0x1a8/0x590)
[ 1472.520144] [] (__mutex_lock.constprop.5) from [] (__mutex_lock_slowpath+0x1c/0x20)
[ 1472.520152] [] (__mutex_lock_slowpath) from [] (mutex_lock+0x5c/0x60)
[ 1472.520163] [] (mutex_lock) from [] (blkdev_put+0x28/0x160)
[ 1472.520174] [] (blkdev_put) from [] (blkdev_close+0x28/0x30)
[ 1472.520184] [] (blkdev_close) from [] (__fput+0xa4/0x1e8)
[ 1472.520193] [] (__fput) from [] (____fput+0x18/0x1c)
[ 1472.520202] [] (____fput) from [] (task_work_run+0xbc/0xe0)
[ 1472.520212] [] (task_work_run) from [] (do_work_pending+0xf0/0xf4)
[ 1472.520220] [] (do_work_pending) from [] (slow_work_pending+0xc/0x20)
[ 1472.520225] Exception stack(0xd41b7fb0 to 0xd41b7ff8)
[ 1472.520230] 7fa0: 00000000 00000000 0000006c 00000000
[ 1472.520236] 7fc0: 00e032e0 00e032e0 00035c50 00000006 00000007 b6f0d1e0 befa886c 00dfdac8
[ 1472.520241] 7fe0: b6f0cf9c befa8818 b6ec7b98 b6e2e6bc 60000010 00000005
[ 1506.033448] sdd: sdd1
[ 1510.551442] sdd: sdd1
[ 1946.815538] EXT4-fs (sda1): mounted filesystem with ordered data mode. Opts: user_xattr,usrjquota=aquota.user,grpjquota=aquota.group,jqfmt=vfsv0,acl
[ 1976.666878] EXT4-fs (sda1): re-mounted. Opts: acl,grpjquota=aquota.group,jqfmt=vfsv0,user_xattr,usrjquota=aquota.user
[ 1976.738645] EXT4-fs (sda1): re-mounted. Opts: acl,grpjquota=aquota.group,jqfmt=vfsv0,user_xattr,usrjquota=aquota.user
[ 1977.350672] EXT4-fs (sdb1): mounted filesystem with ordered data mode. Opts: user_xattr,usrjquota=aquota.user,grpjquota=aquota.group,jqfmt=vfsv0,acl
[ 2004.897139] EXT4-fs (sda1): re-mounted. Opts: acl,grpjquota=aquota.group,jqfmt=vfsv0,user_xattr,usrjquota=aquota.user
[ 2004.950943] EXT4-fs (sda1): re-mounted. Opts: acl,grpjquota=aquota.group,jqfmt=vfsv0,user_xattr,usrjquota=aquota.user
[ 2005.049592] EXT4-fs (sdb1): re-mounted. Opts: acl,grpjquota=aquota.group,jqfmt=vfsv0,user_xattr,usrjquota=aquota.user
[ 2005.103306] EXT4-fs (sdb1): re-mounted. Opts: acl,grpjquota=aquota.group,jqfmt=vfsv0,user_xattr,usrjquota=aquota.user
[ 2005.859357] EXT4-fs (sdc1): mounted filesystem with ordered data mode. Opts: user_xattr,usrjquota=aquota.user,grpjquota=aquota.group,jqfmt=vfsv0,acl
[ 2034.024749] EXT4-fs (sda1): re-mounted. Opts: acl,grpjquota=aquota.group,jqfmt=vfsv0,user_xattr,usrjquota=aquota.user
[ 2034.096030] EXT4-fs (sda1): re-mounted. Opts: acl,grpjquota=aquota.group,jqfmt=vfsv0,user_xattr,usrjquota=aquota.user
[ 2034.314272] EXT4-fs (sdb1): re-mounted. Opts: acl,grpjquota=aquota.group,jqfmt=vfsv0,user_xattr,usrjquota=aquota.user
[ 2034.387505] EXT4-fs (sdb1): re-mounted. Opts: acl,grpjquota=aquota.group,jqfmt=vfsv0,user_xattr,usrjquota=aquota.user
[ 2034.499073] EXT4-fs (sdc1): re-mounted. Opts: acl,grpjquota=aquota.group,jqfmt=vfsv0,user_xattr,usrjquota=aquota.user
[ 2034.566100] EXT4-fs (sdc1): re-mounted. Opts: acl,grpjquota=aquota.group,jqfmt=vfsv0,user_xattr,usrjquota=aquota.user
[ 2035.209197] EXT4-fs (sdd1): mounted filesystem with ordered data mode. Opts: user_xattr,usrjquota=aquota.user,grpjquota=aquota.group,jqfmt=vfsv0,acl

#12

From the kernel information, the hat is fine.