Hello.
I'd like to request some help with the following issue, I've been reading posts left and right about very similar problems but still haven't been able to fix it.
I'm trying to encode a 1080p60 stream, however, the encoder seems to be dropping frames. This is my usecase:
vip --> dup --> merge --> dei --> nsf --> enc
Looking at the statistics logs (attached at the end) the nsf link seems to be dropping frames. I assume this is because, further down the chain, the encoder appears to be stuck at 50fps and causes the nsf buffers to fill up... that is, if I'm reading the logs correctly.
This are the clock settings:
ARM clk: 720MHz
DDR clk: 533MHz
L3 clk: 200MHz
IVA clk: 440MHz
ISS clk: 500MHz
DSS clk: 200MHz
DSP clk: 750MHz
I'd appreciate it if someone could help me sort this out.
Thank you.
Statistics logs:
[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 ] 000 | 5381 5381 0 60 60 0 1920 / 1920 1080 / 1080 21 0 0/0 (0/0)
[m3vpss ]
[m3vpss ] VIP Capture Port 0 | DescMissMatch1 = 0, DescMissMatch2 = 0 , DescMissMatch3 = 0
[m3vpss ]
[m3vpss ] *** Capture List Manager Advanced Statistics ***
[m3vpss ]
[m3vpss ] List Post Count : 90660
[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 = 979, 989)
[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 ] VPDMA: PERF_MON34 = 0x4305ffff, PERF_MON35 = 0x4305ffff, PERF_MON38 = 0x4305ffff, PERF_MON39 = 0x4305ffff
[m3vpss ]
[m3vpss ]
[m3vpss ] 99188: CAPTURE: Fields = 5360 (fps = 59, CPU Load = 0)
[m3vpss ] 99188: CAPTURE: Num Resets = 0 (Avg 0 ms per reset)
[m3vpss ] 99188: SYSTEM : FREE SPACE : System Heap = 244984 B, Mbx = 10238 msgs)
[m3vpss ] 99188: SYSTEM : FREE SPACE : SR0 Heap = 22738176 B (21 MB)
[m3vpss ] 99188: SYSTEM : FREE SPACE : Frame Buffer = 13198208 B (12 MB)
[m3vpss ] 99188: SYSTEM : FREE SPACE : Bitstream Buffer = 157716352 B (150 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: 0
[m3vpss ] TILER_STATS: NUM USED BUCKETS: 1
[m3vpss ] TILER_STATS: TOTAL FREE AREA: 44793856 (33 %)
[m3vpss ] TILER_STATS: TOTAL USED AREA: 89358336 (66 %)
[m3vpss ] TILER_STATS: TOTAL WASTE AREA: 0 (0 %)
[m3vpss ] TILER_STATS: MAX WIDTH RECT: 16384 x 1596
[m3vpss ] TILER_STATS: MAX HEIGHT RECT: 16384 x 1596
[m3vpss ] TILER_STATS: RES ALLOC COUNT [1080P] : 42
[m3vpss ] TILER_STATS: CNT :16BIT
[m3vpss ] TILER_STATS: CNT RESOLUTION: 32768 x 4096
[m3vpss ] TILER_STATS: BUCKET RESOLUTION: 32768 x 4096
[m3vpss ] TILER_STATS: NUM FREE BUCKETS: 0
[m3vpss ] TILER_STATS: NUM USED BUCKETS: 1
[m3vpss ] TILER_STATS: TOTAL FREE AREA: 89399296 (66 %)
[m3vpss ] TILER_STATS: TOTAL USED AREA: 44818432 (33 %)
[m3vpss ] TILER_STATS: TOTAL WASTE AREA: 139264 (0 %)
[m3vpss ] TILER_STATS: MAX WIDTH RECT: 32768 x 1888
[m3vpss ] TILER_STATS: MAX HEIGHT RECT: 32768 x 1888
[m3vpss ] TILER_STATS: RES ALLOC COUNT [1080P] : 42
[m3vpss ] 99191: SYSTEM : FREE SPACE : Tiler 8-bit = 44793856 B (42 MB) - TILER ON
[m3vpss ] 99191: SYSTEM : FREE SPACE : Tiler 16-bit = 89399296 B (85 MB) - TILER ON
[m3vpss ]
[m3vpss ] *** [NSF0] NSF Statistics ***
[m3vpss ]
[m3vpss ] Elasped Time : 90 secs
[m3vpss ] Total Fields Processed : 3386
[m3vpss ] Total Fields FPS : 65 FPS
[m3vpss ]
[m3vpss ]
[m3vpss ] CH | In Recv In Reject In Process Out User Out Out
[m3vpss ] Num | FPS FPS FPS FPS Skip FPS Skip FPS
[m3vpss ] ------------------------------------------------
[m3vpss ] 0 | 44 7 37 37 0 7
[m3vpss ] 1 | 0 0 0 0 0 0
[m3vpss ] 2 | 0 0 0 0 0 0
[m3vpss ]
[m3vpss ]
[m3vpss ] *** [DEI2 ] DEI Statistics ***
[m3vpss ]
[m3vpss ] Elasped Time : 90 secs
[m3vpss ] Total Fields Processed : 4044
[m3vpss ] Total Fields FPS : 111 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 | 59 14 44 44 0 0 0 0 0 44 0 0 0 0 0 0 0 0 11 / 31
[m3vpss ] 1 | 59 59 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 255 / 0
[m3vpss ] 2 | 59 59 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 255 / 0
[m3vpss ]
[m3video]
[m3video] *** (VCOP) OSD Statistics ***
[m3video]
[m3video] Elasped Time : 92 secs
[m3video] Total Fields Processed : 5506
[m3video] Total Fields FPS : 102 FPS
[m3video] Latency AVG/MIN/MAX : 0/0/2 ms
[m3video]
[m3video] Detailed Internal Profile Log,
[m3video] - OSD ALG Total time : 0 msecs (0 % of elasped time)
[m3video] - GATHER Total time : 0 msecs (0 % of elasped time)
[m3video] - BLEND Total time : 0 msecs (0 % of elasped time)
[m3video] - SCATTER Total time : 0 msecs (0 % of elasped time)
[m3video] - EDMA Total time : 0 msecs (0 % of elasped time)
[m3video] - VCOP Total time : 0 msecs (0 % of elasped time)
[m3video] - SCAT/GATH CPU time : 0 msecs (0 % of elasped time)
[m3video] - BLEND CPU time : 0 msecs (0 % of elasped time)
[m3video]
[m3video]
[m3video] CH | In Recv In Que In Reject In Process
[m3video] Num | FPS FPS FPS FPS
[m3video] ------------------------------------------
[m3video] 0 | 59 59 0 59
[m3video]
[m3video]
[m3video] *** (VCOP) SCD Statistics ***
[m3video]
[m3video] Elasped Time : 101 secs
[m3video] Total Fields Processed : 0
[m3video] Total Fields FPS : 0 FPS
[m3video]
[m3video] Detailed Internal Profile Log,
[m3video] - SCD ALG Total time : 0 msecs (0 % of elasped time)
[m3video] - VCOP Total time : 0 msecs (0 % of elasped time)
[m3video] - SCD ALG CPU time : 0 msecs (0 % of elasped time)
[m3video]
[m3video]
[m3video] CH | In Recv In Skip In Process
[m3video] Num | FPS FPS FPS
[m3video] ---------------------------------
[m3video] 0 | 0 0 0
[m3video]
[m3video] 101813: HDVICP-ID:0
[m3video] All percentage figures are based off totalElapsedTime
[m3video] totalAcquire2wait :1 %
[m3video] totalWait2Isr :97 %
[m3video] totalIsr2Done :0 %
[m3video] totalWait2Done :97 %
[m3video] totalDone2Release :0 %
[m3video] totalAcquire2Release :98 %
[m3video] totalAcq2acqDelay :1 %
[m3video] totalElapsedTime in msec : 69670
[m3video] numAccessCnt: 3508
[m3video] IVA-FPS : 50
[m3video] Average time spent per frame in microsec: 19264
[m3video] 101814: 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] 101815: 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 : 69 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 | 50 0 0 50 255 / -1354904534
[m3video] 1 | 0 0 0 0 255 / 0
[m3video] 2 | 0 0 0 0 255 / 0
[m3video]
[m3video] *** ENCODER I/O stats ***
[m3video] CH: 0 In Process: 3518 Out Process: 3512
[m3video] CH: 1 In Process: 0 Out Process: 0
[m3video] CH: 2 In Process: 0 Out Process: 0
[m3video] ENC: Get Frames: 3518 Put Frames: 3507
[m3video] Idx: |Addr: |Get Cnt: |Put Cnt: |Processing: |Channel: |
[m3video] 0 |3f6dad24 |294 |293 |0 |0 |
[m3video] 1 |3f6dad60 |293 |292 |1 |0 |
[m3video] 2 |3f6dad9c |294 |293 |0 |0 |
[m3video] 3 |3f6dadd8 |293 |293 |0 |0 |
[m3video] 4 |3f6dae14 |293 |292 |1 |0 |
[m3video] 5 |3f6dae50 |293 |292 |1 |0 |
[m3video] 6 |3f6dae8c |293 |292 |1 |0 |
[m3video] 7 |3f6daec8 |293 |292 |0 |0 |
[m3video] 8 |3f6daf04 |293 |292 |0 |0 |
[m3video] 9 |3f6daf40 |293 |292 |1 |0 |
[m3video] 10 |3f6daf7c |293 |292 |1 |0 |
[m3video] 11 |3f6dafb8 |293 |292 |0 |0 |
[m3video] ReqObj in use by Codec:
[m3video] Addr |InCodec |
[m3video] 3e162768 |1 |
[m3video] 3e19b62c |1 |
[m3video] 3e19c49c |1 |
[m3video] 3e19eb1c |1 |
[m3video] 3e1a1c70 |1 |
[m3video] 3e1a95f4 |1 |
[m3video]
[m3video] *** Encode Statistics ***
[m3vpss ]
[m3video] 101821: ENC: Rcvd from prev = 3518, Returned to prev = 3507
[m3vpss ] 102024: LOAD: CPU: 22.3% HWI: 2.7%, SWI:3.5%
[m3vpss ]
[m3video] ENC Out BitBuf Q Status
[m3vpss ] 102024: LOAD: TSK: IPC_OUT_M30 : 0.7%
[m3video] Empty Q 0 -> count 29, wrPtr 91, rdPtr 62
[m3video] Full Q -> count 0, wrPtr 51, rdPtr 51
[m3vpss ] 102024: LOAD: TSK: IPC_OUT_M31 : 1.0%
[m3vpss ] 102024: LOAD: TSK: IPC_IN_M31 : 0.4%
[m3video] Multi Channel Encode Average Submit Batch Size
[m3video] Max Submit Batch Size : 24
[m3vpss ] 102024: LOAD: TSK: CAPTURE : 3.1%
[m3video] IVAHD_0 Average Batch Size : 1
[m3video] IVAHD_0 Max achieved Batch Size : 1
[m3vpss ] 102024: LOAD: TSK: NSF0 : 0.7%
[m3video]
[m3video] Multi Channel Encode Batch break Stats
[m3vpss ] 102025: LOAD: TSK: DEI2 : 1.9%
[m3video] Total Number of Batches created: 3509
[m3video] All numbers are based off total number of Batches created
[m3vpss ] 102025: LOAD: TSK: DUP0 : 0.4%
[m3video] Batch breaks due to batch sizeexceeding limit: 100 %
[m3video] Batch breaks due to ReqObj Que being empty: 0 %
[m3vpss ] 102025: LOAD: TSK: MERGE0 : 0.3%
[m3video] Batch breaks due to changed resolution class: 0 %
[m3video] Batch breaks due to interlace and progressivecontent mix: 0 %
[m3vpss ] 102026: LOAD: TSK: MISC : 7.6%
[m3video] Batch breaks due to channel repeat: 0 %
[m3video] Batch breaks due to different codec: 0 %
[m3vpss ]
[m3video]
[m3video] 102527: LOAD: CPU: 16.4% HWI: 2.3%, SWI:2.1%
[m3video]
[m3video] 102527: LOAD: TSK: IPC_OUT_M31 : 1.5%
[m3video] 102528: LOAD: TSK: IPC_IN_M30 : 0.4%
[m3video] 102528: LOAD: TSK: IPC_IN_M31 : 0.6%
[m3video] 102528: LOAD: TSK: IPC_BITS_OUT0 : 1.1%
[m3video] 102528: LOAD: TSK: VCOP : 0.6%
[m3video] 102528: LOAD: TSK: ENC0 : 1.6%
[m3video] 102528: LOAD: TSK: ALG0 : 3.7%
[m3video] 102528: LOAD: TSK: ENC_PROCESS_TSK_0 : 1.8%
[m3video] 102529: LOAD: TSK: MISC : 0.7%
[m3video]
[m3vpss ]
[m3vpss ] *** CAPTURE Statistics ***
[m3vpss ] 103130: CAPTURE: Queued to driver = 5612, Dequeued from driver = 5597
[m3vpss ] CAPTURE [0] Buf Q Status
[m3vpss ] Empty Q -> count 0, wrPtr 0, rdPtr 0
[m3vpss ] Full Q -> count 0, wrPtr 221, rdPtr 221
[m3vpss ]
[m3vpss ] *** [NSF0] NSF Statistics ***
[m3vpss ] 103132: NSF: Rcvd from prev = 4267, Returned to prev = 4267
[m3vpss ] NSF Out [0] Buf Q Status
[m3vpss ] Empty Q -> count 0, wrPtr 0, rdPtr 0
[m3vpss ] Full Q -> count 1, wrPtr 128, rdPtr 127
[m3vpss ]
[m3vpss ] *** [DEI2 ] DEI Statistics ***
[m3vpss ] 103135: DEI: Rcvd from prev = 16791, Returned to prev = 16791
[m3vpss ] DEI Out [0] Buf Q Status
[m3vpss ] Empty Q -> count 0, wrPtr 0, rdPtr 0
[m3vpss ] Full Q -> count 0, wrPtr 44, rdPtr 44
[m3vpss ] DEI Out [1] Buf Q Status
[m3vpss ] Empty Q -> count 0, wrPtr 0, rdPtr 0
[m3vpss ] Full Q -> count 0, wrPtr 0, rdPtr 0
[m3vpss ] DEI Out [2] Buf Q Status
[m3vpss ] Empty Q -> count 0, wrPtr 0, rdPtr 0
[m3vpss ] Full Q -> count 0, wrPtr 0, rdPtr 0
[m3vpss ] DEI Out [3] Buf Q Status
[m3vpss ] Empty Q -> count 0, wrPtr 0, rdPtr 0
[m3vpss ] Full Q -> count 0, wrPtr 0, rdPtr 0
[m3vpss ] DEI Out [4] Buf Q Status
[m3vpss ] Empty Q -> count 0, wrPtr 0, rdPtr 0
[m3vpss ] Full Q -> count 0, wrPtr 0, rdPtr 0
[m3video]
[m3video] *** Encode Statistics ***
[m3video] 103637: ENC: Rcvd from prev = 3609, Returned to prev = 3598
[m3video] ENC Out BitBuf Q Status
[m3video] Empty Q 0 -> count 29, wrPtr 182, rdPtr 153
[m3video] Full Q -> count 0, wrPtr 142, rdPtr 142