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

Raghu Kadari

Part Number: PROCESSOR-SDK-DRA7X
Other Parts Discussed in Thread: INA226

Tool/software: TI C/C++ Compiler

Hello TI team,

Intialliy we are running usecase from HLOS and because of certain requirements we are starting usecase from RTOS, After moving to RTOS usecase (M4 IPL Loading)

we are unable to see the Statics logs.

Current LOGS

[HOST] [IPU1-0] 3675.543189 s: Statistics Collector,
[HOST] [IPU1-0] 3675.543341 s:
[HOST] [IPU1-0] 3675.543433 s: STATISTIC Avg Data Peak Data
[HOST] [IPU1-0] 3675.543646 s: COLLECTOR MB/s MB/s
[HOST] [IPU1-0] 3675.543982 s: --------------------------------------------------
[HOST] [IPU1-0] 3675.640669 s:

In the function "Utils_statCollectorPrintCount" , value pCfg->num_uc is 0.

/**************************CODE SNIPET ******************************/

Void Utils_statCollectorPrintCount(void)
{
Int32 uc_idx = 0;
Int32 num_counters = 0;
UInt32 cfg_idx;

Vps_printf(" \r\n");
Vps_printf(" Statistics Collector,\r\n");
Vps_printf(" \r\n");
Vps_printf(" STATISTIC Avg Data Peak Data \r\n");
Vps_printf(" COLLECTOR MB/s MB/s \r\n");
Vps_printf(" -------------------------------------------------- \r\n");

for(cfg_idx=0U; cfg_idx<MAX_SDRAM_CFGS; cfg_idx++)
{
Utils_StatCollSdramCfg *pCfg;

pCfg = &gUtils_statCollCtrl.sdramCfg[cfg_idx];

Vps_printf("pCfg->num_uc %d \r\n", pCfg->num_uc);
for(uc_idx = 0; uc_idx < pCfg->num_uc; uc_idx++) // RAGHU: THIS LOOP ITS NOT ENTERING BECAUSE num_uc is zero */
{
num_counters = pCfg->data[uc_idx].numCounters;

Vps_printf("NumCounter: %d, %s\r\n", num_counters , pCfg->cfg[uc_idx].descr);
switch(num_counters)
{
/*
* MISRA.PTR.ARITH
* MISRAC_2004_Rule_17.1 MISRAC_2004_Rule_17.4:
* Pointer is used in arithmetic or array index expression
* KW State: Defer -> Waiver -> Case by case
*/
case 1 :
Utils_statCollectorPrintThroughPut(

Regards,
Raghu

over 5 years ago

0 Anuj Gupta over 5 years ago

TI__Genius 15985 points

Hi,

Can you try to install a fresh SDK and build that and try to run any usecase and check.

Stats collector is not been touched since last few release and tested in all previous release.

So if you are not doing any changes then it should work straight forward.

Regards,

Anuj

0 Anuj Gupta over 5 years ago in reply to Anuj Gupta

TI__Genius 15985 points

Hi,

Its been long since any update on this threads.

I hope the issue is resolved.

if not then please reply below or create a new thread for different issue.

Regards,

Anuj

0 Raghu Kadari over 5 years ago in reply to Anuj Gupta

Expert 1380 points

Hi Anuj,

I have made certain changes and able to see some change in Log, But complete static is still not coming

[0][ 59.656] 61.047550 s:
[0][ 59.656] 61.047703 s: CPU [IPU1-0 ] Statistics,
[0][ 59.656] 61.047855 s: *************************
[0][ 59.656] 61.048008 s: CPU: IPU1-0: Unable to get CPU statistics !!!
[0][ 59.656] 61.048221 s:
[0][ 59.656] 61.048313 s:
[0][ 59.657] 61.048465 s:
[0][ 59.657] 61.049289 s: UTILS_PRCM_STATS: Current Temperature,
[0][ 59.658] 61.049472 s:
[0][ 59.658] 61.049624 s: Voltage Rail || Curr Temp Min - Max
[0][ 59.658] 61.049838 s: ---------------------------------------------------------
[0][ 59.658] 61.050082 s: PMHAL_PRCM_VD_MPU || [46.800 , 47.200]
[0][ 59.659] 61.050326 s: PMHAL_PRCM_VD_CORE || [45.600 , 46. 0]
[0][ 59.659] 61.050600 s: PMHAL_PRCM_VD_IVAHD || [46.400 , 46.800]
[0][ 59.659] 61.050844 s: PMHAL_PRCM_VD_DSPEVE || [47.200 , 47.600]
[0][ 59.659] 61.051088 s: PMHAL_PRCM_VD_GPU || [46.800 , 47.200]
[0][ 59.659] 61.051332 s:
[0][ 59.660] 61.051424 s: ============================================================================
[0][ 59.660] 61.051729 s: Name | Bus (mV) | Res (mOhm) | Shunt (uV) | Current (mA) | Power (mW)
[0][ 59.660] 61.052003 s: ----------------------------------------------------------------------------
[0][ 59.660] 61.052308 s: UTILS_PRCM_STATS: Reading the regulator data failed
[0][ 59.661] 61.052552 s: UTILS_PRCM_STATS: PM INA226 Power Read Failed !!
[0][ 59.661] 61.052796 s:
[0][ 59.661] 61.052918 s: Statistics Collector,
[0][ 59.661] 61.053071 s:
[0][ 59.661] 61.053162 s: STATISTIC Avg Data Peak Data
[0][ 59.662] 61.053376 s: COLLECTOR MB/s MB/s
[0][ 59.662] 61.053681 s: --------------------------------------------------
[0][ 59.762] 61.153571 s:
[0][ 59.762] 61.153724 s:
[0][ 59.762] 61.153876 s:
[0][ 59.762] 61.153998 s: Statistics Collector,
[0][ 59.762] 61.154151 s:
[0][ 59.762] 61.154242 s: STATISTIC Avg Data Peak Data
[0][ 59.763] 61.154456 s: COLLECTOR MB/s MB/s
[0][ 59.763] 61.154822 s: --------------------------------------------------
[0][ 59.863] 61.254681 s: [CAPTURE] Buffer Q Status,
[0][ 59.863] 61.254895 s: Empty Q : Elements in Q = 0, Write Idx = 0, Read Idx = 0
[0][ 59.863] 61.255169 s: Full Q : Elements in Q = 0, Write Idx = 22, Read Idx = 22
[0][ 59.864] 61.255596 s: [CAPTURE] Buffer Q Status,
[0][ 59.864] 61.255779 s: Empty Q : Elements in Q = 0, Write Idx = 0, Read Idx = 0
[0][ 59.864] 61.256023 s: Full Q : Elements in Q = 0, Write Idx = 22, Read Idx = 22
[0][ 61.864] 63.255779 s: [CAPTURE] Buffer Q Status,
[0][ 61.864] 63.255993 s: Empty Q : Elements in Q = 0, Write Idx = 0, Read Idx = 0
[0][ 61.864] 63.256267 s: Full Q : Elements in Q = 0, Write Idx = 272, Read Idx = 272
[0][ 62.365] 63.756694 s:
[0][ 62.365] 63.756847 s: ### CPU [IPU1-0], LinkID [ 68],
[0][ 62.365] 63.757030 s:
[0][ 62.365] 63.757121 s: [ CAPTURE ] Link Statistics,
[0][ 62.365] 63.757304 s: ******************************
[0][ 62.365] 63.757457 s:
[0][ 62.366] 63.757579 s: Elapsed time = 62513 msec
[0][ 62.366] 63.757792 s:
[0][ 62.366] 63.757884 s: New data Recv = 52.8 fps
[0][ 62.366] 63.758067 s: Get Full Buf Cb = 25.93 fps
[0][ 62.366] 63.758250 s: Put Empty Buf Cb = 25.93 fps
[0][ 62.366] 63.758433 s: Driver/Notify Cb = 52.8 fps
[0][ 62.367] 63.758646 s:
[0][ 62.367] 63.758738 s: Input Statistics,
[0][ 62.367] 63.758890 s:
[0][ 62.367] 63.758982 s: CH | In Recv | In Drop | In User Drop | In Process
[0][ 62.367] 63.759195 s: | FPS | FPS | FPS | FPS
[0][ 62.368] 63.759409 s: --------------------------------------------------
[0][ 62.368] 63.759714 s: 0 | 25.93 0. 0 0. 0 25.93
[0][ 62.368] 63.759989 s:
[0][ 62.368] 63.760080 s: Output Statistics,
[0][ 62.368] 63.760233 s:
[0][ 62.368] 63.760324 s: CH | Out | Out | Out Drop | Out User Drop
[0][ 62.369] 63.760538 s: | ID | FPS | FPS | FPS
[0][ 62.369] 63.761087 s: ---------------------------------------------
[0][ 62.369] 63.761300 s: 0 | 0 25.96 25.97 0. 0
[0][ 62.370] 63.761544 s:
[0][ 62.370] 63.761666 s: [ CAPTURE ] LATENCY,
[0][ 62.370] 63.761819 s: ********************
[0][ 62.370] 63.761971 s:
[0][ 62.370] 63.762124 s:
[0][ 62.370] 63.762215 s: ### CPU [IPU1-0], LinkID [ 72],
[0][ 62.370] 63.762398 s:
[0][ 62.371] 63.762490 s: [ DISPLAY ] Link Statistics,
[0][ 62.371] 63.762703 s: ******************************
[0][ 62.371] 63.762886 s:
[0][ 62.371] 63.762978 s: Elapsed time = 62518 msec
[0][ 62.371] 63.763161 s:
[0][ 62.371] 63.763252 s: New data Recv = 25.91 fps
[0][ 62.371] 63.763435 s: Driver/Notify Cb = 62.46 fps
[0][ 62.372] 63.763710 s:
[0][ 62.372] 63.763801 s: Input Statistics,
[0][ 62.372] 63.763954 s:
[0][ 62.372] 63.764045 s: CH | In Recv | In Drop | In User Drop | In Process
[0][ 62.372] 63.764259 s: | FPS | FPS | FPS | FPS
[0][ 62.373] 63.764472 s: --------------------------------------------------
[0][ 62.373] 63.764808 s: 0 | 25.96 0. 0 0. 0 25.96
[0][ 62.373] 63.765052 s:
[0][ 62.373] 63.765143 s: [ DISPLAY ] LATENCY,
[0][ 62.373] 63.765296 s: ********************
[0][ 62.374] 63.765448 s: Local Link Latency : Avg = 162 us, Min = 91 us, Max = 1037 us,
[0][ 62.374] 63.765784 s: Source to Link Latency : Avg = 175 us, Min = 92 us, Max = 1037 us,
[0][ 62.375] 63.766577 s:
[0][ 62.375] 63.766760 s: Display UnderFlow Count = 0
[0][ 62.375] 63.766943 s:
[0][ 62.376] 63.767095 s:
[0][ 62.376] 63.767949 s: ### CPU [IPU1-0], LinkID [ 69],
[0][ 62.376] 63.768163 s:
[0][ 62.376] 63.768285 s: [ CAPTURE ] Link Statistics,
[0][ 62.376] 63.768437 s: ******************************
[0][ 62.377] 63.768651 s:
[0][ 62.377] 63.768742 s: Elapsed time = 62525 msec
[0][ 62.377] 63.768925 s:
[0][ 62.377] 63.769017 s: New data Recv = 52.9 fps
[0][ 62.377] 63.769200 s: Get Full Buf Cb = 25.94 fps
[0][ 62.377] 63.769383 s: Put Empty Buf Cb = 25.94 fps
[0][ 62.378] 63.769566 s: Driver/Notify Cb = 52.9 fps
[0][ 62.378] 63.769840 s:
[0][ 62.378] 63.769962 s: Input Statistics,
[0][ 62.378] 63.770084 s:
[0][ 62.378] 63.770176 s: CH | In Recv | In Drop | In User Drop | In Process
[0][ 62.379] 63.770389 s: | FPS | FPS | FPS | FPS
[0][ 62.380] 63.771609 s: --------------------------------------------------
[0][ 62.380] 63.771853 s: 0 | 25.94 0. 0 0. 0 25.94
[0][ 62.380] 63.772128 s:
[0][ 62.380] 63.772219 s: Output Statistics,
[0][ 62.380] 63.772341 s:
[0][ 62.381] 63.772433 s: CH | Out | Out | Out Drop | Out User Drop
[0][ 62.381] 63.772677 s: | ID | FPS | FPS | FPS
[0][ 62.381] 63.772890 s: ---------------------------------------------
[0][ 62.381] 63.773104 s: 0 | 0 25.95 25.97 0. 0
[0][ 62.381] 63.773348 s:
[0][ 62.381] 63.773439 s: [ CAPTURE ] LATENCY,
[0][ 62.382] 63.773653 s: ********************
[0][ 62.382] 63.773805 s:
[0][ 62.382] 63.773958 s:
[0][ 62.382] 63.774049 s: ### CPU [IPU1-0], LinkID [ 73],
[0][ 62.382] 63.774263 s:
[0][ 62.382] 63.774354 s: [ DISPLAY ] Link Statistics,
[0][ 62.383] 63.774507 s: ******************************
[0][ 62.383] 63.774781 s:
[0][ 62.383] 63.774873 s: Elapsed time = 62512 msec
[0][ 62.383] 63.775056 s:
[0][ 62.383] 63.775147 s: New data Recv = 25.94 fps
[0][ 62.383] 63.775330 s: Driver/Notify Cb = 62.45 fps
[0][ 62.384] 63.775513 s:
[0][ 62.384] 63.775696 s: Input Statistics,
[0][ 62.384] 63.775849 s:
[0][ 62.384] 63.775940 s: CH | In Recv | In Drop | In User Drop | In Process
[0][ 62.384] 63.776154 s: | FPS | FPS | FPS | FPS
[0][ 62.384] 63.776367 s: --------------------------------------------------
[0][ 62.385] 63.776642 s: 0 | 25.97 0. 0 0. 0 25.97
[0][ 62.385] 63.776916 s:
[0][ 62.385] 63.777008 s: [ DISPLAY ] LATENCY,
[0][ 62.385] 63.777130 s: ********************
[0][ 62.385] 63.777282 s: Local Link Latency : Avg = 634724419 us, Min = 91 us, Max = 1129 us,
[0][ 62.386] 63.777618 s: Source to Link Latency : Avg = 634724434 us, Min = 92 us, Max = 1159 us,
[0][ 62.386] 63.777954 s:
[0][ 62.386] 63.778045 s: Display UnderFlow Count = 0
[0][ 62.386] 63.778198 s:
[0][ 62.886] 64.277648 s: CPU [ HOST], LinkID [ 92], Link Statistics not available !
[0][ 62.886] 64.277954 s: CPU [ HOST], LinkID [ 0], Link Statistics not available !
[0][ 63.386] 64.777740 s:
[0][ 63.386] 64.777893 s: ### CPU [IPU1-0], LinkID [ 10],
[0][ 63.386] 64.778076 s:
[0][ 63.386] 64.778167 s: [ IPC_IN_0 ] Link Statistics,
[0][ 63.386] 64.778320 s: ******************************
[0][ 63.386] 64.778503 s:
[0][ 63.387] 64.778594 s: Elapsed time = 61209 msec
[0][ 63.387] 64.779479 s:
[0][ 63.388] 64.779601 s: Get Full Buf Cb = 0.32 fps
[0][ 63.388] 64.779814 s: Put Empty Buf Cb = 0.31 fps
[0][ 63.388] 64.779997 s: Driver/Notify Cb = 0.31 fps
[0][ 63.388] 64.780180 s:
[0][ 63.388] 64.780272 s: Input Statistics,
[0][ 63.388] 64.780424 s:
[0][ 63.389] 64.780516 s: CH | In Recv | In Drop | In User Drop | In Process
[0][ 63.389] 64.780760 s: | FPS | FPS | FPS | FPS
[0][ 63.389] 64.780973 s: --------------------------------------------------
[0][ 63.389] 64.781217 s: 0 | 0.32 0. 0 0. 0 0.32
[0][ 63.389] 64.781461 s:
[0][ 63.390] 64.781583 s: Output Statistics,
[0][ 63.390] 64.781736 s:
[0][ 63.390] 64.781827 s: CH | Out | Out | Out Drop | Out User Drop
[0][ 63.390] 64.782041 s: | ID | FPS | FPS | FPS
[0][ 63.390] 64.782254 s: ---------------------------------------------
[0][ 63.391] 64.782468 s: 0 | 0 0.32 0. 0 0. 0
[0][ 63.391] 64.782742 s:
[0][ 63.391] 64.782834 s: [ IPC_IN_0 ] LATENCY,
[0][ 63.391] 64.782986 s: ********************
[0][ 63.391] 64.783108 s: Local Link Latency : Avg = 22 us, Min = 0 us, Max = 91 us,
[0][ 63.392] 64.783413 s: Source to Link Latency : Avg = 340 us, Min = 274 us, Max = 488 us,
[0][ 63.392] 64.783779 s:
[0][ 63.392] 64.783962 s:
[0][ 63.392] 64.784054 s: ### CPU [IPU1-0], LinkID [ 74],
[0][ 63.392] 64.784237 s:
[0][ 63.393] 64.784359 s: [ DISPLAY ] Link Statistics,
[0][ 63.394] 64.785701 s: ******************************
[0][ 63.394] 64.785853 s:
[0][ 63.394] 64.785975 s: Elapsed time = 61215 msec
[0][ 63.394] 64.786128 s:
[0][ 63.394] 64.786250 s: New data Recv = 0.31 fps
[0][ 63.394] 64.786433 s: Driver/Notify Cb = 62.45 fps
[0][ 63.395] 64.786646 s:
[0][ 63.395] 64.786738 s: Input Statistics,
[0][ 63.395] 64.786860 s:
[0][ 63.395] 64.786982 s: CH | In Recv | In Drop | In User Drop | In Process
[0][ 63.395] 64.787195 s: | FPS | FPS | FPS | FPS
[0][ 63.395] 64.787409 s: --------------------------------------------------
[0][ 63.396] 64.787653 s: 0 | 0.32 0. 0 0. 0 0.32
[0][ 63.396] 64.787927 s:
[0][ 63.396] 64.788019 s: [ DISPLAY ] LATENCY,
[0][ 63.396] 64.788171 s: ********************
[0][ 63.396] 64.788324 s: Local Link Latency : Avg = 369 us, Min = 183 us, Max = 793 us,
[0][ 63.397] 64.788690 s: Source to Link Latency : Avg = 690 us, Min = 427 us, Max = 1098 us,
[0][ 63.397] 64.788995 s:
[0][ 63.397] 64.789086 s: Display UnderFlow Count = 0
[0][ 63.397] 64.789269 s:
[0][ 63.897] 65.288690 s: CPU [ HOST], LinkID [ 93], Link Statistics not available !
[0][ 63.897] 65.288995 s: CPU [ HOST], LinkID [ 1], Link Statistics not available !
[0][ 64.397] 65.788751 s:
[0][ 64.397] 65.788903 s: ### CPU [IPU1-0], LinkID [ 11],
[0][ 64.397] 65.789117 s:
[0][ 64.397] 65.789208 s: [ IPC_IN_1 ] Link Statistics,
[0][ 64.397] 65.789361 s: ******************************
[0][ 64.397] 65.789544 s:
[0][ 64.398] 65.789666 s: Elapsed time = 62220 msec
[0][ 64.398] 65.789849 s:
[0][ 64.398] 65.789940 s: Get Full Buf Cb = 0.32 fps
[0][ 64.398] 65.790123 s: Put Empty Buf Cb = 0.30 fps
[0][ 64.398] 65.790306 s: Driver/Notify Cb = 0.30 fps
[0][ 64.398] 65.790489 s:
[0][ 64.399] 65.790611 s: Input Statistics,
[0][ 64.399] 65.790764 s:
[0][ 64.399] 65.790855 s: CH | In Recv | In Drop | In User Drop | In Process
[0][ 64.399] 65.791069 s: | FPS | FPS | FPS | FPS
[0][ 64.399] 65.791313 s: --------------------------------------------------
[0][ 64.400] 65.791526 s: 0 | 0.32 0. 0 0. 0 0.32
[0][ 64.400] 65.791831 s:
[0][ 64.400] 65.791923 s: Output Statistics,
[0][ 64.400] 65.792045 s:
[0][ 64.400] 65.792136 s: CH | Out | Out | Out Drop | Out User Drop
[0][ 64.400] 65.792350 s: | ID | FPS | FPS | FPS
[0][ 64.401] 65.792563 s: ---------------------------------------------
[0][ 64.401] 65.792807 s: 0 | 0 0.32 0. 0 0. 0
[0][ 64.401] 65.793051 s:
[0][ 64.401] 65.793143 s: [ IPC_IN_1 ] LATENCY,
[0][ 64.401] 65.793295 s: ********************
[0][ 64.402] 65.793417 s: Local Link Latency : Avg = 21 us, Min = 0 us, Max = 31 us,
[0][ 64.402] 65.794180 s: Source to Link Latency : Avg = 300 us, Min = 213 us, Max = 549 us,
[0][ 64.402] 65.794485 s:
[0][ 64.403] 65.794729 s:
[0][ 64.403] 65.794851 s: ### CPU [IPU1-0], LinkID [ 75],
[0][ 64.403] 65.795034 s:
[0][ 64.403] 65.795125 s: [ DISPLAY ] Link Statistics,
[0][ 64.403] 65.795278 s: ******************************
[0][ 64.403] 65.795461 s:
[0][ 64.404] 65.795552 s: Elapsed time = 62225 msec
[0][ 64.404] 65.795766 s:
[0][ 64.404] 65.795857 s: New data Recv = 0.30 fps
[0][ 64.404] 65.796040 s: Driver/Notify Cb = 62.45 fps
[0][ 64.404] 65.796223 s:
[0][ 64.404] 65.796315 s: Input Statistics,
[0][ 64.404] 65.796468 s:
[0][ 64.405] 65.796559 s: CH | In Recv | In Drop | In User Drop | In Process
[0][ 64.405] 65.796803 s: | FPS | FPS | FPS | FPS
[0][ 64.405] 65.797017 s: --------------------------------------------------
[0][ 64.405] 65.797261 s: 0 | 0.32 0. 0 0. 0 0.32
[0][ 64.405] 65.797505 s:
[0][ 64.406] 65.797596 s: [ DISPLAY ] LATENCY,
[0][ 64.406] 65.797840 s: ********************
[0][ 64.406] 65.797962 s: Local Link Latency : Avg = 324 us, Min = 213 us, Max = 610 us,
[0][ 64.406] 65.798267 s: Source to Link Latency : Avg = 611 us, Min = 488 us, Max = 915 us,
[0][ 64.407] 65.798572 s:
[0][ 64.407] 65.798694 s: Display UnderFlow Count = 0
[0][ 64.407] 65.798877 s:
[0][ 64.407] 65.799030 s:
[0][ 64.407] 65.799121 s: ### CPU [IPU1-0], LinkID [ 70],
[0][ 64.407] 65.799335 s:
[0][ 64.408] 65.799945 s: [ CAPTURE ] Link Statistics,
[0][ 64.408] 65.800158 s: ******************************
[0][ 64.408] 65.800341 s:
[0][ 64.408] 65.800433 s: Elapsed time = 62217 msec
[0][ 64.409] 65.800616 s:
[0][ 64.409] 65.800768 s: New data Recv = 62.44 fps
[0][ 64.409] 65.800951 s: Get Full Buf Cb = 62.45 fps
[0][ 64.409] 65.801104 s: Put Empty Buf Cb = 62.45 fps
[0][ 64.409] 65.801287 s: Driver/Notify Cb = 62.44 fps
[0][ 64.410] 65.801470 s:
[0][ 64.410] 65.802049 s: Input Statistics,
[0][ 64.410] 65.802446 s:
[0][ 64.411] 65.802537 s: CH | In Recv | In Drop | In User Drop | In Process
[0][ 64.411] 65.802812 s: | FPS | FPS | FPS | FPS
[0][ 64.411] 65.803025 s: --------------------------------------------------
[0][ 64.411] 65.803269 s: 0 | 62.45 0. 0 0. 0 62.45
[0][ 64.411] 65.803544 s:
[0][ 64.413] 65.803635 s: Output Statistics,
[0][ 64.413] 65.804855 s:
[0][ 64.413] 65.804947 s: CH | Out | Out | Out Drop | Out User Drop
[0][ 64.413] 65.805160 s: | ID | FPS | FPS | FPS
[0][ 64.413] 65.805374 s: ---------------------------------------------
[0][ 64.414] 65.805587 s: 0 | 0 62.45 0. 0 0. 0
[0][ 64.414] 65.805862 s:
[0][ 64.414] 65.805953 s: [ CAPTURE ] LATENCY,
[0][ 64.414] 65.806106 s: ********************
[0][ 64.414] 65.806228 s:
[0][ 64.414] 65.806411 s:
[0][ 64.415] 65.806502 s: ### CPU [IPU1-0], LinkID [ 49],
[0][ 64.415] 65.806746 s:
[0][ 64.415] 65.806838 s: [ ALG_SWCRC ] Link Statistics,

Regards,

Raghu

0 Anuj Gupta over 5 years ago in reply to Raghu Kadari

TI__Genius 15985 points

Hi,

Here i can see you are getting input and output stats of links.

So what is the issue here.

And also what changes have you made.

Regards,

Anuj

0 Raghu Kadari over 5 years ago in reply to Anuj Gupta

Expert 1380 points

HI Anuj,

Earlier we use to get statistics like this, now in the above log this section is empty (Highlighted in Green).

Regards,

Raghu

0 Anuj Gupta over 5 years ago in reply to Raghu Kadari

TI__Genius 15985 points

Hi,

Are you facing the same issue with fresh sdk also.

Can you share your changes, i think its your changes only which is causing this issue.

Regards,

Anuj

0 Raghu Kadari over 5 years ago in reply to Anuj Gupta

Expert 1380 points

Hello Anuj,

I have used TI rtos usecase to print statistics and unable to get the Stats collector logs.

usecase: null_src_display

Path: apps/src/rtos/usecases/null_src_display

root@mmt2020-a880:~# cat /sys/kernel/debug/remoteproc/remoteproc0/trace0

[0][ 0.000] 18 Resource entries at 0x3000
[0][ 0.000] 0.360062 s: SYSTEM: System Common Init in progress !!!
[0][ 0.010] 0.369364 s: SYSTEM: IPC init in progress !!!
[0][ 0.010] 0.369639 s: SYSTEM: Notify init done !!!
[0][ 0.012] 0.371378 s: SYSTEM: MsgQ init done !!!
[0][ 0.013] 0.372384 s: RpmsgInit in progress...
[0][ 0.013] 0.372628 s: SYSTEM: IPC init DONE !!!
[0][ 0.019] 0.378911 s: SYSTEM: System Common Init Done !!!
[0][ 0.019] 0.379155 s: SYSTEM: System Init in progress !!!
[0][ 0.020] 0.379308 s: SYSTEM: BSP Common Init in progress !!!
[0][ 0.020] 0.379521 s: SYSTEM: BSP Common Init Done !!!
[0][ 0.020] 0.379674 s: SYSTEM: BSP Platform Init in progress !!!
[0][ 0.020] 0.379887 s: SYSTEM: BSP Platform Init Done !!!
[0][ 0.020] 0.380070 s: SYSTEM: FVID2 Init in progress !!!
[0][ 0.021] 0.380314 s: SYSTEM: FVID2 Init Done !!!
[0][ 0.021] 0.380497 s: SYSTEM: VPS Init in progress !!!
[0][ 0.021] 0.380650 s: SYSTEM: VPDMA Descriptor Memory Address translation ENABLED [0x80000000 -> 0x80000000]
[0][ 0.024] 0.383669 s: *** VPDMA Firmware Loading... ***
[0][ 0.024] 0.383913 s: VPDMA Firmware Address = 0xa034a500
[0][ 0.024] 0.384096 s: VPDMA Load Address = 0x4897d004
[0][ 0.025] 0.384310 s: VPDMA Firmware Version = 0x4d0001b8
[0][ 0.025] 0.384523 s: VPDMA List Busy Status = 0x00000000
[0][ 0.025] 0.384706 s: *** VPDMA Firmware Load Success ***
[0][ 0.025]
[0][ 0.025] 0.385133 s: *** VPDMA Firmware Loading... ***
[0][ 0.026] 0.385347 s: VPDMA Firmware Address = 0xa034a500
[0][ 0.026] 0.385530 s: VPDMA Load Address = 0x4899d004
[0][ 0.026] 0.385743 s: VPDMA Firmware Version = 0x4d0001b8
[0][ 0.026] 0.385926 s: VPDMA List Busy Status = 0x00000000
[0][ 0.026] 0.386109 s: *** VPDMA Firmware Load Success ***
[0][ 0.026]
[0][ 0.027] 0.386567 s: *** VPDMA Firmware Loading... ***
[0][ 0.027] 0.386750 s: VPDMA Firmware Address = 0xa034a500
[0][ 0.027] 0.386933 s: VPDMA Load Address = 0x489bd004
[0][ 0.027] 0.387146 s: VPDMA Firmware Version = 0x4d0001b8
[0][ 0.028] 0.387329 s: VPDMA List Busy Status = 0x00000000
[0][ 0.028] 0.387543 s: *** VPDMA Firmware Load Success ***
[0][ 0.028]
[0][ 0.083] 0.443054 s: SYSTEM: VPS Init Done !!!
[0][ 0.084] 0.444000 s: UTILS: DMA: HWI Create for INT34 !!!
[0][ 0.085] 0.444366 s: SYSTEM: SW Message Box Msg Pool, Free Msg Count = 1024
[0][ 0.085] 0.444671 s: SYSTEM: Heap = LOCAL_DDR @ 0x00000000, Total size = 262144 B (256 KB), Free size = 260992 B (254 KB)
[0][ 0.085] 0.445068 s: SYSTEM: Heap = SR_OCMC @ 0x00000000, Total size = 0 B (0 KB), Free size = 0 B (0 KB)
[0][ 0.086] 0.445434 s: SYSTEM: Heap = SR_DDR_CACHED @ 0x86203000, Total size = 67108864 B (64 MB), Free size = 67108864 B (64 MB)
[0][ 0.086] 0.445861 s: SYSTEM: Heap = SR_DDR_NON_CACHED @ 0xa0100000, Total size = 1047168 B (0 MB), Free size = 1047168 B (0 MB)
[0][ 0.086] 0.446227 s: SYSTEM: Initializing Links !!!
[0][ 0.329] 0.689196 s: CameraControllerMid_tsk: Task started
[0][ 0.335] 0.694229 s: SYSTEM: Initializing Links ... DONE !!!
[0][ 0.346] 0.695449 s: CHAINS: Application Started !!! 0.705545 s: BOARD: Board Init in progress !!!
[0][ 0.346] 0.706094 s: BOARD: Board Init Done !!!
[0][ 0.354] 0.714207 s:
[0][ 0.355] 0.714451 s:
[0][ 0.355]
[0][ 0.355] ====================
[0][ 0.355] Select Null Link Output
[0][ 0.355] ====================
[0][ 0.355]
[0][ 0.355] 0: Memory
[0][ 0.355]
[0][ 0.355] 1: File Write
[0][ 0.355]
[0][ 0.355] 2: Return Buffer
[0][ 0.355]
[0][ 0.355] Enter Choice:
[0][ 0.513] 0.872842 s: UTILS: DMA: Allocated CH (TCC) = 32 (32)
[0][ 0.513] 0.873086 s: UTILS: DMA: 0 of 1: Allocated PaRAM = 32 (0x63304800)
[0][ 0.514] 0.873482 s: DISPLAY: Create in progress !!!
[0][ 0.514] 0.874153 s: DISPLAY: Create Done !!!
[0][ 0.515] 0.874458 s: SYSTEM: SW Message Box Msg Pool, Free Msg Count = 1023
[0][ 0.515] 0.874733 s: SYSTEM: Heap = LOCAL_DDR @ 0x00000000, Total size = 262144 B (256 KB), Free size = 255616 B (249 KB)
[0][ 0.516] 0.875129 s: SYSTEM: Heap = SR_OCMC @ 0x00000000, Total size = 0 B (0 KB), Free size = 0 B (0 KB)
[0][ 0.516] 0.875526 s: SYSTEM: Heap = SR_DDR_CACHED @ 0x86203000, Total size = 67108864 B (64 MB), Free size = 61579264 B (58 MB)
[0][ 0.516] 0.875922 s: SYSTEM: Heap = SR_DDR_NON_CACHED @ 0xa0100000, Total size = 1047168 B (0 MB), Free size = 1047168 B (0 MB)
[0][ 0.616] 0.975751 s: DISPLAY: Start in progress !!!
[0][ 0.616] 0.975965 s: DISPLAY: Start Done !!!
[0][ 0.649] 1.008906 s: NULL: Q0: CH0: Dumped 0 frames @ 0x00000000, size: 7 bytes !!!
[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 !!!
[0][ 0.901] 2.563746 s: Connection established with HLOS, calling rpmsgInit
[0][ 0.901] registering rpmsg-proto:rpmsg-proto service on 80 with HOST
[0][ 1.557] 3.220337 s: ** LINUX INIT COMPLETE
[0][ 4.200] 5.863325 s: FD Disabled
[0][ 4.200] 5.863478 s: CamController command 4
[0][ 5.616] 7.278899 s:
[0][ 5.616] 7.279021 s: ### CPU [IPU1-0], LinkID [ 57],
[0][ 5.616] 7.279204 s:
[0][ 5.616] 7.279296 s: [ NULL_SRC ] Link Statistics,
[0][ 5.616] 7.279479 s: ******************************
[0][ 5.616] 7.279631 s:
[0][ 5.617] 7.279723 s: Elapsed time = 6271 msec
[0][ 5.617] 7.279967 s:
[0][ 5.617] 7.280058 s: New data Recv = 24.7 fps
[0][ 5.617] 7.280211 s: Get Full Buf Cb = 0.63 fps
[0][ 5.617] 7.280394 s: Put Empty Buf Cb = 0.47 fps
[0][ 5.617] 7.280577 s: Driver/Notify Cb = 23.91 fps
[0][ 5.618] 7.280760 s:
[0][ 5.618] 7.280882 s: Input Statistics,
[0][ 5.618] 7.281034 s:
[0][ 5.618] 7.281126 s: CH | In Recv | In Drop | In User Drop | In Process
[0][ 5.618] 7.281339 s: | FPS | FPS | FPS | FPS
[0][ 5.618] 7.281522 s: --------------------------------------------------
[0][ 5.619] 7.281736 s: 0 | 24. 7 23.44 0. 0 0.63
[0][ 5.619] 7.282071 s:
[0][ 5.619] 7.282163 s: Output Statistics,
[0][ 5.619] 7.282285 s:
[0][ 5.619] 7.282376 s: CH | Out | Out | Out Drop | Out User Drop
[0][ 5.619] 7.282590 s: | ID | FPS | FPS | FPS
[0][ 5.620] 7.282773 s: ---------------------------------------------
[0][ 5.620] 7.283017 s: 0 | 0 0.63 0. 0 0. 0
[0][ 5.620] 7.283261 s:
[0][ 5.620] 7.283352 s: [ NULL_SRC ] LATENCY,
[0][ 5.620] 7.283474 s: ********************
[0][ 5.620] 7.283627 s:
[0][ 5.621] 7.283749 s: CPU [ IPU1-0], LinkID [ 25], Link Statistics not available !
[0][ 5.621] 7.284145 s:
[0][ 5.621] 7.284237 s: ### CPU [IPU1-0], LinkID [ 20],
[0][ 5.621] 7.284420 s:
[0][ 5.621] 7.284511 s: [ NULL ] Link Statistics,
[0][ 5.621] 7.284664 s: ******************************
[0][ 5.622] 7.284877 s:
[0][ 5.622] 7.284969 s: Elapsed time = 6276 msec
[0][ 5.622] 7.285121 s:
[0][ 5.622] 7.285213 s:
[0][ 5.622] 7.285304 s: Input Statistics,
[0][ 5.622] 7.285457 s:
[0][ 5.622] 7.285548 s: CH | In Recv | In Drop | In User Drop | In Process
[0][ 5.623] 7.285762 s: | FPS | FPS | FPS | FPS
[0][ 5.623] 7.286006 s: --------------------------------------------------
[0][ 5.623] 7.286219 s: 0 | 0.15 0. 0 0. 0 0.15
[0][ 5.623] 7.286494 s:
[0][ 5.623] 7.286585 s: [ NULL ] LATENCY,
[0][ 5.624] 7.286707 s: ********************
[0][ 5.624] 7.286890 s: Local Link Latency : Avg = 1008875 us, Min = 1008875 us, Max = 1008875 us,
[0][ 5.624] 7.287195 s: Source to Link Latency : Avg = 397 us, Min = 397 us, Max = 397 us,
[0][ 5.624] 7.287470 s:
[0][ 5.624] 7.287653 s:
[0][ 5.625] 7.287744 s: ### CPU [IPU1-0], LinkID [ 71],
[0][ 5.625] 7.287958 s:
[0][ 5.625] 7.288049 s: [ DISPLAY ] Link Statistics,
[0][ 5.625] 7.288202 s: ******************************
[0][ 5.625] 7.288385 s:
[0][ 5.625] 7.288476 s: Elapsed time = 6279 msec
[0][ 5.625] 7.288629 s:
[0][ 5.626] 7.288720 s: New data Recv = 0.47 fps
[0][ 5.626] 7.288964 s: Driver/Notify Cb = 61.79 fps
[0][ 5.626] 7.289117 s:
[0][ 5.626] 7.289208 s: Input Statistics,
[0][ 5.626] 7.289361 s:
[0][ 5.626] 7.289452 s: CH | In Recv | In Drop | In User Drop | In Process
[0][ 5.627] 7.289635 s: | FPS | FPS | FPS | FPS
[0][ 5.627] 7.289910 s: --------------------------------------------------
[0][ 5.627] 7.290123 s: 0 | 0.63 0. 0 0. 0 0.63
[0][ 5.627] 7.290367 s:
[0][ 5.627] 7.290459 s: [ DISPLAY ] LATENCY,
[0][ 5.627] 7.290611 s: ********************
[0][ 5.628] 7.290733 s: Local Link Latency : Avg = 1058187 us, Min = 1008723 us, Max = 1107667 us,
[0][ 5.628] 7.291099 s: Source to Link Latency : Avg = 198 us, Min = 183 us, Max = 214 us,
[0][ 5.628] 7.291374 s:
[0][ 5.628] 7.291465 s: Display UnderFlow Count = 0
[0][ 5.628] 7.291618 s:
[0][ 7.128] 8.790855 s:
[0][ 7.128] 8.791008 s: CPU [IPU1-0 ] Statistics,
[0][ 7.128] 8.791160 s: *************************
[0][ 7.128] 8.791313 s:
[0][ 7.128] 8.791404 s: LOAD: CPU: 9.7 HWI: 0.5, SWI:0.7, Low Power: 1.0
[0][ 7.128] 8.791709 s:
[0][ 7.129] 8.791801 s: LOAD: TSK: SYSTEM : 4.7
[0][ 7.129] 8.792106 s: LOAD: TSK: NULL0 : 0.9
[0][ 7.129] 8.792319 s: LOAD: TSK: NULL_SRC0 : 2.0
[0][ 7.129] 8.792594 s: LOAD: TSK: MISC : 0.9
[0][ 7.130] 8.792777 s:
[0][ 7.130] 8.792929 s: SYSTEM: SW Message Box Msg Pool, Free Msg Count = 1020
[0][ 7.130] 8.793173 s:
[0][ 7.130] 8.793265 s: SYSTEM: Sempahores Objects, 247 of 1050 free
[0][ 7.130] 8.793448 s: SYSTEM: Task Objects , 19 of 100 free
[0][ 7.131] 8.793661 s: SYSTEM: Clock Objects , 97 of 100 free
[0][ 7.131] 8.794027 s: SYSTEM: Hwi Objects , 93 of 100 free
[0][ 7.131] 8.794241 s:
[0][ 7.131] 8.794332 s: SYSTEM: Heap = LOCAL_DDR @ 0x00000000, Total size = 262144 B (256 KB), Free size = 255296 B (249 KB)
[0][ 7.132] 8.794729 s: SYSTEM: Heap = SR_OCMC @ 0x00000000, Total size = 0 B (0 KB), Free size = 0 B (0 KB)
[0][ 7.132] 8.795125 s: SYSTEM: Heap = SR_DDR_CACHED @ 0x86203000, Total size = 67108864 B (64 MB), Free size = 61579264 B (58 MB)
[0][ 7.133] 8.795552 s: SYSTEM: Heap = SR_DDR_NON_CACHED @ 0xa0100000, Total size = 1047168 B (0 MB), Free size = 1047168 B (0 MB)
[0][ 7.133] 8.795979 s:
[0][ 7.133] 8.796132 s:
[0][ 7.133] 8.796223 s: UTILS_PRCM_STATS: Current Temperature,
[0][ 7.133] 8.796407 s:
[0][ 7.133] 8.796498 s: Voltage Rail || Curr Temp Min - Max
[0][ 7.134] 8.796712 s: ---------------------------------------------------------
[0][ 7.134] 8.797017 s: PMHAL_PRCM_VD_MPU || [46.400 , 46.800]
[0][ 7.134] 8.797261 s: PMHAL_PRCM_VD_CORE || [44.800 , 45.200]
[0][ 7.134] 8.797505 s: PMHAL_PRCM_VD_IVAHD || [45.600 , 46. 0]
[0][ 7.135] 8.797749 s: PMHAL_PRCM_VD_DSPEVE || [44.800 , 45.200]
[0][ 7.135] 8.798023 s: PMHAL_PRCM_VD_GPU || [44.800 , 45.200]
[0][ 7.135] 8.798267 s:
[0][ 7.135] 8.798359 s: ============================================================================
[0][ 7.136] 8.798633 s: Name | Bus (mV) | Res (mOhm) | Shunt (uV) | Current (mA) | Power (mW)
[0][ 7.136] 8.798969 s: ----------------------------------------------------------------------------
[0][ 7.136] 8.799243 s: UTILS_PRCM_STATS: Reading the regulator data failed
[0][ 7.136] 8.799457 s: UTILS_PRCM_STATS: PM INA226 Power Read Failed !!
[0][ 7.137] 8.799792 s:
[0][ 7.137] 8.799945 s: Statistics Collector,
[0][ 7.137] 8.800097 s:
[0][ 7.137] 8.800189 s: STATISTIC Avg Data Peak Data
[0][ 7.137] 8.800402 s: COLLECTOR MB/s MB/s
[0][ 7.137] 8.800616 s: --------------------------------------------------
[0][ 7.238] 8.900872 s:
[0][ 7.238] 8.900994 s:
[0][ 7.686] 9.349325 s: FD Disabled
[0][ 7.686] 9.349478 s: CamController command 4

Regards,

Raghu

0 Anuj Gupta over 5 years ago in reply to Raghu Kadari

TI__Genius 15985 points

Hi,

This log does not seems to be correct as it doe not show from which core this logs are coming.

And in console it did not show how you ran usecase.

Still i am asking the same question?

Are you facing this issue in a freshly installed SDK?

As stats collector code is not been touched since 2-3 years and no customer has faced any issue with this.

So its highly unlikely to face such issue with a fresh SDK

Regards,

Anuj

Processors

Processors forum

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