PROCESSOR-SDK-AM62X: AM625 eMMC stablity issue

Hi Cherry,

You are seeing the MMC errors while booted from SD card, right?

Please boot the board, stop at the U-Boot prompt, and run the following U-Boot commands to boot linux, then check if the MMC errors still happen.

=> setenv boot_fit 0
=> run args_all args_mmc get_kern_mmc findfdt get_fdt_mmc                         
=> fdt addr $fdtaddr; fdt resize
=> fdt set /bus@f0000/mmc@fa00000/ no-1-8-v
=> run run_kern

Hi Bin,

Thanks for your support and please let me clarify on the issue:

Bin Liu said:

You are seeing the MMC errors while booted from SD card, right?

Not an error seen when booting from the SD card, but an error is reported when writing data to EMMC after a normal boot. The command to write the data can either use tar to extract the file to EMMC or CP to copy the file from the SD card to EMMC. However, this problem occurs whenever a large number of files are written (EMMC can be mounted normally before writing).

***************************************************************
***************************************************************
[  OK  ] Started Print notice about GPLv3 packages.
[  OK  ] Started weston.service.
         Starting telnetd.service...
[  OK  ] Started telnetd.service.
         Starting hmi_demo.service...
         Starting LSB: Expand Rootfs of boot device...
[  OK  ] Started LSB: Expand Rootfs of boot [   14.641465] PVR_K:  1159: RGX Firmware image 'rgx.fw.33.15.11.3' loaded
device.
[   14.673764] PVR_K:  1159: Shader binary image 'rgx.sh.33.15.11.3' loaded
[  OK  ] Started hmi_demo.service.
         Starting start_camera_demo.service...
         Stopping hmi_demo.service...
[  OK  ] Stopped hmi_demo.service.

 _____                    _____           _         _
|  _  |___ ___ ___ ___   |  _  |___ ___  |_|___ ___| |_
|     |  _| .'| . | . |  |   __|  _| . | | | -_|  _|  _|
|__|__|_| |__,|_  |___|  |__|  |_| |___|_| |___|___|_|
              |___|                    |___|

Arago Project am62xx-evm ttyS2

Arago 2021.09 am62xx-evm ttyS2

am62xx-evm login: root
root@am62xx-evm:~# cd /
root@am62xx-evm:/# mount -t ext4 /dev/mmcblk0p1 /mnt/temp/
root@am62xx-evm:/# df -Th
Filesystem     Type      Size  Used Avail Use% Mounted on
/dev/root      ext4       14G  5.7G  7.7G  43% /
devtmpfs       devtmpfs  712M  4.0K  712M   1% /dev
tmpfs          tmpfs     969M     0  969M   0% /dev/shm
tmpfs          tmpfs     969M  9.1M  960M   1% /run
tmpfs          tmpfs     969M     0  969M   0% /sys/fs/cgroup
tmpfs          tmpfs     969M     0  969M   0% /tmp
tmpfs          tmpfs      16M     0   16M   0% /media/ram
tmpfs          tmpfs      50M   20K   50M   1% /var/volatile
/dev/mmcblk0p1 ext4       15G  8.0K   14G   1% /mnt/temp
/dev/mmcblk1p1 vfat      127M  2.2M  124M   2% /media/mmcblk1p1
tmpfs          tmpfs     194M     0  194M   0% /run/user/0
root@am62xx-evm:/# cd /mnt/temp/
root@am62xx-evm:/mnt/temp# ls
root@am62xx-evm:/mnt/temp# rm * -rf
root@am62xx-evm:/mnt/temp# ls
root@am62xx-evm:/mnt/temp# ls /
AM62x_rootfs.tar.xz  boot  etc   include  lib      media  opt   run   sys   tisdk-default-image-am62xx-evm.tar.xz  usr  www
bin                  dev   home  init     linuxrc  mnt    proc  sbin  temp  tmp                                    var
root@am62xx-evm:/mnt/temp# cp /lib/ ./ -rf
root@am62xx-evm:/mnt/temp# ls
lib
root@am62xx-evm:/mnt/temp# cp /usr/bin/ ./ -rf
[  118.765674] mmc0: running CQE recovery
[  118.782139] ------------[ cut here ]------------
[  118.782160] mmc0: cqhci: spurious TCN for tag 11
[  118.782235] WARNING: CPU: 0 PID: 168 at drivers/mmc/host/cqhci.c:742 cqhci_irq+0x318/0x4a0
[  118.782260] Modules linked in: iptable_nat xt_MASQUERADE nf_nat nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 libcrc32c ip_tables x_tables wl18xx xhci_plat_hcd xhci_hcd usbcore rpmsg_char wlcore mac80211 cfg80211 rfkill libarc4 dwc3 udc_core usb_common cdns_csi2rx v4l2_fwnode pru_rproc irq_pruss_intc crct10dif_ce snd_soc_simple_card snd_soc_simple_card_utils wlcore_sdio pvrsrvkm(O) dwc3_am62 rti_wdt ti_k3_r5_remoteproc virtio_rpmsg_bus ti_k3_m4_remoteproc j721e_csi2rx sa2ul videobuf2_dma_contig videobuf2_memops sha512_generic tps6598x snd_soc_tlv320aic3x authenc videobuf2_v4l2 videobuf2_common cdns_dphy typec pruss roles optee_rng rng_core sch_fq_codel cryptodev(O) ipv6
[  118.782412] CPU: 0 PID: 168 Comm: irq/33-mmc0 Tainted: G           O      5.10.153-rt76-g29dbc132eb #1
[  118.782420] Hardware name: Texas Instruments AM625 SK (DT)
[  118.782424] pstate: 40000005 (nZcv daif -PAN -UAO -TCO BTYPE=--)
[  118.782431] pc : cqhci_irq+0x318/0x4a0
[  118.782436] lr : cqhci_irq+0x318/0x4a0
[  118.782441] sp : ffff800011bd3c20
[  118.782444] x29: ffff800011bd3c20 x28: ffff000001d98600
[  118.782451] x27: ffff0000011a0680 x26: 0000000000000001
[  118.782457] x25: ffff800010ddbe08 x24: ffff0000011a0698
[  118.782463] x23: ffff80001117561a x22: ffff000000c249c0
[  118.782470] x21: ffff000001d98000 x20: 0000000000000002
[  118.782476] x19: 000000000000000b x18: 0000000000000001
[  118.782482] x17: ffff000001fb2100 x16: 0000000000000000
[  118.782488] x15: ffff000000c24f00 x14: ffffffffffffffff
[  118.782494] x13: ffff80001119cdc6 x12: ffff80001119cdc2
[  118.782501] x11: 0000000000000000 x10: ffff80001108ae40
[  118.782507] x9 : 00000000fffffffe x8 : 000000000000000b
[  118.782513] x7 : 6972757073203a69 x6 : ffff800011bd3a70
[  118.782519] x5 : ffff00007fb99b38 x4 : 0000000000000000
[  118.782525] x3 : 0000000000000027 x2 : 0000000100000000
[  118.782531] x1 : 7e8fcbde58ed4500 x0 : 0000000000000000
[  118.782538] Call trace:
[  118.782542]  cqhci_irq+0x318/0x4a0
[  118.782547]  sdhci_am654_cqhci_irq+0x58/0x88
[  118.782557]  sdhci_irq+0xb0/0xf18
[  118.782563]  irq_forced_thread_fn+0x3c/0xc0
[  118.782574]  irq_thread+0x184/0x258
[  118.782580]  kthread+0x174/0x198
[  118.782589]  ret_from_fork+0x10/0x34
[  118.782599] ---[ end trace 0000000000000002 ]---
[  119.782836] sched: RT throttling activated
[  119.788442] mmc0: running CQE recovery
[  119.829688] mmc0: running CQE recovery
[  119.838296] mmc0: running CQE recovery
[  119.845612] mmc0: running CQE recovery
[  119.846821] mmc0: running CQE recovery
[  119.859962] mmc0: running CQE recovery
[  119.867566] mmc0: running CQE recovery
[  119.880868] mmc0: running CQE recovery
[  119.887942] mmc0: running CQE recovery

For example, using CP to copy /lib from SD card to EMMC is ok, but once CP is used to copy /usr/bin directory from SD card to EMMC, there will be a problem. The only difference between the two is that there are more files in the /usr/bin directory and that both folders are files contained in the default root file system and are not modified. 

Bin Liu said:

Please boot the board, stop at the U-Boot prompt, and run the following U-Boot commands to boot linux, then check if the MMC errors still happen.

Tried the way you mentioned but the issue still persists.

Thanks and Regards,

Cherry

Hi Cherry,

Cherry Zhou said:

Bin Liu said:

Please boot the board, stop at the U-Boot prompt, and run the following U-Boot commands to boot linux, then check if the MMC errors still happen.

Tried the way you mentioned but the issue still persists.

Can you please run the test again and attach the test output along with the full kernel boot log? I need to kernel boot log to map the mmc0 or mmc1 logs to the eMMC or SD card device.

Hi Bin,

Please see the full kernel log below, which boot from uboot and execute the above command to start the kernel (boot from SD card): 

U-Boot SPL 2021.01-g3983bffabc (Dec 14 2022 - 11:52:01 +0000)
SYSFW ABI: 3.1 (firmware rev 0x0008 '8.5.3--v08.05.03 (Chill Capybar')
SPL initial stack usage: 13424 bytes
Trying to boot from MMC2
Warning: Detected image signing certificate on GP device. Skipping certificate to prevent boot failure. This will fail if the image was also encrypted
Warning: Detected image signing certificate on GP device. Skipping certificate to prevent boot failure. This will fail if the image was also encrypted
Loading Environment from MMC... *** Warning - No MMC card found, using default environment

Starting ATF on ARM64 core...

NOTICE:  BL31: v2.7(release):v2.7.0-359-g1309c6c805-dirty
NOTICE:  BL31: Built : 11:46:58, Dec 14 2022
I/TC:
I/TC: OP-TEE version: 3.19.0-15-gd6c5d0037 (gcc version 9.2.1 20191025 (GNU Toolchain for the A-profile Architecture 9.2-2019.12 (arm-9.10))) #1 Wed Dec 14 11:50:43 UTC 2022 aarch64
I/TC: WARNING: This OP-TEE configuration might be insecure!
I/TC: WARNING: Please check https://optee.readthedocs.io/en/latest/architecture/porting_guidelines.html
I/TC: Primary CPU initializing
I/TC: SYSFW ABI: 3.1 (firmware rev 0x0008 '8.5.3--v08.05.03 (Chill Capybar')
I/TC: HUK Initialized
I/TC: Activated SA2UL device
I/TC: Fixing SA2UL firewall owner for GP device
I/TC: Enabled firewalls for SA2UL TRNG device
I/TC: SA2UL TRNG initialized
I/TC: SA2UL Drivers initialized
I/TC: Primary CPU switching to normal world boot

U-Boot SPL 2021.01-g3983bffabc (Dec 14 2022 - 11:51:05 +0000)
SYSFW ABI: 3.1 (firmware rev 0x0008 '8.5.3--v08.05.03 (Chill Capybar')
Trying to boot from MMC2


U-Boot 2021.01-g3983bffabc (Dec 14 2022 - 11:51:05 +0000)

SoC:   AM62X SR1.0 GP
Model: Texas Instruments AM625 SK
EEPROM not available at 0x50, trying to read at 0x51
Board: AM62-SKEVM rev E3
DRAM:  2 GiB
MMC:   mmc@fa10000: 0, mmc@fa00000: 1, mmc@fa20000: 2
Loading Environment from MMC... OK
In:    serial@2800000
Out:   serial@2800000
Err:   serial@2800000
Net:   eth0: ethernet@8000000port@1
Hit any key to stop autoboot:  0
=> setenv boot_fit 0
=> run args_all args_mmc get_kern_mmc findfdt get_fdt_mmc
18391552 bytes read in 201 ms (87.3 MiB/s)
55468 bytes read in 4 ms (13.2 MiB/s)
=> fdt addr $fdtaddr; fdt resize
=> fdt set /bus@f0000/mmc@fa00000/ no-1-8-v
=> run run_kern
## Flattened Device Tree blob at 88000000
   Booting using the fdt blob at 0x88000000
   Loading Device Tree to 000000008ffef000, end 000000008fffffff ... OK

Starting kernel ...

[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034]
[    0.000000] Linux version 5.10.153-rt76-g29dbc132eb (oe-user@oe-host) (aarch64-none-linux-gnu-gcc (GNU Toolchain for the A-profile Architecture 9.2-2019.12 (arm-9.10)) 9.2.1 20191025, GNU ld (GNU Toolchain for the A-profile Architecture 9.2-2019.12 (arm-9.10)) 2.33.1.20191209) #1 SMP PREEMPT_RT Wed Dec 14 11:54:09 UTC 2022
[    0.000000] Machine model: Texas Instruments AM625 SK
[    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 0x000000009c800000, size 3 MiB
[    0.000000] OF: reserved mem: initialized node ipc-memories@9c800000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x000000009cb00000, size 1 MiB
[    0.000000] OF: reserved mem: initialized node m4f-dma-memory@9cb00000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x000000009cc00000, size 14 MiB
[    0.000000] OF: reserved mem: initialized node m4f-memory@9cc00000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x000000009da00000, size 1 MiB
[    0.000000] OF: reserved mem: initialized node r5f-dma-memory@9da00000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x000000009db00000, size 12 MiB
[    0.000000] OF: reserved mem: initialized node r5f-memory@9db00000, compatible id shared-dma-pool
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000080000000-0x00000000ffffffff]
[    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-0x000000009c7fffff]
[    0.000000]   node   0: [mem 0x000000009c800000-0x000000009e6fffff]
[    0.000000]   node   0: [mem 0x000000009e700000-0x000000009e77ffff]
[    0.000000]   node   0: [mem 0x000000009e780000-0x000000009fffffff]
[    0.000000]   node   0: [mem 0x00000000a0000000-0x00000000ffffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000080000000-0x00000000ffffffff]
[    0.000000] On node 0 totalpages: 524288
[    0.000000]   DMA zone: 8192 pages used for memmap
[    0.000000]   DMA zone: 0 pages reserved
[    0.000000]   DMA zone: 524288 pages, LIFO batch:63
[    0.000000] cma: Reserved 512 MiB at 0x00000000dd000000
[    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.2
[    0.000000] percpu: Embedded 18 pages/cpu s35008 r8192 d30528 u73728
[    0.000000] pcpu-alloc: s35008 r8192 d30528 u73728 alloc=18*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
[    0.000000] Detected VIPT I-cache on CPU0
[    0.000000] CPU features: detected: ARM erratum 845719
[    0.000000] CPU features: detected: GIC system register CPU interface
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 516096
[    0.000000] Kernel command line: console=ttyS2,115200n8 earlycon=ns16550a,mmio32,0x02800000 root=PARTUUID=fe22c39c-02 rw rootfstype=ext4 rootwait
[    0.000000] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)
[    0.000000] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
[    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
[    0.000000] Memory: 1456676K/2097152K available (10752K kernel code, 1192K rwdata, 4184K rodata, 1728K init, 437K bss, 116188K reserved, 524288K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, 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=4.
[    0.000000] rcu:     RCU priority boosting: priority 1 delay 500 ms.
[    0.000000] rcu:     RCU_SOFTIRQ processing moved to rcuc kthreads.
[    0.000000]  No expedited grace period (rcu_normal_after_boot).
[    0.000000]  Trampoline 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=4
[    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] GICv3: Distributor has no Range Selector support
[    0.000000] GICv3: 16 PPIs implemented
[    0.000000] GICv3: CPU0: found redistributor 0 region 0:0x0000000001880000
[    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] arch_timer: cp15 timer(s) running at 200.00MHz (phys).
[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x2e2049d3e8, max_idle_ns: 440795210634 ns
[    0.000000] sched_clock: 56 bits at 200MHz, resolution 5ns, wraps every 4398046511102ns
[    0.008530] Console: colour dummy device 80x25
[    0.013128] Calibrating delay loop (skipped), value calculated using timer frequency.. 400.00 BogoMIPS (lpj=800000)
[    0.023808] pid_max: default: 32768 minimum: 301
[    0.028627] LSM: Security Framework initializing
[    0.033411] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear)
[    0.040993] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear)
[    0.050897] rcu: Hierarchical SRCU implementation.
[    0.056336] Platform MSI: msi-controller@1820000 domain created
[    0.062724] PCI/MSI: /bus@f0000/interrupt-controller@1800000/msi-controller@1820000 domain created
[    0.072019] EFI services will not be available.
[    0.076976] smp: Bringing up secondary CPUs ...
I/TC: Secondary CPU 1 initializing
I/TC: Secondary CPU 1 switching to normal world boot
[    0.090413] Detected VIPT I-cache on CPU1
[    0.094547] GICv3: CPU1: found redistributor 1 region 0:0x00000000018a0000
[    0.101585] GICv3: CPU1: using allocated LPI pending table @0x0000000080060000
[    0.109022] CPU1: Booted secondary processor 0x0000000001 [0x410fd034]
I/TC: Secondary CPU 2 initializing
I/TC: Secondary CPU 2 switching to normal world boot
[    0.124574] Detected VIPT I-cache on CPU2
[    0.128699] GICv3: CPU2: found redistributor 2 region 0:0x00000000018c0000
[    0.135735] GICv3: CPU2: using allocated LPI pending table @0x0000000080070000
[    0.143150] CPU2: Booted secondary processor 0x0000000002 [0x410fd034]
I/TC: Secondary CPU 3 initializing
I/TC: Secondary CPU 3 switching to normal world boot
[    0.158634] Detected VIPT I-cache on CPU3
[    0.162754] GICv3: CPU3: found redistributor 3 region 0:0x00000000018e0000
[    0.169787] GICv3: CPU3: using allocated LPI pending table @0x0000000080080000
[    0.177199] CPU3: Booted secondary processor 0x0000000003 [0x410fd034]
[    0.183964] smp: Brought up 1 node, 4 CPUs
[    0.188167] SMP: Total of 4 processors activated.
[    0.192987] CPU features: detected: 32-bit EL0 Support
[    0.198248] CPU features: detected: CRC32 instructions
[    0.210558] CPU: All CPU(s) started at EL2
[    0.214767] alternatives: patching kernel code
[    0.220515] devtmpfs: initialized
[    0.232840] KASLR disabled due to lack of seed
[    0.237595] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.247576] futex hash table entries: 1024 (order: 5, 131072 bytes, linear)
[    0.269210] pinctrl core: initialized pinctrl subsystem
[    0.275199] DMI not present or invalid.
[    0.279832] NET: Registered protocol family 16
[    0.286405] DMA: preallocated 256 KiB GFP_KERNEL pool for atomic allocations
[    0.293778] DMA: preallocated 256 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
[    0.301879] DMA: preallocated 256 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
[    0.310665] thermal_sys: Registered thermal governor 'step_wise'
[    0.311289] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
[    0.324476] ASID allocator initialised with 65536 entries
[    0.357362] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages
[    0.364271] HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages
[    0.371136] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[    0.378043] HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages
[    0.385929] cryptd: max_cpu_qlen set to 1000
[    0.393446] k3-chipinfo 43000014.chipid: Family:AM62X rev:SR1.0 JTAGID[0x0bb7e02f] Detected
[    0.402611] vcc_5v0: supplied by vmain_pd
[    0.407181] vcc_3v3_sys: supplied by vmain_pd
[    0.412137] vcc_1v8: supplied by vcc_3v3_sys
[    0.417732] iommu: Default domain type: Translated
[    0.423146] SCSI subsystem initialized
[    0.427448] mc: Linux media interface: v0.10
[    0.431861] videodev: Linux video capture interface: v2.00
[    0.437557] pps_core: LinuxPPS API ver. 1 registered
[    0.442631] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.451980] PTP clock support registered
[    0.456018] EDAC MC: Ver: 3.0.0
[    0.459974] omap-mailbox 29000000.mailbox: omap mailbox rev 0x66fc9100
[    0.467184] FPGA manager framework
[    0.470777] Advanced Linux Sound Architecture Driver Initialized.
[    0.477979] clocksource: Switched to clocksource arch_sys_counter
[    0.484464] VFS: Disk quotas dquot_6.6.0
[    0.488536] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    0.501775] NET: Registered protocol family 2
[    0.506551] IP idents hash table entries: 32768 (order: 6, 262144 bytes, linear)
[    0.515347] tcp_listen_portaddr_hash hash table entries: 1024 (order: 4, 65536 bytes, linear)
[    0.524187] TCP established hash table entries: 16384 (order: 5, 131072 bytes, linear)
[    0.532443] TCP bind hash table entries: 16384 (order: 7, 917504 bytes, linear)
[    0.540837] TCP: Hash tables configured (established 16384 bind 16384)
[    0.547950] UDP hash table entries: 1024 (order: 5, 131072 bytes, linear)
[    0.555077] UDP-Lite hash table entries: 1024 (order: 5, 131072 bytes, linear)
[    0.562880] NET: Registered protocol family 1
[    0.567987] RPC: Registered named UNIX socket transport module.
[    0.574085] RPC: Registered udp transport module.
[    0.578894] RPC: Registered tcp transport module.
[    0.583703] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.590297] PCI: CLS 0 bytes, default 64
[    0.595209] hw perfevents: enabled with armv8_cortex_a53 PMU driver, 7 counters available
[    0.607678] Initialise system trusted keyrings
[    0.612522] workingset: timestamp_bits=46 max_order=19 bucket_order=0
[    0.624065] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.630749] NFS: Registering the id_resolver key type
[    0.636000] Key type id_resolver registered
[    0.640279] Key type id_legacy registered
[    0.644490] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    0.651404] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
[    0.659230] 9p: Installing v9fs 9p2000 file system support
[    0.699439] Key type asymmetric registered
[    0.703664] Asymmetric key parser 'x509' registered
[    0.708716] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 243)
[    0.716336] io scheduler mq-deadline registered
[    0.720969] io scheduler kyber registered
[    0.727346] pinctrl-single 4084000.pinctrl: 34 pins, size 136
[    0.733831] pinctrl-single f4000.pinctrl: 171 pins, size 684
[    0.748141] Serial: 8250/16550 driver, 10 ports, IRQ sharing enabled
[    0.769740] brd: module loaded
[    0.782111] loop: module loaded
[    0.786335] megasas: 07.714.04.00-rc1
[    0.794348] tun: Universal TUN/TAP device driver, 1.6
[    0.800193] igbvf: Intel(R) Gigabit Virtual Function Network Driver
[    0.806636] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.
[    0.812757] sky2: driver version 1.30
[    0.817565] VFIO - User Level meta-driver version: 0.3
[    0.824002] i2c /dev entries driver
[    0.828776] sdhci: Secure Digital Host Controller Interface driver
[    0.835116] sdhci: Copyright(c) Pierre Ossman
[    0.839903] sdhci-pltfm: SDHCI platform and OF driver helper
[    0.847140] SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping ....
[    0.855578] optee: probing for conduit method.
[    0.860261] optee: revision 3.19 (d6c5d003)
[    0.860583] optee: dynamic shared memory is enabled
[    0.870288] optee: initialized driver
[    0.876251] NET: Registered protocol family 17
[    0.881060] 9pnet: Installing 9P2000 support
[    0.885622] Key type dns_resolver registered
[    0.890450] printk: bootconsole [ns16550a0]: printing thread started
[    0.890497] Loading compiled-in X.509 certificates
[    0.902591] ti-sci 44043000.system-controller: ti,ctx-memory-region is required for suspend but not provided.
[    0.902610] ti-sci 44043000.system-controller: ti_sci_init_suspend failed, mem suspend will be non-functional.
[    0.902616] ti-sci 44043000.system-controller: ABI: 3.1 (firmware rev 0x0008 '8.5.3--v08.05.03 (Chill Capybar')
[    0.968216] davinci-mcasp 2b10000.mcasp: IRQ common not found
[    0.970886] omap-gpmc 3b000000.memory-controller: GPMC revision 6.0
[    0.970903] gpmc_mem_init: disabling cs 0 mapped at 0x0-0x1000000
[    0.974164] omap_i2c 20000000.i2c: bus 0 rev0.12 at 400 kHz
[ H▒▒0.976281] omap_i2c 20010000.i2c: bus 1 rev0.12 at 100 kHz
[    0.976916] ti-sci-intr 4210000.interrupt-controller: Interrupt Router 5 domain created
[    1.000207] printk: console [ttyS2]: printing thread started
[    0.977087] ti-sci-intr bus@f0000:interrupt-controller@a00000: Interrupt Router 3 domain created
[    1.000224] printk: console [ttyS2] enabled
[    1.000228] printk: bootconsole [ns16550a0] disabled
[    1.023054] printk: bootconsole [ns16550a0]: printing thread stopped
[    1.029046] spi-nor spi0.0: s28hs512t (65536 Kbytes)
[    1.029102] 7 fixed-partitions partitions found on MTD device fc40000.spi.0
[    1.029108] Creating 7 MTD partitions on "fc40000.spi.0":
[    1.029114] 0x000000000000-0x000000080000 : "ospi.tiboot3"
[    1.030651] 0x000000080000-0x000000280000 : "ospi.tispl"
[    1.031915] 0x000000280000-0x000000680000 : "ospi.u-boot"
[    1.033144] 0x000000680000-0x0000006c0000 : "ospi.env"
[    1.034414] 0x0000006c0000-0x000000700000 : "ospi.env.backup"
[    1.035669] 0x000000800000-0x000003fc0000 : "ospi.rootfs"
[    1.036904] 0x000003fc0000-0x000004000000 : "ospi.phypattern"
[    1.045531] davinci_mdio 8000f00.mdio: Configuring MDIO in manual mode
[    1.081995] davinci_mdio 8000f00.mdio: davinci mdio revision 9.7, bus freq 1000000
[    1.084353] davinci_mdio 8000f00.mdio: phy[0]: device 8000f00.mdio:00, driver TI DP83867
[    1.084368] davinci_mdio 8000f00.mdio: phy[1]: device 8000f00.mdio:01, driver TI DP83867
[    1.084481] am65-cpsw-nuss 8000000.ethernet: initializing am65 cpsw nuss version 0x6BA01103, cpsw version 0x6BA81103 Ports: 3 quirks:00000002
[    1.084677] am65-cpsw-nuss 8000000.ethernet: initialized cpsw ale version 1.5
[    1.084683] am65-cpsw-nuss 8000000.ethernet: ALE Table size 512
[    1.085489] am65-cpsw-nuss 8000000.ethernet: CPTS ver 0x4e8a010c, freq:500000000, add_val:1 pps:0
[    1.089308] rtc-ti-k3 2b1f0000.rtc: registered as rtc0
[    1.089358] rtc-ti-k3 2b1f0000.rtc: setting system clock to 1970-01-01T00:04:05 UTC (245)
[    1.192681] mmc0: CQHCI version 5.10
[    1.199389] davinci-mcasp 2b10000.mcasp: IRQ common not found
[    1.214971] pca953x 1-0022: supply vcc not found, using dummy regulator
[    1.215103] pca953x 1-0022: using AI
[    1.233608] sii902x 1-003b: supply iovcc not found, using dummy regulator
[    1.233753] sii902x 1-003b: supply cvcc12 not found, using dummy regulator
[    1.236695] i2c i2c-1: Added multiplexed i2c bus 2
[    1.239218] [drm] Initialized tidss 1.0.0 20180215 for 30200000.dss on minor 0
[    1.239750] tidss 30200000.dss: [drm] Cannot find any crtc or sizes
[    1.242887] vdd_mmc1: supplied by vcc_3v3_sys
    1.244370] mmc0: SDHCI controller on fa10000.mmc [fa10000.mmc] using ADMA 64-bit
[    1.244755] wlan_lten: supplied by vcc_3v3_sys
[    1.246907] debugfs: Directory 'pd:53' with parent 'pm_genpd' already present!
[    1.246964] debugfs: Directory 'pd:52' with parent 'pm_genpd' already present!
[    1.247027] debugfs: Directory 'pd:51' with parent 'pm_genpd' already present!
[    1.247187] wlan_en: supplied by wlan_lten
[    1.247485] debugfs: Directory 'pd:182' with parent 'pm_genpd' already present!
[    1.247533] mmc2: CQHCI version 5.10
[    1.247697] mmc1: CQHCI version 5.10
[    1.261501] ALSA device list:
[    1.261516]   No soundcards found.
[    1.289765] mmc2: SDHCI controller on fa20000.mmc [fa20000.mmc] using ADMA 64-bit
[    1.295463] sdhci-am654 fa20000.mmc: card claims to support voltages below defined range
[    1.299783] mmc1: SDHCI controller on fa00000.mmc [fa00000.mmc] using ADMA 64-bit
[    1.300380] Waiting for root device PARTUUID=fe22c39c-02...
[    1.308469] mmc2: new high speed SDIO card at address 0001
[    1.325461] mmc0: Command Queue Engine enabled
[    1.325482] mmc0: new HS200 MMC card at address 0001
[    1.326257] mmcblk0: mmc0:0001 S0J56X 14.8 GiB
[    1.326490] mmcblk0boot0: mmc0:0001 S0J56X partition 1 31.5 MiB
[    1.326688] mmcblk0boot1: mmc0:0001 S0J56X partition 2 31.5 MiB
[    1.326902] mmcblk0rpmb: mmc0:0001 S0J56X partition 3 4.00 MiB, chardev (237:0)
[    1.328001]  mmcblk0: p1
[    1.335548] mmc1: new high speed SDHC card at address 1234
[    1.336318] mmcblk1: mmc1:1234 SA16G 14.4 GiB
[    1.344573]  mmcblk1: p1 p2
[    1.365922] EXT4-fs (mmcblk1p2): mounted filesystem with ordered data mode. Opts: (null)
[    1.366071] VFS: Mounted root (ext4 filesystem) on device 179:98.
[    1.373411] devtmpfs: mounted
[    1.374743] Freeing unused kernel memory: 1728K
[    1.382076] Run /sbin/init as init process
[    1.740798] systemd[1]: System time before build time, advancing clock.
[    1.836766] NET: Registered protocol family 10
[    1.838230] Segment Routing with IPv6
[    1.877664] systemd[1]: systemd 244.5+ running in system mode. (+PAM -AUDIT -SELINUX +IMA -APPARMOR -SMACK +SYSVINIT +UTMP -LIBCRYPTSETUP -GCRYPT -GNUTLS +ACL +XZ -LZ4 -SECCOMP +BLKID -ELFUTILS +KMOD -IDN2 -IDN -PCRE2 default-hierarchy=hybrid)
[    1.878319] systemd[1]: Detected architecture arm64.

Welcome to Arago 2021.09!

[    1.931364] systemd[1]: Set hostname to <am62xx-evm>.
[    1.945513] random: systemd: uninitialized urandom read (16 bytes read)
[    1.945614] systemd[1]: Initializing machine ID from random generator.
[    2.315324] systemd[1]: /lib/systemd/system/startwlansta.service:7: Unknown key name 'After' in section 'Service', ignoring.
[    2.316455] systemd[1]: /lib/systemd/system/startwlanap.service:7: Unknown key name 'After' in section 'Service', ignoring.
[    2.351046] systemd[1]: /lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly.
[    2.443751] random: systemd: uninitialized urandom read (16 bytes read)
[    2.444108] systemd[1]: system-getty.slice: unit configures an IP firewall, but the local system does not support BPF/cgroup firewalling.
[    2.444124] systemd[1]: (This warning is only shown for the first unit using IP firewalling.)
[    2.448216] systemd[1]: Created slice system-getty.slice.
[  OK  ] Created slice system-getty.slice.
[  OK  ] Created slice system-serial\x2dgett[    2.494240] random: systemd: uninitialized urandom read (16 bytes read)
[    2.496307] systemd[1]: Created slice system-serial\x2dgetty.slice.
y.slice.
[  OK  ] Created slice User and Session Slic[    2.527953] systemd[1]: Created slice User and Session Slice.
e.
[  OK  ] Started Dispatch Password …ts to [    2.550627] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
Console Directory Watch.
[  OK  ] Started Forward Password R…uests [    2.574474] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
to Wall Directory Watch.
[  OK  ] Reached target Paths.
[    2.598389] systemd[1]: Reached target Paths.
[  OK  ] Reached target Remote File Systems systemd[1]: Reached target Remote File Systems.
[0m.
[  OK  ] Reached target Slices.
[    2.630190] systemd[1]: Reached target Slices.
[  OK  ] Reached target Swap.
[    2.642195] systemd[1]: Reached target Swap.
[  OK  ] Listening on RPCbind Server Activat[    2.663414] systemd[1]: Listening on RPCbind Server Activation Socket.
ion Socket.
[  OK  ] Reached target RPC Port Mapper.[    2.686308] systemd[1]: Reached target RPC Port Mapper.

[  OK  ] Listening on Process Core Dump Sock[    2.711754] systemd[1]: Listening on Process Core Dump Socket.
et.
[  OK  ] Listening on initctl Compatibility [    2.734638] systemd[1]: Listening on initctl Compatibility Named Pipe.
Named Pipe.
[    2.780835] systemd[1]: Condition check resulted in Journal Audit Socket being skipped.
[    2.781741] systemd[1]: Listening on Journal Socket (/dev/log).
[  OK  ] Listening on Journal Socket (/dev/log).
[  OK  ] Listening on Journal Socket.
[    2.811011] systemd[1]: Listening on Journal Socket.
[  OK  ] Listening on Network Service Netlin[    2.830125] systemd[1]: Listening on Network Service Netlink Socket.
k Socket.
[  OK  ] Listening on udev Control Socket    2.854889] systemd[1]: Listening on udev Control Socket.
m.
[  OK  ] Listening on udev Kernel Socket[    2.878624] systemd[1]: Listening on udev Kernel Socket.
.
         Mounting Huge Pages File System...
[    2.904087] systemd[1]: Mounting Huge Pages File System...
         Mounting POSIX Message Queue File System..[    2.920935] systemd[1]: Mounting POSIX Message Queue File System...
.
         Mounting Kernel Debug File System...
[    2.947723] systemd[1]: Mounting Kernel Debug File System...
[    2.966827] random: systemd: uninitialized urandom read (16 bytes read)
[    2.972467] systemd[1]: Mounting Temporary Directory (/tmp)...
         Mounting Temporary Directory (/tmp)...
[    2.995084] random: systemd: uninitialized urandom read (16 bytes read)
[    3.000199] systemd[1]: Starting Create list of static device nodes for the current kernel...
         Starting Create list of st…odes for the current kernel...
[    3.034343] random: systemd: uninitialized urandom read (16 bytes read)
[    3.039500] systemd[1]: Starting Start psplash boot splash screen...
         Starting Start psplash boot splash screen...
[    3.073738] systemd[1]: Starting RPC Bind...
         Starting RPC Bind...
[    3.094581] systemd[1]: Condition check resulted in File System Check on Root Device being skipped.
[    3.101349] systemd[1]: Starting Journal Service...
         Starting Journal Service...
         Starting Load Kernel Modules...
[    3.139630] systemd[1]: Starting Load Kernel Modules...
         Starting Remount Root and Kernel File Systems systemd[1]: Starting Remount Root and Kernel File Systems...
[0m...
[    3.166887] cryptodev: loading out-of-tree module taints kernel.
[    3.169579] cryptodev: driver 1.10 loaded.
[    3.181855] EXT4-fs (mmcblk1p2): re-mounted. Opts: (null)
         Starting udev Coldplug all Devices...
[    3.191202] systemd[1]: Starting udev Coldplug all Devices...
[  OK  ] Started RPC Bind.
[    3.211273] systemd[1]: Started RPC Bind.
[  OK  ] Started Journal Service.
[    3.222937] systemd[1]: Started Journal Service.
[  OK  ] Mounted Huge Pages File System.
[  OK  ] Mounted POSIX Message Queue File System.
[  OK  ] Mounted Kernel Debug File System.
[  OK  ] Mounted Temporary Directory (/tmp).
[  OK  ] Started Create list of sta… nodes for the current kernel.
[FAILED] Failed to start Start psplash boot splash screen.
See 'systemctl status psplash-start.service' for details.
[DEPEND] Dependency failed for Star…progress communication helper.
[  OK  ] Started Load Kernel Modules.
[  OK  ] Started Remount Root and Kernel File Systems.
         Mounting Kernel Configuration File System...
         Starting Flush Journal to Persistent Storage...
[    3.494485] systemd-journald[226]: Received client request to flush runtime journal.
         Starting Apply Kernel Variables...
         Starting Create System Users...
[  OK  ] Mounted Kernel Configuration File System.
[  OK  ] Started Flush Journal to Persistent Storage.
[  OK  ] Started Apply Kernel Variables.
[  OK  ] Started Create System Users.
         Starting Create Static Device Nodes in /dev...
[  OK  ] Started Create Static Device Nodes in /dev.
[  OK  ] Reached target Local File Systems (Pre).
         Mounting /media/ram...
         Mounting /var/volatile...
         Starting udev Kernel Device Manager...
[  OK  ] Started udev Coldplug all Devices.
[  OK  ] Mounted /media/ram.
[  OK  ] Mounted /var/volatile.
         Starting udev Wait for Complete Device Initialization...
         Starting Load/Save Random Seed...
[  OK  ] Reached target Local File Systems.
         Starting Rebuild Dynamic Linker Cache...
         Starting Create Volatile Files and Directories...
[  OK  ] Started Create Volatile Files and Directories.
[    3.979454] random: systemd: uninitialized urandom read (16 bytes read)
         Starting Run pending postinsts...
[    3.992750] random: systemd-journal: uninitialized urandom read (16 bytes read)
[    4.002365] random: systemd: uninitialized urandom read (16 bytes read)
         Starting Rebuild Journal Catalog...
         Starting Network Time Synchronization...
         Starting Update UTMP about System Boot/Shutdown...
[  OK  ] Started udev Kernel Device Manager.
[  OK  ] Started Rebuild Journal Catalog.
[  OK  ] Started Update UTMP about System Boot/Shutdown.
[    5.055629] random: systemd: uninitialized urandom read (16 bytes read)
[  OK  ] Started Network Time Synchronization.
[  OK  ] Reached target System Time Set.
[  OK  ] Reached target System Time Synchronized.
[    5.424710] random: crng init done
[    5.424731] random: 71 urandom warning(s) missed due to ratelimiting
[  OK  ] Started Load/Save Random Seed.
[    6.135852] k3-m4-rproc 5000000.m4fss: assigned reserved memory node m4f-dma-memory@9cb00000
[    6.136092] k3-m4-rproc 5000000.m4fss: configured M4 for remoteproc mode
[    6.136337] k3-m4-rproc 5000000.m4fss: local reset is deasserted for device
[    6.136676] remoteproc remoteproc0: 5000000.m4fss is available
[    6.162507] remoteproc remoteproc0: powering up 5000000.m4fss
[    6.162540] remoteproc remoteproc0: Booting fw image am62-mcu-m4f0_0-fw, size 78960
[    6.206520]  remoteproc0#vdev0buffer: assigned reserved memory node m4f-dma-memory@9cb00000
[    6.232869]  remoteproc0#vdev0buffer: registered virtio0 (type 7)
[    6.232906] remoteproc remoteproc0: remote processor 5000000.m4fss is now up
[    6.555593] virtio_rpmsg_bus virtio0: rpmsg host is online
[    6.556639] virtio_rpmsg_bus virtio0: creating channel ti.ipc4.ping-pong addr 0xd
[    6.594519] virtio_rpmsg_bus virtio0: creating channel rpmsg_chrdev addr 0xe
[    6.662575] platform 78000000.r5f: R5F core may have been powered on by a different host, programmed state (0) != actual state (1)
[    6.662772] platform 78000000.r5f: configured R5F for IPC-only mode
[    6.662967] platform 78000000.r5f: assigned reserved memory node r5f-dma-memory@9da00000
[    6.710179] remoteproc remoteproc1: 78000000.r5f is available
[    6.710322] remoteproc remoteproc1: attaching to 78000000.r5f
[    6.730864] platform 78000000.r5f: R5F core initialized in IPC-only mode
[    6.731059]  remoteproc1#vdev0buffer: assigned reserved memory node r5f-dma-memory@9da00000
[    6.763559] virtio_rpmsg_bus virtio1: rpmsg host is online
[    6.764457] virtio_rpmsg_bus virtio1: creating channel ti.ipc4.ping-pong addr 0xd
[    6.765334]  remoteproc1#vdev0buffer: registered virtio1 (type 7)
[    6.765361] remoteproc remoteproc1: remote processor 78000000.r5f is now attached
[    6.788137] virtio_rpmsg_bus virtio1: creating channel rpmsg_chrdev addr 0xe
[    6.998414] PVR_K:  252: Read BVNC 33.15.11.3 from HW device registers
[    6.998445] PVR_K:  252: RGX Device registered with BVNC 33.15.11.3
[    7.105052] [drm] Initialized pvr 1.15.6133109 20170530 for fd00000.gpu on minor 1
[  OK  ] Started Rebuild Dynamic Linker Cache.
         Starting Update is Completed...
[  OK  ] Started Update is Completed.
[    9.847394] remoteproc remoteproc2: 30074000.pru is available
[    9.884090] remoteproc remoteproc3: 30078000.pru is available
[    9.986470] cdns-csi2rx: probe of 30101000.csi-bridge failed with error -22
[   10.104317] tps6598x 0-003f: Unable to find the interrupt, switching to polling
         Starting Start psplash boot splash screen...
[   10.269151] usbcore: registered new interface driver usbfs
[   10.271120] usbcore: registered new interface driver hub
[   10.282874] usbcore: registered new device driver usb
[FAILED] Failed to start Start psplash boot splash screen.
See 'systemctl status psplash-start.service' for details.
[   10.354159] xhci-hcd xhci-
                             [   17.752861] PVR_K:  1184: RGX Firmware image 'rgx.fw.33.15.11.3' loaded
[   17.839878] PVR_K:  1184: Shader binary image 'rgx.sh.33.15.11.3' loaded
[  OK  ] Started LSB: Expand Rootfs of boot device.
[  OK  ] Started hmi_demo.service.
         Starting start_camera_demo.service...

 _____                    _____           _         _
|  _  |___ ___ ___ ___   |  _  |___ ___  |_|___ ___| |_
|     |  _| .'| . | . |  |   __|  _| . | | | -_|  _|  _|
|__|__|_| |__,|_  |___|  |__|  |_| |___|_| |___|___|_|
              |___|                    |___|

Arago Project am62xx-evm ttyS2

Arago 2021.09 am62xx-evm ttyS2

am62xx-evm login: root
root@am62xx-evm:~# [  135.522629] Initializing XFRM netlink socket
[  138.500795] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
[  138.506641] Bridge firewalling registered
[  139.543289] process 'docker/tmp/qemu-check393563371/check' started with executable stack

root@am62xx-evm:~#
root@am62xx-evm:~# cd /
root@am62xx-evm:/# mkdir /mnt/temp
root@am62xx-evm:/# mount -t ext4 /dev/mmcblk0p1 /mnt/temp/
root@am62xx-evm:/# df -Th
Filesystem     Type      Size  Used Avail Use% Mounted on
/dev/root      ext4       14G  2.0G   12G  15% /
devtmpfs       devtmpfs  712M  4.0K  712M   1% /dev
tmpfs          tmpfs     969M     0  969M   0% /dev/shm
tmpfs          tmpfs     969M  9.1M  960M   1% /run
tmpfs          tmpfs     969M     0  969M   0% /sys/fs/cgroup
tmpfs          tmpfs     969M     0  969M   0% /tmp
tmpfs          tmpfs      16M     0   16M   0% /media/ram
tmpfs          tmpfs      50M   24K   50M   1% /var/volatile
/dev/mmcblk0p1 ext4       15G  441M   14G   4% /mnt/temp
/dev/mmcblk1p1 vfat      127M  2.1M  124M   2% /media/mmcblk1p1
tmpfs          tmpfs     194M     0  194M   0% /run/user/0
root@am62xx-evm:/# cd /mnt/temp/
root@am62xx-evm:/mnt/temp# ls
bin
root@am62xx-evm:/mnt/temp# rm * -rf
root@am62xx-evm:/mnt/temp# ls
root@am62xx-evm:/mnt/temp# cp /lib/ /mnt/temp/ -rf
root@am62xx-evm:/mnt/temp# ls
lib
root@am62xx-evm:/mnt/temp# cp /usr/bin/ /mnt/temp/ -rf
[  283.071153] mmc0: running CQE recovery
[  283.077842] ------------[ cut here ]------------
[  283.077855] mmc0: cqhci: spurious TCN for tag 15
[  283.077919] WARNING: CPU: 0 PID: 169 at drivers/mmc/host/cqhci.c:742 cqhci_irq+0x318/0x4a0
[  283.077943] Modules linked in: xt_conntrack xt_addrtype iptable_filter br_netfilter bridge stp llc overlay xfrm_user xfrm_algo md5 des_generic libdes cbc iptable_nat xt_MASQUERADE nf_nat nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 libcrc32c ip_tables x_tables wl18xx wlcore mac80211 xhci_plat_hcd xhci_hcd rpmsg_char cfg80211 usbcore rfkill libarc4 dwc3 udc_core cdns_csi2rx usb_common v4l2_fwnode pru_rproc irq_pruss_intc crct10dif_ce snd_soc_simple_card snd_soc_simple_card_utils wlcore_sdio pvrsrvkm(O) ti_k3_r5_remoteproc virtio_rpmsg_bus dwc3_am62 ti_k3_m4_remoteproc rti_wdt sa2ul sha512_generic authenc j721e_csi2rx videobuf2_dma_contig videobuf2_memops videobuf2_v4l2 videobuf2_common cdns_dphy tps6598x snd_soc_tlv320aic3x typec roles pruss optee_rng rng_core sch_fq_codel cryptodev(O) ipv6
[  283.078128] CPU: 0 PID: 169 Comm: irq/33-mmc0 Tainted: G           O      5.10.153-rt76-g29dbc132eb #1
[  283.078135] Hardware name: Texas Instruments AM625 SK (DT)
[  283.078140] pstate: 40000005 (nZcv daif -PAN -UAO -TCO BTYPE=--)
[  283.078147] pc : cqhci_irq+0x318/0x4a0
[  283.078153] lr : cqhci_irq+0x318/0x4a0
[  283.078157] sp : ffff800011993c20
[  283.078160] x29: ffff800011993c20 x28: ffff000001dd0600
[  283.078167] x27: ffff0000011da280 x26: 0000000000000001
[  283.078173] x25: ffff800010ddbe08 x24: ffff0000011da298
[  283.078180] x23: ffff80001117561a x22: ffff0000010e8ec0
[  283.078186] x21: ffff000001dd0000 x20: 0000000000000002
[  283.078192] x19: 000000000000000f x18: 0000000000000001
[  283.078198] x17: ffff000001fbf100 x16: 0000000000000000
[  283.078204] x15: ffff0000010e9400 x14: ffffffffffffffff
[  283.078211] x13: ffff80001119cfd6 x12: ffff80001119cfd2
[  283.078218] x11: 0000000000000000 x10: ffff80001108b050
[  283.078224] x9 : 00000000fffffffe x8 : 000000000000000f
[  283.078230] x7 : 6972757073203a69 x6 : ffff800011993a70
[  283.078236] x5 : ffff00007fb92b38 x4 : 0000000000000000
[  283.078242] x3 : 0000000000000027 x2 : 0000000100000000
[  283.078248] x1 : 8d26c85dbabbd400 x0 : 0000000000000000
[  283.078256] Call trace:
[  283.078259]  cqhci_irq+0x318/0x4a0
[  283.078265]  sdhci_am654_cqhci_irq+0x58/0x88
[  283.078274]  sdhci_irq+0xb0/0xf18
[  283.078280]  irq_forced_thread_fn+0x3c/0xc0
[  283.078291]  irq_thread+0x184/0x258
[  283.078297]  kthread+0x174/0x198
[  283.078306]  ret_from_fork+0x10/0x34
[  283.078315] ---[ end trace 0000000000000002 ]---
[  284.078510] sched: RT throttling activated
[  284.103151] mmc0: running CQE recovery
[  284.168175] mmc0: running CQE recovery
[  284.174389] mmc0: running CQE recovery
[  284.180247] mmc0: running CQE recovery
[  284.185948] mmc0: running CQE recovery
[  284.187022] mmc0: running CQE recovery
[  284.204623] mmc0: running CQE recovery
[  284.231769] mmc0: running CQE recovery
[  284.238244] mmc0: running CQE recovery
[  284.247265] mmc0: running CQE recovery
[  284.254225] mmc0: running CQE recovery
[  284.259403] mmc0: running CQE recovery
[  284.273614] mmc0: running CQE recovery
[  284.289342] mmc0: running CQE recovery
[  284.294430] mmc0: running CQE recovery
[  284.302400] mmc0: running CQE recovery
[  284.303125] blk_update_request: I/O error, dev mmcblk0, sector 17061240 op 0x1:(WRITE) flags 0x4000 phys_seg 10 prio class 0
[  345.114017] mmc0: cqhci: timeout for tag 0
[  345.114045] mmc0: cqhci: ============ CQHCI REGISTER DUMP ===========
[  345.114049] mmc0: cqhci: Caps:      0x000030c8 | Version:  0x00000510
[  345.114055] mmc0: cqhci: Config:    0x00000101 | Control:  0x00000000
[  345.114059] mmc0: cqhci: Int stat:  0x00000000 | Int enab: 0x00000006
[  345.114063] mmc0: cqhci: Int sig:   0x00000006 | Int Coal: 0x00000000
[  345.114067] mmc0: cqhci: TDL base:  0x80393000 | TDL up32: 0x00000000
[  345.114072] mmc0: cqhci: Doorbell:  0xffffffff | TCN:      0x00000000
[  345.114076] mmc0: cqhci: Dev queue: 0x00000000 | Dev Pend: 0x00000000
[  345.114080] mmc0: cqhci: Task clr:  0x00000000 | SSC1:     0x00011000
[  345.114085] mmc0: cqhci: SSC2:      0x00000001 | DCMD rsp: 0x00000000
[  345.114089] mmc0: cqhci: RED mask:  0xfdf9a080 | TERRI:    0x9e2f002c
[  345.114093] mmc0: cqhci: Resp idx:  0x0000002f | Resp arg: 0x00000900
[  345.114098] mmc0: sdhci: ============ SDHCI REGISTER DUMP ===========
[  345.114103] mmc0: sdhci: Sys addr:  0x00000400 | Version:  0x00001004
[  345.114107] mmc0: sdhci: Blk size:  0x00007080 | Blk cnt:  0x00000000
[  345.114111] mmc0: sdhci: Argument:  0x00000000 | Trn mode: 0x00000013
[  345.114116] mmc0: sdhci: Present:   0x01ff00f0 | Host ctl: 0x0000003c
[  345.114120] mmc0: sdhci: Power:     0x0000000f | Blk gap:  0x00000080
[  345.114124] mmc0: sdhci: Wake-up:   0x00000000 | Clock:    0x00000007
[  345.114128] mmc0: sdhci: Timeout:   0x0000000e | Int stat: 0x00000000
[  345.114133] mmc0: sdhci: Int enab:  0x02ff4000 | Sig enab: 0x02ff4000
[  345.114137] mmc0: sdhci: ACmd stat: 0x00000000 | Slot int: 0x00000000
[  345.114141] mmc0: sdhci: Caps:      0x3decc801 | Caps_1:   0x18002407
[  345.114146] mmc0: sdhci: Cmd:       0x0000153a | Max curr: 0x00000000
[  345.114150] mmc0: sdhci: Resp[0]:   0x00000900 | Resp[1]:  0xffc003ff
[  345.114155] mmc0: sdhci: Resp[2]:   0x328f5903 | Resp[3]:  0x00d07f01
[  345.114159] mmc0: sdhci: Host ctl2: 0x0000000b
[  345.114162] mmc0: sdhci: ADMA Err:  0x00000000 | ADMA Ptr: 0x000000008111a20c
[  345.114167] mmc0: sdhci: ============================================
[  345.114232] mmc0: running CQE recovery
[  345.118455] mmc0: running CQE recovery
[  345.125198] mmc0: running CQE recovery
[  345.131223] mmc0: running CQE recovery
[  345.136394] blk_update_request: I/O error, dev mmcblk0, sector 17068720 op 0x1:(WRITE) flags 0x4000 phys_seg 10 prio class 0
[  345.137056] mmc0: running CQE recovery
[  345.142092] blk_update_request: I/O error, dev mmcblk0, sector 17084280 op 0x1:(WRITE) flags 0x4000 phys_seg 10 prio class 0
[  345.142776] mmc0: running CQE recovery
[  345.148557] mmc0: running CQE recovery

Thanks and regards,

Cherry

Hi Cherry,

Thank you for the log. It gives me a clear picture of the issue.

Can you please run the following test to see if the issue still happens. This test is similar to the previous one, but disables HS200 on eMMC instead of the SD card (mmc1).

 => setenv boot_fit 0                                                            
 => run args_all args_mmc get_kern_mmc findfdt get_fdt_mmc                       
 => fdt addr $fdtaddr; fdt resize                                                
 => fdt set /bus@f0000/mmc@fa10000/ no-1-8-v                                     
 => run run_kern 

After Linux is booted, you should see the following message in kernel log:

[ 1.561297] mmc0: new high speed MMC card at address 0001

instead of:

[ 1.325482] mmc0: new HS200 MMC card at address 0001

Hi Bin,

The above test did work!

May I know why can't use HS200 mode? Is there a problem with the hardware of the development board? OR is there anything wrong with the AM625 using H200 mode?

Will it affect the performance rate if it is changed to hight speed but not HS200 mode? If the customer wants to use HS200 mode, what could be done on H/W design or S/W? 

Thanks and regards,

Cherry

Hi Cherry,

Thanks for the update. This test narrows the issue is in eMMC HS200 mode.

You use the SK-AM62 EVM, right? What is its revision? You should see a silkscreen label like "PROC114xx", the 'xx' is the board revision.

Hi Bin,

Bin Liu said:

You use the SK-AM62 EVM, right? What is its revision? You should see a silkscreen label like "PROC114xx", the 'xx' is the board revision.

It's AM62x SK EVM PROC114E3. Is this issue related to the H/W circuit?

Thanks and regards,

Cherry

Hi Cherry,

I am able to reproduce the issue on my SK-AM62. I will report the issue to our software dev team to investigate it.

Hi Bin,

Excuse me, have you found the cause of this problem?

Hi Zhoukaide,

I just checked the issue report internally, but it has no update yet since I filed it. I have pinged on the ticket for progress update.