AM6548: SBL doesn't load appimage from PDK example

Huichun Feng

Part Number: AM6548
Other Parts Discussed in Thread: AM625

Hi,

I'm on AM65x IDK, and the SD card has Linux-RT image flashed by using PROCESSOR-SDK-LINUX-RT-AM65X. The system boots, and then I move towarding my development, which is running application on the Cortex-R5F w/o the dependence of Linux. That is, the application is loaded by the SBL running on the Cortex-A53 (to reduce mission-critical app's downtime during reset).

I started from trying to load a LED blink example (in appimage format) in the u-boot prompt, I've tried the following:

Rename the example to "app" and place it to the directory where the boot partition lays (i.e., same directory as tiboot3.bin, sysfw, etc.). However, it seems that it doesn't load, the LED was not blinking even Linux has done booting.
Load and run the app in the SBL dones't work either. The .appimage blob was placed in the boot directory and loaded with fatload (@0x90000000). And the blob loading to the R5F was using command "rproc load 0 0x90000000 <blob_size>", but the load was rejected with error stating that it's not an ELF. This got me confused, according to the doc., SBL doesn't load ELF directly. Instead it loads the appimage format blob. Maybe the SBL prompt doesn't read appimage, and it's the SBL itself reads appimage?
Using same method as #2 except the blob is the .xer5f variant. The result is that I got error ""Error" handler, esr 0xbf000000..." and a coredump followed by a reset. Does this suggests that the .xer5f blob is not properly compiled? (The blob was acquired from within the Platform Development Kit)

None of them have led to a success load. Is there anything I misunderstantood?

Thanks!

7 months ago

0 Jianzhong Xu 7 months ago

TI__Mastermind 42565 points

Hello Huichun,

Huichun Feng said:
I started from trying to load a LED blink example

How did you build this example? Is it from the SDK? If so, which SDK and what's the version?

Thanks,

Jianzhong

0 Huichun Feng 7 months ago in reply to Jianzhong Xu

Prodigy 81 points

Hello Jianzhong,

The example is built from the PDK (Platform Development Kit) in the RTOS-SDK [0-1], and the version was 08_02_00_05.

The step to build the example is:

Navigate to directory "pdk_am65xx_08_02_00_21/packages/ti/drv/gpio"
Run "make BOARD=am65xx_idk GCC_LINUX_ARM_PATH=path/to/gcc-arm-9.2-2019.12-x86_64-aarch64-none-elf"
Copy the artifact ("pdk_am65xx_08_02_00_21/packages/ti/binary/GPIO_Baremetal_LedBlink_TestApp/bin/am65xx_idk/GPIO_Baremetal_LedBlink_TestApp_mcu1_0_release.xer5f") to the SD card.

Thanks!

[0] dr-download.ti.com/.../ti-processor-sdk-rtos-am65xx-evm-08_02_00_05-linux-x64-installer.run

[1] www.ti.com/.../AM6548

0 Huichun Feng 7 months ago

Prodigy 81 points

Per https://e2e.ti.com/support/processors-group/processors/f/processors-forum/1218263/am625-user-applications-on-r5f

I assume that application development guide on the R5F on AM65x is currently also at early stage, is it?

Thanks!

0 Huichun Feng 7 months ago

Prodigy 81 points

As for method #3, if the executable is copied from "/lib/firmware/am65x-mcu-r5f0_0-fw" (Linux-RT SDK rootfs), the manual load in SPL ("rproc load ...") would then be success. Is there any build step for the executable (/lib/firmware/am65x-mcu-r5f0_0-fw)?

Plus, does the load error ""Error" handler, esr 0xbf000000..." resulted from illegal CPU instruction?

Thanks!

0 Jianzhong Xu 7 months ago in reply to Huichun Feng

TI__Mastermind 42565 points

Hello Huichen,

Huichun Feng said:
The example is built from the PDK (Platform Development Kit) in the RTOS-SDK [0-1], and the version was 08_02_00_05.

We've migrated away from the TI-RTOS SDK to freeRTOS-based MCU+ SDK: https://www.ti.com/tool/PROCESSOR-SDK-AM65X#downloads.

Huichun Feng said:
Per https://e2e.ti.com/support/processors-group/processors/f/processors-forum/1218263/am625-user-applications-on-r5f

I assume that application development guide on the R5F on AM65x is currently also at early stage, is it?

That was for AM625, not AM65x.

Huichun Feng said:
As for method #3, if the executable is copied from "/lib/firmware/am65x-mcu-r5f0_0-fw" (Linux-RT SDK rootfs), the manual load in SPL ("rproc load ...") would then be success. Is there any build step for the executable (/lib/firmware/am65x-mcu-r5f0_0-fw)?

Plus, does the load error ""Error" handler, esr 0xbf000000..." resulted from illegal CPU instruction?

Can you try the MCU+ SDK for AM65x?

Thank you.

Jianzhong

0 Huichun Feng 7 months ago in reply to Jianzhong Xu

Prodigy 81 points

Hello Jianzhong,

> That was for AM625, not AM65x.

Sorry I didn't state clearly. Since AM625 and AM65x are somewhat similar, and there has no standalone execution guide for R5F, I was guessing that it's at early stage for AM65x.

> Can you try the MCU+ SDK for AM65x?

I've just tried the MCU SDK, the LED example works. However, if the example is loaded and ran (w/ "rproc start 0"), Linux would hang at boot. Is this because of the address overlapping? (The example was loaded at 0x90000000.)

Thanks!

Huichun

0 Jianzhong Xu 7 months ago in reply to Huichun Feng

TI__Mastermind 42565 points

Hello Huichun,

Thanks for trying the MCU+ SDK. Let me loop in our IPC expert for continuing help.

Regards,

Jianzhong

0 Nick Saulnier 7 months ago in reply to Jianzhong Xu

TI__Guru* 76425 points

FYI, potentially related thread here:

https://e2e.ti.com/support/processors-group/processors/f/processors-forum/1299308/am6548-spl-manual-r5-app-loading-doesn-t-seem-to-work

Regards,

Nick

0 Huichun Feng 7 months ago

Prodigy 81 points

Huichun Feng said:
I've just tried the MCU SDK, the LED example works. However, if the example is loaded and ran (w/ "rproc start 0"), Linux would hang at boot. Is this because of the address overlapping? (The example was loaded at 0x90000000.)

Per e2e.ti.com/.../faq-tda4vm-application-in-the-psdkra-runs-standalone-from-sbl-but-doesn-t-run-alongside-linux

It seems that, when running R5 app alongside Linux, one should take care of topics such as resource conflict.

Is there any documented guides on this for AM65x or similar models?

Thanks!

Huichun

0 Nick Saulnier 7 months ago in reply to Huichun Feng

TI__Guru* 76425 points

Hello Huichun,

You do need to make sure you understand which core is using which region of memory.

We will not create an AM65x Academy, but the AM62Ax Academy multicore module is useful for learning the concepts of getting Linux and RTOS running on the same processor:
https://dev.ti.com/tirex/explore/node?node=A__AS4TbXvv.-OIrnxuMySmZg__AM62A-ACADEMY__WeZ9SsL__LATEST

Can you provide the terminal output for where Linux is hanging?

Regards,

Nick

0 Huichun Feng 7 months ago in reply to Nick Saulnier

Prodigy 81 points

Nick Saulnier said:
We will not create an AM65x Academy, but the AM62Ax Academy multicore module is useful for learning the concepts of getting Linux and RTOS running on the same processor:
https://dev.ti.com/tirex/explore/node?node=A__AS4TbXvv.-OIrnxuMySmZg__AM62A-ACADEMY__WeZ9SsL__LATEST

Got it, thanks for this!

Nick Saulnier said:
Can you provide the terminal output for where Linux is hanging?

[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034]
[    0.000000] Linux version 5.10.168-rt83-gd14fbab5cc (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 Sat Mar 25 15:39:18 UTC 2023
[    0.000000] Machine model: Texas Instruments AM654 Base Board
[    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 0x00000000a0000000, size 1 MiB
[    0.000000] OF: reserved mem: initialized node r5f-dma-memory@a0000000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a0100000, size 15 MiB
[    0.000000] OF: reserved mem: initialized node r5f-memory@a0100000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a1000000, size 1 MiB
[    0.000000] OF: reserved mem: initialized node r5f-dma-memory@a1000000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a1100000, size 15 MiB
[    0.000000] OF: reserved mem: initialized node r5f-memory@a1100000, compatible id shared-dma-pool
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000080000000-0x00000000ffffffff]
[    0.000000]   DMA32    empty
[    0.000000]   Normal   [mem 0x0000000100000000-0x00000008ffffffff]
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000080000000-0x000000009e7fffff]
[    0.000000]   node   0: [mem 0x000000009e800000-0x00000000a20fffff]
[    0.000000]   node   0: [mem 0x00000000a2100000-0x00000000ffffffff]
[    0.000000]   node   0: [mem 0x0000000880000000-0x00000008ffffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000080000000-0x00000008ffffffff]
[    0.000000] On node 0 totalpages: 1048576
[    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]   Normal zone: 8192 pages used for memmap
[    0.000000]   Normal zone: 524288 pages, LIFO batch:63
[    0.000000] cma: Reserved 512 MiB at 0x00000000e0000000
[    0.000000] psci: probing for conduit method from DT.

There is no panic message, it just hangs there.

Thanks!

Huichun

0 Nick Saulnier 7 months ago in reply to Huichun Feng

TI__Guru* 76425 points

Hello Huichun,

I am going to send your thread over to a team member more familiar with Linux boot to see if they have any ideas.

Assuming AM65x has similar uboot uenv veriables as AM64x, I would not expect the address in DDR that the R5F binary is temporarily placed before it is loaded to cause issues.
kernel_addr_r=0x82000000
fdtaddr=0x88000000
fdtoverlay_addr_r=0x89000000 <-- even this is 16MB before 0x9000_0000

You could potentially test to see if you see the same behavior when loading to the R5F core 1? If we assume that the boot flow for AM65x is similar to AM64x, it shouldn't matter if we load software into R5F core 0, because I would expect that all the important ROM and SPL stuff running on the R5F core 0 should have already executed and exited before uboot even starts. But I could be missing something here.
AM64x bootflow here:
https://software-dl.ti.com/processor-sdk-linux/esd/AM64X/09_00_00_03/exports/docs/linux/Foundational_Components/U-Boot/UG-General-Info.html#boot-flow

Regards,

Nick

0 Huichun Feng 7 months ago in reply to Nick Saulnier

Prodigy 81 points

Hello Nick,

Nick Saulnier said:
Assuming AM65x has similar uboot uenv veriables as AM64x, I would not expect the address in DDR that the R5F binary is temporarily placed before it is loaded to cause issues.
kernel_addr_r=0x82000000
fdtaddr=0x88000000
fdtoverlay_addr_r=0x89000000 <-- even this is 16MB before 0x9000_0000

You could potentially test to see if you see the same behavior when loading to the R5F core 1?

Good point. But it seems that I must load and start the R5F core 0 prior to do loading/start on the R5F core 1. As I got the following:

=> rproc load 1 0x90000000 0x${filesize}
k3_r5f_rproc r5f@41400000: k3_r5f_load addr = 0x90000000, size = 0x65b14
k3_r5f_rproc r5f@41400000: Invalid seq: Enable primary core before loading secondary core
Load Remote Processor 1 with data@addr=0x90000000 416532 bytes: Failed!

Is there any way to circumvent this?

Plus, I found that Linux boot can hang by only loading R5 app (@0x90000000). That is, "rproc start 0" is not necessary to hang the Linux boot. Moreover, simply doing "fatload mmc 1 0x90000000 hello_world.debug.out" and starting booting (w/ command "boot") doesn't incur the hanging issue.

Thanks!

Huichun

0 Huichun Feng 7 months ago in reply to Huichun Feng

Prodigy 81 points

I'm suspecting that the matter is where does "rproc load 0" loads the ELF to, because the kernel still boots even fatload loads the app to 0x80000000. Is there an address which can hang the kernel boot?

I'm checking where exactly the rproc program loads the ELF to.

Please correct me if there's anything I misunderstand.

Thanks!

Huichun

+1 Nick Saulnier 7 months ago in reply to Huichun Feng

TI__Guru* 76425 points

Hello Huichun,

Ok, I've grabbed your thread back for now after taking another look at the memory allocations.

Some unorganized notes

First, you are correct that R5F core0 must be powered on before core1. Now, I do not think that is the issue here.

That is interesting that the hello world program does not cause a boot hang issue. I do not totally understand why that would work... We may need to circle back to this if the next test does not work.

From my team member:
"the last line of the kernel message is
psci: probing for conduit method from DT.
I think the next line should be psci related message too, so this seems indicating psci client failed to talk to SYSFW
somehow the communication between kernel and sysfw is crashed"

My current hypothesis: memory allocation conflict in SRAM

We can see the R5F example's memory allocation in the linker.cmd file. If we look at
examples/drivers/gpio/gpio_led_blink/am65x-evm/r5fss0-0_freertos/ti-arm-clang/linker.cmd,
we can see that a bunch of data is getting allocated to MSRAM, which is defined as

MEMORY
{
...
    MSRAM     : ORIGIN = 0x70000000 , LENGTH = 0x40000

And if we build the example and check the .map file, we can verify that both initialized and non-initialized data is actually getting placed in that memory region:

I'm doing this in Linux

$ make -s -C examples/drivers/gpio/gpio_led_blink/am65x-evm/r5fss0-0_freertos/ti-arm-clang/
$ vi examples/drivers/gpio/gpio_led_blink/am65x-evm/r5fss0-0_freertos/ti-arm-clang/gpio_led_blink.release.map

MEMORY CONFIGURATION

         name            origin    length      used     unused   attr    fill
----------------------  --------  ---------  --------  --------  ----  --------
  R5F_VECS              00000000   00000040  00000040  00000000  RWIX
  R5F_TCMA              00000040   00007fc0  00000000  00007fc0  RWIX
  R5F_TCMB0             41010000   00008000  00000000  00008000  RWIX
  NON_CACHE_MEM         41c70000   00008000  00000000  00008000  RWIX
  FLASH                 60100000   00080000  00000000  00080000  RWIX
  MSRAM                 70000000   00040000  0001dd90  00022270  RWIX

But that conflicts with the AM65x SRAM memory allocation.
check /board-support/ti-linux-kernel/arch/arm64/boot/dts/ti/k3-am65-main.dtsi
(if using Linux SDK 8.6 or earlier, the "ti-linux-kernel" will be "linux-x.x.x" instead)

        msmc_ram: sram@70000000 {
                compatible = "mmio-sram";
                reg = <0x0 0x70000000 0x0 0x200000>;
                #address-cells = <1>;
                #size-cells = <1>;
                ranges = <0x0 0x0 0x70000000 0x200000>;

                atf-sram@0 {
                        reg = <0x0 0x20000>;
                };

                sysfw-sram@f0000 {
                        reg = <0xf0000 0x10000>;
                };

                l3cache-sram@100000 {
                        reg = <0x100000 0x100000>;
                };
        };

Please try adjusting the linker.cmd file and re-building the example

If you move the MCU+ LED example to place MSRAM data between offsets 0x2_0000 and 0xF_0000, does Linux successfully boot?

Like this:

examples/drivers/gpio/gpio_led_blink/am65x-evm/r5fss0-0_freertos/ti-arm-clang/linker.cmd

MEMORY
{
...
    MSRAM     : ORIGIN = 0x70020000 , LENGTH = 0x40000

Regards,

Nick

0 Huichun Feng 7 months ago in reply to Nick Saulnier

Prodigy 81 points

Hello Nick,

Seems it's true that there's a conflict in SRAM allocation. Just tried replacing the loading address of the ELF with 0x7002_0000, Linux boots successfully then.

However, there would be a kernel oops and the boot get stucked if the R5 was still running when issuing command "boot" in u-boot. Following is the full log:

=> fatload mmc 1 0x90000000 i2c_led_blink.release.out
686156 bytes read in 37 ms (17.7 MiB/s)
=> rproc load 0 0x90000000 0x${filesize}
k3_r5f_rproc r5f@41000000: k3_r5f_load addr = 0x90000000, size = 0xa784c
k3_r5f_rproc r5f@41000000: k3_r5f_prepare
k3_r5f_rproc r5f@41000000: k3_r5f_split_release
k3_r5f_rproc r5f@41000000: k3_r5f_da_to_va
k3_r5f_rproc r5f@41000000: Loading phdr 0 to 0x0000000041000000 (64 bytes)
k3_r5f_rproc r5f@41000000: k3_r5f_da_to_va
k3_r5f_rproc r5f@41000000: Loading phdr 1 to 0x0000000070020000 (0 bytes)
k3_r5f_rproc r5f@41000000: k3_r5f_da_to_va
k3_r5f_rproc r5f@41000000: Loading phdr 2 to 0x0000000070033320 (53392 bytes)
k3_r5f_rproc r5f@41000000: k3_r5f_da_to_va
k3_r5f_rproc r5f@41000000: Loading phdr 3 to 0x00000000700403b0 (2296 bytes)
k3_r5f_rproc r5f@41000000: k3_r5f_da_to_va
k3_r5f_rproc r5f@41000000: Loading phdr 4 to 0x0000000070040ca8 (0 bytes)
k3_r5f_rproc r5f@41000000: k3_r5f_load: Boot vector = 0x0
Load Remote Processor 0 with data@addr=0x90000000 686156 bytes: Success!
=> rproc start 0
k3_r5f_rproc r5f@41000000: k3_r5f_start
=> boot
switch to partitions #0, OK
mmc1 is current device
SD/MMC found on device 1
Failed to load 'boot.scr'
1490 bytes read in 4 ms (363.3 KiB/s)
Loaded env from uEnv.txt
Importing environment from mmc1 ...
Running uenvcmd ...
1 bytes read in 3 ms (0 Bytes/s)
Already setup.
18862592 bytes read in 789 ms (22.8 MiB/s)
64327 bytes read in 7 ms (8.8 MiB/s)
11268 bytes read in 4 ms (2.7 MiB/s)
2333 bytes read in 4 ms (569.3 KiB/s)
## Flattened Device Tree blob at 88000000
   Booting using the fdt blob at 0x88000000
   Loading Device Tree to 000000008feed000, 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
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-gd14fbab5cc (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 Sat Mar 25 15:39:18 UTC 2023
[    0.000000] Machine model: Texas Instruments AM654 Base Board
[    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 0x00000000a0000000, size 1 MiB
[    0.000000] OF: reserved mem: initialized node r5f-dma-memory@a0000000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a0100000, size 15 MiB
[    0.000000] OF: reserved mem: initialized node r5f-memory@a0100000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a1000000, size 1 MiB
[    0.000000] OF: reserved mem: initialized node r5f-dma-memory@a1000000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a1100000, size 15 MiB
[    0.000000] OF: reserved mem: initialized node r5f-memory@a1100000, compatible id shared-dma-pool
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000080000000-0x00000000ffffffff]
[    0.000000]   DMA32    empty
[    0.000000]   Normal   [mem 0x0000000100000000-0x00000008ffffffff]
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000080000000-0x000000009e7fffff]
[    0.000000]   node   0: [mem 0x000000009e800000-0x00000000a20fffff]
[    0.000000]   node   0: [mem 0x00000000a2100000-0x00000000ffffffff]
[    0.000000]   node   0: [mem 0x0000000880000000-0x00000008ffffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000080000000-0x00000008ffffffff]
[    0.000000] On node 0 totalpages: 1048576
[    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]   Normal zone: 8192 pages used for memmap
[    0.000000]   Normal zone: 524288 pages, LIFO batch:63
[    0.000000] cma: Reserved 512 MiB at 0x00000000e0000000
[    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 s35392 r8192 d30144 u73728
[    0.000000] pcpu-alloc: s35392 r8192 d30144 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: 1032192
[    0.000000] Kernel command line: console=ttyS2,115200n8 earlycon=ns16550a,mmio32,0x02800000 mtdparts=47040000.spi.0:512k(ospi.tiboot3),2m(ospi.tispl),4m(ospi.u-boot),128k(ospi.env),128k(ospi.env.backup),1m(ospi.sysfw),57216k@8m(ospi.rootfs),128k(ospi.phypattern) root=PARTUUID=63ecb215-02 rw rootfstype=ext4 rootwait
[    0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)
[    0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)
[    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
[    0.000000] software IO TLB: mapped [mem 0x00000000dc000000-0x00000000e0000000] (64MB)
[    0.000000] Memory: 3446424K/4194304K available (11008K kernel code, 1204K rwdata, 4272K rodata, 1792K init, 438K bss, 223592K 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] 	Tracing variant of Tasks RCU enabled.
[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4
[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
[    0.000000] GICv3: GIC: Using split EOI/Deactivate mode
[    0.000000] GICv3: 960 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 @880800000 (flat, esz 8, psz 64K, shr 0)
[    0.000000] ITS: using cache flushing for cmd queue
[    0.000000] GICv3: using LPI property table @0x0000000880030000
[    0.000000] GIC: using cache flushing for LPI property table
[    0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000880040000
[    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.000001] sched_clock: 56 bits at 200MHz, resolution 5ns, wraps every 4398046511102ns
[    0.008755] Console: colour dummy device 80x25
[    0.013378] Calibrating delay loop (skipped), value calculated using timer frequency.. 400.00 BogoMIPS (lpj=800000)
[    0.024101] pid_max: default: 32768 minimum: 301
[    0.028982] LSM: Security Framework initializing
[    0.033829] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
[    0.041453] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
[    0.052017] rcu: Hierarchical SRCU implementation.
[    0.057682] Platform MSI: msi-controller@1820000 domain created
[    0.064131] PCI/MSI: /bus@100000/interrupt-controller@1800000/msi-controller@1820000 domain created
[    0.073586] EFI services will not be available.
[    0.078589] smp: Bringing up secondary CPUs ...
I/TC: Secondary CPU 1 initializing
I/TC: Secondary CPU 1 switching to normal world boot
[    0.092641] Detected VIPT I-cache on CPU1
[    0.096792] GICv3: CPU1: found redistributor 1 region 0:0x00000000018a0000
[    0.103856] GICv3: CPU1: using allocated LPI pending table @0x0000000880050000
[    0.111324] CPU1: Booted secondary processor 0x0000000001 [0x410fd034]
I/TC: Secondary CPU 2 initializing
I/TC: Secondary CPU 2 switching to normal world boot
[    0.127649] Detected VIPT I-cache on CPU2
[    0.131815] GICv3: CPU2: found redistributor 100 region 0:0x00000000018c0000
[    0.139057] GICv3: CPU2: using allocated LPI pending table @0x0000000880060000
[    0.146523] CPU2: Booted secondary processor 0x0000000100 [0x410fd034]
I/TC: Secondary CPU 3 initializing
I/TC: Secondary CPU 3 switching to normal world boot
[    0.162734] Detected VIPT I-cache on CPU3
[    0.166868] GICv3: CPU3: found redistributor 101 region 0:0x00000000018e0000
[    0.174106] GICv3: CPU3: using allocated LPI pending table @0x0000000880070000
[    0.181547] CPU3: Booted secondary processor 0x0000000101 [0x410fd034]
[    0.188416] smp: Brought up 1 node, 4 CPUs
[    0.192651] SMP: Total of 4 processors activated.
[    0.197500] CPU features: detected: 32-bit EL0 Support
[    0.202791] CPU features: detected: CRC32 instructions
[    0.223459] CPU: All CPU(s) started at EL2
[    0.227714] alternatives: patching kernel code
[    0.233920] devtmpfs: initialized
[    0.251061] KASLR disabled due to lack of seed
[    0.255856] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.265876] futex hash table entries: 1024 (order: 5, 131072 bytes, linear)
[    0.291226] pinctrl core: initialized pinctrl subsystem
[    0.297347] DMI not present or invalid.
[    0.302223] NET: Registered protocol family 16
[    0.309047] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations
[    0.316547] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
[    0.324905] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
[    0.333795] thermal_sys: Registered thermal governor 'step_wise'
[    0.334566] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
[    0.347825] ASID allocator initialised with 65536 entries
[    0.404857] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages
[    0.411796] HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages
[    0.418714] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[    0.425608] HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages
[    0.434283] cryptd: max_cpu_qlen set to 1000
[    0.443821] k3-chipinfo 43000014.chipid: Family:AM65X rev:SR2.0 JTAGID[0x1bb5a02f] Detected
[    0.453220] vcc3v3_io: supplied by evm_12v0
[    0.459266] iommu: Default domain type: Translated 
[    0.464824] SCSI subsystem initialized
[    0.469483] mc: Linux media interface: v0.10
[    0.473935] videodev: Linux video capture interface: v2.00
[    0.479683] pps_core: LinuxPPS API ver. 1 registered
[    0.484795] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.494204] PTP clock support registered
[    0.498293] EDAC MC: Ver: 3.0.0
[    0.503013] FPGA manager framework
[    0.506665] Advanced Linux Sound Architecture Driver Initialized.
[    0.514162] clocksource: Switched to clocksource arch_sys_counter
[    0.520706] VFS: Disk quotas dquot_6.6.0
[    0.524831] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    0.540818] NET: Registered protocol family 2
[    0.545715] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)
[    0.556023] tcp_listen_portaddr_hash hash table entries: 2048 (order: 5, 131072 bytes, linear)
[    0.565056] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)
[    0.573602] TCP bind hash table entries: 32768 (order: 8, 1835008 bytes, linear)
[    0.583194] TCP: Hash tables configured (established 32768 bind 32768)
[    0.590318] UDP hash table entries: 2048 (order: 6, 262144 bytes, linear)
[    0.597642] UDP-Lite hash table entries: 2048 (order: 6, 262144 bytes, linear)
[    0.605615] NET: Registered protocol family 1
[    0.611108] RPC: Registered named UNIX socket transport module.
[    0.617301] RPC: Registered udp transport module.
[    0.622136] RPC: Registered tcp transport module.
[    0.626967] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.633590] NET: Registered protocol family 44
[    0.638170] PCI: CLS 0 bytes, default 64
[    0.643485] hw perfevents: enabled with armv8_cortex_a53 PMU driver, 7 counters available
[    0.658301] Initialise system trusted keyrings
[    0.663162] workingset: timestamp_bits=46 max_order=20 bucket_order=0
[    0.676774] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.683608] NFS: Registering the id_resolver key type
[    0.688879] Key type id_resolver registered
[    0.693191] Key type id_legacy registered
[    0.697406] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    0.704293] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
[    0.712176] 9p: Installing v9fs 9p2000 file system support
[    0.772475] Key type asymmetric registered
[    0.776731] Asymmetric key parser 'x509' registered
[    0.781809] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 243)
[    0.789424] io scheduler mq-deadline registered
[    0.794094] io scheduler kyber registered
[    0.801975] pinctrl-single 4301c000.pinctrl: 70 pins, size 280
[    0.808767] pinctrl-single 11c000.pinctrl: 185 pins, size 740
[    0.814871] pinctrl-single 11c2e8.pinctrl: 9 pins, size 36
[    0.834604] Serial: 8250/16550 driver, 10 ports, IRQ sharing enabled
[    0.863575] brd: module loaded
[    0.877846] loop: module loaded
[    0.882477] megasas: 07.714.04.00-rc1
[    0.892723] tun: Universal TUN/TAP device driver, 1.6
[    0.898943] igbvf: Intel(R) Gigabit Virtual Function Network Driver
[    0.905412] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.
[    0.911583] sky2: driver version 1.30
[    0.917054] VFIO - User Level meta-driver version: 0.3
[    0.923827] i2c /dev entries driver
[    0.929502] sdhci: Secure Digital Host Controller Interface driver
[    0.935872] sdhci: Copyright(c) Pierre Ossman
[    0.941190] sdhci-pltfm: SDHCI platform and OF driver helper
[    0.948971] ti-sci bus@100000:bus@28380000:bus@42040000:dmsc: invalid resource
[    0.956676] SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping ....
[    0.965539] optee: probing for conduit method.
I/TC: Reserved shared memory is enabled
I/TC: Dynamic shared memory is enabled
I/TC: Normal World virtualization support is disabled
I/TC: Asynchronous notifications are disabled
[    0.970141] optee: revision 3.20 (8e74d476)
[    0.986837] optee: dynamic shared memory is enabled
[    0.996674] optee: initialized driver
[    1.003557] NET: Registered protocol family 17
[    1.008377] 9pnet: Installing 9P2000 support
[    1.012902] Key type dns_resolver registered
[    1.017827] printk: bootconsole [ns16550a0]: printing thread started
[    1.017910] Loading compiled-in X.509 certificates
[    1.040797] ti-sci bus@100000:bus@28380000:bus@42040000:dmsc: invalid resource
[    1.041843] ti-sci bus@100000:bus@28380000:bus@42040000:dmsc: lpm region is required for suspend but not provided.
[    1.041959] ti-sci bus@100000:bus@28380000:bus@42040000:dmsc: ABI: 3.1 (firmware rev 0x0008 '8.6.5--v08.06.05 (Chill Capybar')
[    1.136820] omap_i2c 42120000.i2c: bus 0 rev0.12 at 400 kHz
[    1.138340] omap_i2c 40b00000.i2c: bus 1 rev0.12 at 100 kHz
[    1.140632] pca953x 2-0021: supply vcc not found, using dummy regulator
[    1.140804] pca953x 2-0021: using no AI
[    2.166209] omap_i2c 2000000.i2c: controller timed out
[    2.166227] pca953x 2-0021: failed writing register
[    2.166400] pca953x: probe of 2-0021 failed with error -110
[    2.166559] omap_i2c 2000000.i2c: bus 2 rev0.12 at 400 kHz
;H�� 2.167842] omap_i2c 2010000.i2c: bus 3 rev0.12 at 400 kHz
[    2.168987] omap_i2c 2020000.i2c: bus 4 rev0.12 at 400 kHz
[    2.197911] printk: console [ttyS2]: printing thread started
[    2.170084] omap_i2c 2030000.i2c: bus 5 rev0.12 at 100 kHz
[    2.197938] printk: console [ttyS2] enabled
[    2.197944] printk: bootconsole [ns16550a0] disabled
[    2.214907] printk: bootconsole [ns16550a0]: printing thread stopped
[    2.216209] 2810000.serial: ttyS3 at MMIO 0x2810000 (irq = 30, base_baud = 3000000) is a 8250
[    2.217254] 2820000.serial: ttyS4 at MMIO 0x2820000 (irq = 31, base_baud = 3000000) is a 8250
[    2.235476] davinci_mdio 46000f00.mdio: Configuring MDIO in manual mode
[    2.274196] davinci_mdio 46000f00.mdio: davinci mdio revision 9.7, bus freq 1000000
[    2.276473] davinci_mdio 46000f00.mdio: phy[0]: device 46000f00.mdio:00, driver TI DP83867
[    2.276599] am65-cpsw-nuss 46000000.ethernet: initializing am65 cpsw nuss version 0x6BA00102, cpsw version 0x6BA80102 Ports: 2 quirks:00000000
[    2.276741] am65-cpsw-nuss 46000000.ethernet: initialized cpsw ale version 1.4
[    2.276751] am65-cpsw-nuss 46000000.ethernet: ALE Table size 64
[    2.278056] am65-cpsw-nuss 46000000.ethernet: CPTS ver 0x4e8a010b, freq:200000000, add_val:4 pps:0
[    2.282280] am65-cpts 310d0000.cpts: CPTS ver 0x4e8a010b, freq:200000000, add_val:4 pps:0
[    2.286671] mmc0: CQHCI version 5.10
[    2.301009] pca953x 0-0039: supply vcc not found, using dummy regulator
[    2.301225] pca953x 0-0039: using no AI
[    2.324062] omap-mailbox 31f80000.mailbox: omap mailbox rev 0x66fc7100
[    2.324651] omap-mailbox 31f81000.mailbox: omap mailbox rev 0x66fc7100
[    2.328293] mmc0: SDHCI controller on 4f80000.mmc [4f80000.mmc] using ADMA 64-bit
[    2.330400] ti-udma 285c0000.dma-controller: Channels: 28 (tchan: 14, rchan: 14, gp-rflow: 16)
[    2.337945] ti-udma 31150000.dma-controller: Channels: 119 (tchan: 53, rchan: 66, gp-rflow: 64)
[    2.351876] spi-nor spi7.0: unrecognized JEDEC id bytes: 00 00 00 00 00 00
[    2.351913] spi-nor: probe of spi7.0 failed with error -2
[    2.363797] spi-nor spi9.0: unrecognized JEDEC id bytes: 00 00 00 00 00 00
[    2.363831] spi-nor: probe of spi9.0 failed with error -2
[    2.366404] davinci_mdio 46000f00.mdio: Configuring MDIO in manual mode
[    2.406187] davinci_mdio 46000f00.mdio: davinci mdio revision 9.7, bus freq 1000000
[    2.408398] davinci_mdio 46000f00.mdio: phy[0]: device 46000f00.mdio:00, driver TI DP83867
[    2.408529] am65-cpsw-nuss 46000000.ethernet: initializing am65 cpsw nuss version 0x6BA00102, cpsw version 0x6BA80102 Ports: 2 quirks:00000000
[    2.408674] am65-cpsw-nuss 46000000.ethernet: initialized cpsw ale version 1.4
[    2.408685] am65-cpsw-nuss 46000000.ethernet: ALE Table size 64
    2.409684] am65-cpsw-nuss 46000000.ethernet: CPTS ver 0x4e8a010b, freq:200000000, add_val:4 pps:0
[    2.412224] am65-cpsw-nuss 46000000.ethernet: set new flow-id-base 48
[    2.416595] vdd_mmc1_sd: supplied by vcc3v3_io
[    2.419833] mmc0: Command Queue Engine enabled
[    2.419857] mmc0: new HS200 MMC card at address 0001
[    2.420729] mmcblk0: mmc0:0001 S0J56X 14.8 GiB 
[    2.420890] mmc1: CQHCI version 5.10
[    2.420994] mmcblk0boot0: mmc0:0001 S0J56X partition 1 31.5 MiB
[    2.421260] mmcblk0boot1: mmc0:0001 S0J56X partition 2 31.5 MiB
[    2.421497] mmcblk0rpmb: mmc0:0001 S0J56X partition 3 4.00 MiB, chardev (237:0)
[    2.425150] debugfs: Directory 'pd:121' with parent 'pm_genpd' already present!
[    2.425221] debugfs: Directory 'pd:120' with parent 'pm_genpd' already present!
[    2.429114] input: gpio-keys as /devices/platform/gpio-keys/input/input0
[    2.453190] ALSA device list:
[    2.453205]   No soundcards found.
[    3.356228] mmc1: SDHCI controller on 4fa0000.mmc [4fa0000.mmc] using ADMA 64-bit
[    3.356865] Waiting for root device PARTUUID=63ecb215-02...
[    3.413337] mmc1: new ultra high speed SDR104 SDHC card at address aaaa
[    3.414232] mmcblk1: mmc1:aaaa SC16G 14.8 GiB 
[    3.418962]  mmcblk1: p1 p2
[    3.539230] EXT4-fs (mmcblk1p2): recovery complete
[    3.540198] EXT4-fs (mmcblk1p2): mounted filesystem with ordered data mode. Opts: (null)
[    3.540323] VFS: Mounted root (ext4 filesystem) on device 179:98.
[    3.543841] devtmpfs: mounted
[    3.545220] Freeing unused kernel memory: 1792K
[    3.545392] Run /sbin/init as init process
[    3.720376] systemd[1]: System time before build time, advancing clock.
[    3.781183] NET: Registered protocol family 10
[    3.782927] Segment Routing with IPv6
[    3.813294] 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)
[    3.813941] systemd[1]: Detected architecture arm64.

Welcome to [1mArago 2021.09[0m!

[    3.869126] systemd[1]: Set hostname to <am65xx-evm>.
[    4.217858] systemd[1]: /lib/systemd/system/irqbalanced.service:6: Unknown key name 'ConditionCPUs' in section 'Unit', ignoring.
[    4.252136] 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.
[    4.367019] random: systemd: uninitialized urandom read (16 bytes read)
[    4.372484] systemd[1]: Created slice system-getty.slice.
[[0;32m  OK  [0m] Created slice [0;1;39msystem-getty.slice[0m.
[[0;32m  OK  [0m] Created slice [0;1;39msystem-serial\x2dgett[    4.398384] random: systemd: uninitialized urandom read (16 bytes read)
y.slice[0m.
[    4.400827] systemd[1]: Created slice system-serial\x2dgetty.slice.
[    4.421001] random: systemd: uninitialized urandom read (16 bytes read)
[    4.423245] systemd[1]: Created slice User and Session Slice.
[[0;32m  OK  [0m] Created slice [0;1;39mUser and Session Slice[0m.
[[0;32m  OK  [0m] Started [0;1;39mDispatch Password …ts to [    4.450741] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
Console Directory Watch[0m.
[[0;32m  OK  [0m] Started [0;1;39mForward Password R…uests [    4.474601] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
to Wall Directory Watch[0m.
[[0;32m  OK  [0m] Reached target [0;1;39mPaths[0m.
[    4.498550] systemd[1]: Reached target Paths.
[[0;32m  OK  [0m] Reached target [0;1;39mRemote File Systems[    4.510380] systemd[1]: Reached target Remote File Systems.
[0m.
[[0;32m  OK  [0m] Reached target [0;1;39mSlices[0m.
[    4.530364] systemd[1]: Reached target Slices.
[[0;32m  OK  [0m] Reached target [0;1;39mSwap[0m.[    4.542369] systemd[1]: Reached target Swap.

[    4.589915] systemd[1]: Listening on RPCbind Server Activation Socket.
[[0;32m  OK  [0m] Listening on [0;1;39mRPCbind Server Activation Socket[0m.
[[0;32m  OK  [0m] Reached target [0;1;39mRPC Port Mapper[0m.[    4.614497] systemd[1]: Reached target RPC Port Mapper.

[[0;32m  OK  [0m] Listening on [0;1;39mProcess Core Dump Sock[    4.643678] systemd[1]: Listening on Process Core Dump Socket.
et[0m.
[[0;32m  OK  [0m] Listening on [0;1;39minitctl Compatibility [    4.666766] systemd[1]: Listening on initctl Compatibility Named Pipe.
Named Pipe[0m.
[[0;32m  OK  [0m] Listening on [0;1;39mJournal Socket (/dev/l[    4.694942] systemd[1]: Condition check resulted in Journal Audit Socket being skipped.
og)[0m.
[    4.695810] systemd[1]: Listening on Journal Socket (/dev/log).
[[0;32m  OK  [0m] Listening on [0;1;39mJournal Socket[0m.
[    4.717298] systemd[1]: Listening on Journal Socket.
[[0;32m  OK  [0m] Listening on [0;1;39mNetwork Service Netlin[    4.729030] systemd[1]: Listening on Network Service Netlink Socket.
k Socket[0m.
[[0;32m  OK  [0m] Listening on [0;1;39mudev Control Socket[0[    4.750996] systemd[1]: Listening on udev Control Socket.
m.
[[0;32m  OK  [0m] Listening on [0;1;39mudev Kernel Socket[0m[    4.774738] systemd[1]: Listening on udev Kernel Socket.
.
         Mounting [0;1;39mHuge Pages File System[0m...
[    4.800824] systemd[1]: Mounting Huge Pages File System...
         Mounting [0;1;39mPOSIX Message Queue File System[0m..[    4.818696] systemd[1]: Mounting POSIX Message Queue File System...
.
         Mounting [0;1;39mKernel Debug File System[0m...[    4.848901] systemd[1]: Mounting Kernel Debug File System...

         Mounting [0;1;39mTemporary Directory (/tmp)[0m...
[    4.879217] systemd[1]: Mounting Temporary Directory (/tmp)...
         Starting [0;1;39mCreate list of st…odes for the curr[    4.901145] systemd[1]: Starting Create list of static device nodes for the current kernel...
ent kernel[0m...
         Starting [0;1;39mStart psplash boot splash screen[0m.[    4.936989] systemd[1]: Starting Start psplash boot splash screen...
..
         Starting [0;1;39mRPC Bind[0m...
[    4.964881] systemd[1]: Starting RPC Bind...
[    4.974695] systemd[1]: Condition check resulted in File System Check on Root Device being skipped.
[    4.983765] systemd[1]: Starting Journal Service...
         Starting [0;1;39mJournal Service[0m...
         Starting [0;1;39mLoad Kernel Modules[0m...
[    5.011298] systemd[1]: Starting Load Kernel Modules...
         Starting [0;1;39mRemount Root and Kernel File Systems[    5.029150] systemd[1]: Starting Remount Root and Kernel File Systems...
[    5.041363] cryptodev: loading out-of-tree module taints kernel.
[    5.043871] cryptodev: driver 1.10 loaded.
[0m...
[    5.056963] EXT4-fs (mmcblk1p2): re-mounted. Opts: (null)
[    5.065956] systemd[1]: Starting udev Coldplug all Devices...
         Starting [0;1;39mudev Coldplug all Devices[0m...
[[0;32m  OK  [0m] Started [0;1;39mRPC Bind[0m.
[    5.092203] systemd[1]: Started RPC Bind.
[[0;32m  OK  [0m] Mounted [0;1;39mHuge Pages File System[0m.[    5.103712] systemd[1]: Mounted Huge Pages File System.

[[0;32m  OK  [0m] Mounted [0;1;39mPOSIX Message Queue File Sy[    5.127533] systemd[1]: Mounted POSIX Message Queue File System.
stem[0m.
[[0;32m  OK  [0m] Started [0;1;39mJournal Service[0m.[    5.152326] systemd[1]: Started Journal Service.

[[0;32m  OK  [0m] Mounted [0;1;39mKernel Debug File System[0m.
[[0;32m  OK  [0m] Mounted [0;1;39mTemporary Directory (/tmp)[0m.
[[0;32m  OK  [0m] Started [0;1;39mCreate list of sta… nodes for the current kernel[0m.
[[0;1;31mFAILED[0m] Failed to start [0;1;39mStart psplash boot splash screen[0m.
See 'systemctl status psplash-start.service' for details.
[[0;1;38;5;185mDEPEND[0m] Dependency failed for [0;1;39mStar…progress communication helper[0m.
[[0;32m  OK  [0m] Started [0;1;39mLoad Kernel Modules[0m.
[[0;32m  OK  [0m] Started [0;1;39mRemount Root and Kernel File Systems[0m.
         Mounting [0;1;39mKernel Configuration File System[0m...
[    5.387058] random: systemd: uninitialized urandom read (16 bytes read)
         Starting [0;1;39mFlush Journal to Persistent Storage[0m...
[    5.403248] random: systemd-journal: uninitialized urandom read (16 bytes read)
[    5.418737] random: systemd: uninitialized urandom read (16 bytes read)
[    5.420776] systemd-journald[239]: Received client request to flush runtime journal.
         Starting [0;1;39mApply Kernel Variables[0m...
         Starting [0;1;39mCreate Static Device Nodes in /dev[0m...
[[0;32m  OK  [0m] Mounted [0;1;39mKernel Configuration File System[0m.
[[0;32m  OK  [0m] Started [0;1;39mFlush Journal to Persistent Storage[0m.
[[0;32m  OK  [0m] Started [0;1;39mApply Kernel Variables[0m.
[[0;32m  OK  [0m] Started [0;1;39mCreate Static Device Nodes in /dev[0m.
[[0;32m  OK  [0m] Reached target [0;1;39mLocal File Systems (Pre)[0m.
         Mounting [0;1;39m/media/ram[0m...
         Mounting [0;1;39m/var/volatile[0m...
         Starting [0;1;39mudev Kernel Device Manager[0m...
[[0;32m  OK  [0m] Mounted [0;1;39m/media/ram[0m.
[[0;32m  OK  [0m] Mounted [0;1;39m/var/volatile[0m.
         Starting [0;1;39mLoad/Save Random Seed[0m...
[[0;32m  OK  [0m] Reached target [0;1;39mLocal File Systems[0m.
         Starting [0;1;39mCreate Volatile Files and Directories[0m...
[[0;32m  OK  [0m] Started [0;1;39mudev Kernel Device Manager[0m.
[[0;32m  OK  [0m] Started [0;1;39mCreate Volatile Files and Directories[0m.
         Starting [0;1;39mNetwork Time Synchronization[0m...
[    6.618519] random: systemd-journal: uninitialized urandom read (16 bytes read)
[    6.618901] random: systemd: uninitialized urandom read (16 bytes read)
         Starting [0;1;39mUpdate UTMP about System Boot/Shutdown[0m...
[    6.662496] random: systemd-journal: uninitialized urandom read (16 bytes read)
[[0;32m  OK  [0m] Started [0;1;39mUpdate UTMP about System Boot/Shutdown[0m.
[    7.511597] random: crng init done
[    7.511615] random: 59 urandom warning(s) missed due to rate
[    7.511615] random: 59 urandom warning(s) missed due to ratelimiting

[    7.523062] Unable to handle kernel paging request at virtual address ffff800010f35b40
li
[    7.537895] Mem abort info:
mi
[    7.541017]   ESR = 0x86000007
tin
[    7.544412]   EC = 0x21: IABT (current EL), IL = 32 bits
g

[    7.550107]   SET = 0, FnV = 0

[    7.553493]   EA = 0, S1PTW = 0
[    7.556697] printk: console [ttyS2]: printing thread stopped
[    7.556716] swapper pgtable: 4k pages, 48-bit VAs, pgdp=0000000082f01000
[    7.569178] [ffff800010f35b40] pgd=00000008fffff003, p4d=00000008fffff003, pud=00000008ffffe003, pmd=00000008ffffc003, pte=0000000000000000
[    7.579346] clk: failed to reparent clk:151:7 to clk:151:9: -19
[    7.581821] Internal error: Oops: 86000007 [#1] PREEMPT_RT SMP
[    7.593453] Modules linked in: dwc3_keystone(+) optee_rng rng_core sch_fq_codel cryptodev(O) ipv6
[    7.602346] CPU: 1 PID: 80 Comm: kworker/1:1 Tainted: G           O      5.10.168-rt83-gd14fbab5cc #1
[    7.611559] Hardware name: Texas Instruments AM654 Base Board (DT)
[    7.617737] Workqueue: events deferred_probe_work_func
[    7.622891] pstate: 20000005 (nzCv daif -PAN -UAO -TCO BTYPE=--)
[    7.628895] pc : ks_pcie_probe+0x0/0x644
[    7.632823] lr : platform_drv_probe+0x54/0xa8
[    7.637183] sp : ffff8000117fbbb0
[    7.640492] x29: ffff8000117fbbb0 x28: ffff8000110d9000 
[    7.645803] x27: ffff00087f79b0b0 x26: ffff00087f79b060 
[    7.651114] x25: ffff8000111afc00 x24: 0000000000000043 
[    7.656425] x23: ffff8000111e6ea8 x22: 0000000000000000 
[    7.661735] x21: ffff8000111e6ea8 x20: ffff000800c62410 
[    7.667044] x19: 0000000000000000 x18: 0000000000000000 
[    7.672353] x17: 0000000000000000 x16: 0000000000000000 
[    7.677661] x15: 0000000000000000 x14: 0000000000000312 
[    7.682969] x13: 0000000000000000 x12: 0000000000000000 
[    7.688276] x11: 0000000000000008 x10: 0000000000000aa0 
[    7.693583] x9 : ffff8000117fb860 x8 : ffff00087f795f00 
[    7.698890] x7 : 0002000000000000 x6 : 000000045cd3da22 
[    7.704202] x5 : ffff00080197ee18 x4 : 0000000000000000 
[    7.709511] x3 : ffff00080197ee00 x2 : 0000000000000000 
[    7.714822] x1 : ffff800010f35b40 x0 : ffff000800c62400 
[    7.720132] Call trace:
[    7.722576]  ks_pcie_probe+0x0/0x644
[    7.726159]  really_probe+0xec/0x400
[    7.729738]  driver_probe_device+0x58/0xb8
[    7.733835]  __device_attach_driver+0xb8/0xe0
[    7.738197]  bus_for_each_drv+0x78/0xc8
[    7.742035]  __device_attach+0xf8/0x188
[    7.745872]  device_initial_probe+0x14/0x20
[    7.750056]  bus_probe_device+0x9c/0xa8
[    7.753894]  deferred_probe_work_func+0x88/0xc0
[    7.758426]  process_one_work+0x1a0/0x348
[    7.762440]  worker_thread+0x4c/0x440
[    7.766106]  kthread+0x174/0x198
[    7.769334]  ret_from_fork+0x10/0x30
[    7.772928] Code: bad PC value
[    7.775983] ---[ end trace 0000000000000002 ]---
[    7.940345] [drm] Initialized pvr 1.17.4948957 20110701 for 7000000.gpu on minor 0
[    7.962042] CAN device driver interface
[[0;32m  OK  [0m] Started [0;1;39mLoad/Save Random Seed[0m.
[    8.200689] cal 6f03000.cal: Neither port is configured, no point in staying up
[    8.642108] OF: graph: no port node found in /bus@100000/phy@4110000
[    8.643209] platform 41000000.r5f: configured R5F for IPC-only mode
[    8.655198] platform 41000000.r5f: assigned reserved memory node r5f-dma-memory@a0000000
[    8.684608] remoteproc remoteproc0: 41000000.r5f is available
[    8.690728] remoteproc remoteproc0: attaching to 41000000.r5f
[    8.697062] platform 41000000.r5f: R5F core initialized in IPC-only mode
[    8.703935] remoteproc remoteproc0: remote processor 41000000.r5f is now attached
[    8.712131] platform 41400000.r5f: configured R5F for remoteproc mode
[    8.719362] platform 41400000.r5f: assigned reserved memory node r5f-dma-memory@a1000000
[    8.761536] remoteproc remoteproc1: 41400000.r5f is available
[    8.769262] remoteproc remoteproc1: Direct firmware load for am65x-mcu-r5f0_1-fw failed with error -2
[    8.778620] remoteproc remoteproc1: powering up 41400000.r5f
[    8.784410] remoteproc remoteproc1: Direct firmware load for am65x-mcu-r5f0_1-fw failed with error -2
[    8.793742] remoteproc remoteproc1: request_firmware failed: -2
[    8.844759] davinci_mdio b032400.mdio: Configuring MDIO in manual mode
[    9.218263] davinci_mdio b032400.mdio: davinci mdio revision 1.7, bus freq 1000000
[    9.291003] davinci_mdio b032400.mdio: phy[0]: device b032400.mdio:00, driver unknown
[    9.299039] davinci_mdio b032400.mdio: phy[3]: device b032400.mdio:03, driver unknown
[[0;32m  OK  [0m] Reached target [0;1;39mHardware activated USB gadget[0m.
[    9.484860] usbcore: registered new interface driver usbfs
[    9.500137] usbcore: registered new interface driver hub
[    9.513125] usbcore: registered new device driver usb
[    9.653914] xhci-hcd xhci-hcd.2.auto: xHCI Host Controller
[    9.660454] xhci-hcd xhci-hcd.2.auto: new USB bus registered, assigned bus number 1
[    9.668399] xhci-hcd xhci-hcd.2.auto: hcc params 0x0298fe6d hci version 0x110 quirks 0x0000000000010010
[    9.678331] xhci-hcd xhci-hcd.2.auto: irq 596, io mem 0x04010000
[    9.684841] xhci-hcd xhci-hcd.2.auto: xHCI Host Controller
[    9.690431] xhci-hcd xhci-hcd.2.auto: new USB bus registered, assigned bus number 2
[    9.698249] xhci-hcd xhci-hcd.2.auto: Host supports USB 3.0 SuperSpeed
[    9.705113] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.10
[    9.713470] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    9.720785] usb usb1: Product: xHCI Host Controller
[    9.725762] usb usb1: Manufacturer: Linux 5.10.168-rt83-gd14fbab5cc xhci-hcd
[    9.732911] usb usb1: SerialNumber: xhci-hcd.2.auto
[    9.892584] hub 1-0:1.0: USB hub found
[    9.896501] hub 1-0:1.0: 1 port detected
[   10.050572] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
[   10.059020] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.10
[   10.067768] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[   10.075198] usb usb2: Product: xHCI Host Controller
[   10.075659] davinci_mdio b132400.mdio: Configuring MDIO in manual mode
[   10.080146] usb usb2: Manufacturer: Linux 5.10.168-rt83-gd14fbab5cc xhci-hcd
[   10.093962] usb usb2: SerialNumber: xhci-hcd.2.auto
[   10.153952] hub 2-0:1.0: USB hub found
[   10.158063] hub 2-0:1.0: 1 port detected
[   10.240634] xhci-hcd xhci-hcd.3.auto: xHCI Host Controller
[   10.247689] xhci-hcd xhci-hcd.3.auto: new USB bus registered, assigned bus number 3
[   10.255715] xhci-hcd xhci-hcd.3.auto: hcc params 0x0298fe6d hci version 0x110 quirks 0x0000000000010010
[   10.266065] xhci-hcd xhci-hcd.3.auto: irq 598, io mem 0x04030000
[   10.272734] xhci-hcd xhci-hcd.3.auto: xHCI Host Controller
[   10.274244] davinci_mdio b132400.mdio: davinci mdio revision 1.7, bus freq 1000000
[   10.278473] xhci-hcd xhci-hcd.3.auto: new USB bus registered, assigned bus number 4
[   10.293692] xhci-hcd xhci-hcd.3.auto: Host supports USB 3.0 SuperSpeed
[   10.300658] usb usb3: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.10
[   10.313018] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[   10.320453] usb usb3: Product: xHCI Host Controller
[   10.325447] usb usb3: Manufacturer: Linux 5.10.168-rt83-gd14fbab5cc xhci-hcd
[   10.332892] usb usb3: SerialNumber: xhci-hcd.3.auto
[   10.375863] hub 3-0:1.0: USB hub found
[   10.380403] hub 3-0:1.0: 1 port detected
[   10.408183] davinci_mdio b132400.mdio: phy[0]: device b132400.mdio:00, driver unknown
[   10.416438] davinci_mdio b132400.mdio: phy[3]: device b132400.mdio:03, driver unknown
[   10.448424] usb usb4: We don't know the algorithms for LPM for this host, disabling LPM.
[   10.457027] usb usb4: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.10
[   10.465601] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[   10.473356] usb usb4: Product: xHCI Host Controller
[   10.478464] usb usb4: Manufacturer: Linux 5.10.168-rt83-gd14fbab5cc xhci-hcd
[   10.485906] usb usb4: SerialNumber: xhci-hcd.3.auto
[[0;32m  OK  [0m] Started [0;1;39mudev Coldplug all Devices[0m.
[   10.547717] hub 4-0:1.0: USB hub found
[   10.552914] hub 4-0:1.0: 1 port detected
         Starting [0;1;39mudev Wait for Complete Device Initialization[0m...
[[0;32m  OK  [0m] Started [0;1;39mNetwork Time Synchronization[0m.
[[0;32m  OK  [0m] Reached target [0;1;39mSystem Time Set[0m.
[[0;32m  OK  [0m] Reached target [0;1;39mSystem Time Synchronized[0m.
[   10.732046] davinci_mdio b232400.mdio: Configuring MDIO in manual mode
[   10.778272] davinci_mdio b232400.mdio: davinci mdio revision 1.7, bus freq 1000000
[   10.853593] davinci_mdio b232400.mdio: phy[0]: device b232400.mdio:00, driver TI DP83867
[   10.862046] davinci_mdio b232400.mdio: phy[3]: device b232400.mdio:03, driver TI DP83867
[   10.900278] remoteproc remoteproc2: b034000.pru is available
[   10.917203] remoteproc remoteproc3: b004000.rtu is available
[   10.939634] remoteproc remoteproc4: b00a000.txpru is available
[   10.957927] remoteproc remoteproc5: b038000.pru is available
[   10.984252] remoteproc remoteproc6: b006000.rtu is available
[   10.991252] remoteproc remoteproc7: b00c000.txpru is available
[   11.007885] remoteproc remoteproc8: b134000.pru is available
[   11.014768] remoteproc remoteproc9: b104000.rtu is available
[   11.021410] remoteproc remoteproc10: b10a000.txpru is available
[   11.031267] remoteproc remoteproc11: b138000.pru is available
[   11.047632] remoteproc remoteproc12: b106000.rtu is available
[   11.059303] remoteproc remoteproc13: b10c000.txpru is available
[   11.067876] remoteproc remoteproc14: b234000.pru is available
[   11.083320] remoteproc remoteproc15: b204000.rtu is available
[   11.095513] remoteproc remoteproc16: b20a000.txpru is available
[   11.103114] remoteproc remoteproc17: b238000.pru is available
[   11.117891] remoteproc remoteproc18: b206000.rtu is available
[   11.125852] remoteproc remoteproc19: b20c000.txpru is available
[[0;32m  OK  [0m] Created slice [0;1;39msystem-systemd\x2dfsck.slice[0m.
[   11.427188] Generic PHY b132400.mdio:00: attached PHY driver [Generic PHY] (mii_bus:phy_addr=b132400.mdio:00, irq=POLL)
[[0;32m  OK  [0m] Found device [0;1;39m/dev/mmcblk1p1[0m.
[   11.642633] Generic PHY b132400.mdio:03: attached PHY driver [Generic PHY] (mii_bus:phy_addr=b132400.mdio:03, irq=POLL)
[   11.653558] icssg-prueth icssg1-eth: TI PRU ethernet driver initialized: dual EMAC mode
         Starting [0;1;39mFile System Check on /dev/mmcblk1p1[0m...
[   11.974475] Generic PHY b032400.mdio:00: attached PHY driver [Generic PHY] (mii_bus:phy_addr=b032400.mdio:00, irq=POLL)
[   12.111919] Generic PHY b032400.mdio:03: attached PHY driver [Generic PHY] (mii_bus:phy_addr=b032400.mdio:03, irq=POLL)
[   12.122961] icssg-prueth icssg0-eth: TI PRU ethernet driver initialized: dual EMAC mode
[   12.185766] TI DP83867 b232400.mdio:00: attached PHY driver [TI DP83867] (mii_bus:phy_addr=b232400.mdio:00, irq=POLL)
[   12.223534] TI DP83867 b232400.mdio:03: attached PHY driver [TI DP83867] (mii_bus:phy_addr=b232400.mdio:03, irq=POLL)
[   12.234313] icssg-prueth icssg2-eth: TI PRU ethernet driver initialized: dual EMAC mode
[[0;32m  OK  [0m] Started [0;1;39mFile System Check on /dev/mmcblk1p1[0m.
         Mounting [0;1;39m/run/media/mmcblk1p1[0m...
[[0;32m  OK  [0m] Mounted [0;1;39m/run/media/mmcblk1p1[0m.
[[0m[0;31m*     [0m] A start job is running for udev Wai…ice Initialization (13s / 3min 6s)

(there are some control codes in the log since it was extracted from terminal emulator recorded log. Feel free to ask a more clear log if needed.)

As shown in the log, the boot stucked at "A start job is running for udev Wai…ice Initialization" (for at least 1 minute.). And the oops occured at time 7.523062.

Plus, I've tried using loading address 0x700a_0000 and 0x700f_0000. For the former one, the same result as the above, whereas for the latter one, the loading of ELF resets the SoC. The log is shown below:

=> rproc load 0 0x90000000 0x${filesize}
k3_r5f_rproc r5f@41000000: k3_r5f_load addr = 0x90000000, size = 0xa784c
k3_r5f_rproc r5f@41000000: k3_r5f_prepare
k3_r5f_rproc r5f@41000000: k3_r5f_split_release
k3_r5f_rproc r5f@41000000: k3_r5f_da_to_va
k3_r5f_rproc r5f@41000000: Loading phdr 0 to 0x0000000041000000 (64 bytes)
k3_r5f_rproc r5f@41000000: k3_r5f_da_to_va
k3_r5f_rproc r5f@41000000: Loading phdr 1 to 0x00000000700f0000 (0 bytes)
"Error" handler, esr 0xbf000000
elr: 00000000808a6778 lr : 000000008084526c (reloc)
elr: 00000000fff98778 lr : 00000000fff3726c
x0 : 00000000700f0000 x1 : 0000000000000000
x2 : 0000000000013320 x3 : 00000000000100b8
x4 : 0000000000000000 x5 : 0000000000003270
x6 : 0000000000000000 x7 : 000000000000000f
x8 : 0000000000000000 x9 : 0000000000000008
x10: 00000000fffffff0 x11: 000000000000000a
x12: 0000000000000006 x13: 000000000001869f
x14: 0000000000000008 x15: 0000000000000002
x16: 00000000fff3676c x17: 0000000000000000
x18: 00000000fded1de0 x19: 00000000900a718c
x20: 00000000700f0000 x21: 00000000fded3640
x22: 0000000090000000 x23: 0000000000000001
x24: 00000000fffb5ddd x25: 00000000fffbec96
x26: 00000000fffa1248 x27: 00000000fffbe000
x28: 0000000000000080 x29: 00000000fdec4d10

Code: 540000e1 d65f03c0 f8236804 91002063 (17fffff3) 
Resetting CPU ...

resetting ...
ti-sci-sysreset sysreset-controller: set_state_simple op missing

U-Boot SPL 2021.01-00001-g18e219dc1f (Dec 07 2023 - 00:00:18 +0800)
SYSFW ABI: 3.1 (firmware rev 0x0008 '8.6.5--v08.06.05 (Chill Capybar')
i2c_omap i2c@42120000: pinctrl_select_state_full: uclass_get_device_by_phandle_id: err=-19
ti_i2c_eeprom_am6_parse_record: Ignoring record id 17
k3_avs wkup_vtm@42050000: supply not found for VD2.
SPL initial stack usage: 1464 bytes
Trying to boot from MMC2
spl_load_fit_image: Skip load 'dm': image size is 0!
Starting ATF on ARM64 core...
...(cut here)

Seems loading to 0x700f_0000 would violate something like MPU write permission rule.

In summary, the R5F is probably still using memory area that Linux doesn't expects, but it's fine as long as R5F stops running (e.g., finished executing the example).

I'm still figuring out the cause..

Thanks!

Huichun

0 Nick Saulnier 7 months ago in reply to Huichun Feng

TI__Guru* 76425 points

Hello Huichun,

R5F related discussion

The R5F part of this question is looking good!

I would definitely expect issues if you told the R5F to use the SRAM address starting at 0x700f_0000. From the Linux devicetree snippet in my previous response, we see that SYSFW is using that section of memory. So if R5F was overwriting the SYSFW data, the processor would go into a bad state pretty quickly.

This terminal output is telling me that the Linux remoteproc driver is finding the R5F core0 already running, and then just connecting to it as expected (instead of trying to reboot/reload the core). Core1 fails to load because it does not see a firmware binary in the expected /lib/firmware location:

[    8.643209] platform 41000000.r5f: configured R5F for IPC-only mode
[    8.655198] platform 41000000.r5f: assigned reserved memory node r5f-dma-memory@a0000000
[    8.684608] remoteproc remoteproc0: 41000000.r5f is available
[    8.690728] remoteproc remoteproc0: attaching to 41000000.r5f
[    8.697062] platform 41000000.r5f: R5F core initialized in IPC-only mode
[    8.703935] remoteproc remoteproc0: remote processor 41000000.r5f is now attached
[    8.712131] platform 41400000.r5f: configured R5F for remoteproc mode
[    8.719362] platform 41400000.r5f: assigned reserved memory node r5f-dma-memory@a1000000
[    8.761536] remoteproc remoteproc1: 41400000.r5f is available
[    8.769262] remoteproc remoteproc1: Direct firmware load for am65x-mcu-r5f0_1-fw failed with error -2
[    8.778620] remoteproc remoteproc1: powering up 41400000.r5f
[    8.784410] remoteproc remoteproc1: Direct firmware load for am65x-mcu-r5f0_1-fw failed with error -2
[    8.793742] remoteproc remoteproc1: request_firmware failed: -2

PCIE driver error

First off,

1) are you using a PCIE daughter card with the AM65x IDK? If so, which daughter card are you using?

2) do you still see that PCIE probe issue when the R5F is not getting initialized in uboot?

If you are not using the pcie, then we would want to make sure that is disabled in the Linux devicetree.

Regards,

Nick

0 Huichun Feng 7 months ago in reply to Nick Saulnier

Prodigy 81 points

Hello Nick,

Nick Saulnier said:
1) are you using a PCIE daughter card with the AM65x IDK? If so, which daughter card are you using?

Yes, the PCIE daughter card was shipped with the IDK, the model I found on the card is "proc067a ser-pcie2levm". There's no device installed on the card though.

Nick Saulnier said:
2) do you still see that PCIE probe issue when the R5F is not getting initialized in uboot?

As long as the R5F is not running during boot (e.g., finished execution of example app, or not started yet), there would be no PCIe probe issue. Moreover, I think that the issue is not merely related to the PCIe, since the oops was triggered by invalid page fault at time 7.523062:

[    7.523062] Unable to handle kernel paging request at virtual address ffff800010f35b40

Followed by the core dump with PC at "ks_pcie_probe()".

Thanks!

Huichun

0 Nick Saulnier 7 months ago in reply to Huichun Feng

TI__Guru* 76425 points

Hello Huichun,

Ok, good to know.

The next step here is to see if this issue seems specific to the PCIE, or if it is a more general issue. Apparently multiple different things could cause that kind of an error (e.g., if there is a power event that corrupts the memory address the driver is trying to access).

Can I get you to disable the PCIE in the devicetree file? Or just remove the daughter card from the board. When the R5F is initialized in uboot and still running, do any other errors pop up during boot time?

Regards,

Nick

0 Huichun Feng 7 months ago in reply to Nick Saulnier

Prodigy 81 points

Hello Nick,

Nick Saulnier said:
The next step here is to see if this issue seems specific to the PCIE, or if it is a more general issue. Apparently multiple different things could cause that kind of an error (e.g., if there is a power event that corrupts the memory address the driver is trying to access).

Removing the PCIe daughter card solves the kernel oops. Also, the kernel boots successfully. But the app running on the R5F no longer works (LED blinking stopped). I've tried removing the R5F-related kernel module (ti_k3_r5_remoteproc.ko), but the result is the same.

I'll try to remove R5F related nodes in the device tree and see what happens.

Nick Saulnier said:
When the R5F is initialized in uboot and still running, do any other errors pop up during boot time?

AFAIK, there's no R5F errors popped up during the boot whilst the R5F app is running. Following is the boot log for double check:

=> rproc start 0  
k3_r5f_rproc r5f@41000000: k3_r5f_start
=> boot
switch to partitions #0, OK
mmc1 is current device
SD/MMC found on device 1
Failed to load 'boot.scr'
1490 bytes read in 4 ms (363.3 KiB/s)
Loaded env from uEnv.txt
Importing environment from mmc1 ...
Running uenvcmd ...
1 bytes read in 3 ms (0 Bytes/s)
Already setup.
18862592 bytes read in 789 ms (22.8 MiB/s)
64327 bytes read in 7 ms (8.8 MiB/s)
11268 bytes read in 5 ms (2.1 MiB/s)
## Flattened Device Tree blob at 88000000
   Booting using the fdt blob at 0x88000000
   Loading Device Tree to 000000008feed000, 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
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-gd14fbab5cc (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 Sat Mar 25 15:39:18 UTC 2023
[    0.000000] Machine model: Texas Instruments AM654 Base Board
[    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 0x00000000a0000000, size 1 MiB
[    0.000000] OF: reserved mem: initialized node r5f-dma-memory@a0000000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a0100000, size 15 MiB
[    0.000000] OF: reserved mem: initialized node r5f-memory@a0100000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a1000000, size 1 MiB
[    0.000000] OF: reserved mem: initialized node r5f-dma-memory@a1000000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a1100000, size 15 MiB
[    0.000000] OF: reserved mem: initialized node r5f-memory@a1100000, compatible id shared-dma-pool
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000080000000-0x00000000ffffffff]
[    0.000000]   DMA32    empty
[    0.000000]   Normal   [mem 0x0000000100000000-0x00000008ffffffff]
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000080000000-0x000000009e7fffff]
[    0.000000]   node   0: [mem 0x000000009e800000-0x00000000a20fffff]
[    0.000000]   node   0: [mem 0x00000000a2100000-0x00000000ffffffff]
[    0.000000]   node   0: [mem 0x0000000880000000-0x00000008ffffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000080000000-0x00000008ffffffff]
[    0.000000] On node 0 totalpages: 1048576
[    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]   Normal zone: 8192 pages used for memmap
[    0.000000]   Normal zone: 524288 pages, LIFO batch:63
[    0.000000] cma: Reserved 512 MiB at 0x00000000e0000000
[    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 s35392 r8192 d30144 u73728
[    0.000000] pcpu-alloc: s35392 r8192 d30144 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: 1032192
[    0.000000] Kernel command line: console=ttyS2,115200n8 earlycon=ns16550a,mmio32,0x02800000 mtdparts=47040000.spi.0:512k(ospi.tiboot3),2m(ospi.tispl),4m(ospi.u-boot),128k(ospi.env),128k(ospi.env.backup),1m(ospi.sysfw),57216k@8m(ospi.rootfs),128k(ospi.phypattern) root=PARTUUID=63ecb215-02 rw rootfstype=ext4 rootwait
[    0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)
[    0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)
[    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
[    0.000000] software IO TLB: mapped [mem 0x00000000dc000000-0x00000000e0000000] (64MB)
[    0.000000] Memory: 3446428K/4194304K available (11008K kernel code, 1204K rwdata, 4272K rodata, 1792K init, 438K bss, 223588K 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] 	Tracing variant of Tasks RCU enabled.
[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4
[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
[    0.000000] GICv3: GIC: Using split EOI/Deactivate mode
[    0.000000] GICv3: 960 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 @880800000 (flat, esz 8, psz 64K, shr 0)
[    0.000000] ITS: using cache flushing for cmd queue
[    0.000000] GICv3: using LPI property table @0x0000000880030000
[    0.000000] GIC: using cache flushing for LPI property table
[    0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000880040000
[    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.000001] sched_clock: 56 bits at 200MHz, resolution 5ns, wraps every 4398046511102ns
[    0.008748] Console: colour dummy device 80x25
[    0.013370] Calibrating delay loop (skipped), value calculated using timer frequency.. 400.00 BogoMIPS (lpj=800000)
[    0.024093] pid_max: default: 32768 minimum: 301
[    0.028973] LSM: Security Framework initializing
[    0.033817] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
[    0.041441] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
[    0.052004] rcu: Hierarchical SRCU implementation.
[    0.057669] Platform MSI: msi-controller@1820000 domain created
[    0.064100] PCI/MSI: /bus@100000/interrupt-controller@1800000/msi-controller@1820000 domain created
[    0.073553] EFI services will not be available.
[    0.078559] smp: Bringing up secondary CPUs ...
I/TC: Secondary CPU 1 initializing
I/TC: Secondary CPU 1 switching to normal world boot
[    0.092610] Detected VIPT I-cache on CPU1
[    0.096762] GICv3: CPU1: found redistributor 1 region 0:0x00000000018a0000
[    0.103825] GICv3: CPU1: using allocated LPI pending table @0x0000000880050000
[    0.111293] CPU1: Booted secondary processor 0x0000000001 [0x410fd034]
I/TC: Secondary CPU 2 initializing
I/TC: Secondary CPU 2 switching to normal world boot
[    0.127628] Detected VIPT I-cache on CPU2
[    0.131797] GICv3: CPU2: found redistributor 100 region 0:0x00000000018c0000
[    0.139040] GICv3: CPU2: using allocated LPI pending table @0x0000000880060000
[    0.146508] CPU2: Booted secondary processor 0x0000000100 [0x410fd034]
I/TC: Secondary CPU 3 initializing
I/TC: Secondary CPU 3 switching to normal world boot
[    0.162738] Detected VIPT I-cache on CPU3
[    0.166872] GICv3: CPU3: found redistributor 101 region 0:0x00000000018e0000
[    0.174109] GICv3: CPU3: using allocated LPI pending table @0x0000000880070000
[    0.181554] CPU3: Booted secondary processor 0x0000000101 [0x410fd034]
[    0.188424] smp: Brought up 1 node, 4 CPUs
[    0.192661] SMP: Total of 4 processors activated.
[    0.197510] CPU features: detected: 32-bit EL0 Support
[    0.202801] CPU features: detected: CRC32 instructions
[    0.223495] CPU: All CPU(s) started at EL2
[    0.227749] alternatives: patching kernel code
[    0.233947] devtmpfs: initialized
[    0.251022] KASLR disabled due to lack of seed
[    0.255816] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.265843] futex hash table entries: 1024 (order: 5, 131072 bytes, linear)
[    0.291072] pinctrl core: initialized pinctrl subsystem
[    0.297203] DMI not present or invalid.
[    0.302071] NET: Registered protocol family 16
[    0.308895] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations
[    0.316399] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
[    0.324752] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
[    0.333642] thermal_sys: Registered thermal governor 'step_wise'
[    0.334414] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
[    0.347673] ASID allocator initialised with 65536 entries
[    0.402659] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages
[    0.409605] HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages
[    0.416498] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[    0.423399] HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages
[    0.431925] cryptd: max_cpu_qlen set to 1000
[    0.441313] k3-chipinfo 43000014.chipid: Family:AM65X rev:SR2.0 JTAGID[0x1bb5a02f] Detected
[    0.450720] vcc3v3_io: supplied by evm_12v0
[    0.456602] iommu: Default domain type: Translated 
[    0.462237] SCSI subsystem initialized
[    0.467024] mc: Linux media interface: v0.10
[    0.471485] videodev: Linux video capture interface: v2.00
[    0.477242] pps_core: LinuxPPS API ver. 1 registered
[    0.482367] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.491758] PTP clock support registered
[    0.495825] EDAC MC: Ver: 3.0.0
[    0.500569] FPGA manager framework
[    0.504202] Advanced Linux Sound Architecture Driver Initialized.
[    0.511687] clocksource: Switched to clocksource arch_sys_counter
[    0.518209] VFS: Disk quotas dquot_6.6.0
[    0.522312] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    0.538216] NET: Registered protocol family 2
[    0.543109] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)
[    0.553570] tcp_listen_portaddr_hash hash table entries: 2048 (order: 5, 131072 bytes, linear)
[    0.562612] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)
[    0.571162] TCP bind hash table entries: 32768 (order: 8, 1835008 bytes, linear)
[    0.580771] TCP: Hash tables configured (established 32768 bind 32768)
[    0.587884] UDP hash table entries: 2048 (order: 6, 262144 bytes, linear)
[    0.595219] UDP-Lite hash table entries: 2048 (order: 6, 262144 bytes, linear)
[    0.603176] NET: Registered protocol family 1
[    0.608542] RPC: Registered named UNIX socket transport module.
[    0.614736] RPC: Registered udp transport module.
[    0.619572] RPC: Registered tcp transport module.
[    0.624403] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.631023] NET: Registered protocol family 44
[    0.635601] PCI: CLS 0 bytes, default 64
[    0.640880] hw perfevents: enabled with armv8_cortex_a53 PMU driver, 7 counters available
[    0.655566] Initialise system trusted keyrings
[    0.660452] workingset: timestamp_bits=46 max_order=20 bucket_order=0
[    0.673999] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.681208] NFS: Registering the id_resolver key type
[    0.686495] Key type id_resolver registered
[    0.690824] Key type id_legacy registered
[    0.695050] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    0.701961] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
[    0.709871] 9p: Installing v9fs 9p2000 file system support
[    0.770032] Key type asymmetric registered
[    0.774273] Asymmetric key parser 'x509' registered
[    0.779344] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 243)
[    0.787009] io scheduler mq-deadline registered
[    0.791666] io scheduler kyber registered
[    0.799346] pinctrl-single 4301c000.pinctrl: 70 pins, size 280
[    0.806182] pinctrl-single 11c000.pinctrl: 185 pins, size 740
[    0.812366] pinctrl-single 11c2e8.pinctrl: 9 pins, size 36
[    0.831731] Serial: 8250/16550 driver, 10 ports, IRQ sharing enabled
[    0.860647] brd: module loaded
[    0.874981] loop: module loaded
[    0.879514] megasas: 07.714.04.00-rc1
[    0.889617] tun: Universal TUN/TAP device driver, 1.6
[    0.895788] igbvf: Intel(R) Gigabit Virtual Function Network Driver
[    0.902239] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.
[    0.908414] sky2: driver version 1.30
[    0.913824] VFIO - User Level meta-driver version: 0.3
[    0.920621] i2c /dev entries driver
[    0.926227] sdhci: Secure Digital Host Controller Interface driver
[    0.932580] sdhci: Copyright(c) Pierre Ossman
[    0.937865] sdhci-pltfm: SDHCI platform and OF driver helper
[    0.945492] ti-sci bus@100000:bus@28380000:bus@42040000:dmsc: invalid resource
[    0.953183] SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping ....
[    0.961983] optee: probing for conduit method.
I/TC: Reserved shared memory is enabled
I/TC: Dynamic shared memory is enabled
I/TC: Normal World virtualization support is disabled
I/TC: Asynchronous notifications are disabled
[    0.966586] optee: revision 3.20 (8e74d476)
[    0.983350] optee: dynamic shared memory is enabled
[    0.993182] optee: initialized driver
[    1.000025] NET: Registered protocol family 17
[    1.004824] 9pnet: Installing 9P2000 support
[    1.009339] Key type dns_resolver registered
[    1.014206] printk: bootconsole [ns16550a0]: printing thread started
[    1.014298] Loading compiled-in X.509 certificates
[    1.035436] ti-sci bus@100000:bus@28380000:bus@42040000:dmsc: invalid resource
[    1.036539] ti-sci bus@100000:bus@28380000:bus@42040000:dmsc: lpm region is required for suspend but not provided.
[    1.036654] ti-sci bus@100000:bus@28380000:bus@42040000:dmsc: ABI: 3.1 (firmware rev 0x0008 '8.6.5--v08.06.05 (Chill Capybar')
[    1.118666] omap_i2c 42120000.i2c: bus 0 rev0.12 at 400 kHz
[    1.120208] omap_i2c 40b00000.i2c: bus 1 rev0.12 at 100 kHz
[    1.122253] pca953x 2-0021: supply vcc not found, using dummy regulator
[    1.122433] pca953x 2-0021: using no AI
[    1.144609] omap_i2c 2000000.i2c: bus 2 rev0.12 at 400 kHz
[    1.145869] omap_i2c 2010000.i2c: bus 3 rev0.12 at 400 kHz
[    1.147024] omap_i2c 2020000.i2c: bus 4 rev0.12 at 400 kHz
[    1.148177] omap_i2c 2030000.i2c: bus 5 rev0.12 at 100 kHz
[    1.165195] printk: console [ttyS2]: printing thread started
[    1.149629] ti-sci-intr bus@100000:bus@28380000:bus@42040000:interrupt-controller2: Interrupt Router 156 domain created
[    1.165222] printk: console [ttyS2] enabled
[    1.165228] printk: bootconsole [ns16550a0] disabled
[    1.187651] printk: bootconsole [ns16550a0]: printing thread stopped
[    1.188956] 2810000.serial: ttyS3 at MMIO 0x2810000 (irq = 30, base_baud = 3000000) is a 8250
[    1.190006] 2820000.serial: ttyS4 at MMIO 0x2820000 (irq = 31, base_baud = 3000000) is a 8250
[    1.208303] davinci_mdio 46000f00.mdio: Configuring MDIO in manual mode
[    1.247723] davinci_mdio 46000f00.mdio: davinci mdio revision 9.7, bus freq 1000000
[    1.249997] davinci_mdio 46000f00.mdio: phy[0]: device 46000f00.mdio:00, driver TI DP83867
[    1.250120] am65-cpsw-nuss 46000000.ethernet: initializing am65 cpsw nuss version 0x6BA00102, cpsw version 0x6BA80102 Ports: 2 quirks:00000000
[    1.250266] am65-cpsw-nuss 46000000.ethernet: initialized cpsw ale version 1.4
[    1.250276] am65-cpsw-nuss 46000000.ethernet: ALE Table size 64
[    1.251618] am65-cpsw-nuss 46000000.ethernet: CPTS ver 0x4e8a010b, freq:200000000, add_val:4 pps:0
[    1.255582] am65-cpts 310d0000.cpts: CPTS ver 0x4e8a010b, freq:200000000, add_val:4 pps:0
[    1.260302] mmc0: CQHCI version 5.10
[    1.274951] pca953x 0-0039: supply vcc not found, using dummy regulator
[    1.275167] pca953x 0-0039: using no AI
[    1.297583] omap-mailbox 31f80000.mailbox: omap mailbox rev 0x66fc7100
[    1.298163] omap-mailbox 31f81000.mailbox: omap mailbox rev 0x66fc7100
[    1.300347] ti-udma 285c0000.dma-controller: Channels: 28 (tchan: 14, rchan: 14, gp-rflow: 16)
[    1.302878] mmc0: SDHCI controller on 4f80000.mmc [4f80000.mmc] using ADMA 64-bit
[    1.307938] ti-udma 31150000.dma-controller: Channels: 119 (tchan: 53, rchan: 66, gp-rflow: 64)
[    1.321784] spi-nor spi7.0: unrecognized JEDEC id bytes: 00 00 00 00 00 00
[    1.321820] spi-nor: probe of spi7.0 failed with error -2
[    1.333966] spi-nor spi9.0: n25q128a13 (16384 Kbytes)
[    1.338521] davinci_mdio 46000f00.mdio: Configuring MDIO in manual mode
[    1.375717] davinci_mdio 46000f00.mdio: davinci mdio revision 9.7, bus freq 1000000
[    1.378026] davinci_mdio 46000f00.mdio: phy[0]: device 46000f00.mdio:00, driver TI DP83867
[    1.378151] am65-cpsw-nuss 46000000.ethernet: initializing am65 cpsw nuss version 0x6BA00102, cpsw version 0x6BA80102 Ports: 2 quirks:00000000
[    1.378298] am65-cpsw-nuss 46000000.ethernet: initialized cpsw ale version 1.4
[    1.378307] am65-cpsw-nuss 46000000.ethernet: ALE Table size 64
[    1.379294] am65-cpsw-nuss 46000000.ethernet: CPTS ver 0x4e8a010b, freq:200000000, add_val:4 pps:0
[    1.381857] am65-cpsw-nuss 46000000.ethernet: set new flow-id-base 48
[    1.386205] vdd_mmc1_sd: supplied by vcc3v3_io
[    1.387926] debugfs: Directory 'pd:121' with parent 'pm_genpd' already present!
[    1.387993] debugfs: Directory 'pd:120' with parent 'pm_genpd' already present!
[    1.389112] mmc1: CQHCI version 5.10
[    1.391499] input: gpio-keys as /devices/platform/gpio-keys/input/input0
[    1.393795] mmc0: Command Queue Engine enabled
[    1.393819] mmc0: new HS200 MMC card at address 0001
[    1.394562] mmcblk0: mmc0:0001 S0J56X 14.8 GiB 
[    1.394825] mmcblk0boot0: mmc0:0001 S0J56X partition 1 31.5 MiB
[    1.395140] mmcblk0boot1: mmc0:0001 S0J56X partition 2 31.5 MiB
[    1.395411] mmcblk0rpmb: mmc0:0001 S0J56X partition 3 4.00 MiB, chardev (237:0)
[    1.410803] ALSA device list:
[    1.410820]   No soundcards found.
[    2.323957] mmc1: SDHCI controller on 4fa0000.mmc [4fa0000.mmc] using ADMA 64-bit
[    2.324590] Waiting for root device PARTUUID=63ecb215-02...
[    2.381008] mmc1: new ultra high speed SDR104 SDHC card at address aaaa
[    2.381872] mmcblk1: mmc1:aaaa SC16G 14.8 GiB 
[    2.384561]  mmcblk1: p1 p2
[    2.405373] EXT4-fs (mmcblk1p2): mounted filesystem with ordered data mode. Opts: (null)
[    2.405472] VFS: Mounted root (ext4 filesystem) on device 179:98.
[    2.408666] devtmpfs: mounted
[    2.410080] Freeing unused kernel memory: 1792K
[    2.427859] Run /sbin/init as init process
[    2.628937] systemd[1]: System time before build time, advancing clock.
[    2.690693] NET: Registered protocol family 10
[    2.692262] Segment Routing with IPv6
[    2.731371] 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)
[    2.732200] systemd[1]: Detected architecture arm64.
t
Welcome to Arago 2021.09!
single-pinctrl pinmux@11c000:   reg/val 0x0x000000000011c1e8/0x00010000
[    2.783504] systemd[1]: Set hostname to <am65xx-evm>.
[    3.131196] systemd[1]: /lib/systemd/system/irqbalanced.service:6: Unknown key name 'ConditionCPUs' in section 'Unit', ignoring.
[    3.162270] 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.
[    3.276625] random: systemd: uninitialized urandom read (16 bytes read)
[    3.282188] systemd[1]: Created slice system-getty.slice.
[  OK  ] Created slice system-getty.slice.
[  OK  ] Created slice system-serial\x2dgett[    3.311923] random: systemd: uninitialized urandom read (16 bytes read)
y.slice.
[    3.314373] systemd[1]: Created slice system-serial\x2dgetty.slice.
[  OK  ] Created slice User and Session Slic[    3.335897] random: systemd: uninitialized urandom read (16 bytes read)
e.
[    3.338000] systemd[1]: Created slice User and Session Slice.
[  OK  ] Started Dispatch Password …ts to [    3.360262] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
Console Directory Watch.
[  OK  ] Started Forward Password R…uests [    3.384108] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
to Wall Directory Watch.
[  OK  ] Reached target Paths.
[    3.408032] systemd[1]: Reached target Paths.
[  OK  ] Reached target Remote File Systems systemd[1]: Reached target Remote File Systems.
[0m.
[  OK  ] Reached target Slices.
[    3.439880] systemd[1]: Reached target Slices.
[  OK  ] Reached target Swap.
[    3.451894] systemd[1]: Reached target Swap.
[  OK  ] Listening on RPCbind Server Activat[    3.493427] systemd[1]: Listening on RPCbind Server Activation Socket.
ion Socket.
[  OK  ] Reached target RPC Port Mapper.[    3.520104] systemd[1]: Reached target RPC Port Mapper.
Err:   serial
[  OK  ] Listening on Process Core Dump Sock[    3.549125] systemd[1]: Listening on Process Core Dump Socket.
et.
[  OK  ] Listening on initctl Compatibility [    3.572321] systemd[1]: Listening on initctl Compatibility Named Pipe.
Named Pipe.
[  OK  ] Listening on Journal Socket (/dev/l[    3.600526] systemd[1]: Condition check resulted in Journal Audit Socket being skipped.
og).
[    3.601438] systemd[1]: Listening on Journal Socket (/dev/log).
[  OK  ] Listening on Journal Socket.
[    3.622976] systemd[1]: Listening on Journal Socket.
[  OK  ] Listening on Network Service Netlin[    3.634878] systemd[1]: Listening on Network Service Netlink Socket.
k Socket.
[  OK  ] Listening on udev Control Socket    3.656560] systemd[1]: Listening on udev Control Socket.
m.
[  OK  ] Listening on udev Kernel Socket[    3.680281] systemd[1]: Listening on udev Kernel Socket.
.
         Mounting Huge Pages File System...[    3.706254] systemd[1]: Mounting Huge Pages File System...
pinconfig wkup-i2c0-pins-default: set_state_simple op missing
         Mounting POSIX Message Queue File System..[    3.734186] systemd[1]: Mounting POSIX Message Queue File System...
.
         Mounting Kernel Debug File System...
[    3.762291] systemd[1]: Mounting Kernel Debug File System...
         Mounting Temporary Directory (/tmp)...
[    3.785268] systemd[1]: Mounting Temporary Directory (/tmp)...
         Starting Create list of st…odes for the curr[    3.804096] systemd[1]: Starting Create list of static device nodes for the current kernel...
ent kernel...
         Starting Start psplash boot splash screen.[    3.843200] systemd[1]: Starting Start psplash boot splash screen...
..
         Starting RPC Bind...
[    3.870505] systemd[1]: Starting RPC Bind...
[    3.884140] systemd[1]: Condition check resulted in File System Check on Root Device being skipped.
[    3.893077] systemd[1]: Starting Journal Service...
         Starting Journal Service...
         Starting Load Kernel Modules...
[    3.928504] systemd[1]: Starting Load Kernel Modules...
         Starting Remount Root and Kernel File Systems systemd[1]: Starting Remount Root and Kernel File Systems...
[0m...
[    3.957032] cryptodev: loading out-of-tree module taints kernel.
[    3.959579] cryptodev: driver 1.10 loaded.
         Starting udev Coldplug all Devices...
[    3.977581] systemd[1]: Starting udev Coldplug all Devices...
[    3.983223] EXT4-fs (mmcblk1p2): re-mounted. Opts: (null)
[  OK  ] Started RPC Bind.
[    3.999820] systemd[1]: Started RPC Bind.
[  OK  ] Mounted Huge Pages File System.[    4.017339] systemd[1]: Mounted Huge Pages File System.
pinconfig icssg2-mdio-pins-default: set_state_simple op missing
[  OK  ] Mounted POSIX Message Queue File Sy[    4.041008] systemd[1]: Mounted POSIX Message Queue File System.
stem.
[  OK  ] Started Journal Service.
[    4.064656] systemd[1]: Started Journal Service.
[  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...
[    4.288345] random: systemd: uninitialized urandom read (16 bytes read)
[    4.290242] systemd-journald[225]: Received client request to flush runtime journal.
[    4.302232] random: systemd-journal: uninitialized urandom read (16 bytes read)
         Starting Apply Kernel Variables...
[    4.324326] random: systemd: uninitialized urandom read (16 bytes read)
         Starting Create Static Device Nodes in /dev...
[  OK  ] Mounted Kernel Configuration File System.
[  OK  ] Started Flush Journal to Persistent Storage.
[  OK  ] Started Apply Kernel Variables.
[  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  ] Mounted /media/ram.
[  OK  ] Mounted /var/volatile.
         Starting Load/Save Random Seed...
[  OK  ] Reached target Local File Systems.
         Starting Create Volatile Files and Directories...
[  OK  ] Started udev Kernel Device Manager.
[  OK  ] Started Create Volatile Files and Directories.
         Starting Network Time Synchronization...
[    5.292459] random: systemd: uninitialized urandom read (16 bytes read)
[    5.356136] random: systemd-journal: uninitialized urandom read (16 bytes read)
         Starting Update UTMP about System Boot/Shutdown...
[    5.787910] random: systemd-journal: uninitialized urandom read (16 bytes read)
[  OK  ] Started Update UTMP about System Boot/Shutdown.
[    6.424317] random: crng init done
[    6.424335] random: 59 urandom warning(s) missed due to ratelimiting
[    6.776945] [drm] Initialized pvr 1.17.4948957 20110701 for 7000000.gpu on minor 0
[  OK  ] Started udev Coldplug all Devices.
[    6.870436] CAN device driver interface
[    7.022928] cal 6f03000.cal: Neither port is configured, no point in staying up
[    7.172924] m_can_platform 40528000.mcan: m_can device registered (irq=23, version=32)
[  OK  ] Started Load/Save Random Seed.
[    7.459508] m_can_platform 40568000.mcan: m_can device registered (irq=25, version=32)
[    7.574826] davinci_mdio b032400.mdio: Configuring MDIO in manual mode
         Starting udev Wait for Complete Device Initialization...
[    7.848088] davinci_mdio b032400.mdio: davinci mdio revision 1.7, bus freq 1000000
[    7.941327] davinci_mdio b032400.mdio: phy[0]: device b032400.mdio:00, driver TI DP83867
[    7.941351] davinci_mdio b032400.mdio: phy[3]: device b032400.mdio:03, driver TI DP83867
[    8.589965] davinci_mdio b132400.mdio: Configuring MDIO in manual mode
[    8.647824] davinci_mdio b132400.mdio: davinci mdio revision 1.7, bus freq 1000000
[  OK  ] Started Network Time Synchronization.
[  OK  ] Reached target System Time Set.
[  OK  ] Reached target System Time Synchron[    8.750540] davinci_mdio b132400.mdio: phy[0]: device b132400.mdio:00, driver TI DP83867
ized.
[    8.750568] davinci_mdio b132400.mdio: phy[3]: device b132400.mdio:03, driver TI DP83867
[    8.803399] davinci_mdio b232400.mdio: Configuring MDIO in manual mode
[    8.847791] davinci_mdio b232400.mdio: davinci mdio revision 1.7, bus freq 1000000
[    8.941901] davinci_mdio b232400.mdio: phy[0]: device b232400.mdio:00, driver TI DP83867
[    8.941925] davinci_mdio b232400.mdio: phy[3]: device b232400.mdio:03, driver TI DP83867
[    8.954837] remoteproc remoteproc0: b034000.pru is available
[    8.955612] remoteproc remoteproc1: b004000.rtu is available
[    8.965097] remoteproc remoteproc2: b00a000.txpru is available
[    8.965748] remoteproc remoteproc3: b038000.pru is available
[    8.966632] remoteproc remoteproc4: b006000.rtu is available
[    8.967143] remoteproc remoteproc5: b00c000.txpru is available
[    8.967594] remoteproc remoteproc6: b134000.pru is available
[    8.974840] remoteproc remoteproc7: b104000.rtu is available
[    8.976770] remoteproc remoteproc8: b10a000.txpru is available
[    8.977230] remoteproc remoteproc9: b138000.pru is available
[    8.979223] remoteproc remoteproc10: b106000.rtu is available
[    8.980464] remoteproc remoteproc11: b10c000.txpru is available
[    8.981276] remoteproc remoteproc12: b234000.pru is available
[    8.982312] remoteproc remoteproc13: b204000.rtu is available
[    8.984120] remoteproc remoteproc14: b20a000.txpru is available
[    8.984628] remoteproc remoteproc15: b238000.pru is available
[    8.986017] remoteproc remoteproc16: b206000.rtu is available
[    8.987016] remoteproc remoteproc17: b20c000.txpru is available
[  OK  ] Created slice system-systemd\x2dfsck.slice.
[  OK  ] Found device /dev/mmcblk1p1.
[   10.259886] OF: graph: no port node found in /bus@100000/phy@4110000
[   10.411248] TI DP83867 b132400.mdio:00: attached PHY driver [TI DP83867] (mii_bus:phy_addr=b132400.mdio:00, irq=POLL)
         Starting File System Check on /dev/mmcblk1p1...
[   10.558886] TI DP83867 b132400.mdio:03: attached PHY driver [TI DP83867] (mii_bus:phy_addr=b132400.mdio:03, irq=POLL)
[   10.558933] icssg-prueth icssg1-eth: TI PRU ethernet driver initialized: dual EMAC mode
[  OK  ] Reached target Hardware activated USB gadget.
[   10.666849] TI DP83867 b032400.mdio:00: attached PHY driver [TI DP83867] (mii_bus:phy_addr=b032400.mdio:00, irq=POLL)
[   10.809419] TI DP83867 b032400.mdio:03: attached PHY driver [TI DP83867] (mii_bus:phy_addr=b032400.mdio:03, irq=POLL)
[   10.809465] icssg-prueth icssg0-eth: TI PRU ethernet driver initialized: dual EMAC mode
[   11.001863] TI DP83867 b232400.mdio:00: attached PHY driver [TI DP83867] (mii_bus:phy_addr=b232400.mdio:00, irq=POLL)
[   11.045553] TI DP83867 b232400.mdio:03: attached PHY driver [TI DP83867] (mii_bus:phy_addr=b232400.mdio:03, irq=POLL)
[   11.045605] icssg-prueth icssg2-eth: TI PRU ethernet driver initialized: dual EMAC mode
[  OK  ] Started File System Check on /dev/mmcblk1p1.
         Mounting /run/media/mmcblk1p1...
[  OK  ] Started udev Wait for Complete Device Initialization.
[  OK  ] Started Hardware RNG Entropy Gatherer Daemon.
[  OK  ] Reached target System Initialization.
[  OK  ] Started Daily rotation of log files.
[  OK  ] Started Daily Cleanup of Temporary Directories.
[  OK  ] Reached target Timers.
[  OK  ] Listening on Avahi mDNS/DNS-SD Stack Activation Socket.
[  OK  ] Listening on D-Bus System Message Bus Socket.
         Starting Docker Socket for the API.
[  OK  ] Listening on dropbear.socket.
         Starting Reboot and dump vmcore via kexec...
[  OK  ] Mounted /run/media/mmcblk1p1.
[  OK  ] Listening on Docker Socket for the API.
[  OK  ] Started Reboot and dump vmcore via kexec.
[  OK  ] Reached target Sockets.
[  OK  ] Reached target Basic System.
[  OK  ] Started Job spooling tools.
[  OK  ] Started Periodic Command Scheduler.
[  OK  ] Started D-Bus System Message Bus.
         Starting Print notice about GPLv3 packages...
         Starting IPv6 Packet Filtering Framework...
         Starting IPv4 Packet Filtering Framework...
[  OK  ] Started irqbalance daemon.
         Starting Telephony service...
         Starting rc.pvr.service...
         Starting Login Service...
[  OK  ] Started TEE Supplicant.
[  OK  ] Started IPv6 Packet Filtering Framework.
[  OK  ] Started IPv4 Packet Filtering Framework.
[  OK  ] Reached target Network (Pre).
         Starting Network Service...
[   11.854659] PVR_K: UM DDK-(4948957) and KM DDK-(4948957) match. [ OK ]
[  OK  ] Started Telephony service.
[  OK  ] Listening on Load/Save RF …itch Status /dev/rfkill Watch.
[   11.950844] Bluetooth: Core ver 2.22
[   11.951012] NET: Registered protocol family 31
[   11.951018] Bluetooth: HCI device and connection manager initialized
[   11.951047] Bluetooth: HCI socket layer initialized
[   11.951062] Bluetooth: L2CAP socket layer initialized
[   11.951090] Bluetooth: SCO socket layer initialized
[  OK  ] Started rc.pvr.service.
         Starting weston.service...
[  OK  ] Started weston.service.
         Starting telnetd.service...
[  OK  ] Started telnetd.service.
[  OK  ] Started Network Service.
         Starting Wait for Network to be Configured...
         Starting Network Name Resolution...
[   12.500680] remoteproc remoteproc15: powering up b238000.pru
[   12.510806] remoteproc remoteproc15: Booting fw image ti-pruss/am65x-sr2-pru1-prueth-fw.elf, size 38496
[   12.510851] remoteproc remoteproc15: unsupported resource 5
[   12.510884] remoteproc remoteproc15: remote processor b238000.pru is now up
[   12.510929] remoteproc remoteproc16: powering up b206000.rtu
[   12.515130] remoteproc remoteproc16: Booting fw image ti-pruss/am65x-sr2-rtu1-prueth-fw.elf, size 30104
[   12.515180] remoteproc remoteproc16: remote processor b206000.rtu is now up
[   12.515223] remoteproc remoteproc17: powering up b20c000.txpru
[   12.518481] remoteproc remoteproc17: Booting fw image ti-pruss/am65x-sr2-txpru1-prueth-fw.elf, size 35836
[   12.518534] remoteproc remoteproc17: remote processor b20c000.txpru is now up
[   12.523934] icssg-prueth icssg2-eth: settime timeout
[   12.524311] pps pps0: new PPS source ptp2
[   12.532190] net eth6: started
[   12.644385] remoteproc remoteproc12: powering up b234000.pru
[   12.647318] remoteproc remoteproc12: Booting fw image ti-pruss/am65x-sr2-pru0-prueth-fw.elf, size 38224
[   12.647354] remoteproc remoteproc12: unsupported resource 5
[   12.647385] remoteproc remoteproc12: remote processor b234000.pru is now up
[   12.647425] remoteproc remoteproc13: powering up b204000.rtu
[   12.654673] remoteproc remoteproc13: Booting fw image ti-pruss/am65x-sr2-rtu0-prueth-fw.elf, size 30872
[   12.654730] remoteproc remoteproc13: remote processor b204000.rtu is now up
[   12.654772] remoteproc remoteproc14: powering up b20a000.txpru
[   12.658270] remoteproc remoteproc14: Booting fw image ti-pruss/am65x-sr2-txpru0-prueth-fw.elf, size 37328
[   12.658328] remoteproc remoteproc14: remote processor b20a000.txpru is now up
[   12.673619] net eth5: started
[   12.736860] remoteproc remoteproc3: powering up b038000.pru
[   12.737132] remoteproc remoteproc3: Booting fw image ti-pruss/am65x-sr2-pru1-prueth-fw.elf, size 38496
[   12.737162] remoteproc remoteproc3: unsupported resource 5
[   12.737194] remoteproc remoteproc3: remote processor b038000.pru is now up
[   12.737230] remoteproc remoteproc4: powering up b006000.rtu
[   12.737395] remoteproc remoteproc4: Booting fw image ti-pruss/am65x-sr2-rtu1-prueth-fw.elf, size 30104
[   12.737428] remoteproc remoteproc4: remote processor b006000.rtu is now up
[   12.737451] remoteproc remoteproc5: powering up b00c000.txpru
[   12.737593] remoteproc remoteproc5: Booting fw image ti-pruss/am65x-sr2-txpru1-prueth-fw.elf, size 35836
[   12.737631] remoteproc remoteproc5: remote processor b00c000.txpru is now up
[   12.747191] icssg-prueth icssg0-eth: settime timeout
[   12.747568] pps pps1: new PPS source ptp3
[   12.761958] net eth4: started
[   12.840159] remoteproc remoteproc0: powering up b034000.pru
[   12.840433] remoteproc remoteproc0: Booting fw image ti-pruss/am65x-sr2-pru0-prueth-fw.elf, size 38224
[   12.840462] remoteproc remoteproc0: unsupported resource 5
[   12.840494] remoteproc remoteproc0: remote processor b034000.pru is now up
[   12.840533] remoteproc remoteproc1: powering up b004000.rtu
[   12.840734] remoteproc remoteproc1: Booting fw image ti-pruss/am65x-sr2-rtu0-prueth-fw.elf, size 30872
[   12.840771] remoteproc remoteproc1: remote processor b004000.rtu is now up
[   12.840797] remoteproc remoteproc2: powering up b00a000.txpru
[   12.840996] remoteproc remoteproc2: Booting fw image ti-pruss/am65x-sr2-txpru0-prueth-fw.elf, size 37328
[   12.841710] remoteproc remoteproc2: remote processor b00a000.txpru is now up
[   12.872202] net eth3: started
[   12.938695] remoteproc remoteproc9: powering up b138000.pru
[   12.938982] remoteproc remoteproc9: Booting fw image ti-pruss/am65x-sr2-pru1-prueth-fw.elf, size 38496
[   12.939013] remoteproc remoteproc9: unsupported resource 5
[   12.939045] remoteproc remoteproc9: remote processor b138000.pru is now up
[   12.939081] remoteproc remoteproc10: powering up b106000.rtu
[   12.939231] remoteproc remoteproc10: Booting fw image ti-pruss/am65x-sr2-rtu1-prueth-fw.elf, size 30104
[   12.939264] remoteproc remoteproc10: remote processor b106000.rtu is now up
[   12.939289] remoteproc remoteproc11: powering up b10c000.txpru
[   12.939426] remoteproc remoteproc11: Booting fw image ti-pruss/am65x-sr2-txpru1-prueth-fw.elf, size 35836
[   12.939464] remoteproc remoteproc11: remote processor b10c000.txpru is now up
[   12.944700] icssg-prueth icssg1-eth: settime timeout
[   12.945037] pps pps2: new PPS source ptp4
[   12.969526] net eth2: started
[  OK  ] Started Login Service.[   13.090879] remoteproc remoteproc6: powering up b134000.pru
[   13.091126] remoteproc remoteproc6: Booting fw image ti-pruss/am65x-sr2-pru0-prueth-fw.elf, size 38224
[    0.000000] rcu: 	RCU restricting CPUs from NR_CPUS=256 to nr_cpu_i
[   13.091158] remoteproc remoteproc6: unsupported resource 5
[   13.091189] remoteproc remoteproc6: remote processor b134000.pru is now up
[   13.091226] remoteproc remoteproc7: powering up b104000.rtu
[   13.091360] remoteproc remoteproc7: Booting fw image ti-pruss/am65x-sr2-rtu0-prueth-fw.elf, size 30872
[   13.091399] remoteproc remoteproc7: remote processor b104000.rtu is now up
[   13.091426] remoteproc remoteproc8: powering up b10a000.txpru
[   13.091566] remoteproc remoteproc8: Booting fw image ti-pruss/am65x-sr2-txpru0-prueth-fw.elf, size 37328
[   13.091605] remoteproc remoteproc8: remote processor b10a000.txpru is now up
[   13.095797] net eth1: started
[   13.188604] am65-cpsw-nuss 46000000.ethernet eth0: PHY [46000f00.mdio:00] driver [TI DP83867] (irq=POLL)
[   13.188641] am65-cpsw-nuss 46000000.ethernet eth0: configuring for phy/rgmii-rxid link mode
[  OK  ] Started Network Name Resolution.
[  OK  ] Reached target Network.
[  OK  ] Reached target Host and Network Name Lookups.
         Starting Avahi mDNS/DNS-SD Stack...
         Starting Enable and configure wl18xx bluetooth stack...
[  OK  ] Started NFS status monitor for NFSv2/3 locking..
         Starting Simple Network Ma…ent Protocol (SNMP) Daemon....
         Starting Permit User Sessions...
[  OK  ] Started Enable and configure wl18xx bluetooth stack.
[  OK  ] Started Avahi mDNS/DNS-SD Stack.
[  OK  ] Started Permit User Sessions.
[  OK  ] Started Getty on tty1.
[  OK  ] Started Getty on tty4.
[  OK  ] Started Serial Getty on ttyS1.
[  OK  ] Started Serial Getty on ttyS2.
[  OK  ] Reached target Login Prompts.
         Starting Synchronize System and HW clocks...
[FAILED] Failed to start Synchronize System and HW clocks.
See 'systemctl status sync-clocks.service' for details.
[  OK  ] Started Simple Network Man…ement Protocol (SNMP) Daemon..
***************************************************************
***************************************************************
NOTICE: This file system contains the following GPLv3 packages:
	autoconf
	bash-dev
	bash
	bc
	binutils
	cifs-utils
	coreutils-stdbuf
	coreutils
	cpio
	cpp-symlinks
	cpp
	dosfstools
	elfutils
	g++-symlinks
	g++
	gawk
	gcc-symlinks
	gcc
	gdb
	gdbserver
	gettext
	glmark2
	gstreamer1.0-libav-dev
	gstreamer1.0-libav
	gzip
	hidapi
	less
	libasm1
	libbfd
	libdw1
	libelf1
	libgdbm-compat4
	libgdbm-dev
	libgdbm6
	libgettextlib
	libgettextsrc
	libgmp-dev
	libgmp10
	libgmpxx4
	libidn2-0
	libidn2-dev
	libmpc3
	libmpfr6
	libreadline-dev
	libreadline8
	libunistring-dev
	libunistring2
	m4-dev
	m4
	make
	nettle-dev
	nettle
	parted
	pdm-anomaly-detection
	python3-rfc3987
	python3-strict-rfc3339
	tar
	which
	zeromq

If you do not wish to distribute GPLv3 components please remove
the above packages prior to distribution.  This can be done using
the opkg remove command.  i.e.:
    opkg remove <package>
Where <package> is the name printed in the list above

NOTE: If the package is a dependency of another package you
      will be notified of the dependent packages.  You should
      use the --force-removal-of-dependent-packages option to
      also remove the dependent packages as well
***************************************************************
***************************************************************
[  OK  ] Started Print notice about GPLv3 packages.

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

Arago Project am65xx-evm ttyS2

Arago 2021.09 am65xx-evm ttyS2

am65xx-evm login:

Thanks!

Huichun

0 Huichun Feng 7 months ago in reply to Huichun Feng

Prodigy 81 points

By cat-ing /proc/iomem, I got the following:

0011c000-0011c2e3 : pinctrl-single
0011c2e8-0011c30b : pinctrl-single
00600000-006000ff : 600000.gpio gpio@600000
00601000-006010ff : 601000.gpio gpio@601000
02000000-020000ff : 2000000.i2c i2c@2000000
02010000-020100ff : 2010000.i2c i2c@2010000
02020000-020200ff : 2020000.i2c i2c@2020000
02030000-020300ff : 2030000.i2c i2c@2030000
02100000-021003ff : 2100000.spi spi@2100000
02110000-021103ff : 2110000.spi spi@2110000
02120000-021203ff : 2120000.spi spi@2120000
02130000-021303ff : 2130000.spi spi@2130000
02140000-021403ff : 2140000.spi spi@2140000
02800000-0280001f : serial
02810000-0281001f : serial
02820000-0282001f : serial
03000000-030000ff : 3000000.pwm pwm@3000000
03010000-030100ff : 3010000.pwm pwm@3010000
03020000-030200ff : 3020000.pwm pwm@3020000
03030000-030300ff : 3030000.pwm pwm@3030000
03040000-030400ff : 3040000.pwm pwm@3040000
03050000-030500ff : 3050000.pwm pwm@3050000
03100000-0310005f : 3100000.pwm pwm@3100000
04020000-04023fff : 4020000.dwc3 dwc3@4020000
0403c100-0403ffff : 4030000.usb usb@10000
04110000-04110053 : 4110000.phy phy@4110000
04e00000-04e011ff : 4e00000.crypto crypto@4e00000
04f80000-04f8025f : 4f80000.mmc mmc@4f80000
04f90000-04f90133 : 4f80000.mmc mmc@4f80000
04fa0000-04fa025f : 4fa0000.mmc mmc@4fa0000
04fb0000-04fb0133 : 4fa0000.mmc mmc@4fa0000
0b004000-0b005fff : b004000.rtu iram
0b006000-0b007fff : b006000.rtu iram
0b00a000-0b00b7ff : b00a000.txpru iram
0b00c000-0b00d7ff : b00c000.txpru iram
0b020000-0b021fff : b020000.interrupt-controller interrupt-controller@20000
0b022000-0b0220ff : b034000.pru control
0b022400-0b0224ff : b034000.pru debug
0b023000-0b0230ff : b004000.rtu control
0b023400-0b0234ff : b004000.rtu debug
0b023800-0b0238ff : b006000.rtu control
0b023c00-0b023cff : b006000.rtu debug
0b024000-0b0240ff : b038000.pru control
0b024400-0b0244ff : b038000.pru debug
0b025000-0b0250ff : b00a000.txpru control
0b025400-0b0254ff : b00a000.txpru debug
0b025800-0b0258ff : b00c000.txpru control
0b025c00-0b025cff : b00c000.txpru debug
0b02e000-0b02efff : b02e000.iep iep@2e000
0b02f000-0b02ffff : b02f000.iep iep@2f000
0b034000-0b037fff : b034000.pru iram
0b038000-0b03bfff : b038000.pru iram
0b104000-0b105fff : b104000.rtu iram
0b106000-0b107fff : b106000.rtu iram
0b10a000-0b10b7ff : b10a000.txpru iram
0b10c000-0b10d7ff : b10c000.txpru iram
0b120000-0b121fff : b120000.interrupt-controller interrupt-controller@20000
0b122000-0b1220ff : b134000.pru control
0b122400-0b1224ff : b134000.pru debug
0b123000-0b1230ff : b104000.rtu control
0b123400-0b1234ff : b104000.rtu debug
0b123800-0b1238ff : b106000.rtu control
0b123c00-0b123cff : b106000.rtu debug
0b124000-0b1240ff : b138000.pru control
0b124400-0b1244ff : b138000.pru debug
0b125000-0b1250ff : b10a000.txpru control
0b125400-0b1254ff : b10a000.txpru debug
0b125800-0b1258ff : b10c000.txpru control
0b125c00-0b125cff : b10c000.txpru debug
0b12e000-0b12efff : b12e000.iep iep@2e000
0b12f000-0b12ffff : b12f000.iep iep@2f000
0b134000-0b137fff : b134000.pru iram
0b138000-0b13bfff : b138000.pru iram
0b204000-0b205fff : b204000.rtu iram
0b206000-0b207fff : b206000.rtu iram
0b20a000-0b20b7ff : b20a000.txpru iram
0b20c000-0b20d7ff : b20c000.txpru iram
0b220000-0b221fff : b220000.interrupt-controller interrupt-controller@20000
0b222000-0b2220ff : b234000.pru control
0b222400-0b2224ff : b234000.pru debug
0b223000-0b2230ff : b204000.rtu control
0b223400-0b2234ff : b204000.rtu debug
0b223800-0b2238ff : b206000.rtu control
0b223c00-0b223cff : b206000.rtu debug
0b224000-0b2240ff : b238000.pru control
0b224400-0b2244ff : b238000.pru debug
0b225000-0b2250ff : b20a000.txpru control
0b225400-0b2254ff : b20a000.txpru debug
0b225800-0b2258ff : b20c000.txpru control
0b225c00-0b225cff : b20c000.txpru debug
0b22e000-0b22efff : b22e000.iep iep@2e000
0b22f000-0b22ffff : b22f000.iep iep@2f000
0b234000-0b237fff : b234000.pru iram
0b238000-0b23bfff : b238000.pru iram
28590000-285900ff : 2b800000.ringacc proxy_gcfg
285c0000-285c00ff : 285c0000.dma-controller gcfg
2a500000-2a53ffff : 2b800000.ringacc proxy_target
2a800000-2a83ffff : 285c0000.dma-controller rchanrt
2aa00000-2aa3ffff : 285c0000.dma-controller tchanrt
2b000000-2b3fffff : 2b800000.ringacc fifos
2b800000-2bbfffff : 2b800000.ringacc rt
30e00000-30e00fff : 30e00000.spinlock spinlock@30e00000
310d0000-310d03ff : 310d0000.cpts cpts
31120000-311200ff : 3c000000.ringacc proxy_gcfg
31150000-311500ff : 31150000.dma-controller gcfg
31f80000-31f801ff : 31f80000.mailbox mailbox@31f80000
31f81000-31f811ff : 31f81000.mailbox mailbox@31f81000
32400000-324fffff : 32c00000.mailbox rt
32800000-328fffff : 32c00000.mailbox scfg
32c00000-32cfffff : 32c00000.mailbox target_data
33000000-3303ffff : 3c000000.ringacc proxy_target
33d00000-33dfffff : 33d00000.interrupt-controller interrupt-controller@33d00000
34000000-340fffff : 31150000.dma-controller rchanrt
35000000-350fffff : 31150000.dma-controller tchanrt
38000000-383fffff : 3c000000.ringacc fifos
3c000000-3c3fffff : 3c000000.ringacc rt
40200000-40200fff : 40200000.tscadc tscadc@40200000
40210000-40210fff : 40210000.tscadc tscadc@40210000
40300000-403003ff : 40300000.spi spi@40300000
40310000-403103ff : 40310000.spi spi@40310000
40320000-403203ff : 40320000.spi spi@40320000
40528000-405283ff : 40528000.mcan m_can
40568000-405683ff : 40568000.mcan m_can
40a00000-40a0001f : serial
40b00000-40b000ff : 40b00000.i2c i2c@40b00000
41c00000-41c7ffff : 41c00000.sram sram@41c00000
42050000-4205025b : 42050000.temperature-sensor temperature-sensor@42050000
42110000-421100ff : 42110000.gpio gpio@42110000
42120000-421200ff : 42120000.i2c i2c@42120000
4301c000-4301c117 : pinctrl-single
46000000-461fffff : 46000000.ethernet cpsw_nuss
47040000-470400ff : 47040000.spi spi@47040000
47050000-470500ff : 47050000.spi spi@47050000
70000000-700effff : 70000000.sram sram@70000000
80000000-9e7fffff : System RAM
  82000000-82f0ffff : Kernel code
  82f10000-830cffff : reserved
  830d0000-8326ffff : Kernel data
  8feed000-8fefefff : reserved
9e800000-a20fffff : reserved
a2100000-ffffffff : System RAM
  dc000000-ffffffff : reserved
500000000-5ffffffff : 47040000.spi spi@47040000
700000000-7ffffffff : 47050000.spi spi@47050000
880000000-8ffffffff : System RAM
  8fb000000-8feffffff : reserved
  8ff122000-8ff181fff : reserved
  8ff182000-8ff782fff : reserved
  8ff783000-8ff7cafff : reserved
  8ff7cd000-8ff7cefff : reserved
  8ff7cf000-8ff7cffff : reserved
  8ff7d0000-8ff7d2fff : reserved
  8ff7d3000-8ff7d3fff : reserved
  8ff7d4000-8ffffffff : reserved

Of which "70000000-700effff : 70000000.sram sram@70000000" is pretty interesting. It seems that our ELF loading address, e.g. 0x70020000, still lays in the region that Linux doesn't expect to be used by the remote core.

0 Nick Saulnier 6 months ago in reply to Huichun Feng

TI__Guru* 76425 points

Hello Huichun,

I started replicating your setup today. Will try to finish testing tomorrow and get back to you. Feel free to ping the thread if I haven't responded by the beginning of next week.

Regards,

Nick

0 Huichun Feng 6 months ago in reply to Huichun Feng

Prodigy 81 points

Huichun Feng said:
Of which "70000000-700effff : 70000000.sram sram@70000000" is pretty interesting. It seems that our ELF loading address, e.g. 0x70020000, still lays in the region that Linux doesn't expect to be used by the remote core.

Well, I've mis-understood the mapping. By loading the ELF of size 0xa784c to 0x7002_0000, we're using memory between atf-sram and sysfw-sram, which means we didn't compromise others memory.

So probably SRAM is not usable by the R5F when running Linux alongside?

I'll check the doc further and try to use the DDR instead. (AFAIK, DDR has already initialized after the R5F done its boot stage work.)

0 Nick Saulnier 6 months ago in reply to Huichun Feng

TI__Guru* 76425 points

Hello Huichun,

Apologies for the delayed response. So far I am unable to replicate your observation with the PCIE peripheral on the default EVM & filesystem image, where the only thing that is getting changed is the R5F firmware. I am using the same PCIE daughter card as you are, with nothing plugged into the daughter card.

The exact version of Linux is
root@am65xx-evm:~# uname -a
Linux am65xx-evm 5.10.100-g7a7a3af903 #1 SMP PREEMPT Thu Mar 24 02:44:45 UTC 2022 aarch64 aarch64 aarch64 GNU/Linux

I am not actually seeing any PCIE output in the boot logs. Did you have to change some kernel config settings to enable the PCIE driver?

So far I have tested:
1) default gpio_led_blink example. the board hangs before the kernel can even boot, as in your observations
2) gpio_led_blink example, only change is linker.cmd pointing to 0x70020000 instead of 0x70000000. Board boots as expected with no errors, but Linux does not detect the R5F is currently running (? need to double check), and the R5F firmware gets overwritten at some point in the boot process

The next things I plan to test is
1) default ipc_rpmsg_echo_linux project, then ipc_rpmsg_echo_linux where data is placed in 0x70020000
2) gpio_led_blink, but with all the modifications I would expect to run alongside Linux (memory allocations, resource table, check Linux devicetree for conflicts & resolve)

Regards,

Nick

0 Huichun Feng 6 months ago in reply to Nick Saulnier

Prodigy 81 points

Hello Nick,

Seems that using memory region that is not used by Linux is not enough. Instead, explicitly specifying a region that one would like to use on the remote processor is required. IOW, one should have something like this:

          msmc_ram: sram@70000000 {
                  compatible = "mmio-sram";
                  reg = <0x0 0x70000000 0x0 0x200000>;
                  #address-cells = <1>;
                  #size-cells = <1>;
                  ranges = <0x0 0x0 0x70000000 0x200000>;
  
                  atf-sram@0 {
                          reg = <0x0 0x20000>;
                  };
  
+                 r5-sram@0x20000 {
+                         reg = <0x20000 0xd0000>;
+                 };
+ 
                  sysfw-sram@f0000 {
                          reg = <0xf0000 0x10000>;
                  };
  
                  l3cache-sram@100000 {
                          reg = <0x100000 0x100000>;
                  };
          };

By setting this, the R5F can now run alongside a booted Linux. Also, the PCIe oops is no longer there w/ the daughter card installed.

---

Another problem is that, if the loading address of the R5F app lays within the DDR instead of the MSMC, the app loading stage can be done w/o error, but the execution would be a problem. Specifically, after issuing "rproc start 0", the R5F does not start the execution, it just hangs. Looks like something related to memory execution bit permission setting.

However, as the problem is off-topic to this thread, I'll open another thread for discussion.

Thanks!

Huichun

0 Huichun Feng 6 months ago in reply to Nick Saulnier

Prodigy 81 points

Hello Nick,

I saw this reply after the last post ;p

Nick Saulnier said:
I am not actually seeing any PCIE output in the boot logs. Did you have to change some kernel config settings to enable the PCIE driver?

My kernel is:

Linux am65xx-evm 5.10.168-rt83-gd14fbab5cc #1 SMP PREEMPT_RT Sat Mar 25 15:39:18 UTC 2023 aarch64 aarch64 aarch64 GNU/Linux

Seems the major difference is that you are using non-RT kernel, and mine is the RT one (from the Linux-RT SDK), there must be enormous differences. And I made no change to the kernel config, nor the PCIe driver.

Seems changing the device tree would get rid of the R5F overwriting issue.

Thanks!

Huichun

0 Nick Saulnier 6 months ago in reply to Huichun Feng

TI__Guru* 76425 points

Hello Huichun,

Glad to hear that things are working now!

I am not familiar with the PCIE drivers, but I wonder if it behaves like the PRU Ethernet driver? With the PRU Ethernet driver, we pass in the entire SRAM by default:

arch/arm64/boot/dts/ti/k3-am654-idk.dts

                        /* Dual Ethernet application node on PRU-ICSSG0 */
                        icssg0_eth: icssg0-eth {
...
                                sram = <&msmc_ram>;

Which means that the Linux PRUETH driver thinks it can just use ALL of the SRAM that is not already allocated. (By the way, future readers: we now document how much SRAM has to be made available for the PRUETH driver, so you can partition your SRAM appropriately: https://software-dl.ti.com/processor-sdk-linux/esd/AM65X/09_01_00_01/exports/docs/linux/Foundational_Components/PRU-ICSS/Linux_Drivers/PRU_ICSSG_Ethernet.html#sram-requirement )

If something similar was going on with the PCIE, allocating the SRAM that was getting used by the R5F in the Linux devicetree would absolutely fix the issue.

If there are followup discussions that need to be had, feel free to create a new thread.

Regards,

Nick

0 Nick Saulnier 6 months ago in reply to Nick Saulnier

TI__Guru* 76425 points

For future readers, we are continuing the discussion about how to make sure there is enough SRAM for PRU Ethernet on this new thread:

https://e2e.ti.com/support/processors-group/processors/f/processors-forum/1307841/re-am6548-allocating-sram-for-pru-ethernet

Regards,

Nick

0 Huichun Feng 6 months ago in reply to Nick Saulnier

Prodigy 81 points

For future reader, instead of reading the pinned reply, you can also refer to:

https://e2e.ti.com/support/processors-group/processors/f/processors-forum/1298904/am6548-sbl-doesn-t-load-appimage-from-pdk-example/4960458#4960458

In which you may find something that is also vital for you.

Hello Nick,

Sorry for the late reply, I am now in the middle of another project. I'll go back as soon as I get a moment. Thank you for the exhaustive replies!

Thanks!

Huichun

Processors

Processors forum

AM6548: SBL doesn't load appimage from PDK example