AM5728: NIC (Network Interface Card) detection fails

Part Number: AM5728
Other Parts Discussed in Thread: TEST

Hi,

Customer found below issue on their custom board.
SW is SDK RT-Linux 06.02.00.81.

The issue was found when the product is tested with repeated power-on <=> power-off cycles.
When the issue occurs, NIC is not detected at power-up.
Here is console log with normal case.

dmesg_normal.txt
 Booting Linux on physical CPU 0x0
 Linux version 4.19.79-rt28 (core@hxbuild:5) (gcc version 8.3.0 (GNU Toolchain for the A-profile Architecture 8.3-2019.03 (arm-rel-8.36))) #1 SMP PREEMPT RT Tue Nov 30 18:06:05 JST 2021
 CPU: ARMv7 Processor [412fc0f2] revision 2 (ARMv7), cr=30c5387d
 CPU: div instructions available: patching division code
 CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache
 OF: fdt: Machine model: TI AM5728 HIES HXC2 - 2020/02/07-2
 Memory policy: Data cache writealloc
 cma: Reserved 24 MiB at 0x00000000be400000
 OMAP4: Map 0x00000000bfd00000 to (ptrval) for dram barrier
 On node 0 totalpages: 261120
   DMA zone: 1728 pages used for memmap
   DMA zone: 0 pages reserved
   DMA zone: 196352 pages, LIFO batch:63
   HighMem zone: 64768 pages, LIFO batch:15
 DRA752 ES2.0
 random: get_random_bytes called from start_kernel+0xa8/0x4f0 with crng_init=0
 percpu: Embedded 17 pages/cpu s37536 r8192 d23904 u69632
 pcpu-alloc: s37536 r8192 d23904 u69632 alloc=17*4096
 pcpu-alloc: [0] 0 [0] 1
 Built 1 zonelists, mobility grouping on.  Total pages: 259392
 Kernel command line: console=ttyO2,115200n8 root=PARTUUID=2c4c38a9-01 ro rootfstype=ext4 rootwait
 Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes)
 Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
 Memory: 991384K/1044480K available (10240K kernel code, 760K rwdata, 3296K rodata, 2048K init, 310K bss, 28520K reserved, 24576K cma-reserved, 234496K highmem)
 Virtual kernel memory layout:
     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
     vmalloc : 0xf0800000 - 0xff800000   ( 240 MB)
     lowmem  : 0xc0000000 - 0xf0000000   ( 768 MB)
     pkmap   : 0xbfe00000 - 0xc0000000   (   2 MB)
     modules : 0xbf000000 - 0xbfe00000   (  14 MB)
       .text : 0x(ptrval) - 0x(ptrval)   (12256 kB)
       .init : 0x(ptrval) - 0x(ptrval)   (2048 kB)
       .data : 0x(ptrval) - 0x(ptrval)   ( 761 kB)
        .bss : 0x(ptrval) - 0x(ptrval)   ( 311 kB)
 SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
 ftrace: allocating 32409 entries in 96 pages
 rcu: Preemptible hierarchical RCU implementation.
 rcu:     RCU priority boosting: priority 1 delay 500 ms.
  No expedited grace period (rcu_normal_after_boot).
  Tasks RCU enabled.
 NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
 GIC: Using split EOI/Deactivate mode
 OMAP clockevent: using local gptimer3 for cpu0, irq 18
 OMAP clockevent: using local gptimer4 for cpu1, irq 19
 OMAP clockevent source: timer2 at 20000000 Hz
 sched_clock: 32 bits at 20MHz, resolution 50ns, wraps every 107374182375ns
 clocksource: timer1: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 95563022313 ns
 OMAP clocksource: timer1 at 20000000 Hz
 clocksource: 32k_counter: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 58327039986419 ns
 OMAP clocksource: 32k_counter at 32768 Hz
 Console: colour dummy device 80x30
 Calibrating delay loop... 1993.93 BogoMIPS (lpj=9969664)
 pid_max: default: 32768 minimum: 301
 Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
 Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
 CPU: Testing write buffer coherency: ok
 CPU0: Spectre v2: firmware did not set auxiliary control register IBE bit, system vulnerable
 /cpus/cpu@0 missing clock-frequency property
 /cpus/cpu@1 missing clock-frequency property
 CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
 Setting up static identity map for 0x80200000 - 0x80200060
 rcu: Hierarchical SRCU implementation.
 smp: Bringing up secondary CPUs ...
 CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
 CPU1: Spectre v2: using ICIALLU workaround
 smp: Brought up 1 node, 2 CPUs
 SMP: Total of 2 processors activated (3987.86 BogoMIPS).
 CPU: All CPU(s) started in HYP mode.
 CPU: Virtualization extensions available.
 devtmpfs: initialized
 VFP support v0.3: implementor 41 architecture 4 part 30 variant f rev 0
 clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
 futex hash table entries: 512 (order: 3, 32768 bytes)
 pinctrl core: initialized pinctrl subsystem
 NET: Registered protocol family 16
 DMA: preallocated 256 KiB pool for atomic coherent allocations
 omap_hwmod: l3_main_2 using broken dt data from ocp
 omap_hwmod: rtcss: no dt node
 omap_hwmod: rtcss: doesn't have mpu register target base
 gpio gpiochip0: (gpio-0-31): added GPIO chardev (254:0)
 gpiochip_setup_dev: registered GPIOs 0 to 31 on device: gpiochip0 (gpio-0-31)
 OMAP GPIO hardware version 0.1
 gpio gpiochip1: (gpio-32-63): added GPIO chardev (254:1)
 gpiochip_setup_dev: registered GPIOs 32 to 63 on device: gpiochip1 (gpio-32-63)
 gpio gpiochip2: (gpio-64-95): added GPIO chardev (254:2)
 gpiochip_setup_dev: registered GPIOs 64 to 95 on device: gpiochip2 (gpio-64-95)
 gpio gpiochip3: (gpio-96-127): added GPIO chardev (254:3)
 gpiochip_setup_dev: registered GPIOs 96 to 127 on device: gpiochip3 (gpio-96-127)
 gpio gpiochip4: (gpio-128-159): added GPIO chardev (254:4)
 gpiochip_setup_dev: registered GPIOs 128 to 159 on device: gpiochip4 (gpio-128-159)
 gpio gpiochip5: (gpio-160-191): added GPIO chardev (254:5)
 gpiochip_setup_dev: registered GPIOs 160 to 191 on device: gpiochip5 (gpio-160-191)
 gpio gpiochip6: (gpio-192-223): added GPIO chardev (254:6)
 gpiochip_setup_dev: registered GPIOs 192 to 223 on device: gpiochip6 (gpio-192-223)
 gpio gpiochip7: (gpio-224-255): added GPIO chardev (254:7)
 gpiochip_setup_dev: registered GPIOs 224 to 255 on device: gpiochip7 (gpio-224-255)
 omap-gpmc 50000000.gpmc: GPMC revision 6.0
 gpiochip_find_base: found new base at 510
 gpio gpiochip8: (omap-gpmc): added GPIO chardev (254:8)
 gpiochip_setup_dev: registered GPIOs 510 to 511 on device: gpiochip8 (omap-gpmc)
 hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
 hw-breakpoint: maximum watchpoint size is 8 bytes.
 OMAP DMA hardware revision 0.0
 edma 43300000.edma: TI EDMA DMA engine driver
 omap-dma-engine 4a056000.dma-controller: OMAP DMA engine driver (LinkedList1/2/3 supported)
 of_get_named_gpiod_flags: can't parse 'gpio' property of node '/fixedregulator-vmain[0]'
 of_get_named_gpiod_flags: can't parse 'gpio' property of node '/fixedregulator-v3_3d[0]'
 of_get_named_gpiod_flags: can't parse 'gpio' property of node '/fixedregulator-vtt[0]'
 omap-iommu 40d01000.mmu: 40d01000.mmu registered
 omap-iommu 40d02000.mmu: 40d02000.mmu registered
 omap-iommu 58882000.mmu: 58882000.mmu registered
 omap-iommu 55082000.mmu: 55082000.mmu registered
 omap-iommu 41501000.mmu: 41501000.mmu registered
 omap-iommu 41502000.mmu: 41502000.mmu registered
 usbcore: registered new interface driver usbfs
 usbcore: registered new interface driver hub
 usbcore: registered new device driver usb
 palmas 0-0058: Irq flag is 0x00000004
 palmas 0-0058: Muxing GPIO 2b, PWM 0, LED 0
 SMPS12: supplied by regulator-dummy
 SMPS3: supplied by VMAIN
 SMPS45: supplied by regulator-dummy
 SMPS6: supplied by VMAIN
 SMPS7: supplied by VMAIN
 SMPS8: supplied by VMAIN
 SMPS9: supplied by VMAIN
 LDO1: supplied by VMAIN
 random: fast init done
 LDO2: supplied by VMAIN
 LDO3: supplied by VMAIN
 LDO4: supplied by VMAIN
 LDO5: supplied by regulator-dummy
 LDO6: supplied by regulator-dummy
 LDO7: supplied by regulator-dummy
 LDO8: supplied by regulator-dummy
 LDO9: supplied by VMAIN
 LDOLN: supplied by VMAIN
 LDOUSB: supplied by VMAIN
 gpiochip_find_base: found new base at 502
 gpio gpiochip9: (48070000.i2c:tps659038@58:tps659038_gpio): added GPIO chardev (254:9)
 gpiochip_setup_dev: registered GPIOs 502 to 509 on device: gpiochip9 (48070000.i2c:tps659038@58:tps659038_gpio)
 omap_i2c 48070000.i2c: bus 0 rev0.12 at 400 kHz
 media: Linux media interface: v0.10
 videodev: Linux video capture interface: v2.00
 pps_core: LinuxPPS API ver. 1 registered
 pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
 PTP clock support registered
 EDAC MC: Ver: 3.0.0
 omap-mailbox 48840000.mailbox: omap mailbox rev 0x400
 omap-mailbox 48842000.mailbox: omap mailbox rev 0x400
 Advanced Linux Sound Architecture Driver Initialized.
 clocksource: Switched to clocksource timer1
 NET: Registered protocol family 2
 tcp_listen_portaddr_hash hash table entries: 512 (order: 2, 16384 bytes)
 TCP established hash table entries: 8192 (order: 3, 32768 bytes)
 TCP bind hash table entries: 8192 (order: 5, 229376 bytes)
 TCP: Hash tables configured (established 8192 bind 8192)
 UDP hash table entries: 512 (order: 3, 32768 bytes)
 UDP-Lite hash table entries: 512 (order: 3, 32768 bytes)
 NET: Registered protocol family 1
 RPC: Registered named UNIX socket transport module.
 RPC: Registered udp transport module.
 RPC: Registered tcp transport module.
 RPC: Registered tcp NFSv4.1 backchannel transport module.
 PCI: CLS 0 bytes, default 64
 hw perfevents: no interrupt-affinity property for /pmu, guessing.
 hw perfevents: enabled with armv7_cortex_a15 PMU driver, 7 counters available
 Initialise system trusted keyrings
 workingset: timestamp_bits=14 max_order=18 bucket_order=4
 squashfs: version 4.0 (2009/01/31) Phillip Lougher
 NFS: Registering the id_resolver key type
 Key type id_resolver registered
 Key type id_legacy registered
 ntfs: driver 2.1.32 [Flags: R/O].
 jffs2: version 2.2. (NAND) \xc2\xa9 2001-2006 Red Hat, Inc.
 Key type asymmetric registered
 Asymmetric key parser 'x509' registered
 bounce: pool size: 64 pages
 Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247)
 io scheduler noop registered
 io scheduler deadline registered
 io scheduler cfq registered (default)
 io scheduler mq-deadline registered
 io scheduler kyber registered
 phy phy-4a084000.phy.0: Linked as a consumer to regulator.21
 phy phy-4a085000.phy.1: Linked as a consumer to regulator.21
 pinctrl-single 4a003400.pinmux: 282 pins, size 1128
 dra7-pcie 51000000.pcie: Linked as a consumer to phy-4a094000.pciephy.3
 dra7-pcie 51000000.pcie: Linked as a consumer to phy-4a095000.pciephy.4
 dra7-pcie 51000000.pcie: [[[debug]]] pcie_syscon=[ef0c4600] pcie_reg=[558]
 dra7-pcie 51000000.pcie: GPIO lookup for consumer (null)
 dra7-pcie 51000000.pcie: using device tree for GPIO lookup
 of_get_named_gpiod_flags: can't parse 'gpios' property of node '/ocp/axi@0/pcie@51000000[0]'
 of_get_named_gpiod_flags: can't parse 'gpio' property of node '/ocp/axi@0/pcie@51000000[0]'
 dra7-pcie 51000000.pcie: using lookup tables for GPIO lookup
 dra7-pcie 51000000.pcie: No GPIO consumer (null) found
 dra7-pcie 51000000.pcie: >>>>>>>>>>>>>[[[debug]]] : dra7xx->link_gen=[1]
 dra7-pcie 51000000.pcie: WA for Errata i870 not applied
 dra7-pcie 51000000.pcie: host bridge /ocp/axi@0/pcie@51000000 ranges:
 dra7-pcie 51000000.pcie:    IO 0x20003000..0x20012fff -> 0x00000000
 dra7-pcie 51000000.pcie:   MEM 0x20013000..0x2fffffff -> 0x20013000
 dra7-pcie 51000000.pcie: >>>>>>>>>>>>>debug : dra7xx->link_gen=[1]
 dra7-pcie 51000000.pcie: >>>>>>>>>>>>>debug-1 : pci->dbi_base + exp_cap_off + PCI_EXP_LNKCAP=[733c22]
 dra7-pcie 51000000.pcie: >>>>>>>>>>>>>debug-2 : pci->dbi_base + exp_cap_off + PCI_EXP_LNKCAP=[733c21]
 dra7-pcie 51000000.pcie: >>>>>>>>>>>>>debug-3 : pci->dbi_base + exp_cap_off + PCI_EXP_LNKCAP=[733c22]
 dra7-pcie 51000000.pcie: >>>>>>>>>>>>>debug-1 : pci->dbi_base + exp_cap_off + PCI_EXP_LNKCTL2=[2]
 dra7-pcie 51000000.pcie: >>>>>>>>>>>>>debug-2 : pci->dbi_base + exp_cap_off + PCI_EXP_LNKCTL2=[1]
 dra7-pcie 51000000.pcie: >>>>>>>>>>>>>debug-3 : pci->dbi_base + exp_cap_off + PCI_EXP_LNKCTL2=[1]
 dra7-pcie 51000000.pcie: Link up
 dra7-pcie 51000000.pcie: PCI host bridge to bus 0000:00
 pci_bus 0000:00: root bus resource [bus 00-ff]
 pci_bus 0000:00: root bus resource [io  0x0000-0xffff]
 pci_bus 0000:00: root bus resource [mem 0x20013000-0x2fffffff]
 pci 0000:00:00.0: [104c:8888] type 01 class 0x060400
 pci 0000:00:00.0: reg 0x10: [mem 0x00000000-0x000fffff 64bit]
 pci 0000:00:00.0: supports D1
 pci 0000:00:00.0: PME# supported from D0 D1 D3hot
 PCI: bus0: Fast back to back transfers disabled
 pci 0000:01:00.0: [1b4b:1160] type 00 class 0x010802
 pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00003fff 64bit]
 pci 0000:01:00.0: 4.000 Gb/s available PCIe bandwidth, limited by 2.5 GT/s x2 link at 0000:00:00.0 (capable of 15.752 Gb/s with 8 GT/s x2 link)
 PCI: bus1: Fast back to back transfers disabled
 pci 0000:00:00.0: BAR 0: assigned [mem 0x20100000-0x201fffff 64bit]
 pci 0000:00:00.0: BAR 8: assigned [mem 0x20200000-0x202fffff]
 pci 0000:01:00.0: BAR 0: assigned [mem 0x20200000-0x20203fff 64bit]
 pci 0000:00:00.0: PCI bridge to [bus 01-ff]
 pci 0000:00:00.0:   bridge window [mem 0x20200000-0x202fffff]
 pcieport 0000:00:00.0: Signaling PME with IRQ 169
 pcieport 0000:00:00.0: AER enabled with IRQ 169
 V3_3D: supplied by smps9
 vtt_fixed: supplied by V3_3D
 omap_uart 4806a000.serial: no wakeirq for uart0
 of_get_named_gpiod_flags: parsed 'rts-gpio' property of node '/ocp/serial@4806a000[0]' - status (0)
 4806a000.serial: ttyO0 at MMIO 0x4806a000 (irq = 44, base_baud = 3000000) is a OMAP UART0
 of_get_named_gpiod_flags: can't parse 'rts-gpio' property of node '/ocp/serial@48020000[0]'
 48020000.serial: ttyO2 at MMIO 0x48020000 (irq = 45, base_baud = 3000000) is a OMAP UART2
 console [ttyO2] enabled
 omap_rng 48090000.rng: Random Number Generator ver. 20
 hwrng: no data available
 loop: module loaded
 nvme nvme0: pci function 0000:01:00.0
 nvme 0000:01:00.0: enabling device (0140 -> 0142)
 m25p80 spi0.0: found s25fl512s, expected s25fl256s1
 m25p80 spi0.0: non-uniform erase sector maps are not supported yet.
 m25p80 spi0.0: s25fl512s (65536 Kbytes)
 9 fixed-partitions partitions found on MTD device spi0.0
 Creating 9 MTD partitions on "spi0.0":
 0x000000000000-0x000000040000 : "QSPI.SPL"
 0x000000040000-0x000000140000 : "QSPI.u-boot"
 0x000000140000-0x0000001c0000 : "QSPI.dtb"
 0x0000001c0000-0x0000007c0000 : "QSPI.kernel"
 0x0000007c0000-0x000000800000 : "QSPI.IDROM"
 0x000000800000-0x000003800000 : "QSPI.ramdisk"
 0x000003800000-0x000003a00000 : "QSPI.linux-log"
 0x000003a00000-0x000003c00000 : "QSPI.linux-config"
 0x000003c00000-0x000004000000 : "QSPI.WORK"
 mdio_bus fixed-0: GPIO lookup for consumer reset
 mdio_bus fixed-0: using lookup tables for GPIO lookup
 mdio_bus fixed-0: No GPIO consumer reset found
 libphy: Fixed MDIO Bus: probed
 mdio_bus 4b2b2400.mdio: GPIO lookup for consumer reset
 mdio_bus 4b2b2400.mdio: using device tree for GPIO lookup
 of_get_named_gpiod_flags: can't parse 'reset-gpios' property of node '/ocp/pruss-soc-bus@4b2a6004/pruss@4b280000/mdio@4b2b2400[0]'
 of_get_named_gpiod_flags: can't parse 'reset-gpio' property of node '/ocp/pruss-soc-bus@4b2a6004/pruss@4b280000/mdio@4b2b2400[0]'
 mdio_bus 4b2b2400.mdio: using lookup tables for GPIO lookup
 mdio_bus 4b2b2400.mdio: No GPIO consumer reset found
  nvme0n1: p1
 davinci_mdio 4b2b2400.mdio: davinci mdio revision 1.6, bus freq 1000000
 libphy: 4b2b2400.mdio: probed
 of_get_named_gpiod_flags: can't parse 'reset-gpios' property of node '/ocp/pruss-soc-bus@4b2a6004/pruss@4b280000/mdio@4b2b2400/ethernet-phy@0[0]'
 of_get_named_gpiod_flags: can't parse 'reset-gpios' property of node '/ocp/pruss-soc-bus@4b2a6004/pruss@4b280000/mdio@4b2b2400/ethernet-phy@1[0]'
 davinci_mdio 4b2b2400.mdio: phy[1]: device 4b2b2400.mdio:01, driver SMSC LAN8710/LAN8720
 davinci_mdio 4b2b2400.mdio: phy[2]: device 4b2b2400.mdio:02, driver SMSC LAN8710/LAN8720
 mdio_bus 48485000.mdio: GPIO lookup for consumer reset
 mdio_bus 48485000.mdio: using device tree for GPIO lookup
 of_get_named_gpiod_flags: can't parse 'reset-gpios' property of node '/ocp/ethernet@48484000/mdio@48485000[0]'
 of_get_named_gpiod_flags: can't parse 'reset-gpio' property of node '/ocp/ethernet@48484000/mdio@48485000[0]'
 mdio_bus 48485000.mdio: using lookup tables for GPIO lookup
 mdio_bus 48485000.mdio: No GPIO consumer reset found
 davinci_mdio 48485000.mdio: davinci mdio revision 1.6, bus freq 1000000
 libphy: 48485000.mdio: probed
 of_get_named_gpiod_flags: can't parse 'reset-gpios' property of node '/ocp/ethernet@48484000/mdio@48485000/ethernet-phy@3[0]'
 davinci_mdio 48485000.mdio: phy[3]: device 48485000.mdio:03, driver SMSC LAN8710/LAN8720
 cpsw 48484000.ethernet: Detected MACID = 6c:c3:74:79:df:24
 cpsw 48484000.ethernet: initialized cpsw ale version 1.4
 cpsw 48484000.ethernet: ALE Table size 1024
 cpsw 48484000.ethernet: cpts: overflow check period 500 (jiffies)
 mousedev: PS/2 mouse device common for all mice
 rtc-rx8581 0-0051: rx8581_probe
 rtc-rx8581 0-0051: rx8581_rtc_read_time: raw data is sec=10, min=03, hr=08, wday=08, mday=29, mon=12, year=21
 rtc-rx8581 0-0051: rx8581_rtc_read_time: tm is secs=10, mins=3, hours=8, mday=29, mon=11, year=121, wday=3
 rtc-rx8581 0-0051: char device (253:0)
 rtc-rx8581 0-0051: registered as rtc0
 i2c /dev entries driver
 cpu cpu0: Linked as a consumer to regulator.4
 cpu cpu0: Linked as a consumer to regulator.27
 omap_hsmmc 4809c000.mmc: GPIO lookup for consumer cd
 omap_hsmmc 4809c000.mmc: using device tree for GPIO lookup
 of_get_named_gpiod_flags: parsed 'cd-gpios' property of node '/ocp/mmc@4809c000[0]' - status (0)
 gpio gpiochip5: Persistence not supported for GPIO 27
 omap_gpio 4805d000.gpio: Could not set line 27 debounce to 200000 microseconds (-22)
 omap_hsmmc 4809c000.mmc: Got CD GPIO
 omap_hsmmc 4809c000.mmc: GPIO lookup for consumer wp
 omap_hsmmc 4809c000.mmc: using device tree for GPIO lookup
 of_get_named_gpiod_flags: parsed 'wp-gpios' property of node '/ocp/mmc@4809c000[0]' - status (0)
 gpio gpiochip5: Persistence not supported for GPIO 28
 omap_hsmmc 4809c000.mmc: Got WP GPIO
 omap_hsmmc 4809c000.mmc: Linked as a consumer to regulator.2
 omap_hsmmc 4809c000.mmc: Linked as a consumer to regulator.11
 omap_hsmmc 4809c000.mmc: Linked as a consumer to regulator.26
 omap_hsmmc 480b4000.mmc: GPIO lookup for consumer wp
 omap_hsmmc 480b4000.mmc: using device tree for GPIO lookup
 of_get_named_gpiod_flags: can't parse 'wp-gpios' property of node '/ocp/mmc@480b4000[0]'
 of_get_named_gpiod_flags: can't parse 'wp-gpio' property of node '/ocp/mmc@480b4000[0]'
 omap_hsmmc 480b4000.mmc: using lookup tables for GPIO lookup
 omap_hsmmc 480b4000.mmc: No GPIO consumer wp found
 omap_hsmmc 480b4000.mmc: Linked as a consumer to regulator.2
 NET: Registered protocol family 10
 Segment Routing with IPv6
 sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
 NET: Registered protocol family 17
 Key type dns_resolver registered
 Registering SWP/SWPB emulation handler
 omap_voltage_late_init: Voltage driver support not added
 Power Management for TI OMAP4+ devices.
 Loading compiled-in X.509 certificates
 mmc1: mmc_select_hs200 failed, error -74
 mmc1: new MMC card at address 0001
 mmcblk1: mmc1:0001 IS004G 3.64 GiB
 mmcblk1boot0: mmc1:0001 IS004G partition 1 2.00 MiB
 mmcblk1boot1: mmc1:0001 IS004G partition 2 2.00 MiB
 mmcblk1rpmb: mmc1:0001 IS004G partition 3 512 KiB, chardev (244:0)
  mmcblk1: p1 p2 p3 < p5 p6 > p4
 rtc-rx8581 0-0051: rx8581_rtc_read_time: raw data is sec=10, min=03, hr=08, wday=08, mday=29, mon=12, year=21
 rtc-rx8581 0-0051: rx8581_rtc_read_time: tm is secs=10, mins=3, hours=8, mday=29, mon=11, year=121, wday=3
 rtc-rx8581 0-0051: setting system clock to 2021-12-29 08:03:10 UTC (1640764990)
 ALSA device list:
   No soundcards found.
 EXT4-fs (mmcblk1p1): mounted filesystem without journal. Opts: (null)
 VFS: Mounted root (ext4 filesystem) readonly on device 179:1.
 devtmpfs: mounted
 Freeing unused kernel memory: 2048K
 Run /sbin/init as init process
 EXT4-fs (mmcblk1p4): recovery complete
 EXT4-fs (mmcblk1p4): mounted filesystem with ordered data mode. Opts: (null)
 jffs2: notice: (136) jffs2_build_xattr_subsystem: complete building xattr subsystem, 0 of xdatum (0 unchecked, 0 orphan) and 0 of xref (0 dead, 0 orphan) found.
 jffs2: notice: (136) jffs2_build_xattr_subsystem: complete building xattr subsystem, 15 of xdatum (0 unchecked, 3 orphan) and 45 of xref (3 dead, 0 orphan) found.
 EXT4-fs (mmcblk1p5): recovery complete
 EXT4-fs (mmcblk1p5): mounted filesystem with ordered data mode. Opts: (null)
 EXT4-fs (mmcblk1p6): recovery complete
 EXT4-fs (mmcblk1p6): mounted filesystem with ordered data mode. Opts: (null)
 overlayfs: upper fs does not support tmpfile.
 udevd[161]: starting version 3.2.7
 random: udevd: uninitialized urandom read (16 bytes read)
 random: udevd: uninitialized urandom read (16 bytes read)
 random: udevd: uninitialized urandom read (16 bytes read)
 udevd[161]: specified group 'kvm' unknown
 udevd[162]: starting eudev-3.2.7
 udevd[162]: specified group 'kvm' unknown
 EXT4-fs (nvme0n1p1): recovery complete
 EXT4-fs (nvme0n1p1): mounted filesystem with ordered data mode. Opts: (null)
 EXT4-fs (mmcblk1p1): re-mounted. Opts: (null)
 remoteproc remoteproc0: 4b234000.pru is available
 pru-rproc 4b234000.pru: PRU rproc node pru@4b234000 probed successfully
 remoteproc remoteproc1: 4b238000.pru is available
 pru-rproc 4b238000.pru: PRU rproc node pru@4b238000 probed successfully
 remoteproc remoteproc2: 4b2b4000.pru is available
 pru-rproc 4b2b4000.pru: PRU rproc node pru@4b2b4000 probed successfully
 remoteproc remoteproc3: 4b2b8000.pru is available
 pru-rproc 4b2b8000.pru: PRU rproc node pru@4b2b8000 probed successfully
 prueth pruss2_eth: pruss_fw_drop_untagged_vlan 0
 prueth pruss2_eth: pruss MC Mask (Port 0) ff:ff:ff:ff:ff:ff
 prueth pruss2_eth: pruss MC Mask (Port 1) ff:ff:ff:ff:ff:ff
 prueth pruss2_eth: TI PRU ethernet (type 0) driver initialized
 rtc-rx8581 0-0051: rx8581_rtc_read_time: raw data is sec=14, min=03, hr=08, wday=08, mday=29, mon=12, year=21
 rtc-rx8581 0-0051: rx8581_rtc_read_time: tm is secs=14, mins=3, hours=8, mday=29, mon=11, year=121, wday=3
 rtc-rx8581 0-0051: rx8581_rtc_read_time: raw data is sec=14, min=03, hr=08, wday=08, mday=29, mon=12, year=21
 rtc-rx8581 0-0051: rx8581_rtc_read_time: tm is secs=14, mins=3, hours=8, mday=29, mon=11, year=121, wday=3
 urandom_read: 4 callbacks suppressed
 random: dd: uninitialized urandom read (512 bytes read)
 gpio gpiochip2: Persistence not supported for GPIO 28
 gpio gpiochip2: Persistence not supported for GPIO 29
 gpio gpiochip2: Persistence not supported for GPIO 30
 gpio gpiochip2: Persistence not supported for GPIO 31
 gpio gpiochip2: Persistence not supported for GPIO 3
 gpio gpiochip7: Persistence not supported for GPIO 12
 gpio gpiochip7: Persistence not supported for GPIO 0
 gpio gpiochip7: Persistence not supported for GPIO 2
 gpio gpiochip7: Persistence not supported for GPIO 3
 gpio gpiochip7: Persistence not supported for GPIO 5
 gpio gpiochip7: Persistence not supported for GPIO 4
 gpio gpiochip7: Persistence not supported for GPIO 7
 gpio gpiochip7: Persistence not supported for GPIO 6
 gpio gpiochip7: Persistence not supported for GPIO 8
 gpio gpiochip7: Persistence not supported for GPIO 9
 gpio gpiochip7: Persistence not supported for GPIO 10
 gpio gpiochip7: Persistence not supported for GPIO 11
 cpsw 48484000.ethernet eth3: renamed from eth0
 palmas-usb 48070000.i2c:tps659038@58:tps659038_usb: GPIO lookup for consumer id
 palmas-usb 48070000.i2c:tps659038@58:tps659038_usb: using device tree for GPIO lookup
 of_get_named_gpiod_flags: can't parse 'id-gpios' property of node '/ocp/i2c@48070000/tps659038@58/tps659038_usb[0]'
 of_get_named_gpiod_flags: can't parse 'id-gpio' property of node '/ocp/i2c@48070000/tps659038@58/tps659038_usb[0]'
 palmas-usb 48070000.i2c:tps659038@58:tps659038_usb: using lookup tables for GPIO lookup
 palmas-usb 48070000.i2c:tps659038@58:tps659038_usb: No GPIO consumer id found
 palmas-usb 48070000.i2c:tps659038@58:tps659038_usb: GPIO lookup for consumer vbus
 palmas-usb 48070000.i2c:tps659038@58:tps659038_usb: using device tree for GPIO lookup
 of_get_named_gpiod_flags: can't parse 'vbus-gpios' property of node '/ocp/i2c@48070000/tps659038@58/tps659038_usb[0]'
 of_get_named_gpiod_flags: parsed 'vbus-gpio' property of node '/ocp/i2c@48070000/tps659038@58/tps659038_usb[0]' - status (0)
 gpio gpiochip6: Persistence not supported for GPIO 25
 palmas-usb 48070000.i2c:tps659038@58:tps659038_usb: Spurious disconnect event detected
 dwc3 48890000.usb: Failed to get clk 'ref': -2
 dwc3 48890000.usb: changing max_speed on rev 5533202a
 dwc3 488d0000.usb: Failed to get clk 'ref': -2
 xhci-hcd xhci-hcd.0.auto: xHCI Host Controller
 xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 1
 xhci-hcd xhci-hcd.0.auto: hcc params 0x0220f04c hci version 0x100 quirks 0x0000000002010010
 xhci-hcd xhci-hcd.0.auto: irq 226, io mem 0x488d0000
 hub 1-0:1.0: USB hub found
 hub 1-0:1.0: 1 port detected
 xhci-hcd xhci-hcd.0.auto: xHCI Host Controller
 xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 2
 xhci-hcd xhci-hcd.0.auto: Host supports USB 3.0 SuperSpeed
 usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
 hub 2-0:1.0: USB hub found
 hub 2-0:1.0: 1 port detected
 pruss_intc_irq_set_affinity: event 20 not configured: ch = -1, host = -1
 pruss_intc_irq_set_affinity: event 22 not configured: ch = -1, host = -1
 pruss_intc_irq_set_affinity: event 26 not configured: ch = -1, host = -1
 net eth1: total_size 1552. total_size_allowed 1552
 iep ptp bc clkid -1
 remoteproc remoteproc2: powering up 4b2b4000.pru
 remoteproc remoteproc2: Booting fw image ti-pruss/am57xx-pru0-prueth-fw.elf, size 7124
 pruss 4b280000.pruss: configured system_events[63-0] = 00000600,04500000
 pruss 4b280000.pruss: configured intr_channels = 0x000000d5 host_intr = 0x00000155
 remoteproc remoteproc2: remote processor 4b2b4000.pru is now up
 net eth1: started
 IPv6: ADDRCONF(NETDEV_UP): eth1: link is not ready
 pruss_intc_irq_set_affinity: event 21 not configured: ch = -1, host = -1
 pruss_intc_irq_set_affinity: event 23 not configured: ch = -1, host = -1
 pruss_intc_irq_set_affinity: event 27 not configured: ch = -1, host = -1
 remoteproc remoteproc3: powering up 4b2b8000.pru
 remoteproc remoteproc3: Booting fw image ti-pruss/am57xx-pru1-prueth-fw.elf, size 7152
 pruss 4b280000.pruss: configured system_events[63-0] = 00600000,08a00000
 pruss 4b280000.pruss: configured intr_channels = 0x0000032a host_intr = 0x000002aa
 remoteproc remoteproc3: remote processor 4b2b8000.pru is now up
 net eth2: started
 IPv6: ADDRCONF(NETDEV_UP): eth2: link is not ready
 net eth3: initializing cpsw version 1.15 (0)
 SMSC LAN8710/LAN8720 48485000.mdio:03: attached PHY driver [SMSC LAN8710/LAN8720] (mii_bus:phy_addr=48485000.mdio:03, irq=POLL)
 IPv6: ADDRCONF(NETDEV_UP): eth3: link is not ready
 random: crng init done
 prueth pruss2_eth eth1: Link is Up - 100Mbps/Full - flow control off
 IPv6: ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready
 rtc-rx8581 0-0051: rx8581_rtc_read_time: raw data is sec=22, min=03, hr=08, wday=08, mday=29, mon=12, year=21
 rtc-rx8581 0-0051: rx8581_rtc_read_time: tm is secs=22, mins=3, hours=8, mday=29, mon=11, year=121, wday=3
 NET: Registered protocol family 15
 bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
 Bridge firewalling registered
 Initializing XFRM netlink socket
 IPv6: ADDRCONF(NETDEV_UP): docker0: link is not ready
 IPv6: ADDRCONF(NETDEV_UP): depot_bridge: link is not ready
 depot_bridge: port 1(veth93f5a07) entered blocking state
 depot_bridge: port 1(veth93f5a07) entered disabled state
 device veth93f5a07 entered promiscuous mode
 IPv6: ADDRCONF(NETDEV_UP): veth93f5a07: link is not ready
 cgroup: runc (1239) created nested cgroup for controller "memory" which has incomplete hierarchy support. Nested cgroups may change behavior in the future.
 cgroup: "memory" requires setting use_hierarchy to 1 on the root
 eth0: renamed from veth8c088f0
 IPv6: ADDRCONF(NETDEV_CHANGE): veth93f5a07: link becomes ready
 depot_bridge: port 1(veth93f5a07) entered blocking state
 depot_bridge: port 1(veth93f5a07) entered forwarding state
 IPv6: ADDRCONF(NETDEV_CHANGE): depot_bridge: link becomes ready
 overlayfs: upper fs does not support tmpfile.
 overlayfs: upper fs does not support tmpfile.
 usb_hx: loading out-of-tree module taints kernel.
 USB_HX Driver, version: 2016/06/30 1.0
 usb_hx gadget: usb_hx ready
 fpga7seg: module license 'GPL2' taints kernel.
 Disabling lock debugging due to kernel taint
 fpga7seg is loaded
 major = 242
 minor = 0
 fpgalres is loaded
 major = 241
 minor = 0
 fpgapioconf is loaded
 major = 240
 minor = 0
 ehvgpio : loaded into kernel
 hx_pianosw2 is loaded
 major = 239
 minor = 0
 hx_pianosw4: loaded. major = 238, minor = 0
 hx_errled is loaded
 major = 237
 minor = 0
 hx_subboardchk is loaded
 major = 236
 minor = 0
 hx_wdt is loaded
 major = 235
 minor = 0
 hx_sio is loaded module
 major = 234
 minor = 0
 hx_lres is loaded
 major = 511
 minor = 0
 <13>Dec 29 08:04:31 state-file-monitor: Boot-check timer expired.

Here is console log with failing case.
dmesg_fail.txt
dmesg
 Booting Linux on physical CPU 0x0
 Linux version 4.19.79-rt28 (core@hxbuild:5) (gcc version 8.3.0 (GNU Toolchain for the A-profile Architecture 8.3-2019.03 (arm-rel-8.36))) #1 SMP PREEMPT RT Tue Nov 30 18:06:05 JST 2021
 CPU: ARMv7 Processor [412fc0f2] revision 2 (ARMv7), cr=30c5387d
 CPU: div instructions available: patching division code
 CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache
 OF: fdt: Machine model: TI AM5728 HIES HXC2 - 2020/02/07-2
 Memory policy: Data cache writealloc
 cma: Reserved 24 MiB at 0x00000000be400000
 OMAP4: Map 0x00000000bfd00000 to (ptrval) for dram barrier
 On node 0 totalpages: 261120
   DMA zone: 1728 pages used for memmap
   DMA zone: 0 pages reserved
   DMA zone: 196352 pages, LIFO batch:63
   HighMem zone: 64768 pages, LIFO batch:15
 DRA752 ES2.0
 random: get_random_bytes called from start_kernel+0xa8/0x4f0 with crng_init=0
 percpu: Embedded 17 pages/cpu s37536 r8192 d23904 u69632
 pcpu-alloc: s37536 r8192 d23904 u69632 alloc=17*4096
 pcpu-alloc: [0] 0 [0] 1
 Built 1 zonelists, mobility grouping on.  Total pages: 259392
 Kernel command line: console=ttyO2,115200n8 root=PARTUUID=63c39ad0-01 ro rootfstype=ext4 rootwait
 Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes)
 Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
 Memory: 991384K/1044480K available (10240K kernel code, 760K rwdata, 3296K rodata, 2048K init, 310K bss, 28520K reserved, 24576K cma-reserved, 234496K highmem)
 Virtual kernel memory layout:
     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
     vmalloc : 0xf0800000 - 0xff800000   ( 240 MB)
     lowmem  : 0xc0000000 - 0xf0000000   ( 768 MB)
     pkmap   : 0xbfe00000 - 0xc0000000   (   2 MB)
     modules : 0xbf000000 - 0xbfe00000   (  14 MB)
       .text : 0x(ptrval) - 0x(ptrval)   (12256 kB)
       .init : 0x(ptrval) - 0x(ptrval)   (2048 kB)
       .data : 0x(ptrval) - 0x(ptrval)   ( 761 kB)
        .bss : 0x(ptrval) - 0x(ptrval)   ( 311 kB)
 SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
 ftrace: allocating 32409 entries in 96 pages
 rcu: Preemptible hierarchical RCU implementation.
 rcu:     RCU priority boosting: priority 1 delay 500 ms.
  No expedited grace period (rcu_normal_after_boot).
  Tasks RCU enabled.
 NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
 GIC: Using split EOI/Deactivate mode
 OMAP clockevent: using local gptimer3 for cpu0, irq 18
 OMAP clockevent: using local gptimer4 for cpu1, irq 19
 OMAP clockevent source: timer2 at 20000000 Hz
 sched_clock: 32 bits at 20MHz, resolution 50ns, wraps every 107374182375ns
 clocksource: timer1: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 95563022313 ns
 OMAP clocksource: timer1 at 20000000 Hz
 clocksource: 32k_counter: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 58327039986419 ns
 OMAP clocksource: 32k_counter at 32768 Hz
 Console: colour dummy device 80x30
 Calibrating delay loop... 1993.93 BogoMIPS (lpj=9969664)
 pid_max: default: 32768 minimum: 301
 Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
 Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
 CPU: Testing write buffer coherency: ok
 CPU0: Spectre v2: firmware did not set auxiliary control register IBE bit, system vulnerable
 /cpus/cpu@0 missing clock-frequency property
 /cpus/cpu@1 missing clock-frequency property
 CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
 Setting up static identity map for 0x80200000 - 0x80200060
 rcu: Hierarchical SRCU implementation.
 smp: Bringing up secondary CPUs ...
 CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
 CPU1: Spectre v2: using ICIALLU workaround
 smp: Brought up 1 node, 2 CPUs
 SMP: Total of 2 processors activated (3987.86 BogoMIPS).
 CPU: All CPU(s) started in HYP mode.
 CPU: Virtualization extensions available.
 devtmpfs: initialized
 VFP support v0.3: implementor 41 architecture 4 part 30 variant f rev 0
 clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
 futex hash table entries: 512 (order: 3, 32768 bytes)
 pinctrl core: initialized pinctrl subsystem
 NET: Registered protocol family 16
 DMA: preallocated 256 KiB pool for atomic coherent allocations
 omap_hwmod: l3_main_2 using broken dt data from ocp
 omap_hwmod: rtcss: no dt node
 omap_hwmod: rtcss: doesn't have mpu register target base
 gpio gpiochip0: (gpio-0-31): added GPIO chardev (254:0)
 gpiochip_setup_dev: registered GPIOs 0 to 31 on device: gpiochip0 (gpio-0-31)
 OMAP GPIO hardware version 0.1
 gpio gpiochip1: (gpio-32-63): added GPIO chardev (254:1)
 gpiochip_setup_dev: registered GPIOs 32 to 63 on device: gpiochip1 (gpio-32-63)
 gpio gpiochip2: (gpio-64-95): added GPIO chardev (254:2)
 gpiochip_setup_dev: registered GPIOs 64 to 95 on device: gpiochip2 (gpio-64-95)
 gpio gpiochip3: (gpio-96-127): added GPIO chardev (254:3)
 gpiochip_setup_dev: registered GPIOs 96 to 127 on device: gpiochip3 (gpio-96-127)
 gpio gpiochip4: (gpio-128-159): added GPIO chardev (254:4)
 gpiochip_setup_dev: registered GPIOs 128 to 159 on device: gpiochip4 (gpio-128-159)
 gpio gpiochip5: (gpio-160-191): added GPIO chardev (254:5)
 gpiochip_setup_dev: registered GPIOs 160 to 191 on device: gpiochip5 (gpio-160-191)
 gpio gpiochip6: (gpio-192-223): added GPIO chardev (254:6)
 gpiochip_setup_dev: registered GPIOs 192 to 223 on device: gpiochip6 (gpio-192-223)
 gpio gpiochip7: (gpio-224-255): added GPIO chardev (254:7)
 gpiochip_setup_dev: registered GPIOs 224 to 255 on device: gpiochip7 (gpio-224-255)
 omap-gpmc 50000000.gpmc: GPMC revision 6.0
 gpiochip_find_base: found new base at 510
 gpio gpiochip8: (omap-gpmc): added GPIO chardev (254:8)
 gpiochip_setup_dev: registered GPIOs 510 to 511 on device: gpiochip8 (omap-gpmc)
 hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
 hw-breakpoint: maximum watchpoint size is 8 bytes.
 OMAP DMA hardware revision 0.0
 edma 43300000.edma: TI EDMA DMA engine driver
 omap-dma-engine 4a056000.dma-controller: OMAP DMA engine driver (LinkedList1/2/3 supported)
 of_get_named_gpiod_flags: can't parse 'gpio' property of node '/fixedregulator-vmain[0]'
 of_get_named_gpiod_flags: can't parse 'gpio' property of node '/fixedregulator-v3_3d[0]'
 of_get_named_gpiod_flags: can't parse 'gpio' property of node '/fixedregulator-vtt[0]'
 omap-iommu 40d01000.mmu: 40d01000.mmu registered
 omap-iommu 40d02000.mmu: 40d02000.mmu registered
 omap-iommu 58882000.mmu: 58882000.mmu registered
 omap-iommu 55082000.mmu: 55082000.mmu registered
 omap-iommu 41501000.mmu: 41501000.mmu registered
 omap-iommu 41502000.mmu: 41502000.mmu registered
 usbcore: registered new interface driver usbfs
 usbcore: registered new interface driver hub
 usbcore: registered new device driver usb
 palmas 0-0058: Irq flag is 0x00000004
 palmas 0-0058: Muxing GPIO 2b, PWM 0, LED 0
 SMPS12: supplied by regulator-dummy
 SMPS3: supplied by VMAIN
 SMPS45: supplied by regulator-dummy
 SMPS6: supplied by VMAIN
 SMPS7: supplied by VMAIN
 SMPS8: supplied by VMAIN
 SMPS9: supplied by VMAIN
 LDO1: supplied by VMAIN
 random: fast init done
 LDO2: supplied by VMAIN
 LDO3: supplied by VMAIN
 LDO4: supplied by VMAIN
 LDO5: supplied by regulator-dummy
 LDO6: supplied by regulator-dummy
 LDO7: supplied by regulator-dummy
 LDO8: supplied by regulator-dummy
 LDO9: supplied by VMAIN
 LDOLN: supplied by VMAIN
 LDOUSB: supplied by VMAIN
 gpiochip_find_base: found new base at 502
 gpio gpiochip9: (48070000.i2c:tps659038@58:tps659038_gpio): added GPIO chardev (254:9)
 gpiochip_setup_dev: registered GPIOs 502 to 509 on device: gpiochip9 (48070000.i2c:tps659038@58:tps659038_gpio)
 omap_i2c 48070000.i2c: bus 0 rev0.12 at 400 kHz
 media: Linux media interface: v0.10
 videodev: Linux video capture interface: v2.00
 pps_core: LinuxPPS API ver. 1 registered
 pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
 PTP clock support registered
 EDAC MC: Ver: 3.0.0
 omap-mailbox 48840000.mailbox: omap mailbox rev 0x400
 omap-mailbox 48842000.mailbox: omap mailbox rev 0x400
 Advanced Linux Sound Architecture Driver Initialized.
 clocksource: Switched to clocksource timer1
 NET: Registered protocol family 2
 tcp_listen_portaddr_hash hash table entries: 512 (order: 2, 16384 bytes)
 TCP established hash table entries: 8192 (order: 3, 32768 bytes)
 TCP bind hash table entries: 8192 (order: 5, 229376 bytes)
 TCP: Hash tables configured (established 8192 bind 8192)
 UDP hash table entries: 512 (order: 3, 32768 bytes)
 UDP-Lite hash table entries: 512 (order: 3, 32768 bytes)
 NET: Registered protocol family 1
 RPC: Registered named UNIX socket transport module.
 RPC: Registered udp transport module.
 RPC: Registered tcp transport module.
 RPC: Registered tcp NFSv4.1 backchannel transport module.
 PCI: CLS 0 bytes, default 64
 hw perfevents: no interrupt-affinity property for /pmu, guessing.
 hw perfevents: enabled with armv7_cortex_a15 PMU driver, 7 counters available
 Initialise system trusted keyrings
 workingset: timestamp_bits=14 max_order=18 bucket_order=4
 squashfs: version 4.0 (2009/01/31) Phillip Lougher
 NFS: Registering the id_resolver key type
 Key type id_resolver registered
 Key type id_legacy registered
 ntfs: driver 2.1.32 [Flags: R/O].
 jffs2: version 2.2. (NAND) \xc2\xa9 2001-2006 Red Hat, Inc.
 Key type asymmetric registered
 Asymmetric key parser 'x509' registered
 bounce: pool size: 64 pages
 Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247)
 io scheduler noop registered
 io scheduler deadline registered
 io scheduler cfq registered (default)
 io scheduler mq-deadline registered
 io scheduler kyber registered
 phy phy-4a084000.phy.0: Linked as a consumer to regulator.21
 phy phy-4a085000.phy.1: Linked as a consumer to regulator.21
 pinctrl-single 4a003400.pinmux: 282 pins, size 1128
 dra7-pcie 51000000.pcie: Linked as a consumer to phy-4a094000.pciephy.3
 dra7-pcie 51000000.pcie: Linked as a consumer to phy-4a095000.pciephy.4
 dra7-pcie 51000000.pcie: [[[debug]]] pcie_syscon=[ef0c4600] pcie_reg=[558]
 dra7-pcie 51000000.pcie: GPIO lookup for consumer (null)
 dra7-pcie 51000000.pcie: using device tree for GPIO lookup
 of_get_named_gpiod_flags: can't parse 'gpios' property of node '/ocp/axi@0/pcie@51000000[0]'
 of_get_named_gpiod_flags: can't parse 'gpio' property of node '/ocp/axi@0/pcie@51000000[0]'
 dra7-pcie 51000000.pcie: using lookup tables for GPIO lookup
 dra7-pcie 51000000.pcie: No GPIO consumer (null) found
 dra7-pcie 51000000.pcie: >>>>>>>>>>>>>[[[debug]]] : dra7xx->link_gen=[1]
 dra7-pcie 51000000.pcie: WA for Errata i870 not applied
 dra7-pcie 51000000.pcie: host bridge /ocp/axi@0/pcie@51000000 ranges:
 dra7-pcie 51000000.pcie:    IO 0x20003000..0x20012fff -> 0x00000000
 dra7-pcie 51000000.pcie:   MEM 0x20013000..0x2fffffff -> 0x20013000
 dra7-pcie 51000000.pcie: >>>>>>>>>>>>>debug : dra7xx->link_gen=[1]
 dra7-pcie 51000000.pcie: >>>>>>>>>>>>>debug-1 : pci->dbi_base + exp_cap_off + PCI_EXP_LNKCAP=[733c22]
 dra7-pcie 51000000.pcie: >>>>>>>>>>>>>debug-2 : pci->dbi_base + exp_cap_off + PCI_EXP_LNKCAP=[733c21]
 dra7-pcie 51000000.pcie: >>>>>>>>>>>>>debug-3 : pci->dbi_base + exp_cap_off + PCI_EXP_LNKCAP=[733c22]
 dra7-pcie 51000000.pcie: >>>>>>>>>>>>>debug-1 : pci->dbi_base + exp_cap_off + PCI_EXP_LNKCTL2=[2]
 dra7-pcie 51000000.pcie: >>>>>>>>>>>>>debug-2 : pci->dbi_base + exp_cap_off + PCI_EXP_LNKCTL2=[1]
 dra7-pcie 51000000.pcie: >>>>>>>>>>>>>debug-3 : pci->dbi_base + exp_cap_off + PCI_EXP_LNKCTL2=[1]
 dra7-pcie 51000000.pcie: Link up
 dra7-pcie 51000000.pcie: PCI host bridge to bus 0000:00
 pci_bus 0000:00: root bus resource [bus 00-ff]
 pci_bus 0000:00: root bus resource [io  0x0000-0xffff]
 pci_bus 0000:00: root bus resource [mem 0x20013000-0x2fffffff]
 pci 0000:00:00.0: [104c:8888] type 01 class 0x060400
 pci 0000:00:00.0: reg 0x10: [mem 0x00000000-0x000fffff 64bit]
 pci 0000:00:00.0: supports D1
 pci 0000:00:00.0: PME# supported from D0 D1 D3hot
 PCI: bus0: Fast back to back transfers disabled
 pci 0000:01:00.0: [1b4b:1160] type 00 class 0x010802
 pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00003fff 64bit]
 pci 0000:01:00.0: 4.000 Gb/s available PCIe bandwidth, limited by 2.5 GT/s x2 link at 0000:00:00.0 (capable of 15.752 Gb/s with 8 GT/s x2 link)
 PCI: bus1: Fast back to back transfers disabled
 pci 0000:00:00.0: BAR 0: assigned [mem 0x20100000-0x201fffff 64bit]
 pci 0000:00:00.0: BAR 8: assigned [mem 0x20200000-0x202fffff]
 pci 0000:01:00.0: BAR 0: assigned [mem 0x20200000-0x20203fff 64bit]
 pci 0000:00:00.0: PCI bridge to [bus 01-ff]
 pci 0000:00:00.0:   bridge window [mem 0x20200000-0x202fffff]
 pcieport 0000:00:00.0: Signaling PME with IRQ 169
 pcieport 0000:00:00.0: AER enabled with IRQ 169
 V3_3D: supplied by smps9
 vtt_fixed: supplied by V3_3D
 omap_uart 4806a000.serial: no wakeirq for uart0
 of_get_named_gpiod_flags: parsed 'rts-gpio' property of node '/ocp/serial@4806a000[0]' - status (0)
 4806a000.serial: ttyO0 at MMIO 0x4806a000 (irq = 44, base_baud = 3000000) is a OMAP UART0
 of_get_named_gpiod_flags: can't parse 'rts-gpio' property of node '/ocp/serial@48020000[0]'
 48020000.serial: ttyO2 at MMIO 0x48020000 (irq = 45, base_baud = 3000000) is a OMAP UART2
 console [ttyO2] enabled
 omap_rng 48090000.rng: Random Number Generator ver. 20
 hwrng: no data available
 loop: module loaded
 nvme nvme0: pci function 0000:01:00.0
 m25p80 spi0.0: found s25fl512s, expected s25fl256s1
 nvme 0000:01:00.0: enabling device (0140 -> 0142)
 m25p80 spi0.0: non-uniform erase sector maps are not supported yet.
 m25p80 spi0.0: s25fl512s (65536 Kbytes)
 9 fixed-partitions partitions found on MTD device spi0.0
 Creating 9 MTD partitions on "spi0.0":
 0x000000000000-0x000000040000 : "QSPI.SPL"
 0x000000040000-0x000000140000 : "QSPI.u-boot"
 0x000000140000-0x0000001c0000 : "QSPI.dtb"
 0x0000001c0000-0x0000007c0000 : "QSPI.kernel"
 0x0000007c0000-0x000000800000 : "QSPI.IDROM"
 0x000000800000-0x000003800000 : "QSPI.ramdisk"
 0x000003800000-0x000003a00000 : "QSPI.linux-log"
 0x000003a00000-0x000003c00000 : "QSPI.linux-config"
 0x000003c00000-0x000004000000 : "QSPI.WORK"
 mdio_bus fixed-0: GPIO lookup for consumer reset
 mdio_bus fixed-0: using lookup tables for GPIO lookup
 mdio_bus fixed-0: No GPIO consumer reset found
 libphy: Fixed MDIO Bus: probed
 mdio_bus 4b2b2400.mdio: GPIO lookup for consumer reset
 mdio_bus 4b2b2400.mdio: using device tree for GPIO lookup
 of_get_named_gpiod_flags: can't parse 'reset-gpios' property of node '/ocp/pruss-soc-bus@4b2a6004/pruss@4b280000/mdio@4b2b2400[0]'
 of_get_named_gpiod_flags: can't parse 'reset-gpio' property of node '/ocp/pruss-soc-bus@4b2a6004/pruss@4b280000/mdio@4b2b2400[0]'
 mdio_bus 4b2b2400.mdio: using lookup tables for GPIO lookup
 mdio_bus 4b2b2400.mdio: No GPIO consumer reset found
 davinci_mdio 4b2b2400.mdio: davinci mdio revision 1.6, bus freq 1000000
 libphy: 4b2b2400.mdio: probed
 of_get_named_gpiod_flags: can't parse 'reset-gpios' property of node '/ocp/pruss-soc-bus@4b2a6004/pruss@4b280000/mdio@4b2b2400/ethernet-phy@0[0]'
 of_get_named_gpiod_flags: can't parse 'reset-gpios' property of node '/ocp/pruss-soc-bus@4b2a6004/pruss@4b280000/mdio@4b2b2400/ethernet-phy@1[0]'
 davinci_mdio 4b2b2400.mdio: phy[1]: device 4b2b2400.mdio:01, driver SMSC LAN8710/LAN8720
 davinci_mdio 4b2b2400.mdio: phy[2]: device 4b2b2400.mdio:02, driver SMSC LAN8710/LAN8720
 mdio_bus 48485000.mdio: GPIO lookup for consumer reset
 mdio_bus 48485000.mdio: using device tree for GPIO lookup
 of_get_named_gpiod_flags: can't parse 'reset-gpios' property of node '/ocp/ethernet@48484000/mdio@48485000[0]'
 of_get_named_gpiod_flags: can't parse 'reset-gpio' property of node '/ocp/ethernet@48484000/mdio@48485000[0]'
 mdio_bus 48485000.mdio: using lookup tables for GPIO lookup
 mdio_bus 48485000.mdio: No GPIO consumer reset found
 davinci_mdio 48485000.mdio: davinci mdio revision 1.6, bus freq 1000000
  nvme0n1: p1
 libphy: 48485000.mdio: probed
 of_get_named_gpiod_flags: can't parse 'reset-gpios' property of node '/ocp/ethernet@48484000/mdio@48485000/ethernet-phy@3[0]'
 davinci_mdio 48485000.mdio: phy[3]: device 48485000.mdio:03, driver SMSC LAN8710/LAN8720
 cpsw 48484000.ethernet: Detected MACID = d8:a9:8b:e5:d1:98
 cpsw 48484000.ethernet: initialized cpsw ale version 1.4
 cpsw 48484000.ethernet: ALE Table size 1024
 cpsw 48484000.ethernet: cpts: overflow check period 500 (jiffies)
 mousedev: PS/2 mouse device common for all mice
 rtc-rx8581 0-0051: rx8581_probe
 rtc-rx8581 0-0051: rx8581_rtc_read_time: raw data is sec=35, min=39, hr=03, wday=08, mday=29, mon=12, year=21
 rtc-rx8581 0-0051: rx8581_rtc_read_time: tm is secs=35, mins=39, hours=3, mday=29, mon=11, year=121, wday=3
 rtc-rx8581 0-0051: char device (253:0)
 rtc-rx8581 0-0051: registered as rtc0
 i2c /dev entries driver
 cpu cpu0: Linked as a consumer to regulator.4
 cpu cpu0: Linked as a consumer to regulator.27
 omap_hsmmc 4809c000.mmc: GPIO lookup for consumer cd
 omap_hsmmc 4809c000.mmc: using device tree for GPIO lookup
 of_get_named_gpiod_flags: parsed 'cd-gpios' property of node '/ocp/mmc@4809c000[0]' - status (0)
 gpio gpiochip5: Persistence not supported for GPIO 27
 omap_gpio 4805d000.gpio: Could not set line 27 debounce to 200000 microseconds (-22)
 omap_hsmmc 4809c000.mmc: Got CD GPIO
 omap_hsmmc 4809c000.mmc: GPIO lookup for consumer wp
 omap_hsmmc 4809c000.mmc: using device tree for GPIO lookup
 of_get_named_gpiod_flags: parsed 'wp-gpios' property of node '/ocp/mmc@4809c000[0]' - status (0)
 gpio gpiochip5: Persistence not supported for GPIO 28
 omap_hsmmc 4809c000.mmc: Got WP GPIO
 omap_hsmmc 4809c000.mmc: Linked as a consumer to regulator.2
 omap_hsmmc 4809c000.mmc: Linked as a consumer to regulator.11
 omap_hsmmc 4809c000.mmc: Linked as a consumer to regulator.26
 omap_hsmmc 480b4000.mmc: GPIO lookup for consumer wp
 omap_hsmmc 480b4000.mmc: using device tree for GPIO lookup
 of_get_named_gpiod_flags: can't parse 'wp-gpios' property of node '/ocp/mmc@480b4000[0]'
 of_get_named_gpiod_flags: can't parse 'wp-gpio' property of node '/ocp/mmc@480b4000[0]'
 omap_hsmmc 480b4000.mmc: using lookup tables for GPIO lookup
 omap_hsmmc 480b4000.mmc: No GPIO consumer wp found
 omap_hsmmc 480b4000.mmc: Linked as a consumer to regulator.2
 mmc0: Skipping voltage switch
 NET: Registered protocol family 10
 Segment Routing with IPv6
 sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
 NET: Registered protocol family 17
 mmc0: new high speed SDHC card at address 0007
 Key type dns_resolver registered
 mmcblk0: mmc0:0007 SD04G 3.71 GiB
 Registering SWP/SWPB emulation handler
 mmc1: mmc_select_hs200 failed, error -74
 mmc1: new MMC card at address 0001
 mmcblk1: mmc1:0001 IS004G 3.64 GiB
 mmcblk1boot0: mmc1:0001 IS004G partition 1 2.00 MiB
 mmcblk1boot1: mmc1:0001 IS004G partition 2 2.00 MiB
  mmcblk0: p1 p2
 mmcblk1rpmb: mmc1:0001 IS004G partition 3 512 KiB, chardev (244:0)
  mmcblk1: p1 p2 p3 < p5 p6 > p4
 omap_voltage_late_init: Voltage driver support not added
 Power Management for TI OMAP4+ devices.
 Loading compiled-in X.509 certificates
 rtc-rx8581 0-0051: rx8581_rtc_read_time: raw data is sec=35, min=39, hr=03, wday=08, mday=29, mon=12, year=21
 rtc-rx8581 0-0051: rx8581_rtc_read_time: tm is secs=35, mins=39, hours=3, mday=29, mon=11, year=121, wday=3
 rtc-rx8581 0-0051: setting system clock to 2021-12-29 03:39:35 UTC (1640749175)
 ALSA device list:
   No soundcards found.
 EXT4-fs (mmcblk1p1): mounted filesystem without journal. Opts: (null)
 VFS: Mounted root (ext4 filesystem) readonly on device 179:17.
 devtmpfs: mounted
 Freeing unused kernel memory: 2048K
 Run /sbin/init as init process
 EXT4-fs (mmcblk1p4): recovery complete
 EXT4-fs (mmcblk1p4): mounted filesystem with ordered data mode. Opts: (null)
 jffs2: notice: (138) jffs2_build_xattr_subsystem: complete building xattr subsystem, 0 of xdatum (0 unchecked, 0 orphan) and 0 of xref (0 dead, 0 orphan) found.
 jffs2: notice: (138) jffs2_build_xattr_subsystem: complete building xattr subsystem, 7 of xdatum (0 unchecked, 0 orphan) and 35 of xref (0 dead, 0 orphan) found.
 EXT4-fs (mmcblk1p5): recovery complete
 EXT4-fs (mmcblk1p5): mounted filesystem with ordered data mode. Opts: (null)
 EXT4-fs (mmcblk1p6): recovery complete
 EXT4-fs (mmcblk1p6): mounted filesystem with ordered data mode. Opts: (null)
 overlayfs: upper fs does not support tmpfile.
 udevd[163]: starting version 3.2.7
 random: udevd: uninitialized urandom read (16 bytes read)
 random: udevd: uninitialized urandom read (16 bytes read)
 random: udevd: uninitialized urandom read (16 bytes read)
 udevd[163]: specified group 'kvm' unknown
 udevd[164]: starting eudev-3.2.7
 udevd[164]: specified group 'kvm' unknown
 EXT4-fs (nvme0n1p1): recovery complete
 EXT4-fs (nvme0n1p1): mounted filesystem with ordered data mode. Opts: (null)
 EXT4-fs (mmcblk1p1): re-mounted. Opts: (null)
 remoteproc remoteproc0: 4b234000.pru is available
 pru-rproc 4b234000.pru: PRU rproc node pru@4b234000 probed successfully
 remoteproc remoteproc1: 4b238000.pru is available
 pru-rproc 4b238000.pru: PRU rproc node pru@4b238000 probed successfully
 remoteproc remoteproc2: 4b2b4000.pru is available
 pru-rproc 4b2b4000.pru: PRU rproc node pru@4b2b4000 probed successfully
 remoteproc remoteproc3: 4b2b8000.pru is available
 pru-rproc 4b2b8000.pru: PRU rproc node pru@4b2b8000 probed successfully
 prueth pruss2_eth: freeing port 0
 rtc-rx8581 0-0051: rx8581_rtc_read_time: raw data is sec=40, min=39, hr=03, wday=08, mday=29, mon=12, year=21
 rtc-rx8581 0-0051: rx8581_rtc_read_time: tm is secs=40, mins=39, hours=3, mday=29, mon=11, year=121, wday=3
 rtc-rx8581 0-0051: rx8581_rtc_read_time: raw data is sec=40, min=39, hr=03, wday=08, mday=29, mon=12, year=21
 rtc-rx8581 0-0051: rx8581_rtc_read_time: tm is secs=40, mins=39, hours=3, mday=29, mon=11, year=121, wday=3
 urandom_read: 4 callbacks suppressed
 random: dd: uninitialized urandom read (512 bytes read)
 gpio gpiochip2: Persistence not supported for GPIO 28
 gpio gpiochip2: Persistence not supported for GPIO 29
 gpio gpiochip2: Persistence not supported for GPIO 30
 gpio gpiochip2: Persistence not supported for GPIO 31
 gpio gpiochip2: Persistence not supported for GPIO 3
 gpio gpiochip7: Persistence not supported for GPIO 12
 gpio gpiochip7: Persistence not supported for GPIO 0
 gpio gpiochip7: Persistence not supported for GPIO 2
 gpio gpiochip7: Persistence not supported for GPIO 3
 gpio gpiochip7: Persistence not supported for GPIO 5
 gpio gpiochip7: Persistence not supported for GPIO 4
 gpio gpiochip7: Persistence not supported for GPIO 7
 gpio gpiochip7: Persistence not supported for GPIO 6
 gpio gpiochip7: Persistence not supported for GPIO 8
 gpio gpiochip7: Persistence not supported for GPIO 9
 gpio gpiochip7: Persistence not supported for GPIO 10
 gpio gpiochip7: Persistence not supported for GPIO 11
 cpsw 48484000.ethernet eth3: renamed from eth0
 palmas-usb 48070000.i2c:tps659038@58:tps659038_usb: GPIO lookup for consumer id
 palmas-usb 48070000.i2c:tps659038@58:tps659038_usb: using device tree for GPIO lookup
 of_get_named_gpiod_flags: can't parse 'id-gpios' property of node '/ocp/i2c@48070000/tps659038@58/tps659038_usb[0]'
 of_get_named_gpiod_flags: can't parse 'id-gpio' property of node '/ocp/i2c@48070000/tps659038@58/tps659038_usb[0]'
 palmas-usb 48070000.i2c:tps659038@58:tps659038_usb: using lookup tables for GPIO lookup
 palmas-usb 48070000.i2c:tps659038@58:tps659038_usb: No GPIO consumer id found
 palmas-usb 48070000.i2c:tps659038@58:tps659038_usb: GPIO lookup for consumer vbus
 palmas-usb 48070000.i2c:tps659038@58:tps659038_usb: using device tree for GPIO lookup
 of_get_named_gpiod_flags: can't parse 'vbus-gpios' property of node '/ocp/i2c@48070000/tps659038@58/tps659038_usb[0]'
 of_get_named_gpiod_flags: parsed 'vbus-gpio' property of node '/ocp/i2c@48070000/tps659038@58/tps659038_usb[0]' - status (0)
 gpio gpiochip6: Persistence not supported for GPIO 25
 palmas-usb 48070000.i2c:tps659038@58:tps659038_usb: Spurious disconnect event detected
 dwc3 48890000.usb: Failed to get clk 'ref': -2
 dwc3 48890000.usb: changing max_speed on rev 5533202a
 dwc3 488d0000.usb: Failed to get clk 'ref': -2
 xhci-hcd xhci-hcd.0.auto: xHCI Host Controller
 xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 1
 xhci-hcd xhci-hcd.0.auto: hcc params 0x0220f04c hci version 0x100 quirks 0x0000000002010010
 xhci-hcd xhci-hcd.0.auto: irq 226, io mem 0x488d0000
 hub 1-0:1.0: USB hub found
 hub 1-0:1.0: 1 port detected
 xhci-hcd xhci-hcd.0.auto: xHCI Host Controller
 xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 2
 xhci-hcd xhci-hcd.0.auto: Host supports USB 3.0 SuperSpeed
 usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
 hub 2-0:1.0: USB hub found
 hub 2-0:1.0: 1 port detected
 prueth pruss2_eth: freeing port 0
 net eth3: initializing cpsw version 1.15 (0)
 usb 1-1: new high-speed USB device number 2 using xhci-hcd
 SMSC LAN8710/LAN8720 48485000.mdio:03: attached PHY driver [SMSC LAN8710/LAN8720] (mii_bus:phy_addr=48485000.mdio:03, irq=POLL)
 prueth pruss2_eth: freeing port 0
 IPv6: ADDRCONF(NETDEV_UP): eth3: link is not ready
 random: crng init done
 SCSI subsystem initialized
 usb-storage 1-1:1.0: USB Mass Storage device detected
 scsi host0: usb-storage 1-1:1.0
 usbcore: registered new interface driver usb-storage
 prueth pruss2_eth: freeing port 0
 scsi 0:0:0:0: Direct-Access     JetFlash Transcend 16GB   8.07 PQ: 0 ANSI: 4
 sd 0:0:0:0: [sda] 30531584 512-byte logical blocks: (15.6 GB/14.6 GiB)
 sd 0:0:0:0: [sda] Write Protect is off
 sd 0:0:0:0: [sda] Mode Sense: 23 00 00 00
 sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
  sda: sda1
 sd 0:0:0:0: [sda] Attached SCSI removable disk
 cpsw 48484000.ethernet eth3: Link is Up - 100Mbps/Full - flow control off
 IPv6: ADDRCONF(NETDEV_CHANGE): eth3: link becomes ready
 prueth pruss2_eth: freeing port 0
 prueth pruss2_eth: freeing port 0
 rtc-rx8581 0-0051: rx8581_rtc_read_time: raw data is sec=47, min=39, hr=03, wday=08, mday=29, mon=12, year=21
 rtc-rx8581 0-0051: rx8581_rtc_read_time: tm is secs=47, mins=39, hours=3, mday=29, mon=11, year=121, wday=3
 NET: Registered protocol family 15
 bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
 Bridge firewalling registered
 Initializing XFRM netlink socket
 IPv6: ADDRCONF(NETDEV_UP): depot_bridge: link is not ready
 IPv6: ADDRCONF(NETDEV_UP): docker0: link is not ready
 depot_bridge: port 1(vethbedb0b3) entered blocking state
 depot_bridge: port 1(vethbedb0b3) entered disabled state
 device vethbedb0b3 entered promiscuous mode
 IPv6: ADDRCONF(NETDEV_UP): vethbedb0b3: link is not ready
 cgroup: runc (1179) created nested cgroup for controller "memory" which has incomplete hierarchy support. Nested cgroups may change behavior in the future.
 cgroup: "memory" requires setting use_hierarchy to 1 on the root
 eth0: renamed from vethbdd812f
 IPv6: ADDRCONF(NETDEV_CHANGE): vethbedb0b3: link becomes ready
 depot_bridge: port 1(vethbedb0b3) entered blocking state
 depot_bridge: port 1(vethbedb0b3) entered forwarding state
 IPv6: ADDRCONF(NETDEV_CHANGE): depot_bridge: link becomes ready



At failing case, customer tried to unload then load the module manually, but it also failed.
Here is the console log with some comments.
manual_load.txt
root@XYZ:~# rmmod ti_prueth          <=== No output log for this command
root@XYZ:~# rmmod pru_rproc          <=== normal output log for this command
Jan  6 11:53:26 XYZ user.info kernel: [81431.286721] pru-rproc 4b2b8000.pru: pru_rproc_remove: removing rproc 4b2b8000.pru
Jan  6 11:53:26 XYZ user.info kernel: [81431.295953] remoteproc remoteproc3: releasing 4b2b8000.pru
Jan  6 11:53:26 XYZ user.info kernel: [81431.301857] pru-rproc 4b2b4000.pru: pru_rproc_remove: removing rproc 4b2b4000.pru
Jan  6 11:53:26 XYZ user.info kernel: [81431.309998] remoteproc remoteproc2: releasing 4b2b4000.pru
Jan  6 11:53:26 XYZ user.info kernel: [81431.315820] pru-rproc 4b238000.pru: pru_rproc_remove: removing rproc 4b238000.pru
Jan  6 11:53:26 XYZ user.info kernel: [81431.323896] remoteproc remoteproc1: releasing 4b238000.pru
Jan  6 11:53:27 XYZ user.info kernel: [81431.329734] pru-rproc 4b234000.pru: pru_rproc_remove: removing rproc 4b234000.pru
Jan  6 11:53:27 XYZ user.info kernel: [81431.337802] remoteproc remoteproc0: releasing 4b234000.pru
root@XYZ:~# modprobe ti_prueth
Jan  6 11:53:37 XYZ user.info kernel: [81442.004806] remoteproc remoteproc0: 4b234000.pru is available
Jan  6 11:53:37 XYZ user.info kernel: [81442.010914] pru-rproc 4b234000.pru: PRU rproc node pru@4b234000 probed successfully
Jan  6 11:53:37 XYZ user.info kernel: [81442.019912] remoteproc remoteproc1: 4b238000.pru is available
Jan  6 11:53:37 XYZ user.info kernel: [81442.026008] pru-rproc 4b238000.pru: PRU rproc node pru@4b238000 probed successfully
Jan  6 11:53:37 XYZ user.info kernel: [81442.034422] remoteproc remoteproc2: 4b2b4000.pru is available
Jan  6 11:53:37 XYZ user.info kernel: [81442.040716] pru-rproc 4b2b4000.pru: PRU rproc node pru@4b2b4000 probed successfully
Jan  6 11:53:37 XYZ user.info kernel: [81442.049138] remoteproc remoteproc3: 4b2b8000.pru is available
Jan  6 11:53:37 XYZ user.info kernel: [81442.055229] pru-rproc 4b2b8000.pru: PRU rproc node pru@4b2b8000 probed successfully
<=== For normal operation, "prueth pruss2_eth: TI PRU ethernet (type 0) driver initialized" should be here, but no such output log.


Customer also tried ftrace at failing case, and found "prueth_netdev_init" was called just once.
ftrace_fail.txt
root@XYZ:~# rmmod ti_prueth
root@XYZ:~# rmmod pru_rproc
root@XYZ:~# cd /sys/kernel/debug/tracing/
root@XYZ:/sys/kernel/debug/tracing#  echo 0 > tracing_on
root@XYZ:/sys/kernel/debug/tracing# echo > trace
root@XYZ:/sys/kernel/debug/tracing# echo '*:mod:ti_prueth' > set_ftrace_filter
root@XYZ:/sys/kernel/debug/tracing# echo 'function_graph' > current_tracer
root@XYZ:/sys/kernel/debug/tracing# echo 1 > tracing_on
root@XYZ:/sys/kernel/debug/tracing# modprobe ti_prueth
root@XYZ:/sys/kernel/debug/tracing# cat trace
# tracer: function_graph
#
# CPU  DURATION                  FUNCTION CALLS
# |     |   |                     |   |   |   |
1)               |  prueth_driver_init [ti_prueth]() {
1)               |    prueth_probe [ti_prueth]() {
1)               |      prueth_netdev_init [ti_prueth]() {
1)   2.750 us    |        prueth_node_port [ti_prueth]();
1)   2.250 us    |        prueth_node_mac [ti_prueth]();
1) @ 840291.9 us |      }
1) @ 841038.1 us |    }
1) @ 841923.0 us |  }


In normal operation, ftrace log is like this.
ftrace_normal.txt
root@XYZ:/sys/kernel/debug/tracing# cat trace
# tracer: function_graph
#
# CPU  DURATION                  FUNCTION CALLS
# |     |   |                     |   |   |   |
1)               |  prueth_driver_init [ti_prueth]() {
1)               |    prueth_probe [ti_prueth]() {
1)               |      prueth_netdev_init [ti_prueth]() {
1)   2.500 us    |        prueth_node_port [ti_prueth]();
1)   2.250 us    |        prueth_node_mac [ti_prueth]();
1) * 97426.60 us |      }
1)               |      prueth_netdev_init [ti_prueth]() {
1)   2.250 us    |        prueth_node_port [ti_prueth]();
1)   2.500 us    |        prueth_node_mac [ti_prueth]();
1) * 99759.60 us |      }
1) ! 254.200 us  |      prueth_init_mem [ti_prueth]();
1)   7.000 us    |      prueth_get_mc_mac_mask [ti_prueth]();
1)   3.750 us    |      prueth_get_mc_mac_mask [ti_prueth]();
1) + 15.600 us   |      iep_create [ti_prueth]();
1)   5.750 us    |      emac_ndo_fix_features [ti_prueth]();
1)   2.250 us    |      emac_ndo_set_features [ti_prueth]();
1)   2.500 us    |      emac_ndo_fix_features [ti_prueth]();
1)               |      emac_ndo_get_stats [ti_prueth]() {
1) + 31.100 us   |        emac_get_stats [ti_prueth]();
1) + 38.350 us   |      }
1)               |      prueth_debugfs_init [ti_prueth]() {
1) + 35.150 us   |        prueth_dualemac_debugfs_init [ti_prueth]();
1) ! 286.700 us  |      }
1) + 24.700 us   |      prueth_sysfs_init [ti_prueth]();
1)   6.500 us    |      emac_ndo_fix_features [ti_prueth]();
1)   2.000 us    |      emac_ndo_set_features [ti_prueth]();
1)   2.250 us    |      emac_ndo_fix_features [ti_prueth]();
1)               |      emac_ndo_get_stats [ti_prueth]() {
1)   4.750 us    |        emac_get_stats [ti_prueth]();
1) + 11.500 us   |      }
1)               |      prueth_debugfs_init [ti_prueth]() {
1) + 29.350 us   |        prueth_dualemac_debugfs_init [ti_prueth]();
1) + 69.500 us   |      }
1) + 13.900 us   |      prueth_sysfs_init [ti_prueth]();
1) @ 235616.6 us |    }
1) @ 238310.2 us |  }


PRU driver used is this.
/sdk/kernel/linux-rt-4.19.79/drivers/net/ethernet/ti/prueth.c

Are there any suspicious points or debug suggestions?

Thanks and regards,
Koichiro Tashiro