This thread has been locked.

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

TDA4VM: system blocked after booting fw with "remoteproc mode"

Part Number: TDA4VM

Hi,

   system blocked after booting fw with "remoteproc mode"

   I do the test on my platform , psdkla version is sdk7.2 , platform is our customer board, 

1, set dorprocboot=0 in uboot,  linux blocked at "remoteproc remoteproc4: Booting fw image j7-main-r5f0_1-fw"

2. set dorprocboot=1 in uboot,  linux work fine.

   About the block problem, I add some log for simple test, it seems that the system was blocked at below code in funciton k3_r5_rproc_prepare

memset(core->mem[0].cpu_addr, 0x00, core->mem[0].size);

  

please check the picture attached. 

system blocked log:

[2021-08-19 16:23:42.584] [    3.065923] remoteproc remoteproc4: 5d00000.r5f is available
[2021-08-19 16:23:42.757] [    3.258858] hjwang rproc_boot-2110:5d00000.r5f 0
[2021-08-19 16:23:42.803] [    3.263490] remoteproc remoteproc4: powering up 5d00000.r5f
[2021-08-19 16:23:42.803] [    3.269910] remoteproc remoteproc4: Booting fw image j7-main-r5f0_1-fw, size 1923072
[2021-08-19 16:23:42.804] [    3.277664] hjwang rproc_fw_boot-1663:5d00000.r5f 
[2021-08-19 16:23:42.804] [    3.282753] hjwang rproc_fw_boot-1665:5d00000.r5f ret=0
[2021-08-19 16:23:42.804] [    3.288107] hjwang k3_r5_rproc_prepare-396:5d00000.r5f 
[2021-08-19 16:23:42.804] [    3.293588] hjwang k3_r5_rproc_prepare-399:5d00000.r5f 
[2021-08-19 16:23:42.805] [    3.299141] hjwang k3_r5_rproc_prepare-407:5d00000.r5f 
[2021-08-19 16:23:42.805] [    3.304369] hjwang k3_r5_rproc_prepare-413:5d00000.r5f 
[2021-08-19 16:23:42.824] [    3.309590] hjwang k3_r5_rproc_prepare-425:5d00000.r5f 

source code picture:

 After compare the logs between good and no good, the difference as below, I hope it is useful.

and I attach the log to the case 

ipc_only_mode_work.log    :  boot up ok

remoteproc_mode_fail.log : boot up failed

 

[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x411fd080]
[    0.000000] Linux version 5.4.74-ACU-1.3.21.1 (hjwang@Tower-5810-Nvidia-Host) (gcc version 9.2.1 20191025 (GNU Toolchain for the A-profile Architecture 9.2-2019.12 (arm-9.10))) #11 SMP PREEMPT Thu Aug 19 15:51:24 CST 2021
[    0.000000] Machine model: Texas Instruments K3 J721E SoC
[    0.000000] earlycon: ns16550a0 at MMIO32 0x0000000002800000 (options '')
[    0.000000] printk: bootconsole [ns16550a0] enabled
[    0.000000] OF: reserved mem: not enough space all defined regions.
[    0.000000] OF: reserved mem: not enough space all defined regions.
[    0.000000] OF: reserved mem: OVERLAP DETECTED!
[    0.000000] vision_apps-r5f-dma-memory@a1000000 (0x00000000a1000000--0x00000000a1100000) overlaps with r5f-dma-memory@a1000000 (0x00000000a1000000--0x00000000a1100000)
[    0.000000] OF: reserved mem: OVERLAP DETECTED!
[    0.000000] vision_apps-r5f-memory@a1100000 (0x00000000a1100000--0x00000000a3000000) overlaps with r5f-memory@a1100000 (0x00000000a1100000--0x00000000a2000000)
[    0.000000] OF: reserved mem: OVERLAP DETECTED!
[    0.000000] r5f-memory@a3100000 (0x00000000a3100000--0x00000000a4000000) overlaps with vision_apps-r5f-memory@a3100000 (0x00000000a3100000--0x00000000a5000000)
[    0.000000] OF: reserved mem: OVERLAP DETECTED!
[    0.000000] vision_apps-r5f-memory@a3100000 (0x00000000a3100000--0x00000000a5000000) overlaps with r5f-dma-memory@a4000000 (0x00000000a4000000--0x00000000a4100000)
[    0.000000] OF: reserved mem: OVERLAP DETECTED!
[    0.000000] vision_apps-r5f-dma-memory@a5000000 (0x00000000a5000000--0x00000000a5100000) overlaps with r5f-dma-memory@a5000000 (0x00000000a5000000--0x00000000a5100000)
[    0.000000] OF: reserved mem: OVERLAP DETECTED!
[    0.000000] r5f-memory@a5100000 (0x00000000a5100000--0x00000000a6000000) overlaps with vision_apps-r5f-memory@a5100000 (0x00000000a5100000--0x00000000a5800000)
[    0.000000] Reserved memory: created DMA memory pool at 0x0000000094000000, size 128 MiB
[    0.000000] OF: reserved mem: initialized node vision_apps-core-heap-memory@94000000, compatible id shared-dma-pool
[    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 vision_apps-r5f-dma-memory@a1000000, 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 31 MiB
[    0.000000] OF: reserved mem: initialized node vision_apps-r5f-memory@a1100000, 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] Reserved memory: created DMA memory pool at 0x00000000a2000000, size 1 MiB
[    0.000000] OF: reserved mem: initialized node r5f-dma-memory@a2000000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a2100000, size 15 MiB
[    0.000000] OF: reserved mem: initialized node r5f-memory@a2100000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a3000000, size 1 MiB
[    0.000000] OF: reserved mem: initialized node r5f-dma-memory@a3000000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a3100000, size 15 MiB
[    0.000000] OF: reserved mem: initialized node r5f-memory@a3100000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a3100000, size 31 MiB
[    0.000000] OF: reserved mem: initialized node vision_apps-r5f-memory@a3100000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a4000000, size 1 MiB
[    0.000000] OF: reserved mem: initialized node r5f-dma-memory@a4000000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a4100000, size 15 MiB
[    0.000000] OF: reserved mem: initialized node r5f-memory@a4100000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a5000000, size 1 MiB
[    0.000000] OF: reserved mem: initialized node vision_apps-r5f-dma-memory@a5000000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a5000000, size 1 MiB
[    0.000000] OF: reserved mem: initialized node r5f-dma-memory@a5000000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a5100000, size 15 MiB
[    0.000000] OF: reserved mem: initialized node r5f-memory@a5100000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a5100000, size 7 MiB
[    0.000000] OF: reserved mem: initialized node vision_apps-r5f-memory@a5100000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a5800000, size 1 MiB
[    0.000000] OF: reserved mem: initialized node vision_apps-r5f-dma-memory@a5800000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a5900000, size 7 MiB
[    0.000000] OF: reserved mem: initialized node vision_apps-r5f-memory@a5900000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a6000000, size 1 MiB
[    0.000000] OF: reserved mem: initialized node c66-dma-memory@a6000000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a6100000, size 15 MiB
[    0.000000] OF: reserved mem: initialized node c66-memory@a6100000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a7000000, size 1 MiB
[    0.000000] OF: reserved mem: initialized node c66-dma-memory@a7000000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a7100000, size 15 MiB
[    0.000000] OF: reserved mem: initialized node c66-memory@a7100000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a8000000, size 1 MiB
[    0.000000] OF: reserved mem: initialized node c71-dma-memory@a8000000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a8100000, size 31 MiB
[    0.000000] OF: reserved mem: initialized node c71-memory@a8100000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000ac000000, size 32 MiB
[    0.000000] OF: reserved mem: initialized node vision_apps-dma-memory@ac000000, compatible id shared-dma-pool
[    0.000000] OF: reserved mem: initialized node vision_apps_shared-memories, compatible id dma-heap-carveout
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000ce000000, size 720 MiB
[    0.000000] OF: reserved mem: initialized node vision_apps-core-heap-memory@ce000000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000fb000000, size 1 MiB
[    0.000000] OF: reserved mem: initialized node vision_apps-r5f-dma-memory@fb000000, compatible id shared-dma-pool
[    0.000000] cma: Failed to reserve 512 MiB
[    0.000000] On node 0 totalpages: 27840
[    0.000000]   DMA zone: 11 pages used for memmap
[    0.000000]   DMA zone: 0 pages reserved
[    0.000000]   DMA zone: 10368 pages, LIFO batch:1
[    0.000000]   DMA32 zone: 16 pages used for memmap
[    0.000000]   DMA32 zone: 18446744073709536320 pages, LIFO batch:3
[    0.000000]   Normal zone: 32 pages used for memmap
[    0.000000]   Normal zone: 32768 pages, LIFO batch:3
[    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.0
[    0.000000] percpu: Embedded 2 pages/cpu s48408 r8192 d74472 u131072
[    0.000000] pcpu-alloc: s48408 r8192 d74472 u131072 alloc=2*65536
[    0.000000] pcpu-alloc: [0] 0 [0] 1 
[    0.000000] Detected PIPT I-cache on CPU0
[    0.000000] CPU features: detected: GIC system register CPU interface
[    0.000000] CPU features: detected: EL2 vector hardening
[    0.000000] Built 1 zonelists, mobility grouping off.  Total pages: 27781
[    0.000000] Kernel command line: console=ttyS2,115200n8 earlycon=ns16550a,mmio32,0x02800000 mtdparts=47040000.spi.0:512k(ospi.sbl),512k(ospi.tifs_a),512k(ospi.tifs_b),4m(ospi.mcufw_a),4m(ospi.mcufw_b),2m(ospi.atf_optee_a),2m(ospi.atf_optee_b),1m(ospi.u-boot-spl_a),1m(ospi.u-boot-spl_b),2m(ospi.u-boot_a),2m(ospi.u-boot_b),128k(ospi.misc_a),128k(ospi.misc_b),128k(ospi.factory),128k(ospi.safe_a),128k(ospi.safe_b),2m(ospi.mcudata),256k(ospi.sonar_data),10092544(ospi.reserved) root=PARTUUID=00000000-02 ro rootfstype=ext4 rootwait loglevel=0 lpj=800000
[    0.000000] Dentry cache hash table entries: 262144 (order: 5, 2097152 bytes, linear)
[    0.000000] Inode-cache hash table entries: 131072 (order: 4, 1048576 bytes, linear)
[    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
[    0.000000] software IO TLB: mapped [mem 0x90000000-0x94000000] (64MB)
[    0.000000] Memory: 2435968K/1781760K available (9406K kernel code, 784K rwdata, 3776K rodata, 1664K init, 705K bss, 18446744073708897408K reserved, 0K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[    0.000000] rcu: Preemptible hierarchical RCU implementation.
[    0.000000] rcu: 	RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=2.
[    0.000000] 	Tasks RCU enabled.
[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2
[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
[    0.000000] GICv3: GIC: Using split EOI/Deactivate mode
[    0.000000] GICv3: 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: no VLPI support, no direct LPI support
[    0.000000] GICv3: CPU0: found redistributor 0 region 0:0x0000000001900000
[    0.000000] ITS [mem 0x01820000-0x0182ffff]
[    0.000000] GIC: enabling workaround for ITS: Socionext Synquacer pre-ITS
[    0.000000] ITS@0x0000000001820000: allocated 1048576 Devices @8c0800000 (flat, esz 8, psz 64K, shr 0)
[    0.000000] ITS: using cache flushing for cmd queue
[    0.000000] GICv3: using LPI property table @0x00000008c00c0000
[    0.000000] GIC: using cache flushing for LPI property table
[    0.000000] GICv3: CPU0: using allocated LPI pending table @0x00000008c00d0000
[    0.000000] random: get_random_bytes called from start_kernel+0x2b8/0x43c with crng_init=0
[    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.000102] Console: colour dummy device 80x25
[    0.000126] Calibrating delay loop (skipped) preset value.. 400.00 BogoMIPS (lpj=800000)
[    0.000131] pid_max: default: 32768 minimum: 301
[    0.000168] LSM: Security Framework initializing
[    0.000200] Mount-cache hash table entries: 8192 (order: 0, 65536 bytes, linear)
[    0.000210] Mountpoint-cache hash table entries: 8192 (order: 0, 65536 bytes, linear)
[    0.001199] ASID allocator initialised with 32768 entries
[    0.001246] rcu: Hierarchical SRCU implementation.
[    0.001361] Platform MSI: gic-its@1820000 domain created
[    0.001439] PCI/MSI: /bus@100000/interrupt-controller@1800000/gic-its@1820000 domain created
[    0.001576] smp: Bringing up secondary CPUs ...
[    0.002233] Detected PIPT I-cache on CPU1
[    0.002255] GICv3: CPU1: found redistributor 1 region 0:0x0000000001920000
[    0.002265] GICv3: CPU1: using allocated LPI pending table @0x00000008c00e0000
[    0.002290] CPU1: Booted secondary processor 0x0000000001 [0x411fd080]
[    0.002334] smp: Brought up 1 node, 2 CPUs
[    0.002336] SMP: Total of 2 processors activated.
[    0.002339] CPU features: detected: 32-bit EL0 Support
[    0.002341] CPU features: detected: CRC32 instructions
[    0.008672] CPU: All CPU(s) started at EL2
[    0.008685] alternatives: patching kernel code
[    0.009142] devtmpfs: initialized
[    0.011569] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.011577] futex hash table entries: 512 (order: -1, 32768 bytes, linear)
[    0.011701] pinctrl core: initialized pinctrl subsystem
[    0.012090] NET: Registered protocol family 16
[    0.012371] DMA: preallocated 256 KiB pool for atomic allocations
[    0.012616] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
[    0.017662] HugeTLB registered 16.0 GiB page size, pre-allocated 0 pages
[    0.017666] HugeTLB registered 512 MiB page size, pre-allocated 0 pages
[    0.017668] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[    0.018615] cryptd: max_cpu_qlen set to 1000
[    0.020134] iommu: Default domain type: Translated 
[    0.020277] SCSI subsystem initialized
[    0.020375] mc: Linux media interface: v0.10
[    0.020384] videodev: Linux video capture interface: v2.00
[    0.020393] pps_core: LinuxPPS API ver. 1 registered
[    0.020395] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.020400] PTP clock support registered
[    0.020409] EDAC MC: Ver: 3.0.0
[    0.020819] FPGA manager framework
[    0.021222] clocksource: Switched to clocksource arch_sys_counter
[    0.021294] VFS: Disk quotas dquot_6.6.0
[    0.021327] VFS: Dquot-cache hash table entries: 8192 (order 0, 65536 bytes)
[    0.023483] Carveout Heap: Exported 512 MiB at 0x00000000ae000000
[    0.023488] thermal_sys: Registered thermal governor 'step_wise'
[    0.023489] thermal_sys: Registered thermal governor 'power_allocator'
[    0.023779] NET: Registered protocol family 2
[    0.024103] tcp_listen_portaddr_hash hash table entries: 4096 (order: 0, 65536 bytes, linear)
[    0.024147] TCP established hash table entries: 16384 (order: 1, 131072 bytes, linear)
[    0.024199] TCP bind hash table entries: 16384 (order: 2, 262144 bytes, linear)
[    0.024348] TCP: Hash tables configured (established 16384 bind 16384)
[    0.024392] UDP hash table entries: 2048 (order: 0, 65536 bytes, linear)
[    0.024432] UDP-Lite hash table entries: 2048 (order: 0, 65536 bytes, linear)
[    0.024521] NET: Registered protocol family 1
[    0.024749] RPC: Registered named UNIX socket transport module.
[    0.024751] RPC: Registered udp transport module.
[    0.024752] RPC: Registered tcp transport module.
[    0.024754] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.024760] PCI: CLS 0 bytes, default 64
[    0.025060] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 counters available
[    0.026745] Initialise system trusted keyrings
[    0.026809] workingset: timestamp_bits=46 max_order=16 bucket_order=0
[    0.028728] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.028973] NFS: Registering the id_resolver key type
[    0.028984] Key type id_resolver registered
[    0.028986] Key type id_legacy registered
[    0.028992] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    0.029084] 9p: Installing v9fs 9p2000 file system support
[    0.036406] Key type asymmetric registered
[    0.036409] Asymmetric key parser 'x509' registered
[    0.036428] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 244)
[    0.036431] io scheduler mq-deadline registered
[    0.036433] io scheduler kyber registered
[    0.037106] pinctrl-single 4301c000.pinmux: 94 pins, size 376
[    0.037354] pinctrl-single 11c000.pinmux: 173 pins, size 692
[    0.038773] k3-ringacc 2b800000.ringacc: Failed to get MSI domain
[    0.038798] k3-ringacc 3c000000.ringacc: Failed to get MSI domain
[    0.038872] ti-pat 31010000.pat: Found PAT Rev 1.0 with 16384 pages
[    0.038880] debugfs: Directory '31010000.pat' with parent 'regmap' already present!
[    0.039026] ti-pat 31011000.pat: Found PAT Rev 1.0 with 16384 pages
[    0.039033] debugfs: Directory '31011000.pat' with parent 'regmap' already present!
[    0.039127] ti-pat 31012000.pat: Found PAT Rev 1.0 with 16384 pages
[    0.039134] debugfs: Directory '31012000.pat' with parent 'regmap' already present!
[    0.039217] ti-pat 31013000.pat: Found PAT Rev 1.0 with 2048 pages
[    0.039223] debugfs: Directory '31013000.pat' with parent 'regmap' already present!
[    0.039309] ti-pat 31014000.pat: Found PAT Rev 1.0 with 2048 pages
[    0.039315] debugfs: Directory '31014000.pat' with parent 'regmap' already present!
[    0.040488] Serial: 8250/16550 driver, 10 ports, IRQ sharing enabled
[    0.044808] brd: module loaded
[    0.047577] loop: module loaded
[    0.047608] mtip32xx Version 1.3.1
[    0.047846] sysfs: cannot create duplicate filename '/devices/platform/dma_buf_phys'
[    0.047851] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 5.4.74-ACU-1.3.21.1 #11
[    0.047852] Hardware name: Texas Instruments K3 J721E SoC (DT)
[    0.047855] Call trace:
[    0.047863]  dump_backtrace+0x0/0x140
[    0.047866]  show_stack+0x14/0x20
[    0.047873]  dump_stack+0xb4/0x114
[    0.047879]  sysfs_warn_dup+0x5c/0x78
[    0.047882]  sysfs_create_dir_ns+0xd8/0xf0
[    0.047885]  kobject_add_internal+0x94/0x280
[    0.047887]  kobject_add+0x90/0xf8
[    0.047891]  device_add+0xdc/0x600
[    0.047894]  platform_device_add+0xfc/0x228
[    0.047896]  platform_device_register_full+0xc8/0x140
[    0.047903]  dma_buf_phys_init+0x68/0x94
[    0.047906]  do_one_initcall+0x50/0x1a8
[    0.047910]  kernel_init_freeable+0x194/0x23c
[    0.047913]  kernel_init+0x10/0xfc
[    0.047915]  ret_from_fork+0x10/0x1c
[    0.047918] kobject_add_internal failed for dma_buf_phys with -EEXIST, don't try to register things with the same name in the same directory.
[    0.048549] libphy: Fixed MDIO Bus: probed
[    0.048703] tun: Universal TUN/TAP device driver, 1.6
[    0.048854] igbvf: Intel(R) Gigabit Virtual Function Network Driver - version 2.4.0-k
[    0.048856] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.
[    0.048871] sky2: driver version 1.30
[    0.049194] VFIO - User Level meta-driver version: 0.3
[    0.049512] i2c /dev entries driver
[    0.049703] Error: Driver 'k3-soc-thermal' is already registered, aborting...
[    0.049851] sdhci: Secure Digital Host Controller Interface driver
[    0.049852] sdhci: Copyright(c) Pierre Ossman
[    0.049946] sdhci-pltfm: SDHCI platform and OF driver helper
[    0.050212] ledtrig-cpu: registered to indicate activity on CPUs
[    0.050668] optee: probing for conduit method from DT.
[    0.050679] optee: revision 3.8 (199fca17)
[    0.050950] optee: initialized driver
[    0.051095] NET: Registered protocol family 17
[    0.051160] 9pnet: Installing 9P2000 support
[    0.051182] Key type dns_resolver registered
[    0.051318] registered taskstats version 1
[    0.051320] Loading compiled-in X.509 certificates
[    0.052848] k3-ringacc 2b800000.ringacc: Failed to get MSI domain
[    0.052934] k3-ringacc 3c000000.ringacc: Failed to get MSI domain
[    0.053737] ti-sci 44083000.dmsc: ABI: 3.1 (firmware rev 0x0014 '20.8.7--v2020.08d (Terrific Lla')
[    0.065809] ti-sci-intr bus@100000:navss@30000000:interrupt-controller1: Interrupt Router 213 domain created
[    0.065983] ti-sci-inta 33d00000.interrupt-controller: Interrupt Aggregator domain 209 created
[    0.066537] k3-ringacc 2b800000.ringacc: Ring Accelerator probed rings:286, gp-rings[96,20] sci-dev-id:235
[    0.066540] k3-ringacc 2b800000.ringacc: dma-ring-reset-quirk: disabled
[    0.066543] k3-ringacc 2b800000.ringacc: RA Proxy rev. 66346100, num_proxies:64
[    0.067962] k3-ringacc 3c000000.ringacc: Ring Accelerator probed rings:1024, gp-rings[440,150] sci-dev-id:211
[    0.067964] k3-ringacc 3c000000.ringacc: dma-ring-reset-quirk: disabled
[    0.067967] k3-ringacc 3c000000.ringacc: RA Proxy rev. 66346100, num_proxies:64
[    0.068347] printk: console [ttyS2] disabled
[    0.068368] 2800000.serial: ttyS2 at MMIO 0x2800000 (irq = 11, base_baud = 3000000) is a 8250
[    0.068394] printk: console [ttyS2] enabled
[    0.068395] printk: bootconsole [ns16550a0] disabled
[    0.068709] arm-smmu-v3 36600000.smmu: ias 48-bit, oas 48-bit (features 0x00001faf)
[    0.069802] arm-smmu-v3 36600000.smmu: allocated 524288 entries for cmdq
[    0.071641] arm-smmu-v3 36600000.smmu: allocated 524288 entries for evtq
[    0.072350] arm-smmu-v3 36600000.smmu: msi_domain absent - falling back to wired irqs
[    0.073457] am65-cpts 310d0000.cpts: CPTS ver 0x4e8a010a, freq:200000000, add_val:4 pps:0
[    0.073677] value of fuse 0 is 0x33d71bf3
[    0.073679] value of fuse 1 is 0x0
[    0.073681] value of fuse 2 is 0x5b5ba000
[    0.073683] value of fuse 3 is 0x1b3b9b
[    0.073688] k3_bandgap_probe and count is 5
[    0.073691] id is 0 and i is 0 and err is -13
[    0.073693] id is 0 and i is 1 and err is -35
[    0.073695] id is 0 and i is 2 and err is 0
[    0.073697] The errors in adc code for -40C, 30C & 125C 150C respectively:: -13 -35 -28 0
[    0.073699] slope m1::-11 and const c1::-132
[    0.073702] slope m2::68 and const c2::2634
[    0.073707] slope m3::7 and const c3::926
[    0.073736] id is 1 and i is 0 and err is -17
[    0.073738] id is 1 and i is 1 and err is -38
[    0.073740] id is 1 and i is 2 and err is 0
[    0.073753] id is 2 and i is 0 and err is -15
[    0.073754] id is 2 and i is 1 and err is -38
[    0.073756] id is 2 and i is 2 and err is 0
[    0.073769] id is 3 and i is 0 and err is -11
[    0.073770] id is 3 and i is 1 and err is -36
[    0.073772] id is 3 and i is 2 and err is 0
[    0.073785] id is 4 and i is 0 and err is -13
[    0.073787] id is 4 and i is 1 and err is -39
[    0.073788] id is 4 and i is 2 and err is 0
[    0.073803] Value of CTRLMMR_WKUP_RESET_SRC_STAT Register is 0x800
[    0.073805] high_max is 742 and low_max is 632 and regval is 0x27802e6
[    0.174789] Value of WKUP_VTM_MISC_CTRL2 is 0x27802e6
[    0.174791] Value of WKUP_VTM_MISC_CTRL is 0x1
[    0.175218] mmc0: CQHCI version 5.10
[    0.216026] mmc0: SDHCI controller on 4f80000.sdhci [4f80000.sdhci] using ADMA 64-bit
[    0.216517] omap-mailbox 31f80000.mailbox: omap mailbox rev 0x66fc7100
[    0.216910] omap-mailbox 31f81000.mailbox: omap mailbox rev 0x66fc7100
[    0.217227] omap-mailbox 31f82000.mailbox: omap mailbox rev 0x66fc7100
[    0.217572] omap-mailbox 31f83000.mailbox: omap mailbox rev 0x66fc7100
[    0.217871] omap-mailbox 31f84000.mailbox: omap mailbox rev 0x66fc7100
[    0.218587] ti-udma 285c0000.dma-controller: Channels: 26 (tchan: 13, rchan: 13, gp-rflow: 8)
[    0.220292] ti-udma 31150000.dma-controller: Channels: 84 (tchan: 42, rchan: 42, gp-rflow: 16)
[    0.222503] hctosys: unable to open rtc device (rtc0)
[    0.228318] Waiting for root device PARTUUID=00000000-02...
[    0.272168] random: fast init done
[    0.273298] mmc0: new DDR MMC card at address 0001
[    0.273653] mmcblk0: mmc0:0001 MMC32G 29.1 GiB 
[    0.273817] mmcblk0boot0: mmc0:0001 MMC32G partition 1 4.00 MiB
[    0.273986] mmcblk0boot1: mmc0:0001 MMC32G partition 2 4.00 MiB
[    0.274081] mmcblk0rpmb: mmc0:0001 MMC32G partition 3 4.00 MiB, chardev (240:0)
[    0.282095]  mmcblk0: p1 p2 p3 p4 < p5 p6 p7 p8 p9 >
[    0.292521] EXT4-fs (mmcblk0p2): INFO: recovery required on readonly filesystem
[    0.292525] EXT4-fs (mmcblk0p2): write access will be enabled during recovery
[    0.791973] EXT4-fs (mmcblk0p2): orphan cleanup on readonly fs
[    0.792298] EXT4-fs (mmcblk0p2): 4 orphan inodes deleted
[    0.792300] EXT4-fs (mmcblk0p2): recovery complete
[    0.800405] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
[    0.800445] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
[    0.801000] devtmpfs: mounted
[    0.801311] Freeing unused kernel memory: 1664K
[    0.813233] Run /sbin/init as init process
[    0.931468] systemd[1]: System time before build time, advancing clock.
[    0.962491] NET: Registered protocol family 10
[    0.962871] Segment Routing with IPv6
[    0.974490] systemd[1]: systemd 246 running in system mode. (-PAM -AUDIT -SELINUX -IMA -APPARMOR -SMACK -SYSVINIT -UTMP -LIBCRYPTSETUP +GCRYPT +GNUTLS -ACL +XZ -LZ4 -ZSTD -SECCOMP +BLKID -ELFUTILS +KMOD -IDN2 -IDN -PCRE2 default-hierarchy=hybrid)
[    0.974655] systemd[1]: Detected architecture arm64.
[    1.013669] systemd[1]: Set hostname to <buildroot>.
[    1.082162] systemd[1]: multi-user.target: Wants dependency dropin /etc/systemd/system/multi-user.target.wants/daemon_watch.service_original is not a symlink, ignoring.
[    1.099920] systemd[1]: sysinit.target: Wants dependency dropin /etc/systemd/system/sysinit.target.wants/daemon_watch.service is not a symlink, ignoring.
[    1.141290] systemd[1]: Configuration file /etc/systemd/system/daemon_watch.service is marked executable. Please remove executable permission bits. Proceeding anyway.
[    1.141653] systemd[1]: /etc/systemd/system/daemon_watch.service:10: Support for option SysVStartPriority= has been removed and it is ignored
[    1.141832] systemd[1]: Configuration file /etc/systemd/system/boot_test.service is marked executable. Please remove executable permission bits. Proceeding anyway.
[    1.141838] systemd[1]: Configuration file /etc/systemd/system/boot_test.service is marked world-writable. Please remove world writability permission bits. Proceeding anyway.
[    1.142196] systemd[1]: /etc/systemd/system/boot_test.service:12: Support for option SysVStartPriority= has been removed and it is ignored
[    1.150897] systemd[1]: Queued start job for default target Multi-User System.
[    1.151269] random: systemd: uninitialized urandom read (16 bytes read)
[    1.151334] systemd[1]: system-serial\x2dgetty.slice: unit configures an IP firewall, but the local system does not support BPF/cgroup firewalling.
[    1.151339] systemd[1]: (This warning is only shown for the first unit using IP firewalling.)
[    1.152467] systemd[1]: Created slice system-serial\x2dgetty.slice.
[    1.169325] random: systemd: uninitialized urandom read (16 bytes read)
[    1.169417] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[    1.185269] random: systemd: uninitialized urandom read (16 bytes read)
[    1.185340] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[    1.201400] systemd[1]: Reached target Paths.
[    1.213275] systemd[1]: Reached target Remote File Systems.
[    1.225263] systemd[1]: Reached target Slices.
[    1.237283] systemd[1]: Reached target Swap.
[    1.249501] systemd[1]: Listening on Syslog Socket.
[    1.267175] systemd[1]: Condition check resulted in Journal Audit Socket being skipped.
[    1.267509] systemd[1]: Listening on Journal Socket (/dev/log).
[    1.281494] systemd[1]: Listening on Journal Socket.
[    1.293507] systemd[1]: Listening on udev Control Socket.
[    1.305409] systemd[1]: Listening on udev Kernel Socket.
[    1.319108] systemd[1]: Mounting Huge Pages File System...
[    1.335570] systemd[1]: Mounting POSIX Message Queue File System...
[    1.347934] systemd[1]: Mounting Kernel Debug File System...
[    1.361477] systemd[1]: Condition check resulted in Kernel Trace File System being skipped.
[    1.363850] systemd[1]: Mounting Temporary Directory (/tmp)...
[    1.373732] systemd[1]: Condition check resulted in /opt/gaia/test_boot_performance.sh Compatibility being skipped.
[    1.375753] systemd[1]: Starting Create list of static device nodes for the current kernel...
[    1.396241] systemd[1]: Starting Journal Service...
[    1.407967] systemd[1]: Starting Load Kernel Modules...
[    1.423407] systemd[1]: Starting Remount Root and Kernel File Systems...
[    1.436391] systemd[1]: Starting Coldplug All udev Devices...
[    1.451725] systemd[1]: Started Journal Service.
[    1.871840] k3-dsp-rproc 4d80800000.dsp: assigned reserved memory node c66-dma-memory@a7000000
[    1.871993] k3-dsp-rproc 4d80800000.dsp: configured DSP for remoteproc mode
[    1.872130] remoteproc remoteproc0: 4d80800000.dsp is available
[    1.873322] k3-dsp-rproc 4d81800000.dsp: assigned reserved memory node c66-dma-memory@a6000000
[    1.873491] k3-dsp-rproc 4d81800000.dsp: configured DSP for remoteproc mode
[    1.873629] remoteproc remoteproc1: 4d81800000.dsp is available
[    1.873887] k3-dsp-rproc 64800000.dsp: assigned reserved memory node c71-dma-memory@a8000000
[    1.874092] k3-dsp-rproc 64800000.dsp: configured DSP for remoteproc mode
[    1.874134] remoteproc remoteproc2: 64800000.dsp is available
[    1.954802] platform 41000000.r5f: ti-sci processor request failed: -19
[    1.954808] platform 41000000.r5f: ti_sci_proc_request failed, ret = -19
[    1.954820] k3_r5_rproc bus@100000:bus@28380000:r5fss@41000000: k3_r5_core_of_init failed, ret = -19
[    1.954823] k3_r5_rproc bus@100000:bus@28380000:r5fss@41000000: k3_r5_cluster_of_init failed, ret = -19
[    1.956146] platform 5c00000.r5f: configured R5F for IPC-only mode
[    1.956172] platform 5c00000.r5f: assigned reserved memory node vision_apps-r5f-dma-memory@a1000000
[    1.956259] remoteproc remoteproc3: 5c00000.r5f is available
[    1.956841] platform 5d00000.r5f: configured R5F for IPC-only mode
[    1.956866] platform 5d00000.r5f: assigned reserved memory node r5f-dma-memory@a3000000
[    1.956976] remoteproc remoteproc4: 5d00000.r5f is available
[    2.172423] hjwang rproc_boot-2110:5d00000.r5f 0
[    2.172432] remoteproc remoteproc4: powering up 5d00000.r5f
[    2.172439] remoteproc remoteproc4: Booting fw image j7-main-r5f0_1-fw, size 1923072
[    2.172441] hjwang rproc_fw_boot-1663:5d00000.r5f 
[    2.172443] hjwang rproc_fw_boot-1665:5d00000.r5f ret=0
[    2.172445] hjwang rproc_fw_boot-1676:5d00000.r5f ret=0
[    2.172447] hjwang rproc_fw_boot-1683:5d00000.r5f 
[    2.172448] hjwang rproc_fw_boot-1685:5d00000.r5f 
[    2.172452] hjwang rproc_fw_boot-1689:5d00000.r5f ret=0
[    2.172507] hjwang rproc_fw_boot-1702:5d00000.r5f ret=0
[    2.172525] hjwang rproc_fw_boot-1711:5d00000.r5f ret=0
[    2.172526] hjwang rproc_start-1568:5d00000.r5f ret=0
[    2.172530] hjwang rproc_start-1583:5d00000.r5f ret=0
[    2.172533] hjwang rproc_start-1587:5d00000.r5f ret=0
[    2.172535] hjwang rproc_start-1596:5d00000.r5f ret=0
[    2.172583] platform 5d00000.r5f: R5F core initialized in IPC-only mode
[    2.172585] hjwang rproc_start-1606:5d00000.r5f ret=0
[    2.172593]  remoteproc4#vdev0buffer: assigned reserved memory node r5f-dma-memory@a3000000
[    2.172655]  remoteproc4#vdev0buffer: registered virtio0 (type 7)
[    2.172657] hjwang rproc_start-1615:5d00000.r5f ret=0
[    2.172660] remoteproc remoteproc4: remote processor 5d00000.r5f is now up
[    2.172661] hjwang rproc_start-1626:5d00000.r5f ret=0
[    2.172663] hjwang rproc_fw_boot-1720:5d00000.r5f ret=0
[    2.221928] omap_rng 4e10000.rng: Random Number Generator ver. 241b34c
[    2.222378] virtio_rpmsg_bus virtio0: rpmsg host is online
[    2.222452] random: crng init done
[    2.222455] random: 7 urandom warning(s) missed due to ratelimiting
[    2.227604] virtio_rpmsg_bus virtio0: creating channel rpmsg_chrdev addr 0xd
[    2.273338] hjwang rproc_boot-2110:5c00000.r5f 0
[    2.273347] remoteproc remoteproc3: powering up 5c00000.r5f
[    2.273353] remoteproc remoteproc3: Booting fw image j7-main-r5f0_0-fw, size 4712824
[    2.273355] hjwang rproc_fw_boot-1663:5c00000.r5f 
[    2.273357] hjwang rproc_fw_boot-1665:5c00000.r5f ret=0
[    2.273360] hjwang rproc_fw_boot-1676:5c00000.r5f ret=0
[    2.273361] hjwang rproc_fw_boot-1683:5c00000.r5f 
[    2.273363] hjwang rproc_fw_boot-1685:5c00000.r5f 
[    2.273368] hjwang rproc_fw_boot-1689:5c00000.r5f ret=0
[    2.273423] hjwang rproc_fw_boot-1702:5c00000.r5f ret=0
[    2.273441] hjwang rproc_fw_boot-1711:5c00000.r5f ret=0
[    2.273443] hjwang rproc_start-1568:5c00000.r5f ret=0
[    2.273446] hjwang rproc_start-1583:5c00000.r5f ret=0
[    2.273450] hjwang rproc_start-1587:5c00000.r5f ret=0
[    2.273451] hjwang rproc_start-1596:5c00000.r5f ret=0
[    2.273482] platform 5c00000.r5f: R5F core initialized in IPC-only mode
[    2.273484] hjwang rproc_start-1606:5c00000.r5f ret=0
[    2.273491]  remoteproc3#vdev0buffer: assigned reserved memory node vision_apps-r5f-dma-memory@a1000000
[    2.273900] virtio_rpmsg_bus virtio1: rpmsg host is online
[    2.273926]  remoteproc3#vdev0buffer: registered virtio1 (type 7)
[    2.273928] hjwang rproc_start-1615:5c00000.r5f ret=0
[    2.273931] remoteproc remoteproc3: remote processor 5c00000.r5f is now up
[    2.273933] hjwang rproc_start-1626:5c00000.r5f ret=0
[    2.273935] hjwang rproc_fw_boot-1720:5c00000.r5f ret=0
[    2.279221] virtio_rpmsg_bus virtio1: creating channel rpmsg_chrdev addr 0xd
[    2.479095] hjwang rproc_boot-2110:4d81800000.dsp 0
[    2.479104] remoteproc remoteproc1: powering up 4d81800000.dsp
[    2.479111] remoteproc remoteproc1: Booting fw image j7-c66_1-fw, size 11705956
[    2.479113] hjwang rproc_fw_boot-1663:4d81800000.dsp 
[    2.479115] hjwang rproc_fw_boot-1665:4d81800000.dsp ret=0
[    2.479321] hjwang rproc_fw_boot-1676:4d81800000.dsp ret=0
[    2.479322] hjwang rproc_fw_boot-1683:4d81800000.dsp 
[    2.479324] hjwang rproc_fw_boot-1685:4d81800000.dsp 
[    2.479328] hjwang rproc_fw_boot-1689:4d81800000.dsp ret=0
[    2.479408] hjwang rproc_fw_boot-1702:4d81800000.dsp ret=0
[    2.479425] hjwang rproc_fw_boot-1711:4d81800000.dsp ret=0
[    2.480509] hjwang rproc_start-1568:4d81800000.dsp ret=0
[    2.480514] hjwang rproc_start-1583:4d81800000.dsp ret=0
[    2.480517] hjwang rproc_start-1587:4d81800000.dsp ret=0
[    2.480519] hjwang rproc_start-1596:4d81800000.dsp ret=0
[    2.480581] k3-dsp-rproc 4d81800000.dsp: booting DSP core using boot addr = 0xa7200000
[    2.480909] hjwang rproc_start-1606:4d81800000.dsp ret=0
[    2.480919]  remoteproc1#vdev0buffer: assigned reserved memory node c66-dma-memory@a6000000
[    2.481393] virtio_rpmsg_bus virtio2: rpmsg host is online
[    2.481417]  remoteproc1#vdev0buffer: registered virtio2 (type 7)
[    2.481420] hjwang rproc_start-1615:4d81800000.dsp ret=0
[    2.481423] remoteproc remoteproc1: remote processor 4d81800000.dsp is now up
[    2.481425] hjwang rproc_start-1626:4d81800000.dsp ret=0
[    2.481427] hjwang rproc_fw_boot-1720:4d81800000.dsp ret=0
[    2.489752] hjwang rproc_boot-2110:4d80800000.dsp 0
[    2.489882] remoteproc remoteproc0: powering up 4d80800000.dsp
[    2.489946] remoteproc remoteproc0: Booting fw image j7-c66_0-fw, size 11705900
[    2.489957] hjwang rproc_fw_boot-1663:4d80800000.dsp 
[    2.489967] hjwang rproc_fw_boot-1665:4d80800000.dsp ret=0
[    2.492084] hjwang rproc_fw_boot-1676:4d80800000.dsp ret=0
[    2.492090] hjwang rproc_fw_boot-1683:4d80800000.dsp 
[    2.492095] hjwang rproc_fw_boot-1685:4d80800000.dsp 
[    2.492161] hjwang rproc_fw_boot-1689:4d80800000.dsp ret=0
[    2.493028] hjwang rproc_fw_boot-1702:4d80800000.dsp ret=0
[    2.493094] hjwang rproc_fw_boot-1711:4d80800000.dsp ret=0
[    2.501899] hjwang rproc_start-1568:4d80800000.dsp ret=0
[    2.501906] hjwang rproc_start-1583:4d80800000.dsp ret=0
[    2.501910] hjwang rproc_start-1587:4d80800000.dsp ret=0
[    2.501912] hjwang rproc_start-1596:4d80800000.dsp ret=0
[    2.501945] k3-dsp-rproc 4d80800000.dsp: booting DSP core using boot addr = 0xa6200000
[    2.502331] hjwang rproc_start-1606:4d80800000.dsp ret=0
[    2.502341]  remoteproc0#vdev0buffer: assigned reserved memory node c66-dma-memory@a7000000
[    2.505077] virtio_rpmsg_bus virtio3: rpmsg host is online
[    2.505106]  remoteproc0#vdev0buffer: registered virtio3 (type 7)
[    2.505109] hjwang rproc_start-1615:4d80800000.dsp ret=0
[    2.505112] remoteproc remoteproc0: remote processor 4d80800000.dsp is now up
[    2.505114] hjwang rproc_start-1626:4d80800000.dsp ret=0
[    2.505115] hjwang rproc_fw_boot-1720:4d80800000.dsp ret=0
[    2.524589] virtio_rpmsg_bus virtio2: creating channel rpmsg_chrdev addr 0xd
[    2.534306] virtio_rpmsg_bus virtio3: creating channel rpmsg_chrdev addr 0xd
[    2.662360] hjwang rproc_boot-2110:64800000.dsp 0
[    2.662369] remoteproc remoteproc2: powering up 64800000.dsp
[    2.662376] remoteproc remoteproc2: Booting fw image j7-c71_0-fw, size 17699336
[    2.662379] hjwang rproc_fw_boot-1663:64800000.dsp 
[    2.662381] hjwang rproc_fw_boot-1665:64800000.dsp ret=0
[    2.662384] hjwang rproc_fw_boot-1676:64800000.dsp ret=0
[    2.662385] hjwang rproc_fw_boot-1683:64800000.dsp 
[    2.662387] hjwang rproc_fw_boot-1685:64800000.dsp 
[    2.662394] hjwang rproc_fw_boot-1689:64800000.dsp ret=0
[    2.662447] hjwang rproc_fw_boot-1702:64800000.dsp ret=0
[    2.662465] hjwang rproc_fw_boot-1711:64800000.dsp ret=0
[    2.664903] hjwang rproc_start-1568:64800000.dsp ret=0
[    2.664911] hjwang rproc_start-1583:64800000.dsp ret=0
[    2.664916] hjwang rproc_start-1587:64800000.dsp ret=0
[    2.664918] hjwang rproc_start-1596:64800000.dsp ret=0
[    2.664972] k3-dsp-rproc 64800000.dsp: booting DSP core using boot addr = 0xa8200000
[    2.665971] hjwang rproc_start-1606:64800000.dsp ret=0
[    2.665990]  remoteproc2#vdev0buffer: assigned reserved memory node c71-dma-memory@a8000000
[    2.666415] virtio_rpmsg_bus virtio4: rpmsg host is online
[    2.666434]  remoteproc2#vdev0buffer: registered virtio4 (type 7)
[    2.666436] hjwang rproc_start-1615:64800000.dsp ret=0
[    2.666439] remoteproc remoteproc2: remote processor 64800000.dsp is now up
[    2.666440] hjwang rproc_start-1626:64800000.dsp ret=0
[    2.666443] hjwang rproc_fw_boot-1720:64800000.dsp ret=0
[    2.696147] virtio_rpmsg_bus virtio4: creating channel rpmsg_chrdev addr 0xd
[    2.696360] virtio_rpmsg_bus virtio2: creating channel rpmsg_chrdev addr 0x15
[    2.696450] virtio_rpmsg_bus virtio3: creating channel rpmsg_chrdev addr 0x15
[    2.696802] virtio_rpmsg_bus virtio1: creating channel rpmsg_chrdev addr 0x15
[    2.697063] virtio_rpmsg_bus virtio0: creating channel rpmsg_chrdev addr 0x15
[    2.697182] virtio_rpmsg_bus virtio4: creating channel rpmsg_chrdev addr 0x15
[    2.697627] virtio_rpmsg_bus virtio4: creating channel ti.ipc4.ping-pong addr 0xe
[    2.706206] virtio_rpmsg_bus virtio3: creating channel ti.ipc4.ping-pong addr 0xe
[    2.706347] virtio_rpmsg_bus virtio2: creating channel ti.ipc4.ping-pong addr 0xe
[    2.745765] EXT4-fs (mmcblk0p3): recovery complete
[    2.745774] EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null)
[    2.761859] EXT4-fs (mmcblk0p5): recovery complete
[    2.761869] EXT4-fs (mmcblk0p5): mounted filesystem with ordered data mode. Opts: (null)
[    2.774043] EXT4-fs (mmcblk0p7): recovery complete
[    2.774051] EXT4-fs (mmcblk0p7): mounted filesystem with ordered data mode. Opts: (null)
[    2.777868] virtio_rpmsg_bus virtio0: creating channel ti.ipc4.ping-pong addr 0xe
[    2.790855] EXT4-fs (mmcblk0p9): recovery complete
[    2.790863] EXT4-fs (mmcblk0p9): mounted filesystem with ordered data mode. Opts: (null)
[    2.807491] EXT4-fs (mmcblk0p6): recovery complete
[    2.808571] EXT4-fs (mmcblk0p6): mounted filesystem with ordered data mode. Opts: (null)
[    2.814745] EXT4-fs (mmcblk0p8): recovery complete
[    2.814753] EXT4-fs (mmcblk0p8): mounted filesystem with ordered data mode. Opts: (null)
[    2.877021] virtio_rpmsg_bus virtio1: creating channel ti.ethfw.notifyservice addr 0x1e
[    2.878476] virtio_rpmsg_bus virtio1: creating channel rpmsg-kdrv addr 0x1a
[    2.879680] rpmsg-kdrv-eth-switch rpmsg-kdrv-2-mpu_1_0_ethswitch-device-0: Device info: permissions: 07FFFFFF uart_id: 6
[    2.879686] rpmsg-kdrv-eth-switch rpmsg-kdrv-2-mpu_1_0_ethswitch-device-0: FW ver 0.1 (rev 1) 17/Aug/2021 SHA:a3cbb14d
[    2.889618] j721e-cpsw-virt-mac main_r5fss_cpsw9g_virt_mac0: virt_cpsw_nuss mac loaded
[    2.889626] j721e-cpsw-virt-mac main_r5fss_cpsw9g_virt_mac0: rdev_features:00000003 rdev_mtu:1522 flow_id:172 tx_psil_dst_id:4A00
[    2.889630] j721e-cpsw-virt-mac main_r5fss_cpsw9g_virt_mac0: local_mac_addr:00:00:00:00:00:00 rdev_mac_addr:70:ff:76:1d:92:c1
[    3.375563] j721e-cpsw-virt-mac main_r5fss_cpsw9g_virt_mac0: virt_cpsw_nuss mac started
[    3.400451] 8021q: 802.1Q VLAN Support v1.8
[    4.183927] virtio_rpmsg_bus virtio1: creating channel ti.ipc4.ping-pong addr 0xe
[   16.002934] spi-nor spi0.0: Unknown command extension type
[   16.002950] spi-nor spi0.0: Unknown command extension type
[   16.016625] cadence-qspi 47040000.spi: PHY calibration failed: -2
[   16.016632] spi-nor spi0.0: mt35xu256aba (32768 Kbytes)
[   16.016648] 19 cmdlinepart partitions found on MTD device 47040000.spi.0
[   16.016650] Creating 19 MTD partitions on "47040000.spi.0":
[   16.016655] 0x000000000000-0x000000080000 : "ospi.sbl"
[   16.025989] 0x000000080000-0x000000100000 : "ospi.tifs_a"
[   16.045658] 0x000000100000-0x000000180000 : "ospi.tifs_b"
[   16.061797] 0x000000180000-0x000000580000 : "ospi.mcufw_a"
[   16.077681] 0x000000580000-0x000000980000 : "ospi.mcufw_b"
[   16.097778] 0x000000980000-0x000000b80000 : "ospi.atf_optee_a"
[   16.117646] 0x000000b80000-0x000000d80000 : "ospi.atf_optee_b"
[   16.137688] 0x000000d80000-0x000000e80000 : "ospi.u-boot-spl_a"
[   16.153676] 0x000000e80000-0x000000f80000 : "ospi.u-boot-spl_b"
[   16.169645] 0x000000f80000-0x000001180000 : "ospi.u-boot_a"
[   16.185667] 0x000001180000-0x000001380000 : "ospi.u-boot_b"
[   16.201675] 0x000001380000-0x0000013a0000 : "ospi.misc_a"
[   16.221662] 0x0000013a0000-0x0000013c0000 : "ospi.misc_b"
[   16.237639] 0x0000013c0000-0x0000013e0000 : "ospi.factory"
[   16.253665] 0x0000013e0000-0x000001400000 : "ospi.safe_a"
[   16.277706] 0x000001400000-0x000001420000 : "ospi.safe_b"
[   16.293646] 0x000001420000-0x000001620000 : "ospi.mcudata"
[   16.317787] 0x000001620000-0x000001660000 : "ospi.sonar_data"
[   16.333715] 0x000001660000-0x000002000000 : "ospi.reserved"
root@buildroot:~# 
[2021-08-19 16:22:46.649] root@buildroot:~# ls
[2021-08-19 16:22:47.496] root@buildroot:~# NOTICE:  BL31: v2.3():07.01.00.006-dirty
[2021-08-19 16:22:52.334] NOTICE:  BL31: Built : 03:49:38, Jan 25 2021
[2021-08-19 16:22:52.414] 
[2021-08-19 16:22:52.414] U-Boot SPL 2020.01 (Aug 19 2021 - 15:56:43 +0800)
[2021-08-19 16:22:52.429] K3_PRIMARY_BOOTMODE bootmode = 0x3 
[2021-08-19 16:22:52.446] spl_boot_device from SPI 
[2021-08-19 16:22:52.749] 
[2021-08-19 16:22:52.749] 
[2021-08-19 16:22:52.749] U-Boot 2020.01 (Aug 19 2021 - 15:56:43 +0800)
[2021-08-19 16:22:52.749] 
[2021-08-19 16:22:52.749] 4 GiB
[2021-08-19 16:22:52.781] Flash: 0 Bytes
[2021-08-19 16:22:52.781] MMC:   sdhci@4f80000: 0
[2021-08-19 16:22:52.927] MMC: block number 0x3500 exceeds max(0x2000)
[2021-08-19 16:22:52.927] MMC: block number 0x3600 exceeds max(0x2000)
[2021-08-19 16:22:52.927] *** Error - No Valid Environment Area found
[2021-08-19 16:22:52.928] *** Warning - bad env area, using default environment
[2021-08-19 16:22:52.928] 
[2021-08-19 16:22:52.928] serial@2800000
[2021-08-19 16:22:52.928] serial@2800000
[2021-08-19 16:22:52.928] serial@2800000
[2021-08-19 16:22:52.975] No ethernet found.
[2021-08-19 16:22:52.991] Hit any key to stop autoboot:  2  0 
[2021-08-19 16:22:53.199] => 
[2021-08-19 16:22:54.893] => set do   env dorprocboot 
[2021-08-19 16:23:00.983] => em ditenv do  eor   dorprocboot
[2021-08-19 16:23:13.914] edit: 0
[2021-08-19 16:23:16.104] => 
[2021-08-19 16:23:16.600] => 
[2021-08-19 16:23:16.759] => 
[2021-08-19 16:23:16.887] => prin    editenv emm   mmc
[2021-08-19 16:23:26.190]   mmcboot mmcdev mmcloados mmcrootfstype
[2021-08-19 16:23:26.206] => editenv mmcrootfstype 
[2021-08-19 16:23:29.083] edit: ext4 rootwait loglevel=0 lpj=800000 lpj=800000  lpj=800000  lpj=800000  lpj=800000  lpj=800000  lpj=800000  lpj=800000  lpj=800000  lpj=800000  lpj=800000  lpj=800000  
[2021-08-19 16:23:34.310] => 
[2021-08-19 16:23:35.253] => 
[2021-08-19 16:23:35.397] => boot
[2021-08-19 16:23:37.906] switch to partitions #0, OK
[2021-08-19 16:23:37.906] mmc0(part 0) is current device
[2021-08-19 16:23:37.984] SD/MMC found on device 0
[2021-08-19 16:23:37.984] ** Unrecognized filesystem type **
[2021-08-19 16:23:38.064] 16400392 bytes read in 91 ms (171.9 MiB/s)
[2021-08-19 16:23:38.088] 45697 bytes read in 2 ms (21.8 MiB/s)
[2021-08-19 16:23:38.088] Invalid bus 0 (err=-19)
[2021-08-19 16:23:38.089] Failed to initialize SPI flash at 0:0 (error -19)
[2021-08-19 16:23:38.162] ## Flattened Device Tree blob at 88000000
[2021-08-19 16:23:38.162]    Booting using the fdt blob at 0x88000000
[2021-08-19 16:23:38.162] ERROR: reserving fdt memory region failed (addr=a1000000 size=100000)
[2021-08-19 16:23:38.163] ERROR: reserving fdt memory region failed (addr=a1100000 size=1f00000)
[2021-08-19 16:23:38.163] ERROR: reserving fdt memory region failed (addr=a3100000 size=1f00000)
[2021-08-19 16:23:38.164] ERROR: reserving fdt memory region failed (addr=a5000000 size=100000)
[2021-08-19 16:23:38.164] ERROR: reserving fdt memory region failed (addr=a5100000 size=700000)
[2021-08-19 16:23:38.165] ERROR: reserving fdt memory region failed (addr=a5800000 size=100000)
[2021-08-19 16:23:38.205] ERROR: reserving fdt memory region failed (addr=a5900000 size=700000)
[2021-08-19 16:23:38.206] ERROR: reserving fdt memory region failed (addr=fdc00000 size=400000)
[2021-08-19 16:23:38.206]    Loading Device Tree to 000000008fef1000, end 000000008fffffff ... OK
[2021-08-19 16:23:38.206] 
[2021-08-19 16:23:38.207] Starting kernel ...
[2021-08-19 16:23:38.207] 
[2021-08-19 16:23:38.477] [    0.000000] Booting Linux on physical CPU 0x0000000000 [0x411fd080]
[2021-08-19 16:23:38.521] [    0.000000] Linux version 5.4.74-ACU-1.3.21.1 (hjwang@Tower-5810-Nvidia-Host) (gcc version 9.2.1 20191025 (GNU Toolchain for the A-profile Architecture 9.2-2019.12 (arm-9.10))) #11 SMP PREEMPT Thu Aug 19 15:51:24 CST 2021
[2021-08-19 16:23:38.523] [    0.000000] Machine model: Texas Instruments K3 J721E SoC
[2021-08-19 16:23:38.523] [    0.000000] earlycon: ns16550a0 at MMIO32 0x0000000002800000 (options '')
[2021-08-19 16:23:38.523] [    0.000000] printk: bootconsole [ns16550a0] enabled
[2021-08-19 16:23:38.524] [    0.000000] OF: reserved mem: not enough space all defined regions.
[2021-08-19 16:23:38.565] [    0.000000] OF: reserved mem: not enough space all defined regions.
[2021-08-19 16:23:38.566] [    0.000000] OF: reserved mem: OVERLAP DETECTED!
[2021-08-19 16:23:38.566] [    0.000000] vision_apps-r5f-dma-memory@a1000000 (0x00000000a1000000--0x00000000a1100000) overlaps with r5f-dma-memory@a1000000 (0x00000000a1000000--0x00000000a1100000)
[2021-08-19 16:23:38.567] [    0.000000] OF: reserved mem: OVERLAP DETECTED!
[2021-08-19 16:23:38.567] [    0.000000] vision_apps-r5f-memory@a1100000 (0x00000000a1100000--0x00000000a3000000) overlaps with r5f-memory@a1100000 (0x00000000a1100000--0x00000000a2000000)
[2021-08-19 16:23:38.609] [    0.000000] OF: reserved mem: OVERLAP DETECTED!
[2021-08-19 16:23:38.610] [    0.000000] r5f-memory@a3100000 (0x00000000a3100000--0x00000000a4000000) overlaps with vision_apps-r5f-memory@a3100000 (0x00000000a3100000--0x00000000a5000000)
[2021-08-19 16:23:38.610] [    0.000000] OF: reserved mem: OVERLAP DETECTED!
[2021-08-19 16:23:38.611] [    0.000000] vision_apps-r5f-memory@a3100000 (0x00000000a3100000--0x00000000a5000000) overlaps with r5f-dma-memory@a4000000 (0x00000000a4000000--0x00000000a4100000)
[2021-08-19 16:23:38.612] [    0.000000] OF: reserved mem: OVERLAP DETECTED!
[2021-08-19 16:23:38.653] [    0.000000] vision_apps-r5f-dma-memory@a5000000 (0x00000000a5000000--0x00000000a5100000) overlaps with r5f-dma-memory@a5000000 (0x00000000a5000000--0x00000000a5100000)
[2021-08-19 16:23:38.654] [    0.000000] OF: reserved mem: OVERLAP DETECTED!
[2021-08-19 16:23:38.655] [    0.000000] r5f-memory@a5100000 (0x00000000a5100000--0x00000000a6000000) overlaps with vision_apps-r5f-memory@a5100000 (0x00000000a5100000--0x00000000a5800000)
[2021-08-19 16:23:38.655] [    0.000000] Reserved memory: created DMA memory pool at 0x0000000094000000, size 128 MiB
[2021-08-19 16:23:38.697] [    0.000000] OF: reserved mem: initialized node vision_apps-core-heap-memory@94000000, compatible id shared-dma-pool
[2021-08-19 16:23:38.698] [    0.000000] Reserved memory: created DMA memory pool at 0x00000000a0000000, size 1 MiB
[2021-08-19 16:23:38.698] [    0.000000] OF: reserved mem: initialized node r5f-dma-memory@a0000000, compatible id shared-dma-pool
[2021-08-19 16:23:38.699] [    0.000000] Reserved memory: created DMA memory pool at 0x00000000a0100000, size 15 MiB
[2021-08-19 16:23:38.699] [    0.000000] OF: reserved mem: initialized node r5f-memory@a0100000, compatible id shared-dma-pool
[2021-08-19 16:23:38.741] [    0.000000] Reserved memory: created DMA memory pool at 0x00000000a1000000, size 1 MiB
[2021-08-19 16:23:38.742] [    0.000000] OF: reserved mem: initialized node vision_apps-r5f-dma-memory@a1000000, compatible id shared-dma-pool
[2021-08-19 16:23:38.742] [    0.000000] Reserved memory: created DMA memory pool at 0x00000000a1000000, size 1 MiB
[2021-08-19 16:23:38.742] [    0.000000] OF: reserved mem: initialized node r5f-dma-memory@a1000000, compatible id shared-dma-pool
[2021-08-19 16:23:38.743] [    0.000000] Reserved memory: created DMA memory pool at 0x00000000a1100000, size 31 MiB
[2021-08-19 16:23:38.786] [    0.000000] OF: reserved mem: initialized node vision_apps-r5f-memory@a1100000, compatible id shared-dma-pool
[2021-08-19 16:23:38.786] [    0.000000] Reserved memory: created DMA memory pool at 0x00000000a1100000, size 15 MiB
[2021-08-19 16:23:38.786] [    0.000000] OF: reserved mem: initialized node r5f-memory@a1100000, compatible id shared-dma-pool
[2021-08-19 16:23:38.787] [    0.000000] Reserved memory: created DMA memory pool at 0x00000000a2000000, size 1 MiB
[2021-08-19 16:23:38.787] [    0.000000] OF: reserved mem: initialized node r5f-dma-memory@a2000000, compatible id shared-dma-pool
[2021-08-19 16:23:38.830] [    0.000000] Reserved memory: created DMA memory pool at 0x00000000a2100000, size 15 MiB
[2021-08-19 16:23:38.830] [    0.000000] OF: reserved mem: initialized node r5f-memory@a2100000, compatible id shared-dma-pool
[2021-08-19 16:23:38.831] [    0.000000] Reserved memory: created DMA memory pool at 0x00000000a3000000, size 1 MiB
[2021-08-19 16:23:38.831] [    0.000000] OF: reserved mem: initialized node r5f-dma-memory@a3000000, compatible id shared-dma-pool
[2021-08-19 16:23:38.832] [    0.000000] Reserved memory: created DMA memory pool at 0x00000000a3100000, size 15 MiB
[2021-08-19 16:23:38.874] [    0.000000] OF: reserved mem: initialized node r5f-memory@a3100000, compatible id shared-dma-pool
[2021-08-19 16:23:38.874] [    0.000000] Reserved memory: created DMA memory pool at 0x00000000a3100000, size 31 MiB
[2021-08-19 16:23:38.875] [    0.000000] OF: reserved mem: initialized node vision_apps-r5f-memory@a3100000, compatible id shared-dma-pool
[2021-08-19 16:23:38.875] [    0.000000] Reserved memory: created DMA memory pool at 0x00000000a4000000, size 1 MiB
[2021-08-19 16:23:38.876] [    0.000000] OF: reserved mem: initialized node r5f-dma-memory@a4000000, compatible id shared-dma-pool
[2021-08-19 16:23:38.918] [    0.000000] Reserved memory: created DMA memory pool at 0x00000000a4100000, size 15 MiB
[2021-08-19 16:23:38.918] [    0.000000] OF: reserved mem: initialized node r5f-memory@a4100000, compatible id shared-dma-pool
[2021-08-19 16:23:38.919] [    0.000000] Reserved memory: created DMA memory pool at 0x00000000a5000000, size 1 MiB
[2021-08-19 16:23:38.919] [    0.000000] OF: reserved mem: initialized node vision_apps-r5f-dma-memory@a5000000, compatible id shared-dma-pool
[2021-08-19 16:23:38.920] [    0.000000] Reserved memory: created DMA memory pool at 0x00000000a5000000, size 1 MiB
[2021-08-19 16:23:38.962] [    0.000000] OF: reserved mem: initialized node r5f-dma-memory@a5000000, compatible id shared-dma-pool
[2021-08-19 16:23:38.963] [    0.000000] Reserved memory: created DMA memory pool at 0x00000000a5100000, size 15 MiB
[2021-08-19 16:23:38.963] [    0.000000] OF: reserved mem: initialized node r5f-memory@a5100000, compatible id shared-dma-pool
[2021-08-19 16:23:38.963] [    0.000000] Reserved memory: created DMA memory pool at 0x00000000a5100000, size 7 MiB
[2021-08-19 16:23:38.964] [    0.000000] OF: reserved mem: initialized node vision_apps-r5f-memory@a5100000, compatible id shared-dma-pool
[2021-08-19 16:23:39.006] [    0.000000] Reserved memory: created DMA memory pool at 0x00000000a5800000, size 1 MiB
[2021-08-19 16:23:39.007] [    0.000000] OF: reserved mem: initialized node vision_apps-r5f-dma-memory@a5800000, compatible id shared-dma-pool
[2021-08-19 16:23:39.007] [    0.000000] Reserved memory: created DMA memory pool at 0x00000000a5900000, size 7 MiB
[2021-08-19 16:23:39.008] [    0.000000] OF: reserved mem: initialized node vision_apps-r5f-memory@a5900000, compatible id shared-dma-pool
[2021-08-19 16:23:39.008] [    0.000000] Reserved memory: created DMA memory pool at 0x00000000a6000000, size 1 MiB
[2021-08-19 16:23:39.050] [    0.000000] OF: reserved mem: initialized node c66-dma-memory@a6000000, compatible id shared-dma-pool
[2021-08-19 16:23:39.051] [    0.000000] Reserved memory: created DMA memory pool at 0x00000000a6100000, size 15 MiB
[2021-08-19 16:23:39.051] [    0.000000] OF: reserved mem: initialized node c66-memory@a6100000, compatible id shared-dma-pool
[2021-08-19 16:23:39.052] [    0.000000] Reserved memory: created DMA memory pool at 0x00000000a7000000, size 1 MiB
[2021-08-19 16:23:39.052] [    0.000000] OF: reserved mem: initialized node c66-dma-memory@a7000000, compatible id shared-dma-pool
[2021-08-19 16:23:39.094] [    0.000000] Reserved memory: created DMA memory pool at 0x00000000a7100000, size 15 MiB
[2021-08-19 16:23:39.095] [    0.000000] OF: reserved mem: initialized node c66-memory@a7100000, compatible id shared-dma-pool
[2021-08-19 16:23:39.095] [    0.000000] Reserved memory: created DMA memory pool at 0x00000000a8000000, size 1 MiB
[2021-08-19 16:23:39.096] [    0.000000] OF: reserved mem: initialized node c71-dma-memory@a8000000, compatible id shared-dma-pool
[2021-08-19 16:23:39.096] [    0.000000] Reserved memory: created DMA memory pool at 0x00000000a8100000, size 31 MiB
[2021-08-19 16:23:39.138] [    0.000000] OF: reserved mem: initialized node c71-memory@a8100000, compatible id shared-dma-pool
[2021-08-19 16:23:39.139] [    0.000000] Reserved memory: created DMA memory pool at 0x00000000ac000000, size 32 MiB
[2021-08-19 16:23:39.139] [    0.000000] OF: reserved mem: initialized node vision_apps-dma-memory@ac000000, compatible id shared-dma-pool
[2021-08-19 16:23:39.140] [    0.000000] OF: reserved mem: initialized node vision_apps_shared-memories, compatible id dma-heap-carveout
[2021-08-19 16:23:39.140] [    0.000000] Reserved memory: created DMA memory pool at 0x00000000ce000000, size 720 MiB
[2021-08-19 16:23:39.192] [    0.000000] OF: reserved mem: initialized node vision_apps-core-heap-memory@ce000000, compatible id shared-dma-pool
[2021-08-19 16:23:39.193] [    0.000000] Reserved memory: created DMA memory pool at 0x00000000fb000000, size 1 MiB
[2021-08-19 16:23:39.193] [    0.000000] OF: reserved mem: initialized node vision_apps-r5f-dma-memory@fb000000, compatible id shared-dma-pool
[2021-08-19 16:23:39.194] [    0.000000] cma: Failed to reserve 512 MiB
[2021-08-19 16:23:39.194] [    0.000000] psci: probing for conduit method from DT.
[2021-08-19 16:23:39.237] [    0.000000] psci: PSCIv1.1 detected in firmware.
[2021-08-19 16:23:39.237] [    0.000000] psci: Using standard PSCI v0.2 function IDs
[2021-08-19 16:23:39.237] [    0.000000] psci: Trusted OS migration not required
[2021-08-19 16:23:39.237] [    0.000000] psci: SMC Calling Convention v1.0
[2021-08-19 16:23:39.238] [    0.000000] percpu: Embedded 2 pages/cpu s48408 r8192 d74472 u131072
[2021-08-19 16:23:39.238] [    0.000000] Detected PIPT I-cache on CPU0
[2021-08-19 16:23:39.238] [    0.000000] CPU features: detected: GIC system register CPU interface
[2021-08-19 16:23:39.239] [    0.000000] CPU features: detected: EL2 vector hardening
[2021-08-19 16:23:39.239] [    0.000000] Built 1 zonelists, mobility grouping off.  Total pages: 27781
[2021-08-19 16:23:39.281] [    0.000000] Kernel command line: console=ttyS2,115200n8 earlycon=ns16550a,mmio32,0x02800000 mtdparts=47040000.spi.0:512k(ospi.sbl),512k(ospi.tifs_a),512k(ospi.tifs_b),4m(ospi.mcufw_a),4m(ospi.mcufw_b),2m(ospi.atf_optee_a),2m(ospi.atf_optee_b),1m(ospi.u-boot-spl_a),1m(ospi.u-boot-spl_b),2m(ospi.u-boot_a),2m(ospi.u-boot_b),128k(ospi.misc_a),128k(ospi.misc_b),128k(ospi.factory),128k(ospi.safe_a),128k(ospi.safe_b),2m(ospi.mcudata),256k(ospi.sonar_data),10092544(ospi.reserved) root=PARTUUID=00000000-02 ro rootfstype=ext4 rootwait lpj=800000
[2021-08-19 16:23:39.335] [    0.000000] Dentry cache hash table entries: 262144 (order: 5, 2097152 bytes, linear)
[2021-08-19 16:23:39.335] [    0.000000] Inode-cache hash table entries: 131072 (order: 4, 1048576 bytes, linear)
[2021-08-19 16:23:39.336] [    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
[2021-08-19 16:23:39.336] [    0.000000] software IO TLB: mapped [mem 0x90000000-0x94000000] (64MB)
[2021-08-19 16:23:39.336] [    0.000000] Memory: 2435968K/1781760K available (9406K kernel code, 784K rwdata, 3776K rodata, 1664K init, 705K bss, 18446744073708897408K reserved, 0K cma-reserved)
[2021-08-19 16:23:39.379] [    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[2021-08-19 16:23:39.379] [    0.000000] rcu: Preemptible hierarchical RCU implementation.
[2021-08-19 16:23:39.380] [    0.000000] rcu: 	RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=2.
[2021-08-19 16:23:39.380] [    0.000000] 	Tasks RCU enabled.
[2021-08-19 16:23:39.380] [    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
[2021-08-19 16:23:39.381] [    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2
[2021-08-19 16:23:39.423] [    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
[2021-08-19 16:23:39.423] [    0.000000] GICv3: GIC: Using split EOI/Deactivate mode
[2021-08-19 16:23:39.424] [    0.000000] GICv3: 960 SPIs implemented
[2021-08-19 16:23:39.424] [    0.000000] GICv3: 0 Extended SPIs implemented
[2021-08-19 16:23:39.424] [    0.000000] GICv3: Distributor has no Range Selector support
[2021-08-19 16:23:39.425] [    0.000000] GICv3: 16 PPIs implemented
[2021-08-19 16:23:39.425] [    0.000000] GICv3: no VLPI support, no direct LPI support
[2021-08-19 16:23:39.425] [    0.000000] GICv3: CPU0: found redistributor 0 region 0:0x0000000001900000
[2021-08-19 16:23:39.470] [    0.000000] ITS [mem 0x01820000-0x0182ffff]
[2021-08-19 16:23:39.470] [    0.000000] GIC: enabling workaround for ITS: Socionext Synquacer pre-ITS
[2021-08-19 16:23:39.470] [    0.000000] ITS@0x0000000001820000: allocated 1048576 Devices @8c0800000 (flat, esz 8, psz 64K, shr 0)
[2021-08-19 16:23:39.470] [    0.000000] ITS: using cache flushing for cmd queue
[2021-08-19 16:23:39.471] [    0.000000] GICv3: using LPI property table @0x00000008c00c0000
[2021-08-19 16:23:39.471] [    0.000000] GIC: using cache flushing for LPI property table
[2021-08-19 16:23:39.472] [    0.000000] GICv3: CPU0: using allocated LPI pending table @0x00000008c00d0000
[2021-08-19 16:23:39.515] [    0.000000] random: get_random_bytes called from start_kernel+0x2b8/0x43c with crng_init=0
[2021-08-19 16:23:39.515] [    0.000000] arch_timer: cp15 timer(s) running at 200.00MHz (phys).
[2021-08-19 16:23:39.515] [    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x2e2049d3e8, max_idle_ns: 440795210634 ns
[2021-08-19 16:23:39.516] [    0.000001] sched_clock: 56 bits at 200MHz, resolution 5ns, wraps every 4398046511102ns
[2021-08-19 16:23:39.516] [    0.008291] Console: colour dummy device 80x25
[2021-08-19 16:23:39.516] [    0.012850] Calibrating delay loop (skipped) preset value.. 400.00 BogoMIPS (lpj=800000)
[2021-08-19 16:23:39.560] [    0.021120] pid_max: default: 32768 minimum: 301
[2021-08-19 16:23:39.560] [    0.025868] LSM: Security Framework initializing
[2021-08-19 16:23:39.561] [    0.030611] Mount-cache hash table entries: 8192 (order: 0, 65536 bytes, linear)
[2021-08-19 16:23:39.561] [    0.038176] Mountpoint-cache hash table entries: 8192 (order: 0, 65536 bytes, linear)
[2021-08-19 16:23:39.562] [    0.047138] ASID allocator initialised with 32768 entries
[2021-08-19 16:23:39.562] [    0.052715] rcu: Hierarchical SRCU implementation.
[2021-08-19 16:23:39.562] [    0.057726] Platform MSI: gic-its@1820000 domain created
[2021-08-19 16:23:39.605] [    0.063244] PCI/MSI: /bus@100000/interrupt-controller@1800000/gic-its@1820000 domain created
[2021-08-19 16:23:39.605] [    0.072007] smp: Bringing up secondary CPUs ...
[2021-08-19 16:23:39.606] [    0.077242] Detected PIPT I-cache on CPU1
[2021-08-19 16:23:39.606] [    0.077263] GICv3: CPU1: found redistributor 1 region 0:0x0000000001920000
[2021-08-19 16:23:39.606] [    0.077273] GICv3: CPU1: using allocated LPI pending table @0x00000008c00e0000
[2021-08-19 16:23:39.607] [    0.077296] CPU1: Booted secondary processor 0x0000000001 [0x411fd080]
[2021-08-19 16:23:39.607] [    0.077340] smp: Brought up 1 node, 2 CPUs
[2021-08-19 16:23:39.607] [    0.106684] SMP: Total of 2 processors activated.
[2021-08-19 16:23:39.659] [    0.111487] CPU features: detected: 32-bit EL0 Support
[2021-08-19 16:23:39.659] [    0.116740] CPU features: detected: CRC32 instructions
[2021-08-19 16:23:39.659] [    0.128276] CPU: All CPU(s) started at EL2
[2021-08-19 16:23:39.659] [    0.132468] alternatives: patching kernel code
[2021-08-19 16:23:39.660] [    0.137491] devtmpfs: initialized
[2021-08-19 16:23:39.660] [    0.143182] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[2021-08-19 16:23:39.661] [    0.153149] futex hash table entries: 512 (order: -1, 32768 bytes, linear)
[2021-08-19 16:23:39.661] [    0.160438] pinctrl core: initialized pinctrl subsystem
[2021-08-19 16:23:39.710] [    0.166165] NET: Registered protocol family 16
[2021-08-19 16:23:39.710] [    0.171008] DMA: preallocated 256 KiB pool for atomic allocations
[2021-08-19 16:23:39.710] [    0.177483] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
[2021-08-19 16:23:39.711] [    0.189485] HugeTLB registered 16.0 GiB page size, pre-allocated 0 pages
[2021-08-19 16:23:39.711] [    0.196341] HugeTLB registered 512 MiB page size, pre-allocated 0 pages
[2021-08-19 16:23:39.712] [    0.203099] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[2021-08-19 16:23:39.712] [    0.210934] cryptd: max_cpu_qlen set to 1000
[2021-08-19 16:23:39.756] [    0.216866] iommu: Default domain type: Translated 
[2021-08-19 16:23:39.756] [    0.221987] SCSI subsystem initialized
[2021-08-19 16:23:39.756] [    0.225920] mc: Linux media interface: v0.10
[2021-08-19 16:23:39.756] [    0.230288] videodev: Linux video capture interface: v2.00
[2021-08-19 16:23:39.756] [    0.235899] pps_core: LinuxPPS API ver. 1 registered
[2021-08-19 16:23:39.757] [    0.240968] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[2021-08-19 16:23:39.757] [    0.250304] PTP clock support registered
[2021-08-19 16:23:39.757] [    0.254314] EDAC MC: Ver: 3.0.0
[2021-08-19 16:23:39.757] [    0.257930] FPGA manager framework
[2021-08-19 16:23:39.803] [    0.261778] clocksource: Switched to clocksource arch_sys_counter
[2021-08-19 16:23:39.804] [    0.268072] VFS: Disk quotas dquot_6.6.0
[2021-08-19 16:23:39.804] [    0.272161] VFS: Dquot-cache hash table entries: 8192 (order 0, 65536 bytes)
[2021-08-19 16:23:39.804] [    0.281525] Carveout Heap: Exported 512 MiB at 0x00000000ae000000
[2021-08-19 16:23:39.805] [    0.287766] thermal_sys: Registered thermal governor 'step_wise'
[2021-08-19 16:23:39.805] [    0.287768] thermal_sys: Registered thermal governor 'power_allocator'
[2021-08-19 16:23:39.806] [    0.294165] NET: Registered protocol family 2
[2021-08-19 16:23:39.806] [    0.305515] tcp_listen_portaddr_hash hash table entries: 4096 (order: 0, 65536 bytes, linear)
[2021-08-19 16:23:39.848] [    0.314275] TCP established hash table entries: 16384 (order: 1, 131072 bytes, linear)
[2021-08-19 16:23:39.848] [    0.322419] TCP bind hash table entries: 16384 (order: 2, 262144 bytes, linear)
[2021-08-19 16:23:39.849] [    0.330021] TCP: Hash tables configured (established 16384 bind 16384)
[2021-08-19 16:23:39.849] [    0.336729] UDP hash table entries: 2048 (order: 0, 65536 bytes, linear)
[2021-08-19 16:23:39.850] [    0.343614] UDP-Lite hash table entries: 2048 (order: 0, 65536 bytes, linear)
[2021-08-19 16:23:39.894] [    0.350993] NET: Registered protocol family 1
[2021-08-19 16:23:39.894] [    0.355630] RPC: Registered named UNIX socket transport module.
[2021-08-19 16:23:39.895] [    0.361686] RPC: Registered udp transport module.
[2021-08-19 16:23:39.895] [    0.366498] RPC: Registered tcp transport module.
[2021-08-19 16:23:39.895] [    0.371301] RPC: Registered tcp NFSv4.1 backchannel transport module.
[2021-08-19 16:23:39.896] [    0.377884] PCI: CLS 0 bytes, default 64
[2021-08-19 16:23:39.896] [    0.382198] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 counters available
[2021-08-19 16:23:39.896] [    0.391818] Initialise system trusted keyrings
[2021-08-19 16:23:39.897] [    0.396417] workingset: timestamp_bits=46 max_order=16 bucket_order=0
[2021-08-19 16:23:39.948] [    0.404972] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[2021-08-19 16:23:39.948] [    0.411183] NFS: Registering the id_resolver key type
[2021-08-19 16:23:39.948] [    0.416352] Key type id_resolver registered
[2021-08-19 16:23:39.949] [    0.420622] Key type id_legacy registered
[2021-08-19 16:23:39.949] [    0.424718] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[2021-08-19 16:23:39.949] [    0.431660] 9p: Installing v9fs 9p2000 file system support
[2021-08-19 16:23:39.950] [    0.444199] Key type asymmetric registered
[2021-08-19 16:23:39.950] [    0.448394] Asymmetric key parser 'x509' registered
[2021-08-19 16:23:39.994] [    0.453396] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 244)
[2021-08-19 16:23:39.995] [    0.460955] io scheduler mq-deadline registered
[2021-08-19 16:23:39.995] [    0.465580] io scheduler kyber registered
[2021-08-19 16:23:39.995] [    0.470376] pinctrl-single 4301c000.pinmux: 94 pins, size 376
[2021-08-19 16:23:39.995] [    0.476413] pinctrl-single 11c000.pinmux: 173 pins, size 692
[2021-08-19 16:23:39.996] [    0.483600] k3-ringacc 2b800000.ringacc: Failed to get MSI domain
[2021-08-19 16:23:39.996] [    0.489868] k3-ringacc 3c000000.ringacc: Failed to get MSI domain
[2021-08-19 16:23:39.996] [    0.496174] ti-pat 31010000.pat: Found PAT Rev 1.0 with 16384 pages
[2021-08-19 16:23:40.039] [    0.502585] debugfs: Directory '31010000.pat' with parent 'regmap' already present!
[2021-08-19 16:23:40.039] [    0.510533] ti-pat 31011000.pat: Found PAT Rev 1.0 with 16384 pages
[2021-08-19 16:23:40.040] [    0.516947] debugfs: Directory '31011000.pat' with parent 'regmap' already present!
[2021-08-19 16:23:40.040] [    0.524863] ti-pat 31012000.pat: Found PAT Rev 1.0 with 16384 pages
[2021-08-19 16:23:40.041] [    0.531274] debugfs: Directory '31012000.pat' with parent 'regmap' already present!
[2021-08-19 16:23:40.041] [    0.539189] ti-pat 31013000.pat: Found PAT Rev 1.0 with 2048 pages
[2021-08-19 16:23:40.091] [    0.545512] debugfs: Directory '31013000.pat' with parent 'regmap' already present!
[2021-08-19 16:23:40.092] [    0.553424] ti-pat 31014000.pat: Found PAT Rev 1.0 with 2048 pages
[2021-08-19 16:23:40.092] [    0.559746] debugfs: Directory '31014000.pat' with parent 'regmap' already present!
[2021-08-19 16:23:40.093] [    0.568806] Serial: 8250/16550 driver, 10 ports, IRQ sharing enabled
[2021-08-19 16:23:40.093] [    0.579708] brd: module loaded
[2021-08-19 16:23:40.093] [    0.585507] loop: module loaded
[2021-08-19 16:23:40.094] [    0.588762] mtip32xx Version 1.3.1
[2021-08-19 16:23:40.094] [    0.592472] sysfs: cannot create duplicate filename '/devices/platform/dma_buf_phys'
[2021-08-19 16:23:40.136] [    0.600393] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 5.4.74-ACU-1.3.21.1 #11
[2021-08-19 16:23:40.136] [    0.607680] Hardware name: Texas Instruments K3 J721E SoC (DT)
[2021-08-19 16:23:40.137] [    0.613634] Call trace:
[2021-08-19 16:23:40.137] [    0.616131]  dump_backtrace+0x0/0x140
[2021-08-19 16:23:40.137] [    0.619865]  show_stack+0x14/0x20
[2021-08-19 16:23:40.137] [    0.623248]  dump_stack+0xb4/0x114
[2021-08-19 16:23:40.137] [    0.626719]  sysfs_warn_dup+0x5c/0x78
[2021-08-19 16:23:40.138] [    0.630453]  sysfs_create_dir_ns+0xd8/0xf0
[2021-08-19 16:23:40.138] [    0.634632]  kobject_add_internal+0x94/0x280
[2021-08-19 16:23:40.180] [    0.638987]  kobject_add+0x90/0xf8
[2021-08-19 16:23:40.180] [    0.642457]  device_add+0xdc/0x600
[2021-08-19 16:23:40.180] [    0.645925]  platform_device_add+0xfc/0x228
[2021-08-19 16:23:40.180] [    0.650191]  platform_device_register_full+0xc8/0x140
[2021-08-19 16:23:40.181] [    0.655351]  dma_buf_phys_init+0x68/0x94
[2021-08-19 16:23:40.181] [    0.659352]  do_one_initcall+0x50/0x1a8
[2021-08-19 16:23:40.181] [    0.663266]  kernel_init_freeable+0x194/0x23c
[2021-08-19 16:23:40.181] [    0.667712]  kernel_init+0x10/0xfc
[2021-08-19 16:23:40.182] [    0.671179]  ret_from_fork+0x10/0x1c
[2021-08-19 16:23:40.182] [    0.674838] kobject_add_internal failed for dma_buf_phys with -EEXIST, don't try to register things with the same name in the same directory.
[2021-08-19 16:23:40.226] [    0.688473] libphy: Fixed MDIO Bus: probed
[2021-08-19 16:23:40.226] [    0.692850] tun: Universal TUN/TAP device driver, 1.6
[2021-08-19 16:23:40.226] [    0.698176] igbvf: Intel(R) Gigabit Virtual Function Network Driver - version 2.4.0-k
[2021-08-19 16:23:40.227] [    0.706179] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.
[2021-08-19 16:23:40.227] [    0.712241] sky2: driver version 1.30
[2021-08-19 16:23:40.227] [    0.716272] VFIO - User Level meta-driver version: 0.3
[2021-08-19 16:23:40.228] [    0.721796] i2c /dev entries driver
[2021-08-19 16:23:40.228] [    0.725541] Error: Driver 'k3-soc-thermal' is already registered, aborting...
[2021-08-19 16:23:40.271] [    0.732979] sdhci: Secure Digital Host Controller Interface driver
[2021-08-19 16:23:40.272] [    0.739293] sdhci: Copyright(c) Pierre Ossman
[2021-08-19 16:23:40.272] [    0.743834] sdhci-pltfm: SDHCI platform and OF driver helper
[2021-08-19 16:23:40.272] [    0.749889] ledtrig-cpu: registered to indicate activity on CPUs
[2021-08-19 16:23:40.273] [    0.756492] optee: probing for conduit method from DT.
[2021-08-19 16:23:40.273] [    0.761761] optee: revision 3.8 (199fca17)
[2021-08-19 16:23:40.273] [    0.762037] optee: initialized driver
[2021-08-19 16:23:40.273] [    0.770132] NET: Registered protocol family 17
[2021-08-19 16:23:40.328] [    0.774742] 9pnet: Installing 9P2000 support
[2021-08-19 16:23:40.328] [    0.779125] Key type dns_resolver registered
[2021-08-19 16:23:40.328] [    0.783622] registered taskstats version 1
[2021-08-19 16:23:40.329] [    0.787808] Loading compiled-in X.509 certificates
[2021-08-19 16:23:40.329] [    0.794233] k3-ringacc 2b800000.ringacc: Failed to get MSI domain
[2021-08-19 16:23:40.330] [    0.800550] k3-ringacc 3c000000.ringacc: Failed to get MSI domain
[2021-08-19 16:23:40.330] [    0.807574] ti-sci 44083000.dmsc: ABI: 3.1 (firmware rev 0x0014 '20.8.7--v2020.08d (Terrific Lla')
[2021-08-19 16:23:40.330] [    0.828668] ti-sci-intr bus@100000:navss@30000000:interrupt-controller1: Interrupt Router 213 domain created
[2021-08-19 16:23:40.375] [    0.838934] ti-sci-inta 33d00000.interrupt-controller: Interrupt Aggregator domain 209 created
[2021-08-19 16:23:40.375] [    0.848332] k3-ringacc 2b800000.ringacc: Ring Accelerator probed rings:286, gp-rings[96,20] sci-dev-id:235
[2021-08-19 16:23:40.376] [    0.858208] k3-ringacc 2b800000.ringacc: dma-ring-reset-quirk: disabled
[2021-08-19 16:23:40.376] [    0.864966] k3-ringacc 2b800000.ringacc: RA Proxy rev. 66346100, num_proxies:64
[2021-08-19 16:23:40.377] [    0.873935] k3-ringacc 3c000000.ringacc: Ring Accelerator probed rings:1024, gp-rings[440,150] sci-dev-id:211
[2021-08-19 16:23:40.419] [    0.884072] k3-ringacc 3c000000.ringacc: dma-ring-reset-quirk: disabled
[2021-08-19 16:23:40.420] [    0.890830] k3-ringacc 3c000000.ringacc: RA Proxy rev. 66346100, num_proxies:64
[2021-08-19 16:23:40.420] [    0.898711] printk: console [ttyS2] disabled
[2021-08-19 16:23:40.420] [    0.903093] 2800000.serial: ttyS2 at MMIO 0x2800000 (irq = 11, base_baud = 3000000) is a 8250
[2021-08-19 16:23:40.421] [    0.911828] printk: console [ttyS2] enabled
[2021-08-19 16:23:40.421] [    0.911828] printk: console [ttyS2] enabled
[2021-08-19 16:23:40.421] [    0.920262] printk: bootconsole [ns16550a0] disabled
[2021-08-19 16:23:40.467] [    0.920262] printk: bootconsole [ns16550a0] disabled
[2021-08-19 16:23:40.467] [    0.930608] arm-smmu-v3 36600000.smmu: ias 48-bit, oas 48-bit (features 0x00001faf)
[2021-08-19 16:23:40.468] [    0.939289] arm-smmu-v3 36600000.smmu: allocated 524288 entries for cmdq
[2021-08-19 16:23:40.468] [    0.947796] arm-smmu-v3 36600000.smmu: allocated 524288 entries for evtq
[2021-08-19 16:23:40.469] [    0.955187] arm-smmu-v3 36600000.smmu: msi_domain absent - falling back to wired irqs
[2021-08-19 16:23:40.469] [    0.964087] am65-cpts 310d0000.cpts: CPTS ver 0x4e8a010a, freq:200000000, add_val:4 pps:0
[2021-08-19 16:23:40.510] [    0.972509] value of fuse 0 is 0x33d71bf3
[2021-08-19 16:23:40.511] [    0.976509] value of fuse 1 is 0x0
[2021-08-19 16:23:40.511] [    0.979899] value of fuse 2 is 0x5b5ba000
[2021-08-19 16:23:40.511] [    0.983895] value of fuse 3 is 0x1b3b9b
[2021-08-19 16:23:40.511] [    0.987722] k3_bandgap_probe and count is 5
[2021-08-19 16:23:40.512] [    0.991894] id is 0 and i is 0 and err is -13
[2021-08-19 16:23:40.512] [    0.996237] id is 0 and i is 1 and err is -35
[2021-08-19 16:23:40.512] [    1.000580] id is 0 and i is 2 and err is 0
[2021-08-19 16:23:40.512] [    1.004751] The errors in adc code for -40C, 30C & 125C 150C respectively:: -13 -35 -28 0
[2021-08-19 16:23:40.553] [    1.012907] slope m1::-11 and const c1::-132
[2021-08-19 16:23:40.554] [    1.017165] slope m2::68 and const c2::2634
[2021-08-19 16:23:40.554] [    1.021340] slope m3::7 and const c3::926
[2021-08-19 16:23:40.554] [    1.025363] id is 1 and i is 0 and err is -17
[2021-08-19 16:23:40.554] [    1.029706] id is 1 and i is 1 and err is -38
[2021-08-19 16:23:40.555] [    1.034053] id is 1 and i is 2 and err is 0
[2021-08-19 16:23:40.555] [    1.038235] id is 2 and i is 0 and err is -15
[2021-08-19 16:23:40.555] [    1.042579] id is 2 and i is 1 and err is -38
[2021-08-19 16:23:40.556] [    1.046921] id is 2 and i is 2 and err is 0
[2021-08-19 16:23:40.556] [    1.051103] id is 3 and i is 0 and err is -11
[2021-08-19 16:23:40.556] [    1.055446] id is 3 and i is 1 and err is -36
[2021-08-19 16:23:40.602] [    1.059789] id is 3 and i is 2 and err is 0
[2021-08-19 16:23:40.602] [    1.063971] id is 4 and i is 0 and err is -13
[2021-08-19 16:23:40.603] [    1.068315] id is 4 and i is 1 and err is -39
[2021-08-19 16:23:40.603] [    1.072658] id is 4 and i is 2 and err is 0
[2021-08-19 16:23:40.603] [    1.076843] Value of CTRLMMR_WKUP_RESET_SRC_STAT Register is 0x800
[2021-08-19 16:23:40.603] [    1.083008] high_max is 742 and low_max is 632 and regval is 0x27802e6
[2021-08-19 16:23:40.714] [    1.190482] Value of WKUP_VTM_MISC_CTRL2 is 0x27802e6
[2021-08-19 16:23:40.715] [    1.195519] Value of WKUP_VTM_MISC_CTRL is 0x1
[2021-08-19 16:23:40.715] [    1.200396] mmc0: CQHCI version 5.10
[2021-08-19 16:23:40.746] [    1.244770] mmc0: SDHCI controller on 4f80000.sdhci [4f80000.sdhci] using ADMA 64-bit
[2021-08-19 16:23:40.792] [    1.252966] omap-mailbox 31f80000.mailbox: omap mailbox rev 0x66fc7100
[2021-08-19 16:23:40.793] [    1.259965] omap-mailbox 31f81000.mailbox: omap mailbox rev 0x66fc7100
[2021-08-19 16:23:40.793] [    1.266830] omap-mailbox 31f82000.mailbox: omap mailbox rev 0x66fc7100
[2021-08-19 16:23:40.793] [    1.273720] omap-mailbox 31f83000.mailbox: omap mailbox rev 0x66fc7100
[2021-08-19 16:23:40.794] [    1.280617] omap-mailbox 31f84000.mailbox: omap mailbox rev 0x66fc7100
[2021-08-19 16:23:40.794] [    1.288017] ti-udma 285c0000.dma-controller: Channels: 26 (tchan: 13, rchan: 13, gp-rflow: 8)
[2021-08-19 16:23:40.833] [    1.298559] ti-udma 31150000.dma-controller: Channels: 84 (tchan: 42, rchan: 42, gp-rflow: 16)
[2021-08-19 16:23:40.834] [    1.309079] hctosys: unable to open rtc device (rtc0)
[2021-08-19 16:23:40.834] [    1.319814] Waiting for root device PARTUUID=00000000-02...
[2021-08-19 16:23:40.849] [    1.348005] random: fast init done
[2021-08-19 16:23:40.906] [    1.352517] mmc0: new DDR MMC card at address 0001
[2021-08-19 16:23:40.907] [    1.357656] mmcblk0: mmc0:0001 MMC32G 29.1 GiB 
[2021-08-19 16:23:40.907] [    1.362344] mmcblk0boot0: mmc0:0001 MMC32G partition 1 4.00 MiB
[2021-08-19 16:23:40.907] [    1.368427] mmcblk0boot1: mmc0:0001 MMC32G partition 2 4.00 MiB
[2021-08-19 16:23:40.908] [    1.374421] mmcblk0rpmb: mmc0:0001 MMC32G partition 3 4.00 MiB, chardev (240:0)
[2021-08-19 16:23:40.908] [    1.389715]  mmcblk0: p1 p2 p3 p4 < p5 p6 p7 p8 p9 >
[2021-08-19 16:23:40.908] [    1.405638] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
[2021-08-19 16:23:40.954] [    1.413750] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
[2021-08-19 16:23:40.954] [    1.422560] devtmpfs: mounted
[2021-08-19 16:23:40.954] [    1.425819] Freeing unused kernel memory: 1664K
[2021-08-19 16:23:40.954] [    1.437789] Run /sbin/init as init process
[2021-08-19 16:23:41.079] [    1.560348] systemd[1]: System time before build time, advancing clock.
[2021-08-19 16:23:41.117] [    1.598205] NET: Registered protocol family 10
[2021-08-19 16:23:41.117] [    1.602994] Segment Routing with IPv6
[2021-08-19 16:23:41.118] [    1.619060] systemd[1]: systemd 246 running in system mode. (-PAM -AUDIT -SELINUX -IMA -APPARMOR -SMACK -SYSVINIT -UTMP -LIBCRYPTSETUP +GCRYPT +GNUTLS -ACL +XZ -LZ4 -ZSTD -SECCOMP +BLKID -ELFUTILS +KMOD -IDN2 -IDN -PCRE2 default-hierarchy=hybrid)
[2021-08-19 16:23:41.155] [    1.641018] systemd[1]: Detected architecture arm64.
[2021-08-19 16:23:41.186] 
[2021-08-19 16:23:41.186] Welcome to Buildroot 2020.11.2!
[2021-08-19 16:23:41.186] 
[2021-08-19 16:23:41.202] [    1.690225] systemd[1]: Set hostname to <buildroot>.
[2021-08-19 16:23:41.286] [    1.762432] systemd[1]: multi-user.target: Wants dependency dropin /etc/systemd/system/multi-user.target.wants/daemon_watch.service_original is not a symlink, ignoring.
[2021-08-19 16:23:41.319] [    1.794991] systemd[1]: sysinit.target: Wants dependency dropin /etc/systemd/system/sysinit.target.wants/daemon_watch.service is not a symlink, ignoring.
[2021-08-19 16:23:41.351] [    1.850046] systemd[1]: Configuration file /etc/systemd/system/daemon_watch.service is marked executable. Please remove executable permission bits. Proceeding anyway.
[2021-08-19 16:23:41.395] [    1.865261] systemd[1]: /etc/systemd/system/daemon_watch.service:10: Support for option SysVStartPriority= has been removed and it is ignored
[2021-08-19 16:23:41.396] [    1.878122] systemd[1]: Configuration file /etc/systemd/system/boot_test.service is marked executable. Please remove executable permission bits. Proceeding anyway.
[2021-08-19 16:23:41.397] [    1.892702] systemd[1]: Configuration file /etc/systemd/system/boot_test.service is marked world-writable. Please remove world writability permission bits. Proceeding anyway.
[2021-08-19 16:23:41.448] [    1.908615] systemd[1]: /etc/systemd/system/boot_test.service:12: Support for option SysVStartPriority= has been removed and it is ignored
[2021-08-19 16:23:41.448] [    1.929676] systemd[1]: Queued start job for default target Multi-User System.
[2021-08-19 16:23:41.449] [    1.937266] random: systemd: uninitialized urandom read (16 bytes read)
[2021-08-19 16:23:41.449] [    1.943933] systemd[1]: system-serial\x2dgetty.slice: unit configures an IP firewall, but the local system does not support BPF/cgroup firewalling.
[2021-08-19 16:23:41.498] [    1.957127] systemd[1]: (This warning is only shown for the first unit using IP firewalling.)
[2021-08-19 16:23:41.498] [    1.966770] systemd[1]: Created slice system-serial\x2dgetty.slice.
[2021-08-19 16:23:41.499] [  OK  ] Created slice system-serial\x2dgetty.slice.
[2021-08-19 16:23:41.499] [    1.985911] random: systemd: uninitialized urandom read (16 bytes read)
[2021-08-19 16:23:41.499] [    1.992605] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[2021-08-19 16:23:41.562] [  OK  ] Started Dispatch Password �ts to Console Directory Watch.
[2021-08-19 16:23:41.563] [    2.017826] random: systemd: uninitialized urandom read (16 bytes read)
[2021-08-19 16:23:41.563] [    2.024503] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[2021-08-19 16:23:41.563] [  OK  ] Started Forward Password R�uests to Wall Directory Watch.
[2021-08-19 16:23:41.564] [    2.045963] systemd[1]: Reached target Paths.
[2021-08-19 16:23:41.564] [  OK  ] Reached target Paths.
[2021-08-19 16:23:41.564] [    2.061837] systemd[1]: Reached target Remote File Systems.
[2021-08-19 16:23:41.629] [  OK  ] Reached target Remote File Systems.
[2021-08-19 16:23:41.629] [    2.077822] systemd[1]: Reached target Slices.
[2021-08-19 16:23:41.629] [  OK  ] Reached target Slices.
[2021-08-19 16:23:41.630] [    2.093842] systemd[1]: Reached target Swap.
[2021-08-19 16:23:41.630] [  OK  ] Reached target Swap.
[2021-08-19 16:23:41.630] [    2.110057] systemd[1]: Listening on Syslog Socket.
[2021-08-19 16:23:41.630] [  OK  ] Listening on Syslog Socket.
[2021-08-19 16:23:41.689] [    2.131585] systemd[1]: Condition check resulted in Journal Audit Socket being skipped.
[2021-08-19 16:23:41.689] [    2.139913] systemd[1]: Listening on Journal Socket (/dev/log).
[2021-08-19 16:23:41.689] [  OK  ] Listening on Journal Socket (/dev/log).
[2021-08-19 16:23:41.690] [    2.158038] systemd[1]: Listening on Journal Socket.
[2021-08-19 16:23:41.690] [  OK  ] Listening on Journal Socket.
[2021-08-19 16:23:41.690] [    2.174056] systemd[1]: Listening on udev Control Socket.
[2021-08-19 16:23:41.691] [  OK  ] Listening on udev Control Socket.
[2021-08-19 16:23:41.691] [    2.189967] systemd[1]: Listening on udev Kernel Socket.
[2021-08-19 16:23:41.749] [  OK  ] Listening on udev Kernel Socket.
[2021-08-19 16:23:41.749] [    2.207734] systemd[1]: Mounting Huge Pages File System...
[2021-08-19 16:23:41.749]          Mounting Huge Pages File System...
[2021-08-19 16:23:41.749] [    2.223726] systemd[1]: Mounting POSIX Message Queue File System...
[2021-08-19 16:23:41.749]          Mounting POSIX Message Queue File System...
[2021-08-19 16:23:41.749] [    2.247993] systemd[1]: Mounting Kernel Debug File System...
[2021-08-19 16:23:41.770]          Mounting Kernel Debug File System...
[2021-08-19 16:23:41.770] [    2.269998] systemd[1]: Condition check resulted in Kernel Trace File System being skipped.
[2021-08-19 16:23:41.804] [    2.280401] systemd[1]: Mounting Temporary Directory (/tmp)...
[2021-08-19 16:23:41.804]          Mounting Temporary Directory (/tmp)...
[2021-08-19 16:23:41.805] [    2.302252] systemd[1]: Condition check resulted in /opt/gaia/test_boot_performance.sh Compatibility being skipped.
[2021-08-19 16:23:41.843] [    2.314651] systemd[1]: Starting Create list of static device nodes for the current kernel...
[2021-08-19 16:23:41.843]          Starting Create list of st�odes for the current kernel...
[2021-08-19 16:23:41.844] [    2.341343] systemd[1]: Starting Journal Service...
[2021-08-19 16:23:41.879]          Starting Journal Service...
[2021-08-19 16:23:41.879] [    2.360756] systemd[1]: Starting Load Kernel Modules...
[2021-08-19 16:23:41.879]          Starting Load Kernel Modules...
[2021-08-19 16:23:41.956] [    2.384739] systemd[1]: Starting Remount Root and Kernel File Systems...
[2021-08-19 16:23:41.956]          Starting Remount Root and Kernel File Systems...
[2021-08-19 16:23:41.956] [    2.404009] systemd[1]: Starting Coldplug All udev Devices...
[2021-08-19 16:23:41.957]          Starting Coldplug All udev Devices...
[2021-08-19 16:23:41.957] [    2.424201] systemd[1]: Started Journal Service.
[2021-08-19 16:23:41.957] [  OK  ] Started Journal Service.
[2021-08-19 16:23:41.958] [  OK  ] Mounted Huge Pages File System.
[2021-08-19 16:23:41.958] [  OK  ] Mounted POSIX Message Queue File System.
[2021-08-19 16:23:41.985] [  OK  ] Mounted Kernel Debug File System.
[2021-08-19 16:23:41.986] [  OK  ] Mounted Temporary Directory (/tmp).
[2021-08-19 16:23:42.047] [  OK  ] Finished Create list of st� nodes for the current kernel.
[2021-08-19 16:23:42.048] [  OK  ] Finished Load Kernel Modules.
[2021-08-19 16:23:42.048] [  OK  ] Finished Remount Root and Kernel File Systems.
[2021-08-19 16:23:42.070]          Mounting Kernel Configuration File System...
[2021-08-19 16:23:42.112]          Starting Flush Journal to Persistent Storage...
[2021-08-19 16:23:42.113]          Starting Apply Kernel Variables...
[2021-08-19 16:23:42.113]          Starting Create Static Device Nodes in /dev...
[2021-08-19 16:23:42.113] [  OK  ] Mounted Kernel Configuration File System.
[2021-08-19 16:23:42.161] [  OK  ] Finished Flush Journal to Persistent Storage.
[2021-08-19 16:23:42.162] [  OK  ] Finished Coldplug All udev Devices.
[2021-08-19 16:23:42.162] [  OK  ] Finished Apply Kernel Variables.
[2021-08-19 16:23:42.228] [  OK  ] Finished Create Static Device Nodes in /dev.
[2021-08-19 16:23:42.228] [  OK  ] Reached target Local File Systems (Pre).
[2021-08-19 16:23:42.229]          Starting Rule-based Manage�for Device Events and Files...
[2021-08-19 16:23:42.267] [  OK  ] Started Rule-based Manager for Device Events and Files.
[2021-08-19 16:23:42.315] [    2.815719] k3-dsp-rproc 4d80800000.dsp: assigned reserved memory node c66-dma-memory@a7000000
[2021-08-19 16:23:42.377] [  OK  ] Found device /dev/ttyS2.
[2021-08-19 16:23:42.377] [    2.835091] k3-dsp-rproc 4d80800000.dsp: configured DSP for remoteproc mode
[2021-08-19 16:23:42.377] [    2.847884] platform 41000000.r5f: ti-sci processor request failed: -19
[2021-08-19 16:23:42.378] [    2.861121] k3-dsp-rproc 4d80800000.dsp: local reset is deasserted for device
[2021-08-19 16:23:42.378] [    2.865841] platform 41000000.r5f: ti_sci_proc_request failed, ret = -19
[2021-08-19 16:23:42.378] [    2.872022] remoteproc remoteproc0: 4d80800000.dsp is available
[2021-08-19 16:23:42.429] [    2.885760] k3_r5_rproc bus@100000:bus@28380000:r5fss@41000000: k3_r5_core_of_init failed, ret = -19
[2021-08-19 16:23:42.430] [    2.886543] k3-dsp-rproc 4d81800000.dsp: assigned reserved memory node c66-dma-memory@a6000000
[2021-08-19 16:23:42.430] [    2.906350] k3-dsp-rproc 4d81800000.dsp: configured DSP for remoteproc mode
[2021-08-19 16:23:42.431] [    2.913994] k3-dsp-rproc 4d81800000.dsp: local reset is deasserted for device
[2021-08-19 16:23:42.431] [    2.921944] remoteproc remoteproc1: 4d81800000.dsp is available
[2021-08-19 16:23:42.432] [    2.928519] k3-dsp-rproc 64800000.dsp: assigned reserved memory node c71-dma-memory@a8000000
[2021-08-19 16:23:42.472] [    2.931743] k3_r5_rproc bus@100000:bus@28380000:r5fss@41000000: k3_r5_cluster_of_init failed, ret = -19
[2021-08-19 16:23:42.473] [    2.938034] k3-dsp-rproc 64800000.dsp: configured DSP for remoteproc mode
[2021-08-19 16:23:42.473] [    2.953673] remoteproc remoteproc2: 64800000.dsp is available
[2021-08-19 16:23:42.582] [    3.016409] platform 5c00000.r5f: configured R5F for remoteproc mode
[2021-08-19 16:23:42.582] [    3.028636] platform 5c00000.r5f: assigned reserved memory node vision_apps-r5f-dma-memory@a1000000
[2021-08-19 16:23:42.583] [    3.039708] remoteproc remoteproc3: 5c00000.r5f is available
[2021-08-19 16:23:42.583] [    3.047582] platform 5d00000.r5f: configured R5F for remoteproc mode
[2021-08-19 16:23:42.583] [    3.056400] platform 5d00000.r5f: assigned reserved memory node r5f-dma-memory@a3000000
[2021-08-19 16:23:42.584] [    3.065923] remoteproc remoteproc4: 5d00000.r5f is available
[2021-08-19 16:23:42.757] [    3.258858] hjwang rproc_boot-2110:5d00000.r5f 0
[2021-08-19 16:23:42.803] [    3.263490] remoteproc remoteproc4: powering up 5d00000.r5f
[2021-08-19 16:23:42.803] [    3.269910] remoteproc remoteproc4: Booting fw image j7-main-r5f0_1-fw, size 1923072
[2021-08-19 16:23:42.804] [    3.277664] hjwang rproc_fw_boot-1663:5d00000.r5f 
[2021-08-19 16:23:42.804] [    3.282753] hjwang rproc_fw_boot-1665:5d00000.r5f ret=0
[2021-08-19 16:23:42.804] [    3.288107] hjwang k3_r5_rproc_prepare-396:5d00000.r5f 
[2021-08-19 16:23:42.804] [    3.293588] hjwang k3_r5_rproc_prepare-399:5d00000.r5f 
[2021-08-19 16:23:42.805] [    3.299141] hjwang k3_r5_rproc_prepare-407:5d00000.r5f 
[2021-08-19 16:23:42.805] [    3.304369] hjwang k3_r5_rproc_prepare-413:5d00000.r5f 
[2021-08-19 16:23:42.824] [    3.309590] hjwang k3_r5_rproc_prepare-425:5d00000.r5f 

  • The difference between about the two log as below,  I can't find the log in the failed case .

    I hope it is useful

    [    0.000000] On node 0 totalpages: 27840
    [    0.000000]   DMA zone: 11 pages used for memmap
    [    0.000000]   DMA zone: 0 pages reserved
    [    0.000000]   DMA zone: 10368 pages, LIFO batch:1
    [    0.000000]   DMA32 zone: 16 pages used for memmap
    [    0.000000]   DMA32 zone: 18446744073709536320 pages, LIFO batch:3
    [    0.000000]   Normal zone: 32 pages used for memmap
    [    0.000000]   Normal zone: 32768 pages, LIFO batch:3

    Regards

  • Hi Wang,

    There is currently a limitation with the Kernel K3 R5F remoteproc driver in Split-mode. The Split-mode requires Core0 to be booted first before Core1, otherwise it can hang. The issue will be manifested when Core0 firmware file size is larger than Core1, and the delays in the firmware class while requesting the larger firmware causes the Core1 boot to proceed before Core0. 

    What are the file sizes of your firmwares for your corresponding Core0 and Core1? Are you using stripped ELF firmware images?

    Possible workarounds are:

    1. Boot Core0 in U-Boot

    2. Ensure Core1 firmware file size is larger than Core0. Use a stripped image for Core0 and an unstripped image for Core1.

    3. Modify the driver to disable auto-boot, and then boot Core0 and Core1 from userspace in that order.

    regards

    Suman