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.

DRA77P: Inconsistency in mounting time for eMMC

Part Number: DRA77P

Hi Team,

I am working on vision SDk v3.07.


I have implemented the single stage boot and have been loading the application apps.out through init-demo.sh


The following is the kernel command line I have used,

CONFIG_CMDLINE="elevator=noop console=ttyS0,115200n8 cma=64M omapdrm.num_crtc=1 consoleblank=0 snd.slots_reserved=1,1 fixrtc loglevel=0 root=/dev/mmcblk0p1 rootfstype=ext4 rw rootwait init=/home/root/init-demo.sh clk_ignore_unused quiet"

On different boots I find different time in launching my application. Looking into the dmesg logs, I find the timing for mounting the ext4 partition is taking more time i.e. from 100 ms to 700 ms. This adds up to delay to the launch of our application.

I have attached 4 logs each having different instants of launching the same application at 1712 ms, 1905 ms, 2255 ms, 2339 ms.

1712ms.txt
U-Boot SPL 2016.05 (Jul 17 2020 - 18:44:02)
DRA762-GP ES1.0
[    0.000000] Reserved memory: OVERLAP DETECTED!
[    0.000000] vsdk_sr1_mem@84000000 (0x0000000084000000--0x0000000097000000) overlaps with cmem@95400000 (0x0000000095400000--0x0000000095800)
[    0.000000] Reserved memory: regions without no-map are not yet supported
[    0.000000] Reserved memory: regions without no-map are not yet supported
[    0.000000] Reserved memory: regions without no-map are not yet supported
[    0.002013] /cpus/cpu@0 missing clock-frequency property
[    0.002030] /cpus/cpu@1 missing clock-frequency property
[    0.612491] omap_hsmmc 480b4000.mmc: no pinctrl state for sdr25 mode
[    0.618926] omap_hsmmc 480b4000.mmc: no pinctrl state for sdr12 mode
[    0.685183] omap_voltage_late_init: Voltage driver support not added
[    0.862920] Kernel Entry time 8179 ticks
Loaded PowerVR consumer services.
insmod: ERROR: could not load module ./bin/cmemk.ko: No such file or directory
CUSTOM Application launched at 1712


Dmesg

root@dra7xx-evm:/run/media/mmcblk1p1/opt/vision_sdk# cat dmesg.log 
[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Initializing cgroup subsys cpuset
[    0.000000] Initializing cgroup subsys cpu
[    0.000000] Initializing cgroup subsys cpuacct
[    0.000000] Linux version 4.4.84+ (ubantu@teltvml0203x) (gcc version 5.3.1 20160113 (Linaro GCC 5.3-2016.02) ) #11 SMP PREEMPT Wed Aug 5 120
[    0.000000] CPU: ARMv7 Processor [412fc0f2] revision 2 (ARMv7), cr=30c5387d
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache
[    0.000000] Machine model: TI DRA762 EVM
[    0.000000] Reserved memory: OVERLAP DETECTED!
[    0.000000] vsdk_sr1_mem@84000000 (0x0000000084000000--0x0000000097000000) overlaps with cmem@95400000 (0x0000000095400000--0x0000000095800)
[    0.000000] Reserved memory: created DMA memory pool at 0x0000000040300000, size 3 MiB
[    0.000000] Reserved memory: initialized node cmem@40300000, compatible id shared-dma-pool
[    0.000000] Reserved memory: regions without no-map are not yet supported
[    0.000000] Reserved memory: created CMA memory pool at 0x0000000099000000, size 80 MiB
[    0.000000] Reserved memory: initialized node ipu2_cma@95800000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created CMA memory pool at 0x000000009e000000, size 32 MiB
[    0.000000] Reserved memory: initialized node ipu1_cma@9d000000, compatible id shared-dma-pool
[    0.000000] Reserved memory: regions without no-map are not yet supported
[    0.000000] Reserved memory: created CMA memory pool at 0x00000000a1000000, size 32 MiB
[    0.000000] Reserved memory: initialized node dsp1_cma@99000000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created CMA memory pool at 0x00000000a3000000, size 32 MiB
[    0.000000] Reserved memory: initialized node dsp2_cma@9f000000, compatible id shared-dma-pool
[    0.000000] Reserved memory: regions without no-map are not yet supported
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a9000000, size 32 MiB
[    0.000000] Reserved memory: initialized node cmem@A9000000, compatible id shared-dma-pool
[    0.000000] cma: Reserved 64 MiB at 0x00000000dbc00000
[    0.000000] Forcing write-allocate cache policy for SMP
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] OMAP4: Map 0x00000000dfd00000 to fe600000 for dram barrier
[    0.000000] On node 0 totalpages: 382976
[    0.000000] free_area_init_node: node 0, pgdat c0584640, node_mem_map ef21a000
[    0.000000]   DMA zone: 1728 pages used for memmap
[    0.000000]   DMA zone: 0 pages reserved
[    0.000000]   DMA zone: 187392 pages, LIFO batch:31
[    0.000000]   HighMem zone: 195584 pages, LIFO batch:31
[    0.000000] DRA762 ES1.0
[    0.000000] PERCPU: Embedded 11 pages/cpu @ef1a6000 s13376 r8192 d23488 u45056
[    0.000000] pcpu-alloc: s13376 r8192 d23488 u45056 alloc=11*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 381248
[    0.000000] Kernel command line: elevator=noop console=ttyS0,115200n8 cma=64M omapdrm.num_crtc=1 consoleblank=0 snd.slots_reserved=1,1 fixrt
[    0.000000] PID hash table entries: 4096 (order: 2, 16384 bytes)
[    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
[    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
[    0.000000] Memory: 875856K/1531904K available (4322K kernel code, 216K rwdata, 804K rodata, 280K init, 256K bss, 410288K reserved, 245760K)
[    0.000000] Virtual kernel memory layout:
[    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
[    0.000000]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
[    0.000000]     vmalloc : 0xf0800000 - 0xff800000   ( 240 MB)
[    0.000000]     lowmem  : 0xc0000000 - 0xf0000000   ( 768 MB)
[    0.000000]     pkmap   : 0xbfe00000 - 0xc0000000   (   2 MB)
[    0.000000]     modules : 0xbf000000 - 0xbfe00000   (  14 MB)
[    0.000000]       .text : 0xc0008000 - 0xc0509f54   (5128 kB)
[    0.000000]       .init : 0xc050a000 - 0xc0550000   ( 280 kB)
[    0.000000]       .data : 0xc0550000 - 0xc0586368   ( 217 kB)
[    0.000000]        .bss : 0xc0588000 - 0xc05c83f4   ( 257 kB)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[    0.000000] Preemptible hierarchical RCU implementation.
[    0.000000]  Build-time adjustment of leaf fanout to 32.
[    0.000000] NR_IRQS:16 nr_irqs:16 16
[    0.000000] ti_dt_clocks_register: failed to lookup clock node gmac_gmii_ref_clk_div
[    0.000000] OMAP clockevent source: timer1 at 32786 Hz
[    0.000000] Architected cp15 timer(s) running at 6.14MHz (phys).
[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x16af5adb9, max_idle_ns: 440795202250 ns
[    0.000004] sched_clock: 56 bits at 6MHz, resolution 162ns, wraps every 4398046511023ns
[    0.000015] Switching to timer-based delay loop, resolution 162ns
[    0.000410] clocksource: 32k_counter: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 58327039986419 ns
[    0.000418] OMAP clocksource: 32k_counter at 32768 Hz
[    0.000965] Console: colour dummy device 80x30
[    0.000990] Calibrating delay loop (skipped), value calculated using timer frequency.. 12.29 BogoMIPS (lpj=61475)
[    0.001004] pid_max: default: 4096 minimum: 301
[    0.001101] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
[    0.001112] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
[    0.001713] Initializing cgroup subsys io
[    0.001728] Initializing cgroup subsys memory
[    0.001753] Initializing cgroup subsys devices
[    0.001766] Initializing cgroup subsys freezer
[    0.001777] Initializing cgroup subsys perf_event
[    0.001789] Initializing cgroup subsys pids
[    0.001815] CPU: Testing write buffer coherency: ok
[    0.002013] /cpus/cpu@0 missing clock-frequency property
[    0.002030] /cpus/cpu@1 missing clock-frequency property
[    0.002040] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[    0.002057] Setting up static identity map for 0x80008380 - 0x800083e0
[    0.079998] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[    0.080063] Brought up 2 CPUs
[    0.080076] SMP: Total of 2 processors activated (24.59 BogoMIPS).
[    0.080083] CPU: All CPU(s) started in HYP mode.
[    0.080089] CPU: Virtualization extensions available.
[    0.080484] devtmpfs: initialized
[    0.112964] VFP support v0.3: implementor 41 architecture 4 part 30 variant f rev 0
[    0.113622] omap_hwmod: l3_main_2 using broken dt data from ocp
[    0.126060] omap_hwmod: dcan1: _wait_target_disable failed
[    0.223673] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.223688] futex hash table entries: 16 (order: -2, 1024 bytes)
[    0.229348] pinctrl core: initialized pinctrl subsystem
[    0.229999] NET: Registered protocol family 16
[    0.231033] DMA: preallocated 256 KiB pool for atomic coherent allocations
[    0.260119] cpuidle: using governor ladder
[    0.290145] cpuidle: using governor menu
[    0.298668] OMAP GPIO hardware version 0.1
[    0.302860] GPIO line 161 (radio_rst) hogged as output/low
[    0.305305] irq: no irq domain found for /ocp/l4@4a000000/scm@2000/pinmux@1400 !
[    0.329304] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[    0.329314] hw-breakpoint: maximum watchpoint size is 8 bytes.
[    0.329746] omap4_sram_init:Unable to allocate sram needed to handle errata I688
[    0.329756] omap4_sram_init:Unable to get sram pool needed to handle errata I688
[    0.330017] OMAP DMA hardware revision 0.0
[    0.371960] omap-dma-engine 4a056000.dma-controller: OMAP DMA engine driver (LinkedList1/2/3 supported)
[    0.373158] edma 43300000.edma: memcpy is disabled
[    0.378174] edma 43300000.edma: TI EDMA DMA engine driver
[    0.382511] omap-iommu 40d01000.mmu: 40d01000.mmu registered
[    0.382660] omap-iommu 40d02000.mmu: 40d02000.mmu registered
[    0.382785] omap-iommu 58882000.mmu: 58882000.mmu registered
[    0.382909] omap-iommu 55082000.mmu: 55082000.mmu registered
[    0.383162] omap-iommu 41501000.mmu: 41501000.mmu registered
[    0.383315] omap-iommu 41502000.mmu: 41502000.mmu registered
[    0.385840] palmas 0-0058: IRQ missing: skipping irq request
[    0.400620] palmas 0-0058: Muxing GPIO 51, PWM 0, LED 2
[    0.451059] irq: no irq domain found for /ocp/i2c@48070000/tps65917@58 !
[    0.451771] omap_i2c 48070000.i2c: bus 0 rev0.12 at 400 kHz
[    0.452177] omap_i2c 48060000.i2c: bus 2 rev0.12 at 400 kHz
[    0.452645] omap_i2c 4807a000.i2c: bus 3 rev0.12 at 400 kHz
[    0.452880] EDAC MC: Ver: 3.0.0
[    0.453588] omap-mailbox 48840000.mailbox: omap mailbox rev 0x400
[    0.453886] omap-mailbox 48842000.mailbox: omap mailbox rev 0x400
[    0.454915] clocksource: Switched to clocksource arch_sys_counter
[    0.465386] NET: Registered protocol family 2
[    0.465928] TCP established hash table entries: 8192 (order: 3, 32768 bytes)
[    0.465992] TCP bind hash table entries: 8192 (order: 4, 65536 bytes)
[    0.466117] TCP: Hash tables configured (established 8192 bind 8192)
[    0.466163] UDP hash table entries: 512 (order: 2, 16384 bytes)
[    0.466195] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
[    0.466333] NET: Registered protocol family 1
[    0.467257] hw perfevents: enabled with armv7_cortex_a15 PMU driver, 7 counters available
[    0.477321] ntfs: driver 2.1.32 [Flags: R/O].
[    0.478422] bounce: pool size: 64 pages
[    0.478570] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250)
[    0.478580] io scheduler noop registered (default)
[    0.478588] io scheduler deadline registered
[    0.478617] io scheduler cfq registered
[    0.483218] pinctrl-single 4a003400.pinmux: 282 pins at pa fc003400 size 1128
[    0.545986] Serial: 8250/16550 driver, 10 ports, IRQ sharing disabled
[    0.549382] console [ttyS0] disabled
[    0.549435] 4806a000.serial: ttyS0 at MMIO 0x4806a000 (irq = 301, base_baud = 3000000) is a 8250
[    0.598348] console [ttyS0] enabled
[    0.599244] 4806c000.serial: ttyS1 at MMIO 0x4806c000 (irq = 302, base_baud = 3000000) is a 8250
[    0.600104] 48020000.serial: ttyS2 at MMIO 0x48020000 (irq = 303, base_baud = 3000000) is a 8250
[    0.601486] [drm] Initialized drm 1.1.0 20060810
[    0.602234] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
[    0.602242] [drm] No driver support for vblank timestamp query.
[    0.602440] [drm] Initialized vdrm 1.0.0 20110917 on minor 0
[    0.606086] vmemexp device MAJOR num = 248
[    0.606109] vmemexp class registered
[    0.606240] /dev/vmemexp device registered
[    0.606248] ioctl DBUFIOC_EXPORT_VIRTMEM = -1072899120
[    0.608000] i2c /dev entries driver
[    0.612491] omap_hsmmc 480b4000.mmc: no pinctrl state for sdr25 mode
[    0.618926] omap_hsmmc 480b4000.mmc: no pinctrl state for sdr12 mode
[    0.625610] vsys_3v3: supplied by vsys_12v0
[    0.625631] smps5: supplied by vsys_3v3
[    0.625829] vio_1v8: supplied by smps5
[    0.670624] hwspinlock_user gatemp: requested 20 hwspinlocks
[    0.671099] omap-rproc 58820000.ipu: assigned reserved memory node ipu1_cma@9d000000
[    0.671151]  remoteproc0: 58820000.ipu is available
[    0.671159]  remoteproc0: Note: remoteproc is still under development and considered experimental.
[    0.671167]  remoteproc0: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
[    0.671257]  remoteproc0: Direct firmware load for dra7-ipu1-fw.xem4 failed with error -2
[    0.671265]  remoteproc0: Falling back to user helper
[    0.671525] omap-rproc 55020000.ipu: assigned reserved memory node ipu2_cma@95800000
[    0.671575]  remoteproc1: 55020000.ipu is available
[    0.671583]  remoteproc1: Note: remoteproc is still under development and considered experimental.
[    0.671590]  remoteproc1: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
[    0.671787]  remoteproc1: Direct firmware load for dra7-ipu2-fw.xem4 failed with error -2
[    0.671795]  remoteproc1: Falling back to user helper
[    0.671996] omap-rproc 40800000.dsp: assigned reserved memory node dsp1_cma@99000000
[    0.672045]  remoteproc2: 40800000.dsp is available
[    0.672053]  remoteproc2: Note: remoteproc is still under development and considered experimental.
[    0.672060]  remoteproc2: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
[    0.672380] omap-rproc 41000000.dsp: assigned reserved memory node dsp2_cma@9f000000
[    0.672441]  remoteproc3: 41000000.dsp is available
[    0.672449]  remoteproc3: Note: remoteproc is still under development and considered experimental.
[    0.672456]  remoteproc3: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
[    0.673575] NET: Registered protocol family 10
[    0.684329] sit: IPv6 over IPv4 tunneling driver
[    0.684970] NET: Registered protocol family 17
[    0.684991] NET: Registered protocol family 41
[    0.685183] omap_voltage_late_init: Voltage driver support not added
[    0.692001] Adding alias for supply vdd,cpu0 -> vdd,4a003b20.oppdm
[    0.692010] Adding alias for supply vbb,cpu0 -> vbb,4a003b20.oppdm
[    0.692093] buck10: supplied by vsys_3v3
[    0.692251]  remoteproc2: Direct firmware load for dra7-dsp1-fw.xe66 failed with error -2
[    0.692259]  remoteproc2: Falling back to user helper
[    0.692495]  remoteproc3: Direct firmware load for dra7-dsp2-fw.xe66 failed with error -2
[    0.692504]  remoteproc3: Falling back to user helper
[    0.693054] Adding alias for supply vdd,cpu0 -> vdd,4a003b20.oppdm
[    0.693064] Adding alias for supply vbb,cpu0 -> vbb,4a003b20.oppdm
[    0.694735] Power Management for TI OMAP4+ devices.
[    0.694885] Registering SWP/SWPB emulation handler
[    0.695409] dmm 4e000000.dmm: workaround for errata i878 in use
[    0.696726] dmm 4e000000.dmm: initialized all PAT entries
[    0.697201] [drm] Initialized omapdrm 1.0.0 20110917 on minor 1
[    0.707350] hctosys: unable to open rtc device (rtc0)
[    0.707621] vio_3v3_sd: disabling
[    0.707629] aic_dvdd: disabling
[    0.707640] pbias_mmc_omap5: disabling
[    0.707650] clk: Not disabling unused clocks
[    0.708127] Waiting for root device /dev/mmcblk0p1...
[    0.748266] mmc0: MAN_BKOPS_EN bit is not set
[    0.755221] mmc0: new HS200 MMC card at address 0001
[    0.765498] mmcblk0: mmc0:0001 MMC08G 7.25 GiB 
[    0.775646] mmcblk0boot0: mmc0:0001 MMC08G partition 1 8.00 MiB
[    0.785788] mmcblk0boot1: mmc0:0001 MMC08G partition 2 8.00 MiB
[    0.786444]  mmcblk0: p1
[    0.851370] EXT4-fs (mmcblk0p1): mounted filesystem with ordered data mode. Opts: (null)
[    0.851391] VFS: Mounted root (ext4 filesystem) on device 179:1.
[    0.852011] devtmpfs: mounted
[    0.852145] Freeing unused kernel memory: 280K
[    0.852149] This architecture does not have kernel memory protection.
[    0.862920] Kernel Entry time 8179 ticks
[    1.013486] Inside rproc_elf_sanity_check
[    1.014117]  remoteproc0: powering up 58820000.ipu
[    1.014124] Inside rproc_elf_sanity_check
[    1.014130]  remoteproc0: Booting fw image dra7-ipu1-fw.xem4, size 8270008
[    1.014187] omap-iommu 58882000.mmu: 58882000.mmu: version 2.1
[    1.027198] Inside rproc_elf_sanity_check
[    1.028388]  remoteproc2: powering up 40800000.dsp
[    1.028397] Inside rproc_elf_sanity_check
[    1.028402]  remoteproc2: Booting fw image dra7-dsp1-fw.xe66, size 773384
[    1.034984] omap_hwmod: mmu0_dsp1: _wait_target_disable failed
[    1.035007] omap-iommu 40d01000.mmu: 40d01000.mmu: version 3.0
[    1.035061] omap-iommu 40d02000.mmu: 40d02000.mmu: version 3.0
[    1.043315]  remoteproc0: remote processor 58820000.ipu is now up
[    1.050271] virtio_rpmsg_bus virtio0: rpmsg host is online
[    1.050293]  remoteproc0: registered virtio0 (type 7)
[    1.058609]  remoteproc2: remote processor 40800000.dsp is now up
[    1.058887] virtio_rpmsg_bus virtio1: rpmsg host is online
[    1.058906]  remoteproc2: registered virtio1 (type 7)
[    1.065752] [drm] Initialized pvr 1.14.3699939 20110701 on minor 2
[    1.090395] PVR_K: UM DDK-(3699939) and KM DDK-(3699939) match. [ OK ]
[    1.091752] virtio_rpmsg_bus virtio1: creating channel rpmsg-proto addr 0x50
[    1.094830] virtio_rpmsg_bus virtio0: creating channel rpmsg-proto addr 0x50
[    1.107854] DMA: Module install successful, device major num = 245 
[    1.107861] DRV: Module install successful
1905ms.txt
Grabserial

[0.000001 0.000001] �
[0.173371 0.173370] U-Boot SPL 2016.05 (Jul 17 2020 - 18:44:02)
[0.174812 0.001441] DRA762-GP ES1.0
[1.148145 0.973333] [    0.000000] Reserved memory: OVERLAP DETECTED!
[1.193032 0.044887] [    0.000000] vsdk_sr1_mem@84000000 (0x0000000084000000--0x0000000097000000) overlaps with cmem@95400000 (0x0000000095400000--0x0000000095800000)
[1.199107 0.006075] [    0.000000] Reserved memory: regions without no-map are not yet supported
[1.201477 0.002370] [    0.000000] Reserved memory: regions without no-map are not yet supported
[1.203307 0.001830] [    0.000000] Reserved memory: regions without no-map are not yet supported
[1.204968 0.001661] [    0.002029] /cpus/cpu@0 missing clock-frequency property
[1.205891 0.000923] [    0.002044] /cpus/cpu@1 missing clock-frequency property
[1.233066 0.027175] [    0.621035] omap_hsmmc 480b4000.mmc: no pinctrl state for sdr25 mode
[1.235124 0.002058] [    0.627470] omap_hsmmc 480b4000.mmc: no pinctrl state for sdr12 mode
[1.288158 0.053034] [    0.681576] omap_voltage_late_init: Voltage driver support not added
[1.464087 0.175929] [    0.864728] Kernel Entry time 8180 ticks
[1.917959 0.453872] Loaded PowerVR consumer services.
[1.919351 0.001392] insmod: ERROR: could not load module ./bin/cmemk.ko: No such file or directory
[1.921463 0.002112] CUSTOM Application launched at 1905
[2.285707 0.364244] [    1.698443] omap_l3_noc 44000000.ocp: L3 application error: target 3 mod:2 (unclearable)
[2.314183 0.028476] [    1.706579] omap_l3_noc 44000000.ocp: L3 debug error: target 3 mod:2 (unclearable)
[4.164917 1.850734] loaded module : gbm_pvr.so
[4.166984 0.002067] found valid GBM backend : gbm_pvr.so



dmesg
root@dra7xx-evm:/run/media/mmcblk1p1/opt/vision_sdk# cat dmesg.log 
[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Initializing cgroup subsys cpuset
[    0.000000] Initializing cgroup subsys cpu
[    0.000000] Initializing cgroup subsys cpuacct
[    0.000000] Linux version 4.4.84+ (ubantu@teltvml0203x) (gcc version 5.3.1 20160113 (Linaro GCC 5.3-2016.02) ) #11 SMP PREEMPT Wed Aug 5 120
[    0.000000] CPU: ARMv7 Processor [412fc0f2] revision 2 (ARMv7), cr=30c5387d
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache
[    0.000000] Machine model: TI DRA762 EVM
[    0.000000] Reserved memory: OVERLAP DETECTED!
[    0.000000] vsdk_sr1_mem@84000000 (0x0000000084000000--0x0000000097000000) overlaps with cmem@95400000 (0x0000000095400000--0x0000000095800)
[    0.000000] Reserved memory: created DMA memory pool at 0x0000000040300000, size 3 MiB
[    0.000000] Reserved memory: initialized node cmem@40300000, compatible id shared-dma-pool
[    0.000000] Reserved memory: regions without no-map are not yet supported
[    0.000000] Reserved memory: created CMA memory pool at 0x0000000099000000, size 80 MiB
[    0.000000] Reserved memory: initialized node ipu2_cma@95800000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created CMA memory pool at 0x000000009e000000, size 32 MiB
[    0.000000] Reserved memory: initialized node ipu1_cma@9d000000, compatible id shared-dma-pool
[    0.000000] Reserved memory: regions without no-map are not yet supported
[    0.000000] Reserved memory: created CMA memory pool at 0x00000000a1000000, size 32 MiB
[    0.000000] Reserved memory: initialized node dsp1_cma@99000000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created CMA memory pool at 0x00000000a3000000, size 32 MiB
[    0.000000] Reserved memory: initialized node dsp2_cma@9f000000, compatible id shared-dma-pool
[    0.000000] Reserved memory: regions without no-map are not yet supported
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a9000000, size 32 MiB
[    0.000000] Reserved memory: initialized node cmem@A9000000, compatible id shared-dma-pool
[    0.000000] cma: Reserved 64 MiB at 0x00000000dbc00000
[    0.000000] Forcing write-allocate cache policy for SMP
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] OMAP4: Map 0x00000000dfd00000 to fe600000 for dram barrier
[    0.000000] On node 0 totalpages: 382976
[    0.000000] free_area_init_node: node 0, pgdat c0584640, node_mem_map ef21a000
[    0.000000]   DMA zone: 1728 pages used for memmap
[    0.000000]   DMA zone: 0 pages reserved
[    0.000000]   DMA zone: 187392 pages, LIFO batch:31
[    0.000000]   HighMem zone: 195584 pages, LIFO batch:31
[    0.000000] DRA762 ES1.0
[    0.000000] PERCPU: Embedded 11 pages/cpu @ef1a6000 s13376 r8192 d23488 u45056
[    0.000000] pcpu-alloc: s13376 r8192 d23488 u45056 alloc=11*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 381248
[    0.000000] Kernel command line: elevator=noop console=ttyS0,115200n8 cma=64M omapdrm.num_crtc=1 consoleblank=0 snd.slots_reserved=1,1 fixrt
[    0.000000] PID hash table entries: 4096 (order: 2, 16384 bytes)
[    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
[    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
[    0.000000] Memory: 875856K/1531904K available (4322K kernel code, 216K rwdata, 804K rodata, 280K init, 256K bss, 410288K reserved, 245760K)
[    0.000000] Virtual kernel memory layout:
[    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
[    0.000000]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
[    0.000000]     vmalloc : 0xf0800000 - 0xff800000   ( 240 MB)
[    0.000000]     lowmem  : 0xc0000000 - 0xf0000000   ( 768 MB)
[    0.000000]     pkmap   : 0xbfe00000 - 0xc0000000   (   2 MB)
[    0.000000]     modules : 0xbf000000 - 0xbfe00000   (  14 MB)
[    0.000000]       .text : 0xc0008000 - 0xc0509f54   (5128 kB)
[    0.000000]       .init : 0xc050a000 - 0xc0550000   ( 280 kB)
[    0.000000]       .data : 0xc0550000 - 0xc0586368   ( 217 kB)
[    0.000000]        .bss : 0xc0588000 - 0xc05c83f4   ( 257 kB)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[    0.000000] Preemptible hierarchical RCU implementation.
[    0.000000]  Build-time adjustment of leaf fanout to 32.
[    0.000000] NR_IRQS:16 nr_irqs:16 16
[    0.000000] ti_dt_clocks_register: failed to lookup clock node gmac_gmii_ref_clk_div
[    0.000000] OMAP clockevent source: timer1 at 32786 Hz
[    0.000000] Architected cp15 timer(s) running at 6.14MHz (phys).
[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x16af5adb9, max_idle_ns: 440795202250 ns
[    0.000004] sched_clock: 56 bits at 6MHz, resolution 162ns, wraps every 4398046511023ns
[    0.000015] Switching to timer-based delay loop, resolution 162ns
[    0.000416] clocksource: 32k_counter: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 58327039986419 ns
[    0.000424] OMAP clocksource: 32k_counter at 32768 Hz
[    0.000975] Console: colour dummy device 80x30
[    0.000999] Calibrating delay loop (skipped), value calculated using timer frequency.. 12.29 BogoMIPS (lpj=61475)
[    0.001013] pid_max: default: 4096 minimum: 301
[    0.001110] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
[    0.001121] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
[    0.001727] Initializing cgroup subsys io
[    0.001743] Initializing cgroup subsys memory
[    0.001768] Initializing cgroup subsys devices
[    0.001780] Initializing cgroup subsys freezer
[    0.001792] Initializing cgroup subsys perf_event
[    0.001803] Initializing cgroup subsys pids
[    0.001830] CPU: Testing write buffer coherency: ok
[    0.002029] /cpus/cpu@0 missing clock-frequency property
[    0.002044] /cpus/cpu@1 missing clock-frequency property
[    0.002054] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[    0.002070] Setting up static identity map for 0x80008380 - 0x800083e0
[    0.079997] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[    0.080061] Brought up 2 CPUs
[    0.080073] SMP: Total of 2 processors activated (24.59 BogoMIPS).
[    0.080080] CPU: All CPU(s) started in HYP mode.
[    0.080086] CPU: Virtualization extensions available.
[    0.080485] devtmpfs: initialized
[    0.112804] VFP support v0.3: implementor 41 architecture 4 part 30 variant f rev 0
[    0.113462] omap_hwmod: l3_main_2 using broken dt data from ocp
[    0.125885] omap_hwmod: dcan1: _wait_target_disable failed
[    0.223460] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.223476] futex hash table entries: 16 (order: -2, 1024 bytes)
[    0.229136] pinctrl core: initialized pinctrl subsystem
[    0.229791] NET: Registered protocol family 16
[    0.230827] DMA: preallocated 256 KiB pool for atomic coherent allocations
[    0.270125] cpuidle: using governor ladder
[    0.300155] cpuidle: using governor menu
[    0.308703] OMAP GPIO hardware version 0.1
[    0.312866] GPIO line 161 (radio_rst) hogged as output/low
[    0.315316] irq: no irq domain found for /ocp/l4@4a000000/scm@2000/pinmux@1400 !
[    0.337370] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[    0.337381] hw-breakpoint: maximum watchpoint size is 8 bytes.
[    0.337819] omap4_sram_init:Unable to allocate sram needed to handle errata I688
[    0.337829] omap4_sram_init:Unable to get sram pool needed to handle errata I688
[    0.338086] OMAP DMA hardware revision 0.0
[    0.382042] omap-dma-engine 4a056000.dma-controller: OMAP DMA engine driver (LinkedList1/2/3 supported)
[    0.383226] edma 43300000.edma: memcpy is disabled
[    0.388358] edma 43300000.edma: TI EDMA DMA engine driver
[    0.392624] omap-iommu 40d01000.mmu: 40d01000.mmu registered
[    0.392767] omap-iommu 40d02000.mmu: 40d02000.mmu registered
[    0.392895] omap-iommu 58882000.mmu: 58882000.mmu registered
[    0.393027] omap-iommu 55082000.mmu: 55082000.mmu registered
[    0.393261] omap-iommu 41501000.mmu: 41501000.mmu registered
[    0.393411] omap-iommu 41502000.mmu: 41502000.mmu registered
[    0.395930] palmas 0-0058: IRQ missing: skipping irq request
[    0.410617] palmas 0-0058: Muxing GPIO 51, PWM 0, LED 2
[    0.461058] irq: no irq domain found for /ocp/i2c@48070000/tps65917@58 !
[    0.461780] omap_i2c 48070000.i2c: bus 0 rev0.12 at 400 kHz
[    0.462182] omap_i2c 48060000.i2c: bus 2 rev0.12 at 400 kHz
[    0.462654] omap_i2c 4807a000.i2c: bus 3 rev0.12 at 400 kHz
[    0.462877] EDAC MC: Ver: 3.0.0
[    0.463609] omap-mailbox 48840000.mailbox: omap mailbox rev 0x400
[    0.463905] omap-mailbox 48842000.mailbox: omap mailbox rev 0x400
[    0.464907] clocksource: Switched to clocksource arch_sys_counter
[    0.475307] NET: Registered protocol family 2
[    0.475850] TCP established hash table entries: 8192 (order: 3, 32768 bytes)
[    0.475914] TCP bind hash table entries: 8192 (order: 4, 65536 bytes)
[    0.476039] TCP: Hash tables configured (established 8192 bind 8192)
[    0.476085] UDP hash table entries: 512 (order: 2, 16384 bytes)
[    0.476117] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
[    0.476250] NET: Registered protocol family 1
[    0.477150] hw perfevents: enabled with armv7_cortex_a15 PMU driver, 7 counters available
[    0.487177] ntfs: driver 2.1.32 [Flags: R/O].
[    0.488267] bounce: pool size: 64 pages
[    0.488421] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250)
[    0.488431] io scheduler noop registered (default)
[    0.488439] io scheduler deadline registered
[    0.488468] io scheduler cfq registered
[    0.493013] pinctrl-single 4a003400.pinmux: 282 pins at pa fc003400 size 1128
[    0.555313] Serial: 8250/16550 driver, 10 ports, IRQ sharing disabled
[    0.558725] console [ttyS0] disabled
[    0.558777] 4806a000.serial: ttyS0 at MMIO 0x4806a000 (irq = 301, base_baud = 3000000) is a 8250
[    0.607684] console [ttyS0] enabled
[    0.608590] 4806c000.serial: ttyS1 at MMIO 0x4806c000 (irq = 302, base_baud = 3000000) is a 8250
[    0.609458] 48020000.serial: ttyS2 at MMIO 0x48020000 (irq = 303, base_baud = 3000000) is a 8250
[    0.610786] [drm] Initialized drm 1.1.0 20060810
[    0.611528] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
[    0.611536] [drm] No driver support for vblank timestamp query.
[    0.611731] [drm] Initialized vdrm 1.0.0 20110917 on minor 0
[    0.615352] vmemexp device MAJOR num = 248
[    0.615376] vmemexp class registered
[    0.615509] /dev/vmemexp device registered
[    0.615517] ioctl DBUFIOC_EXPORT_VIRTMEM = -1072899120
[    0.616655] i2c /dev entries driver
[    0.621035] omap_hsmmc 480b4000.mmc: no pinctrl state for sdr25 mode
[    0.627470] omap_hsmmc 480b4000.mmc: no pinctrl state for sdr12 mode
[    0.634125] vsys_3v3: supplied by vsys_12v0
[    0.634146] smps5: supplied by vsys_3v3
[    0.634329] vio_1v8: supplied by smps5
[    0.677057] hwspinlock_user gatemp: requested 20 hwspinlocks
[    0.677468] omap-rproc 58820000.ipu: assigned reserved memory node ipu1_cma@9d000000
[    0.677519]  remoteproc0: 58820000.ipu is available
[    0.677527]  remoteproc0: Note: remoteproc is still under development and considered experimental.
[    0.677535]  remoteproc0: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
[    0.677622]  remoteproc0: Direct firmware load for dra7-ipu1-fw.xem4 failed with error -2
[    0.677630]  remoteproc0: Falling back to user helper
[    0.677842] omap-rproc 55020000.ipu: assigned reserved memory node ipu2_cma@95800000
[    0.677892]  remoteproc1: 55020000.ipu is available
[    0.677899]  remoteproc1: Note: remoteproc is still under development and considered experimental.
[    0.677906]  remoteproc1: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
[    0.678068]  remoteproc1: Direct firmware load for dra7-ipu2-fw.xem4 failed with error -2
[    0.678076]  remoteproc1: Falling back to user helper
[    0.678316] omap-rproc 40800000.dsp: assigned reserved memory node dsp1_cma@99000000
[    0.678370]  remoteproc2: 40800000.dsp is available
[    0.678378]  remoteproc2: Note: remoteproc is still under development and considered experimental.
[    0.678385]  remoteproc2: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
[    0.678649] omap-rproc 41000000.dsp: assigned reserved memory node dsp2_cma@9f000000
[    0.678699]  remoteproc3: 41000000.dsp is available
[    0.678706]  remoteproc3: Note: remoteproc is still under development and considered experimental.
[    0.678713]  remoteproc3: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
[    0.679841] NET: Registered protocol family 10
[    0.680406]  remoteproc2: Direct firmware load for dra7-dsp1-fw.xe66 failed with error -2
[    0.680414]  remoteproc2: Falling back to user helper
[    0.680748] sit: IPv6 over IPv4 tunneling driver
[    0.681367] NET: Registered protocol family 17
[    0.681384] NET: Registered protocol family 41
[    0.681576] omap_voltage_late_init: Voltage driver support not added
[    0.688148]  remoteproc3: Direct firmware load for dra7-dsp2-fw.xe66 failed with error -2
[    0.688156]  remoteproc3: Falling back to user helper
[    0.688693] Adding alias for supply vdd,cpu0 -> vdd,4a003b20.oppdm
[    0.688703] Adding alias for supply vbb,cpu0 -> vbb,4a003b20.oppdm
[    0.688784] buck10: supplied by vsys_3v3
[    0.689298] Adding alias for supply vdd,cpu0 -> vdd,4a003b20.oppdm
[    0.689308] Adding alias for supply vbb,cpu0 -> vbb,4a003b20.oppdm
[    0.690987] Power Management for TI OMAP4+ devices.
[    0.691136] Registering SWP/SWPB emulation handler
[    0.691616] dmm 4e000000.dmm: workaround for errata i878 in use
[    0.692970] dmm 4e000000.dmm: initialized all PAT entries
[    0.693426] [drm] Initialized omapdrm 1.0.0 20110917 on minor 1
[    0.703574] hctosys: unable to open rtc device (rtc0)
[    0.703845] vio_3v3_sd: disabling
[    0.703852] aic_dvdd: disabling
[    0.703863] pbias_mmc_omap5: disabling
[    0.703873] clk: Not disabling unused clocks
[    0.704350] Waiting for root device /dev/mmcblk0p1...
[    0.748251] mmc0: MAN_BKOPS_EN bit is not set
[    0.755157] mmc0: new HS200 MMC card at address 0001
[    0.765421] mmcblk0: mmc0:0001 MMC08G 7.25 GiB 
[    0.775568] mmcblk0boot0: mmc0:0001 MMC08G partition 1 8.00 MiB
[    0.785708] mmcblk0boot1: mmc0:0001 MMC08G partition 2 8.00 MiB
[    0.786292]  mmcblk0: p1
[    0.856867] EXT4-fs (mmcblk0p1): recovery complete
[    0.857636] EXT4-fs (mmcblk0p1): mounted filesystem with ordered data mode. Opts: (null)
[    0.857658] VFS: Mounted root (ext4 filesystem) on device 179:1.
[    0.858222] devtmpfs: mounted
[    0.858356] Freeing unused kernel memory: 280K
[    0.858360] This architecture does not have kernel memory protection.
[    0.864728] Kernel Entry time 8180 ticks
[    1.085517] Inside rproc_elf_sanity_check
[    1.086319]  remoteproc2: powering up 40800000.dsp
[    1.086329] Inside rproc_elf_sanity_check
[    1.086338]  remoteproc2: Booting fw image dra7-dsp1-fw.xe66, size 13712676
[    1.092955] omap_hwmod: mmu0_dsp1: _wait_target_disable failed
[    1.092981] omap-iommu 40d01000.mmu: 40d01000.mmu: version 3.0
[    1.093015] omap-iommu 40d02000.mmu: 40d02000.mmu: version 3.0
[    1.115814]  remoteproc2: remote processor 40800000.dsp is now up
[    1.116309] virtio_rpmsg_bus virtio0: rpmsg host is online
[    1.116344]  remoteproc2: registered virtio0 (type 7)
[    1.213648] Inside rproc_elf_sanity_check
[    1.214473]  remoteproc1: powering up 55020000.ipu
[    1.214480] Inside rproc_elf_sanity_check
[    1.214486]  remoteproc1: Booting fw image dra7-ipu2-fw.xem4, size 8245568
[    1.214540] omap-iommu 55082000.mmu: 55082000.mmu: version 2.1
[    1.236760]  remoteproc1: remote processor 55020000.ipu is now up
[    1.237037] virtio_rpmsg_bus virtio1: rpmsg host is online
[    1.237605]  remoteproc1: registered virtio1 (type 7)
[    1.256761] [drm] Initialized pvr 1.14.3699939 20110701 on minor 2
[    1.284946] virtio_rpmsg_bus virtio0: creating channel rpmsg-proto addr 0x50
[    1.286393] PVR_K: UM DDK-(3699939) and KM DDK-(3699939) match. [ OK ]
[    1.288046] virtio_rpmsg_bus virtio1: creating channel rpmsg-proto addr 0x50
[    1.303102] DMA: Module install successful, device major num = 245 
[    1.303107] DRV: Module install successful


Apps Log

root@dra7xx-evm:/run/media/mmcblk1p1/opt/vision_sdk# cat vsdk.log | more
 [HOST]  OSA: MEM: 0: Mapped 0xa0100000 to 0xb698b000 of size 0x00100000 
 [HOST]  OSA: MEM: 1: Mapped 0x84203000 to 0xa6d8b000 of size 0x0fc00000 
 [HOST]  OSA: MEM: 2: Mapped 0xa0200000 to 0xa6ccb000 of size 0x00040000 
 [HOST]  OSA: MEM: 3: Mapped 0xa02c0000 to 0xa6d0b000 of size 0x00080000 
 [HOST]  OSA: MEM: 4: Mapped 0x00000000 to 0x00000000 of size 0x00000000 
 [HOST] 
OSA_getCurGlobalTimeInMsec in OSA_init Time: 1933
 [HOST]  OSA: HOST Remote Log Shared Memory @ 0xa024f140
 [HOST]  OSA: DSP1 Remote Log Shared Memory @ 0xa02769e0
 [HOST]  OSA: IPU2 Remote Log Shared Memory @ 0xa0363da0
 [HOST] [HOST  ]      1.933203 s:  SYSTEM: System A15 Init in progress !!!
 [HOST] [DSP1  ]      1.706887 s:  ***** DSP1 Firmware build time 18:08:23 Jul 28 2020
 [HOST] [DSP1  ]      1.706948 s:  *** SYSTEM: CPU Frequency <ORG = 600000000 Hz>, <NEW = 600000000 Hz>
 [HOST] [DSP1  ]      1.707161 s:  SYSTEM: System Common Init in progress !!!
 [HOST] [DSP1  ]      1.707314 s:  UTILS: CIO: Init Done !!!
 [HOST] [DSP1  ]      1.707344 s:  SYSTEM: IPC init in progress !!!
 [HOST] [DSP1  ]      1.707375 s:  SYSTEM: Attaching to [IPU2] ... 
 [HOST] [DSP1  ]      1.874306 s:  SYSTEM: Attaching to [IPU2] ... SUCCESS !!!
 [HOST] [DSP1  ]      1.874336 s:  SYSTEM: Notify register to [IPU2] line 0, event 15... 
 [HOST] [DSP1  ]      1.874397 s:  SYSTEM: Notify init done !!!
 [HOST] [DSP1  ]      1.874519 s:  SYSTEM: MsgQ init done !!!
 [HOST] [DSP1  ]      1.874611 s: RpmsgInit in progress...
 [HOST] [DSP1  ]      1.874641 s: Connection established with HLOS, calling rpmsgInit
 [HOST] [DSP1  ]      1.874824 s:  SYSTEM: IPC init DONE !!!
 [HOST] [DSP1  ]      1.875495 s:  SYSTEM: System Common Init Done !!!
 [HOST] [DSP1  ]      1.875526 s:  SYSTEM: System DSP Init in progress !!!
 [HOST] [DSP1  ]      1.875678 s:  SYSTEM: SW Message Box Msg Pool, Free Msg Count = 1024 
 [HOST] [DSP1  ]      1.875739 s:  SYSTEM: Heap = LOCAL_L2             @ 0x00800000, Total size = 227264 B (221 KB), Free size = 227264 B (221)
 [HOST] [DSP1  ]      1.875770 s:  SYSTEM: Heap = LOCAL_DDR            @ 0x00000000, Total size = 524288 B (512 KB), Free size = 521776 B (509)
 [HOST] [DSP1  ]      1.875831 s:  SYSTEM: Initializing Links !!! 
 [HOST] [DSP1  ]      1.883334 s:  SYSTEM: Initializing Links ... DONE !!! 
 [HOST] [DSP1  ]      1.883395 s: 
 [HOST] [DSP1  ]  AlgorithmLink_SurroundView3d_initPlugin start Time: 1883
 [HOST] [DSP1  ]      1.883395 s: 
 [HOST] [DSP1  ] In AlgorithmLink_SurroundView3d_initPlugin !!!
 [HOST] [DSP1  ]      1.883425 s:  SYSTEM: System DSP Init Done !!!
 [HOST] [IPU2  ]      1.859330 s:  ***** IPU2 Firmware build time 18:09:37 Jul 28 2020
 [HOST] [IPU2  ]      1.859513 s:  *** SYSTEM: CPU Frequency <ORG = 212800000 Hz>, <NEW = 212800000 Hz>
 [HOST] [IPU2  ]      1.862349 s:  SYSTEM: System Common Init in progress !!!
 [HOST] [IPU2  ]      1.871469 s:  UTILS: CIO: Init Done !!!
 [HOST] [IPU2  ]      1.871561 s:  SYSTEM: IPC init in progress !!!
 [HOST] [IPU2  ]      1.871622 s:  SYSTEM: Attaching to [DSP1] ... 
 [HOST] [IPU2  ]      1.874306 s:  SYSTEM: Attaching to [DSP1] ... SUCCESS !!!
 [HOST] [IPU2  ]      1.874397 s:  SYSTEM: Notify register to [DSP1] line 0, event 15... 
 [HOST] [IPU2  ]      1.874550 s:  SYSTEM: Notify init done !!!
 [HOST] [IPU2  ]      1.876288 s:  SYSTEM: MsgQ init done !!!
 [HOST] [IPU2  ]      1.877203 s: RpmsgInit in progress...
 [HOST] [IPU2  ]      1.877295 s: Connection established with HLOS, calling rpmsgInit
 [HOST] [IPU2  ]      1.877966 s:  SYSTEM: IPC init DONE !!!
 [HOST] [IPU2  ]      1.883120 s:  SYSTEM: System Common Init Done !!!
 [HOST] [IPU2  ]      1.883212 s:  SYSTEM: System Init in progress !!!
 [HOST] [IPU2  ]      1.883273 s:  SYSTEM: BSP Common Init in progress !!!
 [HOST] [IPU2  ]      1.883334 s:  SYSTEM: BSP Common Init Done !!!
 [HOST] [IPU2  ]      1.883395 s:  SYSTEM: BSP Platform Init in progress !!!
 [HOST] [IPU2  ]      1.884615 s:  SYSTEM: BSP Platform Init Done !!!
 [HOST] [IPU2  ]      1.884676 s:  SYSTEM: FVID2 Init in progress !!!
 [HOST] [IPU2  ]      1.884828 s:  SYSTEM: FVID2 Init Done !!!
 [HOST] [IPU2  ]      1.884889 s:  SYSTEM: VPS Init in progress !!!
 [HOST] [IPU2  ]      1.884950 s:  SYSTEM: VPDMA Descriptor Memory Address translation ENABLED [0x80000000 -> 0x80000000]
 [HOST] [IPU2  ]      1.887756 s: *** VPDMA Firmware Loading... ***
 [HOST] [IPU2  ]      1.887878 s: VPDMA Firmware Address = 0xa035b5c0
 [HOST] [IPU2  ]      1.887970 s: VPDMA Load Address     = 0x4897d004
 [HOST] [IPU2  ]      1.888061 s: VPDMA Firmware Version = 0x4d0001b8
 [HOST] [IPU2  ]      1.888122 s: VPDMA List Busy Status = 0x00000000
 [HOST] [IPU2  ]      1.888214 s: *** VPDMA Firmware Load Success ***
 [HOST] [IPU2  ]      1.888519 s: *** VPDMA Firmware Loading... ***
 [HOST] [IPU2  ]      1.888610 s: VPDMA Firmware Address = 0xa035b5c0
 [HOST] [IPU2  ]      1.888702 s: VPDMA Load Address     = 0x4899d004
 [HOST] [IPU2  ]      1.888824 s: VPDMA Firmware Version = 0x4d0001b8
 [HOST] [IPU2  ]      1.888916 s: VPDMA List Busy Status = 0x00000000
 [HOST] [IPU2  ]      1.888977 s: *** VPDMA Firmware Load Success ***
 [HOST] [IPU2  ]      1.889282 s: *** VPDMA Firmware Loading... ***
 [HOST] [IPU2  ]      1.889373 s: VPDMA Firmware Address = 0xa035b5c0
 [HOST] [IPU2  ]      1.889465 s: VPDMA Load Address     = 0x489dd004
 [HOST] [IPU2  ]      1.889556 s: VPDMA Firmware Version = 0x4d0001b8
 [HOST] [IPU2  ]      1.889617 s: VPDMA List Busy Status = 0x00000000
 [HOST] [IPU2  ]      1.889739 s: *** VPDMA Firmware Load Success ***
 [HOST] [IPU2  ]      1.958152 s:  SYSTEM: VPS Init Done !!!
 [HOST] [IPU2  ]      1.959037 s:  UTILS: DMA: HWI Create for INT25 !!!
 [HOST] [IPU2  ]      1.959281 s:  SYSTEM: SW Message Box Msg Pool, Free Msg Count = 1024 
 [HOST] [IPU2  ]      1.959372 s:  SYSTEM: Heap = LOCAL_DDR            @ 0x00000000, Total size = 262144 B (256 KB), Free size = 259488 B (253)
 [HOST] [IPU2  ]      1.959525 s:  SYSTEM: Heap = SR_OCMC              @ 0x00000000, Total size = 0 B (0 KB), Free size = 0 B (0 KB)
 [HOST] [IPU2  ]      1.959647 s:  SYSTEM: Heap = SR_DDR_CACHED        @ 0x84203000, Total size = 264241152 B (252 MB), Free size = 264241152 )
 [HOST] [IPU2  ]      1.959891 s:  SYSTEM: Heap = SR_DDR_NON_CACHED    @ 0xa0100000, Total size = 1047040 B (0 MB), Free size = 1037824 B (0 M)
 [HOST] [IPU2  ]      1.960043 s:  SYSTEM: Initializing Links !!! 
 [HOST] [HOST  ]      2.259501 s:  SYSTEM: IPC: Init in progress !!!
 [HOST] [HOST  ]      2.259531 s:  SYSTEM: IPC: Notify init in progress !!!
 [HOST] [HOST  ]      2.259653 s:  SYSTEM: IPC: [IPU2] socket bind success !!! (dst vproc = 1, endpt = 81)
 [HOST] [HOST  ]      2.259684 s:  SYSTEM: IPC: [IPU2] socket info (family = 41, dst proc id = -1, endpt = 81) !!!
 [HOST] [HOST  ]      2.259684 s:  SYSTEM: IPC: [IPU2] socket connect success !!! (dst vproc = 1, endpt = 80)
 [HOST] [HOST  ]      2.259714 s:  SYSTEM: IPC: [IPU2] socket info (family = 41, dst proc id = -1, endpt = 1024) !!!
 [HOST] [HOST  ]      2.259806 s:  SYSTEM: IPC: [DSP1] socket bind success !!! (dst vproc = 2, endpt = 81)
 [HOST] [HOST  ]      2.259806 s:  SYSTEM: IPC: [DSP1] socket info (family = 41, dst proc id = -1, endpt = 81) !!!
 [HOST] [HOST  ]      2.259806 s:  SYSTEM: IPC: [DSP1] socket connect success !!! (dst vproc = 2, endpt = 80)
 [HOST] [HOST  ]      2.259836 s:  SYSTEM: IPC: [DSP1] socket info (family = 41, dst proc id = -1, endpt = 1024) !!!
 [HOST] [HOST  ]      2.259897 s:  SYSTEM: IPC: Notify init DONE !!!
 [HOST] [HOST  ]      2.259897 s:  SYSTEM: MSGQ: MsgQ init in progress !!!
 [HOST] [HOST  ]      2.259958 s:  SYSTEM: MSGQ: MsgQ init DONE !!!
 [HOST] [HOST  ]      2.259958 s:  SYSTEM: IPC: Init DONE !!!
 [HOST] [HOST  ]      2.259958 s:  SYSTEM: Initializing A15 Links !!! 
 [HOST] [HOST  ]      2.260019 s:  IPC_OUT_0 : Init done - linkid-0x2000000
 [HOST] [HOST  ]      2.260080 s:  IPC_OUT_1 : Init done - linkid-0x2000001
 [HOST] [HOST  ]      2.260141 s:  IPC_OUT_2 : Init done - linkid-0x2000002
 [HOST] [HOST  ]      2.260172 s:  IPC_OUT_3 : Init done - linkid-0x2000003
 [HOST] [HOST  ]      2.260233 s:  IPC_OUT_4 : Init done - linkid-0x2000004
 [HOST] [HOST  ]      2.260294 s:  IPC_IN_0 : Init done - linkid-0x200000a
 [HOST] [HOST  ]      2.260355 s:  IPC_IN_1 : Init done - linkid-0x200000b
 [HOST] [HOST  ]      2.260416 s:  IPC_IN_2 : Init done - linkid-0x200000c
 [HOST] [HOST  ]      2.260477 s:  IPC_IN_3 : Init done - linkid-0x200000d
 [HOST] [HOST  ]      2.260507 s:  IPC_IN_4 : Init done - linkid-0x200000e
 [HOST] [HOST  ]      2.260568 s:  IPC_IN_5 : Init done - linkid-0x200000f
 [HOST] [HOST  ]      2.260629 s:  IPC_IN_6 : Init done - linkid-0x2000010
 [HOST] [HOST  ]      2.260690 s:  NULL_0 : Init done - linkid-0x2000014
 [HOST] [HOST  ]      2.260721 s:  NULL_1 : Init done - linkid-0x2000015
 [HOST] [HOST  ]      2.260812 s:  NULL_SRC_0 : Init done - linkid-0x2000039
 [HOST] [HOST  ]      2.260904 s:  DISP_DIST_SRC_0 : Init done - linkid-0x2000062
 [HOST] [HOST  ]      2.260965 s:  DISP_DIST_SRC_1 : Init done - linkid-0x2000063
 [HOST] [HOST  ]      2.260995 s:  DISP_DIST_SRC_2 : Init done - linkid-0x2000064
 [HOST] [HOST  ]      2.261056 s:  DISP_DIST_SRC_3 : Init done - linkid-0x2000065
 [HOST] [HOST  ]      2.261117 s:  ALGORITHM_0 : Init done - linkid-0x2000031
 [HOST] [HOST  ]      2.261178 s:  ALGORITHM_1 : Init done - linkid-0x2000032
 [HOST] [HOST  ]      2.261239 s:  ALGORITHM_2 : Init done - linkid-0x2000033
 [HOST] [HOST  ]      2.261270 s:  ALGORITHM_3 : Init done - linkid-0x2000034
 [HOST] [HOST  ]      2.261331 s:  ALGORITHM_4 : Init done - linkid-0x2000035
 [HOST] [HOST  ]      2.261392 s:  ALGORITHM_5 : Init done - linkid-0x2000036
 [HOST] [HOST  ]      2.261453 s:  ALGORITHM_6 : Init done - linkid-0x2000037
 [HOST] [HOST  ]      2.261514 s:  ALGORITHM_7 : Init done - linkid-0x2000038
 [HOST] [HOST  ]      2.261605 s:  DUP0 : Init done - linkid-0x0
 [HOST] [HOST  ]      2.261697 s:  DUP1 : Init done - linkid-0x0
 [HOST] [HOST  ]      2.261788 s:  DUP2 : Init done - linkid-0x0
 [HOST] [HOST  ]      2.261880 s:  DUP3 : Init done - linkid-0x0
 [HOST] [HOST  ]      2.261941 s:  DUP4 : Init done - linkid-0x0
 [HOST] [HOST  ]      2.262002 s:  SYNC_0 : Init done - linkid-0x0
 [HOST] [HOST  ]      2.262063 s:  SYNC_1 : Init done - linkid-0x0
 [HOST] [HOST  ]      2.262124 s:  SYNC_2 : Init done - linkid-0x0
 [HOST] [HOST  ]      2.262185 s:  SYNC_3 : Init done - linkid-0x0
 [HOST] [HOST  ]      2.262246 s:  MERGE_0 : Init done - linkid-0x0
 [HOST] [HOST  ]      2.262307 s:  MERGE_1 : Init done - linkid-0x0
 [HOST] [HOST  ]      2.262368 s:  MERGE_2 : Init done - linkid-0x0
 [HOST] [HOST  ]      2.262429 s:  MERGE_3 : Init done - linkid-0x0
 [HOST] [HOST  ]      2.262490 s:  MERGE_4 : Init done - linkid-0x0
 [HOST] [HOST  ]      2.262551 s:  SELECT_0 : Init done - linkid-0x0
 [HOST] [HOST  ]      2.262612 s:  SELECT_1 : Init done - linkid-0x0
 [HOST] [HOST  ]      2.262673 s:  GATE_0 : Init done - linkid-0x0
 [HOST] [HOST  ]      2.262703 s:  GATE_1 : Init done - linkid-0x0
 [HOST] [HOST  ]      2.262764 s:  GATE_2 : Init done - linkid-0x0
 [HOST] [HOST  ]      2.262795 s:  GATE_3 : Init done - linkid-0x0
 [HOST] [HOST  ]      2.262856 s:  SGXFRMCPY_0 : Init done - linkid-0x200004a
 [HOST] [HOST  ]      2.262856 s:  SYSTEM: Initializing A15 Links ... DONE !!! 
 [HOST] [DSP1  ]      2.259165 s:  SYSTEM: CACHE: L1P = 32 KB, L1D = 32 KB, L2 = 32 KB ... after boot !!!
 [HOST] [DSP1  ]      2.259195 s:  SYSTEM: CACHE: L1P = 32 KB, L1D = 32 KB, L2 = 32 KB ... after update by APP !!!
 [HOST] [IPU2  ]      2.256054 s:  SYSTEM: Initializing Links ... DONE !!! 
 [HOST] [IPU2  ]      2.259165 s:  CHAINS: Application Started !!!
 [HOST] [IPU2  ]      2.272097 s:  BOARD: Board Init in progress !!!
 [HOST] [IPU2  ]      2.272585 s:  BOARD: Board Init Done !!!
 [HOST] [IPU2  ]      2.282315 s:  
 [HOST] [HOST  ]      2.286463 s:  OSA: Adjusting global time, timeH by 0 units!!!
 [HOST] [HOST  ]      2.286463 s:  SYSTEM: System A15 Init Done !!!
 [HOST] [HOST  ]      2.286555 s:  NETWORK_CTRL: Starting Server (port=5000) !!!
 [HOST] [HOST  ]      2.286585 s:  NETWORK_CTRL: Starting Server ... DONE (port=5000) !!!
 [HOST] [HOST  ]      2.804215 s: main - before while
 [HOST] [HOST  ]      2.804215 s: 
 [HOST] [HOST  ]  
 [HOST] [HOST  ]  ====================
 [HOST] [HOST  ]  Vision SDK Usecases
 [HOST] [HOST  ]  ====================
 [HOST] [HOST  ]  s: 3DSVS
 [HOST] [HOST  ]  o: Offline
 [HOST] [HOST  ]  p: CPU Status
 [HOST] [HOST  ]  
 [HOST] [HOST  ]  t: System Settings 
 [HOST] [HOST  ]  
 [HOST] [HOST  ]  x: Exit 
 [HOST] [HOST  ]  z: Exit - AND SHUTDOWN Remote CPUs
 [HOST] [HOST  ]  
 [HOST] [HOST  ]  Enter Choice: 
 [HOST] [HOST  ]  
 [HOST] [HOST  ]      2.804215 s: 
 [HOST] [HOST  ] In SVS_3D_Fisheye_Main
 [HOST] [HOST  ]      2.824620 s:  IPC_OUT_0   : Create in progress !!!
 [HOST] [HOST  ]      2.824650 s:  IPC_OUT_0   : Create Done !!!
 [HOST] [IPU2  ]      2.824803 s:  IPC_IN_0   : Create in progress !!!
 [HOST] [IPU2  ]      2.825962 s:  IPC_IN_0   : Create Done !!!
 [HOST] [IPU2  ]      2.826297 s:  DECODE: Create in progress ... !!!
 [HOST] [IPU2  ]      3.034008 s:  DECODE: Creating CH0 of 1280 x 720 [1] [0],target bitrate = 10000 Kbps ... 
 [HOST] [IPU2  ]      3.239339 s:  DECODE: Creating CH1 of 1280 x 720 [1] [0],target bitrate = 10000 Kbps ... 
 [HOST] [IPU2  ]      3.444885 s:  DECODE: Creating CH2 of 1280 x 720 [1] [0],target bitrate = 10000 Kbps ... 
 [HOST] [IPU2  ]      3.650338 s:  DECODE: Creating CH3 of 1280 x 720 [1] [0],target bitrate = 10000 Kbps ... 
 [HOST] [IPU2  ]      3.651223 s:  DECODE: All CH Create ... DONE !!!
 [HOST] [IPU2  ]      3.653236 s:  DECODE: Create ... DONE !!!
 [HOST] [IPU2  ]      3.653632 s:  VPE: Create in progress !!!
 [HOST] [IPU2  ]      4.007655 s:  VPE: Loading Down-scaling Co-effs
 [HOST] [IPU2  ]      4.007960 s:  VPE: Co-effs Loading ... DONE !!!
 [HOST] [IPU2  ]      4.008143 s:  VPE: Create Done !!!
 [HOST] [DSP1  ]      4.009577 s:  IPC_IN_0   : Create in progress !!!
 [HOST] [DSP1  ]      4.010034 s:  IPC_IN_0   : Create Done !!!
 [HOST] [DSP1  ]      4.010187 s:  ALGORITHM: Create in progress (algId = 42) !!!
 [HOST] [DSP1  ]      4.010248 s: AlgorithmLink_surroundView3dCreate start Time: 4010
 [HOST] [DSP1  ]      4.010339 s:  ALG_SURROUNDVIEW3D: DMA: Allocated CH (TCC) = 0 (0)
 [HOST] [IPU2  ]      4.008997 s:  IPC_OUT_0   : Create in progress !!!
 [HOST] [IPU2  ]      4.009333 s:  IPC_OUT_0   : Create Done !!!
 [HOST] [DSP1  ]      4.136491 s: *** After SVS memory allocation ***
 [HOST] [HOST  ]      4.146190 s:  IPC_IN_0   : Create in progress !!!
 [HOST] [HOST  ]      4.146434 s:  IPC_IN_0   : Create Done !!!
 [HOST] [HOST  ]      4.146464 s: SgxFrmcpyLink_drvCreate start time: 4146 
 [HOST] [HOST  ]      4.146464 s:  SGXFRMCPY: Create in progress for resolution: 1280x720 !!!
 [HOST] [HOST  ]      4.146525 s:  SYSTEM_PVRSCOPE: FIFO /opt/vision_sdk/gpufifo opened successfully 
 [HOST] [DSP1  ]      4.145824 s:  AlgorithmLink_SurroundView3d Create Done  !!!!!
 [HOST] [DSP1  ]      4.145854 s: AlgorithmLink_surroundView3dCreate end Time: 135
 [HOST] [DSP1  ]      4.145885 s:  ALGORITHM: Create Done (algId = 42) !!!
 [HOST] [DSP1  ]      4.146037 s:  IPC_OUT_0   : Create in progress !!!
 [HOST] [DSP1  ]      4.146098 s:  IPC_OUT_0   : Create Done !!!
 [HOST] [HOST  ]      4.156835 s:  EGL: version 1.4
 [HOST] [HOST  ]      4.164338 s:  EGL: GL Version = (null)
 [HOST] [HOST  ]      4.164368 s:  EGL: GL Vendor = (null)
 [HOST] [HOST  ]      4.164368 s:  EGL: GL Renderer = (null)
 [HOST] [HOST  ]      4.164368 s:  EGL: GL Extensions = (null)
 [HOST] [HOST  ]      4.241414 s:  SGXFRMCPY: Create Done for resolution: 1280x720 !!!
 [HOST] [HOST  ]      4.241414 s: SgxFrmcpyLink_drvCreate end time: 4241 
 [HOST] [HOST  ]      4.241475 s:  IPC_OUT_1   : Create in progress !!!
 [HOST] [HOST  ]      4.241505 s:  IPC_OUT_1   : Create Done !!!
 [HOST] [IPU2  ]      4.241658 s:  IPC_IN_1   : Create in progress !!!
 [HOST] [IPU2  ]      4.242847 s:  IPC_IN_1   : Create Done !!!
 [HOST] [IPU2  ]      4.243213 s: 
 [HOST] [IPU2  ]  In DisplayLink_drvCreate Time: 4243
 [HOST] [IPU2  ]      4.243274 s:  DISPLAY: Create in progress !!!
 [HOST] [IPU2  ]      4.243823 s:  DISPLAY: Create Done !!!
 [HOST] [HOST  ]      4.346428 s:  SGXFRMCPY: Start in progress !!!
 [HOST] [HOST  ]      4.346428 s:  SGXFRMCPY: Start Done !!!
 [HOST] [DSP1  ]      4.344506 s:  SYSTEM: SW Message Box Msg Pool, Free Msg Count = 1023 
 [HOST] [DSP1  ]      4.344567 s:  SYSTEM: Heap = LOCAL_L2             @ 0x00800000, Total size = 227264 B (221 KB), Free size = 227264 B (221)
 [HOST] [DSP1  ]      4.344598 s:  SYSTEM: Heap = LOCAL_DDR            @ 0x00000000, Total size = 524288 B (512 KB), Free size = 506560 B (494)
 [HOST] [IPU2  ]      4.344872 s:  SYSTEM: SW Message Box Msg Pool, Free Msg Count = 1023 
 [HOST] [IPU2  ]      4.344994 s:  SYSTEM: Heap = LOCAL_DDR            @ 0x00000000, Total size = 262144 B (256 KB), Free size = 251704 B (245)
 [HOST] [IPU2  ]      4.345147 s:  SYSTEM: Heap = SR_OCMC              @ 0x00000000, Total size = 0 B (0 KB), Free size = 0 B (0 KB)
 [HOST] [IPU2  ]      4.345330 s:  SYSTEM: Heap = SR_DDR_CACHED        @ 0x84203000, Total size = 264241152 B (252 MB), Free size = 77995008 B)
)        IPU2  ]      4.345513 s:  SYSTEM: Heap = SR_DDR_NON_CACHED    @ 0xa0100000, Total size = 1047040 B (0 MB), Free size = 1037824 B (0 M 
 [HOST] [IPU2  ]      4.345909 s: 
 [HOST] [IPU2  ]  In DisplayLink_drvStart Time: 4345
 [HOST] [IPU2  ]      4.346001 s:  DISPLAY: Start in progress !!!
 [HOST] [IPU2  ]      4.346062 s:  DISPLAY: Start Done !!!
 [HOST] [DSP1  ]      4.507838 s: AlgorithmLink_surroundView3dProcess (1 th frame) start Time: 4507
 [HOST] [DSP1  ]      4.976849 s: *********** SVS3D init Done !!!!
 [HOST] [HOST  ]      5.012993 s: 
 [HOST] [HOST  ]  Start of SgxFrmcpyLink_drvDoProcessFrames (1 th frame) Time: 5012
 [HOST] [DSP1  ]      5.012535 s: AlgorithmLink_surroundView3dProcess (1 th frame) end Time: 505
 [HOST] [DSP1  ]      5.012779 s: AlgorithmLink_surroundView3dProcess (2 th frame) start Time: 5012
 [HOST] [HOST  ]      5.037485 s: 
 [HOST] [HOST  ]  End of SgxFrmcpyLink_drvDoProcessFrames (1 th frame) Time: 5037
 [HOST] [IPU2  ]      5.042426 s: 
 [HOST] [IPU2  ]  End of DisplayLink_drvProcessData Time: 5042
 [HOST] [HOST  ]      5.085524 s: 
 [HOST] [HOST  ]  Start of SgxFrmcpyLink_drvDoProcessFrames (2 th frame) Time: 5085
 [HOST] [DSP1  ]      5.085036 s: AlgorithmLink_surroundView3dProcess (2 th frame) end Time: 73
 [HOST] [HOST  ]      5.096870 s: 
 [HOST] [HOST  ]  End of SgxFrmcpyLink_drvDoProcessFrames (2 th frame) Time: 5096
 [HOST] [IPU2  ]      5.095986 s: 
 [HOST] [IPU2  ]  End of DisplayLink_drvProcessData Time: 5095
 [HOST] [IPU2  ]      5.101598 s: 
 [HOST] [IPU2  ]  End of DisplayLink_drvProcessData Time: 5101
 [HOST] [DSP1  ]      5.148569 s: AlgorithmLink_surroundView3dProcess (3 th frame) start Time: 5148
 [HOST] [DSP1  ]      5.185139 s: AlgorithmLink_surroundView3dProcess (4 th frame) start Time: 5185
 [HOST] [IPU2  ]      5.193100 s: 
 [HOST] [IPU2  ]  End of DisplayLink_drvProcessData Time: 5193
 [HOST] [IPU2  ]      5.230372 s: 
 [HOST] [IPU2  ]  End of DisplayLink_drvProcessData Time: 5230
 [HOST] [DSP1  ]      5.276703 s: AlgorithmLink_surroundView3dProcess (5 th frame) start Time: 5276
 [HOST] [DSP1  ]      5.313273 s: AlgorithmLink_surroundView3dProcess (6 th frame) start Time: 5313
 [HOST] [IPU2  ]      5.321204 s: 
 [HOST] [IPU2  ]  End of DisplayLink_drvProcessData Time: 5321
 [HOST] [IPU2  ]      5.358384 s: 
 [HOST] [IPU2  ]  End of DisplayLink_drvProcessData Time: 5358
 [HOST] [DSP1  ]      5.404532 s: AlgorithmLink_surroundView3dProcess (7 th frame) start Time: 5404
 [HOST] [DSP1  ]      5.441102 s: AlgorithmLink_surroundView3dProcess (8 th frame) start Time: 5441
 [HOST] [IPU2  ]      5.449002 s: 
 [HOST] [IPU2  ]  End of DisplayLink_drvProcessData Time: 5448
 [HOST] [IPU2  ]      5.486244 s: 
 [HOST] [IPU2  ]  End of DisplayLink_drvProcessData Time: 5486
 [HOST] [DSP1  ]      5.532666 s: AlgorithmLink_surroundView3dProcess (9 th frame) start Time: 5532
 [HOST] [DSP1  ]      5.569236 s: AlgorithmLink_surroundView3dProcess (10 th frame) start Time: 5569
 [HOST] [IPU2  ]      5.577167 s: 
 [HOST] [IPU2  ]  End of DisplayLink_drvProcessData Time: 5577
 [HOST] [IPU2  ]      5.614347 s: 
 [HOST] [IPU2  ]  End of DisplayLink_drvProcessData Time: 5614
 [HOST] [DSP1  ]      5.660586 s: AlgorithmLink_surroundView3dProcess (11 th frame) start Time: 5660
 [HOST] [DSP1  ]      5.697126 s: AlgorithmLink_surroundView3dProcess (12 th frame) start Time: 5697
 [HOST] [IPU2  ]      5.704996 s: 
 [HOST] [IPU2  ]  End of DisplayLink_drvProcessData Time: 5704
 [HOST] [IPU2  ]      5.742237 s: 
 [HOST] [IPU2  ]  End of DisplayLink_drvProcessData Time: 5742
 [HOST] [DSP1  ]      5.788568 s: AlgorithmLink_surroundView3dProcess (13 th frame) start Time: 5788
 [HOST] [DSP1  ]      5.825138 s: AlgorithmLink_surroundView3dProcess (14 th frame) start Time: 5825
 [HOST] [IPU2  ]      5.833038 s: 
 [HOST] [IPU2  ]  End of DisplayLink_drvProcessData Time: 5833
 [HOST] [IPU2  ]      5.870218 s: 
 [HOST] [IPU2  ]  End of DisplayLink_drvProcessData Time: 5870
 [HOST] [DSP1  ]      5.916702 s: AlgorithmLink_surroundView3dProcess (15 th frame) start Time: 5916
 [HOST] [DSP1  ]      5.953303 s: AlgorithmLink_surroundView3dProcess (16 th frame) start Time: 5953
 [HOST] [IPU2  ]      5.961233 s: 
 [HOST] [IPU2  ]  End of DisplayLink_drvProcessData Time: 5961
 [HOST] [IPU2  ]      5.998322 s: 
 [HOST] [IPU2  ]  End of DisplayLink_drvProcessData Time: 5998
 [HOST] [DSP1  ]      6.044653 s: AlgorithmLink_surroundView3dProcess (17 th frame) start Time: 6044
 [HOST] [DSP1  ]      6.081162 s: AlgorithmLink_surroundView3dProcess (18 th frame) start Time: 6081
 [HOST] [IPU2  ]      6.089062 s: 
 [HOST] [IPU2  ]  End of DisplayLink_drvProcessData Time: 6089
 [HOST] [IPU2  ]      6.126303 s: 
 [HOST] [IPU2  ]  End of DisplayLink_drvProcessData Time: 6126
 [HOST] [DSP1  ]      6.172543 s: AlgorithmLink_surroundView3dProcess (19 th frame) start Time: 6172
 [HOST] [DSP1  ]      6.209113 s: AlgorithmLink_surroundView3dProcess (20 th frame) start Time: 6209
 [HOST] [IPU2  ]      6.217013 s: 
 [HOST] [IPU2  ]  End of DisplayLink_drvProcessData Time: 6217
 [HOST] [IPU2  ]      6.254224 s: 
 [HOST] [IPU2  ]  End of DisplayLink_drvProcessData Time: 6254
 [HOST] [DSP1  ]      6.300585 s: AlgorithmLink_surroundView3dProcess (21 th frame) start Time: 6300
 [HOST] [DSP1  ]      6.337156 s: AlgorithmLink_surroundView3dProcess (22 th frame) start Time: 6337
 [HOST] [IPU2  ]      6.345086 s: 
 [HOST] [IPU2  ]  End of DisplayLink_drvProcessData Time: 6345

2255ms.txt
GrabSerial

[0.000001 0.000001] �
[0.171856 0.171855] U-Boot SPL 2016.05 (Jul 17 2020 - 18:44:02)
[0.173470 0.001614] DRA762-GP ES1.0
[1.177316 1.003846] [    0.000000] Reserved memory: OVERLAP DETECTED!
[1.179412 0.002096] [    0.000000] vsdk_sr1_mem@84000000 (0x0000000084000000--0x0000000097000000) overlaps with cmem@95400000 (0x0000000095400000--0x0000000095800000)
[1.183727 0.004315] [    0.000000] Reserved memory: regions without no-map are not yet supported
[1.185788 0.002061] [    0.000000] Reserved memory: regions without no-map are not yet supported
[1.187428 0.001640] [    0.000000] Reserved memory: regions without no-map are not yet supported
[1.188980 0.001552] [    0.002002] /cpus/cpu@0 missing clock-frequency property
[1.190625 0.001645] [    0.002018] /cpus/cpu@1 missing clock-frequency property
[1.222158 0.031533] [    0.610716] omap_hsmmc 480b4000.mmc: no pinctrl state for sdr25 mode
[1.224333 0.002175] [    0.617157] omap_hsmmc 480b4000.mmc: no pinctrl state for sdr12 mode
[1.278152 0.053819] [    0.671974] omap_voltage_late_init: Voltage driver support not added
[1.805994 0.527842] [    1.219083] Kernel Entry time 8180 ticks
[2.237634 0.431640] Loaded PowerVR consumer services.
[2.259496 0.021862] insmod: ERROR: could not load module ./bin/cmemk.ko: No such file or directory
[2.263030 0.003534] CUSTOM Application launched at 2255
[2.676640 0.413610] [    2.064697] omap_l3_noc 44000000.ocp: L3 application error: target 3 mod:2 (unclearable)
[2.681496 0.004856] [    2.072838] omap_l3_noc 44000000.ocp: L3 debug error: target 3 mod:2 (unclearable)
[4.526177 1.844681] loaded module : gbm_pvr.so
[4.527212 0.001035] found valid GBM backend : gbm_pvr.so


Dmesg

[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Initializing cgroup subsys cpuset
[    0.000000] Initializing cgroup subsys cpu
[    0.000000] Initializing cgroup subsys cpuacct
[    0.000000] Linux version 4.4.84+ (ubantu@teltvml0203x) (gcc version 5.3.1 20160113 (Linaro GCC 5.3-2016.02) ) #11 SMP PREEMPT Wed Aug 5 120
[    0.000000] CPU: ARMv7 Processor [412fc0f2] revision 2 (ARMv7), cr=30c5387d
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache
[    0.000000] Machine model: TI DRA762 EVM
[    0.000000] Reserved memory: OVERLAP DETECTED!
[    0.000000] vsdk_sr1_mem@84000000 (0x0000000084000000--0x0000000097000000) overlaps with cmem@95400000 (0x0000000095400000--0x0000000095800)
[    0.000000] Reserved memory: created DMA memory pool at 0x0000000040300000, size 3 MiB
[    0.000000] Reserved memory: initialized node cmem@40300000, compatible id shared-dma-pool
[    0.000000] Reserved memory: regions without no-map are not yet supported
[    0.000000] Reserved memory: created CMA memory pool at 0x0000000099000000, size 80 MiB
[    0.000000] Reserved memory: initialized node ipu2_cma@95800000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created CMA memory pool at 0x000000009e000000, size 32 MiB
[    0.000000] Reserved memory: initialized node ipu1_cma@9d000000, compatible id shared-dma-pool
[    0.000000] Reserved memory: regions without no-map are not yet supported
[    0.000000] Reserved memory: created CMA memory pool at 0x00000000a1000000, size 32 MiB
[    0.000000] Reserved memory: initialized node dsp1_cma@99000000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created CMA memory pool at 0x00000000a3000000, size 32 MiB
[    0.000000] Reserved memory: initialized node dsp2_cma@9f000000, compatible id shared-dma-pool
[    0.000000] Reserved memory: regions without no-map are not yet supported
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a9000000, size 32 MiB
[    0.000000] Reserved memory: initialized node cmem@A9000000, compatible id shared-dma-pool
[    0.000000] cma: Reserved 64 MiB at 0x00000000dbc00000
[    0.000000] Forcing write-allocate cache policy for SMP
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] OMAP4: Map 0x00000000dfd00000 to fe600000 for dram barrier
[    0.000000] On node 0 totalpages: 382976
[    0.000000] free_area_init_node: node 0, pgdat c0584640, node_mem_map ef21a000
[    0.000000]   DMA zone: 1728 pages used for memmap
[    0.000000]   DMA zone: 0 pages reserved
[    0.000000]   DMA zone: 187392 pages, LIFO batch:31
[    0.000000]   HighMem zone: 195584 pages, LIFO batch:31
[    0.000000] DRA762 ES1.0
[    0.000000] PERCPU: Embedded 11 pages/cpu @ef1a6000 s13376 r8192 d23488 u45056
[    0.000000] pcpu-alloc: s13376 r8192 d23488 u45056 alloc=11*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 381248
[    0.000000] Kernel command line: elevator=noop console=ttyS0,115200n8 cma=64M omapdrm.num_crtc=1 consoleblank=0 snd.slots_reserved=1,1 fixrt
[    0.000000] PID hash table entries: 4096 (order: 2, 16384 bytes)
[    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
[    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
[    0.000000] Memory: 875856K/1531904K available (4322K kernel code, 216K rwdata, 804K rodata, 280K init, 256K bss, 410288K reserved, 245760K)
[    0.000000] Virtual kernel memory layout:
[    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
[    0.000000]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
[    0.000000]     vmalloc : 0xf0800000 - 0xff800000   ( 240 MB)
[    0.000000]     lowmem  : 0xc0000000 - 0xf0000000   ( 768 MB)
[    0.000000]     pkmap   : 0xbfe00000 - 0xc0000000   (   2 MB)
[    0.000000]     modules : 0xbf000000 - 0xbfe00000   (  14 MB)
[    0.000000]       .text : 0xc0008000 - 0xc0509f54   (5128 kB)
[    0.000000]       .init : 0xc050a000 - 0xc0550000   ( 280 kB)
[    0.000000]       .data : 0xc0550000 - 0xc0586368   ( 217 kB)
[    0.000000]        .bss : 0xc0588000 - 0xc05c83f4   ( 257 kB)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[    0.000000] Preemptible hierarchical RCU implementation.
[    0.000000]  Build-time adjustment of leaf fanout to 32.
[    0.000000] NR_IRQS:16 nr_irqs:16 16
[    0.000000] ti_dt_clocks_register: failed to lookup clock node gmac_gmii_ref_clk_div
[    0.000000] OMAP clockevent source: timer1 at 32786 Hz
[    0.000000] Architected cp15 timer(s) running at 6.14MHz (phys).
[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x16af5adb9, max_idle_ns: 440795202250 ns
[    0.000005] sched_clock: 56 bits at 6MHz, resolution 162ns, wraps every 4398046511023ns
[    0.000016] Switching to timer-based delay loop, resolution 162ns
[    0.000406] clocksource: 32k_counter: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 58327039986419 ns
[    0.000414] OMAP clocksource: 32k_counter at 32768 Hz
[    0.000956] Console: colour dummy device 80x30
[    0.000979] Calibrating delay loop (skipped), value calculated using timer frequency.. 12.29 BogoMIPS (lpj=61475)
[    0.000993] pid_max: default: 4096 minimum: 301
[    0.001089] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
[    0.001100] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
[    0.001701] Initializing cgroup subsys io
[    0.001717] Initializing cgroup subsys memory
[    0.001742] Initializing cgroup subsys devices
[    0.001754] Initializing cgroup subsys freezer
[    0.001764] Initializing cgroup subsys perf_event
[    0.001776] Initializing cgroup subsys pids
[    0.001803] CPU: Testing write buffer coherency: ok
[    0.002002] /cpus/cpu@0 missing clock-frequency property
[    0.002018] /cpus/cpu@1 missing clock-frequency property
[    0.002028] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[    0.002045] Setting up static identity map for 0x80008380 - 0x800083e0
[    0.079998] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[    0.080061] Brought up 2 CPUs
[    0.080074] SMP: Total of 2 processors activated (24.59 BogoMIPS).
[    0.080081] CPU: All CPU(s) started in HYP mode.
[    0.080087] CPU: Virtualization extensions available.
[    0.080487] devtmpfs: initialized
[    0.112661] VFP support v0.3: implementor 41 architecture 4 part 30 variant f rev 0
[    0.113328] omap_hwmod: l3_main_2 using broken dt data from ocp
[    0.125739] omap_hwmod: dcan1: _wait_target_disable failed
[    0.223331] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.223347] futex hash table entries: 16 (order: -2, 1024 bytes)
[    0.229006] pinctrl core: initialized pinctrl subsystem
[    0.229664] NET: Registered protocol family 16
[    0.230711] DMA: preallocated 256 KiB pool for atomic coherent allocations
[    0.260116] cpuidle: using governor ladder
[    0.290143] cpuidle: using governor menu
[    0.298693] OMAP GPIO hardware version 0.1
[    0.302864] GPIO line 161 (radio_rst) hogged as output/low
[    0.305308] irq: no irq domain found for /ocp/l4@4a000000/scm@2000/pinmux@1400 !
[    0.327334] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[    0.327344] hw-breakpoint: maximum watchpoint size is 8 bytes.
[    0.327784] omap4_sram_init:Unable to allocate sram needed to handle errata I688
[    0.327793] omap4_sram_init:Unable to get sram pool needed to handle errata I688
[    0.328053] OMAP DMA hardware revision 0.0
[    0.372085] omap-dma-engine 4a056000.dma-controller: OMAP DMA engine driver (LinkedList1/2/3 supported)
[    0.373258] edma 43300000.edma: memcpy is disabled
[    0.378422] edma 43300000.edma: TI EDMA DMA engine driver
[    0.382696] omap-iommu 40d01000.mmu: 40d01000.mmu registered
[    0.382839] omap-iommu 40d02000.mmu: 40d02000.mmu registered
[    0.382969] omap-iommu 58882000.mmu: 58882000.mmu registered
[    0.383099] omap-iommu 55082000.mmu: 55082000.mmu registered
[    0.383335] omap-iommu 41501000.mmu: 41501000.mmu registered
[    0.383484] omap-iommu 41502000.mmu: 41502000.mmu registered
[    0.386004] palmas 0-0058: IRQ missing: skipping irq request
[    0.400654] palmas 0-0058: Muxing GPIO 51, PWM 0, LED 2
[    0.451076] irq: no irq domain found for /ocp/i2c@48070000/tps65917@58 !
[    0.451800] omap_i2c 48070000.i2c: bus 0 rev0.12 at 400 kHz
[    0.452205] omap_i2c 48060000.i2c: bus 2 rev0.12 at 400 kHz
[    0.452679] omap_i2c 4807a000.i2c: bus 3 rev0.12 at 400 kHz
[    0.452905] EDAC MC: Ver: 3.0.0
[    0.453632] omap-mailbox 48840000.mailbox: omap mailbox rev 0x400
[    0.453931] omap-mailbox 48842000.mailbox: omap mailbox rev 0x400
[    0.454931] clocksource: Switched to clocksource arch_sys_counter
[    0.465364] NET: Registered protocol family 2
[    0.465906] TCP established hash table entries: 8192 (order: 3, 32768 bytes)
[    0.465970] TCP bind hash table entries: 8192 (order: 4, 65536 bytes)
[    0.466095] TCP: Hash tables configured (established 8192 bind 8192)
[    0.466143] UDP hash table entries: 512 (order: 2, 16384 bytes)
[    0.466175] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
[    0.466307] NET: Registered protocol family 1
[    0.467211] hw perfevents: enabled with armv7_cortex_a15 PMU driver, 7 counters available
[    0.477239] ntfs: driver 2.1.32 [Flags: R/O].
[    0.478323] bounce: pool size: 64 pages
[    0.478478] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250)
[    0.478489] io scheduler noop registered (default)
[    0.478497] io scheduler deadline registered
[    0.478526] io scheduler cfq registered
[    0.483070] pinctrl-single 4a003400.pinmux: 282 pins at pa fc003400 size 1128
[    0.544834] Serial: 8250/16550 driver, 10 ports, IRQ sharing disabled
[    0.548303] console [ttyS0] disabled
[    0.548356] 4806a000.serial: ttyS0 at MMIO 0x4806a000 (irq = 301, base_baud = 3000000) is a 8250
[    0.597289] console [ttyS0] enabled
[    0.598205] 4806c000.serial: ttyS1 at MMIO 0x4806c000 (irq = 302, base_baud = 3000000) is a 8250
[    0.599071] 48020000.serial: ttyS2 at MMIO 0x48020000 (irq = 303, base_baud = 3000000) is a 8250
[    0.600403] [drm] Initialized drm 1.1.0 20060810
[    0.601152] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
[    0.601161] [drm] No driver support for vblank timestamp query.
[    0.601353] [drm] Initialized vdrm 1.0.0 20110917 on minor 0
[    0.605031] vmemexp device MAJOR num = 248
[    0.605054] vmemexp class registered
[    0.605189] /dev/vmemexp device registered
[    0.605197] ioctl DBUFIOC_EXPORT_VIRTMEM = -1072899120
[    0.606333] i2c /dev entries driver
[    0.610716] omap_hsmmc 480b4000.mmc: no pinctrl state for sdr25 mode
[    0.617157] omap_hsmmc 480b4000.mmc: no pinctrl state for sdr12 mode
[    0.623809] vsys_3v3: supplied by vsys_12v0
[    0.623830] smps5: supplied by vsys_3v3
[    0.624014] vio_1v8: supplied by smps5
[    0.667074] hwspinlock_user gatemp: requested 20 hwspinlocks
[    0.667486] omap-rproc 58820000.ipu: assigned reserved memory node ipu1_cma@9d000000
[    0.667538]  remoteproc0: 58820000.ipu is available
[    0.667546]  remoteproc0: Note: remoteproc is still under development and considered experimental.
[    0.667554]  remoteproc0: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
[    0.667639]  remoteproc0: Direct firmware load for dra7-ipu1-fw.xem4 failed with error -2
[    0.667646]  remoteproc0: Falling back to user helper
[    0.667860] omap-rproc 55020000.ipu: assigned reserved memory node ipu2_cma@95800000
[    0.667909]  remoteproc1: 55020000.ipu is available
[    0.667917]  remoteproc1: Note: remoteproc is still under development and considered experimental.
[    0.667924]  remoteproc1: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
[    0.668086]  remoteproc1: Direct firmware load for dra7-ipu2-fw.xem4 failed with error -2
[    0.668094]  remoteproc1: Falling back to user helper
[    0.668336] omap-rproc 40800000.dsp: assigned reserved memory node dsp1_cma@99000000
[    0.668389]  remoteproc2: 40800000.dsp is available
[    0.668397]  remoteproc2: Note: remoteproc is still under development and considered experimental.
[    0.668404]  remoteproc2: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
[    0.668668] omap-rproc 41000000.dsp: assigned reserved memory node dsp2_cma@9f000000
[    0.668717]  remoteproc3: 41000000.dsp is available
[    0.668724]  remoteproc3: Note: remoteproc is still under development and considered experimental.
[    0.668731]  remoteproc3: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
[    0.669845] NET: Registered protocol family 10
[    0.670399]  remoteproc2: Direct firmware load for dra7-dsp1-fw.xe66 failed with error -2
[    0.670407]  remoteproc2: Falling back to user helper
[    0.670633]  remoteproc3: Direct firmware load for dra7-dsp2-fw.xe66 failed with error -2
[    0.670641]  remoteproc3: Falling back to user helper
[    0.671157] sit: IPv6 over IPv4 tunneling driver
[    0.671764] NET: Registered protocol family 17
[    0.671783] NET: Registered protocol family 41
[    0.671974] omap_voltage_late_init: Voltage driver support not added
[    0.678836] Adding alias for supply vdd,cpu0 -> vdd,4a003b20.oppdm
[    0.678846] Adding alias for supply vbb,cpu0 -> vbb,4a003b20.oppdm
[    0.678931] buck10: supplied by vsys_3v3
[    0.679458] Adding alias for supply vdd,cpu0 -> vdd,4a003b20.oppdm
[    0.679467] Adding alias for supply vbb,cpu0 -> vbb,4a003b20.oppdm
[    0.681227] Power Management for TI OMAP4+ devices.
[    0.681377] Registering SWP/SWPB emulation handler
[    0.681849] dmm 4e000000.dmm: workaround for errata i878 in use
[    0.683168] dmm 4e000000.dmm: initialized all PAT entries
[    0.683630] [drm] Initialized omapdrm 1.0.0 20110917 on minor 1
[    0.703813] hctosys: unable to open rtc device (rtc0)
[    0.704086] vio_3v3_sd: disabling
[    0.704092] aic_dvdd: disabling
[    0.704104] pbias_mmc_omap5: disabling
[    0.704114] clk: Not disabling unused clocks
[    0.704578] Waiting for root device /dev/mmcblk0p1...
[    0.738283] mmc0: MAN_BKOPS_EN bit is not set
[    0.745201] mmc0: new HS200 MMC card at address 0001
[    0.755473] mmcblk0: mmc0:0001 MMC08G 7.25 GiB 
[    0.765622] mmcblk0boot0: mmc0:0001 MMC08G partition 1 8.00 MiB
[    0.775766] mmcblk0boot1: mmc0:0001 MMC08G partition 2 8.00 MiB
[    0.776356]  mmcblk0: p1
[    1.211893] EXT4-fs (mmcblk0p1): mounted filesystem with ordered data mode. Opts: (null)
[    1.211916] VFS: Mounted root (ext4 filesystem) on device 179:1.
[    1.212570] devtmpfs: mounted
[    1.212705] Freeing unused kernel memory: 280K
[    1.212709] This architecture does not have kernel memory protection.
[    1.219083] Kernel Entry time 8180 ticks
[    1.445158] Inside rproc_elf_sanity_check
[    1.445792]  remoteproc2: powering up 40800000.dsp
[    1.445799] Inside rproc_elf_sanity_check
[    1.445805]  remoteproc2: Booting fw image dra7-dsp1-fw.xe66, size 13712676
[    1.452399] omap_hwmod: mmu0_dsp1: _wait_target_disable failed
[    1.452422] omap-iommu 40d01000.mmu: 40d01000.mmu: version 3.0
[    1.452449] omap-iommu 40d02000.mmu: 40d02000.mmu: version 3.0
[    1.475915]  remoteproc2: remote processor 40800000.dsp is now up
[    1.476167] virtio_rpmsg_bus virtio0: rpmsg host is online
[    1.476198]  remoteproc2: registered virtio0 (type 7)
[    1.579134] Inside rproc_elf_sanity_check
[    1.579910]  remoteproc1: powering up 55020000.ipu
[    1.579918] Inside rproc_elf_sanity_check
[    1.579924]  remoteproc1: Booting fw image dra7-ipu2-fw.xem4, size 8245568
[    1.579981] omap-iommu 55082000.mmu: 55082000.mmu: version 2.1
[    1.601863]  remoteproc1: remote processor 55020000.ipu is now up
[    1.602163] virtio_rpmsg_bus virtio1: rpmsg host is online
[    1.602191]  remoteproc1: registered virtio1 (type 7)
[    1.603204] [drm] Initialized pvr 1.14.3699939 20110701 on minor 2
[    1.634438] PVR_K: UM DDK-(3699939) and KM DDK-(3699939) match. [ OK ]
[    1.651037] virtio_rpmsg_bus virtio0: creating channel rpmsg-proto addr 0x50
[    1.651336] DMA: Module install successful, device major num = 245 
[    1.651341] DRV: Module install successful
[    1.654159] virtio_rpmsg_bus virtio1: creating channel rpmsg-proto addr 0x50


2339ms.txt
U-Boot SPL 2016.05 (Jul 17 2020 - 18:44:02)
DRA762-GP ES1.0
[    0.000000] Reserved memory: OVERLAP DETECTED!
[    0.000000] vsdk_sr1_mem@84000000 (0x0000000084000000--0x0000000097000000) overlaps with cmem@95400000 (0x0000000095400000--0x0000000095800)
[    0.000000] Reserved memory: regions without no-map are not yet supported
[    0.000000] Reserved memory: regions without no-map are not yet supported
[    0.000000] Reserved memory: regions without no-map are not yet supported
[    0.002011] /cpus/cpu@0 missing clock-frequency property
[    0.002027] /cpus/cpu@1 missing clock-frequency property
[    0.611889] omap_hsmmc 480b4000.mmc: no pinctrl state for sdr25 mode
[    0.618325] omap_hsmmc 480b4000.mmc: no pinctrl state for sdr12 mode
[    0.681601] omap_voltage_late_init: Voltage driver support not added
[    1.495542] Kernel Entry time 8181 ticks
Loaded PowerVR consumer services.
insmod: ERROR: could not load module ./bin/cmemk.ko: No such file or directory
CUSTOM Application launched at 2339


Dmesg



[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Initializing cgroup subsys cpuset
[    0.000000] Initializing cgroup subsys cpu
[    0.000000] Initializing cgroup subsys cpuacct
[    0.000000] Linux version 4.4.84+ (ubantu@teltvml0203x) (gcc version 5.3.1 20160113 (Linaro GCC 5.3-2016.02) ) #11 SMP PREEMPT Wed Aug 5 120
[    0.000000] CPU: ARMv7 Processor [412fc0f2] revision 2 (ARMv7), cr=30c5387d
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache
[    0.000000] Machine model: TI DRA762 EVM
[    0.000000] Reserved memory: OVERLAP DETECTED!
[    0.000000] vsdk_sr1_mem@84000000 (0x0000000084000000--0x0000000097000000) overlaps with cmem@95400000 (0x0000000095400000--0x0000000095800)
[    0.000000] Reserved memory: created DMA memory pool at 0x0000000040300000, size 3 MiB
[    0.000000] Reserved memory: initialized node cmem@40300000, compatible id shared-dma-pool
[    0.000000] Reserved memory: regions without no-map are not yet supported
[    0.000000] Reserved memory: created CMA memory pool at 0x0000000099000000, size 80 MiB
[    0.000000] Reserved memory: initialized node ipu2_cma@95800000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created CMA memory pool at 0x000000009e000000, size 32 MiB
[    0.000000] Reserved memory: initialized node ipu1_cma@9d000000, compatible id shared-dma-pool
[    0.000000] Reserved memory: regions without no-map are not yet supported
[    0.000000] Reserved memory: created CMA memory pool at 0x00000000a1000000, size 32 MiB
[    0.000000] Reserved memory: initialized node dsp1_cma@99000000, compatible id shared-dma-pool
[    0.000000] Reserved memory: created CMA memory pool at 0x00000000a3000000, size 32 MiB
[    0.000000] Reserved memory: initialized node dsp2_cma@9f000000, compatible id shared-dma-pool
[    0.000000] Reserved memory: regions without no-map are not yet supported
[    0.000000] Reserved memory: created DMA memory pool at 0x00000000a9000000, size 32 MiB
[    0.000000] Reserved memory: initialized node cmem@A9000000, compatible id shared-dma-pool
[    0.000000] cma: Reserved 64 MiB at 0x00000000dbc00000
[    0.000000] Forcing write-allocate cache policy for SMP
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] OMAP4: Map 0x00000000dfd00000 to fe600000 for dram barrier
[    0.000000] On node 0 totalpages: 382976
[    0.000000] free_area_init_node: node 0, pgdat c0584640, node_mem_map ef21a000
[    0.000000]   DMA zone: 1728 pages used for memmap
[    0.000000]   DMA zone: 0 pages reserved
[    0.000000]   DMA zone: 187392 pages, LIFO batch:31
[    0.000000]   HighMem zone: 195584 pages, LIFO batch:31
[    0.000000] DRA762 ES1.0
[    0.000000] PERCPU: Embedded 11 pages/cpu @ef1a6000 s13376 r8192 d23488 u45056
[    0.000000] pcpu-alloc: s13376 r8192 d23488 u45056 alloc=11*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 381248
[    0.000000] Kernel command line: elevator=noop console=ttyS0,115200n8 cma=64M omapdrm.num_crtc=1 consoleblank=0 snd.slots_reserved=1,1 fixrt
[    0.000000] PID hash table entries: 4096 (order: 2, 16384 bytes)
[    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
[    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
[    0.000000] Memory: 875856K/1531904K available (4322K kernel code, 216K rwdata, 804K rodata, 280K init, 256K bss, 410288K reserved, 245760K)
[    0.000000] Virtual kernel memory layout:
[    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
[    0.000000]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
[    0.000000]     vmalloc : 0xf0800000 - 0xff800000   ( 240 MB)
[    0.000000]     lowmem  : 0xc0000000 - 0xf0000000   ( 768 MB)
[    0.000000]     pkmap   : 0xbfe00000 - 0xc0000000   (   2 MB)
[    0.000000]     modules : 0xbf000000 - 0xbfe00000   (  14 MB)
[    0.000000]       .text : 0xc0008000 - 0xc0509f54   (5128 kB)
[    0.000000]       .init : 0xc050a000 - 0xc0550000   ( 280 kB)
[    0.000000]       .data : 0xc0550000 - 0xc0586368   ( 217 kB)
[    0.000000]        .bss : 0xc0588000 - 0xc05c83f4   ( 257 kB)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[    0.000000] Preemptible hierarchical RCU implementation.
[    0.000000]  Build-time adjustment of leaf fanout to 32.
[    0.000000] NR_IRQS:16 nr_irqs:16 16
[    0.000000] ti_dt_clocks_register: failed to lookup clock node gmac_gmii_ref_clk_div
[    0.000000] OMAP clockevent source: timer1 at 32786 Hz
[    0.000000] Architected cp15 timer(s) running at 6.14MHz (phys).
[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x16af5adb9, max_idle_ns: 440795202250 ns
[    0.000004] sched_clock: 56 bits at 6MHz, resolution 162ns, wraps every 4398046511023ns
[    0.000015] Switching to timer-based delay loop, resolution 162ns
[    0.000411] clocksource: 32k_counter: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 58327039986419 ns
[    0.000418] OMAP clocksource: 32k_counter at 32768 Hz
[    0.000960] Console: colour dummy device 80x30
[    0.000984] Calibrating delay loop (skipped), value calculated using timer frequency.. 12.29 BogoMIPS (lpj=61475)
[    0.000997] pid_max: default: 4096 minimum: 301
[    0.001094] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
[    0.001105] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
[    0.001709] Initializing cgroup subsys io
[    0.001725] Initializing cgroup subsys memory
[    0.001750] Initializing cgroup subsys devices
[    0.001762] Initializing cgroup subsys freezer
[    0.001773] Initializing cgroup subsys perf_event
[    0.001786] Initializing cgroup subsys pids
[    0.001813] CPU: Testing write buffer coherency: ok
[    0.002011] /cpus/cpu@0 missing clock-frequency property
[    0.002027] /cpus/cpu@1 missing clock-frequency property
[    0.002038] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[    0.002054] Setting up static identity map for 0x80008380 - 0x800083e0
[    0.079994] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[    0.080058] Brought up 2 CPUs
[    0.080072] SMP: Total of 2 processors activated (24.59 BogoMIPS).
[    0.080079] CPU: All CPU(s) started in HYP mode.
[    0.080085] CPU: Virtualization extensions available.
[    0.080483] devtmpfs: initialized
[    0.112832] VFP support v0.3: implementor 41 architecture 4 part 30 variant f rev 0
[    0.113487] omap_hwmod: l3_main_2 using broken dt data from ocp
[    0.125951] omap_hwmod: dcan1: _wait_target_disable failed
[    0.223526] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.223541] futex hash table entries: 16 (order: -2, 1024 bytes)
[    0.229219] pinctrl core: initialized pinctrl subsystem
[    0.229869] NET: Registered protocol family 16
[    0.230902] DMA: preallocated 256 KiB pool for atomic coherent allocations
[    0.260112] cpuidle: using governor ladder
[    0.290144] cpuidle: using governor menu
[    0.298622] OMAP GPIO hardware version 0.1
[    0.302784] GPIO line 161 (radio_rst) hogged as output/low
[    0.305231] irq: no irq domain found for /ocp/l4@4a000000/scm@2000/pinmux@1400 !
[    0.329316] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[    0.329327] hw-breakpoint: maximum watchpoint size is 8 bytes.
[    0.329763] omap4_sram_init:Unable to allocate sram needed to handle errata I688
[    0.329772] omap4_sram_init:Unable to get sram pool needed to handle errata I688
[    0.330035] OMAP DMA hardware revision 0.0
[    0.371976] omap-dma-engine 4a056000.dma-controller: OMAP DMA engine driver (LinkedList1/2/3 supported)
[    0.373174] edma 43300000.edma: memcpy is disabled
[    0.378224] edma 43300000.edma: TI EDMA DMA engine driver
[    0.382564] omap-iommu 40d01000.mmu: 40d01000.mmu registered
[    0.382713] omap-iommu 40d02000.mmu: 40d02000.mmu registered
[    0.382838] omap-iommu 58882000.mmu: 58882000.mmu registered
[    0.382960] omap-iommu 55082000.mmu: 55082000.mmu registered
[    0.383212] omap-iommu 41501000.mmu: 41501000.mmu registered
[    0.383366] omap-iommu 41502000.mmu: 41502000.mmu registered
[    0.385899] palmas 0-0058: IRQ missing: skipping irq request
[    0.400612] palmas 0-0058: Muxing GPIO 51, PWM 0, LED 2
[    0.451046] irq: no irq domain found for /ocp/i2c@48070000/tps65917@58 !
[    0.451758] omap_i2c 48070000.i2c: bus 0 rev0.12 at 400 kHz
[    0.452167] omap_i2c 48060000.i2c: bus 2 rev0.12 at 400 kHz
[    0.452635] omap_i2c 4807a000.i2c: bus 3 rev0.12 at 400 kHz
[    0.452873] EDAC MC: Ver: 3.0.0
[    0.453601] omap-mailbox 48840000.mailbox: omap mailbox rev 0x400
[    0.453902] omap-mailbox 48842000.mailbox: omap mailbox rev 0x400
[    0.454929] clocksource: Switched to clocksource arch_sys_counter
[    0.465388] NET: Registered protocol family 2
[    0.465926] TCP established hash table entries: 8192 (order: 3, 32768 bytes)
[    0.465990] TCP bind hash table entries: 8192 (order: 4, 65536 bytes)
[    0.466115] TCP: Hash tables configured (established 8192 bind 8192)
[    0.466162] UDP hash table entries: 512 (order: 2, 16384 bytes)
[    0.466194] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
[    0.466332] NET: Registered protocol family 1
[    0.467261] hw perfevents: enabled with armv7_cortex_a15 PMU driver, 7 counters available
[    0.477453] ntfs: driver 2.1.32 [Flags: R/O].
[    0.478545] bounce: pool size: 64 pages
[    0.478698] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250)
[    0.478708] io scheduler noop registered (default)
[    0.478716] io scheduler deadline registered
[    0.478745] io scheduler cfq registered
[    0.483349] pinctrl-single 4a003400.pinmux: 282 pins at pa fc003400 size 1128
[    0.545357] Serial: 8250/16550 driver, 10 ports, IRQ sharing disabled
[    0.548727] console [ttyS0] disabled
[    0.548779] 4806a000.serial: ttyS0 at MMIO 0x4806a000 (irq = 301, base_baud = 3000000) is a 8250
[    0.597689] console [ttyS0] enabled
[    0.598588] 4806c000.serial: ttyS1 at MMIO 0x4806c000 (irq = 302, base_baud = 3000000) is a 8250
[    0.599452] 48020000.serial: ttyS2 at MMIO 0x48020000 (irq = 303, base_baud = 3000000) is a 8250
[    0.600839] [drm] Initialized drm 1.1.0 20060810
[    0.601588] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
[    0.601597] [drm] No driver support for vblank timestamp query.
[    0.601796] [drm] Initialized vdrm 1.0.0 20110917 on minor 0
[    0.605464] vmemexp device MAJOR num = 248
[    0.605486] vmemexp class registered
[    0.605618] /dev/vmemexp device registered
[    0.605627] ioctl DBUFIOC_EXPORT_VIRTMEM = -1072899120
[    0.607477] i2c /dev entries driver
[    0.611889] omap_hsmmc 480b4000.mmc: no pinctrl state for sdr25 mode
[    0.618325] omap_hsmmc 480b4000.mmc: no pinctrl state for sdr12 mode
[    0.625014] vsys_3v3: supplied by vsys_12v0
[    0.625037] smps5: supplied by vsys_3v3
[    0.625253] vio_1v8: supplied by smps5
[    0.677120] hwspinlock_user gatemp: requested 20 hwspinlocks
[    0.677530] omap-rproc 58820000.ipu: assigned reserved memory node ipu1_cma@9d000000
[    0.677582]  remoteproc0: 58820000.ipu is available
[    0.677591]  remoteproc0: Note: remoteproc is still under development and considered experimental.
[    0.677599]  remoteproc0: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
[    0.677684]  remoteproc0: Direct firmware load for dra7-ipu1-fw.xem4 failed with error -2
[    0.677692]  remoteproc0: Falling back to user helper
[    0.677910] omap-rproc 55020000.ipu: assigned reserved memory node ipu2_cma@95800000
[    0.677960]  remoteproc1: 55020000.ipu is available
[    0.677967]  remoteproc1: Note: remoteproc is still under development and considered experimental.
[    0.677974]  remoteproc1: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
[    0.678136]  remoteproc1: Direct firmware load for dra7-ipu2-fw.xem4 failed with error -2
[    0.678143]  remoteproc1: Falling back to user helper
[    0.678378] omap-rproc 40800000.dsp: assigned reserved memory node dsp1_cma@99000000
[    0.678428]  remoteproc2: 40800000.dsp is available
[    0.678436]  remoteproc2: Note: remoteproc is still under development and considered experimental.
[    0.678443]  remoteproc2: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
[    0.678724] omap-rproc 41000000.dsp: assigned reserved memory node dsp2_cma@9f000000
[    0.678774]  remoteproc3: 41000000.dsp is available
[    0.678781]  remoteproc3: Note: remoteproc is still under development and considered experimental.
[    0.678788]  remoteproc3: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed.
[    0.679891] NET: Registered protocol family 10
[    0.680464]  remoteproc2: Direct firmware load for dra7-dsp1-fw.xe66 failed with error -2
[    0.680472]  remoteproc2: Falling back to user helper
[    0.680793] sit: IPv6 over IPv4 tunneling driver
[    0.681391] NET: Registered protocol family 17
[    0.681409] NET: Registered protocol family 41
[    0.681601] omap_voltage_late_init: Voltage driver support not added
[    0.688170]  remoteproc3: Direct firmware load for dra7-dsp2-fw.xe66 failed with error -2
[    0.688178]  remoteproc3: Falling back to user helper
[    0.688719] Adding alias for supply vdd,cpu0 -> vdd,4a003b20.oppdm
[    0.688729] Adding alias for supply vbb,cpu0 -> vbb,4a003b20.oppdm
[    0.688812] buck10: supplied by vsys_3v3
[    0.689370] Adding alias for supply vdd,cpu0 -> vdd,4a003b20.oppdm
[    0.689380] Adding alias for supply vbb,cpu0 -> vbb,4a003b20.oppdm
[    0.691054] Power Management for TI OMAP4+ devices.
[    0.691200] Registering SWP/SWPB emulation handler
[    0.691680] dmm 4e000000.dmm: workaround for errata i878 in use
[    0.693033] dmm 4e000000.dmm: initialized all PAT entries
[    0.693510] [drm] Initialized omapdrm 1.0.0 20110917 on minor 1
[    0.703666] hctosys: unable to open rtc device (rtc0)
[    0.703939] vio_3v3_sd: disabling
[    0.703946] aic_dvdd: disabling
[    0.703957] pbias_mmc_omap5: disabling
[    0.703967] clk: Not disabling unused clocks
[    0.704451] Waiting for root device /dev/mmcblk0p1...
[    0.868277] mmc0: MAN_BKOPS_EN bit is not set
[    0.904516] mmc0: new HS200 MMC card at address 0001
[    0.914801] mmcblk0: mmc0:0001 MMC08G 7.25 GiB 
[    0.924953] mmcblk0boot0: mmc0:0001 MMC08G partition 1 8.00 MiB
[    0.935095] mmcblk0boot1: mmc0:0001 MMC08G partition 2 8.00 MiB
[    0.935747]  mmcblk0: p1
[    1.487143] EXT4-fs (mmcblk0p1): recovery complete
[    1.487803] EXT4-fs (mmcblk0p1): mounted filesystem with ordered data mode. Opts: (null)
[    1.487827] VFS: Mounted root (ext4 filesystem) on device 179:1.
[    1.488445] devtmpfs: mounted
[    1.488580] Freeing unused kernel memory: 280K
[    1.488584] This architecture does not have kernel memory protection.
[    1.495542] Kernel Entry time 8181 ticks
[    1.644786] Inside rproc_elf_sanity_check
[    1.645546]  remoteproc0: powering up 58820000.ipu
[    1.645554] Inside rproc_elf_sanity_check
[    1.645560]  remoteproc0: Booting fw image dra7-ipu1-fw.xem4, size 8270008
[    1.645617] omap-iommu 58882000.mmu: 58882000.mmu: version 2.1
[    1.667128] Inside rproc_elf_sanity_check
[    1.667611]  remoteproc0: remote processor 58820000.ipu is now up
[    1.668322]  remoteproc2: powering up 40800000.dsp
[    1.668330] Inside rproc_elf_sanity_check
[    1.668335]  remoteproc2: Booting fw image dra7-dsp1-fw.xe66, size 773384
[    1.674836] omap_hwmod: mmu0_dsp1: _wait_target_disable failed
[    1.674859] omap-iommu 40d01000.mmu: 40d01000.mmu: version 3.0
[    1.674893] omap-iommu 40d02000.mmu: 40d02000.mmu: version 3.0
[    1.682105] virtio_rpmsg_bus virtio0: rpmsg host is online
[    1.682132]  remoteproc0: registered virtio0 (type 7)
[    1.690499]  remoteproc2: remote processor 40800000.dsp is now up
[    1.690963] virtio_rpmsg_bus virtio1: rpmsg host is online
[    1.690983]  remoteproc2: registered virtio1 (type 7)
[    1.693440] [drm] Initialized pvr 1.14.3699939 20110701 on minor 2
[    1.714567] PVR_K: UM DDK-(3699939) and KM DDK-(3699939) match. [ OK ]
[    1.716648] virtio_rpmsg_bus virtio1: creating channel rpmsg-proto addr 0x50
[    1.719733] virtio_rpmsg_bus virtio0: creating channel rpmsg-proto addr 0x50
[    1.730954] DMA: Module install successful, device major num = 245 
[    1.730960] DRV: Module install successful
root@dra7xx-evm:/run/media/mmcblk1p1/opt/vision_sdk# 




Could you suggest any ways I can tackle this inconsistency in timing.

Regards,

Padmesh

  • Hi Padmesh,

    Do you do an orderly shutdown or poweroff?

    Regards,
    Vishal

  • Hi Vishal,

    Poweroff usually.

    What exact do we need to follow ? We are working on the EVM now.

    Regards,

    Padmesh

  • I m sorry it wasn't resolved.

    I start the application through init-demo.sh.

    From there how do I gracefully shutdown the system.

    Regards,

    Padmesh

  • Can you try unmounting the partitions before powering off?

  • Hi Vishal.

    The following is included in the iniit-demo.sh

    cd /opt/vision_sdk
    ./vision_sdk_load.sh 
    APP_LAUNCH=$(/usr/bin/read32k_driver)
    echo Application launched at $APP_LAUNCH
    ./apps.out 
    sleep 1
    fi
    
    #Launch prompt
    exec /sbin/init  
    

    After the apps.out is ended, we get to the prompt via /sbin/init. Later we enter the halt command to shutdown the system.

    I'm seeing some improvement here in the mount delay but at some powercycles its taking more time. I can show some cases here.


    [    0.704518] Waiting for root device /dev/mmcblk0p1...
    [    0.748288] mmc0: MAN_BKOPS_EN bit is not set
    [    0.755188] mmc0: new HS200 MMC card at address 0001
    [    0.755443] mmcblk0: mmc0:0001 MMC08G 7.25 GiB 
    [    0.765581] mmcblk0boot0: mmc0:0001 MMC08G partition 1 8.00 MiB
    [    0.775723] mmcblk0boot1: mmc0:0001 MMC08G partition 2 8.00 MiB
    [    0.776320]  mmcblk0: p1
    [    0.837163] EXT4-fs (mmcblk0p1): mounted filesystem with ordered data mode. Opts: (null)
    [    0.837186] VFS: Mounted root (ext4 filesystem) on device 179:1.
    [    0.838822] devtmpfs: mounted
    [    0.838957] Freeing unused kernel memory: 280K
    [    0.838961] This architecture does not have kernel memory protection.
    [    0.847039] Kernel Entry time 8180 ticks
    [    1.042016] Inside rproc_elf_sanity_check
    [    1.042828]  remoteproc0: powering up 58820000.ipu

    [    0.694484] Waiting for root device /dev/mmcblk0p1...
    [    0.768300] mmc0: MAN_BKOPS_EN bit is not set
    [    0.775228] mmc0: new HS200 MMC card at address 0001
    [    0.785507] mmcblk0: mmc0:0001 MMC08G 7.25 GiB 
    [    0.785634] mmcblk0boot0: mmc0:0001 MMC08G partition 1 8.00 MiB
    [    0.795771] mmcblk0boot1: mmc0:0001 MMC08G partition 2 8.00 MiB
    [    0.796371]  mmcblk0: p1
    [    1.303122] EXT4-fs (mmcblk0p1): mounted filesystem with ordered data mode. Opts: (null)
    [    1.303144] VFS: Mounted root (ext4 filesystem) on device 179:1.
    [    1.304865] devtmpfs: mounted
    [    1.305013] Freeing unused kernel memory: 280K
    [    1.305017] This architecture does not have kernel memory protection.
    [    1.313009] Kernel Entry time 8179 ticks
    [    1.507977] Inside rproc_elf_sanity_check
    [    1.508716]  remoteproc0: powering up 58820000.ipu

    [    0.708159] Waiting for root device /dev/mmcblk0p1...
    [    0.768292] mmc0: MAN_BKOPS_EN bit is not set
    [    0.775226] mmc0: new HS200 MMC card at address 0001
    [    0.785505] mmcblk0: mmc0:0001 MMC08G 7.25 GiB 
    [    0.795655] mmcblk0boot0: mmc0:0001 MMC08G partition 1 8.00 MiB
    [    0.805794] mmcblk0boot1: mmc0:0001 MMC08G partition 2 8.00 MiB
    [    0.806390]  mmcblk0: p1
    [    1.220899] EXT4-fs (mmcblk0p1): mounted filesystem with ordered data mode. Opts: (null)
    [    1.220921] VFS: Mounted root (ext4 filesystem) on device 179:1.
    [    1.222552] devtmpfs: mounted
    [    1.222686] Freeing unused kernel memory: 280K
    [    1.222690] This architecture does not have kernel memory protection.
    [    1.230794] Kernel Entry time 8185 ticks

    [    0.704480] Waiting for root device /dev/mmcblk0p1...
    [    0.748269] mmc0: MAN_BKOPS_EN bit is not set
    [    0.784449] mmc0: new HS200 MMC card at address 0001
    [    0.794734] mmcblk0: mmc0:0001 MMC08G 7.25 GiB 
    [    0.804879] mmcblk0boot0: mmc0:0001 MMC08G partition 1 8.00 MiB
    [    0.815036] mmcblk0boot1: mmc0:0001 MMC08G partition 2 8.00 MiB
    [    0.815635]  mmcblk0: p1
    [    0.937910] EXT4-fs (mmcblk0p1): mounted filesystem with ordered data mode. Opts: (null)
    [    0.937932] VFS: Mounted root (ext4 filesystem) on device 179:1.
    [    0.939651] devtmpfs: mounted
    [    0.939786] Freeing unused kernel memory: 280K
    [    0.939790] This architecture does not have kernel memory protection.
    [    0.947832] Kernel Entry time 8180 ticks
    [    1.142970] Inside rproc_elf_sanity_check
    [    1.143913]  remoteproc0: powering up 58820000.ipu
    [    1.143921] Inside rproc_elf_sanity_check
    [    1.143926]  remoteproc0: Booting fw image dra7-ip

    Is there any specific command for unmounting the partition where fs is mounted. The only partition we use is /dev/mmcblk0p1 for linux filesystem.

    Timings are still changing i.e. a variation of 100 to 600 ms delay can occur at some power cycles.

    Regards,
    Padmesh

  • Check if 'halt' command is already unmounting /dev/mmcblk0p1 or mounting it as readonly before powering down.
    You can do a test by running fsck on rootfs before halting and check the behavior.


    Regards,
    Vishal

  • Hi Vishal,

    Basically what we have done is, we removed the entire systemd, inorder to reduce the filesystem size.i.e the link to /sbin/init in init-demo.sh is removed now and we do not need to go to prompt. So the way we are seeing is to mount it as read only before powering off the board.

    So the current setup is only the init-demo.sh is waken as init script just to start the application. And as the application exits, it should unmount the device. 

    I have tried the above mentioned points but still see this delay

    The init-demo.sh is attached and the corresponding dmesg also. Around 400 ms is lost for mounting the emmc paritition

    init-demo_17Aug_withfsck.txt
    #!/bin/bash
    export PATH=/usr/bin:/sbin:/bin
    mount -t sysfs none /sys
    mount -t proc none /proc
    
    
    FW_NAMES="dra7-ipu1-fw.xem4 dra7-dsp1-fw.xe66"
    for FW in $FW_NAMES ; do
        echo 1 > /sys/class/firmware/$FW/loading
        cat /lib/firmware/$FW > /sys/class/firmware/$FW/data
        echo 0 > /sys/class/firmware/$FW/loading
    done
    
    #Initiale SGX and EGL
    /etc/init.d/rc.pvr start
    
    #Launch 2DRV upon startup
    cd /opt/vision_sdk
    ./vision_sdk_load.sh >> vsdk.log
    SVS_LAUNCH=$(/usr/bin/read32k_driver)
    echo SVS Application launched at $SVS_LAUNCH
    ./apps.out
    
    sleep 1
    ROOT_DEV=`cat /proc/cmdline | sed -e 's/^.*root=//' -e 's/ .*$//'`
    mount -o remount -o ro $ROOT_DEV
    fsck $ROOT_DEV
    umount /
    umount /dev
    
    dmesg
    
    
    
    


    fsck from util-linux 2.27.1 e2fsck 1.43-WIP (18-May-2015) /dev/mmcblk0p1: clean, 1346/125696 files, 55673/502023 blocks [ 0.000000] Booting Linux on physical CPU 0x0 [ 0.000000] Initializing cgroup subsys cpuset [ 0.000000] Initializing cgroup subsys cpu [ 0.000000] Initializing cgroup subsys cpuacct [ 0.000000] Linux version 4.4.84+ (ubantu@teltvml0203x) (gcc version 5.3.1 20160113 (Linaro GCC 5.3-2016.02) ) #1 SMP PREEMPT Mon Aug 17 12:31:12 IST 2020 [ 0.000000] CPU: ARMv7 Processor [412fc0f2] revision 2 (ARMv7), cr=30c5387d [ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache [ 0.000000] Machine model: TI DRA762 EVM [ 0.000000] Reserved memory: OVERLAP DETECTED! [ 0.000000] vsdk_sr1_mem@84000000 (0x0000000084000000--0x0000000097000000) overlaps with cmem@95400000 (0x0000000095400000--0x0000000095800000) [ 0.000000] Reserved memory: created DMA memory pool at 0x0000000040300000, size 3 MiB [ 0.000000] Reserved memory: initialized node cmem@40300000, compatible id shared-dma-pool [ 0.000000] Reserved memory: regions without no-map are not yet supported [ 0.000000] Reserved memory: created CMA memory pool at 0x0000000099000000, size 80 MiB [ 0.000000] Reserved memory: initialized node ipu2_cma@95800000, compatible id shared-dma-pool [ 0.000000] Reserved memory: created CMA memory pool at 0x000000009e000000, size 32 MiB [ 0.000000] Reserved memory: initialized node ipu1_cma@9d000000, compatible id shared-dma-pool [ 0.000000] Reserved memory: regions without no-map are not yet supported [ 0.000000] Reserved memory: created CMA memory pool at 0x00000000a1000000, size 32 MiB [ 0.000000] Reserved memory: initialized node dsp1_cma@99000000, compatible id shared-dma-pool [ 0.000000] Reserved memory: created CMA memory pool at 0x00000000a3000000, size 32 MiB [ 0.000000] Reserved memory: initialized node dsp2_cma@9f000000, compatible id shared-dma-pool [ 0.000000] Reserved memory: regions without no-map are not yet supported [ 0.000000] Reserved memory: created DMA memory pool at 0x00000000a9000000, size 32 MiB [ 0.000000] Reserved memory: initialized node cmem@A9000000, compatible id shared-dma-pool [ 0.000000] cma: Reserved 64 MiB at 0x00000000dbc00000 [ 0.000000] Forcing write-allocate cache policy for SMP [ 0.000000] Memory policy: Data cache writealloc [ 0.000000] OMAP4: Map 0x00000000dfd00000 to fe600000 for dram barrier [ 0.000000] On node 0 totalpages: 382976 [ 0.000000] free_area_init_node: node 0, pgdat c04efc80, node_mem_map ef21a000 [ 0.000000] DMA zone: 1728 pages used for memmap [ 0.000000] DMA zone: 0 pages reserved [ 0.000000] DMA zone: 187392 pages, LIFO batch:31 [ 0.000000] HighMem zone: 195584 pages, LIFO batch:31 [ 0.000000] DRA762 ES1.0 [ 0.000000] PERCPU: Embedded 11 pages/cpu @ef1a6000 s13184 r8192 d23680 u45056 [ 0.000000] pcpu-alloc: s13184 r8192 d23680 u45056 alloc=11*4096 [ 0.000000] pcpu-alloc: [0] 0 [0] 1 [ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 381248 [ 0.000000] Kernel command line: elevator=noop console=ttyS0,115200n8 cma=64M omapdrm.num_crtc=1 consoleblank=0 snd.slots_reserved=1,1 fixrtc loglevel=0 root=/dev/mmcblk0p1 rootfstype=ext4 rw rootwait init=/home/root/init-demo.sh clk_ignore_unused quiet [ 0.000000] PID hash table entries: 4096 (order: 2, 16384 bytes) [ 0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes) [ 0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes) [ 0.000000] Memory: 876476K/1531904K available (3710K kernel code, 245K rwdata, 824K rodata, 248K init, 233K bss, 409668K reserved, 245760K cma-reserved, 716800K highmem) [ 0.000000] Virtual kernel memory layout: [ 0.000000] vector : 0xffff0000 - 0xffff1000 ( 4 kB) [ 0.000000] fixmap : 0xffc00000 - 0xfff00000 (3072 kB) [ 0.000000] vmalloc : 0xf0800000 - 0xff800000 ( 240 MB) [ 0.000000] lowmem : 0xc0000000 - 0xf0000000 ( 768 MB) [ 0.000000] pkmap : 0xbfe00000 - 0xc0000000 ( 2 MB) [ 0.000000] modules : 0xbf000000 - 0xbfe00000 ( 14 MB) [ 0.000000] .text : 0xc0008000 - 0xc0475f54 (4536 kB) [ 0.000000] .init : 0xc0476000 - 0xc04b4000 ( 248 kB) [ 0.000000] .data : 0xc04b4000 - 0xc04f1758 ( 246 kB) [ 0.000000] .bss : 0xc04f3000 - 0xc052d690 ( 234 kB) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 [ 0.000000] Preemptible hierarchical RCU implementation. [ 0.000000] Build-time adjustment of leaf fanout to 32. [ 0.000000] NR_IRQS:16 nr_irqs:16 16 [ 0.000000] ti_dt_clocks_register: failed to lookup clock node gmac_gmii_ref_clk_div [ 0.000000] OMAP clockevent source: timer1 at 32786 Hz [ 0.000000] Architected cp15 timer(s) running at 6.14MHz (phys). [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x16af5adb9, max_idle_ns: 440795202250 ns [ 0.000005] sched_clock: 56 bits at 6MHz, resolution 162ns, wraps every 4398046511023ns [ 0.000015] Switching to timer-based delay loop, resolution 162ns [ 0.000416] clocksource: 32k_counter: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 58327039986419 ns [ 0.000423] OMAP clocksource: 32k_counter at 32768 Hz [ 0.000977] Console: colour dummy device 80x30 [ 0.001001] Calibrating delay loop (skipped), value calculated using timer frequency.. 12.29 BogoMIPS (lpj=61475) [ 0.001014] pid_max: default: 4096 minimum: 301 [ 0.001113] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes) [ 0.001124] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes) [ 0.001730] Initializing cgroup subsys io [ 0.001745] Initializing cgroup subsys memory [ 0.001770] Initializing cgroup subsys devices [ 0.001782] Initializing cgroup subsys freezer [ 0.001792] Initializing cgroup subsys perf_event [ 0.001803] Initializing cgroup subsys pids [ 0.001829] CPU: Testing write buffer coherency: ok [ 0.002028] /cpus/cpu@0 missing clock-frequency property [ 0.002042] /cpus/cpu@1 missing clock-frequency property [ 0.002052] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 [ 0.002068] Setting up static identity map for 0x80008380 - 0x800083e0 [ 0.080000] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 [ 0.080061] Brought up 2 CPUs [ 0.080073] SMP: Total of 2 processors activated (24.59 BogoMIPS). [ 0.080080] CPU: All CPU(s) started in HYP mode. [ 0.080086] CPU: Virtualization extensions available. [ 0.080487] devtmpfs: initialized [ 0.112567] VFP support v0.3: implementor 41 architecture 4 part 30 variant f rev 0 [ 0.113226] omap_hwmod: l3_main_2 using broken dt data from ocp [ 0.125607] omap_hwmod: dcan1: _wait_target_disable failed [ 0.223173] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns [ 0.223188] futex hash table entries: 16 (order: -2, 1024 bytes) [ 0.228849] pinctrl core: initialized pinctrl subsystem [ 0.229498] NET: Registered protocol family 16 [ 0.230522] DMA: preallocated 256 KiB pool for atomic coherent allocations [ 0.260113] cpuidle: using governor ladder [ 0.290142] cpuidle: using governor menu [ 0.298663] OMAP GPIO hardware version 0.1 [ 0.302853] GPIO line 161 (radio_rst) hogged as output/low [ 0.305314] irq: no irq domain found for /ocp/l4@4a000000/scm@2000/pinmux@1400 ! [ 0.329393] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. [ 0.329404] hw-breakpoint: maximum watchpoint size is 8 bytes. [ 0.329844] omap4_sram_init:Unable to allocate sram needed to handle errata I688 [ 0.329853] omap4_sram_init:Unable to get sram pool needed to handle errata I688 [ 0.330117] OMAP DMA hardware revision 0.0 [ 0.371861] omap-dma-engine 4a056000.dma-controller: OMAP DMA engine driver (LinkedList1/2/3 supported) [ 0.373064] edma 43300000.edma: memcpy is disabled [ 0.378064] edma 43300000.edma: TI EDMA DMA engine driver [ 0.382431] omap-iommu 40d01000.mmu: 40d01000.mmu registered [ 0.382582] omap-iommu 40d02000.mmu: 40d02000.mmu registered [ 0.382708] omap-iommu 58882000.mmu: 58882000.mmu registered [ 0.382831] omap-iommu 55082000.mmu: 55082000.mmu registered [ 0.383073] omap-iommu 41501000.mmu: 41501000.mmu registered [ 0.383223] omap-iommu 41502000.mmu: 41502000.mmu registered [ 0.385781] palmas 0-0058: IRQ missing: skipping irq request [ 0.400631] palmas 0-0058: Muxing GPIO 51, PWM 0, LED 2 [ 0.451065] irq: no irq domain found for /ocp/i2c@48070000/tps65917@58 ! [ 0.451781] omap_i2c 48070000.i2c: bus 0 rev0.12 at 400 kHz [ 0.452186] omap_i2c 48060000.i2c: bus 2 rev0.12 at 400 kHz [ 0.452664] omap_i2c 4807a000.i2c: bus 3 rev0.12 at 400 kHz [ 0.452903] EDAC MC: Ver: 3.0.0 [ 0.453634] omap-mailbox 48840000.mailbox: omap mailbox rev 0x400 [ 0.453930] omap-mailbox 48842000.mailbox: omap mailbox rev 0x400 [ 0.454933] clocksource: Switched to clocksource arch_sys_counter [ 0.465317] NET: Registered protocol family 1 [ 0.466210] hw perfevents: enabled with armv7_cortex_a15 PMU driver, 7 counters available [ 0.476486] bounce: pool size: 64 pages [ 0.476629] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) [ 0.476639] io scheduler noop registered (default) [ 0.476646] io scheduler deadline registered [ 0.476675] io scheduler cfq registered [ 0.481259] pinctrl-single 4a003400.pinmux: 282 pins at pa fc003400 size 1128 [ 0.543128] Serial: 8250/16550 driver, 10 ports, IRQ sharing disabled [ 0.546590] console [ttyS0] disabled [ 0.546641] 4806a000.serial: ttyS0 at MMIO 0x4806a000 (irq = 301, base_baud = 3000000) is a 8250 [ 0.595548] console [ttyS0] enabled [ 0.596458] 4806c000.serial: ttyS1 at MMIO 0x4806c000 (irq = 302, base_baud = 3000000) is a 8250 [ 0.597319] 48020000.serial: ttyS2 at MMIO 0x48020000 (irq = 303, base_baud = 3000000) is a 8250 [ 0.598714] [drm] Initialized drm 1.1.0 20060810 [ 0.599455] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013). [ 0.599463] [drm] No driver support for vblank timestamp query. [ 0.599659] [drm] Initialized vdrm 1.0.0 20110917 on minor 0 [ 0.603337] vmemexp device MAJOR num = 248 [ 0.603360] vmemexp class registered [ 0.603495] /dev/vmemexp device registered [ 0.603503] ioctl DBUFIOC_EXPORT_VIRTMEM = -1072899120 [ 0.605406] i2c /dev entries driver [ 0.609814] omap_hsmmc 480b4000.mmc: no pinctrl state for sdr25 mode [ 0.616250] omap_hsmmc 480b4000.mmc: no pinctrl state for sdr12 mode [ 0.622907] vsys_3v3: supplied by vsys_12v0 [ 0.622928] smps5: supplied by vsys_3v3 [ 0.623112] vio_1v8: supplied by smps5 [ 0.667115] hwspinlock_user gatemp: requested 20 hwspinlocks [ 0.667535] omap-rproc 58820000.ipu: assigned reserved memory node ipu1_cma@9d000000 [ 0.667587] remoteproc0: 58820000.ipu is available [ 0.667595] remoteproc0: Note: remoteproc is still under development and considered experimental. [ 0.667602] remoteproc0: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed. [ 0.667685] remoteproc0: Direct firmware load for dra7-ipu1-fw.xem4 failed with error -2 [ 0.667693] remoteproc0: Falling back to user helper [ 0.667909] omap-rproc 55020000.ipu: assigned reserved memory node ipu2_cma@95800000 [ 0.667958] remoteproc1: 55020000.ipu is available [ 0.667966] remoteproc1: Note: remoteproc is still under development and considered experimental. [ 0.667973] remoteproc1: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed. [ 0.668133] remoteproc1: Direct firmware load for dra7-ipu2-fw.xem4 failed with error -2 [ 0.668141] remoteproc1: Falling back to user helper [ 0.668373] omap-rproc 40800000.dsp: assigned reserved memory node dsp1_cma@99000000 [ 0.668421] remoteproc2: 40800000.dsp is available [ 0.668428] remoteproc2: Note: remoteproc is still under development and considered experimental. [ 0.668435] remoteproc2: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed. [ 0.668712] omap-rproc 41000000.dsp: assigned reserved memory node dsp2_cma@9f000000 [ 0.668761] remoteproc3: 41000000.dsp is available [ 0.668769] remoteproc3: Note: remoteproc is still under development and considered experimental. [ 0.668775] remoteproc3: THE BINARY FORMAT IS NOT YET FINALIZED, and backward compatibility isn't yet guaranteed. [ 0.669044] NET: Registered protocol family 17 [ 0.669063] NET: Registered protocol family 41 [ 0.669235] omap_voltage_late_init: Voltage driver support not added [ 0.675794] remoteproc2: Direct firmware load for dra7-dsp1-fw.xe66 failed with error -2 [ 0.675802] remoteproc2: Falling back to user helper [ 0.676321] Adding alias for supply vdd,cpu0 -> vdd,4a003b20.oppdm [ 0.676331] Adding alias for supply vbb,cpu0 -> vbb,4a003b20.oppdm [ 0.676409] buck10: supplied by vsys_3v3 [ 0.676636] remoteproc3: Direct firmware load for dra7-dsp2-fw.xe66 failed with error -2 [ 0.676644] remoteproc3: Falling back to user helper [ 0.677054] Adding alias for supply vdd,cpu0 -> vdd,4a003b20.oppdm [ 0.677063] Adding alias for supply vbb,cpu0 -> vbb,4a003b20.oppdm [ 0.678747] Power Management for TI OMAP4+ devices. [ 0.678905] Registering SWP/SWPB emulation handler [ 0.679459] dmm 4e000000.dmm: workaround for errata i878 in use [ 0.680934] dmm 4e000000.dmm: initialized all PAT entries [ 0.681488] [drm] Initialized omapdrm 1.0.0 20110917 on minor 1 [ 0.691657] hctosys: unable to open rtc device (rtc0) [ 0.691968] vio_3v3_sd: disabling [ 0.691975] aic_dvdd: disabling [ 0.691989] pbias_mmc_omap5: disabling [ 0.691999] clk: Not disabling unused clocks [ 0.692551] Waiting for root device /dev/mmcblk0p1... [ 0.858287] mmc0: MAN_BKOPS_EN bit is not set [ 0.865348] mmc0: new HS200 MMC card at address 0001 [ 0.865655] mmcblk0: mmc0:0001 MMC08G 7.25 GiB [ 0.865810] mmcblk0boot0: mmc0:0001 MMC08G partition 1 8.00 MiB [ 0.875980] mmcblk0boot1: mmc0:0001 MMC08G partition 2 8.00 MiB [ 0.876604] mmcblk0: p1 [ 1.293327] EXT4-fs (mmcblk0p1): mounted filesystem with ordered data mode. Opts: (null) [ 1.293351] VFS: Mounted root (ext4 filesystem) on device 179:1. [ 1.294253] devtmpfs: mounted [ 1.294397] Freeing unused kernel memory: 248K [ 1.294402] This architecture does not have kernel memory protection. [ 1.303301] Kernel Entry time 7691 ticks [ 1.485821] remoteproc0: powering up 58820000.ipu [ 1.485833] remoteproc0: Booting fw image dra7-ipu1-fw.xem4, size 8274140 [ 1.485895] omap-iommu 58882000.mmu: 58882000.mmu: version 2.1 [ 1.509474] remoteproc0: remote processor 58820000.ipu is now up [ 1.509769] virtio_rpmsg_bus virtio0: rpmsg host is online [ 1.509804] remoteproc0: registered virtio0 (type 7) [ 1.513830] remoteproc2: powering up 40800000.dsp [ 1.513841] remoteproc2: Booting fw image dra7-dsp1-fw.xe66, size 766248 [ 1.520443] omap_hwmod: mmu0_dsp1: _wait_target_disable failed [ 1.520469] omap-iommu 40d01000.mmu: 40d01000.mmu: version 3.0 [ 1.520529] omap-iommu 40d02000.mmu: 40d02000.mmu: version 3.0 [ 1.541191] [drm] Initialized pvr 1.14.3699939 20110701 on minor 2 [ 1.542316] remoteproc2: remote processor 40800000.dsp is now up [ 1.542654] virtio_rpmsg_bus virtio1: rpmsg host is online [ 1.542690] remoteproc2: registered virtio1 (type 7) [ 1.558896] virtio_rpmsg_bus virtio1: creating channel rpmsg-proto addr 0x50 [ 1.562160] virtio_rpmsg_bus virtio0: creating channel rpmsg-proto addr 0x50 [ 1.570449] PVR_K: UM DDK-(3699939) and KM DDK-(3699939) match. [ OK ] [ 1.588253] DMA: Module install successful, device major num = 245 [ 1.588260] DRV: Module install successful [ 1.986439] omap_l3_noc 44000000.ocp: L3 application error: target 3 mod:2 (unclearable) [ 2.022232] omap_l3_noc 44000000.ocp: L3 debug error: target 3 mod:2 (unclearable) [ 9.579423] random: nonblocking pool is initialized [ 16.103507] virtio_rpmsg_bus virtio0: destroying channel rpmsg-proto addr 0x50 [ 16.287491] virtio_rpmsg_bus virtio1: destroying channel rpmsg-proto addr 0x50 [ 17.683722] EXT4-fs (mmcblk0p1): re-mounted. Opts: data=ordered [ 20.225088] Kernel panic - not syncing: Attempted to kill init! exitcode=0x00000000 [ 20.225088] [ 20.234261] CPU0: stopping [ 20.236981] CPU: 0 PID: 0 Comm: swapper/0 Tainted: G O 4.4.84+ #1 [ 20.244229] Hardware name: Generic DRA74X (Flattened Device Tree) [ 20.250342] Backtrace: [ 20.252801] Function entered at [<c001252c>] from [<c0012718>] [ 20.258654] r7:c04b5f10 r6:20070193 r5:00000000 r4:c04db190 [ 20.264357] Function entered at [<c0012700>] from [<c01dbee8>] [ 20.270210] Function entered at [<c01dbe64>] from [<c00148ac>] [ 20.276060] r7:c04b5f10 r6:00000000 r5:00000000 r4:c04b0e1c [ 20.281762] Function entered at [<c00147bc>] from [<c0009474>] [ 20.287614] r7:fa213000 r6:c04b5f10 r5:c04b68e8 r4:fa212000 [ 20.293318] Function entered at [<c0009400>] from [<c0013140>] [ 20.299170] Exception stack(0xc04b5f10 to 0xc04b5f58) [ 20.304239] 5f00: 00000001 00000000 fe600000 00000000 [ 20.312447] 5f20: 00000000 00000000 c04b0364 00000000 ffffffff c04b6400 c04a4a28 c04b5f6c [ 20.320654] 5f40: c04b5f4c c04b5f60 c00242f0 c000fc54 60070013 ffffffff [ 20.327290] r7:c04b5f44 r6:ffffffff r5:60070013 r4:c000fc54 [ 20.332994] Function entered at [<c000fc20>] from [<c0065330>] [ 20.338848] Function entered at [<c0065300>] from [<c00654e4>] [ 20.344699] Function entered at [<c0065388>] from [<c03a1218>] [ 20.350551] Function entered at [<c03a1190>] from [<c0476cb0>] [ 20.356402] r5:00000000 r4:c04f3060 [ 20.360000] Function entered at [<c04768e4>] from [<80008090>] [ 20.365859] ---[ end Kernel panic - not syncing: Attempted to kill init! exitcode=0x00000000 [ 20.365859]

    Regards,

    Padmesh

  • Hi Padmesh,

    Right now I don't have any other ideas for what could cause the mounting to take more time in certain boot iterations.
    Are these observations on TI EVM or custom board?

    Regards,
    Vishal

  • Hi Vishal,

    The issue is observed from EVM

    Regards,

    Padmesh

  • Hi Vishal,

    One more observation I find, when I just reset the board by switching the power cycle button in the EVM, the mount issue is not observed. The time is really less while in the previous case we were shutting off the power switch.

    [    0.697903] vio_3v3_sd: disabling
    [    0.697911] aic_dvdd: disabling
    [    0.697925] pbias_mmc_omap5: disabling
    [    0.697936] clk: Not disabling unused clocks
    [    0.697941] ALSA device list:
    [    0.697944]   #0: DRA7xx-EVM
    [    0.698498] Waiting for root device /dev/mmcblk0p1...
    [    0.778322] mmc0: MAN_BKOPS_EN bit is not set
    [    0.785394] mmc0: new HS200 MMC card at address 0001
    [    0.785695] mmcblk0: mmc0:0001 MMC08G 7.25 GiB 
    [    0.785857] mmcblk0boot0: mmc0:0001 MMC08G partition 1 8.00 MiB
    [    0.786018] mmcblk0boot1: mmc0:0001 MMC08G partition 2 8.00 MiB
    [    0.786540]  mmcblk0: p1
    [    0.889214] EXT4-fs (mmcblk0p1): recovery complete
    [    0.891572] EXT4-fs (mmcblk0p1): mounted filesystem with ordered data mode. Opts: (null)
    [    0.891597] VFS: Mounted root (ext4 filesystem) on device 179:1.
    [    0.894987] devtmpfs: mounted
    [    0.895132] Freeing unused kernel memory: 256K
    

  • Hi Padmesh,

    The PWR CYCLE button likely cycles power very fast (button press is very quick) so possible the power doesn’t have time to discharge completely.
    The power switch is a slower cycle and would likely discharge more before power was re-applied.

    This should be the only difference between these two options.

    Regards,
    Vishal

  • Hi Vishal,

    Any other suggestions ??

    Regards,
    Padmesh

  • Is root file system coming up as readonly or readwrite? I couldn't see full bootargs from logs you shared.
    Could you try updating bootargs to make rootfs as ro and try?

    Regards,
    Vishal

  • Hi VIshal,

    For the current iimplementation, the bootargs are passed with rw, since we are generating some files from the usecase.

    If we go with ro, we wont be able to create any files. 

    Regards,

    Padmesh

  • Can you try it to just check if there is any difference in mount time?

    Also, have you tried experiment without HS200 mode?

  • Hi Vishal,

    We tried with the read only configuration.

    We didnt face the mount delay here. In all the tries it was showing a consistent timing.

    We have a need to write a file from a usecase we running but since it ro mount, what can we do to solve this ?

    Regards,
    Padmesh

  • Hi Padmesh,

    How about you start rootfs as 'ro' mode and part of your script remount rootfs as 'rw'?

    Regards,
    Vishal