USB controller dying under heavy IO load

Test and fix the Kernel 5.4 features
Post Reply
linuxtardis
Posts: 11
Joined: Fri May 28, 2021 1:07 am
languages_spoken: english
ODROIDs: Odroid HC2
Has thanked: 0
Been thanked: 1 time
Contact:
Contact linuxtardis

USB controller dying under heavy IO load

Post by linuxtardis »

Hi all,

I would like to ask for help with Odroid-HC2 and the 5.4 kernel. I've been running on 4.14 so far and I/O has been rock solid there (110 days uptime!). I tried to update to 5.4 few times. Unfortunately, every time I tried it I got hit by a kernel bug. When the system is under heavy I/O load, the SSD disappears from the system.

I can more or less reliably trigger this by running btrfs scrub on an INTEL SSDSC2KG240G8. I managed to capture this log on the serial line:

Code: Select all

[  143.337804] xhci-hcd xhci-hcd.8.auto: xHCI host controller not responding, assume dead
[  143.344297] usb 4-1: cmd cmplt err -108
[  143.354832] xhci-hcd xhci-hcd.8.auto: HC died; cleaning up
[  143.406289] BTRFS error (device sda2): bdev /dev/sda2 errs: wr 0, rd 1, flush 0, corrupt 0, gen 0
[  143.414044] BTRFS error (device sda2): bdev /dev/sda2 errs: wr 0, rd 2, flush 0, corrupt 0, gen 0
[  143.422827] BTRFS error (device sda2): bdev /dev/sda2 errs: wr 0, rd 3, flush 0, corrupt 0, gen 0
[  143.431672] BTRFS error (device sda2): bdev /dev/sda2 errs: wr 0, rd 4, flush 0, corrupt 0, gen 0
[  143.433766] blk_update_request: I/O error, dev sda, sector 53553904 op 0x0:(READ) flags 0x4000 phys_seg 128 prio class 0
[  143.440757] BTRFS error (device sda2): bdev /dev/sda2 errs: wr 0, rd 5, flush 0, corrupt 0, gen 0
[  143.451113] blk_update_request: I/O error, dev sda, sector 53554928 op 0x0:(READ) flags 0x0 phys_seg 128 prio class 0
[  143.451141] BTRFS error (device sda2): bdev /dev/sda2 errs: wr 0, rd 6, flush 0, corrupt 0, gen 0
[  143.451147] blk_update_request: I/O error, dev sda, sector 53558512 op 0x0:(READ) flags 0x0 phys_seg 32 prio class 0
[  143.451163] blk_update_request: I/O error, dev sda, sector 53560304 op 0x0:(READ) flags 0x0 phys_seg 32 prio class 0
[  143.451180] blk_update_request: I/O error, dev sda, sector 53557488 op 0x0:(READ) flags 0x4000 phys_seg 128 prio class 0
[  143.451207] blk_update_request: I/O error, dev sda, sector 53558768 op 0x0:(READ) flags 0x0 phys_seg 32 prio class 0
[  143.451223] blk_update_request: I/O error, dev sda, sector 53559536 op 0x0:(READ) flags 0x0 phys_seg 96 prio class 0
[  143.451230] BTRFS error (device sda2): bdev /dev/sda2 errs: wr 0, rd 7, flush 0, corrupt 0, gen 0
[  143.451246] blk_update_request: I/O error, dev sda, sector 53560816 op 0x0:(READ) flags 0x0 phys_seg 32 prio class 0
[  143.451262] blk_update_request: I/O error, dev sda, sector 53562096 op 0x0:(READ) flags 0x0 phys_seg 32 prio class 0
[  143.451278] blk_update_request: I/O error, dev sda, sector 53562864 op 0x0:(READ) flags 0x0 phys_seg 96 prio class 0
[  143.451598] BTRFS error (device sda2): bdev /dev/sda2 errs: wr 1, rd 7, flush 0, corrupt 0, gen 0
[  143.451629] BTRFS error (device sda2): bdev /dev/sda2 errs: wr 2, rd 7, flush 0, corrupt 0, gen 0
[  143.452286] BTRFS error (device sda2): bdev /dev/sda2 errs: wr 3, rd 7, flush 0, corrupt 0, gen 0
[  143.453108] BTRFS error (device sda2): unable to fixup (regular) error at logical 236898869248 on dev /dev/sda2
[  143.453713] BTRFS error (device sda2): unable to fixup (regular) error at logical 236899393536 on dev /dev/sda2
[  143.453724] BTRFS error (device sda2): unable to fixup (regular) error at logical 236899131392 on dev /dev/sda2
[  143.453732] BTRFS error (device sda2): unable to fixup (regular) error at logical 236898873344 on dev /dev/sda2
[  143.453795] BTRFS error (device sda2): unable to fixup (regular) error at logical 236899135488 on dev /dev/sda2
[  143.453835] BTRFS error (device sda2): unable to fixup (regular) error at logical 236899139584 on dev /dev/sda2
[  143.453866] BTRFS error (device sda2): unable to fixup (regular) error at logical 236899143680 on dev /dev/sda2
[  143.453891] BTRFS error (device sda2): unable to fixup (regular) error at logical 236899147776 on dev /dev/sda2
[  143.453922] BTRFS error (device sda2): unable to fixup (regular) error at logical 236899151872 on dev /dev/sda2
[  143.453945] BTRFS error (device sda2): unable to fixup (regular) error at logical 236899155968 on dev /dev/sda2
[  144.031099] BTRFS: error (device sda2) in btrfs_commit_transaction:2280: errno=-5 IO failure (Error while writing out transaction)
[  144.041619] BTRFS: error (device sda2) in cleanup_transaction:1833: errno=-5 IO failure
[  144.066368] systemd-journald[254]: Failed to rotate /var/log/journal/de3e51515aa64f2abe61e07ccfb156a5/system.journal: Read-only file system
[  144.077634] systemd-journald[254]: Failed to rotate /var/log/journal/de3e51515aa64f2abe61e07ccfb156a5/user-1000.journal: Read-only file system
<...>
Is there anything I can do about this?

Thank you for any help and with regards,

Jakub
Top
linuxtardis
Posts: 11
Joined: Fri May 28, 2021 1:07 am
languages_spoken: english
ODROIDs: Odroid HC2
Has thanked: 0
Been thanked: 1 time
Contact:
Contact linuxtardis

Re: USB controller dying under heavy IO load

Post by linuxtardis »

I'm using Ubuntu 20.04 and the official kernel from http://deb.odroid.in/5422-s/. The 4.14 kernel comes from the 18.04 version and it works even under 20.04, but I've noticed that there have been no updates since September.
Top
linuxtardis
Posts: 11
Joined: Fri May 28, 2021 1:07 am
languages_spoken: english
ODROIDs: Odroid HC2
Has thanked: 0
Been thanked: 1 time
Contact:
Contact linuxtardis

Re: USB controller dying under heavy IO load

Post by linuxtardis »

I've managed to complete a btrfs scrub using the latest 4.14 kernel from the bionic branch, so the SSD/HW itself shouldn't be the cause.
Top
User avatar
odroid
Site Admin
Posts: 41084
Joined: Fri Feb 22, 2013 11:14 pm
languages_spoken: English, Korean
ODROIDs: ODROID
Has thanked: 3194 times
Been thanked: 1753 times
Contact:
Contact odroid

Re: USB controller dying under heavy IO load

Post by odroid »

We don't manage the Kernel 4.14 anymore due to our limited resources.

We've run a few XU4 and HC2 devices with Kernel 5.4 + Ubuntu 20.04 for several months but there was no such issue.
We used only EXT4 file system. But, I'm not sure if the issue is related to the file system or not.

BTW, did you use the UASP mode on Kernel 4.14? or the legacy mass-storage mode?
Top
linuxtardis
Posts: 11
Joined: Fri May 28, 2021 1:07 am
languages_spoken: english
ODROIDs: Odroid HC2
Has thanked: 0
Been thanked: 1 time
Contact:
Contact linuxtardis

Re: USB controller dying under heavy IO load

Post by linuxtardis »

Thank you for your quick reply, it seems that UASP is used on both kernel versions. This is the relevant section of `usb-devices` command output from 4.14; on 5.4 the driver is uas too:

Code: Select all

T:  Bus=04 Lev=01 Prnt=01 Port=00 Cnt=01 Dev#=  2 Spd=5000 MxCh= 0
D:  Ver= 3.00 Cls=00(>ifc ) Sub=00 Prot=00 MxPS= 9 #Cfgs=  1
P:  Vendor=152d ProdID=0578 Rev=31.02
S:  Manufacturer=JMicron
S:  Product=USB to ATA/ATAPI Bridge
S:  SerialNumber=0123456789ABCDEF
C:  #Ifs= 1 Cfg#= 1 Atr=80 MxPwr=896mA
I:  If#=0x0 Alt= 1 #EPs= 4 Cls=08(stor.) Sub=06 Prot=62 Driver=uas
I've noticed that I had the RAM overclock active. I've disabled it but unfortunately the crash still occurs. This time I got a different error:

Code: Select all

[  244.152353] BTRFS info (device sda2): scrub: started on devid 1
[  287.725713] sd 0:0:0:0: [sda] tag#29 uas_eh_abort_handler 0 uas-tag 21 inflight: CMD IN 
[  287.725751] sd 0:0:0:0: [sda] tag#29 CDB: opcode=0x28 28 00 01 06 03 a8 00 00 10 00
[  292.833220] xhci-hcd xhci-hcd.8.auto: xHCI host not responding to stop endpoint command.
[  292.849266] xhci-hcd xhci-hcd.8.auto: Host halt failed, -110
[  292.849282] xhci-hcd xhci-hcd.8.auto: xHCI host controller not responding, assume dead
[  292.855763] usb 4-1: cmd cmplt err -108
[  292.859555] usb 4-1: cmd cmplt err -108
[  292.863358] usb 4-1: cmd cmplt err -108
[  292.867176] usb 4-1: cmd cmplt err -108
[  292.870985] usb 4-1: cmd cmplt err -108
[  292.874813] usb 4-1: cmd cmplt err -108
[  292.878611] usb 4-1: cmd cmplt err -108
[  292.882427] usb 4-1: cmd cmplt err -108
[  292.886236] usb 4-1: cmd cmplt err -108
[  292.890049] usb 4-1: cmd cmplt err -108
[  292.895232] xhci-hcd xhci-hcd.8.auto: HC died; cleaning up
[  292.895494] sd 0:0:0:0: [sda] tag#28 uas_eh_abort_handler 0 uas-tag 9 inflight: CMD 
[  292.899322] sd 0:0:0:0: [sda] tag#28 CDB: opcode=0x28 28 00 01 05 fa 58 00 00 10 00
[  292.899549] sd 0:0:0:0: [sda] tag#27 uas_eh_abort_handler 0 uas-tag 4 inflight: CMD 
[  292.899578] sd 0:0:0:0: [sda] tag#27 CDB: opcode=0x28 28 00 01 06 03 88 00 00 10 00
[  292.899613] sd 0:0:0:0: [sda] tag#26 uas_eh_abort_handler 0 uas-tag 8 inflight: CMD 
[  292.899637] sd 0:0:0:0: [sda] tag#26 CDB: opcode=0x28 28 00 01 05 f9 c8 00 00 80 00
[  292.899673] sd 0:0:0:0: [sda] tag#25 uas_eh_abort_handler 0 uas-tag 3 inflight: CMD 
[  292.899702] sd 0:0:0:0: [sda] tag#25 CDB: opcode=0x28 28 00 01 06 03 60 00 00 10 00
[  292.899743] sd 0:0:0:0: [sda] tag#24 uas_eh_abort_handler 0 uas-tag 2 inflight: CMD 
[  292.899768] sd 0:0:0:0: [sda] tag#24 CDB: opcode=0x28 28 00 01 06 03 28 00 00 10 00
[  292.899777] usb 4-1: USB disconnect, device number 2
<...>

Below I'm attaching more logs from previous boots:

Boot dmesg from kernel 4.14 (trimmed at the end):

Code: Select all

[    0.000000] Booting Linux on physical CPU 0x100
[    0.000000] Linux version 4.14.180-178 (root@1604_builder_armhf) (gcc version 7.4.0 (Ubuntu/Linaro 7.4.0-1ubuntu1~18.04.1)) #1 SMP PREEMPT Wed Sep 2 12:39:45 -03 2020
[    0.000000] CPU: ARMv7 Processor [410fc073] revision 3 (ARMv7), cr=10c5387d
[    0.000000] CPU: div instructions available: patching division code
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
[    0.000000] OF: fdt: Machine model: Hardkernel Odroid XU4
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] cma: Reserved 128 MiB at 0xb6800000
[    0.000000] Samsung CPU ID: 0xe5422001
[    0.000000] On node 0 totalpages: 518656
[    0.000000] free_area_init_node: node 0, pgdat c0d8ed00, node_mem_map eee25000
[    0.000000]   Normal zone: 1728 pages used for memmap
[    0.000000]   Normal zone: 0 pages reserved
[    0.000000]   Normal zone: 196608 pages, LIFO batch:31
[    0.000000]   HighMem zone: 322048 pages, LIFO batch:31
[    0.000000] Running under secure firmware.
[    0.000000] random: get_random_bytes called from start_kernel+0x90/0x420 with crng_init=0
[    0.000000] percpu: Embedded 17 pages/cpu s37452 r8192 d23988 u69632
[    0.000000] pcpu-alloc: s37452 r8192 d23988 u69632 alloc=17*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 [0] 4 [0] 5 [0] 6 [0] 7 
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 516928
[    0.000000] Kernel command line: console=tty1 console=ttySAC2,115200n8 root=/dev/sda2 rootfstype=btrfs rootflags=subvol=root rootwait ro fsck.repair=yes net.ifnames=0 elevator=deadline  HPD=false vout=hdmi  smsc95xx.macaddr=00:1e:06:61:7a:39 false s5p_mfc.mem=16M
[    0.000000] hdmi: using HDMI mode
[    0.000000] PID hash table entries: 4096 (order: 2, 16384 bytes)
[    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: 1899204K/2074624K available (8192K kernel code, 656K rwdata, 2352K rodata, 1024K init, 319K bss, 44348K reserved, 131072K cma-reserved, 1157120K 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 : 0xc0008000 - 0xc0900000   (9184 kB)
                     .init : 0xc0c00000 - 0xc0d00000   (1024 kB)
                     .data : 0xc0d00000 - 0xc0da42e0   ( 657 kB)
                      .bss : 0xc0dac354 - 0xc0dfbf98   ( 320 kB)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=8, Nodes=1
[    0.000000] hperf_hmp: fast CPUs mask: 000000F0
[    0.000000] hperf_hmp: slow CPUs mask: 0000000F
[    0.000000] Preemptible hierarchical RCU implementation.
[    0.000000] 	Tasks RCU enabled.
[    0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
[    0.000000] GIC: Using split EOI/Deactivate mode
[    0.000000] Switching to timer-based delay loop, resolution 41ns
[    0.000000] clocksource: mct-frc: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 79635851949 ns
[    0.000007] sched_clock: 32 bits at 24MHz, resolution 41ns, wraps every 89478484971ns
[    0.000030] genirq: irq_chip COMBINER did not update eff. affinity mask of irq 49
[    0.001582] Console: colour dummy device 80x30
[    0.002252] console [tty1] enabled
[    0.002294] Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=96000)
[    0.002335] pid_max: default: 32768 minimum: 301
[    0.002534] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
[    0.002573] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
[    0.003403] CPU: Testing write buffer coherency: ok
[    0.004296] CPU0: thread -1, cpu 0, socket 1, mpidr 80000100
[    0.020049] Setting up static identity map for 0x40100000 - 0x40100060
[    0.020657] ARM CCI driver probed
[    0.020891] Exynos MCPM support installed
[    0.027991] Hierarchical SRCU implementation.
[    0.043992] smp: Bringing up secondary CPUs ...
[    0.072349] CPU1: thread -1, cpu 1, socket 1, mpidr 80000101
[    0.096314] CPU2: thread -1, cpu 2, socket 1, mpidr 80000102
[    0.124304] CPU3: thread -1, cpu 3, socket 1, mpidr 80000103
[    0.152342] CPU4: thread -1, cpu 0, socket 0, mpidr 80000000
[    0.152350] CPU4: Spectre v2: firmware did not set auxiliary control register IBE bit, system vulnerable
[    0.172323] CPU5: thread -1, cpu 1, socket 0, mpidr 80000001
[    0.172329] CPU5: Spectre v2: firmware did not set auxiliary control register IBE bit, system vulnerable
[    0.180633] CPU6: thread -1, cpu 2, socket 0, mpidr 80000002
[    0.180640] CPU6: Spectre v2: firmware did not set auxiliary control register IBE bit, system vulnerable
[    0.188616] CPU7: thread -1, cpu 3, socket 0, mpidr 80000003
[    0.188623] CPU7: Spectre v2: firmware did not set auxiliary control register IBE bit, system vulnerable
[    0.188863] smp: Brought up 1 node, 8 CPUs
[    0.188910] SMP: Total of 8 processors activated (384.00 BogoMIPS).
[    0.188933] CPU: All CPU(s) started in HYP mode.
[    0.188951] CPU: Virtualization extensions available.
[    0.190792] devtmpfs: initialized
[    0.207328] VFP support v0.3: implementor 41 architecture 4 part 30 variant f rev 0
[    0.207484] hperf_hmp: registered cpufreq transition notifier
[    0.207670] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.207705] futex hash table entries: 2048 (order: 5, 131072 bytes)
[    0.210518] pinctrl core: initialized pinctrl subsystem
[    0.212630] NET: Registered protocol family 16
[    0.218326] DMA: preallocated 1024 KiB pool for atomic coherent allocations
[    0.224403] cpuidle: using governor menu
[    0.225230] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[    0.225257] hw-breakpoint: maximum watchpoint size is 8 bytes.
[    0.233684] exynos-audss-clk 3810000.audss-clock-controller: epll 180633609
[    0.256108] EXYNOS5420 PMU initialized
[    0.299344] usbcore: registered new interface driver usbfs
[    0.299411] usbcore: registered new interface driver hub
[    0.299561] usbcore: registered new device driver usb
[    0.299998] i2c-gpio soc:i2c-hdmi: using pins 177 (SDA) and 178 (SCL)
[    0.300595] s3c-i2c 12c70000.i2c: slave address 0x00
[    0.300622] s3c-i2c 12c70000.i2c: bus frequency set to 378 KHz
[    0.300840] s3c-i2c 12c70000.i2c: i2c-1: S3C I2C adapter
[    0.301353] media: Linux media interface: v0.10
[    0.301407] Linux video capture interface: v2.00
[    0.301515] pps_core: LinuxPPS API ver. 1 registered
[    0.301534] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.301798] s3c2410-wdt 101d0000.watchdog: watchdog inactive, reset disabled, irq disabled
[    0.302640] Advanced Linux Sound Architecture Driver Initialized.
[    0.303874] clocksource: Switched to clocksource mct-frc
[    0.363579] VFS: Disk quotas dquot_6.6.0
[    0.363661] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    0.377207] NET: Registered protocol family 2
[    0.377783] TCP established hash table entries: 8192 (order: 3, 32768 bytes)
[    0.377879] TCP bind hash table entries: 8192 (order: 4, 65536 bytes)
[    0.378047] TCP: Hash tables configured (established 8192 bind 8192)
[    0.378160] UDP hash table entries: 512 (order: 2, 16384 bytes)
[    0.378211] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
[    0.378416] NET: Registered protocol family 1
[    0.379081] RPC: Registered named UNIX socket transport module.
[    0.379103] RPC: Registered udp transport module.
[    0.379121] RPC: Registered tcp transport module.
[    0.379139] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.379340] Trying to unpack rootfs image as initramfs...
[    0.523587] Freeing initrd memory: 11136K
[    0.533640] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 5 counters available
[    0.534631] hw perfevents: enabled with armv7_cortex_a15 PMU driver, 7 counters available
[    0.538061] audit: initializing netlink subsys (disabled)
[    0.538262] audit: type=2000 audit(0.532:1): state=initialized audit_enabled=0 res=1
[    0.538805] Initialise system trusted keyrings
[    0.538984] workingset: timestamp_bits=14 max_order=19 bucket_order=5
[    0.546071] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.546897] NFS: Registering the id_resolver key type
[    0.546930] Key type id_resolver registered
[    0.546949] Key type id_legacy registered
[    0.546977] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    0.547028] romfs: ROMFS MTD (C) 2007 Red Hat, Inc.
[    0.551603] Key type asymmetric registered
[    0.551626] Asymmetric key parser 'x509' registered
[    0.551690] bounce: pool size: 64 pages
[    0.551756] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245)
[    0.552036] io scheduler noop registered
[    0.552059] io scheduler deadline registered (default)
[    0.552216] io scheduler cfq registered
[    0.552235] io scheduler mq-deadline registered
[    0.552255] io scheduler kyber registered
[    0.552501] io scheduler bfq registered
[    0.554874] samsung-usb2-phy 12130000.phy: 12130000.phy supply vbus not found, using dummy regulator
[    0.555906] exynos5_usb3drd_phy 12100000.phy: 12100000.phy supply vbus not found, using dummy regulator
[    0.555990] exynos5_usb3drd_phy 12100000.phy: 12100000.phy supply vbus-boost not found, using dummy regulator
[    0.556354] exynos5_usb3drd_phy 12500000.phy: 12500000.phy supply vbus not found, using dummy regulator
[    0.556446] exynos5_usb3drd_phy 12500000.phy: 12500000.phy supply vbus-boost not found, using dummy regulator
[    0.561272] dma-pl330 3880000.adma: Loaded driver for PL330 DMAC-241330
[    0.561299] dma-pl330 3880000.adma: 	DBUFF-4x8bytes Num_Chans-6 Num_Peri-16 Num_Events-6
[    0.563802] dma-pl330 121a0000.pdma: Loaded driver for PL330 DMAC-241330
[    0.563829] dma-pl330 121a0000.pdma: 	DBUFF-32x4bytes Num_Chans-8 Num_Peri-32 Num_Events-32
[    0.566461] dma-pl330 121b0000.pdma: Loaded driver for PL330 DMAC-241330
[    0.566489] dma-pl330 121b0000.pdma: 	DBUFF-32x4bytes Num_Chans-8 Num_Peri-32 Num_Events-32
[    0.567311] dma-pl330 10800000.mdma: Loaded driver for PL330 DMAC-241330
[    0.567337] dma-pl330 10800000.mdma: 	DBUFF-64x8bytes Num_Chans-8 Num_Peri-1 Num_Events-32
[    0.632750] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled
[    0.635038] 12c00000.serial: ttySAC0 at MMIO 0x12c00000 (irq = 58, base_baud = 0) is a S3C6400/10
[    0.635438] 12c10000.serial: ttySAC1 at MMIO 0x12c10000 (irq = 59, base_baud = 0) is a S3C6400/10
[    0.635819] 12c20000.serial: ttySAC2 at MMIO 0x12c20000 (irq = 60, base_baud = 0) is a S3C6400/10
[    1.027730] random: fast init done
[    1.572294] console [ttySAC2] enabled
[    1.576270] 12c30000.serial: ttySAC3 at MMIO 0x12c30000 (irq = 61, base_baud = 0) is a S3C6400/10
[    1.586950] exynos-mixer 14450000.mixer: Linked as a consumer to 14650000.sysmmu
[    1.592971] iommu: Adding device 14450000.mixer to group 0
[    1.600065] exynos-hdmi 14530000.hdmi: Failed to get supply 'vdd': -517
[    1.605941] exynos-drm-g2d 10850000.g2d: Linked as a consumer to 10a60000.sysmmu
[    1.612725] exynos-drm-g2d 10850000.g2d: Linked as a consumer to 10a70000.sysmmu
[    1.620031] iommu: Adding device 10850000.g2d to group 1
[    1.625642] exynos-drm-g2d 10850000.g2d: The Exynos G2D (ver 4.1) successfully probed.
[    1.634234] exynos-rot 11c00000.rotator: Linked as a consumer to 11d40000.sysmmu
[    1.640561] iommu: Adding device 11c00000.rotator to group 2
[    1.646518] exynos-rot 11c00000.rotator: The exynos rotator is probed successfully
[    1.654487] exynos-drm-ipp exynos-drm-ipp: drm ipp registered successfully.
[    1.664959] mali 11800000.mali: Continuing without Mali regulator control
[    1.671060] mali 11800000.mali: GPU identified as 0x0620 r0p1 status 0
[    1.677305] mali 11800000.mali: Protected mode not available
[    1.682865] devfreq 11800000.mali: Couldn't update frequency transition information.
[    1.690846] mali 11800000.mali: Probed as mali0
[    1.704257] brd: module loaded
[    1.706457] libphy: Fixed MDIO Bus: probed
[    1.710305] usbcore: registered new interface driver r8152
[    1.715459] usbcore: registered new interface driver cdc_ether
[    1.721269] usbcore: registered new interface driver cdc_subset
[    1.729221] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    1.734315] ehci-exynos: EHCI EXYNOS driver
[    1.738791] exynos-ehci 12110000.usb: EHCI Host Controller
[    1.743940] exynos-ehci 12110000.usb: new USB bus registered, assigned bus number 1
[    1.751903] exynos-ehci 12110000.usb: irq 80, io mem 0x12110000
[    1.771922] exynos-ehci 12110000.usb: USB 2.0 started, EHCI 1.00
[    1.776760] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[    1.783251] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.790438] usb usb1: Product: EHCI Host Controller
[    1.795315] usb usb1: Manufacturer: Linux 4.14.180-178 ehci_hcd
[    1.801181] usb usb1: SerialNumber: 12110000.usb
[    1.806324] hub 1-0:1.0: USB hub found
[    1.809543] hub 1-0:1.0: 3 ports detected
[    1.814415] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    1.819648] ohci-exynos: OHCI EXYNOS driver
[    1.824023] exynos-ohci 12120000.usb: USB Host Controller
[    1.829189] exynos-ohci 12120000.usb: new USB bus registered, assigned bus number 2
[    1.836907] exynos-ohci 12120000.usb: irq 80, io mem 0x12120000
[    1.904156] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001
[    1.909505] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.916867] usb usb2: Product: USB Host Controller
[    1.921458] usb usb2: Manufacturer: Linux 4.14.180-178 ohci_hcd
[    1.927348] usb usb2: SerialNumber: 12120000.usb
[    1.932456] hub 2-0:1.0: USB hub found
[    1.935669] hub 2-0:1.0: 3 ports detected
[    1.941864] mousedev: PS/2 mouse device common for all mice
[    1.947242] i2c /dev entries driver
[    1.966818] vdd_ldo9: Bringing 3300000uV into 3000000-3000000uV
[    1.990437] vdd_sd: Bringing 3300000uV into 2800000-2800000uV
[    2.013639] vdd_ldo30: Bringing 1800000uV into 3300000-3300000uV
[    2.047344] vdd_1.8v_ldo: Bringing 1850000uV into 1500000-1500000uV
[    2.147898] usb 1-1: new high-speed USB device number 2 using exynos-ehci
[    2.309716] usb 1-1: New USB device found, idVendor=05e3, idProduct=0608
[    2.315001] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[    2.322095] usb 1-1: Product: USB2.0 Hub
[    2.326901] hub 1-1:1.0: USB hub found
[    2.330090] hub 1-1:1.0: 4 ports detected
[    2.623896] usb 1-1.4: new high-speed USB device number 3 using exynos-ehci
[    2.746197] usb 1-1.4: New USB device found, idVendor=15f4, idProduct=0131
[    2.751642] usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[    2.758943] usb 1-1.4: Product: dvbt2
[    2.762560] usb 1-1.4: Manufacturer: astrometadvbt2
[    3.109202] s5m-rtc s2mps14-rtc: rtc core: registered s5m-rtc as rtc0
[    3.118182] s5p-jpeg 11f50000.jpeg: Linked as a consumer to 11f10000.sysmmu
[    3.123757] iommu: Adding device 11f50000.jpeg to group 3
[    3.129430] s5p-jpeg 11f50000.jpeg: encoder device registered as /dev/video30
[    3.136343] s5p-jpeg 11f50000.jpeg: decoder device registered as /dev/video31
[    3.143284] s5p-jpeg 11f50000.jpeg: Samsung S5P JPEG codec
[    3.148957] s5p-jpeg 11f60000.jpeg: Linked as a consumer to 11f20000.sysmmu
[    3.155717] iommu: Adding device 11f60000.jpeg to group 4
[    3.161408] s5p-jpeg 11f60000.jpeg: encoder device registered as /dev/video32
[    3.168319] s5p-jpeg 11f60000.jpeg: decoder device registered as /dev/video33
[    3.175259] s5p-jpeg 11f60000.jpeg: Samsung S5P JPEG codec
[    3.181826] s5p-mfc 11000000.codec: Linked as a consumer to 11200000.sysmmu
[    3.187786] s5p-mfc 11000000.codec: Linked as a consumer to 11210000.sysmmu
[    3.194640] iommu: Adding device 11000000.codec to group 5
[    3.217421] s5p-mfc 11000000.codec: preallocated 16 MiB buffer for the firmware and context buffers
[    3.226226] s5p-mfc 11000000.codec: decoder registered as /dev/video10
[    3.231712] s5p-mfc 11000000.codec: encoder registered as /dev/video11
[    3.240656] exynos-gsc 13e00000.video-scaler: Linked as a consumer to 13e80000.sysmmu
[    3.247107] iommu: Adding device 13e00000.video-scaler to group 6
[    3.254346] exynos-gsc 13e10000.video-scaler: Linked as a consumer to 13e90000.sysmmu
[    3.260962] iommu: Adding device 13e10000.video-scaler to group 7
[   14.918557] thermal thermal_zone0: failed to read out thermal zone (-22)
[   14.924739] thermal thermal_zone1: failed to read out thermal zone (-22)
[   14.931437] thermal thermal_zone2: failed to read out thermal zone (-22)
[   14.938110] thermal thermal_zone3: failed to read out thermal zone (-22)
[   14.944808] thermal thermal_zone4: failed to read out thermal zone (-22)
[   14.951244] device-mapper: uevent: version 1.0.3
[   14.955433] device-mapper: ioctl: 4.37.0-ioctl (2017-09-20) initialised: dm-devel@redhat.com
[   14.977678] sdhci: Secure Digital Host Controller Interface driver
[   14.982400] sdhci: Copyright(c) Pierre Ossman
[   14.986925] Synopsys Designware Multimedia Card Interface Driver
[   14.993306] dwmmc_exynos 12200000.mmc: IDMAC supports 32-bit address mode.
[   14.999631] dwmmc_exynos 12200000.mmc: Using internal DMA controller.
[   15.005968] dwmmc_exynos 12200000.mmc: Version ID is 250a
[   15.011361] dwmmc_exynos 12200000.mmc: DW MMC controller at irq 83,64 bit host data width,64 deep fifo
[   15.020756] dwmmc_exynos 12200000.mmc: allocated mmc-pwrseq
[   15.026153] mmc_host mmc0: card is non-removable.
[   15.053113] mmc_host mmc0: Bus speed (slot 0) = 50000000Hz (slot req 400000Hz, actual 396825HZ div = 63)
[   15.080433] dwmmc_exynos 12220000.mmc: IDMAC supports 32-bit address mode.
[   15.085878] dwmmc_exynos 12220000.mmc: Using internal DMA controller.
[   15.093280] dwmmc_exynos 12220000.mmc: Version ID is 250a
[   15.099665] dwmmc_exynos 12220000.mmc: DW MMC controller at irq 84,64 bit host data width,64 deep fifo
[   15.128349] mmc_host mmc1: Bus speed (slot 0) = 50000000Hz (slot req 400000Hz, actual 396825HZ div = 63)
[   15.141093] mmc_host mmc0: Bus speed (slot 0) = 50000000Hz (slot req 300000Hz, actual 297619HZ div = 84)
[   15.156944] ledtrig-cpu: registered to indicate activity on CPUs
[   15.165206] s5p-secss 10830000.sss: s5p-sss driver registered
[   15.169879] hidraw: raw HID events driver (C) Jiri Kosina
[   15.182585] exynos-nocp: new NoC Probe device registered: 10ca1000.nocp
[   15.187895] exynos-nocp: new NoC Probe device registered: 10ca1400.nocp
[   15.194451] exynos-nocp: new NoC Probe device registered: 10ca1800.nocp
[   15.201044] exynos-nocp: new NoC Probe device registered: 10ca1c00.nocp
[   15.212843] NET: Registered protocol family 17
[   15.215818] NET: Registered protocol family 15
[   15.220292] Key type dns_resolver registered
[   15.224858] Registering SWP/SWPB emulation handler
[   15.229608] registered taskstats version 1
[   15.233332] Loading compiled-in X.509 certificates
[   15.233400] mmc_host mmc0: Bus speed (slot 0) = 50000000Hz (slot req 200000Hz, actual 200000HZ div = 125)
[   15.252156] Key type encrypted registered
[   15.274354] OF: graph: no port node found in /soc/hdmi@14530000
[   15.279670] [drm] Exynos DRM: using 14450000.mixer device for DMA mapping operations
[   15.286659] exynos-drm exynos-drm: bound 14450000.mixer (ops 0xc0965990)
[   15.293205] exynos-drm exynos-drm: bound 14530000.hdmi (ops 0xc0966030)
[   15.299782] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
[   15.306364] [drm] No driver support for vblank timestamp query.
[   15.313239] mmc_host mmc0: Bus speed (slot 0) = 50000000Hz (slot req 100000Hz, actual 100000HZ div = 250)
[   15.361943] mmc_host mmc1: Bus speed (slot 0) = 50000000Hz (slot req 50000000Hz, actual 50000000HZ div = 0)
[   15.373992] mmc1: new ultra high speed DDR50 SDHC card at address 0007
[   15.379315] mmcblk1: mmc1:0007 SD4GB 3.71 GiB 
[   15.384594]  mmcblk1: p1 p2
[   16.143882] [drm] Cannot find any crtc or sizes
[   16.147353] [drm] Initialized exynos 1.0.0 20110530 for exynos-drm on minor 0
[   16.983888] [drm] Cannot find any crtc or sizes
[   16.987241] xhci-hcd xhci-hcd.3.auto: xHCI Host Controller
[   16.992433] xhci-hcd xhci-hcd.3.auto: new USB bus registered, assigned bus number 3
[   17.000258] xhci-hcd xhci-hcd.3.auto: hcc params 0x0220f04c hci version 0x100 quirks 0x0000000002010010
[   17.009437] xhci-hcd xhci-hcd.3.auto: irq 150, io mem 0x12000000
[   17.015513] usb usb3: New USB device found, idVendor=1d6b, idProduct=0002
[   17.022144] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[   17.029337] usb usb3: Product: xHCI Host Controller
[   17.034185] usb usb3: Manufacturer: Linux 4.14.180-178 xhci-hcd
[   17.040078] usb usb3: SerialNumber: xhci-hcd.3.auto
[   17.045230] hub 3-0:1.0: USB hub found
[   17.048677] hub 3-0:1.0: 1 port detected
[   17.052745] xhci-hcd xhci-hcd.3.auto: xHCI Host Controller
[   17.058021] xhci-hcd xhci-hcd.3.auto: new USB bus registered, assigned bus number 4
[   17.065652] xhci-hcd xhci-hcd.3.auto: Host supports USB 3.0  SuperSpeed
[   17.072284] usb usb4: We don't know the algorithms for LPM for this host, disabling LPM.
[   17.080379] usb usb4: New USB device found, idVendor=1d6b, idProduct=0003
[   17.087046] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[   17.094237] usb usb4: Product: xHCI Host Controller
[   17.099089] usb usb4: Manufacturer: Linux 4.14.180-178 xhci-hcd
[   17.104982] usb usb4: SerialNumber: xhci-hcd.3.auto
[   17.110114] hub 4-0:1.0: USB hub found
[   17.113580] hub 4-0:1.0: 1 port detected
[   17.119450] xhci-hcd xhci-hcd.6.auto: xHCI Host Controller
[   17.123485] xhci-hcd xhci-hcd.6.auto: new USB bus registered, assigned bus number 5
[   17.131308] xhci-hcd xhci-hcd.6.auto: hcc params 0x0220f04c hci version 0x100 quirks 0x0000000002010010
[   17.140493] xhci-hcd xhci-hcd.6.auto: irq 151, io mem 0x12400000
[   17.146559] usb usb5: New USB device found, idVendor=1d6b, idProduct=0002
[   17.153201] usb usb5: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[   17.160392] usb usb5: Product: xHCI Host Controller
[   17.165241] usb usb5: Manufacturer: Linux 4.14.180-178 xhci-hcd
[   17.171133] usb usb5: SerialNumber: xhci-hcd.6.auto
[   17.176256] hub 5-0:1.0: USB hub found
[   17.179709] hub 5-0:1.0: 1 port detected
[   17.183798] xhci-hcd xhci-hcd.6.auto: xHCI Host Controller
[   17.189080] xhci-hcd xhci-hcd.6.auto: new USB bus registered, assigned bus number 6
[   17.196703] xhci-hcd xhci-hcd.6.auto: Host supports USB 3.0  SuperSpeed
[   17.203347] usb usb6: We don't know the algorithms for LPM for this host, disabling LPM.
[   17.211435] usb usb6: New USB device found, idVendor=1d6b, idProduct=0003
[   17.218102] usb usb6: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[   17.225295] usb usb6: Product: xHCI Host Controller
[   17.230145] usb usb6: Manufacturer: Linux 4.14.180-178 xhci-hcd
[   17.236038] usb usb6: SerialNumber: xhci-hcd.6.auto
[   17.241204] hub 6-0:1.0: USB hub found
[   17.244640] hub 6-0:1.0: 1 port detected
[   17.249489] rtc rtc1: invalid alarm value: 1900-1-20 0:0:0
[   17.254076] s3c-rtc 101e0000.rtc: rtc core: registered s3c as rtc1
[   17.261243] exynos-bus: new bus device registered: soc:bus_wcore ( 84000 KHz ~ 400000 KHz)
[   17.268770] exynos-bus: new bus device registered: soc:bus_noc ( 67000 KHz ~ 100000 KHz)
[   17.276714] exynos-bus: new bus device registered: soc:bus_fsys_apb (200000 KHz ~ 200000 KHz)
[   17.285157] exynos-bus: new bus device registered: soc:bus_fsys (200000 KHz ~ 200000 KHz)
[   17.293419] exynos-bus: new bus device registered: soc:bus_fsys2 ( 75000 KHz ~ 150000 KHz)
[   17.301722] exynos-bus: new bus device registered: soc:bus_mfc ( 96000 KHz ~ 333000 KHz)
[   17.309750] exynos-bus: new bus device registered: soc:bus_gen ( 89000 KHz ~ 267000 KHz)
[   17.317722] exynos-bus: new bus device registered: soc:bus_peri ( 67000 KHz ~  67000 KHz)
[   17.325991] exynos-bus: new bus device registered: soc:bus_g2d ( 84000 KHz ~ 333000 KHz)
[   17.334010] exynos-bus: new bus device registered: soc:bus_g2d_acp ( 67000 KHz ~ 267000 KHz)
[   17.342412] exynos-bus: new bus device registered: soc:bus_jpeg ( 75000 KHz ~ 300000 KHz)
[   17.350558] exynos-bus: new bus device registered: soc:bus_jpeg_apb ( 84000 KHz ~ 167000 KHz)
[   17.358986] exynos-bus: new bus device registered: soc:bus_disp1_fimd (120000 KHz ~ 200000 KHz)
[   17.367679] exynos-bus: new bus device registered: soc:bus_disp1 (120000 KHz ~ 300000 KHz)
[   17.375940] exynos-bus: new bus device registered: soc:bus_gscl_scaler (150000 KHz ~ 300000 KHz)
[   17.384745] exynos-bus: new bus device registered: soc:bus_mscl ( 84000 KHz ~ 400000 KHz)
[   17.392794] samsung-i2s 3830000.i2s: ASoC: Failed to create component debugfs directory
[   17.400447] samsung-i2s 3830000.i2s: ASoC: Failed to create component debugfs directory
[   17.408917] odroid-audio sound: multicodec <-> 3830000.i2s mapping ok
[   17.417975] s5m-rtc s2mps14-rtc: setting system clock to 2021-05-27 16:27:59 UTC (1622132879)
[   17.431283] ALSA device list:
[   17.432801]   #0: Odroid-XU4
[   17.436686] Freeing unused kernel memory: 1024K
[   17.827844] input: gpio_keys as /devices/platform/gpio_keys/input/input0
[   18.300003] usb 4-1: new SuperSpeed USB device number 2 using xhci-hcd
[   18.325182] usb 4-1: New USB device found, idVendor=152d, idProduct=0578
[   18.330440] usb 4-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[   18.337537] usb 4-1: Product: USB to ATA/ATAPI Bridge
[   18.342567] usb 4-1: Manufacturer: JMicron
[   18.346789] usb 4-1: SerialNumber: 0123456789ABCDEF
[   18.357584] SCSI subsystem initialized
[   18.361161] usbcore: registered new interface driver usb-storage
[   18.371798] scsi host0: uas
[   18.373378] usbcore: registered new interface driver uas
[   18.374025] scsi 0:0:0:0: Direct-Access     JMicron  Generic          3102 PQ: 0 ANSI: 6
[   18.403971] usb 6-1: new SuperSpeed USB device number 2 using xhci-hcd
[   18.428808] usb 6-1: New USB device found, idVendor=0bda, idProduct=8153
[   18.434060] usb 6-1: New USB device strings: Mfr=1, Product=2, SerialNumber=6
[   18.441159] usb 6-1: Product: USB 10/100/1000 LAN
[   18.445840] usb 6-1: Manufacturer: Realtek
[   18.449907] usb 6-1: SerialNumber: 000001000000
[   18.643456] usb 6-1: reset SuperSpeed USB device number 2 using xhci-hcd
[   18.708652] r8152 6-1:1.0 eth0: v1.09.9
[   19.117184] sd 0:0:0:0: [sda] 468862128 512-byte logical blocks: (240 GB/224 GiB)
[   19.123215] sd 0:0:0:0: [sda] 4096-byte physical blocks
[   19.128582] sd 0:0:0:0: [sda] Write Protect is off
[   19.133172] sd 0:0:0:0: [sda] Mode Sense: 53 00 00 08
[   19.133520] sd 0:0:0:0: [sda] Disabling FUA
[   19.137331] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[   19.146714] sd 0:0:0:0: [sda] Optimal transfer size 33553920 bytes not a multiple of physical block size (4096 bytes)
[   19.165174]  sda: sda1 sda2
[   19.168557] sd 0:0:0:0: [sda] Attached SCSI disk
[   19.423956] raid6: int32x1  gen()   448 MB/s
[   19.491904] raid6: int32x1  xor()   514 MB/s
[   19.559875] raid6: int32x2  gen()   685 MB/s
[   19.627884] raid6: int32x2  xor()   645 MB/s
[   19.695912] raid6: int32x4  gen()   770 MB/s
[   19.763876] raid6: int32x4  xor()   702 MB/s
[   19.831940] raid6: int32x8  gen()  1038 MB/s
[   19.899909] raid6: int32x8  xor()   674 MB/s
[   19.967890] raid6: neonx1   gen()  1853 MB/s
[   20.035872] raid6: neonx1   xor()  1956 MB/s
[   20.103871] raid6: neonx2   gen()  2454 MB/s
[   20.171871] raid6: neonx2   xor()  3312 MB/s
[   20.239871] raid6: neonx4   gen()  3124 MB/s
[   20.307878] raid6: neonx4   xor()  2936 MB/s
[   20.375880] raid6: neonx8   gen()  3699 MB/s
[   20.443882] raid6: neonx8   xor()  2883 MB/s
[   20.446664] raid6: using algorithm neonx8 gen() 3699 MB/s
[   20.452060] raid6: .... xor() 2883 MB/s, rmw enabled
[   20.456999] raid6: using neon recovery algorithm
[   20.463504] xor: measuring software checksum speed
[   20.503938]    arm4regs  :  3269.000 MB/sec
[   20.543876]    8regs     :  2732.000 MB/sec
[   20.583873]    32regs    :  2718.000 MB/sec
[   20.623872]    neon      :  4488.000 MB/sec
[   20.626575] xor: using function: neon (4488.000 MB/sec)
[   20.644327] Btrfs loaded, crc32c=crc32c-generic
[   20.822701] BTRFS: device label ubuntu devid 1 transid 703487 /dev/sda2
[   20.829536] BTRFS: device label sdfs devid 1 transid 35 /dev/mmcblk1p2
[   20.877380] BTRFS info (device sda2): disk space caching is enabled
[   20.882239] BTRFS info (device sda2): has skinny extents
[   21.081272] random: crng init done
[   21.200971] NET: Registered protocol family 10
[   21.204639] Segment Routing with IPv6
[   21.210286] ip_tables: (C) 2000-2006 Netfilter Core Team
[   21.221147] systemd[1]: systemd 245.4-4ubuntu3.6 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)
[   21.242577] systemd[1]: Detected architecture arm.
[   21.272026] systemd[1]: Set hostname to <oggy-odroid>.
[   21.604568] systemd[1]: Created slice system-modprobe.slice.
[   21.632973] systemd[1]: Created slice system-serial\x2dgetty.slice.
[   21.656920] systemd[1]: Created slice system-systemd\x2dfsck.slice.
[   21.685030] systemd[1]: Created slice User and Session Slice.
[   21.708130] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[   21.732084] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[   21.756575] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[   21.784075] systemd[1]: Reached target Local Encrypted Volumes.
[   21.804026] systemd[1]: Reached target Paths.
[   21.819958] systemd[1]: Reached target Remote File Systems.
[   21.839956] systemd[1]: Reached target Slices.
[   21.860308] systemd[1]: Listening on Syslog Socket.
[   21.880199] systemd[1]: Listening on fsck to fsckd communication Socket.
[   21.904071] systemd[1]: Listening on initctl Compatibility Named Pipe.
[   21.932398] systemd[1]: Listening on Journal Audit Socket.
[   21.952166] systemd[1]: Listening on Journal Socket (/dev/log).
[   21.976235] systemd[1]: Listening on Journal Socket.
[   21.996230] systemd[1]: Listening on udev Control Socket.
[   22.016117] systemd[1]: Listening on udev Kernel Socket.
[   22.036243] systemd[1]: Condition check resulted in Huge Pages File System being skipped.
[   22.045747] systemd[1]: Mounting POSIX Message Queue File System...
[   22.071657] systemd[1]: Mounting Kernel Debug File System...
[   22.094850] systemd[1]: Mounting Kernel Trace File System...
[   22.119765] systemd[1]: Starting Journal Service...
[   22.144086] systemd[1]: Starting Set the console keyboard layout...
[   22.168013] systemd[1]: Starting Create list of static device nodes for the current kernel...
[   22.192050] systemd[1]: Condition check resulted in Load Kernel Module drm being skipped.
[   22.198887] systemd[1]: Condition check resulted in OpenVSwitch configuration for cleanup being skipped.
[   22.208701] systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped.
[   22.217324] systemd[1]: Condition check resulted in File System Check on Root Device being skipped.
[   22.229924] systemd[1]: Starting Load Kernel Modules...
[   22.255335] systemd[1]: Starting Remount Root and Kernel File Systems...
[   22.281243] BTRFS info (device sda2): enabling ssd optimizations
[   22.285879] BTRFS info (device sda2): disk space caching is enabled
[   22.292408] systemd[1]: Starting udev Coldplug all Devices...
[   22.315364] systemd[1]: Starting Uncomplicated firewall...
[   22.341415] systemd[1]: Started Journal Service.
[   22.412875] ip6_tables: (C) 2000-2006 Netfilter Core Team
[   22.543281] systemd-journald[249]: Received client request to flush runtime journal.
[   22.569255] nf_conntrack version 0.5.0 (16384 buckets, 65536 max)
[   22.986747] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[   23.012560] r8152 6-1:1.0 eth0: carrier on
[   23.012782] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   23.047692] gpiomem-exynos 13400000.gpiomem: Initialised: GPIO register area is 2
[   23.054235] gpiomem-exynos 13400000.gpiomem: Initialised: Registers at 0x13400000
[   23.054242] gpiomem-exynos 13400000.gpiomem: Initialised: Registers at 0x14010000
[   23.062107] Driver for 1-wire Dallas network protocol.
[   23.124481] usb 1-1.4: dvb_usb_v2: found a 'Astrometa DVB-T2' in warm state
[   23.169678] ads7846 spi1.1: touchscreen, irq 156
[   23.170373] input: ADS7846 Touchscreen as /devices/platform/soc/12d30000.spi/spi_master/spi1/spi1.1/input/input1
[   23.207456] usb 1-1.4: dvb_usb_v2: will pass the complete MPEG2 transport stream to the software demuxer
[   23.207488] dvbdev: DVB: registering new adapter (Astrometa DVB-T2)
[   23.207495] usb 1-1.4: media controller created
[   23.208386] dvbdev: dvb_create_media_entity: media entity 'dvb-demux' registered.
[   23.216784] i2c i2c-11: Added multiplexed i2c bus 12
[   23.216791] rtl2832 11-0010: Realtek RTL2832 successfully attached
[   23.229087] mn88473 11-0018: Panasonic MN88473 successfully identified
[   23.229157] usb 1-1.4: DVB: registering adapter 0 frontend 0 (Realtek RTL2832 (DVB-T))...
[   23.229172] dvbdev: dvb_create_media_entity: media entity 'Realtek RTL2832 (DVB-T)' registered.
[   23.229316] usb 1-1.4: DVB: registering adapter 0 frontend 1 (Panasonic MN88473)...
[   23.229324] dvbdev: dvb_create_media_entity: media entity 'Panasonic MN88473' registered.
[   23.237114] r820t 12-003a: creating new instance
[   23.244224] r820t 12-003a: Rafael Micro r820t successfully identified
[   23.244243] r820t 12-003a: attaching existing instance
[   23.249217] r820t 12-003a: Rafael Micro r820t successfully identified
[   23.266907] rtl2832_sdr rtl2832_sdr.7.auto: Registered as swradio0
[   23.266917] rtl2832_sdr rtl2832_sdr.7.auto: Realtek RTL2832 SDR attached
[   23.266924] rtl2832_sdr rtl2832_sdr.7.auto: SDR API is still slightly experimental and functionality changes may follow
[   23.284704] lirc_dev: IR Remote Control driver registered, major 240
[   23.287075] IR LIRC bridge handler initialized
[   23.288090] Registered IR keymap rc-empty
[   23.288224] rc rc0: Astrometa DVB-T2 as /devices/platform/soc/12110000.usb/usb1/1-1/1-1.4/rc/rc0
[   23.288367] input: Astrometa DVB-T2 as /devices/platform/soc/12110000.usb/usb1/1-1/1-1.4/rc/rc0/input2
[   23.288799] rc rc0: lirc_dev: driver ir-lirc-codec (dvb_usb_rtl28xxu) registered at minor = 0
[   23.288818] usb 1-1.4: dvb_usb_v2: schedule remote query interval to 200 msecs
[   23.297628] usb 1-1.4: dvb_usb_v2: 'Astrometa DVB-T2' successfully initialized and connected
[   23.297808] usbcore: registered new interface driver dvb_usb_rtl28xxu
[   23.322615] sd 0:0:0:0: Attached scsi generic sg0 type 0
[   23.683979] [UFW BLOCK] IN= OUT=eth0 SRC=0000:0000:0000:0000:0000:0000:0000:0000 DST=ff02:0000:0000:0000:0000:0000:0000:0016 LEN=76 TC=0 HOPLIMIT=1 FLOWLBL=0 PROTO=ICMPv6 TYPE=143 CODE=0 MARK=0x154 
[   24.083157] Adding 2097148k swap on /dev/sda1.  Priority:-2 extents:1 across:2097148k 
[   24.106430] BTRFS info (device mmcblk1p2): enabling ssd optimizations
[   24.106438] BTRFS info (device mmcblk1p2): use zlib compression
[   24.106444] BTRFS info (device mmcblk1p2): disk space caching is enabled
[   24.106449] BTRFS info (device mmcblk1p2): has skinny extents
[   25.932086] [UFW BLOCK] IN= OUT=eth0 SRC=10.0.0.89 DST=224.0.0.22 LEN=40 TOS=0x00 PREC=0xC0 TTL=1 ID=0 DF PROTO=2 MARK=0x154 
[   26.044006] [UFW BLOCK] IN= OUT=eth0 SRC=10.0.0.89 DST=224.0.0.22 LEN=40 TOS=0x00 PREC=0xC0 TTL=1 ID=0 DF PROTO=2 MARK=0x154 
[   26.745029] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
[   26.747809] Bridge firewalling registered
[   27.159195] Initializing XFRM netlink socket
[   27.225236] Netfilter messages via NETLINK v0.30.
[   27.227638] ctnetlink v0.93: registering with nfnetlink.
[   27.501948] IPv6: ADDRCONF(NETDEV_UP): br-33d2fcf7e0ec: link is not ready
[   27.515992] [UFW BLOCK] IN= OUT=br-33d2fcf7e0ec SRC=172.30.0.1 DST=224.0.0.22 LEN=40 TOS=0x00 PREC=0xC0 TTL=1 ID=0 DF PROTO=2 MARK=0x154 
[   27.730554] IPv6: ADDRCONF(NETDEV_UP): docker0: link is not ready
[   27.739996] [UFW BLOCK] IN= OUT=docker0 SRC=172.17.0.1 DST=224.0.0.22 LEN=40 TOS=0x00 PREC=0xC0 TTL=1 ID=0 DF PROTO=2 MARK=0x154 
[   27.939941] [UFW BLOCK] IN= OUT=br-33d2fcf7e0ec SRC=172.30.0.1 DST=224.0.0.22 LEN=40 TOS=0x00 PREC=0xC0 TTL=1 ID=0 DF PROTO=2 MARK=0x154 
[   27.955729] IPv6: ADDRCONF(NETDEV_UP): br-b168457ca201: link is not ready
[   27.963970] [UFW BLOCK] IN= OUT=br-b168457ca201 SRC=172.31.0.1 DST=224.0.0.22 LEN=40 TOS=0x00 PREC=0xC0 TTL=1 ID=0 DF PROTO=2 MARK=0x154 
[   28.456171] br-b168457ca201: port 1(veth5c1c89d) entered blocking state
[   28.456181] br-b168457ca201: port 1(veth5c1c89d) entered disabled state
[   28.456520] device veth5c1c89d entered promiscuous mode
[   28.457371] IPv6: ADDRCONF(NETDEV_UP): veth5c1c89d: link is not ready
[   28.457380] br-b168457ca201: port 1(veth5c1c89d) entered blocking state
[   28.457387] br-b168457ca201: port 1(veth5c1c89d) entered forwarding state
[   28.457741] br-b168457ca201: port 1(veth5c1c89d) entered disabled state
[   28.462784] br-33d2fcf7e0ec: port 1(veth7b07c70) entered blocking state
[   28.462795] br-33d2fcf7e0ec: port 1(veth7b07c70) entered disabled state
[   28.463122] device veth7b07c70 entered promiscuous mode
[   28.463827] IPv6: ADDRCONF(NETDEV_UP): veth7b07c70: link is not ready
[   28.463836] br-33d2fcf7e0ec: port 1(veth7b07c70) entered blocking state
[   28.463844] br-33d2fcf7e0ec: port 1(veth7b07c70) entered forwarding state
[   28.466607] br-33d2fcf7e0ec: port 1(veth7b07c70) entered disabled state
[   28.483995] [UFW BLOCK] IN= OUT=docker0 SRC=172.17.0.1 DST=224.0.0.22 LEN=40 TOS=0x00 PREC=0xC0 TTL=1 ID=0 DF PROTO=2 MARK=0x154 
[   28.556705] br-33d2fcf7e0ec: port 2(veth147e9b5) entered blocking state
[   28.556718] br-33d2fcf7e0ec: port 2(veth147e9b5) entered disabled state
[   28.557001] device veth147e9b5 entered promiscuous mode
[   28.557973] IPv6: ADDRCONF(NETDEV_UP): veth147e9b5: link is not ready
[   28.557983] br-33d2fcf7e0ec: port 2(veth147e9b5) entered blocking state
[   28.557991] br-33d2fcf7e0ec: port 2(veth147e9b5) entered forwarding state
[   28.558638] IPv6: ADDRCONF(NETDEV_CHANGE): br-33d2fcf7e0ec: link becomes ready
[   28.564075] br-33d2fcf7e0ec: port 2(veth147e9b5) entered disabled state
[   28.576008] [UFW BLOCK] IN= OUT=br-33d2fcf7e0ec SRC=0000:0000:0000:0000:0000:0000:0000:0000 DST=ff02:0000:0000:0000:0000:0000:0000:0016 LEN=76 TC=0 HOPLIMIT=1 FLOWLBL=0 PROTO=ICMPv6 TYPE=143 CODE=0 MARK=0x154 
[   28.628058] br-33d2fcf7e0ec: port 3(veth7786969) entered blocking state
[   28.628065] br-33d2fcf7e0ec: port 3(veth7786969) entered disabled state
[   28.628385] device veth7786969 entered promiscuous mode
[   28.629167] IPv6: ADDRCONF(NETDEV_UP): veth7786969: link is not ready
[   28.629175] br-33d2fcf7e0ec: port 3(veth7786969) entered blocking state
[   28.629182] br-33d2fcf7e0ec: port 3(veth7786969) entered forwarding state
[   28.717312] br-33d2fcf7e0ec: port 4(veth2ab63d9) entered blocking state
[   28.717323] br-33d2fcf7e0ec: port 4(veth2ab63d9) entered disabled state
[   28.719516] device veth2ab63d9 entered promiscuous mode
[   28.723616] IPv6: ADDRCONF(NETDEV_UP): veth2ab63d9: link is not ready
[   28.723631] br-33d2fcf7e0ec: port 4(veth2ab63d9) entered blocking state
[   28.723642] br-33d2fcf7e0ec: port 4(veth2ab63d9) entered forwarding state
[   28.803987] [UFW BLOCK] IN= OUT=br-b168457ca201 SRC=172.31.0.1 DST=224.0.0.22 LEN=40 TOS=0x00 PREC=0xC0 TTL=1 ID=0 DF PROTO=2 MARK=0x154 
[   29.192006] [UFW BLOCK] IN= OUT=br-33d2fcf7e0ec SRC=0000:0000:0000:0000:0000:0000:0000:0000 DST=ff02:0000:0000:0000:0000:0000:0000:0016 LEN=76 TC=0 HOPLIMIT=1 FLOWLBL=0 PROTO=ICMPv6 TYPE=143 CODE=0 MARK=0x154 
[   29.284626] br-33d2fcf7e0ec: port 3(veth7786969) entered disabled state
[   29.284930] br-33d2fcf7e0ec: port 4(veth2ab63d9) entered disabled state
[   29.300004] [UFW BLOCK] IN= OUT=br-33d2fcf7e0ec SRC=0000:0000:0000:0000:0000:0000:0000:0000 DST=ff02:0000:0000:0000:0000:0000:0000:0016 LEN=76 TC=0 HOPLIMIT=1 FLOWLBL=0 PROTO=ICMPv6 TYPE=143 CODE=0 MARK=0x154 
[   32.456882] eth0: renamed from vetha7c4507
[   32.483166] eth0: renamed from veth9d6b392
[   32.574012] eth0: renamed from veth81bf588
[   32.596348] eth0: renamed from veth98ca951
[   32.620804] IPv6: ADDRCONF(NETDEV_CHANGE): veth5c1c89d: link becomes ready
[   32.621121] br-b168457ca201: port 1(veth5c1c89d) entered blocking state
[   32.621137] br-b168457ca201: port 1(veth5c1c89d) entered forwarding state
[   32.621698] IPv6: ADDRCONF(NETDEV_CHANGE): veth7786969: link becomes ready
[   32.621941] br-33d2fcf7e0ec: port 3(veth7786969) entered blocking state
[   32.621956] br-33d2fcf7e0ec: port 3(veth7786969) entered forwarding state
[   32.622442] IPv6: ADDRCONF(NETDEV_CHANGE): br-b168457ca201: link becomes ready
[   32.632036] [UFW BLOCK] IN= OUT=veth5c1c89d SRC=0000:0000:0000:0000:0000:0000:0000:0000 DST=ff02:0000:0000:0000:0000:0000:0000:0016 LEN=76 TC=0 HOPLIMIT=1 FLOWLBL=0 PROTO=ICMPv6 TYPE=143 CODE=0 MARK=0x154 
[   32.653618] IPv6: ADDRCONF(NETDEV_CHANGE): veth147e9b5: link becomes ready
[   32.653768] br-33d2fcf7e0ec: port 2(veth147e9b5) entered blocking state
[   32.653782] br-33d2fcf7e0ec: port 2(veth147e9b5) entered forwarding state
[   32.654515] eth0: renamed from vetha8a83a6
[   32.663988] [UFW BLOCK] IN= OUT=veth147e9b5 SRC=0000:0000:0000:0000:0000:0000:0000:0000 DST=ff02:0000:0000:0000:0000:0000:0000:0016 LEN=76 TC=0 HOPLIMIT=1 FLOWLBL=0 PROTO=ICMPv6 TYPE=143 CODE=0 MARK=0x154 
[   32.663991] [UFW BLOCK] IN= OUT=br-b168457ca201 SRC=0000:0000:0000:0000:0000:0000:0000:0000 DST=ff02:0000:0000:0000:0000:0000:0000:0016 LEN=76 TC=0 HOPLIMIT=1 FLOWLBL=0 PROTO=ICMPv6 TYPE=143 CODE=0 MARK=0x154 
[   32.664038] [UFW BLOCK] IN= OUT=veth7786969 SRC=0000:0000:0000:0000:0000:0000:0000:0000 DST=ff02:0000:0000:0000:0000:0000:0000:0016 LEN=76 TC=0 HOPLIMIT=1 FLOWLBL=0 PROTO=ICMPv6 TYPE=143 CODE=0 MARK=0x154 
[   32.672479] IPv6: ADDRCONF(NETDEV_CHANGE): veth7b07c70: link becomes ready
[   32.672655] br-33d2fcf7e0ec: port 1(veth7b07c70) entered blocking state
[   32.672671] br-33d2fcf7e0ec: port 1(veth7b07c70) entered forwarding state
[   32.676138] IPv6: ADDRCONF(NETDEV_CHANGE): veth2ab63d9: link becomes ready
[   32.676317] br-33d2fcf7e0ec: port 4(veth2ab63d9) entered blocking state
[   32.676325] br-33d2fcf7e0ec: port 4(veth2ab63d9) entered forwarding state
[   32.684026] [UFW BLOCK] IN= OUT=veth7b07c70 SRC=0000:0000:0000:0000:0000:0000:0000:0000 DST=ff02:0000:0000:0000:0000:0000:0000:0016 LEN=76 TC=0 HOPLIMIT=1 FLOWLBL=0 PROTO=ICMPv6 TYPE=143 CODE=0 MARK=0x154 
[   32.688019] [UFW BLOCK] IN= OUT=veth2ab63d9 SRC=0000:0000:0000:0000:0000:0000:0000:0000 DST=ff02:0000:0000:0000:0000:0000:0000:0016 LEN=76 TC=0 HOPLIMIT=1 FLOWLBL=0 PROTO=ICMPv6 TYPE=143 CODE=0 MARK=0x154 
[   38.994370] usb 1-1.4: DVB: adapter 0 frontend 0 frequency 0 out of range (174000000..862000000)
[   39.560893] mn88473 11-0018: downloading firmware from file 'dvb-demod-mn88473-01.fw'
[   40.214502] usb 1-1.4: DVB: adapter 0 frontend 1 frequency 0 out of range (42000000..1002000000)
<...>
Boot dmesg from kernel 5.4:

Code: Select all

[    0.000000] Booting Linux on physical CPU 0x100
[    0.000000] Linux version 5.4.118-221 (root@1604_builder_armhf) (gcc version 9.3.0 (Ubuntu 9.3.0-10ubuntu2)) #1 SMP PREEMPT Tue May 11 12:50:02 EDT 2021
[    0.000000] CPU: ARMv7 Processor [410fc073] revision 3 (ARMv7), cr=10c5387d
[    0.000000] CPU: div instructions available: patching division code
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
[    0.000000] OF: fdt: Machine model: Hardkernel Odroid XU4
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] cma: Reserved 128 MiB at 0xb6800000
[    0.000000] Samsung CPU ID: 0xe5422001
[    0.000000] On node 0 totalpages: 518656
[    0.000000]   Normal zone: 1728 pages used for memmap
[    0.000000]   Normal zone: 0 pages reserved
[    0.000000]   Normal zone: 196608 pages, LIFO batch:63
[    0.000000]   HighMem zone: 322048 pages, LIFO batch:63
[    0.000000] Running under secure firmware.
[    0.000000] percpu: Embedded 20 pages/cpu s52364 r8192 d21364 u81920
[    0.000000] pcpu-alloc: s52364 r8192 d21364 u81920 alloc=20*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 [0] 4 [0] 5 [0] 6 [0] 7 
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 516928
[    0.000000] Kernel command line: console=tty1 console=ttySAC2,115200n8 root=/dev/sda2 rootfstype=btrfs rootflags=subvol=root rootwait ro fsck.repair=yes net.ifnames=0 elevator=deadline  HPD=false vout=hdmi  smsc95xx.macaddr=00:1e:06:61:7a:39 false s5p_mfc.mem=16M
[    0.000000] hdmi: using HDMI mode
[    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] Memory: 1896592K/2074624K available (10240K kernel code, 785K rwdata, 2784K rodata, 1024K init, 323K bss, 46960K reserved, 131072K cma-reserved, 1157120K highmem)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=8, Nodes=1
[    0.000000] rcu: Preemptible hierarchical RCU implementation.
[    0.000000] 	Tasks RCU enabled.
[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 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+0x330/0x510 with crng_init=0
[    0.000000] Switching to timer-based delay loop, resolution 41ns
[    0.000000] clocksource: mct-frc: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 79635851949 ns
[    0.000008] sched_clock: 32 bits at 24MHz, resolution 41ns, wraps every 89478484971ns
[    0.000033] genirq: irq_chip COMBINER did not update eff. affinity mask of irq 49
[    0.001663] arch_timer: cp15 timer(s) running at 24.00MHz (phys).
[    0.001683] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x588fe9dc0, max_idle_ns: 440795202592 ns
[    0.001704] sched_clock: 56 bits at 24MHz, resolution 41ns, wraps every 4398046511097ns
[    0.001717] Ignoring duplicate/late registration of read_current_timer delay
[    0.002257] Console: colour dummy device 80x30
[    0.002903] printk: console [tty1] enabled
[    0.002956] Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=96000)
[    0.002998] pid_max: default: 32768 minimum: 301
[    0.003171] LSM: Security Framework initializing
[    0.003279] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
[    0.003324] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
[    0.004558] CPU: Testing write buffer coherency: ok
[    0.005122] CPU0: thread -1, cpu 0, socket 1, mpidr 80000100
[    0.006058] Setting up static identity map for 0x40100000 - 0x40100060
[    0.006396] ARM CCI driver probed
[    0.006994] Exynos MCPM support installed
[    0.007323] rcu: Hierarchical SRCU implementation.
[    0.009858] soc soc0: Exynos: CPU[EXYNOS5800] PRO_ID[0xe5422001] REV[0x1] Detected
[    0.010407] smp: Bringing up secondary CPUs ...
[    0.011439] CPU1: thread -1, cpu 1, socket 1, mpidr 80000101
[    0.012664] CPU2: thread -1, cpu 2, socket 1, mpidr 80000102
[    0.013735] CPU3: thread -1, cpu 3, socket 1, mpidr 80000103
[    0.014809] CPU4: thread -1, cpu 0, socket 0, mpidr 80000000
[    0.014819] CPU4: Spectre v2: firmware did not set auxiliary control register IBE bit, system vulnerable
[    0.016057] CPU5: thread -1, cpu 1, socket 0, mpidr 80000001
[    0.016065] CPU5: Spectre v2: firmware did not set auxiliary control register IBE bit, system vulnerable
[    0.017224] CPU6: thread -1, cpu 2, socket 0, mpidr 80000002
[    0.017232] CPU6: Spectre v2: firmware did not set auxiliary control register IBE bit, system vulnerable
[    0.018376] CPU7: thread -1, cpu 3, socket 0, mpidr 80000003
[    0.018385] CPU7: Spectre v2: firmware did not set auxiliary control register IBE bit, system vulnerable
[    0.018611] smp: Brought up 1 node, 8 CPUs
[    0.018661] SMP: Total of 8 processors activated (384.00 BogoMIPS).
[    0.018685] CPU: All CPU(s) started in HYP mode.
[    0.018704] CPU: Virtualization extensions available.
[    0.019869] devtmpfs: initialized
[    0.039881] VFP support v0.3: implementor 41 architecture 4 part 30 variant f rev 0
[    0.040316] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.040356] futex hash table entries: 2048 (order: 5, 131072 bytes, linear)
[    0.042776] pinctrl core: initialized pinctrl subsystem
[    0.045115] NET: Registered protocol family 16
[    0.048528] DMA: preallocated 2048 KiB pool for atomic coherent allocations
[    0.049967] audit: initializing netlink subsys (disabled)
[    0.050192] audit: type=2000 audit(0.048:1): state=initialized audit_enabled=0 res=1
[    0.050923] cpuidle: using governor menu
[    0.051554] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[    0.051582] hw-breakpoint: maximum watchpoint size is 8 bytes.
[    0.110417] EXYNOS5420 PMU initialized
[    0.217270] iommu: Default domain type: Translated 
[    0.217745] usbcore: registered new interface driver usbfs
[    0.217810] usbcore: registered new interface driver hub
[    0.217881] usbcore: registered new device driver usb
[    0.218523] s3c-i2c 12c80000.i2c: slave address 0x00
[    0.218551] s3c-i2c 12c80000.i2c: bus frequency set to 65 KHz
[    0.218830] s3c-i2c 12c80000.i2c: i2c-2: S3C I2C adapter
[    0.219298] mc: Linux media interface: v0.10
[    0.219348] videodev: Linux video capture interface: v2.00
[    0.219466] pps_core: LinuxPPS API ver. 1 registered
[    0.219487] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.219947] Advanced Linux Sound Architecture Driver Initialized.
[    0.221007] clocksource: Switched to clocksource mct-frc
[    0.269041] random: fast init done
[    0.919869] VFS: Disk quotas dquot_6.6.0
[    0.919958] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    0.931535] thermal_sys: Registered thermal governor 'step_wise'
[    0.933923] NET: Registered protocol family 2
[    0.934654] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear)
[    0.934707] TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear)
[    0.934801] TCP bind hash table entries: 8192 (order: 4, 65536 bytes, linear)
[    0.934969] TCP: Hash tables configured (established 8192 bind 8192)
[    0.935094] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
[    0.935151] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
[    0.935390] NET: Registered protocol family 1
[    0.936024] RPC: Registered named UNIX socket transport module.
[    0.936050] RPC: Registered udp transport module.
[    0.936069] RPC: Registered tcp transport module.
[    0.936087] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.936312] Trying to unpack rootfs image as initramfs...
[    1.107597] Freeing initrd memory: 11500K
[    1.109375] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 5 counters available
[    1.110173] hw perfevents: enabled with armv7_cortex_a15 PMU driver, 7 counters available
[    1.114224] Initialise system trusted keyrings
[    1.114460] workingset: timestamp_bits=14 max_order=19 bucket_order=5
[    1.123792] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    1.124535] NFS: Registering the id_resolver key type
[    1.124573] Key type id_resolver registered
[    1.124593] Key type id_legacy registered
[    1.124622] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    1.124650] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
[    1.124701] romfs: ROMFS MTD (C) 2007 Red Hat, Inc.
[    1.156046] Key type asymmetric registered
[    1.156069] Asymmetric key parser 'x509' registered
[    1.156136] bounce: pool size: 64 pages
[    1.156189] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245)
[    1.156403] io scheduler mq-deadline registered
[    1.156427] io scheduler kyber registered
[    1.156588] io scheduler bfq registered
[    1.159149] samsung-usb2-phy 12130000.phy: 12130000.phy supply vbus not found, using dummy regulator
[    1.160168] exynos5_usb3drd_phy 12100000.phy: 12100000.phy supply vbus not found, using dummy regulator
[    1.160274] exynos5_usb3drd_phy 12100000.phy: 12100000.phy supply vbus-boost not found, using dummy regulator
[    1.160667] exynos5_usb3drd_phy 12500000.phy: 12500000.phy supply vbus not found, using dummy regulator
[    1.160774] exynos5_usb3drd_phy 12500000.phy: 12500000.phy supply vbus-boost not found, using dummy regulator
[    1.169766] dma-pl330 121a0000.pdma: Loaded driver for PL330 DMAC-241330
[    1.169797] dma-pl330 121a0000.pdma: 	DBUFF-32x4bytes Num_Chans-8 Num_Peri-32 Num_Events-32
[    1.172505] dma-pl330 121b0000.pdma: Loaded driver for PL330 DMAC-241330
[    1.172534] dma-pl330 121b0000.pdma: 	DBUFF-32x4bytes Num_Chans-8 Num_Peri-32 Num_Events-32
[    1.173465] dma-pl330 10800000.mdma: Loaded driver for PL330 DMAC-241330
[    1.173493] dma-pl330 10800000.mdma: 	DBUFF-64x8bytes Num_Chans-8 Num_Peri-1 Num_Events-32
[    1.244886] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled
[    1.247245] 12c20000.serial: ttySAC2 at MMIO 0x12c20000 (irq = 62, base_baud = 0) is a S3C6400/10
[    2.126559] printk: console [ttySAC2] enabled
[    2.132537] exynos-trng 10830600.rng: Exynos True Random Number Generator.
[    2.138881] exynos-mixer 14450000.mixer: Adding to iommu group 0
[    2.146576] exynos-drm-g2d 10850000.g2d: Adding to iommu group 1
[    2.152639] exynos-rotator 11c00000.rotator: Adding to iommu group 2
[    2.164941] mali 11800000.gpu: Failed to get regulator
[    2.168652] mali 11800000.gpu: Power control initialization failed
[    2.185702] brd: module loaded
[    2.188033] libphy: Fixed MDIO Bus: probed
[    2.191737] usbcore: registered new interface driver r8152
[    2.196901] usbcore: registered new interface driver cdc_ether
[    2.202694] usbcore: registered new interface driver cdc_subset
[    2.210766] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    2.215879] ehci-exynos: EHCI EXYNOS driver
[    2.220349] exynos-ehci 12110000.usb: EHCI Host Controller
[    2.225491] exynos-ehci 12110000.usb: new USB bus registered, assigned bus number 1
[    2.233370] exynos-ehci 12110000.usb: irq 79, io mem 0x12110000
[    2.253066] exynos-ehci 12110000.usb: USB 2.0 started, EHCI 1.00
[    2.257897] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.04
[    2.265876] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    2.273060] usb usb1: Product: EHCI Host Controller
[    2.277905] usb usb1: Manufacturer: Linux 5.4.118-221 ehci_hcd
[    2.283710] usb usb1: SerialNumber: 12110000.usb
[    2.288888] hub 1-0:1.0: USB hub found
[    2.292072] hub 1-0:1.0: 3 ports detected
[    2.296943] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    2.302185] ohci-exynos: OHCI EXYNOS driver
[    2.306517] exynos-ohci 12120000.usb: USB Host Controller
[    2.311722] exynos-ohci 12120000.usb: new USB bus registered, assigned bus number 2
[    2.319453] exynos-ohci 12120000.usb: irq 79, io mem 0x12120000
[    2.389287] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 5.04
[    2.396116] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    2.403464] usb usb2: Product: USB Host Controller
[    2.408061] usb usb2: Manufacturer: Linux 5.4.118-221 ohci_hcd
[    2.413866] usb usb2: SerialNumber: 12120000.usb
[    2.419004] hub 2-0:1.0: USB hub found
[    2.422223] hub 2-0:1.0: 3 ports detected
[    2.428379] mousedev: PS/2 mouse device common for all mice
[    2.433702] i2c /dev entries driver
[    2.459143] vdd_ldo9: Bringing 3300000uV into 3000000-3000000uV
[    2.476710] vddq_mmc2: Bringing 3300000uV into 2800000-2800000uV
[    2.497608] vdd_sd: Bringing 3300000uV into 2800000-2800000uV
[    2.563870] vdd_2.0v_ldo: Bringing 1650000uV into 1800000-1800000uV
[    2.587254] s5m-rtc s2mps14-rtc: registered as rtc0
[    2.591352] s2mps11-clk s2mps11-clk: DMA mask not set
[    2.599050] s5p-jpeg 11f50000.jpeg: Adding to iommu group 3
[    2.603767] s5p-jpeg 11f50000.jpeg: encoder device registered as /dev/video20
[    2.610553] s5p-jpeg 11f50000.jpeg: decoder device registered as /dev/video21
[    2.617417] s5p-jpeg 11f50000.jpeg: Samsung S5P JPEG codec
[    2.623149] s5p-jpeg 11f60000.jpeg: Adding to iommu group 4
[    2.628790] s5p-jpeg 11f60000.jpeg: encoder device registered as /dev/video22
[    2.633092] usb 1-1: new high-speed USB device number 2 using exynos-ehci
[    2.635784] s5p-jpeg 11f60000.jpeg: decoder device registered as /dev/video23
[    2.649385] s5p-jpeg 11f60000.jpeg: Samsung S5P JPEG codec
[    2.655539] s5p-mfc 11000000.codec: Adding to iommu group 5
[    2.678609] s5p-mfc 11000000.codec: preallocated 16 MiB buffer for the firmware and context buffers
[    2.687464] s5p-mfc 11000000.codec: decoder registered as /dev/video10
[    2.692889] s5p-mfc 11000000.codec: encoder registered as /dev/video11
[    2.701384] exynos-gsc 13e00000.video-scaler: Adding to iommu group 6
[    2.707609] exynos-gsc 13e10000.video-scaler: Adding to iommu group 7
[    2.723468] s3c2410-wdt 101d0000.watchdog: watchdog inactive, reset disabled, irq disabled
[    2.730918] device-mapper: uevent: version 1.0.3
[    2.735199] device-mapper: ioctl: 4.41.0-ioctl (2019-09-16) initialised: dm-devel@redhat.com
[    2.759885] sdhci: Secure Digital Host Controller Interface driver
[    2.764609] sdhci: Copyright(c) Pierre Ossman
[    2.769166] Synopsys Designware Multimedia Card Interface Driver
[    2.775484] dwmmc_exynos 12200000.mmc: IDMAC supports 32-bit address mode.
[    2.781854] dwmmc_exynos 12200000.mmc: Using internal DMA controller.
[    2.788187] dwmmc_exynos 12200000.mmc: Version ID is 250a
[    2.793575] dwmmc_exynos 12200000.mmc: DW MMC controller at irq 81,64 bit host data width,64 deep fifo
[    2.798933] usb 1-1: New USB device found, idVendor=05e3, idProduct=0608, bcdDevice=85.36
[    2.803289] dwmmc_exynos 12200000.mmc: allocated mmc-pwrseq
[    2.810966] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[    2.810971] usb 1-1: Product: USB2.0 Hub
[    2.816513] mmc_host mmc0: card is non-removable.
[    2.824479] hub 1-1:1.0: USB hub found
[    2.836302] hub 1-1:1.0: 4 ports detected
[    2.843671] mmc_host mmc0: Bus speed (slot 0) = 50000000Hz (slot req 400000Hz, actual 396825HZ div = 63)
[    2.864771] dwmmc_exynos 12220000.mmc: IDMAC supports 32-bit address mode.
[    2.870240] dwmmc_exynos 12220000.mmc: Using internal DMA controller.
[    2.876616] dwmmc_exynos 12220000.mmc: Version ID is 250a
[    2.882003] dwmmc_exynos 12220000.mmc: DW MMC controller at irq 82,64 bit host data width,64 deep fifo
[    2.906558] mmc_host mmc1: Bus speed (slot 0) = 50000000Hz (slot req 400000Hz, actual 396825HZ div = 63)
[    2.921876] mmc_host mmc0: Bus speed (slot 0) = 50000000Hz (slot req 300000Hz, actual 297619HZ div = 84)
[    2.929978] ledtrig-cpu: registered to indicate activity on CPUs
[    2.936776] s5p-secss 10830000.sss: s5p-sss driver registered
[    2.941831] hidraw: raw HID events driver (C) Jiri Kosina
[    2.955019] exynos-nocp: new NoC Probe device registered: 10ca1000.nocp
[    2.960294] exynos-nocp: new NoC Probe device registered: 10ca1400.nocp
[    2.966950] exynos-nocp: new NoC Probe device registered: 10ca1800.nocp
[    2.973451] exynos-nocp: new NoC Probe device registered: 10ca1c00.nocp
[    2.978428] mmc_host mmc0: Bus speed (slot 0) = 50000000Hz (slot req 200000Hz, actual 200000HZ div = 125)
[    2.981092] exynos-ppmu: new PPMU device registered 10d00000.ppmu (ppmu-event3-dmc0_0)
[    2.991192] mmc_host mmc1: Bus speed (slot 0) = 50000000Hz (slot req 50000000Hz, actual 50000000HZ div = 0)
[    2.998070] exynos-ppmu: new PPMU device registered 10d10000.ppmu (ppmu-event3-dmc0_1)
[    3.010880] mmc1: new ultra high speed DDR50 SDHC card at address 0007
[    3.015347] exynos-ppmu: new PPMU device registered 10d60000.ppmu (ppmu-event3-dmc1_0)
[    3.022261] mmcblk1: mmc1:0007 SD4GB 3.71 GiB 
[    3.029740] exynos-ppmu: new PPMU device registered 10d70000.ppmu (ppmu-event3-dmc1_1)
[    3.036071]  mmcblk1: p1 p2
[    3.045460] exynos5-dmc 10c20000.memory-controller: DMC initialized, in irq mode: 0
[    3.046781] mmc_host mmc0: Bus speed (slot 0) = 50000000Hz (slot req 100000Hz, actual 100000HZ div = 250)
[    3.053659] exynos-adc 12d10000.adc: IRQ index 1 not found
[    3.072688] NET: Registered protocol family 17
[    3.075685] NET: Registered protocol family 15
[    3.080119] Key type dns_resolver registered
[    3.084815] Registering SWP/SWPB emulation handler
[    3.089247] registered taskstats version 1
[    3.093172] Loading compiled-in X.509 certificates
[    3.098040] Key type ._fscrypt registered
[    3.101924] Key type .fscrypt registered
[    3.115167] Key type encrypted registered
[    3.129065] usb 1-1.4: new high-speed USB device number 3 using exynos-ehci
[    3.165952] exynos-asv 10000000.chipid: cpu0 opp0, freq: 1500 missing
[    3.171079] exynos-asv 10000000.chipid: cpu4 opp0, freq: 2100 missing
[    3.180339] OF: graph: no port node found in /soc/hdmi@14530000
[    3.186724] [drm] Exynos DRM: using 14450000.mixer device for DMA mapping operations
[    3.193171] exynos-drm exynos-drm: bound 14450000.mixer (ops 0xc0b6e940)
[    3.199728] exynos-drm exynos-drm: bound 14530000.hdmi (ops 0xc0b6efc4)
[    3.206539] exynos-drm-g2d 10850000.g2d: The Exynos G2D (ver 4.1) successfully registered.
[    3.214514] exynos-drm exynos-drm: bound 10850000.g2d (ops 0xc0b6ff0c)
[    3.221065] exynos-rotator 11c00000.rotator: The exynos rotator has been probed successfully
[    3.229422] exynos-drm exynos-drm: bound 11c00000.rotator (ops 0xc0b70798)
[    3.236278] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
[    3.242855] [drm] No driver support for vblank timestamp query.
[    3.249135] [drm] Cannot find any crtc or sizes
[    3.252112] usb 1-1.4: New USB device found, idVendor=15f4, idProduct=0131, bcdDevice= 1.00
[    3.253769] [drm] Cannot find any crtc or sizes
[    3.261567] usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[    3.261574] usb 1-1.4: Product: dvbt2
[    3.261581] usb 1-1.4: Manufacturer: astrometadvbt2
[    3.282539] [drm] Initialized exynos 1.1.0 20180330 for exynos-drm on minor 0
[    3.291558] mali 11800000.gpu: GPU identified as 0x0620 r0p1 status 0
[    3.296702] mali 11800000.gpu: Protected mode not available
[    3.303335] mali 11800000.gpu: Probed as mali0
[    3.308599] dwc3 12000000.dwc3: Failed to get clk 'ref': -2
[    3.313596] xhci-hcd xhci-hcd.8.auto: xHCI Host Controller
[    3.318215] xhci-hcd xhci-hcd.8.auto: new USB bus registered, assigned bus number 3
[    3.326128] xhci-hcd xhci-hcd.8.auto: hcc params 0x0220f04c hci version 0x100 quirks 0x0000000002010010
[    3.335249] xhci-hcd xhci-hcd.8.auto: irq 159, io mem 0x12000000
[    3.341617] usb usb3: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.04
[    3.349381] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    3.356570] usb usb3: Product: xHCI Host Controller
[    3.361419] usb usb3: Manufacturer: Linux 5.4.118-221 xhci-hcd
[    3.367225] usb usb3: SerialNumber: xhci-hcd.8.auto
[    3.372573] hub 3-0:1.0: USB hub found
[    3.375841] hub 3-0:1.0: 1 port detected
[    3.380013] xhci-hcd xhci-hcd.8.auto: xHCI Host Controller
[    3.385173] xhci-hcd xhci-hcd.8.auto: new USB bus registered, assigned bus number 4
[    3.392794] xhci-hcd xhci-hcd.8.auto: Host supports USB 3.0 SuperSpeed
[    3.399357] usb usb4: We don't know the algorithms for LPM for this host, disabling LPM.
[    3.407491] usb usb4: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.04
[    3.410234] devfreq 11800000.gpu: Couldn't update frequency transition information.
[    3.415594] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    3.430409] usb usb4: Product: xHCI Host Controller
[    3.435247] usb usb4: Manufacturer: Linux 5.4.118-221 xhci-hcd
[    3.441060] usb usb4: SerialNumber: xhci-hcd.8.auto
[    3.446380] hub 4-0:1.0: USB hub found
[    3.449658] hub 4-0:1.0: 1 port detected
[    3.455689] dwc3 12400000.dwc3: Failed to get clk 'ref': -2
[    3.460473] xhci-hcd xhci-hcd.9.auto: xHCI Host Controller
[    3.465295] xhci-hcd xhci-hcd.9.auto: new USB bus registered, assigned bus number 5
[    3.473363] xhci-hcd xhci-hcd.9.auto: hcc params 0x0220f04c hci version 0x100 quirks 0x0000000002010010
[    3.482395] xhci-hcd xhci-hcd.9.auto: irq 160, io mem 0x12400000
[    3.488871] usb usb5: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.04
[    3.496480] usb usb5: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    3.503674] usb usb5: Product: xHCI Host Controller
[    3.508518] usb usb5: Manufacturer: Linux 5.4.118-221 xhci-hcd
[    3.514324] usb usb5: SerialNumber: xhci-hcd.9.auto
[    3.519818] hub 5-0:1.0: USB hub found
[    3.522937] hub 5-0:1.0: 1 port detected
[    3.527198] xhci-hcd xhci-hcd.9.auto: xHCI Host Controller
[    3.532270] xhci-hcd xhci-hcd.9.auto: new USB bus registered, assigned bus number 6
[    3.539894] xhci-hcd xhci-hcd.9.auto: Host supports USB 3.0 SuperSpeed
[    3.546482] usb usb6: We don't know the algorithms for LPM for this host, disabling LPM.
[    3.554632] usb usb6: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.04
[    3.562691] usb usb6: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    3.569870] usb usb6: Product: xHCI Host Controller
[    3.574721] usb usb6: Manufacturer: Linux 5.4.118-221 xhci-hcd
[    3.580527] usb usb6: SerialNumber: xhci-hcd.9.auto
[    3.586137] hub 6-0:1.0: USB hub found
[    3.589149] hub 6-0:1.0: 1 port detected
[    3.594735] rtc rtc1: invalid alarm value: 1900-01-21T00:00:00
[    3.599389] s3c-rtc 101e0000.rtc: registered as rtc1
[    3.607362] exynos-bus: new bus device registered: soc:bus_wcore ( 84000 KHz ~ 400000 KHz)
[    3.615324] exynos-bus: new bus device registered: soc:bus_noc ( 67000 KHz ~ 100000 KHz)
[    3.623068] exynos-bus: new bus device registered: soc:bus_fsys_apb (100000 KHz ~ 200000 KHz)
[    3.631248] exynos-bus: new bus device registered: soc:bus_fsys (100000 KHz ~ 200000 KHz)
[    3.639830] exynos-bus: new bus device registered: soc:bus_fsys2 ( 75000 KHz ~ 150000 KHz)
[    3.648328] exynos-bus: new bus device registered: soc:bus_mfc ( 96000 KHz ~ 333000 KHz)
[    3.656262] exynos-bus: new bus device registered: soc:bus_gen ( 89000 KHz ~ 267000 KHz)
[    3.663947] exynos-bus: new bus device registered: soc:bus_peri ( 67000 KHz ~  67000 KHz)
[    3.672584] exynos-bus: new bus device registered: soc:bus_g2d ( 84000 KHz ~ 333000 KHz)
[    3.680514] exynos-bus: new bus device registered: soc:bus_g2d_acp ( 67000 KHz ~ 267000 KHz)
[    3.688951] exynos-bus: new bus device registered: soc:bus_jpeg ( 75000 KHz ~ 300000 KHz)
[    3.697115] exynos-bus: new bus device registered: soc:bus_jpeg_apb ( 84000 KHz ~ 167000 KHz)
[    3.705319] exynos-bus: new bus device registered: soc:bus_disp1_fimd (120000 KHz ~ 200000 KHz)
[    3.714113] exynos-bus: new bus device registered: soc:bus_disp1 (120000 KHz ~ 300000 KHz)
[    3.722341] exynos-bus: new bus device registered: soc:bus_gscl_scaler (150000 KHz ~ 300000 KHz)
[    3.731345] exynos-bus: new bus device registered: soc:bus_mscl ( 84000 KHz ~ 400000 KHz)
[    3.745177] s5m-rtc s2mps14-rtc: setting system clock to 2021-05-28T14:17:20 UTC (1622211440)
[    3.809831] ALSA device list:
[    3.811355]   No soundcards found.
[    3.817250] Freeing unused kernel memory: 1024K
[    3.821576] Run /init as init process
[    3.869168] usb 3-1: new high-speed USB device number 2 using xhci-hcd
[    4.368129] input: gpio_keys as /devices/platform/gpio_keys/input/input0
[    4.729143] usb 6-1: new SuperSpeed Gen 1 USB device number 2 using xhci-hcd
[    4.753763] usb 6-1: New USB device found, idVendor=0bda, idProduct=8153, bcdDevice=30.00
[    4.760503] usb 6-1: New USB device strings: Mfr=1, Product=2, SerialNumber=6
[    4.767640] usb 6-1: Product: USB 10/100/1000 LAN
[    4.772302] usb 6-1: Manufacturer: Realtek
[    4.776370] usb 6-1: SerialNumber: 000001000000
[    4.986784] usb 6-1: reset SuperSpeed Gen 1 USB device number 2 using xhci-hcd
[    5.054414] r8152 6-1:1.0 eth0: v1.10.11
[    5.342902] dma-pl330 3880000.adma: Loaded driver for PL330 DMAC-241330
[    5.348100] dma-pl330 3880000.adma: 	DBUFF-4x8bytes Num_Chans-6 Num_Peri-16 Num_Events-6
[    5.360306] samsung-i2s 3830000.i2s-sec: DMA channels sourced from device 3830000.i2s
[    5.371699] odroid-audio sound: snd-soc-dummy-dai <-> samsung-i2s mapping ok
[    5.377427] odroid-audio sound: i2s-hifi <-> snd-soc-dummy-dai mapping ok
[    5.386037] odroid-audio sound: snd-soc-dummy-dai <-> samsung-i2s-sec mapping ok
[    5.392213] dma-pl330 3880000.adma: PM domain MAU will not be powered off
[    6.301366] usb 4-1: new SuperSpeed Gen 1 USB device number 2 using xhci-hcd
[    6.326793] usb 4-1: New USB device found, idVendor=152d, idProduct=0578, bcdDevice=31.02
[    6.333561] usb 4-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    6.340642] usb 4-1: Product: USB to ATA/ATAPI Bridge
[    6.345665] usb 4-1: Manufacturer: JMicron
[    6.349730] usb 4-1: SerialNumber: 0123456789ABCDEF
[    6.363713] SCSI subsystem initialized
[    6.369270] usbcore: registered new interface driver usb-storage
[    6.387243] scsi host0: uas
[    6.389369] usbcore: registered new interface driver uas
[    6.390486] scsi 0:0:0:0: Direct-Access     JMicron  Generic          3102 PQ: 0 ANSI: 6
[    6.419020] sd 0:0:0:0: [sda] 468862128 512-byte logical blocks: (240 GB/224 GiB)
[    6.425604] sd 0:0:0:0: [sda] 4096-byte physical blocks
[    6.431071] sd 0:0:0:0: [sda] Write Protect is off
[    6.435539] sd 0:0:0:0: [sda] Mode Sense: 53 00 00 08
[    6.436075] sd 0:0:0:0: [sda] Disabling FUA
[    6.439696] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    6.452379] sd 0:0:0:0: [sda] Optimal transfer size 33553920 bytes not a multiple of physical block size (4096 bytes)
[    6.501051]  sda: sda1 sda2
[    6.504874] sd 0:0:0:0: [sda] Attached SCSI disk
[    6.593039] raid6: neonx8   gen()  3649 MB/s
[    6.661052] raid6: neonx8   xor()  2931 MB/s
[    6.729086] raid6: neonx4   gen()  3216 MB/s
[    6.797082] raid6: neonx4   xor()  2798 MB/s
[    6.865106] raid6: neonx2   gen()  2470 MB/s
[    6.933154] raid6: neonx2   xor()  3293 MB/s
[    7.001148] raid6: neonx1   gen()  1865 MB/s
[    7.069052] raid6: neonx1   xor()  2524 MB/s
[    7.137048] raid6: int32x8  gen()   866 MB/s
[    7.205075] raid6: int32x8  xor()   667 MB/s
[    7.273040] raid6: int32x4  gen()   904 MB/s
[    7.341043] raid6: int32x4  xor()   698 MB/s
[    7.409061] raid6: int32x2  gen()   688 MB/s
[    7.477041] raid6: int32x2  xor()   653 MB/s
[    7.545061] raid6: int32x1  gen()   493 MB/s
[    7.613059] raid6: int32x1  xor()   508 MB/s
[    7.615855] raid6: using algorithm neonx8 gen() 3649 MB/s
[    7.621270] raid6: .... xor() 2931 MB/s, rmw enabled
[    7.626219] raid6: using neon recovery algorithm
[    7.636871] xor: measuring software checksum speed
[    7.677088]    arm4regs  :  1776.000 MB/sec
[    7.717088]    8regs     :  1599.000 MB/sec
[    7.757158]    32regs    :  1592.000 MB/sec
[    7.797125]    neon      :  2074.000 MB/sec
[    7.799833] xor: using function: neon (2074.000 MB/sec)
[    7.844986] Btrfs loaded, crc32c=crc32c-generic
[    8.029331] BTRFS: device label ubuntu devid 1 transid 705995 /dev/sda2
[    8.036151] BTRFS: device label sdfs devid 1 transid 36 /dev/mmcblk1p2
[    8.089639] BTRFS info (device sda2): disk space caching is enabled
[    8.094469] BTRFS info (device sda2): has skinny extents
[    8.457882] NET: Registered protocol family 10
[    8.461747] Segment Routing with IPv6
[    8.477472] systemd[1]: systemd 245.4-4ubuntu3.6 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)
[    8.498981] systemd[1]: Detected architecture arm.
[    8.545200] systemd[1]: Set hostname to <oggy-odroid>.
[    8.876312] random: crng init done
[    8.908195] systemd[1]: Created slice system-modprobe.slice.
[    8.930253] systemd[1]: Created slice system-serial\x2dgetty.slice.
[    8.954156] systemd[1]: Created slice system-systemd\x2dfsck.slice.
[    8.979352] systemd[1]: Created slice User and Session Slice.
[    9.001501] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[    9.025392] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[    9.050175] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[    9.077386] systemd[1]: Reached target Local Encrypted Volumes.
[    9.101239] systemd[1]: Reached target Paths.
[    9.121174] systemd[1]: Reached target Remote File Systems.
[    9.141244] systemd[1]: Reached target Slices.
[    9.161918] systemd[1]: Listening on Syslog Socket.
[    9.181682] systemd[1]: Listening on fsck to fsckd communication Socket.
[    9.205382] systemd[1]: Listening on initctl Compatibility Named Pipe.
[    9.230074] systemd[1]: Listening on Journal Audit Socket.
[    9.249576] systemd[1]: Listening on Journal Socket (/dev/log).
[    9.273739] systemd[1]: Listening on Journal Socket.
[    9.293692] systemd[1]: Listening on udev Control Socket.
[    9.313458] systemd[1]: Listening on udev Kernel Socket.
[    9.333593] systemd[1]: Condition check resulted in Huge Pages File System being skipped.
[    9.347585] systemd[1]: Mounting POSIX Message Queue File System...
[    9.379365] systemd[1]: Mounting Kernel Debug File System...
[    9.413603] systemd[1]: Mounting Kernel Trace File System...
[    9.447260] systemd[1]: Starting Journal Service...
[    9.474275] systemd[1]: Starting Set the console keyboard layout...
[    9.501813] systemd[1]: Starting Create list of static device nodes for the current kernel...
[    9.525202] systemd[1]: Condition check resulted in Load Kernel Module drm being skipped.
[    9.532038] systemd[1]: Condition check resulted in OpenVSwitch configuration for cleanup being skipped.
[    9.541839] systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped.
[    9.550463] systemd[1]: Condition check resulted in File System Check on Root Device being skipped.
[    9.563470] systemd[1]: Starting Load Kernel Modules...
[    9.585309] systemd[1]: Starting Remount Root and Kernel File Systems...
[    9.611144] BTRFS info (device sda2): enabling ssd optimizations
[    9.615706] BTRFS info (device sda2): disk space caching is enabled
[    9.621170] systemd[1]: Starting udev Coldplug all Devices...
[    9.648990] systemd[1]: Starting Uncomplicated firewall...
[    9.674524] systemd[1]: Started Journal Service.
[    9.905878] systemd-journald[251]: Received client request to flush runtime journal.
[   10.382656] gpiomem-exynos 13400000.gpiomem: Initialised: GPIO register area is 2
[   10.382956] gpiomem-exynos 13400000.gpiomem: Initialised: Registers at 0x13400000
[   10.382963] gpiomem-exynos 13400000.gpiomem: Initialised: Registers at 0x14010000
[   10.411950] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   10.412653] r8152 6-1:1.0 eth0: carrier on
[   10.421220] [UFW BLOCK] IN= OUT=eth0 SRC=0000:0000:0000:0000:0000:0000:0000:0000 DST=ff02:0000:0000:0000:0000:0000:0000:0016 LEN=76 TC=0 HOPLIMIT=1 FLOWLBL=0 PROTO=ICMPv6 TYPE=143 CODE=0 MARK=0x114 
[   10.460826] usb 1-1.4: dvb_usb_v2: found a 'Astrometa DVB-T2' in warm state
[   10.596553] sd 0:0:0:0: Attached scsi generic sg0 type 0
[   10.617266] usb 1-1.4: dvb_usb_v2: will pass the complete MPEG2 transport stream to the software demuxer
[   10.617331] dvbdev: DVB: registering new adapter (Astrometa DVB-T2)
[   10.617345] usb 1-1.4: media controller created
[   10.618965] dvbdev: dvb_create_media_entity: media entity 'dvb-demux' registered.
[   10.636911] i2c i2c-11: Added multiplexed i2c bus 12
[   10.636926] rtl2832 11-0010: Realtek RTL2832 successfully attached
[   10.659231] mn88473 11-0018: Panasonic MN88473 successfully identified
[   10.659368] usb 1-1.4: DVB: registering adapter 0 frontend 0 (Realtek RTL2832 (DVB-T))...
[   10.659403] dvbdev: dvb_create_media_entity: media entity 'Realtek RTL2832 (DVB-T)' registered.
[   10.659657] usb 1-1.4: DVB: registering adapter 0 frontend 1 (Panasonic MN88473)...
[   10.659673] dvbdev: dvb_create_media_entity: media entity 'Panasonic MN88473' registered.
[   10.674039] r820t 12-003a: creating new instance
[   10.681232] r820t 12-003a: Rafael Micro r820t successfully identified
[   10.681256] r820t 12-003a: attaching existing instance
[   10.686350] r820t 12-003a: Rafael Micro r820t successfully identified
[   10.706662] rtl2832_sdr rtl2832_sdr.10.auto: Registered as swradio0
[   10.706679] rtl2832_sdr rtl2832_sdr.10.auto: Realtek RTL2832 SDR attached
[   10.706690] rtl2832_sdr rtl2832_sdr.10.auto: SDR API is still slightly experimental and functionality changes may follow
[   10.715012] Registered IR keymap rc-empty
[   10.715203] rc rc0: Astrometa DVB-T2 as /devices/platform/soc/12110000.usb/usb1/1-1/1-1.4/rc/rc0
[   10.715441] input: Astrometa DVB-T2 as /devices/platform/soc/12110000.usb/usb1/1-1/1-1.4/rc/rc0/input1
[   10.719162] usb 1-1.4: dvb_usb_v2: schedule remote query interval to 200 msecs
[   10.728840] usb 1-1.4: dvb_usb_v2: 'Astrometa DVB-T2' successfully initialized and connected
[   10.729755] usbcore: registered new interface driver dvb_usb_rtl28xxu
[   10.941145] [UFW BLOCK] IN= OUT=eth0 SRC=0000:0000:0000:0000:0000:0000:0000:0000 DST=ff02:0000:0000:0000:0000:0000:0000:0016 LEN=76 TC=0 HOPLIMIT=1 FLOWLBL=0 PROTO=ICMPv6 TYPE=143 CODE=0 MARK=0x114 
[   11.441214] Adding 2097148k swap on /dev/sda1.  Priority:-2 extents:1 across:2097148k 
[   11.444849] BTRFS info (device mmcblk1p2): enabling ssd optimizations
[   11.444875] BTRFS info (device mmcblk1p2): use zlib compression, level 3
[   11.444892] BTRFS info (device mmcblk1p2): disk space caching is enabled
[   11.444909] BTRFS info (device mmcblk1p2): has skinny extents
[   13.305222] [UFW BLOCK] IN= OUT=eth0 SRC=10.0.0.89 DST=224.0.0.22 LEN=40 TOS=0x00 PREC=0xC0 TTL=1 ID=0 DF PROTO=2 MARK=0x114 
[   14.205064] [UFW BLOCK] IN= OUT=eth0 SRC=10.0.0.89 DST=224.0.0.22 LEN=40 TOS=0x00 PREC=0xC0 TTL=1 ID=0 DF PROTO=2 MARK=0x114 
[   14.295976] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
[   14.298351] Bridge firewalling registered
[   14.713295] Initializing XFRM netlink socket
[   15.137170] [UFW BLOCK] IN= OUT=br-33d2fcf7e0ec SRC=172.30.0.1 DST=224.0.0.22 LEN=48 TOS=0x00 PREC=0xC0 TTL=1 ID=0 DF PROTO=2 MARK=0x114 
[   15.413143] [UFW BLOCK] IN= OUT=docker0 SRC=172.17.0.1 DST=224.0.0.22 LEN=48 TOS=0x00 PREC=0xC0 TTL=1 ID=0 DF PROTO=2 MARK=0x114 
[   15.661177] [UFW BLOCK] IN= OUT=br-b168457ca201 SRC=172.31.0.1 DST=224.0.0.22 LEN=48 TOS=0x00 PREC=0xC0 TTL=1 ID=0 DF PROTO=2 MARK=0x114 
[   16.093105] [UFW BLOCK] IN= OUT=docker0 SRC=172.17.0.1 DST=224.0.0.22 LEN=48 TOS=0x00 PREC=0xC0 TTL=1 ID=0 DF PROTO=2 MARK=0x114 
[   16.097088] [UFW BLOCK] IN= OUT=br-33d2fcf7e0ec SRC=172.30.0.1 DST=224.0.0.22 LEN=48 TOS=0x00 PREC=0xC0 TTL=1 ID=0 DF PROTO=2 MARK=0x114 
[   16.244229] br-33d2fcf7e0ec: port 1(vethad771dd) entered blocking state
[   16.244244] br-33d2fcf7e0ec: port 1(vethad771dd) entered disabled state
[   16.244636] device vethad771dd entered promiscuous mode
[   16.248512] br-33d2fcf7e0ec: port 1(vethad771dd) entered blocking state
[   16.248524] br-33d2fcf7e0ec: port 1(vethad771dd) entered forwarding state
[   16.250959] br-33d2fcf7e0ec: port 1(vethad771dd) entered disabled state
[   16.253134] [UFW BLOCK] IN= OUT=br-b168457ca201 SRC=172.31.0.1 DST=224.0.0.22 LEN=48 TOS=0x00 PREC=0xC0 TTL=1 ID=0 DF PROTO=2 MARK=0x114 
[   16.257383] br-b168457ca201: port 1(vethc4470ba) entered blocking state
[   16.257398] br-b168457ca201: port 1(vethc4470ba) entered disabled state
[   16.258074] device vethc4470ba entered promiscuous mode
[   16.259164] br-b168457ca201: port 1(vethc4470ba) entered blocking state
[   16.259176] br-b168457ca201: port 1(vethc4470ba) entered forwarding state
[   16.262438] br-b168457ca201: port 1(vethc4470ba) entered disabled state
[   16.346409] br-33d2fcf7e0ec: port 2(veth71d060f) entered blocking state
[   16.346422] br-33d2fcf7e0ec: port 2(veth71d060f) entered disabled state
[   16.346858] device veth71d060f entered promiscuous mode
[   16.347806] br-33d2fcf7e0ec: port 2(veth71d060f) entered blocking state
[   16.347823] br-33d2fcf7e0ec: port 2(veth71d060f) entered forwarding state
[   16.348017] IPv6: ADDRCONF(NETDEV_CHANGE): br-33d2fcf7e0ec: link becomes ready
[   16.350631] br-33d2fcf7e0ec: port 2(veth71d060f) entered disabled state
[   16.365150] [UFW BLOCK] IN= OUT=br-33d2fcf7e0ec SRC=0000:0000:0000:0000:0000:0000:0000:0000 DST=ff02:0000:0000:0000:0000:0000:0000:0016 LEN=96 TC=0 HOPLIMIT=1 FLOWLBL=0 PROTO=ICMPv6 TYPE=143 CODE=0 MARK=0x114 
[   16.433590] br-33d2fcf7e0ec: port 3(veth6aed8c6) entered blocking state
[   16.433652] br-33d2fcf7e0ec: port 3(veth6aed8c6) entered disabled state
[   16.434790] device veth6aed8c6 entered promiscuous mode
[   16.436338] br-33d2fcf7e0ec: port 3(veth6aed8c6) entered blocking state
[   16.436352] br-33d2fcf7e0ec: port 3(veth6aed8c6) entered forwarding state
[   16.529186] [UFW BLOCK] IN= OUT=br-33d2fcf7e0ec SRC=0000:0000:0000:0000:0000:0000:0000:0000 DST=ff02:0000:0000:0000:0000:0000:0000:0016 LEN=96 TC=0 HOPLIMIT=1 FLOWLBL=0 PROTO=ICMPv6 TYPE=143 CODE=0 MARK=0x114 
[   16.540008] br-33d2fcf7e0ec: port 4(veth76bec56) entered blocking state
[   16.540018] br-33d2fcf7e0ec: port 4(veth76bec56) entered disabled state
[   16.540794] device veth76bec56 entered promiscuous mode
[   16.543571] br-33d2fcf7e0ec: port 4(veth76bec56) entered blocking state
[   16.543581] br-33d2fcf7e0ec: port 4(veth76bec56) entered forwarding state
[   16.866105] br-33d2fcf7e0ec: port 3(veth6aed8c6) entered disabled state
[   16.866535] br-33d2fcf7e0ec: port 4(veth76bec56) entered disabled state
[   16.877177] [UFW BLOCK] IN= OUT=br-33d2fcf7e0ec SRC=0000:0000:0000:0000:0000:0000:0000:0000 DST=ff02:0000:0000:0000:0000:0000:0000:0016 LEN=96 TC=0 HOPLIMIT=1 FLOWLBL=0 PROTO=ICMPv6 TYPE=143 CODE=0 MARK=0x114 
[   17.044171] cgroup: cgroup: disabling cgroup2 socket matching due to net_prio or net_cls activation
[   17.147571] br-b168457ca201: port 1(vethc4470ba) entered disabled state
[   17.165253] device vethc4470ba left promiscuous mode
[   17.165268] br-b168457ca201: port 1(vethc4470ba) entered disabled state
[   17.629158] [UFW BLOCK] IN= OUT=br-33d2fcf7e0ec SRC=0000:0000:0000:0000:0000:0000:0000:0000 DST=ff02:0000:0000:0000:0000:0000:0000:0016 LEN=96 TC=0 HOPLIMIT=1 FLOWLBL=0 PROTO=ICMPv6 TYPE=143 CODE=0 MARK=0x114 
[   18.625296] eth0: renamed from vethf7babe0
[   18.670008] IPv6: ADDRCONF(NETDEV_CHANGE): vethad771dd: link becomes ready
[   18.670345] br-33d2fcf7e0ec: port 1(vethad771dd) entered blocking state
[   18.670357] br-33d2fcf7e0ec: port 1(vethad771dd) entered forwarding state
[   18.681905] [UFW BLOCK] IN= OUT=vethad771dd SRC=0000:0000:0000:0000:0000:0000:0000:0000 DST=ff02:0000:0000:0000:0000:0000:0000:0016 LEN=76 TC=0 HOPLIMIT=1 FLOWLBL=0 PROTO=ICMPv6 TYPE=143 CODE=0 MARK=0x114 
[   18.810636] eth0: renamed from veth4e41447
[   18.835138] IPv6: ADDRCONF(NETDEV_CHANGE): veth71d060f: link becomes ready
[   18.835348] br-33d2fcf7e0ec: port 2(veth71d060f) entered blocking state
[   18.835356] br-33d2fcf7e0ec: port 2(veth71d060f) entered forwarding state
[   18.897137] [UFW BLOCK] IN= OUT=veth71d060f SRC=0000:0000:0000:0000:0000:0000:0000:0000 DST=ff02:0000:0000:0000:0000:0000:0000:0016 LEN=76 TC=0 HOPLIMIT=1 FLOWLBL=0 PROTO=ICMPv6 TYPE=143 CODE=0 MARK=0x114 
[   18.924903] eth0: renamed from veth9477a98
[   18.973664] eth0: renamed from vethb9477c4
[   19.005909] [UFW BLOCK] IN= OUT=vethad771dd SRC=0000:0000:0000:0000:0000:0000:0000:0000 DST=ff02:0000:0000:0000:0000:0000:0000:0016 LEN=76 TC=0 HOPLIMIT=1 FLOWLBL=0 PROTO=ICMPv6 TYPE=143 CODE=0 MARK=0x114 
[   19.009876] IPv6: ADDRCONF(NETDEV_CHANGE): veth6aed8c6: link becomes ready
[   19.010277] br-33d2fcf7e0ec: port 3(veth6aed8c6) entered blocking state
[   19.010295] br-33d2fcf7e0ec: port 3(veth6aed8c6) entered forwarding state
[   19.016720] IPv6: ADDRCONF(NETDEV_CHANGE): veth76bec56: link becomes ready
[   19.017156] br-33d2fcf7e0ec: port 4(veth76bec56) entered blocking state
[   19.017170] br-33d2fcf7e0ec: port 4(veth76bec56) entered forwarding state
[   19.025159] [UFW BLOCK] IN= OUT=veth6aed8c6 SRC=0000:0000:0000:0000:0000:0000:0000:0000 DST=ff02:0000:0000:0000:0000:0000:0000:0016 LEN=76 TC=0 HOPLIMIT=1 FLOWLBL=0 PROTO=ICMPv6 TYPE=143 CODE=0 MARK=0x114 
[   35.806124] vdd_ldo12: disabling
[   35.807833] vdd_ldo16: disabling
[   35.810938] vdd_ldo24: disabling
[   35.812668] vdd_ldo28: disabling
[   35.814336] vdd_ldo30: disabling
Current boot.ini (tweaked):

Code: Select all

[generic]
# Mac address configuration
macaddr=00:1e:06:61:7a:39

vout=hdmi
HPD=false
cecenable=false
disable_vu7=true
; ddr_freq=933
; board_name=hc1

; Device Tree Overlay
; overlay_resize=16384
; overlay_profile=
; overlays="spi0 i2c0 i2c1 uart0"

[overlay_custom]
overlays="i2c0 i2c1"

[overlay_hktft32]
overlays="hktft32 ads7846"

[overlay_hktft35]
overlays="hktft35 sx865x-i2c1"

[overlay_hktft_cs_ogst]
overlays="hktft-cs-ogst"
Top
linuxtardis
Posts: 11
Joined: Fri May 28, 2021 1:07 am
languages_spoken: english
ODROIDs: Odroid HC2
Has thanked: 0
Been thanked: 1 time
Contact:
Contact linuxtardis

Re: USB controller dying under heavy IO load

Post by linuxtardis »

After doing some boot.scr/flash-kernel hacks, I managed to boot the 5.10.0-6-armmp kernel from Debian. Unfortunately the crash happens there too:

Code: Select all

[  231.424537] xhci-hcd xhci-hcd.7.auto: xHCI host controller not responding, assume dead
[  231.431499] xhci-hcd xhci-hcd.7.auto: HC died; cleaning up
[  231.439404] usb 4-1: cmd cmplt err -108
[  231.442021] usb 4-1: cmd cmplt err -108
[  231.445942] usb 4-1: cmd cmplt err -108
[  231.449636] usb 4-1: cmd cmplt err -108
[  231.453437] usb 4-1: cmd cmplt err -108
[  231.457286] usb 4-1: cmd cmplt err -108
[  231.461063] usb 4-1: cmd cmplt err -108
[  231.464873] usb 4-1: cmd cmplt err -108
[  231.492427] blk_update_request: I/O error, dev sda, sector 246039488 op 0x0:(READ) flags 0x1000 phys_seg 4 prio class 0
[  231.502006] BTRFS error (device sda2): bdev /dev/sda2 errs: wr 0, rd 1, flush 0, corrupt 0, gen 0
[  231.511726] BTRFS error (device sda2): bdev /dev/sda2 errs: wr 0, rd 2, flush 0, corrupt 0, gen 0
Top
linuxtardis
Posts: 11
Joined: Fri May 28, 2021 1:07 am
languages_spoken: english
ODROIDs: Odroid HC2
Has thanked: 0
Been thanked: 1 time
Contact:
Contact linuxtardis

Re: USB controller dying under heavy IO load

Post by linuxtardis »

It looks like a maiinline regression between 4.14 and 5.4. I have now booted Debian 4.9.0-15-armmp (so without non-mainline code) and btrfs scrub finished without problems (in UAS mode).
Top
linuxtardis
Posts: 11
Joined: Fri May 28, 2021 1:07 am
languages_spoken: english
ODROIDs: Odroid HC2
Has thanked: 0
Been thanked: 1 time
Contact:
Contact linuxtardis

Re: USB controller dying under heavy IO load

Post by linuxtardis »

Debian kernel 4.19.0-16-armmp is also failing to finish the scrub.
Top
linuxtardis
Posts: 11
Joined: Fri May 28, 2021 1:07 am
languages_spoken: english
ODROIDs: Odroid HC2
Has thanked: 0
Been thanked: 1 time
Contact:
Contact linuxtardis

Re: USB controller dying under heavy IO load

Post by linuxtardis »

I'm trying to bisect the issue using the mainline kernel; it seems that the issue was introduced between the 4.18 and 4.19 releases.
Top
linuxtardis
Posts: 11
Joined: Fri May 28, 2021 1:07 am
languages_spoken: english
ODROIDs: Odroid HC2
Has thanked: 0
Been thanked: 1 time
Contact:
Contact linuxtardis

Re: USB controller dying under heavy IO load

Post by linuxtardis »

It seems the problem is caused by commit https://github.com/torvalds/linux/commi ... 27b81bddf7. Mainline kernels before this commit perform the scrub successfully; starting with this commit btrfs scrub fails.

I've also noticed that on mainline kernels I often get messages like this during the scrub:

Code: Select all

[   47.532829] xhci-hcd xhci-hcd.3.auto: ERROR unknown event type 37
From some googling it seems that this is caused by too slow handling of XHCI events.

EDIT: I have irqbalance enabled; I'm wondering if that might be causing problems like this.
EDIT2: Crash occurs even with irqbalance uninstalled.
EDIT3: Interestingly, I don't see the "ERROR unknown event type 37" messages under Hardkernel 4.14 kernel at all. However, I've seen them under mainline 4.14.
EDIT4: The JMS578 USB-SATA bridge is currently running firmware v173.1.0.2.
These users thanked the author linuxtardis for the post:
odroid (Mon May 31, 2021 10:16 am)
Top
linuxtardis
Posts: 11
Joined: Fri May 28, 2021 1:07 am
languages_spoken: english
ODROIDs: Odroid HC2
Has thanked: 0
Been thanked: 1 time
Contact:
Contact linuxtardis

Re: USB controller dying under heavy IO load

Post by linuxtardis »

I think that the "unknown event" error and the crash might not be related. I've cherry-picked the problematic commit on top of the odroidxu4-4.14.y branch and I got the crash there too.
Top
User avatar
mad_ady
Posts: 11446
Joined: Wed Jul 15, 2015 5:00 pm
languages_spoken: english
ODROIDs: XU4 (HC1, HC2), C1+, C2, C4 (HC4), N1, N2, N2L, H2, H3+, Go, Go Advance, M1
Location: Bucharest, Romania
Has thanked: 647 times
Been thanked: 1115 times
Contact:
Contact mad_ady

Re: USB controller dying under heavy IO load

Post by mad_ady »

Early on when I moved from 3.10 to 4.9 there were instabilities with regard to attached storage that could be mitigated by reducing the queue length for the device. Something like:

Code: Select all

echo 100 > /sys/class/block/sda/queue/max_sectors_kb
You could try it before doing intensive IO operations.
Top
User avatar
rooted
Posts: 10137
Joined: Fri Dec 19, 2014 9:12 am
languages_spoken: english
Location: Gulf of Mexico, US
Has thanked: 791 times
Been thanked: 605 times
Contact:
Contact rooted

Re: USB controller dying under heavy IO load

Post by rooted »

I've been running 5.4.3 on my cloudshell 2 for well over a year and have no issues, current uptime is 83 days.

The branch is from Hardkernel git.

Looking at the modules modified date it seems to be from January 6, 2020
Top
linuxtardis
Posts: 11
Joined: Fri May 28, 2021 1:07 am
languages_spoken: english
ODROIDs: Odroid HC2
Has thanked: 0
Been thanked: 1 time
Contact:
Contact linuxtardis

Re: USB controller dying under heavy IO load

Post by linuxtardis »

Thank you for your replies. I've now tried decreasing the maximum queue length, but unfortunately the crash persists.

Code: Select all

   47.968246] BTRFS info (device sda2): scrub: started on devid 1
[   93.241871] sd 0:0:0:0: [sda] tag#29 uas_eh_abort_handler 0 uas-tag 3 inflight: CMD IN 
[   93.241898] sd 0:0:0:0: [sda] tag#29 CDB: opcode=0x28 28 00 01 16 ef 88 00 00 10 00
[   98.349543] xhci-hcd xhci-hcd.8.auto: xHCI host not responding to stop endpoint command.
[   98.365582] xhci-hcd xhci-hcd.8.auto: Host halt failed, -110
[   98.365594] xhci-hcd xhci-hcd.8.auto: xHCI host controller not responding, assume dead
[   98.372061] usb 4-1: cmd cmplt err -108
[   98.375857] usb 4-1: cmd cmplt err -108
[   98.380660] xhci-hcd xhci-hcd.8.auto: HC died; cleaning up
[   98.381082] sd 0:0:0:0: [sda] tag#28 uas_eh_abort_handler 0 uas-tag 30 inflight: CMD 
[   98.385129] sd 0:0:0:0: [sda] tag#28 CDB: opcode=0x28 28 00 01 16 f1 a8 00 00 c8 00
[   98.385608] usb 4-1: USB disconnect, device number 2
However, I have partially solved the problem that led me to wanting to move to kernel 5.4. I've managed to (locally, not in the Hardkernel git) merge the latest mainline stable 4.14 into the Hardkernel XU4 tree and the resulting build is running stable so far; so hopefully the kernel has the necessary security patches for now.
Top
odroiduser3
Posts: 1
Joined: Wed Jan 11, 2023 5:52 am
languages_spoken: english
ODROIDs: 2x HC2, 1x N2
Has thanked: 0
Been thanked: 0
Contact:
Contact odroiduser3

Re: USB controller dying under heavy IO load

Post by odroiduser3 »

I'm having exactly same issue after switching from armbian kernel 4.14 to 5.4

Now I'm using Armbian 22.11.1 Focal with Linux 5.4.225-odroidxu4 and after 6 minutes of scrub the controller chash:

Code: Select all

UUID:             c06e2135-6cca-4614-8773-b0c519cccb47
Scrub started:    Tue Jan 10 21:44:39 2023
Status:           aborted
Duration:         0:06:51
Total to scrub:   426.89GiB
Rate:             190.54MiB/s
Error summary:    read=19872
  Corrected:      0
  Uncorrectable:  19872
  Unverified:     0
erros from dmesg:

Code: Select all

[1765478.078438] BTRFS info (device dm-1): scrub: started on devid 1
[1765884.045125] sd 0:0:0:0: [sda] tag#29 uas_eh_abort_handler 0 uas-tag 20 inflight: CMD IN
[1765884.045147] sd 0:0:0:0: [sda] tag#29 CDB: opcode=0x28 28 00 0a a5 f9 00 00 02 00 00
[1765889.064907] xhci-hcd xhci-hcd.7.auto: xHCI host not responding to stop endpoint command.
[1765889.085087] xhci-hcd xhci-hcd.7.auto: xHCI host controller not responding, assume dead
[1765889.094411] xhci-hcd xhci-hcd.7.auto: HC died; cleaning up
[1765889.095101] sd 0:0:0:0: [sda] tag#28 uas_eh_abort_handler 0 uas-tag 18 inflight: CMD
[1765889.098594] sd 0:0:0:0: [sda] tag#28 CDB: opcode=0x28 28 00 0a a5 f2 00 00 04 00 00
[1765889.098607] sd 0:0:0:0: [sda] tag#27 uas_eh_abort_handler 0 uas-tag 9 inflight: CMD
[1765889.098615] sd 0:0:0:0: [sda] tag#27 CDB: opcode=0x28 28 00 0a a5 ee 00 00 04 00 00
[1765889.098627] sd 0:0:0:0: [sda] tag#26 uas_eh_abort_handler 0 uas-tag 25 inflight: CMD
[1765889.098637] sd 0:0:0:0: [sda] tag#26 CDB: opcode=0x28 28 00 0a a6 0d 00 00 01 00 00
[1765889.098654] sd 0:0:0:0: [sda] tag#25 uas_eh_abort_handler 0 uas-tag 8 inflight: CMD
[1765889.098667] sd 0:0:0:0: [sda] tag#25 CDB: opcode=0x28 28 00 0a a5 ea 00 00 04 00 00
[1765889.098685] sd 0:0:0:0: [sda] tag#24 uas_eh_abort_handler 0 uas-tag 1 inflight: CMD
[1765889.098694] usb 4-1: USB disconnect, device number 2
[1765889.098698] sd 0:0:0:0: [sda] tag#24 CDB: opcode=0x28 28 00 0a a6 1b 00 00 02 00 00
[1765889.098710] sd 0:0:0:0: [sda] tag#23 uas_eh_abort_handler 0 uas-tag 24 inflight: CMD
[1765889.098720] sd 0:0:0:0: [sda] tag#23 CDB: opcode=0x28 28 00 0a a6 0c 00 00 01 00 00
[1765889.098741] sd 0:0:0:0: [sda] tag#22 uas_eh_abort_handler 0 uas-tag 23 inflight: CMD
[1765889.098752] sd 0:0:0:0: [sda] tag#22 CDB: opcode=0x28 28 00 0a a6 09 00 00 01 00 00
[1765889.098770] sd 0:0:0:0: [sda] tag#21 uas_eh_abort_handler 0 uas-tag 2 inflight: CMD
[1765889.098781] sd 0:0:0:0: [sda] tag#21 CDB: opcode=0x28 28 00 0a a6 05 00 00 02 00 00
[1765889.098798] sd 0:0:0:0: [sda] tag#20 uas_eh_abort_handler 0 uas-tag 22 inflight: CMD
[1765889.098810] sd 0:0:0:0: [sda] tag#20 CDB: opcode=0x28 28 00 0a a6 01 00 00 04 00 00
[1765889.098826] sd 0:0:0:0: [sda] tag#19 uas_eh_abort_handler 0 uas-tag 6 inflight: CMD
[1765889.098837] sd 0:0:0:0: [sda] tag#19 CDB: opcode=0x28 28 00 0a a6 1a 00 00 01 00 00
[1765889.098853] sd 0:0:0:0: [sda] tag#18 uas_eh_abort_handler 0 uas-tag 5 inflight: CMD
[1765889.098862] sd 0:0:0:0: [sda] tag#18 CDB: opcode=0x28 28 00 0a a6 16 00 00 04 00 00
[1765889.098879] sd 0:0:0:0: [sda] tag#17 uas_eh_abort_handler 0 uas-tag 15 inflight: CMD
[1765889.098888] sd 0:0:0:0: [sda] tag#17 CDB: opcode=0x28 28 00 0a a6 12 00 00 01 00 00
[1765889.098903] sd 0:0:0:0: [sda] tag#16 uas_eh_abort_handler 0 uas-tag 4 inflight: CMD
[1765889.098913] sd 0:0:0:0: [sda] tag#16 CDB: opcode=0x28 28 00 0a a6 0f 00 00 01 00 00
[1765889.098926] sd 0:0:0:0: [sda] tag#15 uas_eh_abort_handler 0 uas-tag 13 inflight: CMD
[1765889.098934] sd 0:0:0:0: [sda] tag#15 CDB: opcode=0x28 28 00 0a a6 0a 00 00 01 00 00
[1765889.098949] sd 0:0:0:0: [sda] tag#14 uas_eh_abort_handler 0 uas-tag 11 inflight: CMD
[1765889.098957] sd 0:0:0:0: [sda] tag#14 CDB: opcode=0x28 28 00 0a a5 ff 00 00 01 00 00
[1765889.098971] sd 0:0:0:0: [sda] tag#13 uas_eh_abort_handler 0 uas-tag 19 inflight: CMD
[1765889.098981] sd 0:0:0:0: [sda] tag#13 CDB: opcode=0x28 28 00 0a a5 f6 00 00 03 00 00
[1765889.098994] sd 0:0:0:0: [sda] tag#12 uas_eh_abort_handler 0 uas-tag 17 inflight: CMD
[1765889.099003] sd 0:0:0:0: [sda] tag#12 CDB: opcode=0x28 28 00 0a a6 23 00 00 01 00 00
[1765889.099016] sd 0:0:0:0: [sda] tag#11 uas_eh_abort_handler 0 uas-tag 16 inflight: CMD
[1765889.099024] sd 0:0:0:0: [sda] tag#11 CDB: opcode=0x28 28 00 0a a6 1f 00 00 04 00 00
[1765889.099036] sd 0:0:0:0: [sda] tag#10 uas_eh_abort_handler 0 uas-tag 30 inflight: CMD
[1765889.099045] sd 0:0:0:0: [sda] tag#10 CDB: opcode=0x28 28 00 0a a6 15 00 00 01 00 00
[1765889.099062] sd 0:0:0:0: [sda] tag#9 uas_eh_abort_handler 0 uas-tag 29 inflight: CMD
[1765889.099072] sd 0:0:0:0: [sda] tag#9 CDB: opcode=0x28 28 00 0a a6 14 00 00 01 00 00
[1765889.099087] sd 0:0:0:0: [sda] tag#8 uas_eh_abort_handler 0 uas-tag 28 inflight: CMD
[1765889.099103] sd 0:0:0:0: [sda] tag#8 CDB: opcode=0x28 28 00 0a a6 13 00 00 01 00 00
[1765889.099118] sd 0:0:0:0: [sda] tag#7 uas_eh_abort_handler 0 uas-tag 27 inflight: CMD
[1765889.099128] sd 0:0:0:0: [sda] tag#7 CDB: opcode=0x28 28 00 0a a6 11 00 00 01 00 00
[1765889.099143] sd 0:0:0:0: [sda] tag#6 uas_eh_abort_handler 0 uas-tag 14 inflight: CMD
[1765889.099153] sd 0:0:0:0: [sda] tag#6 CDB: opcode=0x28 28 00 0a a6 10 00 00 01 00 00
[1765889.099167] sd 0:0:0:0: [sda] tag#5 uas_eh_abort_handler 0 uas-tag 26 inflight: CMD
[1765889.099175] sd 0:0:0:0: [sda] tag#5 CDB: opcode=0x28 28 00 0a a6 0e 00 00 01 00 00
[1765889.099189] sd 0:0:0:0: [sda] tag#4 uas_eh_abort_handler 0 uas-tag 7 inflight: CMD
[1765889.099200] sd 0:0:0:0: [sda] tag#4 CDB: opcode=0x28 28 00 0a a6 1d 00 00 02 00 00
[1765889.099214] sd 0:0:0:0: [sda] tag#3 uas_eh_abort_handler 0 uas-tag 3 inflight: CMD
[1765889.099223] sd 0:0:0:0: [sda] tag#3 CDB: opcode=0x28 28 00 0a a6 0b 00 00 01 00 00
[1765889.099237] sd 0:0:0:0: [sda] tag#2 uas_eh_abort_handler 0 uas-tag 21 inflight: CMD
[1765889.099245] sd 0:0:0:0: [sda] tag#2 CDB: opcode=0x28 28 00 0a a6 07 00 00 02 00 00
[1765889.099260] sd 0:0:0:0: [sda] tag#1 uas_eh_abort_handler 0 uas-tag 12 inflight: CMD
[1765889.099270] sd 0:0:0:0: [sda] tag#1 CDB: opcode=0x28 28 00 0a a6 00 00 00 01 00 00
[1765889.099284] sd 0:0:0:0: [sda] tag#0 uas_eh_abort_handler 0 uas-tag 10 inflight: CMD
[1765889.099293] sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 0a a5 fb 00 00 04 00 00
[1765889.136196] sd 0:0:0:0: [sda] Synchronizing SCSI cache
[1765889.145065] sd 0:0:0:0: Device offlined - not ready after error recovery
[1765889.145077] sd 0:0:0:0: Device offlined - not ready after error recovery
[1765889.145085] sd 0:0:0:0: Device offlined - not ready after error recovery
[1765889.145091] sd 0:0:0:0: Device offlined - not ready after error recovery
[1765889.145098] sd 0:0:0:0: Device offlined - not ready after error recovery
[1765889.145105] sd 0:0:0:0: Device offlined - not ready after error recovery
[1765889.145112] sd 0:0:0:0: Device offlined - not ready after error recovery
[1765889.145120] sd 0:0:0:0: Device offlined - not ready after error recovery
[1765889.145126] sd 0:0:0:0: Device offlined - not ready after error recovery
[1765889.145132] sd 0:0:0:0: Device offlined - not ready after error recovery
[1765889.145138] sd 0:0:0:0: Device offlined - not ready after error recovery
[1765889.145145] sd 0:0:0:0: Device offlined - not ready after error recovery
[1765889.145152] sd 0:0:0:0: Device offlined - not ready after error recovery
[1765889.145159] sd 0:0:0:0: Device offlined - not ready after error recovery
[1765889.145167] sd 0:0:0:0: Device offlined - not ready after error recovery
[1765889.145174] sd 0:0:0:0: Device offlined - not ready after error recovery
[1765889.145193] sd 0:0:0:0: Device offlined - not ready after error recovery
[1765889.145198] sd 0:0:0:0: Device offlined - not ready after error recovery
[1765889.145204] sd 0:0:0:0: Device offlined - not ready after error recovery
[1765889.145210] sd 0:0:0:0: Device offlined - not ready after error recovery
[1765889.145216] sd 0:0:0:0: Device offlined - not ready after error recovery
[1765889.145222] sd 0:0:0:0: Device offlined - not ready after error recovery
[1765889.145228] sd 0:0:0:0: Device offlined - not ready after error recovery
[1765889.145234] sd 0:0:0:0: Device offlined - not ready after error recovery
[1765889.145241] sd 0:0:0:0: Device offlined - not ready after error recovery
[1765889.145247] sd 0:0:0:0: Device offlined - not ready after error recovery
[1765889.145254] sd 0:0:0:0: Device offlined - not ready after error recovery
[1765889.145259] sd 0:0:0:0: Device offlined - not ready after error recovery
[1765889.145265] sd 0:0:0:0: Device offlined - not ready after error recovery
[1765889.145271] sd 0:0:0:0: Device offlined - not ready after error recovery
[1765889.174956] blk_update_request: I/O error, dev sda, sector 178649856 op 0x0:(READ) flags 0x0 phys_seg 128 prio class 0
[1765889.184432] blk_update_request: I/O error, dev sda, sector 178651136 op 0x0:(READ) flags 0x0 phys_seg 32 prio class 0
[1765889.185030] BTRFS error (device dm-1): bdev /dev/mapper/nas_vg-nas_data_lv errs: wr 0, rd 1, flush 0, corrupt 0, gen 0
[1765889.195319] blk_update_request: I/O error, dev sda, sector 178652928 op 0x0:(READ) flags 0x0 phys_seg 64 prio class 0
[1765889.206971] BTRFS error (device dm-1): bdev /dev/mapper/nas_vg-nas_data_lv errs: wr 0, rd 2, flush 0, corrupt 0, gen 0
[1765889.216801] blk_update_request: I/O error, dev sda, sector 178653952 op 0x0:(READ) flags 0x0 phys_seg 32 prio class 0
[1765889.230589] BTRFS error (device dm-1): bdev /dev/mapper/nas_vg-nas_data_lv errs: wr 0, rd 3, flush 0, corrupt 0, gen 0
[1765889.238379] blk_update_request: I/O error, dev sda, sector 178658560 op 0x0:(READ) flags 0x0 phys_seg 64 prio class 0
[1765889.238471] blk_update_request: I/O error, dev sda, sector 178654720 op 0x0:(READ) flags 0x0 phys_seg 32 prio class 0
[1765889.238650] blk_update_request: I/O error, dev sda, sector 178660352 op 0x0:(READ) flags 0x4000 phys_seg 128 prio class 0
[1765889.238834] blk_update_request: I/O error, dev sda, sector 178661376 op 0x0:(READ) flags 0x0 phys_seg 64 prio class 0
[1765889.255826] BTRFS error (device dm-1): bdev /dev/mapper/nas_vg-nas_data_lv errs: wr 0, rd 4, flush 0, corrupt 0, gen 0
[1765889.284971] blk_update_request: I/O error, dev sda, sector 178655232 op 0x0:(READ) flags 0x0 phys_seg 32 prio class 0
[1765889.293778] BTRFS error (device dm-1): bdev /dev/mapper/nas_vg-nas_data_lv errs: wr 0, rd 5, flush 0, corrupt 0, gen 0
[1765889.303306] blk_update_request: I/O error, dev sda, sector 178655488 op 0x0:(READ) flags 0x0 phys_seg 32 prio class 0
[1765889.335728] BTRFS error (device dm-1): bdev /dev/mapper/nas_vg-nas_data_lv errs: wr 0, rd 6, flush 0, corrupt 0, gen 0
[1765889.346485] BTRFS error (device dm-1): bdev /dev/mapper/nas_vg-nas_data_lv errs: wr 0, rd 7, flush 0, corrupt 0, gen 0
[1765889.357418] BTRFS error (device dm-1): bdev /dev/mapper/nas_vg-nas_data_lv errs: wr 0, rd 8, flush 0, corrupt 0, gen 0
[1765889.368379] BTRFS error (device dm-1): bdev /dev/mapper/nas_vg-nas_data_lv errs: wr 0, rd 9, flush 0, corrupt 0, gen 0
[1765889.379790] BTRFS error (device dm-1): bdev /dev/mapper/nas_vg-nas_data_lv errs: wr 0, rd 10, flush 0, corrupt 0, gen 0
[1765889.390381] BTRFS error (device dm-1): unable to fixup (regular) error at logical 81794564096 on dev /dev/mapper/nas_vg-nas_data_lv
[1765889.402411] BTRFS error (device dm-1): unable to fixup (regular) error at logical 81796136960 on dev /dev/mapper/nas_vg-nas_data_lv
[1765889.414052] BTRFS error (device dm-1): unable to fixup (regular) error at logical 81794957312 on dev /dev/mapper/nas_vg-nas_data_lv
[1765889.414060] BTRFS error (device dm-1): unable to fixup (regular) error at logical 81794695168 on dev /dev/mapper/nas_vg-nas_data_lv
[1765889.414099] BTRFS error (device dm-1): unable to fixup (regular) error at logical 81796268032 on dev /dev/mapper/nas_vg-nas_data_lv
[1765889.414565] BTRFS error (device dm-1): unable to fixup (regular) error at logical 81794826240 on dev /dev/mapper/nas_vg-nas_data_lv
[1765889.414693] BTRFS error (device dm-1): unable to fixup (regular) error at logical 81794830336 on dev /dev/mapper/nas_vg-nas_data_lv
[1765889.414808] BTRFS error (device dm-1): unable to fixup (regular) error at logical 81794834432 on dev /dev/mapper/nas_vg-nas_data_lv
[1765889.418792] BTRFS error (device dm-1): unable to fixup (regular) error at logical 81794838528 on dev /dev/mapper/nas_vg-nas_data_lv
[1765889.418912] BTRFS error (device dm-1): unable to fixup (regular) error at logical 81794842624 on dev /dev/mapper/nas_vg-nas_data_lv
[1765889.652392] BTRFS: error (device dm-1) in btrfs_commit_transaction:2274: errno=-5 IO failure (Error while writing out transaction)
[1765889.662954] BTRFS info (device dm-1): forced readonly
[1765889.662968] BTRFS warning (device dm-1): Skipping commit of aborted transaction.
[1765889.662976] BTRFS: error (device dm-1) in cleanup_transaction:1826: errno=-5 IO failure
[1765889.663090] BTRFS info (device dm-1): scrub: not finished on devid 1 with status: -5
[1765889.671039] BTRFS info (device dm-1): delayed_refs has NO entry
[1765889.694915] sd 0:0:0:0: [sda] Synchronize Cache(10) failed: Result: hostbyte=0x07 driverbyte=0x00
[1765889.745854] xhci-hcd xhci-hcd.7.auto: WARN Can't disable streams for endpoint 0x82, streams are being disabled already
Top
swizzodroid
Posts: 2
Joined: Sat Jan 14, 2023 3:42 am
languages_spoken: english
ODROIDs: XU4
Has thanked: 0
Been thanked: 0
Contact:
Contact swizzodroid

Re: USB controller dying under heavy IO load

Post by swizzodroid »

I have the same problem with XU4 and kernel 5.4.227-248 (Ubuntu 20.04.5 LTS). After a couple of minutes of heavy I/O (copying files from one HDD to SSD) the USB drives disappear from system, with the following dmesg messages:

Code: Select all

[ 2227.445203] sd 0:0:0:0: [sda] tag#25 uas_eh_abort_handler 0 uas-tag 8 inflight: CMD OUT 
[ 2227.445213] sd 0:0:0:0: [sda] tag#25 CDB: opcode=0x2a 2a 00 05 38 97 30 00 04 00 00
[ 2229.237210] sd 1:0:0:0: [sdb] tag#19 uas_eh_abort_handler 0 uas-tag 2 inflight: CMD IN 
[ 2229.237219] sd 1:0:0:0: [sdb] tag#19 CDB: opcode=0x88 88 00 00 00 00 00 4f dd 93 50 00 00 04 00 00 00
[ 2232.553205] xhci-hcd xhci-hcd.8.auto: xHCI host not responding to stop endpoint command.
[ 2232.573376] xhci-hcd xhci-hcd.8.auto: xHCI host controller not responding, assume dead
[ 2232.579895] sd 0:0:0:0: [sda] tag#7 uas_eh_abort_handler 0 uas-tag 4 inflight: CMD 
[ 2232.579904] sd 0:0:0:0: [sda] tag#7 CDB: opcode=0x2a 2a 00 02 10 b1 10 00 00 08 00
[ 2232.579914] sd 0:0:0:0: [sda] tag#6 uas_eh_abort_handler 0 uas-tag 2 inflight: CMD 
[ 2232.579919] usb 4-1.1: cmd cmplt err -108
[ 2232.579922] sd 0:0:0:0: [sda] tag#6 CDB: opcode=0x2a 2a 00 02 10 b0 e0 00 00 30 00
[ 2232.583796] usb 4-1.1: cmd cmplt err -108
[ 2232.587986] sd 1:0:0:0: [sdb] tag#16 uas_eh_abort_handler 0 uas-tag 3 inflight: CMD IN 
[ 2232.587995] sd 1:0:0:0: [sdb] tag#16 CDB: opcode=0x88 88 00 00 00 00 00 4f dd 97 50 00 00 02 98 00 00
[ 2232.588197] usb 4-1.2: cmd cmplt err -108
[ 2232.591770] usb 4-1.2: cmd cmplt err -108
[ 2232.595754] usb 4-1.2: cmd cmplt err -108
[ 2232.599741] usb 4-1.2: cmd cmplt err -108
[ 2232.603726] usb 4-1.2: cmd cmplt err -108
[ 2232.607712] usb 4-1.2: cmd cmplt err -108
[ 2232.611698] usb 4-1.2: cmd cmplt err -108
[ 2232.615684] usb 4-1.2: cmd cmplt err -108
[ 2232.619669] usb 4-1.2: cmd cmplt err -108
[ 2232.623656] usb 4-1.2: cmd cmplt err -108
[ 2232.627642] usb 4-1.2: cmd cmplt err -108
[ 2232.631628] usb 4-1.2: cmd cmplt err -108
[ 2232.635614] usb 4-1.2: cmd cmplt err -108
[ 2232.639600] usb 4-1.2: cmd cmplt err -108
[ 2232.643586] usb 4-1.2: cmd cmplt err -108
[ 2232.647573] usb 4-1.2: cmd cmplt err -108
[ 2232.651558] usb 4-1.2: cmd cmplt err -108
[ 2232.655544] usb 4-1.2: cmd cmplt err -108
[ 2232.659531] usb 4-1.2: cmd cmplt err -108
[ 2232.663517] usb 4-1.2: cmd cmplt err -108
[ 2232.667503] usb 4-1.2: cmd cmplt err -108
[ 2232.671489] usb 4-1.2: cmd cmplt err -108
[ 2232.675476] xhci-hcd xhci-hcd.8.auto: HC died; cleaning up
[ 2232.681137] usb 3-1: USB disconnect, device number 2
[ 2232.682360] usb 4-1: USB disconnect, device number 2
[ 2232.682368] usb 4-1.1: USB disconnect, device number 3
...
It is very annoying since I use the Odroid as a NAS device. I didn't have the problem with the old Ubuntu 16.04 version.
Top
User avatar
mad_ady
Posts: 11446
Joined: Wed Jul 15, 2015 5:00 pm
languages_spoken: english
ODROIDs: XU4 (HC1, HC2), C1+, C2, C4 (HC4), N1, N2, N2L, H2, H3+, Go, Go Advance, M1
Location: Bucharest, Romania
Has thanked: 647 times
Been thanked: 1115 times
Contact:
Contact mad_ady

Re: USB controller dying under heavy IO load

Post by mad_ady »

Back in the day of kernel 3.x I used to have issues too with throughput. I was able to get stable results lowering this queue:
echo 100 > /sys/class/block/$device/queue/max_sectors_kb. See if it improves stability for you (it affects performance a bit)

You're also using uas. It may be useful to try with usb-storage instead (either blacklist uas, or add usb-quirks for your controller)
Top
swizzodroid
Posts: 2
Joined: Sat Jan 14, 2023 3:42 am
languages_spoken: english
ODROIDs: XU4
Has thanked: 0
Been thanked: 0
Contact:
Contact swizzodroid

Re: USB controller dying under heavy IO load

Post by swizzodroid »

Thanks @mat_ady, it helped a bit, the I/O intensive copy process got much further, but after several minutes it did crash with a kernel panic. It is something new, I don't know whether it is related to the original issue:

Code: Select all

Message from syslogd@odroid at Jan 14 10:52:06 ...
 kernel:[52835.785470] Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b
Now I am going to try the usb-quirks as well, let's see if it gets me even further.
Top
User avatar
mad_ady
Posts: 11446
Joined: Wed Jul 15, 2015 5:00 pm
languages_spoken: english
ODROIDs: XU4 (HC1, HC2), C1+, C2, C4 (HC4), N1, N2, N2L, H2, H3+, Go, Go Advance, M1
Location: Bucharest, Romania
Has thanked: 647 times
Been thanked: 1115 times
Contact:
Contact mad_ady

Re: USB controller dying under heavy IO load

Post by mad_ady »

You can reduce the value further (at the cost of performance). I started low (10 or 20), and increased based on tests/stability.
Top
Post Reply

Return to “Linux Kernel 5.4 Development Party”

Who is online

Users browsing this forum: No registered users and 1 guest