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.

the latency of Process data

Hi,

We use RDK4, DM8168.

I want to test the latency, I find a report about Performance Measurement Summary.

How to test the Ti, just like T4, T5?

Thanks very much for all your help.

  • Runtime latency info is already printed when you invoke Vsys_printDetailedStatistics. Is there something specific you want to measure which is not present in latency info ?

  • Hi, Badri.

    I invoke Vsys_printDetailedStatistics. The log is as following:

    root@dm816x:/opt/dvr_rdk/ti816x/bin# ./hdcard20_app.out 
     0: SYSTEM: System Common Init in progress !!!
     0: SYSTEM: IPC init in progress !!!
     11: SYSTEM: CPU [DSP] syslink proc ID is [0] !!!
     12: SYSTEM: CPU [VIDEO-M3] syslink proc ID is [1] !!!
     12: SYSTEM: CPU [VPSS-M3] syslink proc ID is [2] !!!
     12: SYSTEM: CPU [HOST] syslink proc ID is [3] !!!
     12: SYSTEM: Creating MsgQ Heap [IPC_MSGQ_MSG_HEAP_3] ...
     14: SYSTEM: Creating MsgQ [HOST_MSGQ] ...
     15: SYSTEM: Creating MsgQ [HOST_ACK_MSGQ] ...
     17: SYSTEM: Opening MsgQ [DSP_MSGQ] ...
     17: SYSTEM: Opening MsgQ [VIDEO-M3_MSGQ] ...
     18: SYSTEM: Opening MsgQ [VPSS-M3_MSGQ] ...
     18: SYSTEM: Notify register to [DSP] line 0, event 15 ... 
     19: SYSTEM: Notify register to [VIDEO-M3] line 0, event 15 ... 
     20: SYSTEM: Notify register to [VPSS-M3] line 0, event 15 ... 
     20: SYSTEM: IPC init DONE !!!
     21: SYSTEM: Creating ListMP [HOST_IPC_OUT_24] in region 0 ...
     23: SYSTEM: Creating ListMP [HOST_IPC_IN_24] in region 0 ...
     24: SYSTEM: ListElem Shared Addr = 0x40663880
     25: SYSTEM: Creating ListMP [HOST_IPC_OUT_25] in region 0 ...
     27: SYSTEM: Creating ListMP [HOST_IPC_IN_25] in region 0 ...
     28: SYSTEM: ListElem Shared Addr = 0x40698a80
     29: SYSTEM: Creating ListMP [HOST_IPC_OUT_19] in region 0 ...
     31: SYSTEM: Creating ListMP [HOST_IPC_IN_19] in region 0 ...
     33: SYSTEM: ListElem Shared Addr = 0x406cdc80
     33: SYSTEM: Creating ListMP [HOST_IPC_OUT_20] in region 0 ...
     35: SYSTEM: Creating ListMP [HOST_IPC_IN_20] in region 0 ...
     37: SYSTEM: ListElem Shared Addr = 0x40710f80
     38: SYSTEM: Creating ListMP [HOST_IPC_OUT_21] in region 0 ...
     40: SYSTEM: Creating ListMP [HOST_IPC_IN_21] in region 0 ...
     41: SYSTEM: ListElem Shared Addr = 0x40754280
     57: SYSTEM: System Common Init Done !!!
     
     Selected file write path [/opt] 
     Opened file [/opt/VID_CH00.h264] for writing CH0
     Opened file [/opt/VID_CH01.h264] for writing CH1
     Opened file [/opt/VID_CH02.h264] for writing CH2
     [m3vpss ]  82487: CAPTURE: Create in progress !!!
     [m3vpss ]  82516: CAPTURE: VIP0 PortA capture mode is [16-bit, Non-mux Discrete Sync - ACTVID_VSYNC] !!! 
     [m3vpss ]  82807: CAPTURE: 0: 0xb0ba9480, 1920 x 1080, 003f4800 B --> Extra Frames 
     [m3vpss ]  82807: CAPTURE: 1: 0xb0f9dc80, 1920 x 1080, 003f4800 B --> Extra Frames 
     [m3vpss ]  82807: CAPTURE: 2: 0xb1392480, 1920 x 1080, 003f4800 B --> Extra Frames 
     [m3vpss ]  82807: CAPTURE: 3: 0xb1786c80, 1920 x 1080, 003f4800 B --> Extra Frames 
     [m3vpss ]  82807: CAPTURE: 4: 0xb1b7b480, 1920 x 1080, 003f4800 B --> Extra Frames 
     [m3vpss ]  82807: CAPTURE: 5: 0xb1f6fc80, 1920 x 1080, 003f4800 B --> Extra Frames 
     [m3vpss ]  UTILS: DMA: Allocated CH (TCC) = 58 (58)
     [m3vpss ]  UTILS: DMA: 0 of 4: Allocated PaRAM = 58 (0x49004740)
     [m3vpss ]  UTILS: DMA: 1 of 4: Allocated PaRAM = 64 (0x49004800)
     [m3vpss ]  UTILS: DMA: 2 of 4: Allocated PaRAM = 65 (0x49004820)
     [m3vpss ]  UTILS: DMA: 3 of 4: Allocated PaRAM = 66 (0x49004840)
     [m3vpss ] CAPTURE::HEAPID:0    USED:328
     [m3vpss ] CAPTURE::HEAPID:4    USED:66355200
     [m3vpss ]  82808: CAPTURE: Create Done !!!
     [m3vpss ]  82814: DUP   : Create Done !!!
     [m3vpss ]  82814: DEI    : Create in progress !!!
     [m3vpss ]  82917: DEI     : Loading Down-scaling Co-effs
     [m3vpss ]  82917: DEI     : Co-effs Loading ... DONE !!!
     [m3vpss ] DEI:HEAPID:0 USED:64
     [m3vpss ] DEI:HEAPID:1 USED:4832
     [m3vpss ] DEI:HEAPID:4 USED:21772800
     [m3vpss ]  82917: DEI    : Create Done !!!
     [m3vpss ]  82918: DEI    : Create in progress !!!
     [m3vpss ]  83020: DEI     : Loading Down-scaling Co-effs
     [m3vpss ]  83021: DEI     : Co-effs Loading ... DONE !!!
     [m3vpss ] DEI:HEAPID:0 USED:64
     [m3vpss ] DEI:HEAPID:1 USED:1312
     [m3vpss ] DEI:HEAPID:4 USED:21772800
     [m3vpss ]  83021: DEI    : Create Done !!!
     [m3vpss ]  83021: DEI    : Create in progress !!!
     [m3video]  83127: IPC_IN_M3   : Create in progress !!!
     [m3vpss ]  83124: DEI     : Loading Down-scaling Co-effs
     [m3video]  83127: SYSTEM: Opening ListMP [VPSS-M3_IPC_OUT_0] ...
     [m3vpss ]  83124: DEI     : Co-effs Loading ... DONE !!!
     [m3video]  83128: SYSTEM: Opening ListMP [VPSS-M3_IPC_IN_0] ...
     [m3video]  83130: IPC_IN_M3   : Create Done !!!
     [m3video]  83130: ENCODE: Create in progress ... !!!
     [m3vpss ] DEI:HEAPID:0 USED:64
     [m3vpss ] DEI:HEAPID:1 USED:4704
     [m3vpss ] DEI:HEAPID:4 USED:21772800
     [m3vpss ]  83125: DEI    : Create Done !!!
     [m3vpss ]  83126: MERGE   : Create Done !!!
     [m3vpss ]  83126: IPC_OUT_M3   : Create in progress !!!
     [m3vpss ]  83127: IPC_OUT_M3   : Create Done !!!
     [m3video]  83219: ENCODE: Creating CH0 of 1920 x 1080, pitch = (1920, 1920) [PROGRESSIVE] [NON-TILED  ], bitrate = 2000 Kbps ... 
     [m3video] ENCLINK_H264:HEAPID:0        USED:13808
     [m3video] ENCLINK_H264:HEAPID:3        USED:7176192
     [m3video]  83253: ENCODE: Creating CH1 of 1920 x 1080, pitch = (1920, 1920) [PROGRESSIVE] [NON-TILED  ], bitrate = 2000 Kbps ... 
     [m3video] ENCLINK_H264:HEAPID:0        USED:11912
     [m3video] ENCLINK_H264:HEAPID:3        USED:7176192
     [m3video]  83288: ENCODE: Creating CH2 of 1920 x 1080, pitch = (1920, 1920) [PROGRESSIVE] [NON-TILED  ], bitrate = 2000 Kbps ... 
    
     [host] IpcBitsInLink_tskMain:Entered
     [host]  899: IPC_BITS_IN   : Create in progress !!!
    
     [host]  899: IPC_BITS_IN   : ListMPOpen start !!!
    
     [host]  899: SYSTEM: Opening ListMP [VIDEO-M3_IPC_OUT_24] ...
     900: SYSTEM: Opening ListMP [VIDEO-M3_IPC_IN_24] ...
    
     [host]  902: IPC_BITS_IN   : ListMPOpen done !!!
    
     [host]  902: IPC_BITS_IN   : System_linkGetInfo done !!!
    
     [host]  903: IPC_BITS_IN   : Create Done !!!
    
     registerOMs  success!
    
     [host] IpcBitsInLink_tskMain:Entered [m3vpss ]  83331: CAPTURE: Start in progress !!!
     [m3video] ENCLINK_H264:HEAPID:0        USED:11912
     [m3video] ENCLINK_H264:HEAPID:3        USED:7176192
     [m3video]  83322: ENCODE: All CH Create ... DONE !!!
     [m3video] ENCLINK:HEAPID:0     USED:37752
     [m3video] ENCLINK:HEAPID:2     USED:18662400
     [m3video] ENCLINK:HEAPID:3     USED:21528576
     [m3video]  83323: ENCODE: Create ... DONE !!!
     [m3video]  83324: IPC_BITS_OUT   : Create in progress !!!
     [m3video]  83325: IPC_BITS_OUT   : Create Done !!!
     [m3vpss ]  83431: CAPTURE: Enabled Time Stamping !!!
    
    Disable Channel: 0
    Disable Channel: 1
    Disable Channel: 2
     [m3vpss ]  83439: CAPTURE: Start Done !!!
     [m3vpss ]  
     [m3vpss ]  *** Capture Driver Advanced Statistics *** 
     [m3vpss ]  
     [m3vpss ]  VIP Parser Reset Count : 0
     [m3vpss ]  
     [m3vpss ]      |   Total    Even     Odd  Total  Even   Odd  Min /  Max  Min /  Max Dropped Fid Repeat Frame Error Y/C
     [m3vpss ]  CH  |  Fields  Fields  Fields    FPS   FPS   FPS       Width      Height  Fields      Count (Desc Error Y/C)
     [m3vpss ]  ------------------------------------------------------------------------------------------------------------
     [m3vpss ]  
     [m3vpss ]  VIP Capture Port 0 | DescMissMatch1 = 0, DescMissMatch2 = 0 , DescMissMatch3 = 0 
     [m3vpss ]  
     [m3vpss ]  *** Capture List Manager Advanced Statistics *** 
     [m3vpss ]  
     [m3vpss ]  List Post Count        : 1 
     [m3vpss ]  List Stall Count       : 0 
     [m3vpss ]  List Post Time (ms)    : Max = 0, Min = 0, Avg = 0, Total = 0 
     [m3vpss ]  INTC Timeout Count     : (0, 0) (Min timeout value = 993, 1000) 
     [m3vpss ]  Descriptor miss found count : 0 
     [m3vpss ]  
     [m3vpss ]  
     [m3vpss ]  VIP and VPDMA registers, 
     [m3vpss ]  VIP0 : FIQ_STATUS  : 0x4810551c = 0x00000000
     [m3vpss ]  VIP1 : FIQ_STATUS  : 0x48105a1c = 0x00000000
     [m3vpss ]  VPDMA: LIST_BUSY   : 0x4810d00c = 0x00000000
     [m3vpss ]  
     [m3vpss ]  
     [m3vpss ]  83442: CAPTURE: Fields = 0 (fps = 0, CPU Load = 0)
     [m3vpss ]  83442: CAPTURE: Num Resets = 0 (Avg 0 ms per reset)
     [m3vpss ]  83442: SYSTEM  : FREE SPACE : System Heap      = 67856 B, Mbx = 10239 msgs) 
     [m3vpss ]  83442: SYSTEM  : FREE SPACE : SR0 Heap         = 8713728 B (8 MB) 
     [m3vpss ]  83442: SYSTEM  : FREE SPACE : Frame Buffer     = 121146752 B (115 MB) 
     [m3vpss ]  83442: SYSTEM  : FREE SPACE : Bitstream Buffer = 192101248 B (183 MB) 
     [m3vpss ] TILER_STATS: CNT :8BIT
     [m3vpss ] TILER_STATS: CNT RESOLUTION:    16384 x 8192
     [m3vpss ] TILER_STATS: BUCKET RESOLUTION: 16384 x 8188
     [m3vpss ] TILER_STATS: NUM FREE BUCKETS:  1
     [m3vpss ] TILER_STATS: NUM USED BUCKETS:  0
     [m3vpss ] TILER_STATS: TOTAL FREE AREA:   134152192 (99 %)
     [m3vpss ] TILER_STATS: TOTAL USED AREA:   0 (0 %)
     [m3vpss ] TILER_STATS: CNT :16BIT
     [m3vpss ] TILER_STATS: CNT RESOLUTION:    32768 x 3072
     [m3vpss ] TILER_STATS: BUCKET RESOLUTION: 32768 x 3072
     [m3vpss ] TILER_STATS: NUM FREE BUCKETS:  1
     [m3vpss ] TILER_STATS: NUM USED BUCKETS:  0
     [m3vpss ] TILER_STATS: TOTAL FREE AREA:   100663296 (100 %)
     [m3vpss ] TILER_STATS: TOTAL USED AREA:   0 (0 %)
     [m3vpss ]  83443: SYSTEM  : FREE SPACE : Tiler 8-bit      = 134152192 B (127 MB)  - TILER ON 
     [m3vpss ]  83444: SYSTEM  : FREE SPACE : Tiler 16-bit     = 100663296 B (96 MB)  - TILER ON 
     [m3vpss ]  
     [m3vpss ]  *** [DEI0    ] DEI Statistics *** 
     [m3vpss ]  
     [m3vpss ]  Elasped Time           : 0 secs
     [m3vpss ]  Total Fields Processed : 0 
     [m3vpss ]  Total Fields FPS       : 0 FPS
     [m3vpss ]  
     [m3vpss ]  
     [m3vpss ]  CH  | In Recv In Reject In Process Out[0] Out[1] Out[2] Out[3] Out[4] Skip Out[0] Skip Out[1] Skip Out[2] Skip Out[3] Skip Out[4] User Out[0] User Out[1] User Out[2] User Out[3] User Out[4] Latency   
     [m3vpss ]  Num | FPS     FPS       FPS        FPS    FPS    FPS    FPS    FPS       FPS         FPS         FPS         FPS         FPS       Skip FPS    Skip FPS    Skip FPS    Skip FPS    Skip FPS   Min / Max 
     [m3vpss ]  -------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------
     [m3vpss ]    0 |       0         0          0      0      0      0      0      0           0           0           0           0           0           0           0           0           0           0 255 /   0
     [m3vpss ]  
     [m3vpss ]  
     [m3vpss ]  *** [DEI1    ] DEI Statistics *** 
     [m3vpss ]  
     [m3vpss ]  Elasped Time           : 0 secs
     [m3vpss ]  Total Fields Processed : 0 
     [m3vpss ]  Total Fields FPS       : 0 FPS
     [m3vpss ]  
     [m3vpss ]  
     [m3vpss ]  CH  | In Recv In Reject In Process Out[0] Out[1] Out[2] Out[3] Out[4] Skip Out[0] Skip Out[1] Skip Out[2] Skip Out[3] Skip Out[4] User Out[0] User Out[1] User Out[2] User Out[3] User Out[4] Latency   
     [m3vpss ]  Num | FPS     FPS       FPS        FPS    FPS    FPS    FPS    FPS       FPS         FPS         FPS         FPS         FPS       Skip FPS    Skip FPS    Skip FPS    Skip FPS    Skip FPS   Min / Max 
     [m3vpss ]  -------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------
     [m3vpss ]    0 |       0         0          0      0      0      0      0      0           0           0           0           0           0           0           0           0           0           0 255 /   0
     [m3vpss ]  
     [m3vpss ]  
     [m3vpss ]  *** [DEI2    ] DEI Statistics *** 
     [m3vpss ]  
     [m3vpss ]  Elasped Time           : 0 secs
     [m3vpss ]  Total Fields Processed : 0 
     [m3vpss ]  Total Fields FPS       : 0 FPS
     [m3vpss ]  
     [m3vpss ]  
     [m3vpss ]  CH  | In Recv In Reject In Process Out[0] Out[1] Out[2] Out[3] Out[4] Skip Out[0] Skip Out[1] Skip Out[2] Skip Out[3] Skip Out[4] User Out[0] User Out[1] User Out[2] User Out[3] User Out[4] Latency   
     [m3vpss ]  Num | FPS     FPS       FPS        FPS    FPS    FPS    FPS    FPS       FPS         FPS         FPS         FPS         FPS       Skip FPS    Skip FPS    Skip FPS    Skip FPS    Skip FPS   Min / Max 
     [m3vpss ]  -------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------
     [m3vpss ]    0 |       0         0          0      0      0      0      0      0           0           0           0           0           0           0           0           0           0           0 255 /   0
     [m3vpss ]  
     [m3video]      89449: HDVICP-ID:0
     [m3video] All percentage figures are based off totalElapsedTime
     [m3video]               totalAcquire2wait :0 %
     [m3video]               totalWait2Isr :65 %
     [m3video]               totalIsr2Done :0 %
     [m3video]               totalWait2Done :65 %
     [m3video]               totalDone2Release :0 %
     [m3video]               totalAcquire2Release :67 %
     [m3video]               totalAcq2acqDelay :32 %
     [m3video]               totalElapsedTime in msec :    5935
     [m3video]               numAccessCnt:     356
     [m3video]              IVA-FPS :      71
     [m3video]              Average time spent per frame in microsec:   10836
     [m3video]      89450: HDVICP-ID:1
     [m3video] All percentage figures are based off totalElapsedTime
     [m3video]               totalAcquire2wait :0 %
     [m3video]               totalWait2Isr :0 %
     [m3video]               totalIsr2Done :0 %
     [m3video]               totalWait2Done :0 %
     [m3video]               totalDone2Release :0 %
     [m3video]               totalAcquire2Release :0 %
     [m3video]               totalAcq2acqDelay :0 %
     [m3video]               totalElapsedTime in msec :       0
     [m3video]               numAccessCnt:       0
     [m3video]              IVA-FPS :       0
     [m3video]              Average time spent per frame in microsec:       0
     [m3video]      89450: HDVICP-ID:2
     [m3video] All percentage figures are based off totalElapsedTime
     [m3video]               totalAcquire2wait :0 %
     [m3video]               totalWait2Isr :0 %
     [m3video]               totalIsr2Done :0 %
     [m3video]               totalWait2Done :0 %
     [m3video]               totalDone2Release :0 %
     [m3video]               totalAcquire2Release :0 %
     [m3video]               totalAcq2acqDelay :0 %
     [m3video]               totalElapsedTime in msec :       0
     [m3video]               numAccessCnt:       0
     [m3video]              IVA-FPS :       0
     [m3video]              Average time spent per frame in microsec:       0
     [m3video]  
     [m3video]  *** ENCODE Statistics *** 
     [m3video]  
     [m3video]  Elasped Time           : 5 secs
     [m3video]  
     [m3video]  
     [m3video]  CH  | In Recv In Skip In User  Out Latency  
     [m3video]  Num | FPS     FPS     Skip FPS FPS Min / Max
     [m3video]  --------------------------------------------
     [m3video]    0 |      70       0        0  70 255 / 82532
     [m3video]    1 |      70       0       70   0 255 /   0
     [m3video]    2 |      70       0       70   0 255 /   0
     [m3video]  
     [m3video] Multi Channel Encode Average Submit Batch Size 
     [m3video] Max Submit Batch Size : 24
     [m3video] IVAHD_0 Average Batch Size : 1
     [m3video] IVAHD_0 Max achieved Batch Size : 1
     [m3video] IVAHD_1 Average Batch Size : 0
     [m3video] IVAHD_1 Max achieved Batch Size : 0
     [m3video] IVAHD_2 Average Batch Size : 0
     [m3video] IVAHD_2 Max achieved Batch Size : 0
     [m3video]  
     [m3video] Multi Channel Encode Batch break Stats 
     [m3video] Total Number of Batches created: 353 
     [m3video] All numbers are based off total number of Batches created
     [m3video]       Batch breaks due to batch sizeexceeding limit: 100 %
     [m3video]       Batch breaks due to ReqObj Que being empty: 0 %
     [m3video]       Batch breaks due to changed resolution class: 0 %
     [m3video]       Batch breaks due to interlace and progressivecontent mix: 0 %
     [m3video]       Batch breaks due to channel repeat: 0 %
     [m3video]       Batch breaks due to different codec: 0 %
     [m3video] Total Number of Batches created: 0 
     [m3video] All numbers are based off total number of Batches created
     [m3video]       Batch breaks due to batch sizeexceeding limit: 0 %
     [m3video]       Batch breaks due to ReqObj Que being empty: 0 %
     [m3video]       Batch breaks due to changed resolution class: 0 %
     [m3video]       Batch breaks due to interlace and progressivecontent mix: 0 %
     [m3video]       Batch breaks due to channel repeat: 0 %
     [m3video]       Batch breaks due to different codec: 0 %
     [m3video] Total Number of Batches created: 0 
     [m3video] All numbers are based off total number of Batches created
     [m3video]       Batch breaks due to batch sizeexceeding limit: 0 %
     [m3video]       Batch breaks due to ReqObj Que being empty: 0 %
     [m3video]       Batch breaks due to changed resolution class: 0 %
     [m3video]       Batch breaks due to interlace and progressivecontent mix: 0 %
     [m3video]       Batch breaks due to channel repeat: 0 %
     [m3video]       Batch breaks due to different codec: 0 %
     [m3video] 89454:DECLINK::links_m3video/iva_dec/decLink_tsk.c:[33]::INTERNAL ERROR:-8
     [m3video] Link create should be first cmd.Received Cmd:8196
     [m3vpss ]  
     [m3vpss ]  92455: LOAD: CPU: 8.7% HWI: 1.1%, SWI:0.9% 
     [m3vpss ]  
     [m3vpss ]  92455: LOAD: TSK: IPC_OUT_M30         : 1.2% 
     [m3vpss ]  92455: LOAD: TSK: CAPTURE             : 0.4% 
     [m3vpss ]  92455: LOAD: TSK: DEI0                : 1.0% 
     [m3vpss ]  92455: LOAD: TSK: DEI1                : 1.1% 
     [m3vpss ]  92455: LOAD: TSK: DEI2                : 0.9% 
     [m3vpss ]  92455: LOAD: TSK: DUP0                : 0.3% 
     [m3vpss ]  92456: LOAD: TSK: MERGE0              : 0.4% 
     [m3vpss ]  92456: LOAD: TSK: MISC                : 1.4% 
     [m3vpss ]  
     [m3video]  
     [m3video]  92956: LOAD: CPU: 6.0% HWI: 1.0%, SWI:0.6% 
     [m3video]  
     [m3video]  92956: LOAD: TSK: IPC_IN_M30          : 0.7% 
     [m3video]  92956: LOAD: TSK: IPC_BITS_OUT0       : 0.6% 
     [m3video]  92957: LOAD: TSK: ENC0                : 1.5% 
     [m3video]  92957: LOAD: TSK: ENC_PROCESS_TSK_0   : 1.4% 
     [m3video]  92957: LOAD: TSK: MISC                : 0.2% 
     [m3video]  
     [c6xdsp ]  
     [c6xdsp ]  92373: LOAD: CPU: 0.2% HWI: 0.0%, SWI:0.0% 
     [c6xdsp ]  
     [c6xdsp ]  92373: LOAD: TSK: MISC                : 0.2% 
     [c6xdsp ]  
    

    [m3vpss ]  83439: CAPTURE: Start Done !!!
     [m3vpss ] 
     [m3vpss ]  *** Capture Driver Advanced Statistics ***
     [m3vpss ] 
     [m3vpss ]  VIP Parser Reset Count : 0
     [m3vpss ] 
     [m3vpss ]      |   Total    Even     Odd  Total  Even   Odd  Min /  Max  Min /  Max Dropped Fid Repeat Frame Error Y/C
     [m3vpss ]  CH  |  Fields  Fields  Fields    FPS   FPS   FPS       Width      Height  Fields      Count (Desc Error Y/C)
     [m3vpss ]  ------------------------------------------------------------------------------------------------------------
     [m3vpss ] 
     [m3vpss ]  VIP Capture Port 0 | DescMissMatch1 = 0, DescMissMatch2 = 0 , DescMissMatch3 = 0
     [m3vpss ] 
     [m3vpss ]  *** Capture List Manager Advanced Statistics ***
     [m3vpss ] 
     [m3vpss ]  List Post Count        : 1
     [m3vpss ]  List Stall Count       : 0
     [m3vpss ]  List Post Time (ms)    : Max = 0, Min = 0, Avg = 0, Total = 0
     [m3vpss ]  INTC Timeout Count     : (0, 0) (Min timeout value = 993, 1000)
     [m3vpss ]  Descriptor miss found count : 0
     [m3vpss ] 
     [m3vpss ] 
     [m3vpss ]  VIP and VPDMA registers,
     [m3vpss ]  VIP0 : FIQ_STATUS  : 0x4810551c = 0x00000000
     [m3vpss ]  VIP1 : FIQ_STATUS  : 0x48105a1c = 0x00000000
     [m3vpss ]  VPDMA: LIST_BUSY   : 0x4810d00c = 0x00000000
     [m3vpss ] 
     [m3vpss ] 
     [m3vpss ]  83442: CAPTURE: Fields = 0 (fps = 0, CPU Load = 0)
     [m3vpss ]  83442: CAPTURE: Num Resets = 0 (Avg 0 ms per reset)
     [m3vpss ]  83442: SYSTEM  : FREE SPACE : System Heap      = 67856 B, Mbx = 10239 msgs)
     [m3vpss ]  83442: SYSTEM  : FREE SPACE : SR0 Heap         = 8713728 B (8 MB)
     [m3vpss ]  83442: SYSTEM  : FREE SPACE : Frame Buffer     = 121146752 B (115 MB)
     [m3vpss ]  83442: SYSTEM  : FREE SPACE : Bitstream Buffer = 192101248 B (183 MB)
     [m3vpss ] TILER_STATS: CNT :8BIT
     [m3vpss ] TILER_STATS: CNT RESOLUTION:    16384 x 8192
     [m3vpss ] TILER_STATS: BUCKET RESOLUTION: 16384 x 8188
     [m3vpss ] TILER_STATS: NUM FREE BUCKETS:  1
     [m3vpss ] TILER_STATS: NUM USED BUCKETS:  0
     [m3vpss ] TILER_STATS: TOTAL FREE AREA:   134152192 (99 %)
     [m3vpss ] TILER_STATS: TOTAL USED AREA:   0 (0 %)
     [m3vpss ] TILER_STATS: CNT :16BIT
     [m3vpss ] TILER_STATS: CNT RESOLUTION:    32768 x 3072
     [m3vpss ] TILER_STATS: BUCKET RESOLUTION: 32768 x 3072
     [m3vpss ] TILER_STATS: NUM FREE BUCKETS:  1
     [m3vpss ] TILER_STATS: NUM USED BUCKETS:  0
     [m3vpss ] TILER_STATS: TOTAL FREE AREA:   100663296 (100 %)
     [m3vpss ] TILER_STATS: TOTAL USED AREA:   0 (0 %)
     [m3vpss ]  83443: SYSTEM  : FREE SPACE : Tiler 8-bit      = 134152192 B (127 MB)  - TILER ON
     [m3vpss ]  83444: SYSTEM  : FREE SPACE : Tiler 16-bit     = 100663296 B (96 MB)  - TILER ON
     [m3vpss ] 
     [m3vpss ]  *** [DEI0    ] DEI Statistics ***
     [m3vpss ] 
     [m3vpss ]  Elasped Time           : 0 secs
     [m3vpss ]  Total Fields Processed : 0
     [m3vpss ]  Total Fields FPS       : 0 FPS
     [m3vpss ] 
     [m3vpss ] 
     [m3vpss ]  CH  | In Recv In Reject In Process Out[0] Out[1] Out[2] Out[3] Out[4] Skip Out[0] Skip Out[1] Skip Out[2] Skip Out[3] Skip Out[4] User Out[0] User Out[1] User Out[2] User Out[3] User Out[4] Latency  
     [m3vpss ]  Num | FPS     FPS       FPS        FPS    FPS    FPS    FPS    FPS       FPS         FPS         FPS         FPS         FPS       Skip FPS    Skip FPS    Skip FPS    Skip FPS    Skip FPS   Min / Max
     [m3vpss ]  -------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------
     [m3vpss ]    0 |       0         0          0      0      0      0      0      0           0           0           0           0           0           0           0           0           0           0 255 /   0
     [m3vpss ] 
     [m3vpss ] 
     [m3vpss ]  *** [DEI1    ] DEI Statistics ***
     [m3vpss ] 
     [m3vpss ]  Elasped Time           : 0 secs
     [m3vpss ]  Total Fields Processed : 0
     [m3vpss ]  Total Fields FPS       : 0 FPS
     [m3vpss ] 
     [m3vpss ] 
     [m3vpss ]  CH  | In Recv In Reject In Process Out[0] Out[1] Out[2] Out[3] Out[4] Skip Out[0] Skip Out[1] Skip Out[2] Skip Out[3] Skip Out[4] User Out[0] User Out[1] User Out[2] User Out[3] User Out[4] Latency  
     [m3vpss ]  Num | FPS     FPS       FPS        FPS    FPS    FPS    FPS    FPS       FPS         FPS         FPS         FPS         FPS       Skip FPS    Skip FPS    Skip FPS    Skip FPS    Skip FPS   Min / Max
     [m3vpss ]  -------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------
     [m3vpss ]    0 |       0         0          0      0      0      0      0      0           0           0           0           0           0           0           0           0           0           0 255 /   0
     [m3vpss ] 
     [m3vpss ] 
     [m3vpss ]  *** [DEI2    ] DEI Statistics ***
     [m3vpss ] 
     [m3vpss ]  Elasped Time           : 0 secs
     [m3vpss ]  Total Fields Processed : 0
     [m3vpss ]  Total Fields FPS       : 0 FPS
     [m3vpss ] 
     [m3vpss ] 
     [m3vpss ]  CH  | In Recv In Reject In Process Out[0] Out[1] Out[2] Out[3] Out[4] Skip Out[0] Skip Out[1] Skip Out[2] Skip Out[3] Skip Out[4] User Out[0] User Out[1] User Out[2] User Out[3] User Out[4] Latency  
     [m3vpss ]  Num | FPS     FPS       FPS        FPS    FPS    FPS    FPS    FPS       FPS         FPS         FPS         FPS         FPS       Skip FPS    Skip FPS    Skip FPS    Skip FPS    Skip FPS   Min / Max
     [m3vpss ]  -------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------
     [m3vpss ]    0 |       0         0          0      0      0      0      0      0           0           0           0           0           0           0           0           0           0           0 255 /   0
     [m3vpss ] 
     [m3video]      89449: HDVICP-ID:0
     [m3video] All percentage figures are based off totalElapsedTime
     [m3video]               totalAcquire2wait :0 %
     [m3video]               totalWait2Isr :65 %
     [m3video]               totalIsr2Done :0 %
     [m3video]               totalWait2Done :65 %
     [m3video]               totalDone2Release :0 %
     [m3video]               totalAcquire2Release :67 %
     [m3video]               totalAcq2acqDelay :32 %
     [m3video]               totalElapsedTime in msec :    5935
     [m3video]               numAccessCnt:     356
     [m3video]              IVA-FPS :      71
     [m3video]              Average time spent per frame in microsec:   10836
     [m3video]      89450: HDVICP-ID:1
     [m3video] All percentage figures are based off totalElapsedTime
     [m3video]               totalAcquire2wait :0 %
     [m3video]               totalWait2Isr :0 %
     [m3video]               totalIsr2Done :0 %
     [m3video]               totalWait2Done :0 %
     [m3video]               totalDone2Release :0 %
     [m3video]               totalAcquire2Release :0 %
     [m3video]               totalAcq2acqDelay :0 %
     [m3video]               totalElapsedTime in msec :       0
     [m3video]               numAccessCnt:       0
     [m3video]              IVA-FPS :       0
     [m3video]              Average time spent per frame in microsec:       0
     [m3video]      89450: HDVICP-ID:2
     [m3video] All percentage figures are based off totalElapsedTime
     [m3video]               totalAcquire2wait :0 %
     [m3video]               totalWait2Isr :0 %
     [m3video]               totalIsr2Done :0 %
     [m3video]               totalWait2Done :0 %
     [m3video]               totalDone2Release :0 %
     [m3video]               totalAcquire2Release :0 %
     [m3video]               totalAcq2acqDelay :0 %
     [m3video]               totalElapsedTime in msec :       0
     [m3video]               numAccessCnt:       0
     [m3video]              IVA-FPS :       0
     [m3video]              Average time spent per frame in microsec:       0
     [m3video] 
     [m3video]  *** ENCODE Statistics ***
     [m3video] 
     [m3video]  Elasped Time           : 5 secs
     [m3video] 
     [m3video] 
     [m3video]  CH  | In Recv In Skip In User  Out Latency 
     [m3video]  Num | FPS     FPS     Skip FPS FPS Min / Max
     [m3video]  --------------------------------------------
     [m3video]    0 |      70       0        0  70 255 / 82532
     [m3video]    1 |      70       0       70   0 255 /   0
     [m3video]    2 |      70       0       70   0 255 /   0
     [m3video] 
     [m3video] Multi Channel Encode Average Submit Batch Size
     [m3video] Max Submit Batch Size : 24
     [m3video] IVAHD_0 Average Batch Size : 1
     [m3video] IVAHD_0 Max achieved Batch Size : 1
     [m3video] IVAHD_1 Average Batch Size : 0
     [m3video] IVAHD_1 Max achieved Batch Size : 0
     [m3video] IVAHD_2 Average Batch Size : 0
     [m3video] IVAHD_2 Max achieved Batch Size : 0
     [m3video] 
     [m3video] Multi Channel Encode Batch break Stats
     [m3video] Total Number of Batches created: 353
     [m3video] All numbers are based off total number of Batches created
     [m3video]       Batch breaks due to batch sizeexceeding limit: 100 %
     [m3video]       Batch breaks due to ReqObj Que being empty: 0 %
     [m3video]       Batch breaks due to changed resolution class: 0 %
     [m3video]       Batch breaks due to interlace and progressivecontent mix: 0 %
     [m3video]       Batch breaks due to channel repeat: 0 %
     [m3video]       Batch breaks due to different codec: 0 %
     [m3video] Total Number of Batches created: 0
     [m3video] All numbers are based off total number of Batches created
     [m3video]       Batch breaks due to batch sizeexceeding limit: 0 %
     [m3video]       Batch breaks due to ReqObj Que being empty: 0 %
     [m3video]       Batch breaks due to changed resolution class: 0 %
     [m3video]       Batch breaks due to interlace and progressivecontent mix: 0 %
     [m3video]       Batch breaks due to channel repeat: 0 %
     [m3video]       Batch breaks due to different codec: 0 %
     [m3video] Total Number of Batches created: 0
     [m3video] All numbers are based off total number of Batches created
     [m3video]       Batch breaks due to batch sizeexceeding limit: 0 %
     [m3video]       Batch breaks due to ReqObj Que being empty: 0 %
     [m3video]       Batch breaks due to changed resolution class: 0 %
     [m3video]       Batch breaks due to interlace and progressivecontent mix: 0 %
     [m3video]       Batch breaks due to channel repeat: 0 %
     [m3video]       Batch breaks due to different codec: 0 %
     [m3video] 89454:DECLINK::links_m3video/iva_dec/decLink_tsk.c:[33]::INTERNAL ERROR:-8
     [m3video] Link create should be first cmd.Received Cmd:8196
     [m3vpss ] 
     [m3vpss ]  92455: LOAD: CPU: 8.7% HWI: 1.1%, SWI:0.9%
     [m3vpss ] 
     [m3vpss ]  92455: LOAD: TSK: IPC_OUT_M30         : 1.2%
     [m3vpss ]  92455: LOAD: TSK: CAPTURE             : 0.4%
     [m3vpss ]  92455: LOAD: TSK: DEI0                : 1.0%
     [m3vpss ]  92455: LOAD: TSK: DEI1                : 1.1%
     [m3vpss ]  92455: LOAD: TSK: DEI2                : 0.9%
     [m3vpss ]  92455: LOAD: TSK: DUP0                : 0.3%
     [m3vpss ]  92456: LOAD: TSK: MERGE0              : 0.4%
     [m3vpss ]  92456: LOAD: TSK: MISC                : 1.4%
     [m3vpss ] 
     [m3video] 
     [m3video]  92956: LOAD: CPU: 6.0% HWI: 1.0%, SWI:0.6%
     [m3video] 
     [m3video]  92956: LOAD: TSK: IPC_IN_M30          : 0.7%
     [m3video]  92956: LOAD: TSK: IPC_BITS_OUT0       : 0.6%
     [m3video]  92957: LOAD: TSK: ENC0                : 1.5%
     [m3video]  92957: LOAD: TSK: ENC_PROCESS_TSK_0   : 1.4%
     [m3video]  92957: LOAD: TSK: MISC                : 0.2%
     [m3video] 
     [c6xdsp ] 
     [c6xdsp ]  92373: LOAD: CPU: 0.2% HWI: 0.0%, SWI:0.0%
     [c6xdsp ] 
     [c6xdsp ]  92373: LOAD: TSK: MISC                : 0.2%
     [c6xdsp ] 

    The usecase is capture-dei-enc.

    I just find the latency of encode is 82532, is the latency from capture to encode?

    What's the ldifferent of Elasped Time and latency ?

    The dei latency time is Zero. And I can not find the latency of capture.

  • Hi, Badri.

    Is there any suggestion?

  • You are capturing logs when neither capture has captured any frames nor DEI has processed any frames. I don't understand from where encoder is receiving frames. Don't refer latency printed in encLink. It is not correct.+\You can measure total latency from capture to receiving the frames on A8 by getting difference between Avsync_getWallTime - pBitBuffer->timestamp.

    Refer /dvr_rdk/demos/mcfw_api_demos/mcfw_demo/demo_vcap_venc_vdec_vdis_bits_rdwr.c

    VcapVencVdecVdis_updateStatistics

            latency = Avsync_getWallTime() - (((UInt64)pBuf->upperTimeStamp << 32) |  (UInt64)pBuf->lowerTimeStamp);
    
            if(latency > pChInfo->maxLatency)
                pChInfo->maxLatency = latency;
    
            if(latency < pChInfo->minLatency)
                pChInfo->minLatency = latency;
    

     

  • Hi, Badri.

    You mean if I change  latency frome (pBuf->encodeTimestamp - pBuf->timestamp) to  (Avsync_getWallTime() - (((UInt64)pBuf->upperTimeStamp << 32) |  (UInt64)pBuf->lowerTimeStamp) )  in VcapVenc_bitsWriteMain.

    The  time is the latency frome capture to encode?

  • The time is the latency from capture to when you receive the frame on A8 after encoding.