AM6442: EMMC Timeout in Booting Kernel

Hi Kevin,

Thanks for the issue report. I will review it tomorrow and get back to you.

Hi Kevin,

Our hw expert reviewed the board schematics provided in the thread linked below,

https://e2e.ti.com/support/processors-group/processors/f/processors-forum/1432304/am6442-emmc-boot-meet-resetting-problem

and have a couple of comments.

- The MMC CLK pullup R515 is unnecessary. It doesn't affect MMC function, just consumes unnecessary power;

- The MMC CLK series resister R512 shouldn't be there. Please remove it to see if the eMMC timeout still happens in Linux.

Hi Bin,

I removed R515, and change R512 to 0R. But it still doesn't help.

[    1.321231] am65-cpsw-nuss 8000000.ethernet: CPTS ver 0x4e8a010c, freq:500000000, add_val:1 pps:1
[    1.338728] am65-cpsw-nuss 8000000.ethernet: set new flow-id-base 16
[    1.350991] am65-cpts 39000000.cpts: CPTS ver 0x4e8a010c, freq:500000000, add_val:1 pps:0
[    1.361265] mmc0: CQHCI version 5.10
[    1.374668] clk: Disabling unused clocks
[    1.384613] ti-sci-clk 44043000.system-controller:clock-controller: is_prepared failed for dev=62, clk=3, ret=-19
[    1.408044] mmc0: SDHCI controller on fa10000.mmc [fa10000.mmc] using ADMA 64-bit
[    1.423263] ALSA device list:
[    1.426349]   No soundcards found.
[    1.430209] Waiting for root device PARTUUID=e14aea3b-1f60-447e-b0df-37a749a387e9...
[    1.490254] mmc0: Command Queue Engine enabled
[    1.494764] mmc0: new HS200 MMC card at address 0001
[    1.500521] mmcblk0: mmc0:0001 M04A11 3.64 GiB
[    1.509522]  mmcblk0: p1
[    1.512866] mmcblk0boot0: mmc0:0001 M04A11 4.00 MiB
[    1.519130] mmcblk0boot1: mmc0:0001 M04A11 4.00 MiB
[    1.525252] mmcblk0rpmb: mmc0:0001 M04A11 4.00 MiB, chardev (239:0)
[    1.581289] EXT4-fs (mmcblk0p1): recovery complete
[    1.586164] EXT4-fs (mmcblk0p1): mounted filesystem bef90e4d-4ff1-4f75-a999-38b6040d4747 r/w with ordered data mode. Quota mode: none.
[    1.598336] VFS: Mounted root (ext4 filesystem) on device 179:1.
[    1.607366] devtmpfs: mounted
[    1.612072] Freeing unused kernel memory: 2368K
[    1.616719] Run /sbin/init as init process
[   62.551556] mmc0: cqhci: timeout for tag 29, qcnt 4
[   62.556475] mmc0: cqhci: ============ CQHCI REGISTER DUMP ===========
[   62.562912] mmc0: cqhci: Caps:      0x000030c8 | Version:  0x00000510
[   62.569347] mmc0: cqhci: Config:    0x00000101 | Control:  0x00000000
[   62.575780] mmc0: cqhci: Int stat:  0x00000000 | Int enab: 0x00000016
[   62.582214] mmc0: cqhci: Int sig:   0x00000016 | Int Coal: 0x00000000
[   62.588648] mmc0: cqhci: TDL base:  0x81409000 | TDL up32: 0x00000000
[   62.595081] mmc0: cqhci: Doorbell:  0xe0008000 | TCN:      0x00000000
[   62.601514] mmc0: cqhci: Dev queue: 0x08000000 | Dev Pend: 0xe0008000
[   62.607948] mmc0: cqhci: Task clr:  0x00000000 | SSC1:     0x00011000
[   62.614386] mmc0: cqhci: SSC2:      0x00000001 | DCMD rsp: 0x00000000
[   62.620820] mmc0: cqhci: RED mask:  0xfdf9a080 | TERRI:    0x00000000
[   62.627253] mmc0: cqhci: Resp idx:  0x0000002d | Resp arg: 0x00000900
[   62.633686] mmc0: sdhci: ============ SDHCI REGISTER DUMP ===========
[   62.640119] mmc0: sdhci: Sys addr:  0x00000090 | Version:  0x00001004
[   62.646552] mmc0: sdhci: Blk size:  0x00000200 | Blk cnt:  0x00000000
[   62.652986] mmc0: sdhci: Argument:  0x00000282 | Trn mode: 0x00000033
[   62.659421] mmc0: sdhci: Present:   0x01ff00f0 | Host ctl: 0x0000003c
[   62.665855] mmc0: sdhci: Power:     0x0000000b | Blk gap:  0x00000080
[   62.672294] mmc0: sdhci: Wake-up:   0x00000000 | Clock:    0x00000007
[   62.678727] mmc0: sdhci: Timeout:   0x0000000e | Int stat: 0x00000000
[   62.685160] mmc0: sdhci: Int enab:  0x02ff4000 | Sig enab: 0x02ff4000
[   62.691594] mmc0: sdhci: ACmd stat: 0x00000000 | Slot int: 0x00000000
[   62.698026] mmc0: sdhci: Caps:      0x7cecc801 | Caps_1:   0x98002407
[   62.704459] mmc0: sdhci: Cmd:       0x00002d1a | Max curr: 0x00000000
[   62.710893] mmc0: sdhci: Resp[0]:   0x00000900 | Resp[1]:  0xfff6dbff
[   62.717326] mmc0: sdhci: Resp[2]:   0x329f5903 | Resp[3]:  0x00d02701
[   62.723758] mmc0: sdhci: Host ctl2: 0x0000000b
[   62.728196] mmc0: sdhci: ADMA Err:  0x00000000 | ADMA Ptr: 0x000000009bf8a818
[   62.735328] mmc0: sdhci: ============================================
[   62.741786] mmc0: running CQE recovery

the EMMC manufacture says it may be require tunning the sequency.

I lookup some other forum post, but I find my emmc is already working in HS200 mode.

Is there any parameter for tunning?

Thanks!

Hi Wangbin,

Please apply the following kernel debug patch, it prints eMMC tuning details in kernel boot log.

diff --git a/drivers/mmc/host/sdhci_am654.c b/drivers/mmc/host/sdhci_am654.c
index 32f51d22978e..dc8a1baceeca 100644
--- a/drivers/mmc/host/sdhci_am654.c
+++ b/drivers/mmc/host/sdhci_am654.c
@@ -538,6 +538,8 @@ static int sdhci_am654_platform_execute_tuning(struct sdhci_host *host,
                sdhci_am654_write_itapdly(sdhci_am654, itap, sdhci_am654->itap_del_ena[timing]);
 
                curr_pass = !mmc_send_tuning(host->mmc, opcode, NULL);
+               dev_info(mmc_dev(host->mmc), "For itapdly=%d:%s\n", itap,
+                               curr_pass ? "PASS" : "FAIL");
 
                if (!curr_pass && prev_pass)
                        fail_window[fail_index].start = itap;
@@ -559,6 +561,7 @@ static int sdhci_am654_platform_execute_tuning(struct sdhci_host *host,
        itap = sdhci_am654_calculate_itap(host, fail_window, fail_index,
                                          sdhci_am654->dll_enable);
 
+       dev_info(mmc_dev(host->mmc), "final tunning point %d\n", itap);
        sdhci_am654_write_itapdly(sdhci_am654, itap, sdhci_am654->itap_del_ena[timing]);
 
        /* Save ITAPDLY */

Hi Bin,

         Currntlly, I move the work around reset in a53 and added above code in the kernel driver.

Please kindly analyze the following logs.

Thanks!

U-Boot 2024.04-00002-g8792c3f6-dirty (Nov 05 2024 - 18:12:04 +0800)

SoC:   AM64X SR2.0 HS-FS
Model: Texas Instruments AM642 EVM
DRAM:  512 MiB
rst_src: 0x10000
Core:  57 devices, 26 uclasses, devicetree: separate
NAND:  0 MiB
MMC:   mmc@fa10000: 0
Loading Environment from MMC... OK
In:    serial@2800000
Out:   serial@2800000
Err:   serial@2800000
Net:   No ethernet found.
Hit any key to stop autoboot:  0
switch to partitions #0, OK
mmc0(part 0) is current device
SD/MMC found on device 0
Can't set block device
ERROR: reserving fdt memory region failed (addr=9e800000 size=1800000 flags=4)
20118016 bytes read in 181 ms (106 MiB/s)
ERROR: reserving fdt memory region failed (addr=9e800000 size=1800000 flags=4)
60714 bytes read in 45 ms (1.3 MiB/s)
Working FDT set to 88000000
## Flattened Device Tree blob at 88000000
   Booting using the fdt blob at 0x88000000
Working FDT set to 88000000
ERROR: reserving fdt memory region failed (addr=9e800000 size=1800000 flags=4)
   Loading Device Tree to 000000008feee000, end 000000008fffffff ... OK
Working FDT set to 8feee000

Starting kernel ...

[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034]
[    0.000000] Linux version 6.6.32-g6de6e418c80e-dirty (xxx@xxx-xxx-N000) (aarch64-oe-linux-gcc (GCC) 13.3.0, GNU ld (GNU Binutils) 2.42.0.20240716                                                                  ) #5 SMP PREEMPT Tue Nov  5 18:26:23 CST 2024
[    0.000000] KASLR disabled due to lack of seed
[    0.000000] Machine model: Texas Instruments AM642 EVM
[    0.000000] earlycon: ns16550a0 at MMIO32 0x0000000002800000 (options '')
[    0.000000] printk: bootconsole [ns16550a0] enabled
[    0.000000] efi: UEFI not found.
[    0.000000] Reserved memory: created DMA memory pool at 0x0000000090000000, size 1 MiB
[    0.000000] OF: reserved mem: initialized node r5f-dma-memory@90000000, compatible id shared-dma-pool
[    0.000000] OF: reserved mem: 0x0000000090000000..0x00000000900fffff (1024 KiB) nomap non-reusable r5f-dma-memory@90000000
[    0.000000] Reserved memory: created DMA memory pool at 0x0000000090100000, size 15 MiB
[    0.000000] OF: reserved mem: initialized node r5f-memory@90100000, compatible id shared-dma-pool
[    0.000000] OF: reserved mem: 0x0000000090100000..0x0000000090ffffff (15360 KiB) nomap non-reusable r5f-memory@90100000
[    0.000000] Reserved memory: created DMA memory pool at 0x0000000091000000, size 1 MiB
[    0.000000] OF: reserved mem: initialized node r5f-dma-memory@91000000, compatible id shared-dma-pool
[    0.000000] OF: reserved mem: 0x0000000091000000..0x00000000910fffff (1024 KiB) nomap non-reusable r5f-dma-memory@91000000
[    0.000000] Reserved memory: created DMA memory pool at 0x0000000091100000, size 15 MiB
[    0.000000] OF: reserved mem: initialized node r5f-memory@91100000, compatible id shared-dma-pool
[    0.000000] OF: reserved mem: 0x0000000091100000..0x0000000091ffffff (15360 KiB) nomap non-reusable r5f-memory@91100000
[    0.000000] Reserved memory: created DMA memory pool at 0x0000000092000000, size 1 MiB
[    0.000000] OF: reserved mem: initialized node r5f-dma-memory@92000000, compatible id shared-dma-pool
[    0.000000] OF: reserved mem: 0x0000000092000000..0x00000000920fffff (1024 KiB) nomap non-reusable r5f-dma-memory@92000000
[    0.000000] Reserved memory: created DMA memory pool at 0x0000000092100000, size 15 MiB
[    0.000000] OF: reserved mem: initialized node r5f-memory@92100000, compatible id shared-dma-pool
[    0.000000] OF: reserved mem: 0x0000000092100000..0x0000000092ffffff (15360 KiB) nomap non-reusable r5f-memory@92100000
[    0.000000] Reserved memory: created DMA memory pool at 0x0000000093000000, size 1 MiB
[    0.000000] OF: reserved mem: initialized node r5f-dma-memory@93000000, compatible id shared-dma-pool
[    0.000000] OF: reserved mem: 0x0000000093000000..0x00000000930fffff (1024 KiB) nomap non-reusable r5f-dma-memory@93000000
[    0.000000] Reserved memory: created DMA memory pool at 0x0000000093100000, size 15 MiB
[    0.000000] OF: reserved mem: initialized node r5f-memory@93100000, compatible id shared-dma-pool
[    0.000000] OF: reserved mem: 0x0000000093100000..0x0000000093ffffff (15360 KiB) nomap non-reusable r5f-memory@93100000
[    0.000000] Reserved memory: created DMA memory pool at 0x0000000094000000, size 1 MiB
[    0.000000] OF: reserved mem: initialized node m4f-dma-memory@94000000, compatible id shared-dma-pool
[    0.000000] OF: reserved mem: 0x0000000094000000..0x00000000940fffff (1024 KiB) nomap non-reusable m4f-dma-memory@94000000
[    0.000000] Reserved memory: created DMA memory pool at 0x0000000094100000, size 15 MiB
[    0.000000] OF: reserved mem: initialized node m4f-memory@94100000, compatible id shared-dma-pool
[    0.000000] OF: reserved mem: 0x0000000094100000..0x0000000094ffffff (15360 KiB) nomap non-reusable m4f-memory@94100000
[    0.000000] OF: reserved mem: 0x0000000095000000..0x00000000957fffff (8192 KiB) nomap non-reusable ipc-memories@95000000
[    0.000000] OF: reserved mem: 0x000000009e800000..0x000000009fffffff (24576 KiB) nomap non-reusable optee@9e800000
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000080000000-0x000000009fffffff]
[    0.000000]   DMA32    empty
[    0.000000]   Normal   empty
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000080000000-0x000000008fffffff]
[    0.000000]   node   0: [mem 0x0000000090000000-0x00000000957fffff]
[    0.000000]   node   0: [mem 0x0000000095800000-0x000000009e7fffff]
[    0.000000]   node   0: [mem 0x000000009e800000-0x000000009fffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000080000000-0x000000009fffffff]
[    0.000000] cma: Reserved 32 MiB at 0x000000009be00000 on node -1
[    0.000000] psci: probing for conduit method from DT.
[    0.000000] psci: PSCIv1.1 detected in firmware.
[    0.000000] psci: Using standard PSCI v0.2 function IDs
[    0.000000] psci: Trusted OS migration not required
[    0.000000] psci: SMC Calling Convention v1.4
[    0.000000] percpu: Embedded 20 pages/cpu s42920 r8192 d30808 u81920
[    0.000000] Detected VIPT I-cache on CPU0
[    0.000000] CPU features: detected: GIC system register CPU interface
[    0.000000] CPU features: detected: ARM erratum 845719
[    0.000000] alternatives: applying boot alternatives
[    0.000000] Kernel command line: console=ttyS2,115200n8 earlycon=ns16550a,mmio32,0x02800000 omap2-nand.0:2m(NAND.tiboot3),2m(NAND.tispl),2m(NAND.tiboot3.backup),4m(NAND                                                                  .u-boot),256k(NAND.u-boot-env),256k(NAND.u-boot-env.backup),-(NAND.file-system) root=PARTUUID=e14aea3b-1f60-447e-b0df-37a749a387e9 rw rootfstype=ext4 rootwait
[    0.000000] Dentry cache hash table entries: 65536 (order: 7, 524288 bytes, linear)
[    0.000000] Inode-cache hash table entries: 32768 (order: 6, 262144 bytes, linear)
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 129024
[    0.000000] mem auto-init: stack:all(zero), heap alloc:off, heap free:off
[    0.000000] software IO TLB: area num 2.
[    0.000000] software IO TLB: mapped [mem 0x0000000097dc0000-0x000000009bdc0000] (64MB)
[    0.000000] Memory: 280036K/524288K available (11904K kernel code, 1214K rwdata, 3996K rodata, 2368K init, 502K bss, 211484K reserved, 32768K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[    0.000000] rcu: Preemptible hierarchical RCU implementation.
[    0.000000] rcu:     RCU event tracing is enabled.
[    0.000000] rcu:     RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=2.
[    0.000000]  Trampoline variant of Tasks RCU enabled.
[    0.000000]  Tracing variant of Tasks RCU enabled.
[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2
[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
[    0.000000] GICv3: GIC: Using split EOI/Deactivate mode
[    0.000000] GICv3: 256 SPIs implemented
[    0.000000] GICv3: 0 Extended SPIs implemented
[    0.000000] Root IRQ handler: gic_handle_irq
[    0.000000] GICv3: GICv3 features: 16 PPIs
[    0.000000] GICv3: CPU0: found redistributor 0 region 0:0x0000000001840000
[    0.000000] ITS [mem 0x01820000-0x0182ffff]
[    0.000000] GIC: enabling workaround for ITS: Socionext Synquacer pre-ITS
[    0.000000] ITS@0x0000000001820000: Devices Table too large, reduce ids 20->19
[    0.000000] ITS@0x0000000001820000: allocated 524288 Devices @80800000 (flat, esz 8, psz 64K, shr 0)
[    0.000000] ITS: using cache flushing for cmd queue
[    0.000000] GICv3: using LPI property table @0x0000000080040000
[    0.000000] GIC: using cache flushing for LPI property table
[    0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000080050000
[    0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.
[    0.000000] arch_timer: cp15 timer(s) running at 200.00MHz (phys).
[    0.000000] clocksource: arch_sys_counter: mask: 0x3ffffffffffffff max_cycles: 0x2e2049d3e8, max_idle_ns: 440795210634 ns
[    0.000001] sched_clock: 58 bits at 200MHz, resolution 5ns, wraps every 4398046511102ns
[    0.008638] Console: colour dummy device 80x25
[    0.013246] Calibrating delay loop (skipped), value calculated using timer frequency.. 400.00 BogoMIPS (lpj=800000)
[    0.023926] pid_max: default: 32768 minimum: 301
[    0.028736] LSM: initializing lsm=capability,integrity
[    0.034115] Mount-cache hash table entries: 1024 (order: 1, 8192 bytes, linear)
[    0.041597] Mountpoint-cache hash table entries: 1024 (order: 1, 8192 bytes, linear)
[    0.051579] RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1.
[    0.058934] RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1.
[    0.066917] rcu: Hierarchical SRCU implementation.
[    0.071827] rcu:     Max phase no-delay instances is 1000.
[    0.077485] Platform MSI: msi-controller@1820000 domain created
[    0.083869] PCI/MSI: /bus@f4000/interrupt-controller@1800000/msi-controller@1820000 domain created
[    0.093394] EFI services will not be available.
[    0.098363] smp: Bringing up secondary CPUs ...
I/TC: Secondary CPU 1 initializing
I/TC: Secondary CPU 1 switching to normal world boot
[    0.112039] Detected VIPT I-cache on CPU1
[    0.112143] GICv3: CPU1: found redistributor 1 region 0:0x0000000001860000
[    0.112163] GICv3: CPU1: using allocated LPI pending table @0x0000000080060000
[    0.112224] CPU1: Booted secondary processor 0x0000000001 [0x410fd034]
[    0.112369] smp: Brought up 1 node, 2 CPUs
[    0.141750] SMP: Total of 2 processors activated.
[    0.146563] CPU features: detected: 32-bit EL0 Support
[    0.151834] CPU features: detected: CRC32 instructions
[    0.157156] CPU: All CPU(s) started at EL2
[    0.161342] alternatives: applying system-wide alternatives
[    0.168557] devtmpfs: initialized
[    0.181529] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.191542] futex hash table entries: 512 (order: 3, 32768 bytes, linear)
[    0.199912] pinctrl core: initialized pinctrl subsystem
[    0.205918] DMI not present or invalid.
[    0.210665] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[    0.217741] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations
[    0.225144] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
[    0.233197] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
[    0.241405] audit: initializing netlink subsys (disabled)
[    0.247220] audit: type=2000 audit(0.156:1): state=initialized audit_enabled=0 res=1
[    0.247790] thermal_sys: Registered thermal governor 'step_wise'
[    0.255168] thermal_sys: Registered thermal governor 'power_allocator'
[    0.261570] cpuidle: using governor menu
[    0.272506] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
[    0.279513] ASID allocator initialised with 65536 entries
[    0.296897] platform a40000.pinctrl: Fixed dependency cycle(s) with /bus@f4000/pinctrl@a40000/cpsw-cpts-pps
[    0.310896] Modules: 27712 pages in range for non-PLT usage
[    0.310975] Modules: 519232 pages in range for PLT usage
[    0.317705] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages
[    0.330100] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page
[    0.336505] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages
[    0.343442] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page
[    0.349847] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages
[    0.356782] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page
[    0.363185] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages
[    0.370121] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page
[    0.378398] k3-chipinfo 43000014.chipid: Family:AM64X rev:SR2.0 JTAGID[0x1bb3802f] Detected
[    0.388000] iommu: Default domain type: Translated
[    0.392938] iommu: DMA domain TLB invalidation policy: strict mode
[    0.399662] SCSI subsystem initialized
[    0.404028] usbcore: registered new interface driver usbfs
[    0.409685] usbcore: registered new interface driver hub
[    0.415164] usbcore: registered new device driver usb
[    0.420874] pps_core: LinuxPPS API ver. 1 registered
[    0.425953] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.435301] PTP clock support registered
[    0.439498] EDAC MC: Ver: 3.0.0
[    0.443579] scmi_core: SCMI protocol bus registered
[    0.449079] FPGA manager framework
[    0.452717] Advanced Linux Sound Architecture Driver Initialized.
[    0.460129] vgaarb: loaded
[    0.463418] clocksource: Switched to clocksource arch_sys_counter
[    0.469972] VFS: Disk quotas dquot_6.6.0
[    0.474027] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    0.488969] NET: Registered PF_INET protocol family
[    0.494199] IP idents hash table entries: 8192 (order: 4, 65536 bytes, linear)
[    0.502648] tcp_listen_portaddr_hash hash table entries: 256 (order: 0, 4096 bytes, linear)
[    0.511245] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
[    0.519176] TCP established hash table entries: 4096 (order: 3, 32768 bytes, linear)
[    0.527127] TCP bind hash table entries: 4096 (order: 5, 131072 bytes, linear)
[    0.534643] TCP: Hash tables configured (established 4096 bind 4096)
[    0.541292] UDP hash table entries: 256 (order: 1, 8192 bytes, linear)
[    0.547994] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes, linear)
[    0.555282] NET: Registered PF_UNIX/PF_LOCAL protocol family
[    0.561737] RPC: Registered named UNIX socket transport module.
[    0.567840] RPC: Registered udp transport module.
[    0.572648] RPC: Registered tcp transport module.
[    0.577455] RPC: Registered tcp-with-tls transport module.
[    0.583061] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.589655] NET: Registered PF_XDP protocol family
[    0.594574] PCI: CLS 0 bytes, default 64
[    0.600076] Initialise system trusted keyrings
[    0.604987] workingset: timestamp_bits=46 max_order=17 bucket_order=0
[    0.612027] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.618452] NFS: Registering the id_resolver key type
[    0.623688] Key type id_resolver registered
[    0.627965] Key type id_legacy registered
[    0.632089] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    0.638943] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
[    0.687089] Key type asymmetric registered
[    0.691294] Asymmetric key parser 'x509' registered
[    0.696352] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 244)
[    0.703918] io scheduler mq-deadline registered
[    0.708558] io scheduler kyber registered
[    0.712704] io scheduler bfq registered
[    0.719791] pinctrl-single 4084000.pinctrl: 33 pins, size 132
[    0.726248] pinctrl-single f4000.pinctrl: 180 pins, size 720
[    0.733722] pinctrl-single a40000.pinctrl: 512 pins, size 2048
[    0.747133] Serial: 8250/16550 driver, 12 ports, IRQ sharing enabled
[    0.766405] loop: module loaded
[    0.770945] megasas: 07.725.01.00-rc1
[    0.777667] tun: Universal TUN/TAP device driver, 1.6
[    0.784451] VFIO - User Level meta-driver version: 0.3
[    0.791106] usbcore: registered new interface driver usb-storage
[    0.797719] i2c_dev: i2c /dev entries driver
[    0.803444] sdhci: Secure Digital Host Controller Interface driver
[    0.809793] sdhci: Copyright(c) Pierre Ossman
[    0.814515] sdhci-pltfm: SDHCI platform and OF driver helper
[    0.821125] ledtrig-cpu: registered to indicate activity on CPUs
[    0.827744] SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping ....
[    0.835222] usbcore: registered new interface driver usbhid
[    0.840943] usbhid: USB HID core driver
[    0.845280] omap-mailbox 29020000.mailbox: omap mailbox rev 0x66fc9100
[    0.852241] omap-mailbox 29040000.mailbox: omap mailbox rev 0x66fc9100
[    0.859114] omap-mailbox 29060000.mailbox: omap mailbox rev 0x66fc9100
[    0.867699] hw perfevents: enabled with armv8_cortex_a53 PMU driver, 7 counters available
[    0.876862] optee: probing for conduit method.
I/TC: Reserved shared memory is enabled
I/TC: Dynamic shared memory is enabled
I/TC: Normal World virtualization support is disabled
I/TC: Asynchronous notifications are disabled
[    0.881449] optee: revision 4.2 (12d7c4ee)
[    0.898169] optee: dynamic shared memory is enabled
[    0.908362] random: crng init done
[    0.912019] optee: initialized driver
[    0.918339] NET: Registered PF_PACKET protocol family
[    0.923666] Key type dns_resolver registered
[    0.937548] registered taskstats version 1
[    0.941937] Loading compiled-in X.509 certificates
[    0.962276] ti-sci 44043000.system-controller: ABI: 4.0 (firmware rev 0x000a '10.0.8--v10.00.08 (Fiery Fox)')
[    1.072896] ti-sci-clk 44043000.system-controller:clock-controller: recalc-rate failed for dev=62, clk=3, ret=-19
[    1.112049] ti-sci-intr bus@f4000:interrupt-controller@a00000: Interrupt Router 3 domain created
[    1.121467] ti-sci-inta 48000000.interrupt-controller: Interrupt Aggregator domain 28 created
[    1.130584] ti-udma 485c0100.dma-controller: Number of rings: 68
[    1.138461] ti-udma 485c0100.dma-controller: Channels: 24 (bchan: 12, tchan: 6, rchan: 6)
[    1.148819] ti-udma 485c0000.dma-controller: Number of rings: 288
[    1.166523] ti-udma 485c0000.dma-controller: Channels: 44 (tchan: 29, rchan: 15)
[    1.177938] printk: console [ttyS2] disabled
[    1.182826] 2800000.serial: ttyS2 at MMIO 0x2800000 (irq = 315, base_baud = 3000000) is a 8250
[    1.191739] printk: console [ttyS2] enabled
[    1.191739] printk: console [ttyS2] enabled
[    1.200294] printk: bootconsole [ns16550a0] disabled
[    1.200294] printk: bootconsole [ns16550a0] disabled
[    1.215380] spi-nor spi0.0: unrecognized JEDEC id bytes: 90 80 2f 90 80 2f
[    1.224294] davinci_mdio 8000f00.mdio: Configuring MDIO in manual mode
[    1.267430] davinci_mdio 8000f00.mdio: davinci mdio revision 9.7, bus freq 1000000
[    1.277739] davinci_mdio 8000f00.mdio: phy[7]: device 8000f00.mdio:07, driver TI DP83826C
[    1.286000] am65-cpsw-nuss 8000000.ethernet: initializing am65 cpsw nuss version 0x6BA00903, cpsw version 0x6BA80903 Ports: 3 quirks:00000006
[    1.298849] am65-cpsw-nuss 8000000.ethernet: initialized cpsw ale version 1.4
[    1.305983] am65-cpsw-nuss 8000000.ethernet: ALE Table size 512
[    1.312744] pps pps0: new PPS source ptp0
[    1.317196] am65-cpsw-nuss 8000000.ethernet: CPTS ver 0x4e8a010c, freq:500000000, add_val:1 pps:1
[    1.334696] am65-cpsw-nuss 8000000.ethernet: set new flow-id-base 16
[    1.346915] am65-cpts 39000000.cpts: CPTS ver 0x4e8a010c, freq:500000000, add_val:1 pps:0
[    1.357210] mmc0: CQHCI version 5.10
[    1.370693] clk: Disabling unused clocks
[    1.380612] ti-sci-clk 44043000.system-controller:clock-controller: is_prepared failed for dev=62, clk=3, ret=-19
[    1.402125] mmc0: SDHCI controller on fa10000.mmc [fa10000.mmc] using ADMA 64-bit
[    1.419220] ALSA device list:
[    1.422209]   No soundcards found.
[    1.426011] Waiting for root device PARTUUID=e14aea3b-1f60-447e-b0df-37a749a387e9...
[    1.473379] sdhci-am654 fa10000.mmc: For itapdly=0:PASS
[    1.478669] sdhci-am654 fa10000.mmc: For itapdly=1:PASS
[    1.483946] sdhci-am654 fa10000.mmc: For itapdly=2:PASS
[    1.489222] sdhci-am654 fa10000.mmc: For itapdly=3:PASS
[    1.494498] sdhci-am654 fa10000.mmc: For itapdly=4:PASS
[    1.499777] sdhci-am654 fa10000.mmc: For itapdly=5:PASS
[    1.505052] sdhci-am654 fa10000.mmc: For itapdly=6:PASS
[    1.510328] sdhci-am654 fa10000.mmc: For itapdly=7:PASS
[    1.515604] sdhci-am654 fa10000.mmc: For itapdly=8:PASS
[    1.520880] sdhci-am654 fa10000.mmc: For itapdly=9:PASS
[    1.526157] sdhci-am654 fa10000.mmc: For itapdly=10:PASS
[    1.531522] sdhci-am654 fa10000.mmc: For itapdly=11:PASS
[    1.536889] sdhci-am654 fa10000.mmc: For itapdly=12:PASS
[    1.542258] sdhci-am654 fa10000.mmc: For itapdly=13:PASS
[    1.547667] sdhci-am654 fa10000.mmc: For itapdly=14:FAIL
[    1.553099] sdhci-am654 fa10000.mmc: For itapdly=15:FAIL
[    1.558524] sdhci-am654 fa10000.mmc: For itapdly=16:FAIL
[    1.563934] sdhci-am654 fa10000.mmc: For itapdly=17:FAIL
[    1.569301] sdhci-am654 fa10000.mmc: For itapdly=18:PASS
[    1.574666] sdhci-am654 fa10000.mmc: For itapdly=19:PASS
[    1.580034] sdhci-am654 fa10000.mmc: For itapdly=20:PASS
[    1.585400] sdhci-am654 fa10000.mmc: For itapdly=21:PASS
[    1.590766] sdhci-am654 fa10000.mmc: For itapdly=22:PASS
[    1.596132] sdhci-am654 fa10000.mmc: For itapdly=23:PASS
[    1.601498] sdhci-am654 fa10000.mmc: For itapdly=24:PASS
[    1.606863] sdhci-am654 fa10000.mmc: For itapdly=25:PASS
[    1.612231] sdhci-am654 fa10000.mmc: For itapdly=26:PASS
[    1.617597] sdhci-am654 fa10000.mmc: For itapdly=27:PASS
[    1.622963] sdhci-am654 fa10000.mmc: For itapdly=28:PASS
[    1.628331] sdhci-am654 fa10000.mmc: For itapdly=29:PASS
[    1.633696] sdhci-am654 fa10000.mmc: For itapdly=30:PASS
[    1.639062] sdhci-am654 fa10000.mmc: For itapdly=31:PASS
[    1.644379] sdhci-am654 fa10000.mmc: final tunning point 0
[    1.650393] mmc0: Command Queue Engine enabled
[    1.654891] mmc0: new HS200 MMC card at address 0001
[    1.660698] mmcblk0: mmc0:0001 M04A11 3.64 GiB
[    1.669961]  mmcblk0: p1
[    1.673290] mmcblk0boot0: mmc0:0001 M04A11 4.00 MiB
[    1.679629] mmcblk0boot1: mmc0:0001 M04A11 4.00 MiB
[    1.685752] mmcblk0rpmb: mmc0:0001 M04A11 4.00 MiB, chardev (239:0)
[    1.720692] EXT4-fs (mmcblk0p1): recovery complete
[    1.725546] EXT4-fs (mmcblk0p1): mounted filesystem bef90e4d-4ff1-4f75-a999-38b6040d4747 r/w with ordered data mode. Quota mode: none.
[    1.737704] VFS: Mounted root (ext4 filesystem) on device 179:1.
[    1.746744] devtmpfs: mounted
[    1.751464] Freeing unused kernel memory: 2368K
[    1.756114] Run /sbin/init as init process
[   62.551499] mmc0: cqhci: timeout for tag 5, qcnt 4
[   62.556332] mmc0: cqhci: ============ CQHCI REGISTER DUMP ===========
[   62.562767] mmc0: cqhci: Caps:      0x000030c8 | Version:  0x00000510
[   62.569202] mmc0: cqhci: Config:    0x00000101 | Control:  0x00000000
[   62.575635] mmc0: cqhci: Int stat:  0x00000000 | Int enab: 0x00000016
[   62.582068] mmc0: cqhci: Int sig:   0x00000016 | Int Coal: 0x00000000
[   62.588503] mmc0: cqhci: TDL base:  0x812b7000 | TDL up32: 0x00000000
[   62.594937] mmc0: cqhci: Doorbell:  0x000080e0 | TCN:      0x00000000
[   62.601369] mmc0: cqhci: Dev queue: 0x00000008 | Dev Pend: 0x000080e0
[   62.607803] mmc0: cqhci: Task clr:  0x00000000 | SSC1:     0x00011000
[   62.614241] mmc0: cqhci: SSC2:      0x00000001 | DCMD rsp: 0x00000000
[   62.620675] mmc0: cqhci: RED mask:  0xfdf9a080 | TERRI:    0x00000000
[   62.627109] mmc0: cqhci: Resp idx:  0x0000002d | Resp arg: 0x00000900
[   62.633543] mmc0: sdhci: ============ SDHCI REGISTER DUMP ===========
[   62.639975] mmc0: sdhci: Sys addr:  0x00000090 | Version:  0x00001004
[   62.646408] mmc0: sdhci: Blk size:  0x00000200 | Blk cnt:  0x00000000
[   62.652841] mmc0: sdhci: Argument:  0x00000282 | Trn mode: 0x00000033
[   62.659274] mmc0: sdhci: Present:   0x01ff00f0 | Host ctl: 0x0000003c
[   62.665708] mmc0: sdhci: Power:     0x0000000b | Blk gap:  0x00000080
[   62.672145] mmc0: sdhci: Wake-up:   0x00000000 | Clock:    0x00000007
[   62.678579] mmc0: sdhci: Timeout:   0x0000000e | Int stat: 0x00000000
[   62.685013] mmc0: sdhci: Int enab:  0x02ff4000 | Sig enab: 0x02ff4000
[   62.691446] mmc0: sdhci: ACmd stat: 0x00000000 | Slot int: 0x00000000
[   62.697879] mmc0: sdhci: Caps:      0x7cecc801 | Caps_1:   0x98002407
[   62.704312] mmc0: sdhci: Cmd:       0x00002d1a | Max curr: 0x00000000
[   62.710745] mmc0: sdhci: Resp[0]:   0x00000900 | Resp[1]:  0xfff6dbff
[   62.717179] mmc0: sdhci: Resp[2]:   0x329f5903 | Resp[3]:  0x00d02701
[   62.723612] mmc0: sdhci: Host ctl2: 0x0000000b
[   62.728050] mmc0: sdhci: ADMA Err:  0x00000000 | ADMA Ptr: 0x000000009bf81818
[   62.735182] mmc0: sdhci: ============================================
[   62.741642] mmc0: running CQE recovery

No.

For tunning, 

[ 1.639062] sdhci-am654 fa10000.mmc: For itapdly=31:PASS
[ 1.644379] sdhci-am654 fa10000.mmc: final tunning point 0

is the last one.

For boot,

[    1.756114] Run /sbin/init as init process

this is the last.

I cannot get linux login prompt.

Hi Wangbin,

just for testing, before the patch line 18

+       dev_info(mmc_dev(host->mmc), "final tunning point %d\n", itap);

please add

        itap = 6;

to force the itap to 6 and see if the mmc0 timeout still happens.

after itap setting to 6,  there's still timeout.

=> boot
switch to partitions #0, OK
mmc0(part 0) is current device
SD/MMC found on device 0
Can't set block device
ERROR: reserving fdt memory region failed (addr=9e800000 size=1800000 flags=4)
20118016 bytes read in 180 ms (106.6 MiB/s)
ERROR: reserving fdt memory region failed (addr=9e800000 size=1800000 flags=4)
60738 bytes read in 46 ms (1.3 MiB/s)
Working FDT set to 88000000
## Flattened Device Tree blob at 88000000
   Booting using the fdt blob at 0x88000000
Working FDT set to 88000000
ERROR: reserving fdt memory region failed (addr=9e800000 size=1800000 flags=4)
   Loading Device Tree to 000000008feee000, end 000000008fffffff ... OK
Working FDT set to 8feee000

Starting kernel ...

[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034]
[    0.000000] Linux version 6.6.32-g6de6e418c80e-dirty (xxx@xxx-xxx-N000) (aarch64-oe-linux-gcc (GCC) 13.3.0, GNU ld (GNU Binutils) 2.42.0.20240716) #1 SMP PREEMPT Tue Nov  5 23:21:58 CST 2024
[    0.000000] KASLR disabled due to lack of seed
[    0.000000] Machine model: Texas Instruments AM642 EVM
[    0.000000] earlycon: ns16550a0 at MMIO32 0x0000000002800000 (options '')
[    0.000000] printk: bootconsole [ns16550a0] enabled
[    0.000000] efi: UEFI not found.
[    0.000000] Reserved memory: created DMA memory pool at 0x0000000090000000, size 1 MiB
[    0.000000] OF: reserved mem: initialized node r5f-dma-memory@90000000, compatible id shared-dma-pool
[    0.000000] OF: reserved mem: 0x0000000090000000..0x00000000900fffff (1024 KiB) nomap non-reusable r5f-dma-memory@90000000
[    0.000000] Reserved memory: created DMA memory pool at 0x0000000090100000, size 15 MiB
[    0.000000] OF: reserved mem: initialized node r5f-memory@90100000, compatible id shared-dma-pool
[    0.000000] OF: reserved mem: 0x0000000090100000..0x0000000090ffffff (15360 KiB) nomap non-reusable r5f-memory@90100000
[    0.000000] Reserved memory: created DMA memory pool at 0x0000000091000000, size 1 MiB
[    0.000000] OF: reserved mem: initialized node r5f-dma-memory@91000000, compatible id shared-dma-pool
[    0.000000] OF: reserved mem: 0x0000000091000000..0x00000000910fffff (1024 KiB) nomap non-reusable r5f-dma-memory@91000000
[    0.000000] Reserved memory: created DMA memory pool at 0x0000000091100000, size 15 MiB
[    0.000000] OF: reserved mem: initialized node r5f-memory@91100000, compatible id shared-dma-pool
[    0.000000] OF: reserved mem: 0x0000000091100000..0x0000000091ffffff (15360 KiB) nomap non-reusable r5f-memory@91100000
[    0.000000] Reserved memory: created DMA memory pool at 0x0000000092000000, size 1 MiB
[    0.000000] OF: reserved mem: initialized node r5f-dma-memory@92000000, compatible id shared-dma-pool
[    0.000000] OF: reserved mem: 0x0000000092000000..0x00000000920fffff (1024 KiB) nomap non-reusable r5f-dma-memory@92000000
[    0.000000] Reserved memory: created DMA memory pool at 0x0000000092100000, size 15 MiB
[    0.000000] OF: reserved mem: initialized node r5f-memory@92100000, compatible id shared-dma-pool
[    0.000000] OF: reserved mem: 0x0000000092100000..0x0000000092ffffff (15360 KiB) nomap non-reusable r5f-memory@92100000
[    0.000000] Reserved memory: created DMA memory pool at 0x0000000093000000, size 1 MiB
[    0.000000] OF: reserved mem: initialized node r5f-dma-memory@93000000, compatible id shared-dma-pool
[    0.000000] OF: reserved mem: 0x0000000093000000..0x00000000930fffff (1024 KiB) nomap non-reusable r5f-dma-memory@93000000
[    0.000000] Reserved memory: created DMA memory pool at 0x0000000093100000, size 15 MiB
[    0.000000] OF: reserved mem: initialized node r5f-memory@93100000, compatible id shared-dma-pool
[    0.000000] OF: reserved mem: 0x0000000093100000..0x0000000093ffffff (15360 KiB) nomap non-reusable r5f-memory@93100000
[    0.000000] Reserved memory: created DMA memory pool at 0x0000000094000000, size 1 MiB
[    0.000000] OF: reserved mem: initialized node m4f-dma-memory@94000000, compatible id shared-dma-pool
[    0.000000] OF: reserved mem: 0x0000000094000000..0x00000000940fffff (1024 KiB) nomap non-reusable m4f-dma-memory@94000000
[    0.000000] Reserved memory: created DMA memory pool at 0x0000000094100000, size 15 MiB
[    0.000000] OF: reserved mem: initialized node m4f-memory@94100000, compatible id shared-dma-pool
[    0.000000] OF: reserved mem: 0x0000000094100000..0x0000000094ffffff (15360 KiB) nomap non-reusable m4f-memory@94100000
[    0.000000] OF: reserved mem: 0x0000000095000000..0x00000000957fffff (8192 KiB) nomap non-reusable ipc-memories@95000000
[    0.000000] OF: reserved mem: 0x000000009e800000..0x000000009fffffff (24576 KiB) nomap non-reusable optee@9e800000
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000080000000-0x000000009fffffff]
[    0.000000]   DMA32    empty
[    0.000000]   Normal   empty
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000080000000-0x000000008fffffff]
[    0.000000]   node   0: [mem 0x0000000090000000-0x00000000957fffff]
[    0.000000]   node   0: [mem 0x0000000095800000-0x000000009e7fffff]
[    0.000000]   node   0: [mem 0x000000009e800000-0x000000009fffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000080000000-0x000000009fffffff]
[    0.000000] cma: Reserved 32 MiB at 0x000000009be00000 on node -1
[    0.000000] psci: probing for conduit method from DT.
[    0.000000] psci: PSCIv1.1 detected in firmware.
[    0.000000] psci: Using standard PSCI v0.2 function IDs
[    0.000000] psci: Trusted OS migration not required
[    0.000000] psci: SMC Calling Convention v1.4
[    0.000000] percpu: Embedded 20 pages/cpu s42920 r8192 d30808 u81920
[    0.000000] Detected VIPT I-cache on CPU0
[    0.000000] CPU features: detected: GIC system register CPU interface
[    0.000000] CPU features: detected: ARM erratum 845719
[    0.000000] alternatives: applying boot alternatives
[    0.000000] Kernel command line: console=ttyS2,115200n8 earlycon=ns16550a,mmio32,0x02800000 omap2-nand.0:2m(NAND.tiboot3),2m(NAND.tispl),2m(NAND.tiboot3.backup),4m(NAND.u-boot),256k(NAND.u-boot-env),256k(NAND.u-boot-env.backup),-(NAND.file-system) root=PARTUUID=e14aea3b-1f60-447e-b0df-37a749a387e9 rw rootfstype=ext4 rootwait
[    0.000000] Dentry cache hash table entries: 65536 (order: 7, 524288 bytes, linear)
[    0.000000] Inode-cache hash table entries: 32768 (order: 6, 262144 bytes, linear)
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 129024
[    0.000000] mem auto-init: stack:all(zero), heap alloc:off, heap free:off
[    0.000000] software IO TLB: area num 2.
[    0.000000] software IO TLB: mapped [mem 0x0000000097dc0000-0x000000009bdc0000] (64MB)
[    0.000000] Memory: 280036K/524288K available (11904K kernel code, 1214K rwdata, 3996K rodata, 2368K init, 502K bss, 211484K reserved, 32768K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[    0.000000] rcu: Preemptible hierarchical RCU implementation.
[    0.000000] rcu:     RCU event tracing is enabled.
[    0.000000] rcu:     RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=2.
[    0.000000]  Trampoline variant of Tasks RCU enabled.
[    0.000000]  Tracing variant of Tasks RCU enabled.
[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2
[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
[    0.000000] GICv3: GIC: Using split EOI/Deactivate mode
[    0.000000] GICv3: 256 SPIs implemented
[    0.000000] GICv3: 0 Extended SPIs implemented
[    0.000000] Root IRQ handler: gic_handle_irq
[    0.000000] GICv3: GICv3 features: 16 PPIs
[    0.000000] GICv3: CPU0: found redistributor 0 region 0:0x0000000001840000
[    0.000000] ITS [mem 0x01820000-0x0182ffff]
[    0.000000] GIC: enabling workaround for ITS: Socionext Synquacer pre-ITS
[    0.000000] ITS@0x0000000001820000: Devices Table too large, reduce ids 20->19
[    0.000000] ITS@0x0000000001820000: allocated 524288 Devices @80800000 (flat, esz 8, psz 64K, shr 0)
[    0.000000] ITS: using cache flushing for cmd queue
[    0.000000] GICv3: using LPI property table @0x0000000080040000
[    0.000000] GIC: using cache flushing for LPI property table
[    0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000080050000
[    0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.
[    0.000000] arch_timer: cp15 timer(s) running at 200.00MHz (phys).
[    0.000000] clocksource: arch_sys_counter: mask: 0x3ffffffffffffff max_cycles: 0x2e2049d3e8, max_idle_ns: 440795210634 ns
[    0.000000] sched_clock: 58 bits at 200MHz, resolution 5ns, wraps every 4398046511102ns
[    0.008640] Console: colour dummy device 80x25
[    0.013244] Calibrating delay loop (skipped), value calculated using timer frequency.. 400.00 BogoMIPS (lpj=800000)
[    0.023926] pid_max: default: 32768 minimum: 301
[    0.028740] LSM: initializing lsm=capability,integrity
[    0.034117] Mount-cache hash table entries: 1024 (order: 1, 8192 bytes, linear)
[    0.041598] Mountpoint-cache hash table entries: 1024 (order: 1, 8192 bytes, linear)
[    0.051584] RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1.
[    0.058937] RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1.
[    0.066922] rcu: Hierarchical SRCU implementation.
[    0.071831] rcu:     Max phase no-delay instances is 1000.
[    0.077488] Platform MSI: msi-controller@1820000 domain created
[    0.083858] PCI/MSI: /bus@f4000/interrupt-controller@1800000/msi-controller@1820000 domain created
[    0.093385] EFI services will not be available.
[    0.098354] smp: Bringing up secondary CPUs ...
I/TC: Secondary CPU 1 initializing
I/TC: Secondary CPU 1 switching to normal world boot
[    0.112027] Detected VIPT I-cache on CPU1
[    0.112134] GICv3: CPU1: found redistributor 1 region 0:0x0000000001860000
[    0.112153] GICv3: CPU1: using allocated LPI pending table @0x0000000080060000
[    0.112214] CPU1: Booted secondary processor 0x0000000001 [0x410fd034]
[    0.112359] smp: Brought up 1 node, 2 CPUs
[    0.141747] SMP: Total of 2 processors activated.
[    0.146558] CPU features: detected: 32-bit EL0 Support
[    0.151828] CPU features: detected: CRC32 instructions
[    0.157150] CPU: All CPU(s) started at EL2
[    0.161337] alternatives: applying system-wide alternatives
[    0.168525] devtmpfs: initialized
[    0.181525] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.191539] futex hash table entries: 512 (order: 3, 32768 bytes, linear)
[    0.199913] pinctrl core: initialized pinctrl subsystem
[    0.205901] DMI not present or invalid.
[    0.210642] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[    0.217727] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations
[    0.225127] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
[    0.233182] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
[    0.241384] audit: initializing netlink subsys (disabled)
[    0.247201] audit: type=2000 audit(0.156:1): state=initialized audit_enabled=0 res=1
[    0.247769] thermal_sys: Registered thermal governor 'step_wise'
[    0.255149] thermal_sys: Registered thermal governor 'power_allocator'
[    0.261556] cpuidle: using governor menu
[    0.272494] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
[    0.279504] ASID allocator initialised with 65536 entries
[    0.296853] platform a40000.pinctrl: Fixed dependency cycle(s) with /bus@f4000/pinctrl@a40000/cpsw-cpts-pps
[    0.310813] Modules: 27712 pages in range for non-PLT usage
[    0.310835] Modules: 519232 pages in range for PLT usage
[    0.317603] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages
[    0.329989] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page
[    0.336395] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages
[    0.343330] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page
[    0.349734] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages
[    0.356669] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page
[    0.363073] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages
[    0.370012] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page
[    0.378305] k3-chipinfo 43000014.chipid: Family:AM64X rev:SR2.0 JTAGID[0x1bb3802f] Detected
[    0.387900] iommu: Default domain type: Translated
[    0.392836] iommu: DMA domain TLB invalidation policy: strict mode
[    0.399563] SCSI subsystem initialized
[    0.403927] usbcore: registered new interface driver usbfs
[    0.409576] usbcore: registered new interface driver hub
[    0.415048] usbcore: registered new device driver usb
[    0.420761] pps_core: LinuxPPS API ver. 1 registered
[    0.425843] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.435188] PTP clock support registered
[    0.439386] EDAC MC: Ver: 3.0.0
[    0.443469] scmi_core: SCMI protocol bus registered
[    0.448970] FPGA manager framework
[    0.452607] Advanced Linux Sound Architecture Driver Initialized.
[    0.460024] vgaarb: loaded
[    0.463309] clocksource: Switched to clocksource arch_sys_counter
[    0.469865] VFS: Disk quotas dquot_6.6.0
[    0.473916] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    0.488788] NET: Registered PF_INET protocol family
[    0.494019] IP idents hash table entries: 8192 (order: 4, 65536 bytes, linear)
[    0.502466] tcp_listen_portaddr_hash hash table entries: 256 (order: 0, 4096 bytes, linear)
[    0.511063] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
[    0.518994] TCP established hash table entries: 4096 (order: 3, 32768 bytes, linear)
[    0.526955] TCP bind hash table entries: 4096 (order: 5, 131072 bytes, linear)
[    0.534470] TCP: Hash tables configured (established 4096 bind 4096)
[    0.541097] UDP hash table entries: 256 (order: 1, 8192 bytes, linear)
[    0.547797] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes, linear)
[    0.555084] NET: Registered PF_UNIX/PF_LOCAL protocol family
[    0.561525] RPC: Registered named UNIX socket transport module.
[    0.567632] RPC: Registered udp transport module.
[    0.572443] RPC: Registered tcp transport module.
[    0.577250] RPC: Registered tcp-with-tls transport module.
[    0.582856] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.589457] NET: Registered PF_XDP protocol family
[    0.594378] PCI: CLS 0 bytes, default 64
[    0.599981] Initialise system trusted keyrings
[    0.604932] workingset: timestamp_bits=46 max_order=17 bucket_order=0
[    0.611971] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.618389] NFS: Registering the id_resolver key type
[    0.623626] Key type id_resolver registered
[    0.627905] Key type id_legacy registered
[    0.632026] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    0.638879] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
[    0.687043] Key type asymmetric registered
[    0.691245] Asymmetric key parser 'x509' registered
[    0.696304] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 244)
[    0.703869] io scheduler mq-deadline registered
[    0.708513] io scheduler kyber registered
[    0.712660] io scheduler bfq registered
[    0.719760] pinctrl-single 4084000.pinctrl: 33 pins, size 132
[    0.726226] pinctrl-single f4000.pinctrl: 180 pins, size 720
[    0.733717] pinctrl-single a40000.pinctrl: 512 pins, size 2048
[    0.747093] Serial: 8250/16550 driver, 12 ports, IRQ sharing enabled
[    0.766501] loop: module loaded
[    0.771031] megasas: 07.725.01.00-rc1
[    0.777683] tun: Universal TUN/TAP device driver, 1.6
[    0.784384] VFIO - User Level meta-driver version: 0.3
[    0.791016] usbcore: registered new interface driver usb-storage
[    0.797629] i2c_dev: i2c /dev entries driver
[    0.803358] sdhci: Secure Digital Host Controller Interface driver
[    0.809706] sdhci: Copyright(c) Pierre Ossman
[    0.814443] sdhci-pltfm: SDHCI platform and OF driver helper
[    0.821039] ledtrig-cpu: registered to indicate activity on CPUs
[    0.827633] SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping ....
[    0.835099] usbcore: registered new interface driver usbhid
[    0.840809] usbhid: USB HID core driver
[    0.845155] omap-mailbox 29020000.mailbox: omap mailbox rev 0x66fc9100
[    0.852126] omap-mailbox 29040000.mailbox: omap mailbox rev 0x66fc9100
[    0.858994] omap-mailbox 29060000.mailbox: omap mailbox rev 0x66fc9100
[    0.867567] hw perfevents: enabled with armv8_cortex_a53 PMU driver, 7 counters available
[    0.876705] optee: probing for conduit method.
I/TC: Reserved shared memory is enabled
I/TC: Dynamic shared memory is enabled
I/TC: Normal World virtualization support is disabled
I/TC: Asynchronous notifications are disabled
[    0.881290] optee: revision 4.2 (12d7c4ee)
[    0.898005] optee: dynamic shared memory is enabled
[    0.908218] random: crng init done
[    0.911882] optee: initialized driver
[    0.918182] NET: Registered PF_PACKET protocol family
[    0.923506] Key type dns_resolver registered
[    0.937334] registered taskstats version 1
[    0.941729] Loading compiled-in X.509 certificates
[    0.961985] ti-sci 44043000.system-controller: ABI: 4.0 (firmware rev 0x000a '10.0.8--v10.00.08 (Fiery Fox)')
[    1.072998] ti-sci-clk 44043000.system-controller:clock-controller: recalc-rate failed for dev=62, clk=3, ret=-19
[    1.112007] ti-sci-intr bus@f4000:interrupt-controller@a00000: Interrupt Router 3 domain created
[    1.121431] ti-sci-inta 48000000.interrupt-controller: Interrupt Aggregator domain 28 created
[    1.130544] ti-udma 485c0100.dma-controller: Number of rings: 68
[    1.138414] ti-udma 485c0100.dma-controller: Channels: 24 (bchan: 12, tchan: 6, rchan: 6)
[    1.148747] ti-udma 485c0000.dma-controller: Number of rings: 288
[    1.166384] ti-udma 485c0000.dma-controller: Channels: 44 (tchan: 29, rchan: 15)
[    1.177783] printk: console [ttyS2] disabled
[    1.182665] 2800000.serial: ttyS2 at MMIO 0x2800000 (irq = 315, base_baud = 3000000) is a 8250
[    1.191576] printk: console [ttyS2] enabled
[    1.191576] printk: console [ttyS2] enabled
[    1.200132] printk: bootconsole [ns16550a0] disabled
[    1.200132] printk: bootconsole [ns16550a0] disabled
[    1.214267] spi-nor spi0.0: unrecognized JEDEC id bytes: 90 80 2f 90 80 2f
[    1.223155] davinci_mdio 8000f00.mdio: Configuring MDIO in manual mode
[    1.267319] davinci_mdio 8000f00.mdio: davinci mdio revision 9.7, bus freq 1000000
[    1.277637] davinci_mdio 8000f00.mdio: phy[7]: device 8000f00.mdio:07, driver TI DP83826C
[    1.285904] am65-cpsw-nuss 8000000.ethernet: initializing am65 cpsw nuss version 0x6BA00903, cpsw version 0x6BA80903 Ports: 3 quirks:00000006
[    1.298745] am65-cpsw-nuss 8000000.ethernet: initialized cpsw ale version 1.4
[    1.305879] am65-cpsw-nuss 8000000.ethernet: ALE Table size 512
[    1.312616] pps pps0: new PPS source ptp0
[    1.317063] am65-cpsw-nuss 8000000.ethernet: CPTS ver 0x4e8a010c, freq:500000000, add_val:1 pps:1
[    1.334574] am65-cpsw-nuss 8000000.ethernet: set new flow-id-base 16
[    1.346906] am65-cpts 39000000.cpts: CPTS ver 0x4e8a010c, freq:500000000, add_val:1 pps:0
[    1.357180] mmc0: CQHCI version 5.10
[    1.373953] clk: Disabling unused clocks
[    1.383892] ti-sci-clk 44043000.system-controller:clock-controller: is_prepared failed for dev=62, clk=3, ret=-19
[    1.399875] mmc0: SDHCI controller on fa10000.mmc [fa10000.mmc] using ADMA 64-bit
[    1.422553] ALSA device list:
[    1.425625]   No soundcards found.
[    1.429438] Waiting for root device PARTUUID=e14aea3b-1f60-447e-b0df-37a749a387e9...
[    1.473549] sdhci-am654 fa10000.mmc: For itapdly=0:PASS
[    1.478837] sdhci-am654 fa10000.mmc: For itapdly=1:PASS
[    1.484112] sdhci-am654 fa10000.mmc: For itapdly=2:PASS
[    1.489388] sdhci-am654 fa10000.mmc: For itapdly=3:PASS
[    1.494663] sdhci-am654 fa10000.mmc: For itapdly=4:PASS
[    1.499936] sdhci-am654 fa10000.mmc: For itapdly=5:PASS
[    1.505214] sdhci-am654 fa10000.mmc: For itapdly=6:PASS
[    1.510492] sdhci-am654 fa10000.mmc: For itapdly=7:PASS
[    1.515767] sdhci-am654 fa10000.mmc: For itapdly=8:PASS
[    1.521042] sdhci-am654 fa10000.mmc: For itapdly=9:PASS
[    1.526317] sdhci-am654 fa10000.mmc: For itapdly=10:PASS
[    1.531681] sdhci-am654 fa10000.mmc: For itapdly=11:PASS
[    1.537046] sdhci-am654 fa10000.mmc: For itapdly=12:PASS
[    1.542413] sdhci-am654 fa10000.mmc: For itapdly=13:PASS
[    1.547779] sdhci-am654 fa10000.mmc: For itapdly=14:PASS
[    1.553213] sdhci-am654 fa10000.mmc: For itapdly=15:FAIL
[    1.558641] sdhci-am654 fa10000.mmc: For itapdly=16:FAIL
[    1.564048] sdhci-am654 fa10000.mmc: For itapdly=17:FAIL
[    1.569456] sdhci-am654 fa10000.mmc: For itapdly=18:FAIL
[    1.574823] sdhci-am654 fa10000.mmc: For itapdly=19:PASS
[    1.580189] sdhci-am654 fa10000.mmc: For itapdly=20:PASS
[    1.585555] sdhci-am654 fa10000.mmc: For itapdly=21:PASS
[    1.590921] sdhci-am654 fa10000.mmc: For itapdly=22:PASS
[    1.596285] sdhci-am654 fa10000.mmc: For itapdly=23:PASS
[    1.601649] sdhci-am654 fa10000.mmc: For itapdly=24:PASS
[    1.607015] sdhci-am654 fa10000.mmc: For itapdly=25:PASS
[    1.612381] sdhci-am654 fa10000.mmc: For itapdly=26:PASS
[    1.617748] sdhci-am654 fa10000.mmc: For itapdly=27:PASS
[    1.623114] sdhci-am654 fa10000.mmc: For itapdly=28:PASS
[    1.628481] sdhci-am654 fa10000.mmc: For itapdly=29:PASS
[    1.633846] sdhci-am654 fa10000.mmc: For itapdly=30:PASS
[    1.639212] sdhci-am654 fa10000.mmc: For itapdly=31:PASS
[    1.644524] sdhci-am654 fa10000.mmc: final tunning point 6
[    1.650540] mmc0: Command Queue Engine enabled
[    1.655032] mmc0: new HS200 MMC card at address 0001
[    1.660828] mmcblk0: mmc0:0001 M04A11 3.64 GiB
[    1.670092]  mmcblk0: p1
[    1.673466] mmcblk0boot0: mmc0:0001 M04A11 4.00 MiB
[    1.679801] mmcblk0boot1: mmc0:0001 M04A11 4.00 MiB
[    1.685950] mmcblk0rpmb: mmc0:0001 M04A11 4.00 MiB, chardev (239:0)
[    1.702856] EXT4-fs (mmcblk0p1): mounted filesystem bef90e4d-4ff1-4f75-a999-38b6040d4747 r/w with ordered data mode. Quota mode: none.
[    1.715058] VFS: Mounted root (ext4 filesystem) on device 179:1.
[    1.723910] devtmpfs: mounted
[    1.728590] Freeing unused kernel memory: 2368K
[    1.733254] Run /sbin/init as init process
[   31.835338] vtt: disabling
[   62.555374] mmc0: cqhci: timeout for tag 13, qcnt 4
[   62.560278] mmc0: cqhci: ============ CQHCI REGISTER DUMP ===========
[   62.566713] mmc0: cqhci: Caps:      0x000030c8 | Version:  0x00000510
[   62.573148] mmc0: cqhci: Config:    0x00000101 | Control:  0x00000000
[   62.579581] mmc0: cqhci: Int stat:  0x00000000 | Int enab: 0x00000016
[   62.586015] mmc0: cqhci: Int sig:   0x00000016 | Int Coal: 0x00000000
[   62.592448] mmc0: cqhci: TDL base:  0x813fa000 | TDL up32: 0x00000000
[   62.598882] mmc0: cqhci: Doorbell:  0x0000e080 | TCN:      0x00000000
[   62.605315] mmc0: cqhci: Dev queue: 0x00000800 | Dev Pend: 0x0000e080
[   62.611749] mmc0: cqhci: Task clr:  0x00000000 | SSC1:     0x00011000
[   62.618188] mmc0: cqhci: SSC2:      0x00000001 | DCMD rsp: 0x00000000
[   62.624622] mmc0: cqhci: RED mask:  0xfdf9a080 | TERRI:    0x00000000
[   62.631056] mmc0: cqhci: Resp idx:  0x0000002d | Resp arg: 0x00000900
[   62.637490] mmc0: sdhci: ============ SDHCI REGISTER DUMP ===========
[   62.643922] mmc0: sdhci: Sys addr:  0x00000090 | Version:  0x00001004
[   62.650355] mmc0: sdhci: Blk size:  0x00000200 | Blk cnt:  0x00000000
[   62.656789] mmc0: sdhci: Argument:  0x00000282 | Trn mode: 0x00000033
[   62.663222] mmc0: sdhci: Present:   0x01ff00f0 | Host ctl: 0x0000003c
[   62.669656] mmc0: sdhci: Power:     0x0000000b | Blk gap:  0x00000080
[   62.676093] mmc0: sdhci: Wake-up:   0x00000000 | Clock:    0x00000007
[   62.682527] mmc0: sdhci: Timeout:   0x0000000e | Int stat: 0x00000000
[   62.688960] mmc0: sdhci: Int enab:  0x02ff4000 | Sig enab: 0x02ff4000
[   62.695394] mmc0: sdhci: ACmd stat: 0x00000000 | Slot int: 0x00000000
[   62.701827] mmc0: sdhci: Caps:      0x7cecc801 | Caps_1:   0x98002407
[   62.708260] mmc0: sdhci: Cmd:       0x00002d1a | Max curr: 0x00000000
[   62.714693] mmc0: sdhci: Resp[0]:   0x00000900 | Resp[1]:  0xfff6dbff
[   62.721127] mmc0: sdhci: Resp[2]:   0x329f5903 | Resp[3]:  0x00d02701
[   62.727560] mmc0: sdhci: Host ctl2: 0x0000000b
[   62.732000] mmc0: sdhci: ADMA Err:  0x00000000 | ADMA Ptr: 0x000000009bf84818
[   62.739132] mmc0: sdhci: ============================================
[   62.745589] mmc0: running CQE recovery
[  123.995379] mmc0: cqhci: timeout for tag 13, qcnt 2
[  124.000308] mmc0: cqhci: ============ CQHCI REGISTER DUMP ===========
[  124.006743] mmc0: cqhci: Caps:      0x000030c8 | Version:  0x00000510
[  124.013178] mmc0: cqhci: Config:    0x00000101 | Control:  0x00000000
[  124.019611] mmc0: cqhci: Int stat:  0x00000000 | Int enab: 0x00000016
[  124.026044] mmc0: cqhci: Int sig:   0x00000016 | Int Coal: 0x00000000
[  124.032478] mmc0: cqhci: TDL base:  0x813fa000 | TDL up32: 0x00000000
[  124.038911] mmc0: cqhci: Doorbell:  0x00006000 | TCN:      0x00000000
[  124.045344] mmc0: cqhci: Dev queue: 0x00000800 | Dev Pend: 0x00006000
[  124.051778] mmc0: cqhci: Task clr:  0x00000000 | SSC1:     0x00011000
[  124.058217] mmc0: cqhci: SSC2:      0x00000001 | DCMD rsp: 0x00000000
[  124.064651] mmc0: cqhci: RED mask:  0xfdf9a080 | TERRI:    0x00000000
[  124.071085] mmc0: cqhci: Resp idx:  0x0000002e | Resp arg: 0x00000900
[  124.077518] mmc0: sdhci: ============ SDHCI REGISTER DUMP ===========
[  124.083950] mmc0: sdhci: Sys addr:  0x000000b0 | Version:  0x00001004
[  124.090383] mmc0: sdhci: Blk size:  0x00000200 | Blk cnt:  0x00000000
[  124.096817] mmc0: sdhci: Argument:  0x000c0000 | Trn mode: 0x00000033
[  124.103250] mmc0: sdhci: Present:   0x01ff00f0 | Host ctl: 0x0000003c
[  124.109684] mmc0: sdhci: Power:     0x0000000b | Blk gap:  0x00000080
[  124.116121] mmc0: sdhci: Wake-up:   0x00000000 | Clock:    0x00000007
[  124.122555] mmc0: sdhci: Timeout:   0x0000000e | Int stat: 0x00000000
[  124.128988] mmc0: sdhci: Int enab:  0x02ff4000 | Sig enab: 0x02ff4000
[  124.135422] mmc0: sdhci: ACmd stat: 0x00000000 | Slot int: 0x00000000
[  124.141855] mmc0: sdhci: Caps:      0x7cecc801 | Caps_1:   0x98002407
[  124.148289] mmc0: sdhci: Cmd:       0x00002e3a | Max curr: 0x00000000
[  124.154722] mmc0: sdhci: Resp[0]:   0x00000900 | Resp[1]:  0xfff6dbff
[  124.161155] mmc0: sdhci: Resp[2]:   0x329f5903 | Resp[3]:  0x00d02701
[  124.167589] mmc0: sdhci: Host ctl2: 0x0000000b
[  124.172027] mmc0: sdhci: ADMA Err:  0x00000000 | ADMA Ptr: 0x000000009bf84818
[  124.179159] mmc0: sdhci: ============================================
[  124.185605] mmc0: running CQE recovery
[  185.435372] mmc0: cqhci: timeout for tag 15, qcnt 1
[  185.440288] mmc0: cqhci: ============ CQHCI REGISTER DUMP ===========
[  185.446722] mmc0: cqhci: Caps:      0x000030c8 | Version:  0x00000510
[  185.453156] mmc0: cqhci: Config:    0x00000101 | Control:  0x00000000
[  185.459590] mmc0: cqhci: Int stat:  0x00000000 | Int enab: 0x00000016
[  185.466023] mmc0: cqhci: Int sig:   0x00000016 | Int Coal: 0x00000000
[  185.472457] mmc0: cqhci: TDL base:  0x813fa000 | TDL up32: 0x00000000
[  185.478891] mmc0: cqhci: Doorbell:  0x00008000 | TCN:      0x00000000
[  185.485325] mmc0: cqhci: Dev queue: 0x00002000 | Dev Pend: 0x00008000
[  185.491758] mmc0: cqhci: Task clr:  0x00000000 | SSC1:     0x00011000
[  185.498197] mmc0: cqhci: SSC2:      0x00000001 | DCMD rsp: 0x00000000
[  185.504632] mmc0: cqhci: RED mask:  0xfdf9a080 | TERRI:    0x00000000
[  185.511066] mmc0: cqhci: Resp idx:  0x0000000d | Resp arg: 0x00002000
[  185.517499] mmc0: sdhci: ============ SDHCI REGISTER DUMP ===========
[  185.523931] mmc0: sdhci: Sys addr:  0x00000048 | Version:  0x00001004
[  185.530365] mmc0: sdhci: Blk size:  0x00000200 | Blk cnt:  0x00000000
[  185.536798] mmc0: sdhci: Argument:  0x00018000 | Trn mode: 0x00000033
[  185.543231] mmc0: sdhci: Present:   0x01ff00f0 | Host ctl: 0x0000003c
[  185.549665] mmc0: sdhci: Power:     0x0000000b | Blk gap:  0x00000080
[  185.556103] mmc0: sdhci: Wake-up:   0x00000000 | Clock:    0x00000007
[  185.562537] mmc0: sdhci: Timeout:   0x0000000e | Int stat: 0x00000000
[  185.568969] mmc0: sdhci: Int enab:  0x02ff4000 | Sig enab: 0x02ff4000
[  185.575403] mmc0: sdhci: ACmd stat: 0x00000000 | Slot int: 0x00000000
[  185.581836] mmc0: sdhci: Caps:      0x7cecc801 | Caps_1:   0x98002407
[  185.588269] mmc0: sdhci: Cmd:       0x00000d1a | Max curr: 0x00000000
[  185.594702] mmc0: sdhci: Resp[0]:   0x00002000 | Resp[1]:  0xfff6dbff
[  185.601135] mmc0: sdhci: Resp[2]:   0x329f5903 | Resp[3]:  0x00d02701
[  185.607568] mmc0: sdhci: Host ctl2: 0x0000000b
[  185.612007] mmc0: sdhci: ADMA Err:  0x00000000 | ADMA Ptr: 0x000000009bf84e18
[  185.619138] mmc0: sdhci: ============================================
[  185.625589] mmc0: running CQE recovery
[  246.875372] mmc0: cqhci: timeout for tag 8, qcnt 2
[  246.880203] mmc0: cqhci: ============ CQHCI REGISTER DUMP ===========
[  246.886638] mmc0: cqhci: Caps:      0x000030c8 | Version:  0x00000510
[  246.893073] mmc0: cqhci: Config:    0x00000101 | Control:  0x00000000
[  246.899507] mmc0: cqhci: Int stat:  0x00000000 | Int enab: 0x00000016
[  246.905940] mmc0: cqhci: Int sig:   0x00000016 | Int Coal: 0x00000000
[  246.912374] mmc0: cqhci: TDL base:  0x813fa000 | TDL up32: 0x00000000
[  246.918808] mmc0: cqhci: Doorbell:  0x00000300 | TCN:      0x00000000
[  246.925241] mmc0: cqhci: Dev queue: 0x00004000 | Dev Pend: 0x00000300
[  246.931675] mmc0: cqhci: Task clr:  0x00000000 | SSC1:     0x00011000
[  246.938113] mmc0: cqhci: SSC2:      0x00000001 | DCMD rsp: 0x00000000
[  246.944548] mmc0: cqhci: RED mask:  0xfdf9a080 | TERRI:    0x00000000
[  246.950981] mmc0: cqhci: Resp idx:  0x0000002d | Resp arg: 0x00000900
[  246.957415] mmc0: sdhci: ============ SDHCI REGISTER DUMP ===========
[  246.963847] mmc0: sdhci: Sys addr:  0x00000058 | Version:  0x00001004
[  246.970281] mmc0: sdhci: Blk size:  0x00000200 | Blk cnt:  0x00000000
[  246.976714] mmc0: sdhci: Argument:  0x00080022 | Trn mode: 0x00000033
[  246.983147] mmc0: sdhci: Present:   0x01ff00f0 | Host ctl: 0x0000003c
[  246.989581] mmc0: sdhci: Power:     0x0000000b | Blk gap:  0x00000080
[  246.996019] mmc0: sdhci: Wake-up:   0x00000000 | Clock:    0x00000007
[  247.002453] mmc0: sdhci: Timeout:   0x0000000e | Int stat: 0x00000000
[  247.008886] mmc0: sdhci: Int enab:  0x02ff4000 | Sig enab: 0x02ff4000
[  247.015320] mmc0: sdhci: ACmd stat: 0x00000000 | Slot int: 0x00000000
[  247.021753] mmc0: sdhci: Caps:      0x7cecc801 | Caps_1:   0x98002407
[  247.028187] mmc0: sdhci: Cmd:       0x00002d1a | Max curr: 0x00000000
[  247.034620] mmc0: sdhci: Resp[0]:   0x00000900 | Resp[1]:  0xfff6dbff
[  247.041055] mmc0: sdhci: Resp[2]:   0x329f5903 | Resp[3]:  0x00d02701
[  247.047488] mmc0: sdhci: Host ctl2: 0x0000000b
[  247.051928] mmc0: sdhci: ADMA Err:  0x00000000 | ADMA Ptr: 0x000000009bf85a0c
[  247.059060] mmc0: sdhci: ============================================
[  247.065513] mmc0: running CQE recovery

Hi Bin & Kevin,

I refer to anoter post to close the cqhci feature.

https://e2e.ti.com/support/processors-group/processors/f/processors-forum/1344972/am623-issues-with-sandisk-emmc-entering-hs200-mode

diff --git a/drivers/mmc/host/sdhci_am654.c b/drivers/mmc/host/sdhci_am654.c
index 38392b6f4100..84cf23d7ae9f 100644
--- a/drivers/mmc/host/sdhci_am654.c
+++ b/drivers/mmc/host/sdhci_am654.c
@@ -668,6 +668,8 @@ static int sdhci_am654_cqe_add_host(struct sdhci_host *host)
 {
        struct cqhci_host *cq_host;
 
+       return 0;
+
        cq_host = devm_kzalloc(mmc_dev(host->mmc), sizeof(struct cqhci_host),
                               GFP_KERNEL);
        if (!cq_host)

It works! And i am able to get linux login prompt.

So it seems the emmc not comptible or the tunning is not good.

Does it affect performance for closing this feature?