Dear experts,
We ran into a issue that rtos decoder graph verify failed.
Reproduce steps are:
(PSDKRA 6.2 version on TDA4VMXEVM)
1. run "VX_TEST_DATA_PATH=/opt/vision_apps/test_data ./vx_app_conformance.out --filter=tivxHwaVideoDecoder.MultiStreamProcessing" for at least 3~5 times.
2. you will see "[ FAILED ] 1 test(s)"
Here is my log: from TIOVX
118.037524 s: VX_ZONE_INFO:[ownGraphScheduleGraph:763] Scheduling Graph (graph=13, pipe=0)
118.037578 s: VX_ZONE_INFO:[ownNodeKernelSchedule:570] Scheduling Node (node=8, pipe=0)
118.037607 s: VX_ZONE_INFO:[ownNodeKernelSchedule:570] Scheduling Node (node=7, pipe=0)
118.072115 s: VX_ZONE_INFO:[ownCheckGraphCompleted:676] Graph Completed (graph=13, pipe=0)
118.072144 s: VX_ZONE_INFO:[ownCheckGraphCompleted:701] All Graphs Completed
118.717528 s: VX_ZONE_INFO:[ownGraphScheduleGraph:763] Scheduling Graph (graph=13, pipe=0)
118.717550 s: VX_ZONE_INFO:[ownNodeKernelSchedule:570] Scheduling Node (node=8, pipe=0)
118.717595 s: VX_ZONE_INFO:[ownNodeKernelSchedule:570] Scheduling Node (node=7, pipe=0)
118.752087 s: VX_ZONE_INFO:[ownCheckGraphCompleted:676] Graph Completed (graph=13, pipe=0)
118.752116 s: VX_ZONE_INFO:[ownCheckGraphCompleted:701] All Graphs Completed
119.485541 s: VX_ZONE_INFO:[ownGraphScheduleGraph:763] Scheduling Graph (graph=13, pipe=0)
119.485561 s: VX_ZONE_INFO:[ownNodeKernelSchedule:570] Scheduling Node (node=8, pipe=0)
119.485608 s: VX_ZONE_INFO:[ownNodeKernelSchedule:570] Scheduling Node (node=7, pipe=0)
119.520099 s: VX_ZONE_INFO:[ownCheckGraphCompleted:676] Graph Completed (graph=13, pipe=0)
119.520127 s: VX_ZONE_INFO:[ownCheckGraphCompleted:701] All Graphs Completed
120.309507 s: VX_ZONE_INFO:[ownGraphScheduleGraph:763] Scheduling Graph (graph=13, pipe=0)
120.309529 s: VX_ZONE_INFO:[ownNodeKernelSchedule:570] Scheduling Node (node=8, pipe=0)
120.309574 s: VX_ZONE_INFO:[ownNodeKernelSchedule:570] Scheduling Node (node=7, pipe=0)
120.343900 s: VX_ZONE_INFO:[ownCheckGraphCompleted:676] Graph Completed (graph=13, pipe=0)
120.343928 s: VX_ZONE_INFO:[ownCheckGraphCompleted:701] All Graphs Completed
121.125495 s: VX_ZONE_INFO:[ownGraphScheduleGraph:763] Scheduling Graph (graph=13, pipe=0)
121.125516 s: VX_ZONE_INFO:[ownNodeKernelSchedule:570] Scheduling Node (node=8, pipe=0)
121.125559 s: VX_ZONE_INFO:[ownNodeKernelSchedule:570] Scheduling Node (node=7, pipe=0)
121.160043 s: VX_ZONE_INFO:[ownCheckGraphCompleted:676] Graph Completed (graph=13, pipe=0)
121.160072 s: VX_ZONE_INFO:[ownCheckGraphCompleted:701] All Graphs Completed
122.094723 s: VX_ZONE_INFO:[tivxQueueDelete:182] tivxQueueDelete: if this hangs, please ensure all application threads have been destroyed
122.095337 s: VX_ZONE_INFO:[tivxQueueDelete:182] tivxQueueDelete: if this hangs, please ensure all application threads have been destroyed
122.095371 s: VX_ZONE_INFO:[tivxQueueDelete:182] tivxQueueDelete: if this hangs, please ensure all application threads have been destroyed
[ DONE ] tivxHwaVideoDecoder.MultiStreamProcessing
[ -------- ] 1 tests from test case tivxHwaVideoDecoder
[ ======== ]
[ ALL DONE ] 1 test(s) from 1 test case(s) ran
[ PASSED ] 1 test(s)
[ FAILED ] 0 test(s)
[ DISABLED ] 0 test(s)
=================================
OpenVX Conformance report summary
=================================
To be conformant to the OpenVX baseline, 1 required test(s) must pass. 1 tests passed, 0 tests failed. PASSED.
To be conformant to the User Data Object extension, 0 required test(s) must pass. 0 tests passed, 0 tests failed. PASSED.
Note: The 0 disabled tests are optional and are not considered for conformance.
#REPORT: YYYYMMDDHHMMSS FILTERED 21932 0 1 1 1 0 (version 1.1-20170301)
122.105486 s: VX_ZONE_INIT:[tivxHostDeInit:49] De-Initialization Done for HOST !!!
122.106599 s: VX_ZONE_INFO:[tivxQueueDelete:182] tivxQueueDelete: if this hangs, please ensure all application threads have been destroyed
122.107671 s: VX_ZONE_INFO:[tivxQueueDelete:182] tivxQueueDelete: if this hangs, please ensure all application threads have been destroyed
122.108738 s: VX_ZONE_INFO:[tivxQueueDelete:182] tivxQueueDelete: if this hangs, please ensure all application threads have been destroyed
122.109804 s: VX_ZONE_INFO:[tivxQueueDelete:182] tivxQueueDelete: if this hangs, please ensure all application threads have been destroyed
122.109849 s: VX_ZONE_INIT:[tivxDeInit:100] De-Initialization Done !!!
APP: Deinit ... !!!
REMOTE_SERVICE: Deinit ... !!!
REMOTE_SERVICE: Deinit ... Done !!!
IPC: Deinit ... !!!
IPC: DeInit ... Done !!!
MEM: Deinit ION ... !!!
MEM: Alloc's: 11 alloc's of 8986376 bytes
MEM: Free's : 11 free's of 8986376 bytes
MEM: Open's : 0 allocs of 0 bytes
MEM: Deinit ION ... Done !!!
APP: Deinit ... Done !!!
root@j7-evm:/opt/vision_apps# VX_TEST_DATA_PATH=/opt/vision_apps/test_data ./vx_app_conformance.out --filter=tivxHwaVideoDecoder.MultiStreamProcessing
APP: Init ... !!!
APP_LOG: Mapping 0xac000000 ...
APP_LOG: Mapped 0xac000000 -> 0xffff8b0f0000 of size 262144 bytes
MEM: Init ION ... !!!
MEM: Initialized ION (fd=4) !!!
MEM: Init ION ... Done !!!
IPC: Init ... !!!
APP_LOG: Mapping 0xac040000 ...
APP_LOG: Mapped 0xac040000 -> 0xffff89150000 of size 33161216 bytes
APP_LOG: Mapping 0x30e00000 ...
APP_LOG: Mapped 0x30e00000 -> 0xffff8b5e0000 of size 3072 bytes
IPC: Init ... Done !!!
REMOTE_SERVICE: Init ... !!!
REMOTE_SERVICE: Init ... Done !!!
APP: Init ... Done !!!
0.000913 s: VX_ZONE_INIT:Enabled
0.000921 s: VX_ZONE_ERROR:Enabled
0.000925 s: VX_ZONE_WARNING:Enabled
0.000929 s: VX_ZONE_INFO:Disabled
0.001806 s: VX_ZONE_INIT:[tivxInit:64] Initialization Done !!!
0.002183 s: VX_ZONE_INIT:[tivxHostInit:44] Initialization Done for HOST !!!
VxTests version: 1.1-20170301
VCS version: unknown
Build config: Release
[ ======== ] Total 21932 tests from 144 test cases
Use test filter: tivxHwaVideoDecoder.MultiStreamProcessing
Use global OpenVX context: FALSE
[ -------- ] tests from tivxHwaVideoDecoder
[ RUN 0001 ] tivxHwaVideoDecoder.MultiStreamProcessing ...
0.015868 s: VX_ZONE_PARAMETER:[vxSetParameterByIndex:203] Attempting to set parameter[0] on com.ti.hwa.video_decoder (enum:-2097101) to 0xb075c0
0.015891 s: VX_ZONE_PARAMETER:[vxSetParameterByIndex:236] Query returned type 00000816 for ref 0xb075c0
0.015899 s: VX_ZONE_PARAMETER:[vxSetParameterByIndex:302] Assigned Node[0] 0xadafc0 type:00000816 ref=0xb075c0
0.015904 s: VX_ZONE_PARAMETER:[vxSetParameterByIndex:203] Attempting to set parameter[1] on com.ti.hwa.video_decoder (enum:-2097101) to 0xb07a00
0.015910 s: VX_ZONE_PARAMETER:[vxSetParameterByIndex:236] Query returned type 00000816 for ref 0xb07a00
0.016055 s: VX_ZONE_PARAMETER:[vxSetParameterByIndex:302] Assigned Node[1] 0xadafc0 type:00000816 ref=0xb07a00
0.016066 s: VX_ZONE_PARAMETER:[vxSetParameterByIndex:203] Attempting to set parameter[2] on com.ti.hwa.video_decoder (enum:-2097101) to 0xb396c0
0.016072 s: VX_ZONE_PARAMETER:[vxSetParameterByIndex:236] Query returned type 0000080f for ref 0xb396c0
0.016077 s: VX_ZONE_PARAMETER:[vxSetParameterByIndex:302] Assigned Node[2] 0xadafc0 type:0000080f ref=0xb396c0
0.016112 s: VX_ZONE_PARAMETER:[vxSetParameterByIndex:203] Attempting to set parameter[0] on com.ti.hwa.video_decoder (enum:-2097101) to 0xb077e0
0.016118 s: VX_ZONE_PARAMETER:[vxSetParameterByIndex:236] Query returned type 00000816 for ref 0xb077e0
0.016123 s: VX_ZONE_PARAMETER:[vxSetParameterByIndex:302] Assigned Node[0] 0xadb290 type:00000816 ref=0xb077e0
0.016128 s: VX_ZONE_PARAMETER:[vxSetParameterByIndex:203] Attempting to set parameter[1] on com.ti.hwa.video_decoder (enum:-2097101) to 0xb07c20
0.016133 s: VX_ZONE_PARAMETER:[vxSetParameterByIndex:236] Query returned type 00000816 for ref 0xb07c20
0.016139 s: VX_ZONE_PARAMETER:[vxSetParameterByIndex:302] Assigned Node[1] 0xadb290 type:00000816 ref=0xb07c20
0.016144 s: VX_ZONE_PARAMETER:[vxSetParameterByIndex:203] Attempting to set parameter[2] on com.ti.hwa.video_decoder (enum:-2097101) to 0xb39978
0.016149 s: VX_ZONE_PARAMETER:[vxSetParameterByIndex:236] Query returned type 0000080f for ref 0xb39978
0.016154 s: VX_ZONE_PARAMETER:[vxSetParameterByIndex:302] Assigned Node[2] 0xadb290 type:0000080f ref=0xb39978
0.086957 s: VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 0, kernel com.ti.hwa.video_decoder ...
0.086975 s: VX_ZONE_INFO:[ownGraphNodeKernelInit:589] kernel init for node 0, kernel com.ti.hwa.video_decoder ... done !!!
0.146141 s: VX_ZONE_ERROR:[ownContextSendCmd:553] Command ack message returned failure cmd_status: -1
0.146148 s: VX_ZONE_ERROR:[ownNodeKernelInit:486] Target kernel, TIVX_CMD_NODE_CREATE failed
0.146153 s: VX_ZONE_INFO:[ownGraphNodeKernelInit:578] kernel init for node 1, kernel com.ti.hwa.video_decoder ...
0.146158 s: VX_ZONE_ERROR:[ownGraphNodeKernelInit:583] kernel init for node 1, kernel com.ti.hwa.video_decoder ... failed !!!
0.146164 s: VX_ZONE_ERROR:[vxVerifyGraph:2010] Node kernel init failed
0.146169 s: VX_ZONE_ERROR:[vxVerifyGraph:2064] Graph verify failed
FAILED at /work/j7/6.2/psdk_rtos_auto_j7_06_02_00_21/tiovx/kernels_j7/hwa/test/test_video_decoder.c:549
Expected: VX_SUCCESS == vxVerifyGraph(graph)
Actual: VX_SUCCESS != VX_FAILURE
0.183689 s: VX_ZONE_WARNING:[vxReleaseContext:743] Stale reference 0xb075c0 of type 00000816 at external count 1, internal count 1
0.183697 s: VX_ZONE_WARNING:[vxReleaseContext:746] Unreleased reference name = user_data_object_84
0.183702 s: VX_ZONE_WARNING:[vxReleaseContext:743] Stale reference 0xb077e0 of type 00000816 at external count 1, internal count 1
0.183707 s: VX_ZONE_WARNING:[vxReleaseContext:746] Unreleased reference name = user_data_object_85
0.183712 s: VX_ZONE_WARNING:[vxReleaseContext:743] Stale reference 0xb07a00 of type 00000816 at external count 1, internal count 1
0.183716 s: VX_ZONE_WARNING:[vxReleaseContext:746] Unreleased reference name = user_data_object_86
0.183721 s: VX_ZONE_WARNING:[vxReleaseContext:743] Stale reference 0xb07c20 of type 00000816 at external count 1, internal count 1
0.183725 s: VX_ZONE_WARNING:[vxReleaseContext:746] Unreleased reference name = user_data_object_87
0.183730 s: VX_ZONE_WARNING:[vxReleaseContext:743] Stale reference 0xb396c0 of type 0000080f at external count 1, internal count 1
0.183735 s: VX_ZONE_WARNING:[vxReleaseContext:746] Unreleased reference name = image_88
0.183740 s: VX_ZONE_WARNING:[vxReleaseContext:743] Stale reference 0xb39978 of type 0000080f at external count 1, internal count 1
0.183744 s: VX_ZONE_WARNING:[vxReleaseContext:746] Unreleased reference name = image_89
0.183749 s: VX_ZONE_WARNING:[vxReleaseContext:743] Stale reference 0xa9af20 of type 00000802 at external count 1, internal count 0
0.183753 s: VX_ZONE_WARNING:[vxReleaseContext:746] Unreleased reference name = graph_90
0.183995 s: VX_ZONE_INFO:[tivxQueueDelete:182] tivxQueueDelete: if this hangs, please ensure all application threads have been destroyed
0.184038 s: VX_ZONE_INFO:[tivxQueueDelete:182] tivxQueueDelete: if this hangs, please ensure all application threads have been destroyed
0.184048 s: VX_ZONE_INFO:[tivxQueueDelete:182] tivxQueueDelete: if this hangs, please ensure all application threads have been destroyed
[ !FAILED! ] tivxHwaVideoDecoder.MultiStreamProcessing
[ -------- ] 1 tests from test case tivxHwaVideoDecoder
[ ======== ]
[ ALL DONE ] 1 test(s) from 1 test case(s) ran
[ PASSED ] 0 test(s)
[ FAILED ] 1 test(s), listed below:
[ FAILED ] tivxHwaVideoDecoder.MultiStreamProcessing
[ DISABLED ] 0 test(s)
=================================
OpenVX Conformance report summary
=================================
To be conformant to the OpenVX baseline, 1 required test(s) must pass. 0 tests passed, 1 tests failed. FAILED.
To be conformant to the User Data Object extension, 0 required test(s) must pass. 0 tests passed, 0 tests failed. PASSED.
Note: The 0 disabled tests are optional and are not considered for conformance.
#REPORT: YYYYMMDDHHMMSS FILTERED 21932 0 1 1 0 1 (version 1.1-20170301)
0.193843 s: VX_ZONE_INIT:[tivxHostDeInit:49] De-Initialization Done for HOST !!!
0.194946 s: VX_ZONE_INFO:[tivxQueueDelete:182] tivxQueueDelete: if this hangs, please ensure all application threads have been destroyed
0.196018 s: VX_ZONE_INFO:[tivxQueueDelete:182] tivxQueueDelete: if this hangs, please ensure all application threads have been destroyed
0.197085 s: VX_ZONE_INFO:[tivxQueueDelete:182] tivxQueueDelete: if this hangs, please ensure all application threads have been destroyed
0.198151 s: VX_ZONE_INFO:[tivxQueueDelete:182] tivxQueueDelete: if this hangs, please ensure all application threads have been destroyed
0.198194 s: VX_ZONE_INIT:[tivxDeInit:100] De-Initialization Done !!!
APP: Deinit ... !!!
REMOTE_SERVICE: Deinit ... !!!
REMOTE_SERVICE: Deinit ... Done !!!
IPC: Deinit ... !!!
IPC: DeInit ... Done !!!
MEM: Deinit ION ... !!!
MEM: Alloc's: 12 alloc's of 8986632 bytes
MEM: Free's : 12 free's of 8986632 bytes
MEM: Open's : 0 allocs of 0 bytes
MEM: Deinit ION ... Done !!!
APP: Deinit ... Done !!!
root@j7-evm:/opt/vision_apps#
Another log from my own app, FYI:
202004142219-multi-decode-error.log
Thanks & Best Regards!
ZM