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.

TDA4VM: decode crash

Part Number: TDA4VM

Hello TI experts:

The RTOS SDK version I use is 7.2.
I use tivxVideoDecoderNode to decode the h264 video. After running for a while, the decoder will crash.

MCU2_1 log:

[MCU2_1]    188.973944 s: fw decode time for msg_id x0 is not measured
[MCU2_1]    188.974161 s: TID=NULL [GENERAL FIRMWARE PANIC Reason(s): Watch Dog Timeo]
[MCU2_1]    188.974241 s:  Assertion @ Line: 930 in /ti-processor-sdk-rtos-j721e-evm-07_02_00_06/video_codec/ti-img-encode-decode/tirtos/decoder/../../driver/decoder/hw_control.c: !(0) : failed !!!
[MCU2_1]    188.974350 s:  Assertion @ Line: 930 in /ti-processor-sdk-rtos-j721e-evm-07_02_00_06/video_codec/ti-img-encode-decode/tirtos/decoder/../../driver/decoder/hw_control.c: !(0) : failed !!!
[MCU2_1]    188.974448 s:  Assertion @ Line: 4349 in /ti-processor-sdk-rtos-j721e-evm-07_02_00_06/video_codec/ti-img-encode-decode/tirtos/decoder/../../driver/decoder/decoder.c: !(ret == (0)) : failed !!!
[MCU2_1]    188.974541 s:  Assertion @ Line: 4349 in /ti-processor-sdk-rtos-j721e-evm-07_02_00_06/video_codec/ti-img-encode-decode/tirtos/decoder/../../driver/decoder/decoder.c: !(ret == (0)) : failed !!!
[MCU2_1]    188.974645 s:  Assertion @ Line: 367 in /ti-processor-sdk-rtos-j721e-evm-07_02_00_06/video_codec/ti-img-encode-decode/tirtos/decoder/../../driver/decoder/core.c: !(ret == (0)) : failed !!!
[MCU2_1]    188.974750 s:  Assertion @ Line: 367 in /ti-processor-sdk-rtos-j721e-evm-07_02_00_06/video_codec/ti-img-encode-decode/tirtos/decoder/../../driver/decoder/core.c: !(ret == (0)) : failed !!!
[MCU2_1]    188.974834 s: decoder_service_firmware_response failed
[MCU2_1]    188.974909 s: [TID=0x00150655] [DECODE_FAILED]
 
  • Hello TI experts:

    I turned on the debug switch and grabbed a log,
    [MCU2_1]    227.953071 s: [HWCTRL] send batch message
    [MCU2_1]    227.953131 s: [HWCTRL] adding message to vxd queue
    [MCU2_1]    227.953186 s: vxd_send_msg: added item d0112000 to pend, ptd: 0xcf4490, str: 1 flags: 0x0
    [MCU2_1]    227.953230 s: vxd_make_hw_on_locked:132
    [MCU2_1]    227.953259 s: vxd_sched_single_locked: checking msg_size: 13, item: d0112000
    [MCU2_1]    227.953338 s: vxd_pvdec_send_msg: [msg out] size: 13, id: 0x6601, type: 0x89
    [MCU2_1]    227.953378 s: vxd_pvdec_send_msg: to_mtx: (313 @ 50), wr_idx: 126, rd_idx: 126
    [MCU2_1]    227.953427 s: vxd_sched_single_locked: moving item d0112000, id 0x6601 to msgs
    [MCU2_1]    227.953473 s: vxd_monitor_locked: scheduling DWR work (1000 ms)!
    [MCU2_1]    227.953684 s: [USERSID=0x00000001] [TID=0x00160366] START
    [MCU2_1]    227.953772 s: core_stream_submit_unit stream unit type = 7
    [MCU2_1]    227.953811 s: decoder_stream_process_unit : stream unit type = 7
    [MCU2_1]    227.953867 s: [USERSID=0x00000001] [TID=0x00160366] END
    [MCU2_1]    227.953929 s:  VX_ZONE_ERROR:[tivxVideoDecoderProcess:389] wait dec done s
    [MCU2_1]    227.956026 s: vxd_pvdec_pend_msg_info: to host: (34 @ 16), wr: 2, rd: 29
    [MCU2_1]    227.956087 s: vxd_pvdec_pend_msg_info: [msg in] rd_idx: 29, size: 7, id: 0x6601, type: 0xc0
    [MCU2_1]    227.956133 s: vxd_handle_single_msg_locked: [msg] size: 7, cnt: 102, str_id: 1, id: 0x6601
    [MCU2_1]    227.956171 s: vxd_handle_single_msg_locked: [msg] not last: 0
    [MCU2_1]    227.956211 s: vxd_handle_single_msg_locked: checking item d0112000 [0x6601] [des: 0]
    [MCU2_1]    227.956248 s: vxd_handle_single_msg_locked: found item d0112000 [destroy: 0]
    [MCU2_1]    227.956292 s: vxd_pvdec_recv_msg: to host: (34 @ 16), wr: 2, rd: 29
    [MCU2_1]    227.956369 s: fw decode time is 3034 us for msg_id x6601
    [MCU2_1]    227.956400 s: vxd_handle_single_msg_locked: msg 0: 0x6601c007
    [MCU2_1]    227.956429 s: vxd_handle_single_msg_locked: msg 1: 0x80913050
    [MCU2_1]    227.956459 s: vxd_handle_single_msg_locked: msg 2: 0x00160366
    [MCU2_1]    227.956495 s: vxd_handle_single_msg_locked: msg 3: 0x82880000
    [MCU2_1]    227.956525 s: vxd_handle_single_msg_locked: msg 4: 0x00000000
    [MCU2_1]    227.956553 s: vxd_handle_single_msg_locked: msg 5: 0x00000000
    [MCU2_1]    227.956581 s: vxd_handle_single_msg_locked: msg 6: 0x00000000
    [MCU2_1]    227.956615 s: vxd_handle_single_msg_locked: adding to done list, item: d0112000, msg_size: 7
    [MCU2_1]    227.956704 s: vxd_handle_single_msg_locked: signaling worker for cf309000
    [MCU2_1]    227.956769 s: vxd_pvdec_pend_msg_info: to host: (34 @ 16), wr: 2, rd: 2
    [MCU2_1]    227.956815 s: vxd_monitor_locked: scheduling APM work (1000 ms)!
    [MCU2_1]    227.956917 s: stream_worker: got work for ctx cf309000
    [MCU2_1]    227.956956 s: stream_worker: item: d0112000, payload_size: 28, flags: 0x0
    [MCU2_1]    227.957008 s: decoder_service_firmware_response : process firmware response
    [MCU2_1]    227.957045 s: [HWCTRL] : process message
    [MCU2_1]    227.957071 s: Received message from firmware
    [MCU2_1]    227.957137 s: [USERSID=0x00000001] [TID=0x00160366] DECODED
    [MCU2_1]    227.957281 s: decoder_picture_decoded : number of picture decoded = 2
    [MCU2_1]    227.957351 s: core_stream_processed_cb callback type = 3
    [MCU2_1]    227.957437 s: [USERSID=0x00000001] [TID=0x00160366] DISPLAY
    [MCU2_1]    227.957493 s: [USERSID=0x00000001] DISPLAY(870): PIC_ID[870]
    [MCU2_1]    227.957526 s: core_stream_processed_cb callback type = 4
    [MCU2_1]    227.957576 s: [USERSID=0x00000001] RELEASE( ): PIC_ID[869]
    [MCU2_1]    227.957621 s: Decoder picture released pict_id = 869
    [MCU2_1]    227.957769 s: core_stream_processed_cb callback type = 5
    [MCU2_1]    227.957877 s: [USERSID=0x00000001] [TID=0x00150365] COMPLETE
    [MCU2_1]    227.958034 s: core_stream_processed_cb callback type = 0
    [MCU2_1]    227.958072 s: core_handle_processed_unit stream unit type = 5
    [MCU2_1]    227.958139 s: [SID=0x03000001] [UTYPE=0x00000005] PROCESSED
    [MCU2_1]    227.958236 s:  VX_ZONE_ERROR:[tivxVideoDecoderProcess:391] wait dec done e
    [MCU2_1]    227.985544 s: MM_DEC_BufPrepare:This buffer is already mapped
    [MCU2_1]    227.985620 s: MM_DEC_BufPrepare:This buffer is already mapped
    [MCU2_1]    227.986237 s: Unit Parser:Found SEQUENCE_PARAMETER_SET NAL unit
    [MCU2_1]    227.986306 s: Parsing Sequence Parameter Set
    [MCU2_1]    227.986370 s: This is High Profile Bitstream
    [MCU2_1]    227.986703 s: vui_parameters_present_flag is available
    [MCU2_1]    227.987800 s: Unit Parser:Found PICTURE DATA unit
    [MCU2_1]    227.988102 s: core_stream_submit_unit stream unit type = 1
    [MCU2_1]    227.988229 s: vdecddutils_GetRenderInfo() plane 0 stride 640 size 327680 offset 0
    [MCU2_1]    227.988277 s: vdecddutils_GetRenderInfo() plane 1 stride 640 size 163840 offset 327680
    [MCU2_1]    227.988344 s: vdecddutils_GetRenderInfo() total 491520 (inc. alignment for addressing/tiling)
    [MCU2_1]    227.988441 s: [SID=0x03000001] VSH: Maximum Frame Resolution [640x512]
    [MCU2_1]    227.988500 s: decoder_stream_process_unit : stream unit type = 1
    [MCU2_1]    227.988544 s: core_stream_processed_cb callback type = 0
    [MCU2_1]    227.988576 s: core_handle_processed_unit stream unit type = 1
    [MCU2_1]    227.988616 s: [SID=0x03000001] [UTYPE=0x00000001] PROCESSED
    [MCU2_1]    227.988747 s: core_stream_submit_unit stream unit type = 5
    [MCU2_1]    227.989001 s: [USERSID=0x00000001] MB parameter buffer device virtual address: 0xE44D5000
    [MCU2_1]    227.989056 s: [USERSID=0x00000001] Display picture virtual address: LUMA 0x00400000, CHROMA 0x00450000
    [MCU2_1]    227.989107 s: decoder_stream_process_unit : stream unit type = 5
    [MCU2_1]    227.990944 s: translation_set_buffer image_buffer->byte_offset[0] = 0x400000
    [MCU2_1]    227.990998 s: translation_set_buffer image_buffer->byte_offset[1] = 0x450000
    [MCU2_1]    227.991036 s: translation_set_buffer image_buffer->byte_offset[2] = 0x400000
    [MCU2_1]    227.991072 s: translation_set_buffer image_buffer->byte_offset[3] = 0x400000
    [MCU2_1]    227.992140 s: ctrl_alloc_buf[0] == d0000041
    [MCU2_1]    227.992214 s: ctrl_alloc_buf[1] == 0004070f
    [MCU2_1]    227.992247 s: ctrl_alloc_buf[2] == 00000031
    [MCU2_1]    227.992270 s: ctrl_alloc_buf[3] == d2040000
    [MCU2_1]    227.992293 s: ctrl_alloc_buf[4] == 000406de
    [MCU2_1]    227.992365 s: ctrl_alloc_buf[5] == d2040031
    [MCU2_1]    227.992401 s: ctrl_alloc_buf[6] == 90000000
    [MCU2_1]    227.992427 s: ctrl_alloc_buf[7] == 00000000
    [MCU2_1]    227.992453 s: ctrl_alloc_buf[8] == 00000000
    [MCU2_1]    227.992493 s: ctrl_alloc_buf[9] == 00000280
    [MCU2_1]    227.992519 s: ctrl_alloc_buf[10] == 00000000
    [MCU2_1]    227.992543 s: ctrl_alloc_buf[11] == 00000000
    [MCU2_1]    227.992572 s: ctrl_alloc_buf[12] == 00000000
    [MCU2_1]    227.992597 s: ctrl_alloc_buf[13] == 00000000
    [MCU2_1]    227.992621 s: ctrl_alloc_buf[14] == 00000400
    [MCU2_1]    227.992694 s: ctrl_alloc_buf[15] == 00000001
    [MCU2_1]    227.992724 s: ctrl_alloc_buf[16] == c0000000
    [MCU2_1]    227.992757 s: ctrl_alloc_buf[17] == 00170367
    [MCU2_1]    227.992786 s: ctrl_alloc_buf[18] == e4344000
    [MCU2_1]    227.992810 s: ctrl_alloc_buf[19] == 0000012c
    [MCU2_1]    227.992833 s: ctrl_alloc_buf[20] == e4426000
    [MCU2_1]    227.992857 s: ctrl_alloc_buf[21] == e4449000
    [MCU2_1]    227.992880 s: ctrl_alloc_buf[22] == e4450000
    [MCU2_1]    227.992903 s: ctrl_alloc_buf[23] == d2000000
    [MCU2_1]    227.992926 s: ctrl_alloc_buf[24] == d203e000
    [MCU2_1]    227.992949 s: ctrl_alloc_buf[25] == 00000000
    [MCU2_1]    227.992978 s: ctrl_alloc_buf[26] == 00000000
    [MCU2_1]    227.993003 s: ctrl_alloc_buf[27] == 00000000
    [MCU2_1]    227.993029 s: ctrl_alloc_buf[28] == 00000000
    [MCU2_1]    227.993053 s: ctrl_alloc_buf[29] == 00000000
    [MCU2_1]    227.993076 s: ctrl_alloc_buf[30] == 00400000
    [MCU2_1]    227.993099 s: ctrl_alloc_buf[31] == 00450000
    [MCU2_1]    227.993122 s: ctrl_alloc_buf[32] == e445c000
    [MCU2_1]    227.993146 s: ctrl_alloc_buf[33] == e44ac000
    [MCU2_1]    227.993169 s: ctrl_alloc_buf[34] == 00000000
    [MCU2_1]    227.993192 s: ctrl_alloc_buf[35] == 00000000
    [MCU2_1]    227.993215 s: ctrl_alloc_buf[36] == 00000000
    [MCU2_1]    227.993238 s: ctrl_alloc_buf[37] == 00000000
    [MCU2_1]    227.993261 s: ctrl_alloc_buf[38] == 00000000
    [MCU2_1]    227.993284 s: ctrl_alloc_buf[39] == 00000000
    [MCU2_1]    227.993309 s: ctrl_alloc_buf[40] == 00000000
    [MCU2_1]    227.993368 s: ctrl_alloc_buf[41] == 00000000
    [MCU2_1]    227.993402 s: ctrl_alloc_buf[42] == 001ff27f
    [MCU2_1]    227.993428 s: ctrl_alloc_buf[43] == 00000000
    [MCU2_1]    227.993452 s: ctrl_alloc_buf[44] == e4000000
    [MCU2_1]    227.993480 s: ctrl_alloc_buf[45] == 00008000
    [MCU2_1]    227.993504 s: ctrl_alloc_buf[46] == 00018000
    [MCU2_1]    227.993528 s: ctrl_alloc_buf[47] == 00400000
    [MCU2_1]    227.993551 s: ctrl_alloc_buf[48] == 00000000
    [MCU2_1]    227.993577 s: ctrl_alloc_buf[49] == 00000000
    [MCU2_1]    227.993601 s: ctrl_alloc_buf[50] == 00000000
    [MCU2_1]    227.993656 s: ctrl_alloc_buf[51] == 00180000
    [MCU2_1]    227.993692 s: ctrl_alloc_buf[52] == e4031000
    [MCU2_1]    227.993718 s: ctrl_alloc_buf[53] == 00000400
    [MCU2_1]    227.993742 s: ctrl_alloc_buf[54] == 00000000
    [MCU2_1]    227.993765 s: ctrl_alloc_buf[55] == e0000440
    [MCU2_1]    227.993788 s: ctrl_alloc_buf[56] == e4332000
    [MCU2_1]    227.993818 s: ctrl_alloc_buf[57] == 181060ec
    [MCU2_1]    227.993842 s: ctrl_alloc_buf[58] == 00026000
    [MCU2_1]    227.993869 s: ctrl_alloc_buf[59] == 000738a0
    [MCU2_1]    227.993894 s: ctrl_alloc_buf[60] == 000828f4
    [MCU2_1]    227.993917 s: ctrl_alloc_buf[61] == 000a312d
    [MCU2_1]    227.993941 s: ctrl_alloc_buf[62] == 000b5959
    [MCU2_1]    227.993964 s: ctrl_alloc_buf[63] == 000c517b
    [MCU2_1]    227.993987 s: ctrl_alloc_buf[64] == 000d1196
    [MCU2_1]    227.994010 s: ctrl_alloc_buf[65] == 000db1ad
    [MCU2_1]    227.994033 s: ctrl_alloc_buf[66] == 000e21be
    [MCU2_1]    227.994056 s: ctrl_alloc_buf[67] == 000e59c8
    [MCU2_1]    227.994079 s: ctrl_alloc_buf[68] == 000e79cd
    [MCU2_1]    227.994102 s: ctrl_alloc_buf[69] == 000eb1d3
    [MCU2_1]    227.994128 s: ctrl_alloc_buf[70] == 000ed1d8
    [MCU2_1]    227.994153 s: ctrl_alloc_buf[71] == 000f09dd
    [MCU2_1]    227.994176 s: ctrl_alloc_buf[72] == 000f71e7
    [MCU2_1]    227.994199 s: ctrl_alloc_buf[73] == 001041f6
    [MCU2_1]    227.994226 s: ctrl_alloc_buf[74] == 180361c0
    [MCU2_1]    227.994251 s: ctrl_alloc_buf[75] == 0010920e
    [MCU2_1]    227.994274 s: ctrl_alloc_buf[76] == 0010d216
    [MCU2_1]    227.994297 s: ctrl_alloc_buf[77] == 0010f21c
    [MCU2_1]    227.994356 s: ctrl_alloc_buf[78] == 1804612c
    [MCU2_1]    227.994383 s: ctrl_alloc_buf[79] == 1256a4dd
    [MCU2_1]    227.994415 s: ctrl_alloc_buf[80] == 01489292
    [MCU2_1]    227.994441 s: ctrl_alloc_buf[81] == 11248050
    [MCU2_1]    227.994465 s: ctrl_alloc_buf[82] == 000022a2
    [MCU2_1]    227.994494 s: ctrl_alloc_buf[83] == 1803613c
    [MCU2_1]    227.994518 s: ctrl_alloc_buf[84] == 00002a02
    [MCU2_1]    227.994541 s: ctrl_alloc_buf[85] == 8108282a
    [MCU2_1]    227.994565 s: ctrl_alloc_buf[86] == 00000012
    [MCU2_1]    227.994588 s: ctrl_alloc_buf[87] == 50017040
    [MCU2_1]    227.994611 s: ctrl_alloc_buf[88] == 00000280
    [MCU2_1]    227.994680 s: ctrl_alloc_buf[89] == 500171b4
    [MCU2_1]    227.994708 s: ctrl_alloc_buf[90] == 00000000
    [MCU2_1]    227.994731 s: ctrl_alloc_buf[91] == 60000000
    [MCU2_1]    227.994790 s: [HWCTRL] send batch message
    [MCU2_1]    227.994844 s: [HWCTRL] adding message to vxd queue
    [MCU2_1]    227.994899 s: vxd_send_msg: added item d0116000 to pend, ptd: 0xcf4490, str: 1 flags: 0x0
    [MCU2_1]    227.994944 s: vxd_make_hw_on_locked:132
    [MCU2_1]    227.994980 s: vxd_sched_single_locked: checking msg_size: 13, item: d0116000
    [MCU2_1]    227.995042 s: vxd_pvdec_send_msg: [msg out] size: 13, id: 0x6701, type: 0x89
    [MCU2_1]    227.995081 s: vxd_pvdec_send_msg: to_mtx: (313 @ 50), wr_idx: 139, rd_idx: 139
    [MCU2_1]    227.995131 s: vxd_sched_single_locked: moving item d0116000, id 0x6701 to msgs
    [MCU2_1]    227.995172 s: vxd_monitor_locked: scheduling DWR work (1000 ms)!
    [MCU2_1]    227.995281 s: [USERSID=0x00000001] [TID=0x00170367] START
    [MCU2_1]    227.995414 s: core_stream_submit_unit stream unit type = 7
    [MCU2_1]    227.995469 s: decoder_stream_process_unit : stream unit type = 7
    [MCU2_1]    227.995527 s: [USERSID=0x00000001] [TID=0x00170367] END
    [MCU2_1]    227.995582 s:  VX_ZONE_ERROR:[tivxVideoDecoderProcess:389] wait dec done s
    [MCU2_1]    228.146725 s: vxd_pvdec_pend_msg_info: to host: (34 @ 16), wr: 6, rd: 2
    [MCU2_1]    228.146794 s: vxd_pvdec_pend_msg_info: [msg in] rd_idx: 2, size: 4, id: 0x0000, type: 0xc1
    [MCU2_1]    228.146838 s: vxd_handle_single_msg_locked: [msg] size: 4, cnt: 0, str_id: 0, id: 0x0
    [MCU2_1]    228.146881 s: vxd_handle_single_msg_locked: [msg] not last: 0
    [MCU2_1]    228.146925 s: vxd_handle_single_msg_locked: checking item d0116000 [0x6701] [des: 0]
    [MCU2_1]    228.146961 s: vxd_handle_single_msg_locked: found item 0 [destroy: -1]
    [MCU2_1]    228.147045 s: vxd_pvdec_recv_msg: to host: (34 @ 16), wr: 6, rd: 2
    [MCU2_1]    228.147102 s: fw decode time for msg_id x0 is not measured
    [MCU2_1]    228.147134 s: vxd_get_orphaned_item_locked: item: d0118000 str_id: 1
    [MCU2_1]    228.147169 s: vxd_get_orphaned_item_locked: msg 0: 0x0000c104
    [MCU2_1]    228.147201 s: vxd_get_orphaned_item_locked: msg 1: 0x04800484
    [MCU2_1]    228.147232 s: vxd_get_orphaned_item_locked: msg 2: 0x00000000
    [MCU2_1]    228.147263 s: vxd_get_orphaned_item_locked: msg 3: 0x10000000
    [MCU2_1]    228.147313 s: vxd_pvdec_pend_msg_info: to host: (34 @ 16), wr: 6, rd: 6
    [MCU2_1]    228.147360 s: fw fatal condition: resetting!!!
    [MCU2_1]    228.147387 s: vxd_emrg_reset_locked: enter
    [MCU2_1]    228.147430 s: vxd_report_item_locked: waking cf309000
    [MCU2_1]    228.147457 s: vxd_report_item_locked: signaling worker for cf309000
    [MCU2_1]    228.147514 s: vxd_report_item_locked: waking cf309000
    [MCU2_1]    228.147543 s: vxd_report_item_locked: signaling worker for cf309000
    [MCU2_1]    228.147623 s: stream_worker: got work for ctx cf309000
    [MCU2_1]    228.147735 s: stream_worker: item: d0118000, payload_size: 16, flags: 0x40
    [MCU2_1]    228.147790 s: decoder_service_firmware_response : process firmware response
    [MCU2_1]    228.147826 s: [HWCTRL] : process message
    [MCU2_1]    228.147870 s: TID=NULL [GENERAL FIRMWARE PANIC Reason(s): Watch Dog Timeo]
    


    The error place looks like getting a wrong msg id,


    Best Regards
    thanks

  • Hi Yu,

    This is a very old SDK, and we no longer support the Codec from the R5F firmware-side.

    What is the HLOS you are using? And please try the newer 7.3 SDK to check if the issue still persists?

    regards

    Suman