Compiler/PROCESSOR-SDK-DRA7X: Compiler/PROCESSOR-SDK-DRA7X: CPU Statistics is showing Blank Data after moving to RTOS usecase

Hi,

In the Previous ticket, you mentioned pCfg->num_uc is zero. So please check where this variable is updating.

Thanks

Gaviraju

Hi 

I have tested with adding below API with modified parameters (earlier only one parameters was TRUE). But still we are seeing same behaviour. 

Chains_prfLoadCalcEnable(TRUE, TRUE, TRUE);

pCfg->num_uc is 0 in Function Utils_statCollectorPrintCount (links_fw/src/rtos/utils_common/src/utils_stat_collector.c)

would like to under why this variable is 0 ? 

logs 

[0][ 34.202] 34.559934 s: ### CPU [IPU1-0], LinkID [ 68],
[0][ 34.202] 34.560117 s:
[0][ 34.202] 34.560208 s: [ CAPTURE ] Link Statistics,
[0][ 34.202] 34.560361 s: ******************************
[0][ 34.203] 34.560513 s:
[0][ 34.203] 34.560666 s: Elapsed time = 33355 msec
[0][ 34.203] 34.560849 s:
[0][ 34.203] 34.560940 s: New data Recv = 52.7 fps
[0][ 34.203] 34.561123 s: Get Full Buf Cb = 26.5 fps
[0][ 34.203] 34.561276 s: Put Empty Buf Cb = 25.99 fps
[0][ 34.204] 34.561459 s: Driver/Notify Cb = 52.7 fps
[0][ 34.204] 34.561672 s:
[0][ 34.204] 34.561764 s: Input Statistics,
[0][ 34.204] 34.561886 s:
[0][ 34.204] 34.562008 s: CH | In Recv | In Drop | In User Drop | In Process
[0][ 34.204] 34.562221 s: | FPS | FPS | FPS | FPS
[0][ 34.205] 34.562435 s: --------------------------------------------------
[0][ 34.205] 34.562679 s: 0 | 25.99 0. 0 0. 0 25.99
[0][ 34.205] 34.562923 s:
[0][ 34.205] 34.563014 s: Output Statistics,
[0][ 34.205] 34.563167 s:
[0][ 34.205] 34.563258 s: CH | Out | Out | Out Drop | Out User Drop
[0][ 34.206] 34.563441 s: | ID | FPS | FPS | FPS
[0][ 34.206] 34.563685 s: ---------------------------------------------
[0][ 34.206] 34.563899 s: 0 | 0 26. 5 26. 5 0. 0
[0][ 34.206] 34.564112 s:
[0][ 34.206] 34.564204 s: [ CAPTURE ] LATENCY,
[0][ 34.206] 34.564356 s: ********************
[0][ 34.207] 34.564509 s:
[0][ 34.207] 34.564692 s:
[0][ 34.207] 34.564783 s: ### CPU [IPU1-0], LinkID [ 71],
[0][ 34.207] 34.564966 s:
[0][ 34.207] 34.565058 s: [ DISPLAY ] Link Statistics,
[0][ 34.207] 34.565241 s: ******************************
[0][ 34.207] 34.565393 s:
[0][ 34.208] 34.565485 s: Elapsed time = 33359 msec
[0][ 34.208] 34.565698 s:
[0][ 34.208] 34.565790 s: New data Recv = 26.1 fps
[0][ 34.209] 34.566461 s: Driver/Notify Cb = 61.72 fps
[0][ 34.209] 34.566674 s:
[0][ 34.209] 34.566796 s: Input Statistics,
[0][ 34.209] 34.566918 s:
[0][ 34.209] 34.567010 s: CH | In Recv | In Drop | In User Drop | In Process
[0][ 34.209] 34.567223 s: | FPS | FPS | FPS | FPS
[0][ 34.210] 34.567437 s: --------------------------------------------------
[0][ 34.210] 34.567681 s: 0 | 26. 4 0. 0 0. 0 26. 4
[0][ 34.210] 34.567955 s:
[0][ 34.210] 34.568047 s: [ DISPLAY ] LATENCY,
[0][ 34.210] 34.568199 s: ********************
[0][ 34.211] 34.568321 s: Local Link Latency : Avg = 140 us, Min = 91 us, Max = 488 us,
[0][ 34.211] 34.568657 s: Source to Link Latency : Avg = 153 us, Min = 122 us, Max = 518 us,
[0][ 34.211] 34.568962 s:
[0][ 34.211] 34.569053 s: Display UnderFlow Count = 0
[0][ 34.211] 34.569206 s:
[0][ 34.711] 35.068626 s: CPU [ HOST], LinkID [ 92], Link Statistics not available !
[0][ 34.711] 35.068931 s: CPU [ HOST], LinkID [ 0], Link Statistics not available !
[0][ 35.211] 35.568657 s:
[0][ 35.211] 35.568809 s: ### CPU [IPU1-0], LinkID [ 10],
[0][ 35.211] 35.568992 s:
[0][ 35.211] 35.569084 s: [ IPC_IN_0 ] Link Statistics,
[0][ 35.211] 35.569267 s: ******************************
[0][ 35.211] 35.569419 s:
[0][ 35.212] 35.569511 s: Elapsed time = 32517 msec
[0][ 35.212] 35.569846 s:
[0][ 35.212] 35.569938 s: Get Full Buf Cb = 5.68 fps
[0][ 35.212] 35.570121 s: Put Empty Buf Cb = 5.65 fps
[0][ 35.213] 35.570670 s: Driver/Notify Cb = 5.65 fps
[0][ 35.213] 35.570853 s:
[0][ 35.213] 35.570944 s: Input Statistics,
[0][ 35.213] 35.571066 s:
[0][ 35.213] 35.571158 s: CH | In Recv | In Drop | In User Drop | In Process
[0][ 35.213] 35.571371 s: | FPS | FPS | FPS | FPS
[0][ 35.214] 35.571615 s: --------------------------------------------------
[0][ 35.214] 35.571859 s: 0 | 5.68 0. 0 0. 0 5.68
[0][ 35.214] 35.572103 s:
[0][ 35.214] 35.572195 s: Output Statistics,
[0][ 35.214] 35.572347 s:
[0][ 35.215] 35.572439 s: CH | Out | Out | Out Drop | Out User Drop
[0][ 35.215] 35.572652 s: | ID | FPS | FPS | FPS
[0][ 35.215] 35.572866 s: ---------------------------------------------
[0][ 35.215] 35.573079 s: 0 | 0 5.68 0. 0 0. 0
[0][ 35.215] 35.573293 s:
[0][ 35.215] 35.573384 s: [ IPC_IN_0 ] LATENCY,
[0][ 35.216] 35.573537 s: ********************
[0][ 35.216] 35.573933 s: Local Link Latency : Avg = 22 us, Min = 0 us, Max = 213 us,
[0][ 35.217] 35.574239 s: Source to Link Latency : Avg = 338 us, Min = 274 us, Max = 823 us,
[0][ 35.217] 35.574971 s:
[0][ 35.217] 35.575154 s:
[0][ 35.217] 35.575245 s: ### CPU [IPU1-0], LinkID [ 72],
[0][ 35.217] 35.575428 s:
[0][ 35.218] 35.575550 s: [ DISPLAY ] Link Statistics,
[0][ 35.218] 35.575733 s: ******************************
[0][ 35.218] 35.575916 s:
[0][ 35.218] 35.576008 s: Elapsed time = 32523 msec
[0][ 35.218] 35.576191 s:
[0][ 35.218] 35.576282 s: New data Recv = 5.65 fps
[0][ 35.219] 35.576465 s: Driver/Notify Cb = 61.74 fps
[0][ 35.219] 35.576648 s:
[0][ 35.219] 35.576740 s: Input Statistics,
[0][ 35.219] 35.576892 s:
[0][ 35.219] 35.576984 s: CH | In Recv | In Drop | In User Drop | In Process
[0][ 35.219] 35.577197 s: | FPS | FPS | FPS | FPS
[0][ 35.220] 35.577411 s: --------------------------------------------------
[0][ 35.220] 35.577655 s: 0 | 5.68 0. 0 0. 0 5.68
[0][ 35.220] 35.577929 s:
[0][ 35.220] 35.578021 s: [ DISPLAY ] LATENCY,
[0][ 35.220] 35.578173 s: ********************
[0][ 35.220] 35.578295 s: Local Link Latency : Avg = 224 us, Min = 152 us, Max = 732 us,
[0][ 35.221] 35.578631 s: Source to Link Latency : Avg = 543 us, Min = 427 us, Max = 1342 us,
[0][ 35.221] 35.578936 s:
[0][ 35.221] 35.579027 s: Display UnderFlow Count = 0
[0][ 35.221] 35.579180 s:
[0][ 35.236] 35.593759 s: SSC Entering Safe state
[0][ 35.242] 35.599615 s: GPS: eNORMAL_STATE -> eERROR_STATE EventIDReceived:8
[0][ 35.242] 35.599890 s: GPS: eNORMAL_STATE -> eERROR_STATE EventIDReceived:8
[0][ 35.246] 35.603611 s: WDT: state:0 = Entering Safe State !!!
[0][ 35.384] 35.741627 s: SSC state:1 = Moving to Normal State !!!FC:10, NoEvC:0
[0][ 35.392] 35.749679 s: GPS: eERROR_STATE -> eNORMAL_STATE EventIDReceived:4
[0][ 35.392] 35.749954 s: GPS: eERROR_STATE -> eNORMAL_STATE EventIDReceived:4
[0][ 35.411] 35.768681 s: WDT: state:1 = Entering Normal State !!!
[0][ 35.721] 36.078631 s: CPU [ HOST], LinkID [ 93], Link Statistics not available !
[0][ 35.721] 36.078936 s: CPU [ HOST], LinkID [ 1], Link Statistics not available !
[0][ 36.221] 36.578783 s:
[0][ 36.221] 36.578936 s: ### CPU [IPU1-0], LinkID [ 11],
[0][ 36.221] 36.579119 s:
[0][ 36.221] 36.579210 s: [ IPC_IN_1 ] Link Statistics,
[0][ 36.221] 36.579393 s: ******************************
[0][ 36.222] 36.579546 s:
[0][ 36.222] 36.579729 s: Elapsed time = 33527 msec
[0][ 36.222] 36.579912 s:
[0][ 36.222] 36.580034 s: Get Full Buf Cb = 1.28 fps
[0][ 36.222] 36.580217 s: Put Empty Buf Cb = 1.25 fps
[0][ 36.222] 36.580369 s: Driver/Notify Cb = 1.25 fps
[0][ 36.223] 36.580552 s:
[0][ 36.223] 36.580674 s: Input Statistics,
[0][ 36.223] 36.580827 s:
[0][ 36.223] 36.580918 s: CH | In Recv | In Drop | In User Drop | In Process
[0][ 36.223] 36.581132 s: | FPS | FPS | FPS | FPS
[0][ 36.223] 36.581345 s: --------------------------------------------------
[0][ 36.224] 36.581559 s: 0 | 1.28 0. 0 0. 0 1.28
[0][ 36.224] 36.581864 s:
[0][ 36.224] 36.581955 s: Output Statistics,
[0][ 36.224] 36.582077 s:
[0][ 36.224] 36.582169 s: CH | Out | Out | Out Drop | Out User Drop
[0][ 36.224] 36.582382 s: | ID | FPS | FPS | FPS
[0][ 36.225] 36.582565 s: ---------------------------------------------
[0][ 36.225] 36.583175 s: 0 | 0 1.28 0. 0 0. 0
[0][ 36.225] 36.583389 s:
[0][ 36.226] 36.583511 s: [ IPC_IN_1 ] LATENCY,
[0][ 36.226] 36.583663 s: ********************
[0][ 36.226] 36.583816 s: Local Link Latency : Avg = 19 us, Min = 0 us, Max = 31 us,
[0][ 36.226] 36.584121 s: Source to Link Latency : Avg = 323 us, Min = 244 us, Max = 671 us,
[0][ 36.226] 36.584426 s:
[0][ 36.227] 36.584578 s:
[0][ 36.227] 36.584822 s: ### CPU [IPU1-0], LinkID [ 73],
[0][ 36.227] 36.585036 s:
[0][ 36.227] 36.585127 s: [ DISPLAY ] Link Statistics,
[0][ 36.227] 36.585280 s: ******************************
[0][ 36.227] 36.585463 s:
[0][ 36.228] 36.585554 s: Elapsed time = 33533 msec
[0][ 36.228] 36.585768 s:
[0][ 36.228] 36.585859 s: New data Recv = 1.25 fps
[0][ 36.228] 36.586042 s: Driver/Notify Cb = 61.73 fps
[0][ 36.228] 36.586225 s:
[0][ 36.228] 36.586317 s: Input Statistics,
[0][ 36.228] 36.586439 s:
[0][ 36.229] 36.586530 s: CH | In Recv | In Drop | In User Drop | In Process
[0][ 36.229] 36.586774 s: | FPS | FPS | FPS | FPS
[0][ 36.229] 36.586988 s: --------------------------------------------------
[0][ 36.229] 36.587201 s: 0 | 1.28 0. 0 0. 0 1.28
[0][ 36.229] 36.587476 s:
[0][ 36.230] 36.587567 s: [ DISPLAY ] LATENCY,
[0][ 36.230] 36.587811 s: ********************
[0][ 36.230] 36.587933 s: Local Link Latency : Avg = 275 us, Min = 152 us, Max = 580 us,
[0][ 36.230] 36.588238 s: Source to Link Latency : Avg = 585 us, Min = 457 us, Max = 1250 us,
[0][ 36.231] 36.588543 s:
[0][ 36.231] 36.588665 s: Display UnderFlow Count = 0
[0][ 36.231] 36.588818 s:
[0][ 36.231] 36.589001 s:
[0][ 36.231] 36.589092 s: ### CPU [IPU1-0], LinkID [ 69],
[0][ 36.231] 36.589275 s:
[0][ 36.231] 36.589367 s: [ CAPTURE ] Link Statistics,
[0][ 36.232] 36.589550 s: ******************************
[0][ 36.232] 36.589855 s:
[0][ 36.232] 36.589946 s: Elapsed time = 35405 msec
[0][ 36.232] 36.590099 s:
[0][ 36.232] 36.590221 s: New data Recv = 61.71 fps
[0][ 36.233] 36.590404 s: Get Full Buf Cb = 61.74 fps
[0][ 36.233] 36.591105 s: Put Empty Buf Cb = 61.74 fps
[0][ 36.233] 36.591288 s: Driver/Notify Cb = 61.71 fps
[0][ 36.233] 36.591471 s:
[0][ 36.234] 36.591563 s: Input Statistics,
[0][ 36.234] 36.591746 s:
[0][ 36.234] 36.591837 s: CH | In Recv | In Drop | In User Drop | In Process
[0][ 36.234] 36.592051 s: | FPS | FPS | FPS | FPS
[0][ 36.235] 36.592508 s: --------------------------------------------------
[0][ 36.235] 36.592783 s: 0 | 61.74 0. 0 0. 0 61.74
[0][ 36.235] 36.593058 s:
[0][ 36.235] 36.593149 s: Output Statistics,
[0][ 36.235] 36.593271 s:
[0][ 36.235] 36.593393 s: CH | Out | Out | Out Drop | Out User Drop
[0][ 36.236] 36.593576 s: | ID | FPS | FPS | FPS
[0][ 36.236] 36.593820 s: ---------------------------------------------
[0][ 36.236] 36.594003 s: 0 | 0 61.74 0. 0 0. 0
[0][ 36.236] 36.594247 s:
[0][ 36.236] 36.594339 s: [ CAPTURE ] LATENCY,
[0][ 36.237] 36.594491 s: ********************
[0][ 36.237] 36.594857 s:
[0][ 36.237] 36.595040 s:
[0][ 36.237] 36.595132 s: ### CPU [IPU1-0], LinkID [ 49],
[0][ 36.237] 36.595315 s:
[0][ 36.237] 36.595406 s: [ ALG_SWCRC ] Link Statistics,
[0][ 36.238] 36.595589 s: ******************************
[0][ 36.238] 36.595772 s:
[0][ 36.238] 36.595894 s: Elapsed time = 35411 msec
[0][ 36.238] 36.596047 s:
[0][ 36.238] 36.596138 s: New data Recv = 61.70 fps
[0][ 36.238] 36.596321 s:
[0][ 36.238] 36.596413 s: Input Statistics,
[0][ 36.239] 36.596565 s:
[0][ 36.239] 36.596687 s: CH | In Recv | In Drop | In User Drop | In Process
[0][ 36.239] 36.596901 s: | FPS | FPS | FPS | FPS
[0][ 36.239] 36.597114 s: --------------------------------------------------
[0][ 36.239] 36.597328 s: 0 | 61.73 0. 0 0. 0 61.73
[0][ 36.240] 36.597602 s:
[0][ 36.240] 36.597694 s: Output Statistics,
[0][ 36.240] 36.597846 s:
[0][ 36.240] 36.597938 s: CH | Out | Out | Out Drop | Out User Drop
[0][ 36.240] 36.598151 s: | ID | FPS | FPS | FPS
[0][ 36.240] 36.598334 s: ---------------------------------------------
[0][ 36.241] 36.598548 s: 0 | 0 61.73 0. 0 0. 0
[0][ 36.241] 36.598792 s:
[0][ 36.241] 36.598883 s: [ ALG_SWCRC ] LATENCY,
[0][ 36.241] 36.599036 s: ********************
[0][ 36.241] 36.599188 s: Local Link Latency : Avg = 68 us, Min = 0 us, Max = 732 us,
[0][ 36.242] 36.599493 s: Source to Link Latency : Avg = 259 us, Min = 91 us, Max = 8998 us,
[0][ 36.242] 36.599890 s:
[0][ 36.257] 36.614683 s: SSC Entering Safe state
[0][ 36.262] 36.619624 s: GPS: eNORMAL_STATE -> eERROR_STATE EventIDReceived:8
[0][ 36.262] 36.619898 s: GPS: eNORMAL_STATE -> eERROR_STATE EventIDReceived:8
[0][ 36.267] 36.624626 s: WDT: state:0 = Entering Safe State !!!
[0][ 36.405] 36.762673 s: SSC state:1 = Moving to Normal State !!!FC:10, NoEvC:0
[0][ 36.412] 36.769688 s: GPS: eERROR_STATE -> eNORMAL_STATE EventIDReceived:4
[0][ 36.412] 36.769993 s: GPS: eERROR_STATE -> eNORMAL_STATE EventIDReceived:4
[0][ 36.431] 36.788690 s: WDT: state:1 = Entering Normal State !!!
[0][ 37.742] 38.099737 s:
[0][ 37.742] 38.099859 s: CPU [IPU1-0 ] Statistics,
[0][ 37.742] 38.100042 s: *************************
[0][ 37.742] 38.100195 s:
[0][ 37.742] 38.100286 s: LOAD: CPU: 10.8 HWI: 2.3, SWI:1.1, Low Power: 1.3
[0][ 37.743] 38.100591 s:
[0][ 37.743] 38.100835 s: LOAD: TSK: SYSTEM : 1.1
[0][ 37.743] 38.101110 s: LOAD: TSK: DISPLAY0 : 0.5
[0][ 37.743] 38.101323 s: LOAD: TSK: DISPLAY1 : 0.1
[0][ 37.744] 38.101537 s: LOAD: TSK: CAPTURE : 0.8
[0][ 37.744] 38.101750 s: LOAD: TSK: CAPTURE : 0.7
[0][ 37.744] 38.101964 s: LOAD: TSK: ALGORITHM0 : 0.9
[0][ 37.744] 38.102177 s: LOAD: TSK: APP_CTRL : 0.1
[0][ 37.744] 38.102421 s: LOAD: TSK: MISC : 3.2
[0][ 37.745] 38.102604 s:
[0][ 37.745] 38.102726 s: SYSTEM: SW Message Box Msg Pool, Free Msg Count = 1022
[0][ 37.745] 38.102970 s:
[0][ 37.745] 38.103062 s: SYSTEM: Sempahores Objects, 247 of 1050 free
[0][ 37.745] 38.103275 s: SYSTEM: Task Objects , 13 of 100 free
[0][ 37.746] 38.103489 s: SYSTEM: Clock Objects , 98 of 100 free
[0][ 37.746] 38.103733 s: SYSTEM: Hwi Objects , 93 of 100 free
[0][ 37.746] 38.103946 s:
[0][ 37.746] 38.104038 s: SYSTEM: Heap = LOCAL_DDR @ 0x00000000, Total size = 262144 B (256 KB), Free size = 254224 B (248 KB)
[0][ 37.747] 38.104434 s: SYSTEM: Heap = SR_OCMC @ 0x00000000, Total size = 0 B (0 KB), Free size = 0 B (0 KB)
[0][ 37.747] 38.104831 s: SYSTEM: Heap = SR_DDR_CACHED @ 0x86203000, Total size = 67108864 B (64 MB), Free size = 51441664 B (49 MB)
[0][ 37.747] 38.105258 s: SYSTEM: Heap = SR_DDR_NON_CACHED @ 0xa0100000, Total size = 1047168 B (0 MB), Free size = 1047168 B (0 MB)
[0][ 37.748] 38.105654 s:
[0][ 37.748] 38.105807 s:
[0][ 37.748] 38.105929 s: UTILS_PRCM_STATS: Current Temperature,
[0][ 37.748] 38.106112 s:
[0][ 37.748] 38.106203 s: Voltage Rail || Curr Temp Min - Max
[0][ 37.748] 38.106417 s: ---------------------------------------------------------
[0][ 37.749] 38.106813 s: PMHAL_PRCM_VD_MPU || [48. 0 , 48.400]
[0][ 37.749] 38.107088 s: PMHAL_PRCM_VD_CORE || [47.600 , 48. 0]
[0][ 37.749] 38.107332 s: PMHAL_PRCM_VD_IVAHD || [47.600 , 48. 0]
[0][ 37.750] 38.107576 s: PMHAL_PRCM_VD_DSPEVE || [47.600 , 48. 0]
[0][ 37.750] 38.107850 s: PMHAL_PRCM_VD_GPU || [47.600 , 48. 0]
[0][ 37.750] 38.108064 s:
[0][ 37.750] 38.108155 s: ============================================================================
[0][ 37.751] 38.108460 s: Name | Bus (mV) | Res (mOhm) | Shunt (uV) | Current (mA) | Power (mW)
[0][ 37.751] 38.108735 s: ----------------------------------------------------------------------------
[0][ 37.751] 38.109436 s: UTILS_PRCM_STATS: Reading the regulator data failed
[0][ 37.752] 38.109833 s: UTILS_PRCM_STATS: PM INA226 Power Read Failed !!
[0][ 37.752] 38.110107 s:
[0][ 37.752] 38.110199 s: Statistics Collector,
[0][ 37.752] 38.110351 s:
[0][ 37.752] 38.110443 s: STATISTIC Avg Data Peak Data
[0][ 37.753] 38.110687 s: COLLECTOR MB/s MB/s
[0][ 37.753] 38.110931 s: --------------------------------------------------
[0][ 37.853] 38.210699 s:

Hi,

You mentioned "I have tested with Fresh installation with  SDK and still we are not able to see statistics"

Can I know which use-case you ran?

Thanks

Gaviraju

Hi Gaviraju,

we are running rtos custom usecase. 

Path: apps/src/rtos/usecases/early_capture_lateweston_2s_2c_dsswb

UseCase: early_capture_lateweston_2s_2c_dsswb

Capture -> Display_Video
DispDistSrc_weston -> Display_m4
DispDistSrc_vid3 -> Display_vid3
Capture_dsswb -> Alg_SwCrc

Hi,

From the log, I noticed you are receiving  an error as below

[0][ 0.650] 1.009272 s: Assertion @ Line: 1029 in utils_dma.c: Utils_dmaParamSetCheck(pParamSet) == FVID2_SOK : failed !!!
[0][ 0.650] Assertion @ Line: 1029 in utils_dma.c: Utils_dmaParamSetCheck(pParamSet) == FVID2_SOK : failed !!!

Please try to resolve this & let's see the result

Hi

After making below changes I have collected latest logs but Unable to see that error.

Software Change:

-    Chains_prfLoadCalcEnable(TRUE, FALSE, FALSE);
+    Chains_prfLoadCalcEnable(TRUE, TRUE, TRUE);

Error:

[0][ 0.650] 1.009272 s: Assertion @ Line: 1029 in utils_dma.c: Utils_dmaParamSetCheck(pParamSet) == FVID2_SOK : failed !!!
[0][ 0.650] Assertion @ Line: 1029 in utils_dma.c: Utils_dmaParamSetCheck(pParamSet) == FVID2_SOK : failed !!!

root@mmt2020-a880:~# echo "CMD 21" > /tmp/cfifo
root@mmt2020-a880:~#
root@mmt2020-a880:~# cat /sys/kernel/debug/remoteproc/remoteproc0/trace0
[0][      0.000] 18 Resource entries at 0x3000
[0][      0.000]      0.372872 s:  SYSTEM: System Common Init in progress !!!
[0][      0.010]      0.382480 s:  SYSTEM: IPC init in progress !!!
[0][      0.010]      0.382693 s:  SYSTEM: Notify init done !!!
[0][      0.012]      0.384493 s:  SYSTEM: MsgQ init done !!!
[0][      0.013]      0.385499 s: RpmsgInit in progress...
[0][      0.013]      0.385713 s:  SYSTEM: IPC init DONE !!!
[0][      0.019]      0.392027 s:  SYSTEM: System Common Init Done !!!
[0][      0.020]      0.392301 s:  SYSTEM: System Init in progress !!!
[0][      0.020]      0.392484 s:  SYSTEM: BSP Common Init in progress !!!
[0][      0.020]      0.392667 s:  SYSTEM: BSP Common Init Done !!!
[0][      0.020]      0.392820 s:  SYSTEM: BSP Platform Init in progress !!!
[0][      0.020]      0.393033 s:  SYSTEM: BSP Platform Init Done !!!
[0][      0.021]      0.393186 s:  SYSTEM: FVID2 Init in progress !!!
[0][      0.021]      0.393460 s:  SYSTEM: FVID2 Init Done !!!
[0][      0.021]      0.393643 s:  SYSTEM: VPS Init in progress !!!
[0][      0.021]      0.393796 s:  SYSTEM: VPDMA Descriptor Memory Address translation ENABLED [0x80000000 -> 0x80000000]
[0][      0.024]      0.396876 s: *** VPDMA Firmware Loading... ***
[0][      0.025]      0.397120 s: VPDMA Firmware Address = 0xa034a500
[0][      0.025]      0.397334 s: VPDMA Load Address     = 0x4897d004
[0][      0.025]      0.397547 s: VPDMA Firmware Version = 0x4d0001b8
[0][      0.025]      0.397761 s: VPDMA List Busy Status = 0x00000000
[0][      0.025]      0.397944 s: *** VPDMA Firmware Load Success ***
[0][      0.025]
[0][      0.026]      0.398401 s: *** VPDMA Firmware Loading... ***
[0][      0.026]      0.398584 s: VPDMA Firmware Address = 0xa034a500
[0][      0.026]      0.398767 s: VPDMA Load Address     = 0x4899d004
[0][      0.026]      0.398981 s: VPDMA Firmware Version = 0x4d0001b8
[0][      0.027]      0.399164 s: VPDMA List Busy Status = 0x00000000
[0][      0.027]      0.399377 s: *** VPDMA Firmware Load Success ***
[0][      0.027]
[0][      0.027]      0.399835 s: *** VPDMA Firmware Loading... ***
[0][      0.027]      0.400018 s: VPDMA Firmware Address = 0xa034a500
[0][      0.028]      0.400201 s: VPDMA Load Address     = 0x489bd004
[0][      0.028]      0.400445 s: VPDMA Firmware Version = 0x4d0001b8
[0][      0.028]      0.400628 s: VPDMA List Busy Status = 0x00000000
[0][      0.028]      0.400811 s: *** VPDMA Firmware Load Success ***
[0][      0.028]
[0][      0.084]      0.456749 s:  SYSTEM: VPS Init Done !!!
[0][      0.085]      0.457725 s:  UTILS: DMA: HWI Create for INT34 !!!
[0][      0.086]      0.458091 s:  SYSTEM: SW Message Box Msg Pool, Free Msg Count = 1024
[0][      0.086]      0.458396 s:  SYSTEM: Heap = LOCAL_DDR            @ 0x00000000, Total size = 262144 B (256 KB), Free size = 260992 B (254 KB)
[0][      0.086]      0.458793 s:  SYSTEM: Heap = SR_OCMC              @ 0x00000000, Total size = 0 B (0 KB), Free size = 0 B (0 KB)
[0][      0.087]      0.459159 s:  SYSTEM: Heap = SR_DDR_CACHED        @ 0x86203000, Total size = 67108864 B (64 MB), Free size = 67108864 B (64 MB)
[0][      0.087]      0.459586 s:  SYSTEM: Heap = SR_DDR_NON_CACHED    @ 0xa0100000, Total size = 1047168 B (0 MB), Free size = 1047168 B (0 MB)
[0][      0.087]      0.459982 s:  SYSTEM: Initializing Links !!!
[0][      0.333]      0.706002 s: CameraControllerMid_tsk: Task started
[0][      0.338]      0.711035 s:  SYSTEM: Initializing Links ... DONE !!!
[0][      0.350]      0.712285 s:  CHAINS: Application Started !!!     0.722656 s:  BOARD: Board Init in progress !!!
[0][      0.351]      0.723235 s:  BOARD: Board Init Done !!!
[0][      0.359]      0.731592 s:
[0][      0.359]      0.732050 s: Display width 800, Display height 480
[0][      0.360]      0.732294 s:  CHAINS: Sensor create in progress
[0][      0.360]      0.732477 s:  VIDEO_DECODER: ********** CREATE **********
[0][      0.371]      0.743762 s: I2C write Passed
[0][      0.421]      0.793387 s: I2C write Passed
[0][      0.422]      0.794393 s: I2C write Passed
[0][      0.423]      0.795430 s: I2C write Passed
[0][      0.439]      0.811870 s: I2C1: Passed for address 0xc !!!
[0][      0.489]      0.861739 s: I2C1: Passed for address 0x35 !!!
[0][      0.579]      0.951534 s: EarlyCameraMonitor_init: Init
[0][      0.581]      0.953333 s:  CHAINS: Sensor create ... DONE !!!
[0][      0.583]      0.955194 s:  CAPTURE: Create in progress !!!
[0][      0.583]      0.955590 s:  CAPTURE: VIP2 Slice0 PortA capture mode is [ 8-bit] !!!
[0][      0.721]      1.093363 s:  Gpio1:0x0, Gpio2: 0x0
[0][      0.737]      1.109680 s:  CAPTURE: Create Done !!!
[0][      0.737]      1.110077 s:  DISPLAY: Create in progress !!!
[0][      0.738]      1.110778 s:  DISPLAY: Create Done !!!
[0][      0.738]      1.111083 s:  CAPTURE: Create in progress !!!
[0][      0.803]      1.176081 s:  CAPTURE: Create Done !!!
[0][      0.804]      1.176538 s:  ALGORITHM: Create in progress (algId = 14) !!!
[0][      0.804]      1.176996 s:  ALGORITHM: Create Done (algId = 14) !!!
[0][      0.805]      1.177362 s:  CAPTURE: Start in progress !!!
[0][      0.805]      1.177636 s:  CAPTURE: Start Done !!!
[0][      0.805]      1.177850 s:  DISPLAY: Start in progress !!!
[0][      0.805]      1.178033 s:  DISPLAY: Start Done !!!
[0][      0.806]      1.178246 s:  CAPTURE: Start in progress !!!
[0][      0.808]      1.180534 s:  CAPTURE: Start Done !!!
[0][      0.821]      1.193283 s:  Gpio1:0x0, Gpio2: 0x0
[0][      0.921]      1.293295 s:  Gpio1:0x0, Gpio2: 0x0
[0][      1.021]      1.393308 s:  Gpio1:0x0, Gpio2: 0x0
[0][      1.121]      1.493289 s:  Gpio1:0x0, Gpio2: 0x0
[0][      1.221]      1.593302 s:  Gpio1:0x0, Gpio2: 0x0
[0][      1.321]      1.693314 s:  Gpio1:0x0, Gpio2: 0x0
[0][      1.421]      1.793295 s:  Gpio1:0x0, Gpio2: 0x0
[0][      1.459]      1.831879 s: Latency Monitoring Started
[0][      1.466]      1.838528 s:  WDT Tsk Started
[0][      1.466]      1.838711 s: SafeStatetsk Monitoring Started
[0][      1.466]      1.838925 s: Freeze Monitoring Started
[0][      1.521]      1.893338 s:  Gpio1:0x0, Gpio2: 0x0
[0][      1.621]      1.993320 s:  Gpio1:0x0, Gpio2: 0x0
[0][      1.721]      2.093332 s:  Gpio1:0x0, Gpio2: 0x0
[0][      1.787]      2.159366 s: Connection established with HLOS, calling rpmsgInit
[0][      1.787] registering rpmsg-proto:rpmsg-proto service on 80 with HOST
[0][      1.821]      2.193314 s:  Gpio1:0x0, Gpio2: 0x0
[0][      1.921]      2.293326 s:  Gpio1:0x0, Gpio2: 0x0
[0][      2.021]      2.393338 s:  Gpio1:0x0, Gpio2: 0x0
[0][      2.121]      2.493320 s:  Gpio1:0x0, Gpio2: 0x0
[0][      2.221]      2.593332 s:  Gpio1:0x0, Gpio2: 0x0
[0][      2.321]      2.693344 s:  Gpio1:0x0, Gpio2: 0x0
[0][      2.421]      2.793326 s:  Gpio1:0x0, Gpio2: 0x0
[0][      2.521]      2.893338 s:  Gpio1:0x0, Gpio2: 0x0
[0][      2.582]      2.954797 s:  ** LINUX INIT COMPLETE
[0][      2.596]      2.968584 s:  IPC_IN_0   : Create in progress !!!
[0][      2.597]      2.969895 s:  IPC_IN_0   : Create Done !!!
[0][      2.598]      2.970231 s:  DISPLAY: Create in progress !!!
[0][      2.598]      2.970963 s:  DISPLAY: Create Done !!!
[0][      2.603]      2.975782 s:  IPC_IN_1   : Create in progress !!!
[0][      2.605]      2.977612 s:  IPC_IN_1   : Create Done !!!
[0][      2.605]      2.977917 s:  DISPLAY: Create in progress !!!
[0][      2.606]      2.978679 s:  DISPLAY: Create Done !!!
[0][      2.606]      2.978954 s:  DISPLAY: Start in progress !!!
[0][      2.606]      2.979137 s:  DISPLAY: Start Done !!!
[0][      2.608]      2.980875 s:  DISPLAY: Start in progress !!!
[0][      2.608]      2.981089 s:  DISPLAY: Start Done !!!
[0][      2.621]      2.993350 s:  Gpio1:0x0, Gpio2: 0x0
[0][      2.721]      3.093363 s:  Gpio1:0x0, Gpio2: 0x0
[0][      2.821]      3.193558 s:  Gpio1:0x0, Gpio2: 0x0
[0][      2.921]      3.293356 s:  Gpio1:0x0, Gpio2: 0x0
[0][      3.021]      3.393369 s:  Gpio1:0x0, Gpio2: 0x0
[0][      3.121]      3.493350 s:  Gpio1:0x0, Gpio2: 0x0
[0][      3.221]      3.593363 s:  Gpio1:0x0, Gpio2: 0x0
[0][      3.321]      3.693375 s:  Gpio1:0x0, Gpio2: 0x0
[0][      3.421]      3.793417 s:  Gpio1:0x0, Gpio2: 0x0
[0][      3.521]      3.893369 s:  Gpio1:0x0, Gpio2: 0x0
[0][      3.621]      3.993381 s:  Gpio1:0x0, Gpio2: 0x0
[0][      3.721]      4.093363 s:  Gpio1:0x0, Gpio2: 0x0
[0][      3.821]      4.193375 s:  Gpio1:0x0, Gpio2: 0x0
[0][      3.921]      4.293417 s:  Gpio1:0x0, Gpio2: 0x0
[0][      4.021]      4.393399 s:  Gpio1:0x0, Gpio2: 0x0
[0][      4.121]      4.493381 s:  Gpio1:0x0, Gpio2: 0x0
[0][      4.221]      4.593393 s:  Gpio1:0x0, Gpio2: 0x0
[0][      4.321]      4.693405 s:  Gpio1:0x0, Gpio2: 0x0
[0][      4.421]      4.793417 s:  Gpio1:0x0, Gpio2: 0x0
[0][      4.521]      4.893399 s:  Gpio1:0x0, Gpio2: 0x0
[0][      4.621]      4.993381 s:  Gpio1:0x0, Gpio2: 0x0
[0][      4.721]      5.093393 s:  Gpio1:0x0, Gpio2: 0x0
[0][      4.821]      5.193405 s:  Gpio1:0x0, Gpio2: 0x0
[0][      4.921]      5.293417 s:  Gpio1:0x0, Gpio2: 0x0
[0][      5.021]      5.393430 s:  Gpio1:0x0, Gpio2: 0x0
[0][      5.121]      5.493442 s:  Gpio1:0x0, Gpio2: 0x0
[0][      5.165]      5.537729 s:  Gpio1:0x0, Gpio2: 0x0
[0][      5.165]      5.537943 s: RecevdDelay: 59 CounterMax 59
[0][      5.165]      5.538126 s: EarlyCameraMonitor_tsk exited
[0][      5.175]      5.547794 s: FD Disabled
[0][      5.175]      5.547947 s: CamController command 1
[0][    308.491]    308.871622 s:  [CAPTURE] Buffer Q Status,
[0][    308.491]    308.871805 s:  Empty Q : Elements in Q =   0, Write Idx =   0, Read Idx =   0
[0][    308.491]    308.872079 s:  Full  Q : Elements in Q =   0, Write Idx = 334, Read Idx = 334
[0][    310.491]    310.871835 s:  [CAPTURE] Buffer Q Status,
[0][    310.491]    310.872018 s:  Empty Q : Elements in Q =   0, Write Idx =   0, Read Idx =   0
[0][    310.491]    310.872293 s:  Full  Q : Elements in Q =   0, Write Idx = 304, Read Idx = 304
[0][    310.992]    311.372811 s:
[0][    310.992]    311.372964 s:  ### CPU [IPU1-0], LinkID [ 68],
[0][    310.992]    311.373147 s:
[0][    310.992]    311.373269 s:  [ CAPTURE ] Link Statistics,
[0][    310.992]    311.373421 s:  ******************************
[0][    310.993]    311.373574 s:
[0][    310.993]    311.373726 s:  Elapsed time       = 310171 msec
[0][    310.993]    311.373909 s:
[0][    310.993]    311.374001 s:  New data Recv      =  52.8 fps
[0][    310.993]    311.374184 s:  Get Full Buf Cb    =  26.4 fps
[0][    310.993]    311.374336 s:  Put Empty Buf Cb   =  26.4 fps
[0][    310.994]    311.374519 s:  Driver/Notify Cb   =  52.8 fps
[0][    310.994]    311.374733 s:
[0][    310.994]    311.374824 s:  Input Statistics,
[0][    310.994]    311.374977 s:
[0][    310.994]    311.375068 s:  CH | In Recv | In Drop | In User Drop | In Process
[0][    310.994]    311.375282 s:     | FPS     | FPS     | FPS          | FPS  
[0][    310.995]    311.375495 s:  --------------------------------------------------
[0][    310.995]    311.375739 s:   0 |  26. 4      0. 0      0. 0          26. 4
[0][    310.995]    311.376014 s:
[0][    310.995]    311.376105 s:  Output Statistics,
[0][    310.995]    311.376227 s:
[0][    310.995]    311.376319 s:  CH | Out | Out     | Out Drop | Out User Drop
[0][    310.996]    311.376532 s:     | ID  | FPS     | FPS      | FPS          
[0][    310.996]    311.376807 s:  ---------------------------------------------
[0][    310.996]    311.377020 s:   0 |  0     26. 4    26. 3      0. 0
[0][    310.996]    311.377264 s:
[0][    310.996]    311.377356 s:  [ CAPTURE ] LATENCY,
[0][    310.996]    311.377508 s:  ********************
[0][    310.997]    311.377722 s:
[0][    310.997]    311.377905 s:
[0][    310.997]    311.377996 s:  ### CPU [IPU1-0], LinkID [ 71],
[0][    310.997]    311.378179 s:
[0][    310.997]    311.378271 s:  [ DISPLAY ] Link Statistics,
[0][    310.997]    311.378423 s:  ******************************
[0][    310.998]    311.378606 s:
[0][    310.998]    311.378881 s:  Elapsed time       = 310176 msec
[0][    310.998]    311.379064 s:
[0][    310.998]    311.379155 s:  New data Recv      =  26.4 fps
[0][    310.998]    311.379338 s:  Driver/Notify Cb   =  61.73 fps
[0][    310.998]    311.379521 s:
[0][    310.999]    311.379613 s:  Input Statistics,
[0][    310.999]    311.379765 s:
[0][    310.999]    311.379887 s:  CH | In Recv | In Drop | In User Drop | In Process
[0][    310.999]    311.380101 s:     | FPS     | FPS     | FPS          | FPS  
[0][    310.999]    311.380314 s:  --------------------------------------------------
[0][    311.000]    311.380528 s:   0 |  26. 4      0. 0      0. 0          26. 4
[0][    311.000]    311.380802 s:
[0][    311.000]    311.380894 s:  [ DISPLAY ] LATENCY,
[0][    311.000]    311.381046 s:  ********************
[0][    311.000]    311.381199 s:  Local Link Latency     : Avg =    141 us, Min =     91 us, Max =    824 us,
[0][    311.001]    311.381504 s:  Source to Link Latency : Avg =    157 us, Min =    122 us, Max =    824 us,
[0][    311.001]    311.381839 s:
[0][    311.001]    311.381931 s:  Display UnderFlow Count = 0
[0][    311.001]    311.382083 s:
[0][    311.501]    311.881687 s:  CPU [    HOST], LinkID [ 92], Link Statistics not available !
[0][    311.501]    311.882388 s:  CPU [    HOST], LinkID [  0], Link Statistics not available !
[0][    312.002]    312.382907 s:
[0][    312.002]    312.383059 s:  ### CPU [IPU1-0], LinkID [ 10],
[0][    312.002]    312.383242 s:
[0][    312.002]    312.383334 s:  [ IPC_IN_0 ] Link Statistics,
[0][    312.002]    312.383517 s:  ******************************
[0][    312.003]    312.383700 s:
[0][    312.003]    312.383791 s:  Elapsed time       = 309394 msec
[0][    312.003]    312.383974 s:
[0][    312.003]    312.384066 s:  Get Full Buf Cb    =   0.65 fps
[0][    312.003]    312.384249 s:  Put Empty Buf Cb   =   0.64 fps
[0][    312.003]    312.384432 s:  Driver/Notify Cb   =   0.64 fps
[0][    312.004]    312.384615 s:
[0][    312.004]    312.384737 s:  Input Statistics,
[0][    312.004]    312.384889 s:
[0][    312.004]    312.384981 s:  CH | In Recv | In Drop | In User Drop | In Process
[0][    312.004]    312.385194 s:     | FPS     | FPS     | FPS          | FPS  
[0][    312.004]    312.385408 s:  --------------------------------------------------
[0][    312.005]    312.385621 s:   0 |   0.65      0. 0      0. 0           0.65
[0][    312.005]    312.385926 s:
[0][    312.005]    312.386018 s:  Output Statistics,
[0][    312.005]    312.386140 s:
[0][    312.005]    312.386231 s:  CH | Out | Out     | Out Drop | Out User Drop
[0][    312.005]    312.386445 s:     | ID  | FPS     | FPS      | FPS          
[0][    312.006]    312.386750 s:  ---------------------------------------------
[0][    312.006]    312.386994 s:   0 |  0      0.65     0. 0      0. 0
[0][    312.006]    312.387207 s:
[0][    312.006]    312.387329 s:  [ IPC_IN_0 ] LATENCY,
[0][    312.006]    312.387451 s:  ********************
[0][    312.007]    312.387604 s:  Local Link Latency     : Avg =     20 us, Min =      0 us, Max =     31 us,
[0][    312.007]    312.387970 s:  Source to Link Latency : Avg =    346 us, Min =    244 us, Max =   4301 us,
[0][    312.007]    312.388275 s:
[0][    312.007]    312.388458 s:
[0][    312.008]    312.388549 s:  ### CPU [IPU1-0], LinkID [ 72],
[0][    312.008]    312.388763 s:
[0][    312.008]    312.388854 s:  [ DISPLAY ] Link Statistics,
[0][    312.008]    312.389038 s:  ******************************
[0][    312.008]    312.389526 s:
[0][    312.009]    312.389648 s:  Elapsed time       = 309398 msec
[0][    312.009]    312.389861 s:
[0][    312.009]    312.389953 s:  New data Recv      =   0.64 fps
[0][    312.009]    312.390136 s:  Driver/Notify Cb   =  61.73 fps
[0][    312.009]    312.390319 s:
[0][    312.009]    312.390410 s:  Input Statistics,
[0][    312.009]    312.390563 s:
[0][    312.010]    312.390654 s:  CH | In Recv | In Drop | In User Drop | In Process
[0][    312.010]    312.390898 s:     | FPS     | FPS     | FPS          | FPS  
[0][    312.010]    312.391112 s:  --------------------------------------------------
[0][    312.010]    312.391325 s:   0 |   0.65      0. 0      0. 0           0.65
[0][    312.010]    312.391569 s:
[0][    312.011]    312.391691 s:  [ DISPLAY ] LATENCY,
[0][    312.011]    312.391844 s:  ********************
[0][    312.011]    312.391996 s:  Local Link Latency     : Avg =    220 us, Min =    152 us, Max =    854 us,
[0][    312.011]    312.392301 s:  Source to Link Latency : Avg =    550 us, Min =    396 us, Max =   4575 us,
[0][    312.012]    312.392576 s:
[0][    312.012]    312.392942 s:  Display UnderFlow Count = 0
[0][    312.012]    312.393094 s:
[0][    312.512]    312.892728 s:  CPU [    HOST], LinkID [ 93], Link Statistics not available !
[0][    312.512]    312.893033 s:  CPU [    HOST], LinkID [  1], Link Statistics not available !
[0][    313.012]    313.392789 s:
[0][    313.012]    313.392942 s:  ### CPU [IPU1-0], LinkID [ 11],
[0][    313.012]    313.393125 s:
[0][    313.012]    313.393247 s:  [ IPC_IN_1 ] Link Statistics,
[0][    313.013]    313.393765 s:  ******************************
[0][    313.013]    313.393979 s:
[0][    313.013]    313.394070 s:  Elapsed time       = 310403 msec
[0][    313.013]    313.394253 s:
[0][    313.013]    313.394345 s:  Get Full Buf Cb    =   0.17 fps
[0][    313.013]    313.394528 s:  Put Empty Buf Cb   =   0.16 fps
[0][    313.014]    313.394741 s:  Driver/Notify Cb   =   0.16 fps
[0][    313.014]    313.394924 s:
[0][    313.014]    313.395016 s:  Input Statistics,
[0][    313.014]    313.395138 s:
[0][    313.014]    313.395229 s:  CH | In Recv | In Drop | In User Drop | In Process
[0][    313.014]    313.395443 s:     | FPS     | FPS     | FPS          | FPS  
[0][    313.015]    313.395656 s:  --------------------------------------------------
[0][    313.015]    313.395900 s:   0 |   0.17      0. 0      0. 0           0.17
[0][    313.015]    313.396175 s:
[0][    313.015]    313.396266 s:  Output Statistics,
[0][    313.015]    313.396388 s:
[0][    313.015]    313.396510 s:  CH | Out | Out     | Out Drop | Out User Drop
[0][    313.016]    313.396937 s:     | ID  | FPS     | FPS      | FPS          
[0][    313.016]    313.397151 s:  ---------------------------------------------
[0][    313.016]    313.397364 s:   0 |  0      0.17     0. 0      0. 0
[0][    313.016]    313.397608 s:
[0][    313.017]    313.397761 s:  [ IPC_IN_1 ] LATENCY,
[0][    313.017]    313.397913 s:  ********************
[0][    313.017]    313.398066 s:  Local Link Latency     : Avg =     29 us, Min =      0 us, Max =    275 us,
[0][    313.017]    313.398371 s:  Source to Link Latency : Avg =    317 us, Min =    244 us, Max =    641 us,
[0][    313.018]    313.398676 s:
[0][    313.018]    313.399133 s:
[0][    313.018]    313.399255 s:  ### CPU [IPU1-0], LinkID [ 73],
[0][    313.018]    313.399438 s:
[0][    313.018]    313.399530 s:  [ DISPLAY ] Link Statistics,
[0][    313.019]    313.399743 s:  ******************************
[0][    313.019]    313.399896 s:
[0][    313.019]    313.399987 s:  Elapsed time       = 310409 msec
[0][    313.019]    313.400170 s:
[0][    313.019]    313.400262 s:  New data Recv      =   0.16 fps
[0][    313.019]    313.400445 s:  Driver/Notify Cb   =  61.74 fps
[0][    313.020]    313.400628 s:
[0][    313.020]    313.400841 s:  Input Statistics,
[0][    313.020]    313.400963 s:
[0][    313.020]    313.401055 s:  CH | In Recv | In Drop | In User Drop | In Process
[0][    313.020]    313.401268 s:     | FPS     | FPS     | FPS          | FPS  
[0][    313.020]    313.401482 s:  --------------------------------------------------
[0][    313.021]    313.401756 s:   0 |   0.17      0. 0      0. 0           0.17
[0][    313.021]    313.402031 s:
[0][    313.021]    313.402122 s:  [ DISPLAY ] LATENCY,
[0][    313.021]    313.402244 s:  ********************
[0][    313.021]    313.402397 s:  Local Link Latency     : Avg =    370 us, Min =    153 us, Max =    885 us,
[0][    313.022]    313.402732 s:  Source to Link Latency : Avg =    668 us, Min =    488 us, Max =   1251 us,
[0][    313.022]    313.403007 s:
[0][    313.022]    313.403129 s:  Display UnderFlow Count = 0
[0][    313.022]    313.403281 s:
[0][    313.022]    313.403464 s:
[0][    313.023]    313.403556 s:  ### CPU [IPU1-0], LinkID [ 69],
[0][    313.023]    313.403769 s:
[0][    313.023]    313.403861 s:  [ CAPTURE ] Link Statistics,
[0][    313.023]    313.404013 s:  ******************************
[0][    313.023]    313.404196 s:
[0][    313.023]    313.404288 s:  Elapsed time       = 312206 msec
[0][    313.023]    313.404471 s:
[0][    313.024]    313.404562 s:  New data Recv      =  61.73 fps
[0][    313.024]    313.404776 s:  Get Full Buf Cb    =  61.73 fps
[0][    313.024]    313.404928 s:  Put Empty Buf Cb   =  61.73 fps
[0][    313.024]    313.405111 s:  Driver/Notify Cb   =  61.73 fps
[0][    313.024]    313.405294 s:
[0][    313.024]    313.405416 s:  Input Statistics,
[0][    313.024]    313.405538 s:
[0][    313.025]    313.405630 s:  CH | In Recv | In Drop | In User Drop | In Process
[0][    313.025]    313.405874 s:     | FPS     | FPS     | FPS          | FPS  
[0][    313.025]    313.406087 s:  --------------------------------------------------
[0][    313.025]    313.406301 s:   0 |  61.73      0. 0      0. 0          61.73
[0][    313.025]    313.406575 s:
[0][    313.026]    313.406667 s:  Output Statistics,
[0][    313.026]    313.407063 s:
[0][    313.026]    313.407185 s:  CH | Out | Out     | Out Drop | Out User Drop
[0][    313.026]    313.407399 s:     | ID  | FPS     | FPS      | FPS          
[0][    313.027]    313.407582 s:  ---------------------------------------------
[0][    313.027]    313.407857 s:   0 |  0     61.74     0. 0      0. 0
[0][    313.027]    313.408101 s:
[0][    313.027]    313.408192 s:  [ CAPTURE ] LATENCY,
[0][    313.027]    313.408345 s:  ********************
[0][    313.027]    313.408497 s:
[0][    313.028]    313.408650 s:
[0][    313.028]    313.408802 s:  ### CPU [IPU1-0], LinkID [ 49],
[0][    313.028]    313.408985 s:
[0][    313.028]    313.409077 s:  [ ALG_SWCRC ] Link Statistics,
[0][    313.028]    313.409260 s:  ******************************
[0][    313.028]    313.409412 s:
[0][    313.029]    313.409504 s:  Elapsed time       = 312211 msec
[0][    313.029]    313.410236 s:
[0][    313.029]    313.410358 s:  New data Recv      =  61.73 fps
[0][    313.029]    313.410541 s:
[0][    313.030]    313.410632 s:  Input Statistics,
[0][    313.030]    313.410815 s:
[0][    313.030]    313.410907 s:  CH | In Recv | In Drop | In User Drop | In Process
[0][    313.030]    313.411120 s:     | FPS     | FPS     | FPS          | FPS  
[0][    313.030]    313.411334 s:  --------------------------------------------------
[0][    313.031]    313.411547 s:   0 |  61.73      0. 0      0. 0          61.73
[0][    313.031]    313.411852 s:
[0][    313.031]    313.411944 s:  Output Statistics,
[0][    313.031]    313.412066 s:
[0][    313.031]    313.412157 s:  CH | Out | Out     | Out Drop | Out User Drop
[0][    313.031]    313.412371 s:     | ID  | FPS     | FPS      | FPS          
[0][    313.032]    313.412584 s:  ---------------------------------------------
[0][    313.032]    313.412798 s:   0 |  0     61.73     0. 0      0. 0
[0][    313.032]    313.413042 s:
[0][    313.032]    313.413133 s:  [ ALG_SWCRC ] LATENCY,
[0][    313.032]    313.413286 s:  ********************
[0][    313.032]    313.413438 s:  Local Link Latency     : Avg =     69 us, Min =      0 us, Max =    549 us,
[0][    313.033]    313.413774 s:  Source to Link Latency : Avg =    246 us, Min =     91 us, Max =   3934 us,
[0][    313.033]    313.414048 s:
[0][    314.533]    314.913774 s:
[0][    314.533]    314.913926 s:  CPU [IPU1-0 ] Statistics,
[0][    314.533]    314.914079 s:  *************************
[0][    314.533]    314.914231 s:
[0][    314.533]    314.914323 s:  LOAD: CPU: 9.0 HWI: 2.3, SWI:1.1, Low Power: 1.2
[0][    314.533]    314.914628 s:
[0][    314.534]    314.914719 s:  LOAD: TSK: SYSTEM              : 0.1
[0][    314.534]    314.915024 s:  LOAD: TSK: DISPLAY0            : 0.6
[0][    314.534]    314.915268 s:  LOAD: TSK: CAPTURE             : 0.5
[0][    314.535]    314.915451 s:  LOAD: TSK: CAPTURE             : 0.6
[0][    314.536]    314.916610 s:  LOAD: TSK: ALGORITHM0          : 0.9
[0][    314.536]    314.916976 s:  LOAD: TSK: MISC                : 2.9
[0][    314.536]    314.917159 s:
[0][    314.536]    314.917281 s:  SYSTEM: SW Message Box Msg Pool, Free Msg Count = 1022
[0][    314.536]    314.917495 s:
[0][    314.537]    314.917586 s:  SYSTEM: Sempahores Objects,  247 of 1050 free
[0][    314.537]    314.917891 s:  SYSTEM: Task Objects      ,   13 of  100 free
[0][    314.537]    314.918105 s:  SYSTEM: Clock Objects     ,   98 of  100 free
[0][    314.537]    314.918349 s:  SYSTEM: Hwi Objects       ,   93 of  100 free
[0][    314.537]    314.918562 s:
[0][    314.538]    314.918654 s:  SYSTEM: Heap = LOCAL_DDR            @ 0x00000000, Total size = 262144 B (256 KB), Free size = 254224 B (248 KB)
[0][    314.538]    314.919081 s:  SYSTEM: Heap = SR_OCMC              @ 0x00000000, Total size = 0 B (0 KB), Free size = 0 B (0 KB)
[0][    314.539]    314.919447 s:  SYSTEM: Heap = SR_DDR_CACHED        @ 0x86203000, Total size = 67108864 B (64 MB), Free size = 51441664 B (49 MB)
[0][    314.539]    314.920179 s:  SYSTEM: Heap = SR_DDR_NON_CACHED    @ 0xa0100000, Total size = 1047168 B (0 MB), Free size = 1047168 B (0 MB)
[0][    314.539]    314.920575 s:
[0][    314.540]    314.920728 s:
[0][    314.540]    314.920850 s:  UTILS_PRCM_STATS:  Current Temperature,
[0][    314.540]    314.921063 s:
[0][    314.540]    314.921155 s:   Voltage Rail         ||   Curr Temp Min - Max
[0][    314.540]    314.921368 s:  ---------------------------------------------------------
[0][    314.541]    314.921612 s:      PMHAL_PRCM_VD_MPU ||     [53.200 , 53.600]
[0][    314.541]    314.921887 s:     PMHAL_PRCM_VD_CORE ||     [54.  0 , 54.400]
[0][    314.541]    314.922131 s:    PMHAL_PRCM_VD_IVAHD ||     [54.  0 , 54.400]
[0][    314.541]    314.922344 s:   PMHAL_PRCM_VD_DSPEVE ||     [52.800 , 53.200]
[0][    314.542]    314.922588 s:      PMHAL_PRCM_VD_GPU ||     [53.200 , 53.600]
[0][    314.542]    314.922863 s:
[0][    314.542]    314.922954 s: ============================================================================
[0][    314.542]    314.923229 s: Name      | Bus (mV)  | Res (mOhm) | Shunt (uV)  | Current (mA) | Power (mW)
[0][    314.543]    314.923503 s: ----------------------------------------------------------------------------
[0][    314.543]    314.923808 s:  UTILS_PRCM_STATS: Reading the regulator data failed
[0][    314.543]    314.924022 s:  UTILS_PRCM_STATS: PM INA226 Power Read Failed !!
[0][    314.543]    314.924296 s:
[0][    314.543]    314.924388 s:  Statistics Collector,
[0][    314.543]    314.924540 s:
[0][    314.544]    314.924632 s:        STATISTIC          Avg Data        Peak Data
[0][    314.544]    314.924906 s:        COLLECTOR          MB/s            MB/s
[0][    314.544]    314.925120 s:  --------------------------------------------------
[0][    314.644]    315.024766 s:
[0][    314.644]    315.024919 s:
root@mmt2020-a880:~#

Regards,
Raghu