This thread has been locked.

If you have a related question, please click the "Ask a related question" button in the top right corner. The newly created question will be automatically linked to this question.

AM625: AM62x uboot failed

Part Number: AM625

Hi 

"I'm using a custom AM625 board. When testing the boot time, I occasionally encounter the issue shown in the screenshot. The error only happens sporadically.
  1. What is being checked at this stage? How can I understand what's happening here?
  2. What should be my troubleshooting direction for this error?"
image.png
 
 
 
thanks
 
 
 
 
  • Hi Hao,

    The U-Boot 20211.01 for AM625 is very old and no longer supported. You should migrate to the latest version of U-Boot and Linux.

  • hi bin

      Our product has been running on SDK 8.6 in production for a long time, and direct SDK upgrades are not supported for now. I would like to understand what operations this certification involves and whether there is any documentation available for it. What does the communication timeout indicated by ti_scirefer to? Does it mean that R5 failed to re-establish communication after booting U-Boot?

    thanks

  • Hi Hao,

    In the screenshot of the console log you provided, the first two lines look identical to the next two lines. Are all the four lines printed in the same power cycle to the board? Or did you power cycled the board after the first two lines printed?

    Do you see the mmc pintctrl_select_state_full message in every time booting the board even when the boot doesn't fail? or is it only printed when ti_sci failure happens?

    By the way, please copy and paste the log, or attach it as a file if the log is too long. Providing the log in screenshoots make referencing very difficult. It would impact our communication.

  • hi bin

    "The attached txt file is the boot log when my board starts up normally. The screenshot shows the log when an anomaly occurs. This anomaly happens approximately once every 100 reboots. I don't know what exactly happens during authentication. Or could you suggest some directions for troubleshooting?"

    U-Boot SPL 2021.01-g48e76495-dirty (Mar 11 2025 - 17:15:58 +0800)
    SYSFW ABI: 3.1 (firmware rev 0x0008 '8.6.4--v08.06.04 (Chill Capybar')
    SPL initial stack usage: 13424 bytes
    Trying to boot from MMC1
    am654_sdhci mmc@fa10000: pinctrl_select_state_full: uclass_get_device_by_phandle_id: err=-19
    Authentication passed
    Authentication passed
    Authentication passed
    Authentication passed
    Authentication passed
    init_env from device 9 not supported!
    Starting ATF on ARM64 core...
    
    NOTICE:  BL31: v2.8(release):v2.8-226-g2fcd408bb3-dirty
    NOTICE:  BL31: Built : 05:33:19, Feb 24 2023
    
    U-Boot SPL 2021.01-g48e76495-dirty (Mar 11 2025 - 17:16:38 +0800)
    SYSFW ABI: 3.1 (firmware rev 0x0008 '8.6.4--v08.06.04 (Chill Capybar')
    Trying to boot from MMC1
    am654_sdhci mmc@fa10000: pinctrl_select_state_full: uclass_get_device_by_phandle_id: err=-19
    am654_sdhci mmc@fa10000: Couldn't find ti,otap-del-sel-sd-hs
    am654_sdhci mmc@fa10000: Couldn't find
    am654_sdhci mmc@fa10000: Couldn't find ti,otap-del-sel-sdr12
    am654_sdhci mmc@fa10000: Couldn't find ti,otap-del-sel-sdr25
    am654_sdhci mmc@fa10000: Couldn't find ti,otap-del-sel-sdr50
    am654_sdhci mmc@fa10000: Couldn't find ti,otap-del-sel-ddr50
    am654_sdhci mmc@fa10000: Couldn't find ti,otap-del-sel-sdr104
    am654_sdhci mmc@fa10000: Couldn't find ti,otap-del-sel-hs400
    Warning: Did not detect image signing certificate. Skipping authentication to prevent boot failure. This will fail on Security Enforcing(HS-SE) devices
    Warning: Did not detect image signing certificate. Skipping authentication to prevent boot failure. This will fail on Security Enforcing(HS-SE) devices
    
    
    U-Boot 2021.01-g48e76495-dirty (Mar 11 2025 - 17:16:38 +0800)
    
    ti-sci-sysreset sysreset-controller: set_state_simple op missing
    SoC:   AM62X SR1.0 HS-FS
    Model: Texas Instruments AM625 SK
    DRAM:  2 GiB
    pinconfig run_err_led_pins_default: set_state_simple op missing
    single-pinctrl pinctrl@4084000: configuring pins for run_err_led_pins_default
    single-pinctrl pinctrl@4084000:   reg/val 0x0x0000000004084000/0x00010007
    single-pinctrl pinctrl@4084000:   reg/val 0x0x0000000004084004/0x00010007
    simple_bus bus@4000000: set_state_simple op missing
    simple_bus bus@f0000: set_state_simple op missing
    simple_bus bus@2b000000: set_state_simple op missing
    syscon syscon@43000000: set_state_simple op missing
    soc_ti_k3 chipid@14: set_state_simple op missing
    ti_sci system-controller@44043000: set_state_simple op missing
    simple_bus bus@48000000: set_state_simple op missing
    k3-secure-proxy mailbox@4d000000: set_state_simple op missing
    ti_sci system-controller@44043000: ti_sci_of_to_info: Acquiring notify channel failed. ret = -61
    ti-sci-clk clock-controller: set_state_simple op missing
    pinconfig som_led1_pins_default: set_state_simple op missing
    single-pinctrl pinctrl@f4000: configuring pins for som_led1_pins_default
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f4030/0x00010007
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f4098/0x00010007
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f40a4/0x00050007
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f4094/0x00050007
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f41c0/0x00010007
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f41b4/0x00010007
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f41bc/0x00010007
    pinconfig main-uart0-pins-default: set_state_simple op missing
    single-pinctrl pinctrl@f4000: configuring pins for main-uart0-pins-default
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f41c8/0x00050000
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f41cc/0x00010000
    ti-sci-pm-domains power-controller: set_state_simple op missing
    MMC:   pinconfig main-mmc0-pins-default: set_state_simple op missing
    single-pinctrl pinctrl@f4000: configuring pins for main-mmc0-pins-default
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f4220/0x00050000
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f4218/0x00050000
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f4214/0x00050000
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f4210/0x00050000
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f420c/0x00050000
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f4208/0x00050000
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f4204/0x00050000
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f4200/0x00050000
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f41fc/0x00050000
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f41f8/0x00050000
    am654_sdhci mmc@fa10000: Couldn't find ti,otap-del-sel-sd-hs
    am654_sdhci mmc@fa10000: Couldn't find <NULL>
    am654_sdhci mmc@fa10000: Couldn't find ti,otap-del-sel-sdr12
    am654_sdhci mmc@fa10000: Couldn't find ti,otap-del-sel-sdr25
    am654_sdhci mmc@fa10000: Couldn't find ti,otap-del-sel-sdr50
    am654_sdhci mmc@fa10000: Couldn't find ti,otap-del-sel-ddr50
    am654_sdhci mmc@fa10000: Couldn't find ti,otap-del-sel-sdr104
    am654_sdhci mmc@fa10000: Couldn't find ti,otap-del-sel-hs400
    pinconfig main-mmc1-pins-default: set_state_simple op missing
    single-pinctrl pinctrl@f4000: configuring pins for main-mmc1-pins-default
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f423c/0x00050000
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f4234/0x00050000
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f4230/0x00050000
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f422c/0x00050000
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f4228/0x00050000
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f4224/0x00050000
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f4240/0x00050000
    am654_sdhci mmc@fa00000: Couldn't find ti,otap-del-sel-mmc-hs
    am654_sdhci mmc@fa00000: Couldn't find <NULL>
    am654_sdhci mmc@fa00000: Couldn't find ti,otap-del-sel-ddr52
    am654_sdhci mmc@fa00000: Couldn't find ti,otap-del-sel-hs200
    am654_sdhci mmc@fa00000: Couldn't find ti,otap-del-sel-hs400
    am654_sdhci mmc@fa20000: set_state_simple op missing
    am654_sdhci mmc@fa20000: Couldn't find ti,otap-del-sel-mmc-hs
    am654_sdhci mmc@fa20000: Couldn't find <NULL>
    am654_sdhci mmc@fa20000: Couldn't find ti,otap-del-sel-ddr52
    am654_sdhci mmc@fa20000: Couldn't find ti,otap-del-sel-hs200
    am654_sdhci mmc@fa20000: Couldn't find ti,otap-del-sel-hs400
    mmc@fa10000: 0, mmc@fa00000: 1, mmc@fa20000: 2
    Loading Environment from MMC... *** Warning - bad CRC, using default environment
    
    In:    serial@2800000
    Out:   serial@2800000
    Err:   serial@2800000
    pinconfig main-mdio1-pins-default: set_state_simple op missing
    single-pinctrl pinctrl@f4000: configuring pins for main-mdio1-pins-default
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f4160/0x00010000
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f415c/0x00050000
    pinconfig main-rgmii1-pins-default: set_state_simple op missing
    single-pinctrl pinctrl@f4000: configuring pins for main-rgmii1-pins-default
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f414c/0x00050000
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f4150/0x00050000
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f4154/0x00050000
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f4158/0x00050000
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f4148/0x00050000
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f4144/0x00050000
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f4134/0x00010000
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f4138/0x00010000
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f413c/0x00010000
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f4140/0x00010000
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f4130/0x00010000
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f412c/0x00010000
    pinconfig main-rgmii2-pins-default: set_state_simple op missing
    single-pinctrl pinctrl@f4000: configuring pins for main-rgmii2-pins-default
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f4184/0x00050000
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f4188/0x00050000
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f418c/0x00050000
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f4190/0x00050000
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f4180/0x00050000
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f417c/0x00050000
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f416c/0x00010000
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f4170/0x00010000
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f4174/0x00010000
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f4178/0x00010000
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f4168/0x00010000
    single-pinctrl pinctrl@f4000:   reg/val 0x0x00000000000f4164/0x00010000
    am65_cpsw_nuss ethernet@8000000: K3 CPSW: nuss_ver: 0x6BA01103 cpsw_ver: 0x6BA81103 ale_ver: 0x00290105 Ports:2 mdio_freq:1000000
    Net:   am65_cpsw_nuss_port port@1: set_state_simple op missing
    am65_cpsw_nuss ethernet@8000000: old gmii_sel: 00000002
    am65_cpsw_nuss ethernet@8000000: gmii_sel PHY mode: 9, new gmii_sel: 00000002
    eth0: ethernet@8000000port@1
    Failed to load '/moons/update/moons_update_config.txt'
    ext4load /moons/update/moons_update_config.txt  error
    use default model
    Model - 1
     From EMMC update to EMMC
    Failed to load '/moons/update/tiboot3.bin'
    --- ext4load /moons/update/tiboot3.bin error !---
    Failed to load '/moons/update/tispl.bin'
    --- ext4load /moons/update/tispl.bin error !---
    Failed to load '/moons/update/u-boot.img'
    --- ext4load /moons/update/u-boot.img error !---
    Failed to load '/moons/update/rootfs.ext4'
    --- ext4load /moons/update/rootfs.ext4 error ! ---
    Failed to load '/moons/update/Image'
    --- ext4load mmc 0:2 /moons/update/Image error ! ---
    Failed to load '/moons/update/tl62x-evm.dtb'
    --- ext4load mmc 0:2 /moons/update/tl62x-evm.dtb error ! ---
    Failed to load '/moons/update/fpga.bin'
    --- ext4load mmc 0:2 /moons/update/fpga.bin error ! ---
    626777 bytes read in 7 ms (85.4 MiB/s)
    Debug Test  cmd is ext4size mmc 0:2 /usr/Agile/fpga.bin
    ## Total Size      = 0x00099059 = 626777 Bytes
    StrSizeis  99059
    fpga.bin  size is  626777
    fpga_get_desc: found fpga descriptor #2 @ 0x00000000fffeb9a8
    download fpga time: 3766
    -------------------------------------------------------------------
    --- fpga load   mmc  0:2  OK ! ---
    MOONS MCA PAC U-BOOT  version   V1.1.1
    please input  passwordswitch to partitions #0, OK
    mmc0(part 0) is current device
    SD/MMC found on device 0
    Failed to load 'boot.scr'
    Failed to load 'uEnv.txt'
    18938368 bytes read in 107 ms (168.8 MiB/s)
    44404 bytes read in 4 ms (10.6 MiB/s)
    Failed to load '/boot/tl62x-evm-devmem-rw.dtbo'
    ## Flattened Device Tree blob at 88000000
       Booting using the fdt blob at 0x88000000
       Loading Device Tree to 000000008fef2000, end 000000008fffffff ... OK
    
    Starting kernel ...
    
    ti-sci-pm-domains power-controller: set_state_simple op missing
    ti-sci-pm-domains power-controller: set_state_simple op missing
    ti-sci-pm-domains power-controller: set_state_simple op missing
    [    0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034]
    [    0.000000] Linux version 5.10.168-rt83 (root@ubuntu) (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) #14 SMP PREEMPT_RT Mon May 26 10:16:08 CST 2025
    [    0.000000] Machine model: TL62x-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 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 144 MiB at 0x00000000f4000000
    [    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 s36032 r8192 d29504 u73728
    [    0.000000] pcpu-alloc: s36032 r8192 d29504 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 isolcpus=1-3 earlycon=ns16550a,mmio32,0x02800000 root=PARTUUID=fbc97ce2-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: 1832960K/2097152K available (11072K kernel code, 1214K rwdata, 4300K rodata, 1792K init, 437K bss, 116736K reserved, 147456K 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]  Tracing variant of Tasks RCU enabled.
    [    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 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 @0x0000000080030000
    [    0.000000] GIC: using cache flushing for LPI property table
    [    0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000080040000
    [    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.008522] Console: colour dummy device 80x25
    [    0.013113] Calibrating delay loop (skipped), value calculated using timer frequency.. 400.00 BogoMIPS (lpj=200000)
    [    0.023795] pid_max: default: 32768 minimum: 301
    [    0.028615] LSM: Security Framework initializing
    [    0.033411] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear)
    [    0.040994] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear)
    [    0.051019] rcu: Hierarchical SRCU implementation.
    [    0.056462] Platform MSI: msi-controller@1820000 domain created
    [    0.062799] PCI/MSI: /bus@f0000/interrupt-controller@1800000/msi-controller@1820000 domain created
    [    0.072072] EFI services will not be available.
    [    0.077008] smp: Bringing up secondary CPUs ...
    [    0.082430] Detected VIPT I-cache on CPU1
    [    0.086561] GICv3: CPU1: found redistributor 1 region 0:0x00000000018a0000
    [    0.093599] GICv3: CPU1: using allocated LPI pending table @0x0000000080050000
    [    0.101036] CPU1: Booted secondary processor 0x0000000001 [0x410fd034]
    [    0.108590] Detected VIPT I-cache on CPU2
    [    0.112712] GICv3: CPU2: found redistributor 2 region 0:0x00000000018c0000
    [    0.119746] GICv3: CPU2: using allocated LPI pending table @0x0000000080060000
    [    0.127164] CPU2: Booted secondary processor 0x0000000002 [0x410fd034]
    [    0.134648] Detected VIPT I-cache on CPU3
    [    0.138770] GICv3: CPU3: found redistributor 3 region 0:0x00000000018e0000
    [    0.145803] GICv3: CPU3: using allocated LPI pending table @0x0000000080070000
    [    0.153216] CPU3: Booted secondary processor 0x0000000003 [0x410fd034]
    [    0.159991] smp: Brought up 1 node, 4 CPUs
    [    0.164195] SMP: Total of 4 processors activated.
    [    0.169014] CPU features: detected: 32-bit EL0 Support
    [    0.174279] CPU features: detected: CRC32 instructions
    [    0.186719] CPU: All CPU(s) started at EL2
    [    0.190926] alternatives: patching kernel code
    [    0.196523] devtmpfs: initialized
    [    0.207329] KASLR disabled due to lack of seed
    [    0.212038] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
    [    0.222033] futex hash table entries: 1024 (order: 5, 131072 bytes, linear)
    [    0.233586] pinctrl core: initialized pinctrl subsystem
    [    0.239685] DMI not present or invalid.
    [    0.244335] NET: Registered protocol family 16
    [    0.250800] DMA: preallocated 256 KiB GFP_KERNEL pool for atomic allocations
    [    0.258154] DMA: preallocated 256 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
    [    0.266246] DMA: preallocated 256 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
    [    0.274984] thermal_sys: Registered thermal governor 'step_wise'
    [    0.275644] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
    [    0.288826] ASID allocator initialised with 65536 entries
    [    0.322161] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages
    [    0.329060] HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages
    [    0.335948] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
    [    0.342813] HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages
    [    0.350950] cryptd: max_cpu_qlen set to 1000
    [    0.359072] k3-chipinfo 43000014.chipid: Family:AM62X rev:SR1.0 JTAGID[0x0bb7e02f] Detected
    [    0.368413] vcc_5v0: supplied by vmain_pd
    [    0.373068] vcc_3v3_sys: supplied by vmain_pd
    [    0.377955] vdd_mmc1: supplied by vcc_3v3_sys
    [    0.382951] vcc_1v8: supplied by vcc_3v3_sys
    [    0.388486] iommu: Default domain type: Translated
    [    0.393836] SCSI subsystem initialized
    [    0.398136] mc: Linux media interface: v0.10
    [    0.402565] videodev: Linux video capture interface: v2.00
    [    0.408254] pps_core: LinuxPPS API ver. 1 registered
    [    0.413330] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
    [    0.422680] PTP clock support registered
    [    0.426739] EDAC MC: Ver: 3.0.0
    [    0.430702] omap-mailbox 29000000.mailbox: omap mailbox rev 0x66fc9100
    [    0.437878] FPGA manager framework
    [    0.441466] Advanced Linux Sound Architecture Driver Initialized.
    [    0.448722] clocksource: Switched to clocksource arch_sys_counter
    [    0.455249] VFS: Disk quotas dquot_6.6.0
    [    0.459325] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
    [    0.471976] NET: Registered protocol family 2
    [    0.476727] IP idents hash table entries: 32768 (order: 6, 262144 bytes, linear)
    [    0.485798] tcp_listen_portaddr_hash hash table entries: 1024 (order: 4, 65536 bytes, linear)
    [    0.494720] TCP established hash table entries: 16384 (order: 5, 131072 bytes, linear)
    [    0.502974] TCP bind hash table entries: 16384 (order: 7, 917504 bytes, linear)
    [    0.511463] TCP: Hash tables configured (established 16384 bind 16384)
    [    0.518445] UDP hash table entries: 1024 (order: 5, 131072 bytes, linear)
    [    0.525580] UDP-Lite hash table entries: 1024 (order: 5, 131072 bytes, linear)
    [    0.533398] NET: Registered protocol family 1
    [    0.538396] RPC: Registered named UNIX socket transport module.
    [    0.544492] RPC: Registered udp transport module.
    [    0.549303] RPC: Registered tcp transport module.
    [    0.554152] RPC: Registered tcp NFSv4.1 backchannel transport module.
    [    0.560763] NET: Registered protocol family 44
    [    0.565323] PCI: CLS 0 bytes, default 64
    [    0.570324] hw perfevents: enabled with armv8_cortex_a53 PMU driver, 7 counters available
    [    0.583342] Initialise system trusted keyrings
    [    0.588159] workingset: timestamp_bits=46 max_order=19 bucket_order=0
    [    0.600102] squashfs: version 4.0 (2009/01/31) Phillip Lougher
    [    0.606788] NFS: Registering the id_resolver key type
    [    0.612007] Key type id_resolver registered
    [    0.616325] Key type id_legacy registered
    [    0.620511] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
    [    0.627375] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
    [    0.635157] 9p: Installing v9fs 9p2000 file system support
    [    0.676818] Key type asymmetric registered
    [    0.681014] Asymmetric key parser 'x509' registered
    [    0.686074] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 243)
    [    0.693641] io scheduler mq-deadline registered
    [    0.698358] io scheduler kyber registered
    [    0.704922] pinctrl-single 4084000.pinctrl: 34 pins, size 136
    [    0.711400] pinctrl-single f4000.pinctrl: 171 pins, size 684
    [    0.718377] pinctrl-single a40000.timesync-router: 512 pins, size 2048
    [    0.733543] Serial: 8250/16550 driver, 10 ports, IRQ sharing enabled
    [    0.747395] pinctrl-single f4000.pinctrl: pin PIN65 already requested by f4000.pinctrl; cannot claim for 30200000.dss
    [    0.758274] pinctrl-single f4000.pinctrl: pin-65 (30200000.dss) status -22
    [    0.765308] pinctrl-single f4000.pinctrl: could not request pin 65 (PIN65) from group main-dss0-pins-default  on device pinctrl-single
    [    0.777674] tidss 30200000.dss: Error applying setting, reverse things back
    [    0.784806] tidss: probe of 30200000.dss failed with error -22
    [    0.798962] brd: module loaded
    [    0.811132] loop: module loaded
    [    0.815349] megasas: 07.714.04.00-rc1
    [    0.823519] tun: Universal TUN/TAP device driver, 1.6
    [    0.829334] igbvf: Intel(R) Gigabit Virtual Function Network Driver
    [    0.835773] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.
    [    0.841891] sky2: driver version 1.30
    [    0.846810] VFIO - User Level meta-driver version: 0.3
    [    0.853106] i2c /dev entries driver
    [    0.858594] sdhci: Secure Digital Host Controller Interface driver
    [    0.864995] sdhci: Copyright(c) Pierre Ossman
    [    0.870077] sdhci-pltfm: SDHCI platform and OF driver helper
    [    0.877327] SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping ....
    [    0.885654] optee: probing for conduit method.
    [    0.890267] optee: revision 3.20 (8e74d476)
    [    0.890556] optee: dynamic shared memory is enabled
    [    0.900071] optee: initialized driver
    [    0.906057] NET: Registered protocol family 17
    [    0.910814] 9pnet: Installing 9P2000 support
    [    0.915282] Key type dns_resolver registered
    [    0.920044] printk: bootconsole [ns16550a0]: printing thread started
    [    0.920094] Loading compiled-in X.509 certificates
    [    0.945422] ti-sci 44043000.system-controller: lpm region is required for suspend but not provided.
    [    0.945511] ti-sci 44043000.system-controller: ABI: 3.1 (firmware rev 0x0008 '8.6.4--v08.06.04 (Chill Capybar')
    [    1.019099] davinci-mcasp 2b00000.mcasp: IRQ common not found
    [    1.024289] omap-gpmc 3b000000.memory-controller: GPMC revision 6.0
    [    1.024307] gpmc_mem_init: disabling cs 0 mapped at 0x0-0x1000000
    [    1.041600] physmap-flash 50000000.nor: physmap platform flash device: [mem 0x50000000-0x50ffffff]
    [    1.041683] physmap-flash 50000000.nor: map_probe failed
    [    1.055768] bq32k: probe of 0-0068 failed with error -5
    [    1.055844] omap_i2c 20000000.i2c: bus 0 rev0.12 at 400 kHz
    [    1.061434] omap_i2c 20020000.i2c: bus 1 rev0.12 at 400 kHz
    [    1.087320] printk: console [ttyS2]: printing thread started
    [    1.087336] printk: console [ttyS2] enabled
    [    1.087340] printk: bootconsole [ns16550a0] disabled
    [    1.103424] printk: bootconsole [ns16550a0]: printing thread stopped
    [    1.104869] 2810000.serial: ttyS3 at MMIO 0x2810000 (irq = 30, base_baud = 3000000) is a 8250
    [    1.106134] omap8250 2820000.serial: unable to register 8250 port
    [    1.106151] omap8250 2820000.serial: PM domain pd:153 will not be powered off
    [    1.107350] omap8250 2840000.serial: unable to register 8250 port
    [    1.108312] omap8250 2850000.serial: unable to register 8250 port
    [    1.109222] 2860000.serial: ttyS7 at MMIO 0x2860000 (irq = 34, base_baud = 3000000) is a 8250
    [    1.173037] davinci_mdio 8000f00.mdio: Configuring MDIO in manual mode
    [    1.173417] mdio_bus 8000f00.mdio: mii_bus 8000f00.mdio couldn't get reset GPIO
    [    1.176818] am65-cpsw-nuss 8000000.ethernet: initializing am65 cpsw nuss version 0x6BA01103, cpsw version 0x6BA81103 Ports: 3 quirks:00000006
    [    1.177096] am65-cpsw-nuss 8000000.ethernet: Use random MAC address
    [    1.177106] am65-cpsw-nuss 8000000.ethernet: initialized cpsw ale version 1.5
    [    1.177112] am65-cpsw-nuss 8000000.ethernet: ALE Table size 512
    [    1.188936] pps pps0: new PPS source ptp0
    [    1.201239] am65-cpsw-nuss 8000000.ethernet: CPTS ver 0x4e8a010c, freq:500000000, add_val:1 pps:1
    [    1.221913] am65-cpsw-nuss 8000000.ethernet: set new flow-id-base 19
    [    1.244843] rtc-ti-k3 2b1f0000.rtc: registered as rtc1
    [    1.354398] mmc0: CQHCI version 5.10
    [    1.360108] davinci-mcasp 2b00000.mcasp: IRQ common not found
    [    1.360440] mmc1: CQHCI version 5.10
    [    1.385014] 2820000.serial: ttyS4 at MMIO 0x2820000 (irq = 31, base_baud = 3000000) is a 8250
    [    1.394812] 2840000.serial: ttyS5 at MMIO 0x2840000 (irq = 32, base_baud = 3000000) is a 8250
    [    1.398832] 2850000.serial: ttyS6 at MMIO 0x2850000 (irq = 33, base_baud = 3000000) is a 8250
    [    1.415486] davinci_mdio 8000f00.mdio: Configuring MDIO in manual mode
    [    1.427036] mmc1: SDHCI controller on fa00000.mmc [fa00000.mmc] using ADMA 64-bit
    [    1.427295] mmc0: SDHCI controller on fa10000.mmc [fa10000.mmc] using ADMA 64-bit
    [    1.449712] davinci_mdio 8000f00.mdio: davinci mdio revision 9.7, bus freq 1000000
    [    1.459552] davinci_mdio 8000f00.mdio: phy[0]: device 8000f00.mdio:00, driver TI DP83867
    [    1.459566] davinci_mdio 8000f00.mdio: phy[1]: device 8000f00.mdio:01, driver TI DP83867
    [    1.461738] debugfs: Directory 'pd:53' with parent 'pm_genpd' already present!
    [    1.461788] debugfs: Directory 'pd:52' with parent 'pm_genpd' already present!
    [    1.461826] debugfs: Directory 'pd:51' with parent 'pm_genpd' already present!
    [    1.462235] debugfs: Directory 'pd:182' with parent 'pm_genpd' already present!
    [    1.482091] input: poweroff-signal as /devices/platform/poweroff-signal/input/input0
    [    1.490427] input: gpio-keys as /devices/platform/gpio-keys/input/input1
    [    1.502308] ALSA device list:
    [    1.502319]   No soundcards found.
    [    1.521780] Waiting for root device PARTUUID=fbc97ce2-02...
    [    1.541097] mmc0: Command Queue Engine enabled
    [    1.541117] mmc0: new HS200 MMC card at address 0001
    [    1.551317] mmcblk0: mmc0:0001 88A398 7.28 GiB
    [    1.551518] mmcblk0boot0: mmc0:0001 88A398 partition 1 4.00 MiB
    [    1.551755] mmcblk0boot1: mmc0:0001 88A398 partition 2 4.00 MiB
    [    1.551942] mmcblk0rpmb: mmc0:0001 88A398 partition 3 4.00 MiB, chardev (237:0)
    [    1.553060]  mmcblk0: p1 p2
    [    1.596963] EXT4-fs (mmcblk0p2): recovery complete
    [    1.597219] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
    [    1.597303] VFS: Mounted root (ext4 filesystem) on device 179:2.
    [    1.611086] devtmpfs: mounted
    [    1.612416] Freeing unused kernel memory: 1792K
    [    1.612524] Run /sbin/init as init process
    [    1.649483] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
    Seeding 256 bits and crediting[    1.705861] random: crng init done
    
    Saving 256 bits of creditable seed for next boot
    Starting syslogd: OK
    Starting klogd: OK
    Running sysctl: OK
    Populating /dev using udev: [    1.800952] udevd[224]: starting version 3.2.11
    [    1.827636] udevd[225]: starting eudev-3.2.11
    [    2.165168] CAN device driver interface
    [    2.199129] at24 1-0051: supply vcc not found, using dummy regulator
    [    2.212739] at24 1-0051: 16384 byte 24c128 EEPROM, writable, 1 bytes/write
    [    2.223343] m_can_platform 20701000.can: m_can device registered (irq=48, version=32)
    [    2.421208] k3-m4-rproc 5000000.m4fss: assigned reserved memory node m4f-dma-memory@9cb00000
    [    2.430905] k3-m4-rproc 5000000.m4fss: configured M4 for remoteproc mode
    [    2.431288] k3-m4-rproc 5000000.m4fss: local reset is deasserted for device
    [    2.431545] remoteproc remoteproc0: 5000000.m4fss is available
    [    2.432639] remoteproc remoteproc0: Direct firmware load for am62-mcu-m4f0_0-fw failed with error -2
    [    2.432657] remoteproc remoteproc0: powering up 5000000.m4fss
    [    2.432688] remoteproc remoteproc0: Direct firmware load for am62-mcu-m4f0_0-fw failed with error -2
    [    2.432899] remoteproc remoteproc0: request_firmware failed: -2
    [    2.572600] platform 78000000.r5f: R5F core may have been powered on by a different host, programmed state (0) != actual state (1)
    [    2.584932] platform 78000000.r5f: configured R5F for IPC-only mode
    [    2.585231] platform 78000000.r5f: assigned reserved memory node r5f-dma-memory@9da00000
    [    2.587154] remoteproc remoteproc1: 78000000.r5f is available
    [    2.587263] remoteproc remoteproc1: attaching to 78000000.r5f
    [    2.618222] platform 78000000.r5f: R5F core initialized in IPC-only mode
    [    2.618260]  remoteproc1#vdev0buffer: assigned reserved memory node r5f-dma-memory@9da00000
    [    2.618414]  remoteproc1#vdev0buffer: registered virtio0 (type 7)
    [    2.618422] remoteproc remoteproc1: remote processor 78000000.r5f is now attached
    [    2.997062] cdns-csi2rx: probe of 30101000.csi-bridge failed with error -22
    [    3.048284] virtio_rpmsg_bus virtio0: creating channel ti.ipc4.ping-pong addr 0xd
    [    3.048466] virtio_rpmsg_bus virtio0: creating channel rpmsg_chrdev addr 0xe
    [    3.070632] virtio_rpmsg_bus virtio0: rpmsg host is online
    [    3.110737] rpmsg_client_sample virtio0.ti.ipc4.ping-pong.-1.13: new channel: 0x400 -> 0xd!
    [    3.198557] usbcore: registered new interface driver usbfs
    [    3.204849] usbcore: registered new interface driver hub
    [    3.205387] usbcore: registered new device driver usb
    [    3.253278] xhci-hcd xhci-hcd.1.auto: xHCI Host Controller
    [    3.253323] xhci-hcd xhci-hcd.1.auto: new USB bus registered, assigned bus number 1
    [    3.253564] xhci-hcd xhci-hcd.1.auto: hcc params 0x0258fe6d hci version 0x110 quirks 0x0000000000010010
    [    3.276773] xhci-hcd xhci-hcd.1.auto: irq 494, io mem 0x31100000
    [    3.276967] xhci-hcd xhci-hcd.1.auto: xHCI Host Controller
    [    3.276986] xhci-hcd xhci-hcd.1.auto: new USB bus registered, assigned bus number 2
    [    3.277007] xhci-hcd xhci-hcd.1.auto: Host supports USB 3.0 SuperSpeed
    done
    [    3.277163] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.10
    
    Version: modprobe_modules_1.1
    [    3.277171] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
    [    3.277177] usb usb1: Product: xHCI Host Controller
    [    3.277181] usb usb1: Manufacturer: Linux 5.10.168-rt83 xhci-hcd
    [    3.277185] usb usb1: SerialNumber: xhci-hcd.1.auto
    [    3.279074] hub 1-0:1.0: USB hub found
    [    3.279118] hub 1-0:1.0: 1 port detected
    [    3.281759] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
    [    3.281874] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.10
    [    3.281882] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
    [    3.281888] usb usb2: Product: xHCI Host Controller
    [    3.281893] usb usb2: Manufacturer: Linux 5.10.168-rt83 xhci-hcd
    [    3.281897] usb usb2: SerialNumber: xhci-hcd.1.auto
    [    3.283096] hub 2-0:1.0: USB hub found
    [    3.283141] hub 2-0:1.0: config failed, hub doesn't have any ports! (err -19)
    [    3.383479] usbcore: registered new interface driver smsc95xx
    [    3.515760] usb 1-1: new high-speed USB device number 2 using xhci-hcd
    [    3.641983] usb 1-1: New USB device found, idVendor=0424, idProduct=9e00, bcdDevice= 3.00
    [    3.642009] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
    [    3.649360] smsc95xx v2.0.0
    [    3.786340] SMSC LAN8710/LAN8720 usb-001:002:01: attached PHY driver [SMSC LAN8710/LAN8720] (mii_bus:phy_addr=usb-001:002:01, irq=POLL)
    [    3.789241] smsc95xx 1-1:1.0 eth2: register 'smsc95xx' at usb-xhci-hcd.1.auto-1, smsc95xx USB 2.0 Ethernet, e2:ff:97:3a:16:e7
    [    3.951662] NET: Registered protocol family 10
    [    3.957645] Segment Routing with IPv6
    [    4.081883] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
    [    4.108845] Bridge firewalling registered
    [    4.185269] Initializing XFRM netlink socket
    Mounting configfs...
    [    4.388258] using random self ethernet address
    [    4.388276] using random host ethernet address
    sh: write error: No such device
    [    4.409610] usb0: HOST MAC 1a:8d:d3:ad:31:ca
    [    4.409631] usb0: MAC 76:33:89:3c:76:7c
    USB Gadget g1 enabled successfully!
    Using eth0 MAC address as serial number: 98:03:8a:87:9d:a9
    Starting network: [    4.436541] am65-cpsw-nuss 8000000.ethernet LAN1: renamed from eth0
    [    4.457591] am65-cpsw-nuss 8000000.ethernet LAN2: renamed from eth1
    [    4.478685] smsc95xx 1-1:1.0 LAN3: renamed from eth2
    [    4.569473] am65-cpsw-nuss 8000000.ethernet LAN1: PHY [8000f00.mdio:00] driver [TI DP83867] (irq=POLL)
    [    4.569509] am65-cpsw-nuss 8000000.ethernet LAN1: configuring for phy/rgmii-rxid link mode
    [    4.644482] am65-cpsw-nuss 8000000.ethernet LAN2: PHY [8000f00.mdio:01] driver [TI DP83867] (irq=POLL)
    [    4.644515] am65-cpsw-nuss 8000000.ethernet LAN2: configuring for phy/rgmii-rxid link mode
    RTNETLINK answers: File exists
    RTNETLINK answers: File exists
    [    4.734213] smsc95xx 1-1:1.0 LAN3: Link is Down
    FAIL
    Starting sshd: OK
    Starting vsftpd: OK
    

    thanks

  • Hi, I am out of office today. I will review your update and get back to you tomorrow.

  • Hi Hao,

    U-Boot SPL 2021.01-g48e76495-dirty (Mar 11 2025 - 17:15:58 +0800)
    SYSFW ABI: 3.1 (firmware rev 0x0008 '8.6.4--v08.06.04 (Chill Capybar')

    The message above printed twice when the boot failed. It is probably related to the boot failure later. But I am not sure what would cause the message get printed twice, looks like the SoC gets reset after the first two lines in the log.

  • Hi bin

    U-Boot SPL 2021.01-g48e76495-dirty (Mar 11 2025 - 17:15:58 +0800)
    SYSFW ABI: 3.1 (firmware rev 0x0008 '8.6.4--v08.06.04 (Chill Capybar')

    Thank you for your reply. Our self-developed hardware platform exhibits this phenomenon during power-on startup. Therefore, I built a device that uses a relay to control the power-off and power-on operations of the device under test.

    This first printout was generated during the previous power-off operation. Due to a momentary fluctuation in the power supply, the SPL restarted once. However, it then waits 20 seconds before powering off and restarting again. As a result, there is a 20-second waiting period between the two outputs, which can be observed when reviewing each log print.

    Regarding the "Authentication" section — what operation is the AM62 performing here? Do you have any documentation on this that you could share so I can better understand the issue? I found from my own investigation that this error occurs due to a timeout during re-communication with the R5 at the boot stage. I'm wondering if my understanding is correct.

    regards

  • After the console prints "Trying to boot from MMC1", the R5 SPL loads the sw components in tispl.bin from eMMC (it appears you board boots from eMMC) to DDR and pass them to SYSFW for authentication. 

  • Hi bin

    My understanding is that the communication timeout generated by the hardware channel should be investigated. What should I focus on in my next investigation step?

    regards

  • Hi Hao,

    As mentioned above, I am unable to provide debug guidance for the old SDK v8.6 which is no longer supported.