Algorithm_create always failed

zanget

Hi, guys.

we are facing with a problem of Algorithm_create.

we are working with dvsdk_2_10_01_18, CE: codec_engine_2_24, fc: framework_components_2_25_00_04

when it's made as a single executable file and run as a process, it's nice.

but when it's run as a thread, it always failed. I'm running out of mind why it is failed,

 the log with CE_DEBUG=3 has attached. Any idea will be appreciated.

@0,527,449us: [+4 T:0x41e00490 S:0x41dffd7c] OG - Global_init> This program was built with the following packages:
@0,527,797us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package gnu.targets.rts470MV (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/xdctools_3_15_01_59/packages/gnu/targets/rts470MV/) [1, 0, 0, 0]
@0,528,006us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.linuxutils.vicp (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/linuxutils_2_24_03/packages/ti/sdo/linuxutils/vicp/) [1, 0, 0]
@0,528,195us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.utils.trace (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/utils/trace/) [1, 0, 0]
@0,528,375us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.fc.global (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/global/) [1, 0, 0]
@0,528,548us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.fc.utils.gtinfra (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/utils/gtinfra/) [1, 0, 0]
@0,528,724us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.linuxutils.edma (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/linuxutils_2_24_03/packages/ti/sdo/linuxutils/edma/) [1, 0, 0]
@0,528,896us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.linuxutils.cmem (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/linuxutils_2_24_03/packages/ti/sdo/linuxutils/cmem/) [2, 2, 0]
@0,529,144us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.fc.memutils (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/memutils/) [1, 0, 0]
@0,529,334us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.ce.global (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/codec_engine_2_24/packages/ti/sdo/ce/global/) [1, 0, 0]
@0,529,513us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.xdais.dm (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/xdais_6_24/packages/ti/xdais/dm/) [1, 0, 5]
@0,529,686us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.xdais (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/xdais_6_24/packages/ti/xdais/) [1, 2.0, 1]
@0,529,856us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.ce.node (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/codec_engine_2_24/packages/ti/sdo/ce/node/) [1, 0, 0]
@0,530,028us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.ce.utils.xdm (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/codec_engine_2_24/packages/ti/sdo/ce/utils/xdm/) [1, 0, 2]
@0,530,205us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package gnu.targets (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/xdctools_3_15_01_59/packages/gnu/targets/) [1, 0, 1]
@0,935,610us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.fc.utils (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/utils/) [1, 0, 2]
@0,935,853us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.fc.dman3 (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/dman3/) [1, 0, 4]
@0,936,047us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.fc.acpy3 (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/acpy3/) [1, 0, 4]
@0,936,238us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.fc.ires (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/ires/) [1, 0, 0]
@0,936,416us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.fc.ires.hdvicp (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/ires/hdvicp/) [1, 0, 1]
@0,936,597us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.fc.hdvicpsync (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/hdvicpsync/) [1, 0, 0]
@0,936,774us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.fc.ires.addrspace (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/ires/addrspace/) [1, 0, 0]
@0,936,999us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.fc.ires.memtcm (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/ires/memtcm/) [1, 0, 0]
@0,937,185us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.codecs.h264dec (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/dm365_codecs_01_00_06/packages/ti/sdo/codecs/h264dec/) [1, 0, 0]
@0,937,363us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.codecs.h264enc (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/dm365_codecs_01_00_06/packages/ti/sdo/codecs/h264enc/) [1, 0, 0]
@0,937,540us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.fc.ires.nullresource (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/ires/nullresource/) [1, 0, 0]
@0,937,720us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.fc.rman (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/rman/) [2, 0, 0]
@0,937,892us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.fc.edma3 (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/edma3/) [3, 0, 0]
@0,938,064us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.fc.ires.edma3chan (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/ires/edma3chan/) [1, 0, 0]
@0,938,284us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.fc.ires.grouputils (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/ires/grouputils/) [1, 0, 0]
@0,938,550us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.fc.ires.vicp (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/ires/vicp/) [1, 0, 1]
@0,938,745us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.fc.vicpsync (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/vicpsync/) [1, 0, 0]
@0,938,925us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.codecs.mpeg4dec (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/dm365_codecs_01_00_06/packages/ti/sdo/codecs/mpeg4dec/) [1, 0, 0]
@0,939,102us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.codecs.iprun (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/dm365_codecs_01_00_06/packages/ti/sdo/codecs/iprun/) [1, 0, 0]
@1,271,211us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.codecs.mpeg4enc (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/dm365_codecs_01_00_06/packages/ti/sdo/codecs/mpeg4enc/) [1, 0, 0]
@1,271,527us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.codecs.jpegenc (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/dm365_codecs_01_00_06/packages/ti/sdo/codecs/jpegenc/) [1, 0, 0]
@1,271,734us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.codecs.dei (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/dm365_codecs_01_00_06/packages/ti/sdo/codecs/dei/) [1, 0, 0]
@1,271,914us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.codecs.ktnf (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/dm365_codecs_01_00_06/packages/ti/sdo/codecs/ktnf/) [1, 0, 0]
@1,272,089us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.catalog.arm (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/xdctools_3_15_01_59/packages/ti/catalog/arm/) [1, 0, 1, 0]
@1,272,265us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.catalog (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/xdctools_3_15_01_59/packages/ti/catalog/) [1, 0, 0]
@1,272,478us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.platforms.evmDM365 (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/xdctools_3_15_01_59/packages/ti/platforms/evmDM365/) [1, 0, 0]
@1,272,668us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.ce.osal (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/codec_engine_2_24/packages/ti/sdo/ce/osal/) [2, 0, 2]
@1,272,849us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.ce.osal.linux (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/codec_engine_2_24/packages/ti/sdo/ce/osal/linux/) [2, 0, 1]
@1,273,025us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.ce.ipc (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/codec_engine_2_24/packages/ti/sdo/ce/ipc/) [2, 0, 1]
@1,273,198us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.ce.alg (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/codec_engine_2_24/packages/ti/sdo/ce/alg/) [1, 0, 1]
@1,273,373us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.ce.ipc.linux (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/codec_engine_2_24/packages/ti/sdo/ce/ipc/linux/) [2, 0, 1]
@1,273,548us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.ce (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/codec_engine_2_24/packages/ti/sdo/ce/) [1, 0, 6]
@1,273,720us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.ce.video2 (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/codec_engine_2_24/packages/ti/sdo/ce/video2/) [1, 0, 2]
@1,273,933us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.codecs.mpeg4dec.ce (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/dm365_codecs_01_00_06/packages/ti/sdo/codecs/mpeg4dec/ce/) [1, 0, 0]
@1,274,235us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.codecs.h264dec.ce (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/dm365_codecs_01_00_06/packages/ti/sdo/codecs/h264dec/ce/) [1, 0, 0]
@1,274,451us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.ce.video1 (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/codec_engine_2_24/packages/ti/sdo/ce/video1/) [1, 0, 2]
@1,274,632us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.codecs.iprun.ce (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/dm365_codecs_01_00_06/packages/ti/sdo/codecs/iprun/ce/) [1, 0, 0]
@1,274,813us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.codecs.h264enc.ce (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/dm365_codecs_01_00_06/packages/ti/sdo/codecs/h264enc/ce/) [1, 0, 0]
@1,274,989us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.codecs.mpeg4enc.ce (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/dm365_codecs_01_00_06/packages/ti/sdo/codecs/mpeg4enc/ce/) [1, 0, 0]
@1,606,755us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.ce.image1 (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/codec_engine_2_24/packages/ti/sdo/ce/image1/) [1, 0, 1]
@1,606,997us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.codecs.jpegenc.ce (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/dm365_codecs_01_00_06/packages/ti/sdo/codecs/jpegenc/ce/) [1, 0, 0]
@1,607,193us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.codecs.dei.ce (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/dm365_codecs_01_00_06/packages/ti/sdo/codecs/dei/ce/) [1, 0, 0]
@1,607,373us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package ti.sdo.codecs.ktnf.ce (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/dm365_codecs_01_00_06/packages/ti/sdo/codecs/ktnf/ce/) [1, 0, 0]
@1,607,548us: [+4 T:0x41e00490 S:0x41dffd7c] OG -     package encode_config (/home/wen_hao/mnt/win/13391/work/project/ti_dm365/vth1520b-c/Encode/Encode/Build/encode_config/) []
@1,607,813us: [+0 T:0x41e00490 S:0x41dffd84] OG - Global_atexit> enter (fxn=0x504ac)
@1,608,070us: [+0 T:0x41e00490 S:0x41dffd84] OG - Global_atexit> enter (fxn=0x4f8f8)
@1,608,344us: [+0 T:0x41e00490 S:0x41dffd54] OM - Memory_alloc> Enter(0x18)
@1,608,547us: [+0 T:0x41e00490 S:0x41dffd54] OM - Memory_alloc> return (0x3475b8)
@1,608,726us: [+0 T:0x41e00490 S:0x41dffd74] OG - Global_atexit> enter (fxn=0x4d828)
@1,609,031us: [+0 T:0x41e00490 S:0x41dffd74] OG - Global_atexit> enter (fxn=0x4ef5c)
@1,609,384us: [+0 T:0x41e00490 S:0x41dffd84] OG - Global_atexit> enter (fxn=0x4cc48)
@1,609,750us: [+0 T:0x41e00490 S:0x41dffd54] ti.sdo.fc.rman - RMAN_init> Enter
@1,609,950us: [+0 T:0x41e00490 S:0x41dffd3c] ti.sdo.ce.osal.LockMP - LockMP_create> key: 0x524d414e count: 2261280 attrs: 0x228120
@1,610,138us: [+0 T:0x41e00490 S:0x41dffd24] OM - Memory_alloc> Enter(0x10)
@1,610,306us: [+0 T:0x41e00490 S:0x41dffd24] OM - Memory_alloc> return (0x3476f0)
@1,610,469us: [+0 T:0x41e00490 S:0x41dffcfc] ti.sdo.ce.osal.SemMP - SemMP_create> key: 0x524d414e count: 1
@1,610,637us: [+0 T:0x41e00490 S:0x41dffce4] OM - Memory_alloc> Enter(0x4)
@1,610,844us: [+0 T:0x41e00490 S:0x41dffce4] OM - Memory_alloc> return (0x347708)
@1,611,015us: [+0 T:0x41e00490 S:0x41dffce4] ti.sdo.ce.osal.SemMP - Entered enterCS> key[0x4c41534f]
@1,611,222us: [+0 T:0x41e00490 S:0x41dffce4] ti.sdo.ce.osal.SemMP - Leaving enterCS> id[0x80009]
@1,611,455us: [+0 T:0x41e00490 S:0x41dffcfc] ti.sdo.ce.osal.SemMP - SemMP_create> semId: 0x10002 refCount: 1
@1,611,651us: [+0 T:0x41e00490 S:0x41dffce4] ti.sdo.ce.osal.SemMP - Entered exitCS> id[0x80009]
@1,611,834us: [+0 T:0x41e00490 S:0x41dffcfc] ti.sdo.ce.osal.SemMP - Leaving exitCS
@1,611,992us: [+0 T:0x41e00490 S:0x41dffcfc] ti.sdo.ce.osal.SemMP - Leaving SemMP_create> sem[0x347708]
@1,612,195us: [+0 T:0x41e00490 S:0x41dffd3c] ti.sdo.ce.osal.LockMP - Leaving LockMP_create> lock[0x3476f0]
@1,612,370us: [+0 T:0x41e00490 S:0x41dffd54] ti.sdo.fc.rman - RMAN_init> _RMAN_lock = 0x3476f0
@1,612,605us: [+0 T:0x41e00490 S:0x41dffd0c] ti.sdo.fc.rman - RMAN_register> Enter (resmanFxns=0x23bf8c, initArgs=0x41dffda4)
@1,612,855us: [+4 T:0x41e00490 S:0x41dffd0c] ti.sdo.fc.rman - RMAN_register> Registering protocol ti.sdo.fc.ires.examples.resources.nullres revision (1.0.0)
@1,613,141us: [+0 T:0x41e00490 S:0x41dffcf4] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x3476f0]
@1,613,321us: [+0 T:0x41e00490 S:0x41dffccc] ti.sdo.ce.osal.SemMP - Entered SemMP_pend> sem[0x347708] timeout[0xffffffff]
@1,613,557us: [+0 T:0x41e00490 S:0x41dffccc] ti.sdo.ce.osal.SemMP - Leaving SemMP_pend> sem[0x347708] status[0]
@1,613,754us: [+0 T:0x41e00490 S:0x41dffd0c] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x3476f0]
@1,613,934us: [+0 T:0x41e00490 S:0x41dffcf4] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x3476f0]
@1,946,488us: [+0 T:0x41e00490 S:0x41dffcd4] ti.sdo.ce.osal.SemMP - Entered SemMP_post> sem[0x347708]
@1,946,742us: [+0 T:0x41e00490 S:0x41dffcd4] ti.sdo.ce.osal.SemMP - Leaving SemMP_post> sem[0x347708]
@1,946,927us: [+0 T:0x41e00490 S:0x41dffd0c] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x3476f0]
@1,947,101us: [+0 T:0x41e00490 S:0x41dffd0c] ti.sdo.fc.rman - RMAN_register> Exit (status=IRES_OK)
@1,947,440us: [+2 T:0x41e00490 S:0x41dffd54] ti.sdo.fc.rman - RMAN_init> Registering statically added resources
@1,947,643us: [+0 T:0x41e00490 S:0x41dffd0c] ti.sdo.fc.rman - RMAN_register> Enter (resmanFxns=0x23c910, initArgs=0x2496b0)
@1,947,990us: [+0 T:0x41e00490 S:0x41dffcf4] ti.sdo.fc.ires.hdvicp - IRESMAN_HDVICP_getProtocolName> Enter
@1,948,245us: [+0 T:0x41e00490 S:0x41dffcdc] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getName> Enter
@1,948,429us: [+0 T:0x41e00490 S:0x41dffcdc] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getName> Exit (Name=ti.sdo.fc.ires.hdvicp)
@1,948,604us: [+0 T:0x41e00490 S:0x41dffcf4] ti.sdo.fc.ires.hdvicp - IRESMAN_HDVICP_getProtocolName> Exit (name=ti.sdo.fc.ires.hdvicp)
@1,948,779us: [+0 T:0x41e00490 S:0x41dffcdc] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getName> Enter
@1,948,936us: [+0 T:0x41e00490 S:0x41dffcdc] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getName> Exit (Name=ti.sdo.fc.ires.hdvicp)
@1,949,135us: [+0 T:0x41e00490 S:0x41dffce4] ti.sdo.fc.ires.hdvicp - IRESMAN_HDVICP_getProtocolRevision> Enter
@1,949,305us: [+0 T:0x41e00490 S:0x41dffcc4] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getRevision> Enter
@1,949,462us: [+0 T:0x41e00490 S:0x41dffcc4] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getRevision>Exit (Version=(1.0.1))
@1,949,630us: [+0 T:0x41e00490 S:0x41dffce4] ti.sdo.fc.ires.hdvicp - IRESMAN_HDVICP_getProtocolRevision> Exit (version=(1.0.1))
@1,949,799us: [+4 T:0x41e00490 S:0x41dffd0c] ti.sdo.fc.rman - RMAN_register> Registering protocol ti.sdo.fc.ires.hdvicp revision (1.0.1)
@1,949,975us: [+0 T:0x41e00490 S:0x41dffcf4] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x3476f0]
@1,950,138us: [+0 T:0x41e00490 S:0x41dffccc] ti.sdo.ce.osal.SemMP - Entered SemMP_pend> sem[0x347708] timeout[0xffffffff]
@1,950,333us: [+0 T:0x41e00490 S:0x41dffccc] ti.sdo.ce.osal.SemMP - Leaving SemMP_pend> sem[0x347708] status[0]
@1,950,547us: [+0 T:0x41e00490 S:0x41dffd0c] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x3476f0]
@1,950,732us: [+0 T:0x41e00490 S:0x41dffce4] ti.sdo.fc.ires.hdvicp - IRESMAN_HDVICP_init> Enter (initArgs=0x2496b0)
@1,950,904us: [+0 T:0x41e00490 S:0x41dffccc] ti.sdo.ce.osal.LockMP - LockMP_create> key: 0x4956444a count: 2261280 attrs: 0x228120
@1,951,079us: [+0 T:0x41e00490 S:0x41dffcb4] OM - Memory_alloc> Enter(0x10)
@1,951,255us: [+0 T:0x41e00490 S:0x41dffcb4] OM - Memory_alloc> return (0x347748)
@1,951,420us: [+0 T:0x41e00490 S:0x41dffc8c] ti.sdo.ce.osal.SemMP - SemMP_create> key: 0x4956444a count: 1
@1,951,585us: [+0 T:0x41e00490 S:0x41dffc74] OM - Memory_alloc> Enter(0x4)
@1,951,748us: [+0 T:0x41e00490 S:0x41dffc74] OM - Memory_alloc> return (0x347760)
@1,951,943us: [+0 T:0x41e00490 S:0x41dffc74] ti.sdo.ce.osal.SemMP - Entered enterCS> key[0x4c41534f]
@1,952,147us: [+0 T:0x41e00490 S:0x41dffc74] ti.sdo.ce.osal.SemMP - Leaving enterCS> id[0x88009]
@1,952,376us: [+0 T:0x41e00490 S:0x41dffc8c] ti.sdo.ce.osal.SemMP - SemMP_create> semId: 0x20003 refCount: 1
@1,952,574us: [+0 T:0x41e00490 S:0x41dffc74] ti.sdo.ce.osal.SemMP - Entered exitCS> id[0x88009]
@1,952,759us: [+0 T:0x41e00490 S:0x41dffc8c] ti.sdo.ce.osal.SemMP - Leaving exitCS
@1,952,920us: [+0 T:0x41e00490 S:0x41dffc8c] ti.sdo.ce.osal.SemMP - Leaving SemMP_create> sem[0x347760]
@1,953,085us: [+0 T:0x41e00490 S:0x41dffccc] ti.sdo.ce.osal.LockMP - Leaving LockMP_create> lock[0x347748]
@1,953,418us: [+0 T:0x41e00490 S:0x41dffc6c] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x347748]
@2,286,240us: [+0 T:0x41e00490 S:0x41dffc44] ti.sdo.ce.osal.SemMP - Entered SemMP_pend> sem[0x347760] timeout[0xffffffff]
@2,286,502us: [+0 T:0x41e00490 S:0x41dffc44] ti.sdo.ce.osal.SemMP - Leaving SemMP_pend> sem[0x347760] status[0]
@2,286,702us: [+0 T:0x41e00490 S:0x41dffc84] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x347748]
@2,286,896us: [+0 T:0x41e00490 S:0x41dffc84] ti.sdo.fc.utils.shm - _Shared Memory_initImpl> Shared memory, key = 0x18003 already exists
@2,287,156us: [+0 T:0x41e00490 S:0x41dffc84] ti.sdo.fc.utils.shm - SHM_getObj> shmBase = 0x41e01000
@2,287,371us: [+0 T:0x41e00490 S:0x41dffc84] ti.sdo.fc.utils.shm - _Shared Memory_initImpl> Shared Memory lock reference count = 1
@2,287,603us: [+5 T:0x41e00490 S:0x41dffc84] ti.sdo.fc.utils.shm - _Shared Memory_initImpl> Warning: Shared Memory lock reference count indicates no other processes are 
currently using Shared Memory shared memory.
Re-initializing Shared Memory shared memory
@2,287,869us: [+0 T:0x41e00490 S:0x41dffc84] ti.sdo.fc.utils.shm - Initializing Shared Memory region
@2,288,125us: [+0 T:0x41e00490 S:0x41dffc6c] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x347748]
@2,288,325us: [+0 T:0x41e00490 S:0x41dffc4c] ti.sdo.ce.osal.SemMP - Entered SemMP_post> sem[0x347760]
@2,288,532us: [+0 T:0x41e00490 S:0x41dffc4c] ti.sdo.ce.osal.SemMP - Leaving SemMP_post> sem[0x347760]
@2,288,721us: [+0 T:0x41e00490 S:0x41dffc84] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x347748]
@2,289,136us: [+0 T:0x41e00490 S:0x41dffce4] ti.sdo.fc.ires.hdvicp - IRESMAN_HDVICP_init> Exit (status=IRES_OK)
@2,289,363us: [+0 T:0x41e00490 S:0x41dffcf4] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x3476f0]
@2,289,544us: [+0 T:0x41e00490 S:0x41dffcd4] ti.sdo.ce.osal.SemMP - Entered SemMP_post> sem[0x347708]
@2,289,744us: [+0 T:0x41e00490 S:0x41dffcd4] ti.sdo.ce.osal.SemMP - Leaving SemMP_post> sem[0x347708]
@2,289,920us: [+0 T:0x41e00490 S:0x41dffd0c] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x3476f0]
@2,290,085us: [+0 T:0x41e00490 S:0x41dffd0c] ti.sdo.fc.rman - RMAN_register> Exit (status=IRES_OK)
@2,290,287us: [+0 T:0x41e00490 S:0x41dffd0c] ti.sdo.fc.rman - RMAN_register> Enter (resmanFxns=0x23bf10, initArgs=0x249634)
@2,290,565us: [+0 T:0x41e00490 S:0x41dffcf4] ti.sdo.fc.ires.vicp2 - IRESMAN_VICP2_getProtocolName> Enter
@2,290,808us: [+0 T:0x41e00490 S:0x41dffcf4] ti.sdo.fc.ires.vicp2 - IRESMAN_VICP2_getProtocolName> Exit (name=ti.sdo.fc.ires.vicp2)
@2,291,055us: [+0 T:0x41e00490 S:0x41dffce4] ti.sdo.fc.ires.vicp2 - IRESMAN_VICP2_getProtocolRevision> Enter
@2,291,244us: [+0 T:0x41e00490 S:0x41dffce4] ti.sdo.fc.ires.vicp2 - IRESMAN_VICP2_getProtocolRevision> Exit (version=(1.0.0))
@2,291,427us: [+4 T:0x41e00490 S:0x41dffd0c] ti.sdo.fc.rman - RMAN_register> Registering protocol ti.sdo.fc.ires.vicp2 revision (1.0.0)
@2,291,640us: [+0 T:0x41e00490 S:0x41dffcf4] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x3476f0]
@2,291,824us: [+0 T:0x41e00490 S:0x41dffccc] ti.sdo.ce.osal.SemMP - Entered SemMP_pend> sem[0x347708] timeout[0xffffffff]
@2,292,032us: [+0 T:0x41e00490 S:0x41dffccc] ti.sdo.ce.osal.SemMP - Leaving SemMP_pend> sem[0x347708] status[0]
@2,292,222us: [+0 T:0x41e00490 S:0x41dffd0c] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x3476f0]
@2,292,397us: [+0 T:0x41e00490 S:0x41dffcf4] ti.sdo.fc.ires.hdvicp - IRESMAN_HDVICP_getProtocolName> Enter
@2,292,563us: [+0 T:0x41e00490 S:0x41dffcdc] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getName> Enter
@2,292,716us: [+0 T:0x41e00490 S:0x41dffcdc] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getName> Exit (Name=ti.sdo.fc.ires.hdvicp)
@2,292,882us: [+0 T:0x41e00490 S:0x41dffcf4] ti.sdo.fc.ires.hdvicp - IRESMAN_HDVICP_getProtocolName> Exit (name=ti.sdo.fc.ires.hdvicp)
@2,293,092us: [+0 T:0x41e00490 S:0x41dffcdc] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getName> Enter
@2,625,930us: [+0 T:0x41e00490 S:0x41dffcdc] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getName> Exit (Name=ti.sdo.fc.ires.hdvicp)
@2,626,169us: [+0 T:0x41e00490 S:0x41dffca4] ti.sdo.fc.ires.vicp2 - IRESMAN_VICP2_init> Enter (initArgs=0x249634)
@2,626,358us: [+0 T:0x41e00490 S:0x41dffc8c] ti.sdo.ce.osal.LockMP - LockMP_create> key: 0x50434958 count: 2261280 attrs: 0x228120
@2,626,538us: [+0 T:0x41e00490 S:0x41dffc74] OM - Memory_alloc> Enter(0x10)
@2,626,715us: [+0 T:0x41e00490 S:0x41dffc74] OM - Memory_alloc> return (0x3477c8)
@2,626,881us: [+0 T:0x41e00490 S:0x41dffc4c] ti.sdo.ce.osal.SemMP - SemMP_create> key: 0x50434958 count: 1
@2,627,050us: [+0 T:0x41e00490 S:0x41dffc34] OM - Memory_alloc> Enter(0x4)
@2,627,262us: [+0 T:0x41e00490 S:0x41dffc34] OM - Memory_alloc> return (0x3477e0)
@2,627,434us: [+0 T:0x41e00490 S:0x41dffc34] ti.sdo.ce.osal.SemMP - Entered enterCS> key[0x4c41534f]
@2,627,632us: [+0 T:0x41e00490 S:0x41dffc34] ti.sdo.ce.osal.SemMP - Leaving enterCS> id[0x90009]
@2,627,863us: [+0 T:0x41e00490 S:0x41dffc4c] ti.sdo.ce.osal.SemMP - SemMP_create> semId: 0x30004 refCount: 1
@2,628,056us: [+0 T:0x41e00490 S:0x41dffc34] ti.sdo.ce.osal.SemMP - Entered exitCS> id[0x90009]
@2,628,242us: [+0 T:0x41e00490 S:0x41dffc4c] ti.sdo.ce.osal.SemMP - Leaving exitCS
@2,628,405us: [+0 T:0x41e00490 S:0x41dffc4c] ti.sdo.ce.osal.SemMP - Leaving SemMP_create> sem[0x3477e0]
@2,628,610us: [+0 T:0x41e00490 S:0x41dffc8c] ti.sdo.ce.osal.LockMP - Leaving LockMP_create> lock[0x3477c8]
@2,628,859us: [+0 T:0x41e00490 S:0x41dffc2c] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x3477c8]
@2,629,058us: [+0 T:0x41e00490 S:0x41dffc04] ti.sdo.ce.osal.SemMP - Entered SemMP_pend> sem[0x3477e0] timeout[0xffffffff]
@2,629,269us: [+0 T:0x41e00490 S:0x41dffc04] ti.sdo.ce.osal.SemMP - Leaving SemMP_pend> sem[0x3477e0] status[0]
@2,629,459us: [+0 T:0x41e00490 S:0x41dffc44] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x3477c8]
@2,629,651us: [+0 T:0x41e00490 S:0x41dffc44] ti.sdo.fc.utils.shm - _Shared Memory_initImpl> Shared memory, key = 0x20004 already exists
@2,629,920us: [+0 T:0x41e00490 S:0x41dffc44] ti.sdo.fc.utils.shm - SHM_getObj> shmBase = 0x41e02000
@2,630,173us: [+0 T:0x41e00490 S:0x41dffc44] ti.sdo.fc.utils.shm - _Shared Memory_initImpl> Shared Memory lock reference count = 1
@2,630,366us: [+5 T:0x41e00490 S:0x41dffc44] ti.sdo.fc.utils.shm - _Shared Memory_initImpl> Warning: Shared Memory lock reference count indicates no other processes are 
currently using Shared Memory shared memory.
Re-initializing Shared Memory shared memory
@2,630,577us: [+0 T:0x41e00490 S:0x41dffc44] ti.sdo.fc.utils.shm - Initializing Shared Memory region
@2,630,808us: [+0 T:0x41e00490 S:0x41dffc2c] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x3477c8]
@2,631,010us: [+0 T:0x41e00490 S:0x41dffc0c] ti.sdo.ce.osal.SemMP - Entered SemMP_post> sem[0x3477e0]
@2,631,213us: [+0 T:0x41e00490 S:0x41dffc0c] ti.sdo.ce.osal.SemMP - Leaving SemMP_post> sem[0x3477e0]
@2,631,431us: [+0 T:0x41e00490 S:0x41dffc44] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x3477c8]
@2,631,662us: [+0 T:0x41e00490 S:0x41dffc04] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x3477c8]
@2,631,850us: [+0 T:0x41e00490 S:0x41dffbdc] ti.sdo.ce.osal.SemMP - Entered SemMP_pend> sem[0x3477e0] timeout[0xffffffff]
@2,632,057us: [+0 T:0x41e00490 S:0x41dffbdc] ti.sdo.ce.osal.SemMP - Leaving SemMP_pend> sem[0x3477e0] status[0]
@2,632,246us: [+0 T:0x41e00490 S:0x41dffc1c] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x3477c8]
@2,632,436us: [+0 T:0x41e00490 S:0x41dffc1c] ti.sdo.fc.utils.shm - _Shared Memory_initImpl> Shared memory, key = 0x28005 already exists
@2,632,724us: [+0 T:0x41e00490 S:0x41dffc1c] ti.sdo.fc.utils.shm - SHM_getObj> shmBase = 0x41e03000
@2,632,946us: [+0 T:0x41e00490 S:0x41dffc1c] ti.sdo.fc.utils.shm - _Shared Memory_initImpl> Shared Memory lock reference count = 1
@2,965,663us: [+5 T:0x41e00490 S:0x41dffc1c] ti.sdo.fc.utils.shm - _Shared Memory_initImpl> Warning: Shared Memory lock reference count indicates no other processes are 
currently using Shared Memory shared memory.
Re-initializing Shared Memory shared memory
@2,965,927us: [+0 T:0x41e00490 S:0x41dffc1c] ti.sdo.fc.utils.shm - Initializing Shared Memory region
@2,966,183us: [+0 T:0x41e00490 S:0x41dffc04] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x3477c8]
@2,966,385us: [+0 T:0x41e00490 S:0x41dffbe4] ti.sdo.ce.osal.SemMP - Entered SemMP_post> sem[0x3477e0]
@2,966,592us: [+0 T:0x41e00490 S:0x41dffbe4] ti.sdo.ce.osal.SemMP - Leaving SemMP_post> sem[0x3477e0]
@2,966,772us: [+0 T:0x41e00490 S:0x41dffc1c] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x3477c8]
@2,967,250us: [+0 T:0x41e00490 S:0x41dffca4] ti.sdo.fc.ires.vicp2 - IRESMAN_VICP2_init> Exit (status=IRES_OK)
@2,967,472us: [+0 T:0x41e00490 S:0x41dffcf4] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x3476f0]
@2,967,659us: [+0 T:0x41e00490 S:0x41dffcd4] ti.sdo.ce.osal.SemMP - Entered SemMP_post> sem[0x347708]
@2,967,850us: [+0 T:0x41e00490 S:0x41dffcd4] ti.sdo.ce.osal.SemMP - Leaving SemMP_post> sem[0x347708]
@2,968,027us: [+0 T:0x41e00490 S:0x41dffd0c] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x3476f0]
@2,968,197us: [+0 T:0x41e00490 S:0x41dffd0c] ti.sdo.fc.rman - RMAN_register> Exit (status=IRES_OK)
@2,968,403us: [+0 T:0x41e00490 S:0x41dffd0c] ti.sdo.fc.rman - RMAN_register> Enter (resmanFxns=0x23c8d4, initArgs=0x2496a0)
@2,968,682us: [+0 T:0x41e00490 S:0x41dffcec] ti.sdo.fc.ires.addrspace - _IRESMAN_ADDRSPACE_getProtocolName> Enter
@2,968,880us: [+0 T:0x41e00490 S:0x41dffcd4] ti.sdo.fc.ires.addrspace - _IRES_ADDRSPACE_getName> Enter 
@2,969,049us: [+0 T:0x41e00490 S:0x41dffcd4] ti.sdo.fc.ires.addrspace - _IRES_ADDRSPACE_getName> Exit (Name=ti.sdo.fc.ires.addrspace)
@2,969,223us: [+0 T:0x41e00490 S:0x41dffcec] ti.sdo.fc.ires.addrspace - _IRESMAN_ADDRSPACE_getProtocolName> Exit (Name=ti.sdo.fc.ires.addrspace)
@2,969,398us: [+0 T:0x41e00490 S:0x41dffce4] ti.sdo.fc.ires.addrspace - _IRESMAN_ADDRSPACE_getProtocolRevision> Enter
@2,969,559us: [+0 T:0x41e00490 S:0x41dffcc4] ti.sdo.fc.ires.addrspace - _IRES_ADDRSPACE_getRevision> Enter
@2,969,754us: [+0 T:0x41e00490 S:0x41dffcc4] ti.sdo.fc.ires.addrspace - _IRES_ADDRSPACE_getRevision> Exit (Version=(1.0.0))
@2,969,935us: [+0 T:0x41e00490 S:0x41dffce4] ti.sdo.fc.ires.addrspace - _IRESMAN_ADDRSPACE_getProtocolRevision> Exit (Version=(1.0.0))
@2,970,113us: [+4 T:0x41e00490 S:0x41dffd0c] ti.sdo.fc.rman - RMAN_register> Registering protocol ti.sdo.fc.ires.addrspace revision (1.0.0)
@2,970,289us: [+0 T:0x41e00490 S:0x41dffcf4] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x3476f0]
@2,970,453us: [+0 T:0x41e00490 S:0x41dffccc] ti.sdo.ce.osal.SemMP - Entered SemMP_pend> sem[0x347708] timeout[0xffffffff]
@2,970,650us: [+0 T:0x41e00490 S:0x41dffccc] ti.sdo.ce.osal.SemMP - Leaving SemMP_pend> sem[0x347708] status[0]
@2,970,835us: [+0 T:0x41e00490 S:0x41dffd0c] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x3476f0]
@2,971,010us: [+0 T:0x41e00490 S:0x41dffcf4] ti.sdo.fc.ires.hdvicp - IRESMAN_HDVICP_getProtocolName> Enter
@2,971,225us: [+0 T:0x41e00490 S:0x41dffcdc] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getName> Enter
@2,971,384us: [+0 T:0x41e00490 S:0x41dffcdc] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getName> Exit (Name=ti.sdo.fc.ires.hdvicp)
@2,971,548us: [+0 T:0x41e00490 S:0x41dffcf4] ti.sdo.fc.ires.hdvicp - IRESMAN_HDVICP_getProtocolName> Exit (name=ti.sdo.fc.ires.hdvicp)
@2,971,717us: [+0 T:0x41e00490 S:0x41dffcdc] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getName> Enter
@2,971,870us: [+0 T:0x41e00490 S:0x41dffcdc] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getName> Exit (Name=ti.sdo.fc.ires.hdvicp)
@2,972,037us: [+0 T:0x41e00490 S:0x41dffcf4] ti.sdo.fc.ires.vicp2 - IRESMAN_VICP2_getProtocolName> Enter
@3,304,047us: [+0 T:0x41e00490 S:0x41dffcf4] ti.sdo.fc.ires.vicp2 - IRESMAN_VICP2_getProtocolName> Exit (name=ti.sdo.fc.ires.vicp2)
@3,304,370us: [+0 T:0x41e00490 S:0x41dffce4] ti.sdo.fc.ires.addrspace - _IRESMAN_ADDRSPACE_init> Enter (initArgs=0x2496a0)
@3,304,560us: [+0 T:0x41e00490 S:0x41dffce4] ti.sdo.fc.ires.addrspace - _IRESMAN_ADDRSPACE_init> Exit (status=IRES_OK)
@3,304,731us: [+0 T:0x41e00490 S:0x41dffcf4] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x3476f0]
@3,304,899us: [+0 T:0x41e00490 S:0x41dffcd4] ti.sdo.ce.osal.SemMP - Entered SemMP_post> sem[0x347708]
@3,305,094us: [+0 T:0x41e00490 S:0x41dffcd4] ti.sdo.ce.osal.SemMP - Leaving SemMP_post> sem[0x347708]
@3,305,312us: [+0 T:0x41e00490 S:0x41dffd0c] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x3476f0]
@3,305,490us: [+0 T:0x41e00490 S:0x41dffd0c] ti.sdo.fc.rman - RMAN_register> Exit (status=IRES_OK)
@3,305,660us: [+0 T:0x41e00490 S:0x41dffd0c] ti.sdo.fc.rman - RMAN_register> Enter (resmanFxns=0x23c8b8, initArgs=0x249684)
@3,305,936us: [+0 T:0x41e00490 S:0x41dffcf4] ti.sdo.fc.ires.memtcm - IRESMAN_MEMTCM_getProtocolName> Enter
@3,306,209us: [+0 T:0x41e00490 S:0x41dffcdc] ti.sdo.fc.ires.memtcm - _IRES_MEMTCM_getName> Enter 
@3,306,441us: [+0 T:0x41e00490 S:0x41dffcdc] ti.sdo.fc.ires.memtcm - _IRES_MEMTCM_getName> Exit (Name=ti.sdo.fc.ires.examples.resources.memtcm)
@3,306,653us: [+0 T:0x41e00490 S:0x41dffcf4] ti.sdo.fc.ires.memtcm - IRESMAN_MEMTCM_getProtocolName> Exit (Name=ti.sdo.fc.ires.examples.resources.memtcm)
@3,306,843us: [+0 T:0x41e00490 S:0x41dffcdc] ti.sdo.fc.ires.memtcm - _IRES_MEMTCM_getName> Enter 
@3,307,003us: [+0 T:0x41e00490 S:0x41dffcdc] ti.sdo.fc.ires.memtcm - _IRES_MEMTCM_getName> Exit (Name=ti.sdo.fc.ires.examples.resources.memtcm)
@3,307,176us: [+0 T:0x41e00490 S:0x41dffce4] ti.sdo.fc.ires.memtcm - IRESMAN_MEMTCM_getProtocolRevision> Enter
@3,307,338us: [+0 T:0x41e00490 S:0x41dffcc4] ti.sdo.fc.ires.memtcm - _IRES_MEMTCM_getRevision> Enter
@3,307,499us: [+0 T:0x41e00490 S:0x41dffcc4] ti.sdo.fc.ires.memtcm - _IRES_MEMTCM_getRevision> Exit (Version=(1.0.0))
@3,307,662us: [+0 T:0x41e00490 S:0x41dffce4] ti.sdo.fc.ires.memtcm - IRESMAN_MEMTCM_getProtocolRevision> Exit (Version=(1.0.0))
@3,307,832us: [+0 T:0x41e00490 S:0x41dffcc4] ti.sdo.fc.ires.memtcm - _IRES_MEMTCM_getRevision> Enter
@3,307,989us: [+0 T:0x41e00490 S:0x41dffcc4] ti.sdo.fc.ires.memtcm - _IRES_MEMTCM_getRevision> Exit (Version=(1.0.0))
@3,308,198us: [+4 T:0x41e00490 S:0x41dffd0c] ti.sdo.fc.rman - RMAN_register> Registering protocol ti.sdo.fc.ires.examples.resources.memtcm revision (1.0.0)
@3,308,382us: [+0 T:0x41e00490 S:0x41dffcf4] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x3476f0]
@3,308,547us: [+0 T:0x41e00490 S:0x41dffccc] ti.sdo.ce.osal.SemMP - Entered SemMP_pend> sem[0x347708] timeout[0xffffffff]
@3,308,744us: [+0 T:0x41e00490 S:0x41dffccc] ti.sdo.ce.osal.SemMP - Leaving SemMP_pend> sem[0x347708] status[0]
@3,308,928us: [+0 T:0x41e00490 S:0x41dffd0c] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x3476f0]
@3,309,102us: [+0 T:0x41e00490 S:0x41dffcf4] ti.sdo.fc.ires.hdvicp - IRESMAN_HDVICP_getProtocolName> Enter
@3,309,267us: [+0 T:0x41e00490 S:0x41dffcdc] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getName> Enter
@3,309,457us: [+0 T:0x41e00490 S:0x41dffcdc] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getName> Exit (Name=ti.sdo.fc.ires.hdvicp)
@3,309,633us: [+0 T:0x41e00490 S:0x41dffcf4] ti.sdo.fc.ires.hdvicp - IRESMAN_HDVICP_getProtocolName> Exit (name=ti.sdo.fc.ires.hdvicp)
@3,309,801us: [+0 T:0x41e00490 S:0x41dffcdc] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getName> Enter
@3,309,957us: [+0 T:0x41e00490 S:0x41dffcdc] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getName> Exit (Name=ti.sdo.fc.ires.hdvicp)
@3,310,125us: [+0 T:0x41e00490 S:0x41dffcf4] ti.sdo.fc.ires.vicp2 - IRESMAN_VICP2_getProtocolName> Enter
@3,642,353us: [+0 T:0x41e00490 S:0x41dffcf4] ti.sdo.fc.ires.vicp2 - IRESMAN_VICP2_getProtocolName> Exit (name=ti.sdo.fc.ires.vicp2)
@3,642,596us: [+0 T:0x41e00490 S:0x41dffcec] ti.sdo.fc.ires.addrspace - _IRESMAN_ADDRSPACE_getProtocolName> Enter
@3,642,778us: [+0 T:0x41e00490 S:0x41dffcd4] ti.sdo.fc.ires.addrspace - _IRES_ADDRSPACE_getName> Enter 
@3,642,943us: [+0 T:0x41e00490 S:0x41dffcd4] ti.sdo.fc.ires.addrspace - _IRES_ADDRSPACE_getName> Exit (Name=ti.sdo.fc.ires.addrspace)
@3,643,109us: [+0 T:0x41e00490 S:0x41dffcec] ti.sdo.fc.ires.addrspace - _IRESMAN_ADDRSPACE_getProtocolName> Exit (Name=ti.sdo.fc.ires.addrspace)
@3,643,353us: [+0 T:0x41e00490 S:0x41dffccc] ti.sdo.fc.ires.memtcm - IRESMAN_MEMTCM_init> Enter (initArgs=0x249684)
@3,643,545us: [+0 T:0x41e00490 S:0x41dffcb4] ti.sdo.ce.osal.LockMP - LockMP_create> key: 0x4d43544f count: 2261280 attrs: 0x228120
@3,643,773us: [+0 T:0x41e00490 S:0x41dffc9c] OM - Memory_alloc> Enter(0x10)
@3,643,954us: [+0 T:0x41e00490 S:0x41dffc9c] OM - Memory_alloc> return (0x347850)
@3,644,264us: [+0 T:0x41e00490 S:0x41dffc74] ti.sdo.ce.osal.SemMP - SemMP_create> key: 0x4d43544f count: 1
@3,644,468us: [+0 T:0x41e00490 S:0x41dffc5c] OM - Memory_alloc> Enter(0x4)
@3,644,645us: [+0 T:0x41e00490 S:0x41dffc5c] OM - Memory_alloc> return (0x347868)
@3,644,812us: [+0 T:0x41e00490 S:0x41dffc5c] ti.sdo.ce.osal.SemMP - Entered enterCS> key[0x4c41534f]
@3,645,035us: [+0 T:0x41e00490 S:0x41dffc5c] ti.sdo.ce.osal.SemMP - Leaving enterCS> id[0x98009]
@3,645,288us: [+0 T:0x41e00490 S:0x41dffc74] ti.sdo.ce.osal.SemMP - SemMP_create> semId: 0x40005 refCount: 1
@3,645,485us: [+0 T:0x41e00490 S:0x41dffc5c] ti.sdo.ce.osal.SemMP - Entered exitCS> id[0x98009]
@3,645,667us: [+0 T:0x41e00490 S:0x41dffc74] ti.sdo.ce.osal.SemMP - Leaving exitCS
@3,645,826us: [+0 T:0x41e00490 S:0x41dffc74] ti.sdo.ce.osal.SemMP - Leaving SemMP_create> sem[0x347868]
@3,645,991us: [+0 T:0x41e00490 S:0x41dffcb4] ti.sdo.ce.osal.LockMP - Leaving LockMP_create> lock[0x347850]
@3,646,163us: [+0 T:0x41e00490 S:0x41dffc54] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x347850]
@3,646,330us: [+0 T:0x41e00490 S:0x41dffc2c] ti.sdo.ce.osal.SemMP - Entered SemMP_pend> sem[0x347868] timeout[0xffffffff]
@3,646,569us: [+0 T:0x41e00490 S:0x41dffc2c] ti.sdo.ce.osal.SemMP - Leaving SemMP_pend> sem[0x347868] status[0]
@3,646,755us: [+0 T:0x41e00490 S:0x41dffc6c] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x347850]
@3,646,942us: [+0 T:0x41e00490 S:0x41dffc6c] ti.sdo.fc.utils.shm - _Shared Memory_initImpl> Shared memory, key = 0x38007 already exists
@3,647,210us: [+0 T:0x41e00490 S:0x41dffc6c] ti.sdo.fc.utils.shm - SHM_getObj> shmBase = 0x41e04000
@3,647,428us: [+0 T:0x41e00490 S:0x41dffc6c] ti.sdo.fc.utils.shm - _Shared Memory_initImpl> Shared Memory lock reference count = 1
@3,647,613us: [+5 T:0x41e00490 S:0x41dffc6c] ti.sdo.fc.utils.shm - _Shared Memory_initImpl> Warning: Shared Memory lock reference count indicates no other processes are 
currently using Shared Memory shared memory.
Re-initializing Shared Memory shared memory
@3,647,854us: [+0 T:0x41e00490 S:0x41dffc6c] ti.sdo.fc.utils.shm - Initializing Shared Memory region
@3,648,094us: [+0 T:0x41e00490 S:0x41dffc54] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x347850]
@3,648,301us: [+0 T:0x41e00490 S:0x41dffc34] ti.sdo.ce.osal.SemMP - Entered SemMP_post> sem[0x347868]
@3,648,507us: [+0 T:0x41e00490 S:0x41dffc34] ti.sdo.ce.osal.SemMP - Leaving SemMP_post> sem[0x347868]
@3,648,694us: [+0 T:0x41e00490 S:0x41dffc6c] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x347850]
@3,649,040us: [+0 T:0x41e00490 S:0x41dffc2c] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x347850]
@3,649,316us: [+0 T:0x41e00490 S:0x41dffc04] ti.sdo.ce.osal.SemMP - Entered SemMP_pend> sem[0x347868] timeout[0xffffffff]
@3,649,532us: [+0 T:0x41e00490 S:0x41dffc04] ti.sdo.ce.osal.SemMP - Leaving SemMP_pend> sem[0x347868] status[0]
@3,982,086us: [+0 T:0x41e00490 S:0x41dffc44] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x347850]
@3,982,344us: [+0 T:0x41e00490 S:0x41dffc44] ti.sdo.fc.utils.shm - _Shared Memory_initImpl> Shared memory, key = 0x40008 already exists
@3,982,627us: [+0 T:0x41e00490 S:0x41dffc44] ti.sdo.fc.utils.shm - SHM_getObj> shmBase = 0x41e0c000
@3,982,848us: [+0 T:0x41e00490 S:0x41dffc44] ti.sdo.fc.utils.shm - _Shared Memory_initImpl> Shared Memory lock reference count = 1
@3,983,032us: [+5 T:0x41e00490 S:0x41dffc44] ti.sdo.fc.utils.shm - _Shared Memory_initImpl> Warning: Shared Memory lock reference count indicates no other processes are 
currently using Shared Memory shared memory.
Re-initializing Shared Memory shared memory
@3,983,241us: [+0 T:0x41e00490 S:0x41dffc44] ti.sdo.fc.utils.shm - Initializing Shared Memory region
@3,983,522us: [+0 T:0x41e00490 S:0x41dffc2c] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x347850]
@3,983,730us: [+0 T:0x41e00490 S:0x41dffc0c] ti.sdo.ce.osal.SemMP - Entered SemMP_post> sem[0x347868]
@3,983,995us: [+0 T:0x41e00490 S:0x41dffc0c] ti.sdo.ce.osal.SemMP - Leaving SemMP_post> sem[0x347868]
@3,984,302us: [+0 T:0x41e00490 S:0x41dffc44] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x347850]
@3,984,512us: [+0 T:0x41e00490 S:0x41dffccc] ti.sdo.fc.ires.memtcm - IRESMAN_MEMTCM_init> Exit (status=IRES_OK)
@3,984,719us: [+0 T:0x41e00490 S:0x41dffcf4] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x3476f0]
@3,984,898us: [+0 T:0x41e00490 S:0x41dffcd4] ti.sdo.ce.osal.SemMP - Entered SemMP_post> sem[0x347708]
@3,985,097us: [+0 T:0x41e00490 S:0x41dffcd4] ti.sdo.ce.osal.SemMP - Leaving SemMP_post> sem[0x347708]
@3,985,283us: [+0 T:0x41e00490 S:0x41dffd0c] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x3476f0]
@3,985,454us: [+0 T:0x41e00490 S:0x41dffd0c] ti.sdo.fc.rman - RMAN_register> Exit (status=IRES_OK)
@3,985,620us: [+0 T:0x41e00490 S:0x41dffd0c] ti.sdo.fc.rman - RMAN_register> Enter (resmanFxns=0x23bf40, initArgs=0x24965c)
@3,985,899us: [+0 T:0x41e00490 S:0x41dffcf4] ti.sdo.fc.ires.edma3Chan - IRESMAN_EDMA3CHAN_getProtocolName> Enter
@3,986,130us: [+0 T:0x41e00490 S:0x41dffcdc] ti.sdo.fc.ires.edma3Chan - IRES_EDMA3CHAN_getName> Enter
@3,986,376us: [+0 T:0x41e00490 S:0x41dffcdc] ti.sdo.fc.ires.edma3Chan - IRES_EDMA3CHAN_getName> Exit (Name=ti.sdo.fc.ires.edma3chan)
@3,986,563us: [+0 T:0x41e00490 S:0x41dffcf4] ti.sdo.fc.ires.edma3Chan - IRESMAN_EDMA3CHAN_getProtocolName> Exit (name=ti.sdo.fc.ires.edma3chan)
@3,986,739us: [+0 T:0x41e00490 S:0x41dffcdc] ti.sdo.fc.ires.edma3Chan - IRES_EDMA3CHAN_getName> Enter
@3,986,901us: [+0 T:0x41e00490 S:0x41dffcdc] ti.sdo.fc.ires.edma3Chan - IRES_EDMA3CHAN_getName> Exit (Name=ti.sdo.fc.ires.edma3chan)
@3,987,065us: [+0 T:0x41e00490 S:0x41dffce4] ti.sdo.fc.ires.edma3Chan - IRESMAN_EDMA3CHAN_getProtocolRevision> Enter
@3,987,232us: [+0 T:0x41e00490 S:0x41dffcc4] ti.sdo.fc.ires.edma3Chan - IRES_EDMA3CHAN_getRevision> Enter
@3,987,389us: [+0 T:0x41e00490 S:0x41dffcc4] ti.sdo.fc.ires.edma3Chan - IRES_EDMA3CHAN_getRevision> Exit (Version=(1.0.0))
@3,987,595us: [+0 T:0x41e00490 S:0x41dffce4] ti.sdo.fc.ires.edma3Chan - IRESMAN_EDMA3CHAN_getProtocolRevision> Exit (version=(1.0.0))
@3,987,775us: [+4 T:0x41e00490 S:0x41dffd0c] ti.sdo.fc.rman - RMAN_register> Registering protocol ti.sdo.fc.ires.edma3chan revision (1.0.0)
@3,987,955us: [+0 T:0x41e00490 S:0x41dffcf4] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x3476f0]
@3,988,117us: [+0 T:0x41e00490 S:0x41dffccc] ti.sdo.ce.osal.SemMP - Entered SemMP_pend> sem[0x347708] timeout[0xffffffff]
@3,988,319us: [+0 T:0x41e00490 S:0x41dffccc] ti.sdo.ce.osal.SemMP - Leaving SemMP_pend> sem[0x347708] status[0]
@3,988,498us: [+0 T:0x41e00490 S:0x41dffd0c] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x3476f0]
@3,988,674us: [+0 T:0x41e00490 S:0x41dffcf4] ti.sdo.fc.ires.hdvicp - IRESMAN_HDVICP_getProtocolName> Enter
@4,320,435us: [+0 T:0x41e00490 S:0x41dffcdc] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getName> Enter
@4,320,702us: [+0 T:0x41e00490 S:0x41dffcdc] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getName> Exit (Name=ti.sdo.fc.ires.hdvicp)
@4,320,899us: [+0 T:0x41e00490 S:0x41dffcf4] ti.sdo.fc.ires.hdvicp - IRESMAN_HDVICP_getProtocolName> Exit (name=ti.sdo.fc.ires.hdvicp)
@4,321,084us: [+0 T:0x41e00490 S:0x41dffcdc] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getName> Enter
@4,321,245us: [+0 T:0x41e00490 S:0x41dffcdc] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getName> Exit (Name=ti.sdo.fc.ires.hdvicp)
@4,321,422us: [+0 T:0x41e00490 S:0x41dffcf4] ti.sdo.fc.ires.vicp2 - IRESMAN_VICP2_getProtocolName> Enter
@4,321,584us: [+0 T:0x41e00490 S:0x41dffcf4] ti.sdo.fc.ires.vicp2 - IRESMAN_VICP2_getProtocolName> Exit (name=ti.sdo.fc.ires.vicp2)
@4,321,804us: [+0 T:0x41e00490 S:0x41dffcec] ti.sdo.fc.ires.addrspace - _IRESMAN_ADDRSPACE_getProtocolName> Enter
@4,321,977us: [+0 T:0x41e00490 S:0x41dffcd4] ti.sdo.fc.ires.addrspace - _IRES_ADDRSPACE_getName> Enter 
@4,322,131us: [+0 T:0x41e00490 S:0x41dffcd4] ti.sdo.fc.ires.addrspace - _IRES_ADDRSPACE_getName> Exit (Name=ti.sdo.fc.ires.addrspace)
@4,322,295us: [+0 T:0x41e00490 S:0x41dffcec] ti.sdo.fc.ires.addrspace - _IRESMAN_ADDRSPACE_getProtocolName> Exit (Name=ti.sdo.fc.ires.addrspace)
@4,322,469us: [+0 T:0x41e00490 S:0x41dffcf4] ti.sdo.fc.ires.memtcm - IRESMAN_MEMTCM_getProtocolName> Enter
@4,322,632us: [+0 T:0x41e00490 S:0x41dffcdc] ti.sdo.fc.ires.memtcm - _IRES_MEMTCM_getName> Enter 
@4,322,787us: [+0 T:0x41e00490 S:0x41dffcdc] ti.sdo.fc.ires.memtcm - _IRES_MEMTCM_getName> Exit (Name=ti.sdo.fc.ires.examples.resources.memtcm)
@4,322,956us: [+0 T:0x41e00490 S:0x41dffcf4] ti.sdo.fc.ires.memtcm - IRESMAN_MEMTCM_getProtocolName> Exit (Name=ti.sdo.fc.ires.examples.resources.memtcm)
@4,323,167us: [+0 T:0x41e00490 S:0x41dffcdc] ti.sdo.fc.ires.memtcm - _IRES_MEMTCM_getName> Enter 
@4,323,336us: [+0 T:0x41e00490 S:0x41dffcdc] ti.sdo.fc.ires.memtcm - _IRES_MEMTCM_getName> Exit (Name=ti.sdo.fc.ires.examples.resources.memtcm)
@4,323,579us: [+0 T:0x41e00490 S:0x41dffcec] ti.sdo.fc.ires.edma3Chan - IRESMAN_EDMA3CHAN_init> Enter (initArgs=0x24965c)
@4,323,772us: [+0 T:0x41e00490 S:0x41dffcec] ti.sdo.fc.ires.edma3Chan - IRESMAN_EDMA3CHAN_init> Exit (status=IRES_OK)
@4,323,947us: [+0 T:0x41e00490 S:0x41dffcf4] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x3476f0]
@4,324,232us: [+0 T:0x41e00490 S:0x41dffcd4] ti.sdo.ce.osal.SemMP - Entered SemMP_post> sem[0x347708]
@4,324,489us: [+0 T:0x41e00490 S:0x41dffcd4] ti.sdo.ce.osal.SemMP - Leaving SemMP_post> sem[0x347708]
@4,324,685us: [+0 T:0x41e00490 S:0x41dffd0c] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x3476f0]
@4,324,858us: [+0 T:0x41e00490 S:0x41dffd0c] ti.sdo.fc.rman - RMAN_register> Exit (status=IRES_OK)
@4,325,021us: [+0 T:0x41e00490 S:0x41dffd0c] ti.sdo.fc.rman - RMAN_register> Enter (resmanFxns=0x23bf28, initArgs=0x249648)
@4,325,202us: [+4 T:0x41e00490 S:0x41dffd0c] ti.sdo.fc.rman - RMAN_register> Registering protocol ti.sdo.fc.ires.examples.resources.grouputils revision (1.0.0)
@4,325,386us: [+0 T:0x41e00490 S:0x41dffcf4] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x3476f0]
@4,325,551us: [+0 T:0x41e00490 S:0x41dffccc] ti.sdo.ce.osal.SemMP - Entered SemMP_pend> sem[0x347708] timeout[0xffffffff]
@4,325,747us: [+0 T:0x41e00490 S:0x41dffccc] ti.sdo.ce.osal.SemMP - Leaving SemMP_pend> sem[0x347708] status[0]
@4,325,971us: [+0 T:0x41e00490 S:0x41dffd0c] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x3476f0]
@4,326,156us: [+0 T:0x41e00490 S:0x41dffcf4] ti.sdo.fc.ires.hdvicp - IRESMAN_HDVICP_getProtocolName> Enter
@4,326,327us: [+0 T:0x41e00490 S:0x41dffcdc] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getName> Enter
@4,326,482us: [+0 T:0x41e00490 S:0x41dffcdc] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getName> Exit (Name=ti.sdo.fc.ires.hdvicp)
@4,658,780us: [+0 T:0x41e00490 S:0x41dffcf4] ti.sdo.fc.ires.hdvicp - IRESMAN_HDVICP_getProtocolName> Exit (name=ti.sdo.fc.ires.hdvicp)
@4,659,017us: [+0 T:0x41e00490 S:0x41dffcdc] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getName> Enter
@4,659,189us: [+0 T:0x41e00490 S:0x41dffcdc] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getName> Exit (Name=ti.sdo.fc.ires.hdvicp)
@4,659,370us: [+0 T:0x41e00490 S:0x41dffcf4] ti.sdo.fc.ires.vicp2 - IRESMAN_VICP2_getProtocolName> Enter
@4,659,540us: [+0 T:0x41e00490 S:0x41dffcf4] ti.sdo.fc.ires.vicp2 - IRESMAN_VICP2_getProtocolName> Exit (name=ti.sdo.fc.ires.vicp2)
@4,659,714us: [+0 T:0x41e00490 S:0x41dffcec] ti.sdo.fc.ires.addrspace - _IRESMAN_ADDRSPACE_getProtocolName> Enter
@4,659,877us: [+0 T:0x41e00490 S:0x41dffcd4] ti.sdo.fc.ires.addrspace - _IRES_ADDRSPACE_getName> Enter 
@4,660,071us: [+0 T:0x41e00490 S:0x41dffcd4] ti.sdo.fc.ires.addrspace - _IRES_ADDRSPACE_getName> Exit (Name=ti.sdo.fc.ires.addrspace)
@4,660,254us: [+0 T:0x41e00490 S:0x41dffcec] ti.sdo.fc.ires.addrspace - _IRESMAN_ADDRSPACE_getProtocolName> Exit (Name=ti.sdo.fc.ires.addrspace)
@4,660,432us: [+0 T:0x41e00490 S:0x41dffcf4] ti.sdo.fc.ires.memtcm - IRESMAN_MEMTCM_getProtocolName> Enter
@4,660,600us: [+0 T:0x41e00490 S:0x41dffcdc] ti.sdo.fc.ires.memtcm - _IRES_MEMTCM_getName> Enter 
@4,660,758us: [+0 T:0x41e00490 S:0x41dffcdc] ti.sdo.fc.ires.memtcm - _IRES_MEMTCM_getName> Exit (Name=ti.sdo.fc.ires.examples.resources.memtcm)
@4,660,926us: [+0 T:0x41e00490 S:0x41dffcf4] ti.sdo.fc.ires.memtcm - IRESMAN_MEMTCM_getProtocolName> Exit (Name=ti.sdo.fc.ires.examples.resources.memtcm)
@4,661,098us: [+0 T:0x41e00490 S:0x41dffcdc] ti.sdo.fc.ires.memtcm - _IRES_MEMTCM_getName> Enter 
@4,661,254us: [+0 T:0x41e00490 S:0x41dffcdc] ti.sdo.fc.ires.memtcm - _IRES_MEMTCM_getName> Exit (Name=ti.sdo.fc.ires.examples.resources.memtcm)
@4,661,463us: [+0 T:0x41e00490 S:0x41dffcf4] ti.sdo.fc.ires.edma3Chan - IRESMAN_EDMA3CHAN_getProtocolName> Enter
@4,661,638us: [+0 T:0x41e00490 S:0x41dffcdc] ti.sdo.fc.ires.edma3Chan - IRES_EDMA3CHAN_getName> Enter
@4,661,800us: [+0 T:0x41e00490 S:0x41dffcdc] ti.sdo.fc.ires.edma3Chan - IRES_EDMA3CHAN_getName> Exit (Name=ti.sdo.fc.ires.edma3chan)
@4,661,968us: [+0 T:0x41e00490 S:0x41dffcf4] ti.sdo.fc.ires.edma3Chan - IRESMAN_EDMA3CHAN_getProtocolName> Exit (name=ti.sdo.fc.ires.edma3chan)
@4,662,136us: [+0 T:0x41e00490 S:0x41dffcdc] ti.sdo.fc.ires.edma3Chan - IRES_EDMA3CHAN_getName> Enter
@4,662,294us: [+0 T:0x41e00490 S:0x41dffcdc] ti.sdo.fc.ires.edma3Chan - IRES_EDMA3CHAN_getName> Exit (Name=ti.sdo.fc.ires.edma3chan)
@4,662,464us: [+0 T:0x41e00490 S:0x41dffce4] ti.sdo.ce.osal.Sem - Sem_create> count: 1
@4,662,623us: [+0 T:0x41e00490 S:0x41dffccc] OM - Memory_alloc> Enter(0x14)
@4,662,828us: [+0 T:0x41e00490 S:0x41dffccc] OM - Memory_alloc> return (0x3478a8)
@4,663,054us: [+0 T:0x41e00490 S:0x41dffce4] ti.sdo.ce.osal.Sem - Leaving Sem_create> sem[0x3478a8]
@4,663,261us: [+0 T:0x41e00490 S:0x41dffcf4] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x3476f0]
@4,663,438us: [+0 T:0x41e00490 S:0x41dffcd4] ti.sdo.ce.osal.SemMP - Entered SemMP_post> sem[0x347708]
@4,663,631us: [+0 T:0x41e00490 S:0x41dffcd4] ti.sdo.ce.osal.SemMP - Leaving SemMP_post> sem[0x347708]
@4,663,813us: [+0 T:0x41e00490 S:0x41dffd0c] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x3476f0]
@4,663,978us: [+0 T:0x41e00490 S:0x41dffd0c] ti.sdo.fc.rman - RMAN_register> Exit (status=IRES_OK)
@4,664,296us: [+0 T:0x41e00490 S:0x41dffd54] ti.sdo.fc.rman - RMAN_init> Exit (status=0)
@4,664,521us: [+0 T:0x41e00490 S:0x41dffd6c] ti.sdo.ce.alg - ALG_init> Enter
@4,664,693us: [+0 T:0x41e00490 S:0x41dffd2c] ti.sdo.ce.osal.SemMP - SemMP_create> key: 0x4f474c42 count: 1
@4,664,871us: [+0 T:0x41e00490 S:0x41dffd14] OM - Memory_alloc> Enter(0x4)
@4,665,038us: [+0 T:0x41e00490 S:0x41dffd14] OM - Memory_alloc> return (0x3478e8)
@4,997,122us: [+0 T:0x41e00490 S:0x41dffd14] ti.sdo.ce.osal.SemMP - Entered enterCS> key[0x4c41534f]
@4,997,373us: [+0 T:0x41e00490 S:0x41dffd14] ti.sdo.ce.osal.SemMP - Leaving enterCS> id[0xa0009]
@4,997,616us: [+0 T:0x41e00490 S:0x41dffd2c] ti.sdo.ce.osal.SemMP - SemMP_create> semId: 0x50006 refCount: 1
@4,997,811us: [+0 T:0x41e00490 S:0x41dffd14] ti.sdo.ce.osal.SemMP - Entered exitCS> id[0xa0009]
@4,997,997us: [+0 T:0x41e00490 S:0x41dffd2c] ti.sdo.ce.osal.SemMP - Leaving exitCS
@4,998,156us: [+0 T:0x41e00490 S:0x41dffd2c] ti.sdo.ce.osal.SemMP - Leaving SemMP_create> sem[0x3478e8]
@4,998,319us: [+0 T:0x41e00490 S:0x41dffd2c] ti.sdo.ce.osal.SemMP - SemMP_create> key: 0x4f474c43 count: 1
@4,998,530us: [+0 T:0x41e00490 S:0x41dffd14] OM - Memory_alloc> Enter(0x4)
@4,998,705us: [+0 T:0x41e00490 S:0x41dffd14] OM - Memory_alloc> return (0x3478f8)
@4,998,869us: [+0 T:0x41e00490 S:0x41dffd14] ti.sdo.ce.osal.SemMP - Entered enterCS> key[0x4c41534f]
@4,999,054us: [+0 T:0x41e00490 S:0x41dffd14] ti.sdo.ce.osal.SemMP - Leaving enterCS> id[0xa8009]
@4,999,280us: [+0 T:0x41e00490 S:0x41dffd2c] ti.sdo.ce.osal.SemMP - SemMP_create> semId: 0x60007 refCount: 1
@4,999,474us: [+0 T:0x41e00490 S:0x41dffd14] ti.sdo.ce.osal.SemMP - Entered exitCS> id[0xa8009]
@4,999,660us: [+0 T:0x41e00490 S:0x41dffd2c] ti.sdo.ce.osal.SemMP - Leaving exitCS
@4,999,861us: [+0 T:0x41e00490 S:0x41dffd2c] ti.sdo.ce.osal.SemMP - Leaving SemMP_create> sem[0x3478f8]
@5,000,031us: [+0 T:0x41e00490 S:0x41dffd5c] OG - Global_atexit> enter (fxn=0x4cab4)
@5,000,211us: [+0 T:0x41e00490 S:0x41dffd8c] ti.sdo.ce.alg - ALG_init> Exit
@5,000,444us: [+0 T:0x41e00490 S:0x41dffd84] OG - Global_atexit> enter (fxn=0x4bee0)
@5,000,652us: [+0 T:0x41e00490 S:0x41dffd5c] OM - Memory_alloc> Enter(0x18)
@5,000,839us: [+0 T:0x41e00490 S:0x41dffd5c] OM - Memory_alloc> return (0x347948)
@5,001,012us: [+0 T:0x41e00490 S:0x41dffd84] OG - Global_atexit> enter (fxn=0x4f44c)
@5,001,321us: [+6 T:0x41e00490 S:0x41dffd7c] CE - Engine_init> CE debugging on (CE_DEBUG=3; allowed CE_DEBUG levels: 1=min, 2=good, 3=max)
@5,001,538us: [+0 T:0x41e00490 S:0x41dffd6c] OG - Global_atexit> enter (fxn=0x4849c)
@5,001,723us: [+0 T:0x41e00490 S:0x41dffd4c] OM - Memory_alloc> Enter(0x18)
@5,001,899us: [+0 T:0x41e00490 S:0x41dffd4c] OM - Memory_alloc> return (0x347988)
@5,002,070us: [+0 T:0x41e00490 S:0x41dffd4c] OM - Memory_alloc> Enter(0x18)
@5,002,242us: [+0 T:0x41e00490 S:0x41dffd4c] OM - Memory_alloc> return (0x3479a8)
@5,002,403us: [+0 T:0x41e00490 S:0x41dffd4c] OM - Memory_alloc> Enter(0x18)
@5,002,609us: [+0 T:0x41e00490 S:0x41dffd4c] OM - Memory_alloc> return (0x3479c8)
@5,002,870us: [+0 T:0x41e00490 S:0x41dffd94] CS - Server_init()
@5,003,057us: [+0 T:0x41e00490 S:0x41dffd94] CS - Server_init> Global_useLinkArbiter = 0
@5,003,236us: [+0 T:0x41e00490 S:0x41dffd94] OG - Global_atexit> enter (fxn=0x4677c)
@5,003,469us: [+0 T:0x41e00490 S:0x41dffd44] CE - Engine_open> Enter('encode', 0x0, 0x41dffdf4)
@5,003,711us: [+0 T:0x41e00490 S:0x41dffd2c] OM - Memory_alloc> Enter(0x2c)
@5,003,938us: [+0 T:0x41e00490 S:0x41dffd2c] OM - Memory_alloc> return (0x347a18)
@5,004,258us: [+0 T:0x41e00490 S:0x41dffd44] CE - Engine_open> return(3439128)
@5,004,558us: [+0 T:0x41e00490 S:0x41dffc7c] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_create> Enter (engine=0x347a18, name='h264dec', params=0x23e5fc)
@5,004,810us: [+0 T:0x41e00490 S:0x41dffc4c] CV - VISA_create(0x347a18, 'h264dec', 0x23e5fc, 0x2484, 'ti.sdo.ce.video2.IVIDDEC2')
@5,005,014us: [+0 T:0x41e00490 S:0x41dffb4c] CV - VISA_create2(0x347a18, 'h264dec', 0x23e5fc, 0x38, 0x2484, 'ti.sdo.ce.video2.IVIDDEC2')
@5,005,406us: [+0 T:0x41e00490 S:0x41dffb34] OM - Memory_alloc> Enter(0x30)
@5,005,640us: [+0 T:0x41e00490 S:0x41dffb34] OM - Memory_alloc> return (0x347a98)
@5,005,824us: [+0 T:0x41e00490 S:0x41dffb0c] ti.sdo.ce.alg.Algorithm - Algorithm_create> Enter(fxns=0x23c858, idma3Fxns=0x0, iresFxns=0x23c7f4, params=0x23e5fc, attrs=0x41dffc3c)
@5,338,256us: [+0 T:0x41e00490 S:0x41dffaf4] OM - Memory_alloc> Enter(0x10)
@5,338,492us: [+0 T:0x41e00490 S:0x41dffaf4] OM - Memory_alloc> return (0x347ad0)
@5,338,672us: [+0 T:0x41e00490 S:0x41dffac4] ti.sdo.ce.alg - ALG_create> Enter (scratchId=1, fxns=0x23c858, parentAlg=0x0, params=0x23e5fc)
@5,338,921us: [+2 T:0x41e00490 S:0x41dffac4] ti.sdo.ce.alg - ALG_create> algNumAlloc 14 memory recs
@5,339,175us: [+7 T:0x41e00490 S:0x41dffb0c] ti.sdo.ce.alg.Algorithm - Algorithm_create> Algorithm creation FAILED; make sure that 1) alg params are correct/appropriate, 2) there is enough internal and external algorithm memory available -- check DSKT2 settings for heap assignments and scratch allocation
@5,339,407us: [+0 T:0x41e00490 S:0x41dffaf4] ti.sdo.ce.alg.Algorithm - Algorithm_delete> Enter(alg=0x347ad0)
@5,339,631us: [+0 T:0x41e00490 S:0x41dffad4] OM - Memory_free> Enter(0x347ad0, 0x10)
@5,339,892us: [+0 T:0x41e00490 S:0x41dffad4] OM - Memory_free> return (0x1)
@5,340,089us: [+0 T:0x41e00490 S:0x41dffb0c] ti.sdo.ce.alg.Algorithm - Algorithm_delete> Exit
@5,340,263us: [+0 T:0x41e00490 S:0x41dffb0c] ti.sdo.ce.alg.Algorithm - Algorithm_create> return (0x0)
@5,340,432us: [+2 T:0x41e00490 S:0x41dffb4c] CV - VISA_create2> FAILED to create local codec.
@5,340,590us: [+0 T:0x41e00490 S:0x41dffb24] CV - VISA_delete(0x347a98)
@5,340,799us: [+5 T:0x41e00490 S:0x41dffb24] CV - VISA_delete> deleting codec (localQueue=0xffff, remoteQueue=0xffff)
@5,341,039us: [+0 T:0x41e00490 S:0x41dffb04] OM - Memory_free> Enter(0x347a98, 0x30)
@5,341,224us: [+0 T:0x41e00490 S:0x41dffb04] OM - Memory_free> return (0x1)
@5,341,393us: [+0 T:0x41e00490 S:0x41dffc7c] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_create> return (0x0)
[decode] ERROR  (../src/h264dec.c|create_h264decoder|132): open decode algorithm Failed: h264dec (0x0)
@5,341,688us: [+0 T:0x41e00490 S:0x41dffc8c] CE - Engine_getNumAlgs('encode', 0x41dffdbc)
[decode] available Alg=8====>>>>
@5,341,939us: [+0 T:0x41e00490 S:0x41dffc84] CE - Engine_getAlgInfo('encode', 0x41dffdac, 0x0)
[decode] alg[0]: name = iprun typeTab = ti.sdo.ce.video1.IVIDENC1 Local = 1
@5,342,204us: [+0 T:0x41e00490 S:0x41dffc84] CE - Engine_getAlgInfo('encode', 0x41dffdac, 0x1)
[decode] alg[1]: name = h264enc typeTab = ti.sdo.ce.video1.IVIDENC1 Local = 1
@5,342,521us: [+0 T:0x41e00490 S:0x41dffc84] CE - Engine_getAlgInfo('encode', 0x41dffdac, 0x2)
[decode] alg[2]: name = mpeg4enc typeTab = ti.sdo.ce.video1.IVIDENC1 Local = 1
@5,342,798us: [+0 T:0x41e00490 S:0x41dffc84] CE - Engine_getAlgInfo('encode', 0x41dffdac, 0x3)
[decode] alg[3]: name = dei typeTab = ti.sdo.ce.video1.IVIDENC1 Local = 1
@5,343,062us: [+0 T:0x41e00490 S:0x41dffc84] CE - Engine_getAlgInfo('encode', 0x41dffdac, 0x4)
[decode] alg[4]: name = ktnf typeTab = ti.sdo.ce.video1.IVIDENC1 Local = 1
@5,343,332us: [+0 T:0x41e00490 S:0x41dffc84] CE - Engine_getAlgInfo('encode', 0x41dffdac, 0x5)
[decode] alg[5]: name = jpegenc typeTab = ti.sdo.ce.image1.IIMGENC1 Local = 1
@5,343,601us: [+0 T:0x41e00490 S:0x41dffc84] CE - Engine_getAlgInfo('encode', 0x41dffdac, 0x6)
[decode] alg[6]: name = mpeg4dec typeTab = ti.sdo.ce.video2.IVIDDEC2 Local = 1
@5,343,909us: [+0 T:0x41e00490 S:0x41dffc84] CE - Engine_getAlgInfo('encode', 0x41dffdac, 0x7)
[decode] alg[7]: name = h264dec typeTab = ti.sdo.ce.video2.IVIDDEC2 Local = 1
[decode] ERROR  (../src/decode.c|create_decoder|114): open decode algorithm Failed: h264dec (0x0)
[decode] ERROR  (../src/main.c|decode_thread|74): create encoder err,streamid=0

Thank you for your attention.

I hope I could got your help. If necessary, I can provide the *.cfg or other detail debug information for you .

Regards, Mike

over 13 years ago

0 zanget over 13 years ago

Expert 2150 points

i have checked and tried with the suggest in post here, but still no hope.

Could it be that you have multiple processes accessing Codec Engine, and only one of them called CERuntime_init()? A multi-threaded process should call it only once, before the threading starts, but each process needs to call it separately.

i will put new log here when update my app according to the above post.

0 zanget over 13 years ago

Expert 2150 points

main process start here

[capture] (videoServe.c|VIDEO_serverInit|571): LOADING CE

@0,955,855us: [+4 T:0x4001ebe0] OG - Global_init> This program was built with the following packages:

@0,956,157us: [+4 T:0x4001ebe0] OG - package gnu.targets.rts470MV (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/xdctools_3_15_01_59/packages/gnu/targets/rts470MV/) [1, 0, 0, 0]

@0,956,414us: [+4 T:0x4001ebe0] OG - package ti.sdo.linuxutils.vicp (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/linuxutils_2_24_03/packages/ti/sdo/linuxutils/vicp/) [1, 0, 0]

@0,956,582us: [+4 T:0x4001ebe0] OG - package ti.sdo.utils.trace (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/utils/trace/) [1, 0, 0]

@0,956,737us: [+4 T:0x4001ebe0] OG - package ti.sdo.fc.global (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/global/) [1, 0, 0]

@0,956,883us: [+4 T:0x4001ebe0] OG - package ti.sdo.fc.utils.gtinfra (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/utils/gtinfra/) [1, 0, 0]

@0,957,029us: [+4 T:0x4001ebe0] OG - package ti.sdo.linuxutils.edma (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/linuxutils_2_24_03/packages/ti/sdo/linuxutils/edma/) [1, 0, 0]

@0,957,273us: [+4 T:0x4001ebe0] OG - package ti.sdo.linuxutils.cmem (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/linuxutils_2_24_03/packages/ti/sdo/linuxutils/cmem/) [2, 2, 0]

@0,957,427us: [+4 T:0x4001ebe0] OG - package ti.sdo.fc.memutils (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/memutils/) [1, 0, 0]

@0,957,576us: [+4 T:0x4001ebe0] OG - package ti.sdo.ce.global (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/codec_engine_2_24/packages/ti/sdo/ce/global/) [1, 0, 0]

@0,957,720us: [+4 T:0x4001ebe0] OG - package ti.xdais.dm (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/xdais_6_24/packages/ti/xdais/dm/) [1, 0, 5]

@0,957,859us: [+4 T:0x4001ebe0] OG - package ti.xdais (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/xdais_6_24/packages/ti/xdais/) [1, 2.0, 1]

@0,957,994us: [+4 T:0x4001ebe0] OG - package ti.sdo.ce.node (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/codec_engine_2_24/packages/ti/sdo/ce/node/) [1, 0, 0]

@0,958,135us: [+4 T:0x4001ebe0] OG - package ti.sdo.ce.utils.xdm (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/codec_engine_2_24/packages/ti/sdo/ce/utils/xdm/) [1, 0, 2]

@0,958,278us: [+4 T:0x4001ebe0] OG - package gnu.targets (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/xdctools_3_15_01_59/packages/gnu/targets/) [1, 0, 1]

@0,958,418us: [+4 T:0x4001ebe0] OG - package ti.sdo.fc.utils (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/utils/) [1, 0, 2]

@0,958,560us: [+4 T:0x4001ebe0] OG - package ti.sdo.fc.dman3 (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/dman3/) [1, 0, 4]

@0,958,701us: [+4 T:0x4001ebe0] OG - package ti.sdo.fc.acpy3 (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/acpy3/) [1, 0, 4]

@0,958,841us: [+4 T:0x4001ebe0] OG - package ti.sdo.fc.ires (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/ires/) [1, 0, 0]

@0,958,986us: [+4 T:0x4001ebe0] OG - package ti.sdo.fc.ires.hdvicp (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/ires/hdvicp/) [1, 0, 1]

@0,959,130us: [+4 T:0x4001ebe0] OG - package ti.sdo.fc.hdvicpsync (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/hdvicpsync/) [1, 0, 0]

@1,435,082us: [+4 T:0x4001ebe0] OG - package ti.sdo.fc.ires.addrspace (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/ires/addrspace/) [1, 0, 0]

@1,435,295us: [+4 T:0x4001ebe0] OG - package ti.sdo.fc.ires.memtcm (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/ires/memtcm/) [1, 0, 0]

@1,435,458us: [+4 T:0x4001ebe0] OG - package ti.sdo.codecs.h264dec (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/dm365_codecs_01_00_06/packages/ti/sdo/codecs/h264dec/) [1, 0, 0]

@1,435,605us: [+4 T:0x4001ebe0] OG - package ti.sdo.codecs.h264enc (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/dm365_codecs_01_00_06/packages/ti/sdo/codecs/h264enc/) [1, 0, 0]

@1,435,752us: [+4 T:0x4001ebe0] OG - package ti.sdo.fc.ires.nullresource (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/ires/nullresource/) [1, 0, 0]

@1,435,901us: [+4 T:0x4001ebe0] OG - package ti.sdo.fc.rman (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/rman/) [2, 0, 0]

@1,436,042us: [+4 T:0x4001ebe0] OG - package ti.sdo.fc.edma3 (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/edma3/) [3, 0, 0]

@1,436,183us: [+4 T:0x4001ebe0] OG - package ti.sdo.fc.ires.edma3chan (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/ires/edma3chan/) [1, 0, 0]

@1,436,456us: [+4 T:0x4001ebe0] OG - package ti.sdo.fc.ires.grouputils (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/ires/grouputils/) [1, 0, 0]

@1,436,623us: [+4 T:0x4001ebe0] OG - package ti.sdo.fc.ires.vicp (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/ires/vicp/) [1, 0, 1]

@1,436,773us: [+4 T:0x4001ebe0] OG - package ti.sdo.fc.vicpsync (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/vicpsync/) [1, 0, 0]

@1,436,920us: [+4 T:0x4001ebe0] OG - package ti.sdo.codecs.mpeg4dec (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/dm365_codecs_01_00_06/packages/ti/sdo/codecs/mpeg4dec/) [1, 0, 0]

@1,437,064us: [+4 T:0x4001ebe0] OG - package ti.sdo.codecs.iprun (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/dm365_codecs_01_00_06/packages/ti/sdo/codecs/iprun/) [1, 0, 0]

@1,437,210us: [+4 T:0x4001ebe0] OG - package ti.sdo.codecs.mpeg4enc (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/dm365_codecs_01_00_06/packages/ti/sdo/codecs/mpeg4enc/) [1, 0, 0]

@1,437,355us: [+4 T:0x4001ebe0] OG - package ti.sdo.codecs.jpegenc (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/dm365_codecs_01_00_06/packages/ti/sdo/codecs/jpegenc/) [1, 0, 0]

@1,437,498us: [+4 T:0x4001ebe0] OG - package ti.sdo.codecs.dei (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/dm365_codecs_01_00_06/packages/ti/sdo/codecs/dei/) [1, 0, 0]

@1,437,638us: [+4 T:0x4001ebe0] OG - package ti.sdo.codecs.ktnf (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/dm365_codecs_01_00_06/packages/ti/sdo/codecs/ktnf/) [1, 0, 0]

@1,437,818us: [+4 T:0x4001ebe0] OG - package ti.catalog.arm (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/xdctools_3_15_01_59/packages/ti/catalog/arm/) [1, 0, 1, 0]

@1,437,968us: [+4 T:0x4001ebe0] OG - package ti.catalog (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/xdctools_3_15_01_59/packages/ti/catalog/) [1, 0, 0]

@1,438,105us: [+4 T:0x4001ebe0] OG - package ti.platforms.evmDM365 (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/xdctools_3_15_01_59/packages/ti/platforms/evmDM365/) [1, 0, 0]

@1,770,642us: [+4 T:0x4001ebe0] OG - package ti.sdo.ce.osal (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/codec_engine_2_24/packages/ti/sdo/ce/osal/) [2, 0, 2]

@1,770,848us: [+4 T:0x4001ebe0] OG - package ti.sdo.ce.osal.linux (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/codec_engine_2_24/packages/ti/sdo/ce/osal/linux/) [2, 0, 1]

@1,771,007us: [+4 T:0x4001ebe0] OG - package ti.sdo.ce.ipc (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/codec_engine_2_24/packages/ti/sdo/ce/ipc/) [2, 0, 1]

@1,771,156us: [+4 T:0x4001ebe0] OG - package ti.sdo.ce.alg (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/codec_engine_2_24/packages/ti/sdo/ce/alg/) [1, 0, 1]

@1,771,297us: [+4 T:0x4001ebe0] OG - package ti.sdo.ce.ipc.linux (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/codec_engine_2_24/packages/ti/sdo/ce/ipc/linux/) [2, 0, 1]

@1,771,437us: [+4 T:0x4001ebe0] OG - package ti.sdo.ce (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/codec_engine_2_24/packages/ti/sdo/ce/) [1, 0, 6]

@1,771,573us: [+4 T:0x4001ebe0] OG - package ti.sdo.ce.video2 (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/codec_engine_2_24/packages/ti/sdo/ce/video2/) [1, 0, 2]

@1,771,711us: [+4 T:0x4001ebe0] OG - package ti.sdo.codecs.mpeg4dec.ce (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/dm365_codecs_01_00_06/packages/ti/sdo/codecs/mpeg4dec/ce/) [1, 0, 0]

@1,771,852us: [+4 T:0x4001ebe0] OG - package ti.sdo.codecs.h264dec.ce (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/dm365_codecs_01_00_06/packages/ti/sdo/codecs/h264dec/ce/) [1, 0, 0]

@1,772,040us: [+4 T:0x4001ebe0] OG - package ti.sdo.ce.video1 (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/codec_engine_2_24/packages/ti/sdo/ce/video1/) [1, 0, 2]

@1,772,188us: [+4 T:0x4001ebe0] OG - package ti.sdo.codecs.iprun.ce (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/dm365_codecs_01_00_06/packages/ti/sdo/codecs/iprun/ce/) [1, 0, 0]

@1,772,330us: [+4 T:0x4001ebe0] OG - package ti.sdo.codecs.h264enc.ce (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/dm365_codecs_01_00_06/packages/ti/sdo/codecs/h264enc/ce/) [1, 0, 0]

@1,772,473us: [+4 T:0x4001ebe0] OG - package ti.sdo.codecs.mpeg4enc.ce (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/dm365_codecs_01_00_06/packages/ti/sdo/codecs/mpeg4enc/ce/) [1, 0, 0]

@1,772,620us: [+4 T:0x4001ebe0] OG - package ti.sdo.ce.image1 (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/codec_engine_2_24/packages/ti/sdo/ce/image1/) [1, 0, 1]

@1,772,759us: [+4 T:0x4001ebe0] OG - package ti.sdo.codecs.jpegenc.ce (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/dm365_codecs_01_00_06/packages/ti/sdo/codecs/jpegenc/ce/) [1, 0, 0]

@1,772,901us: [+4 T:0x4001ebe0] OG - package ti.sdo.codecs.dei.ce (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/dm365_codecs_01_00_06/packages/ti/sdo/codecs/dei/ce/) [1, 0, 0]

@1,773,133us: [+4 T:0x4001ebe0] OG - package ti.sdo.codecs.ktnf.ce (/home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/dm365_codecs_01_00_06/packages/ti/sdo/codecs/ktnf/ce/) [1, 0, 0]

@1,773,323us: [+4 T:0x4001ebe0] OG - package encode_config (/home/wen_hao/mnt/win/13391/work/project/ti_dm365/vth1520b-c/Encode/Encode/Build/encode_config/) []

@1,774,035us: [+0 T:0x4001ebe0] ti.sdo.fc.rman - RMAN_init> Enter

@1,774,236us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - LockMP_create> key: 0x524d414e count: 2261280 attrs: 0x228120

@1,774,508us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Leaving LockMP_create> lock[0x347260]

@1,774,720us: [+0 T:0x4001ebe0] ti.sdo.fc.rman - RMAN_init> _RMAN_lock = 0x347260

@1,774,884us: [+0 T:0x4001ebe0] ti.sdo.fc.rman - RMAN_register> Enter (resmanFxns=0x23becc, initArgs=0xbec76cec)

@1,775,100us: [+4 T:0x4001ebe0] ti.sdo.fc.rman - RMAN_register> Registering protocol ti.sdo.fc.ires.examples.resources.nullres revision (1.0.0)

@2,107,603us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x347260]

@2,107,990us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x347260]

@2,108,190us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x347260]

@2,108,377us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x347260]

@2,108,529us: [+0 T:0x4001ebe0] ti.sdo.fc.rman - RMAN_register> Exit (status=IRES_OK)

@2,108,871us: [+2 T:0x4001ebe0] ti.sdo.fc.rman - RMAN_init> Registering statically added resources

@2,109,045us: [+0 T:0x4001ebe0] ti.sdo.fc.rman - RMAN_register> Enter (resmanFxns=0x23c850, initArgs=0x249650)

@2,109,323us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.hdvicp - IRESMAN_HDVICP_getProtocolName> Enter

@2,109,551us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getName> Enter

@2,109,705us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getName> Exit (Name=ti.sdo.fc.ires.hdvicp)

@2,109,848us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.hdvicp - IRESMAN_HDVICP_getProtocolName> Exit (name=ti.sdo.fc.ires.hdvicp)

@2,109,990us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getName> Enter

@2,110,117us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getName> Exit (Name=ti.sdo.fc.ires.hdvicp)

@2,110,290us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.hdvicp - IRESMAN_HDVICP_getProtocolRevision> Enter

@2,110,433us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getRevision> Enter

@2,110,564us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getRevision>Exit (Version=(1.0.1))

@2,110,700us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.hdvicp - IRESMAN_HDVICP_getProtocolRevision> Exit (version=(1.0.1))

@2,110,841us: [+4 T:0x4001ebe0] ti.sdo.fc.rman - RMAN_register> Registering protocol ti.sdo.fc.ires.hdvicp revision (1.0.1)

@2,110,991us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x347260]

@2,111,160us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x347260]

@2,111,318us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.hdvicp - IRESMAN_HDVICP_init> Enter (initArgs=0x249650)

@2,111,462us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - LockMP_create> key: 0x4956444a count: 2261280 attrs: 0x228120

@2,111,745us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Leaving LockMP_create> lock[0x3472b8]

@2,112,022us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x3472b8]

@2,112,234us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x3472b8]

@2,112,490us: [+0 T:0x4001ebe0] ti.sdo.fc.utils.shm - _Shared Memory_initImpl> Shared memory, key = 0x30006 already exists

@2,112,811us: [+0 T:0x4001ebe0] ti.sdo.fc.utils.shm - SHM_getObj> shmBase = 0x40021000

@2,113,046us: [+0 T:0x4001ebe0] ti.sdo.fc.utils.shm - _Shared Memory_initImpl> Shared Memory lock reference count = 1

@2,113,215us: [+5 T:0x4001ebe0] ti.sdo.fc.utils.shm - _Shared Memory_initImpl> Warning: Shared Memory lock reference count indicates no other processes are

currently using Shared Memory shared memory.

Re-initializing Shared Memory shared memory

@2,113,397us: [+0 T:0x4001ebe0] ti.sdo.fc.utils.shm - Initializing Shared Memory region

@2,113,729us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x3472b8]

@2,113,959us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x3472b8]

@2,114,250us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.hdvicp - IRESMAN_HDVICP_init> Exit (status=IRES_OK)

@2,114,478us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x347260]

@2,114,667us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x347260]

@2,114,817us: [+0 T:0x4001ebe0] ti.sdo.fc.rman - RMAN_register> Exit (status=IRES_OK)

@2,114,962us: [+0 T:0x4001ebe0] ti.sdo.fc.rman - RMAN_register> Enter (resmanFxns=0x23be50, initArgs=0x2495d4)

@2,447,437us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.vicp2 - IRESMAN_VICP2_getProtocolName> Enter

@2,447,688us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.vicp2 - IRESMAN_VICP2_getProtocolName> Exit (name=ti.sdo.fc.ires.vicp2)

@2,458,201us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.vicp2 - IRESMAN_VICP2_getProtocolRevision> Enter

@2,458,416us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.vicp2 - IRESMAN_VICP2_getProtocolRevision> Exit (version=(1.0.0))

@2,458,574us: [+4 T:0x4001ebe0] ti.sdo.fc.rman - RMAN_register> Registering protocol ti.sdo.fc.ires.vicp2 revision (1.0.0)

@2,458,732us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x347260]

@2,458,904us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x347260]

@2,459,062us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.hdvicp - IRESMAN_HDVICP_getProtocolName> Enter

@2,459,206us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getName> Enter

@2,459,341us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getName> Exit (Name=ti.sdo.fc.ires.hdvicp)

@2,459,476us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.hdvicp - IRESMAN_HDVICP_getProtocolName> Exit (name=ti.sdo.fc.ires.hdvicp)

@2,459,614us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getName> Enter

@2,459,785us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getName> Exit (Name=ti.sdo.fc.ires.hdvicp)

@2,459,997us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.vicp2 - IRESMAN_VICP2_init> Enter (initArgs=0x2495d4)

@2,460,163us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - LockMP_create> key: 0x50434958 count: 2261280 attrs: 0x228120

@2,460,427us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Leaving LockMP_create> lock[0x347338]

@2,460,664us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x347338]

@2,460,854us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x347338]

@2,461,027us: [+0 T:0x4001ebe0] ti.sdo.fc.utils.shm - _Shared Memory_initImpl> Shared memory, key = 0x38007 already exists

@2,461,306us: [+0 T:0x4001ebe0] ti.sdo.fc.utils.shm - SHM_getObj> shmBase = 0x40022000

@2,461,490us: [+0 T:0x4001ebe0] ti.sdo.fc.utils.shm - _Shared Memory_initImpl> Shared Memory lock reference count = 1

@2,461,642us: [+5 T:0x4001ebe0] ti.sdo.fc.utils.shm - _Shared Memory_initImpl> Warning: Shared Memory lock reference count indicates no other processes are

currently using Shared Memory shared memory.

Re-initializing Shared Memory shared memory

@2,461,816us: [+0 T:0x4001ebe0] ti.sdo.fc.utils.shm - Initializing Shared Memory region

@2,462,013us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x347338]

@2,462,214us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x347338]

@2,462,444us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x347338]

@2,462,641us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x347338]

@2,462,815us: [+0 T:0x4001ebe0] ti.sdo.fc.utils.shm - _Shared Memory_initImpl> Shared memory, key = 0x40008 already exists

@2,463,040us: [+0 T:0x4001ebe0] ti.sdo.fc.utils.shm - SHM_getObj> shmBase = 0x40023000

@2,463,226us: [+0 T:0x4001ebe0] ti.sdo.fc.utils.shm - _Shared Memory_initImpl> Shared Memory lock reference count = 1

@2,463,379us: [+5 T:0x4001ebe0] ti.sdo.fc.utils.shm - _Shared Memory_initImpl> Warning: Shared Memory lock reference count indicates no other processes are

currently using Shared Memory shared memory.

Re-initializing Shared Memory shared memory

@2,463,697us: [+0 T:0x4001ebe0] ti.sdo.fc.utils.shm - Initializing Shared Memory region

@2,463,978us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x347338]

@2,464,187us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x347338]

@2,506,223us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.vicp2 - IRESMAN_VICP2_init> Exit (status=IRES_OK)

@2,506,502us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x347260]

@2,506,704us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x347260]

@2,506,897us: [+0 T:0x4001ebe0] ti.sdo.fc.rman - RMAN_register> Exit (status=IRES_OK)

@2,507,048us: [+0 T:0x4001ebe0] ti.sdo.fc.rman - RMAN_register> Enter (resmanFxns=0x23c814, initArgs=0x249640)

@2,507,332us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.addrspace - _IRESMAN_ADDRSPACE_getProtocolName> Enter

@2,507,509us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.addrspace - _IRES_ADDRSPACE_getName> Enter

@2,507,651us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.addrspace - _IRES_ADDRSPACE_getName> Exit (Name=ti.sdo.fc.ires.addrspace)

@2,839,782us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.addrspace - _IRESMAN_ADDRSPACE_getProtocolName> Exit (Name=ti.sdo.fc.ires.addrspace)

@2,839,991us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.addrspace - _IRESMAN_ADDRSPACE_getProtocolRevision> Enter

@2,840,142us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.addrspace - _IRES_ADDRSPACE_getRevision> Enter

@2,840,275us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.addrspace - _IRES_ADDRSPACE_getRevision> Exit (Version=(1.0.0))

@2,840,419us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.addrspace - _IRESMAN_ADDRSPACE_getProtocolRevision> Exit (Version=(1.0.0))

@2,840,565us: [+4 T:0x4001ebe0] ti.sdo.fc.rman - RMAN_register> Registering protocol ti.sdo.fc.ires.addrspace revision (1.0.0)

@2,840,715us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x347260]

@2,840,885us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x347260]

@2,841,080us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.hdvicp - IRESMAN_HDVICP_getProtocolName> Enter

@2,841,233us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getName> Enter

@2,841,368us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getName> Exit (Name=ti.sdo.fc.ires.hdvicp)

@2,841,502us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.hdvicp - IRESMAN_HDVICP_getProtocolName> Exit (name=ti.sdo.fc.ires.hdvicp)

@2,841,640us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getName> Enter

@2,841,763us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getName> Exit (Name=ti.sdo.fc.ires.hdvicp)

@2,841,904us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.vicp2 - IRESMAN_VICP2_getProtocolName> Enter

@2,842,034us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.vicp2 - IRESMAN_VICP2_getProtocolName> Exit (name=ti.sdo.fc.ires.vicp2)

@2,842,181us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.addrspace - _IRESMAN_ADDRSPACE_init> Enter (initArgs=0x249640)

@2,842,326us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.addrspace - _IRESMAN_ADDRSPACE_init> Exit (status=IRES_OK)

@2,842,503us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x347260]

@2,842,675us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x347260]

@2,842,831us: [+0 T:0x4001ebe0] ti.sdo.fc.rman - RMAN_register> Exit (status=IRES_OK)

@2,842,967us: [+0 T:0x4001ebe0] ti.sdo.fc.rman - RMAN_register> Enter (resmanFxns=0x23c7f8, initArgs=0x249624)

@2,843,211us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.memtcm - IRESMAN_MEMTCM_getProtocolName> Enter

@2,843,428us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.memtcm - _IRES_MEMTCM_getName> Enter

@2,843,812us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.memtcm - _IRES_MEMTCM_getName> Exit (Name=ti.sdo.fc.ires.examples.resources.memtcm)

@2,843,992us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.memtcm - IRESMAN_MEMTCM_getProtocolName> Exit (Name=ti.sdo.fc.ires.examples.resources.memtcm)

@2,844,144us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.memtcm - _IRES_MEMTCM_getName> Enter

@2,844,275us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.memtcm - _IRES_MEMTCM_getName> Exit (Name=ti.sdo.fc.ires.examples.resources.memtcm)

@2,844,417us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.memtcm - IRESMAN_MEMTCM_getProtocolRevision> Enter

@2,844,548us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.memtcm - _IRES_MEMTCM_getRevision> Enter

@2,844,673us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.memtcm - _IRES_MEMTCM_getRevision> Exit (Version=(1.0.0))

@2,844,811us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.memtcm - IRESMAN_MEMTCM_getProtocolRevision> Exit (Version=(1.0.0))

@2,844,952us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.memtcm - _IRES_MEMTCM_getRevision> Enter

@2,845,078us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.memtcm - _IRES_MEMTCM_getRevision> Exit (Version=(1.0.0))

@2,845,257us: [+4 T:0x4001ebe0] ti.sdo.fc.rman - RMAN_register> Registering protocol ti.sdo.fc.ires.examples.resources.memtcm revision (1.0.0)

@2,845,423us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x347260]

@3,178,154us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x347260]

@3,178,364us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.hdvicp - IRESMAN_HDVICP_getProtocolName> Enter

@3,178,517us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getName> Enter

@3,178,652us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getName> Exit (Name=ti.sdo.fc.ires.hdvicp)

@3,178,790us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.hdvicp - IRESMAN_HDVICP_getProtocolName> Exit (name=ti.sdo.fc.ires.hdvicp)

@3,178,925us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getName> Enter

@3,179,049us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getName> Exit (Name=ti.sdo.fc.ires.hdvicp)

@3,179,190us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.vicp2 - IRESMAN_VICP2_getProtocolName> Enter

@3,179,322us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.vicp2 - IRESMAN_VICP2_getProtocolName> Exit (name=ti.sdo.fc.ires.vicp2)

@3,179,515us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.addrspace - _IRESMAN_ADDRSPACE_getProtocolName> Enter

@3,179,657us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.addrspace - _IRES_ADDRSPACE_getName> Enter

@3,179,791us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.addrspace - _IRES_ADDRSPACE_getName> Exit (Name=ti.sdo.fc.ires.addrspace)

@3,179,933us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.addrspace - _IRESMAN_ADDRSPACE_getProtocolName> Exit (Name=ti.sdo.fc.ires.addrspace)

@3,180,080us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.memtcm - IRESMAN_MEMTCM_init> Enter (initArgs=0x249624)

@3,180,226us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - LockMP_create> key: 0x4d43544f count: 2261280 attrs: 0x228120

@3,180,483us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Leaving LockMP_create> lock[0x3473c0]

@3,180,664us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x3473c0]

@3,180,883us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x3473c0]

@3,181,053us: [+0 T:0x4001ebe0] ti.sdo.fc.utils.shm - _Shared Memory_initImpl> Shared memory, key = 0x48009 already exists

@3,181,300us: [+0 T:0x4001ebe0] ti.sdo.fc.utils.shm - SHM_getObj> shmBase = 0x402bf000

@3,181,483us: [+0 T:0x4001ebe0] ti.sdo.fc.utils.shm - _Shared Memory_initImpl> Shared Memory lock reference count = 1

@3,181,641us: [+5 T:0x4001ebe0] ti.sdo.fc.utils.shm - _Shared Memory_initImpl> Warning: Shared Memory lock reference count indicates no other processes are

currently using Shared Memory shared memory.

Re-initializing Shared Memory shared memory

@3,181,816us: [+0 T:0x4001ebe0] ti.sdo.fc.utils.shm - Initializing Shared Memory region

@3,182,014us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x3473c0]

@3,182,247us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x3473c0]

@3,182,571us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x3473c0]

@3,182,794us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x3473c0]

@3,182,977us: [+0 T:0x4001ebe0] ti.sdo.fc.utils.shm - _Shared Memory_initImpl> Shared memory, key = 0x5000a already exists

@3,183,206us: [+0 T:0x4001ebe0] ti.sdo.fc.utils.shm - SHM_getObj> shmBase = 0x402c7000

@3,183,385us: [+0 T:0x4001ebe0] ti.sdo.fc.utils.shm - _Shared Memory_initImpl> Shared Memory lock reference count = 1

@3,183,715us: [+5 T:0x4001ebe0] ti.sdo.fc.utils.shm - _Shared Memory_initImpl> Warning: Shared Memory lock reference count indicates no other processes are

currently using Shared Memory shared memory.

Re-initializing Shared Memory shared memory

@3,183,926us: [+0 T:0x4001ebe0] ti.sdo.fc.utils.shm - Initializing Shared Memory region

@3,184,137us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x3473c0]

@3,184,339us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x3473c0]

@3,184,521us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.memtcm - IRESMAN_MEMTCM_init> Exit (status=IRES_OK)

@3,184,680us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x347260]

@3,516,505us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x347260]

@3,516,707us: [+0 T:0x4001ebe0] ti.sdo.fc.rman - RMAN_register> Exit (status=IRES_OK)

@3,516,854us: [+0 T:0x4001ebe0] ti.sdo.fc.rman - RMAN_register> Enter (resmanFxns=0x23be80, initArgs=0x2495fc)

@3,517,040us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.edma3Chan - IRESMAN_EDMA3CHAN_getProtocolName> Enter

@3,517,196us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.edma3Chan - IRES_EDMA3CHAN_getName> Enter

@3,517,418us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.edma3Chan - IRES_EDMA3CHAN_getName> Exit (Name=ti.sdo.fc.ires.edma3chan)

@3,517,576us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.edma3Chan - IRESMAN_EDMA3CHAN_getProtocolName> Exit (name=ti.sdo.fc.ires.edma3chan)

@3,517,762us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.edma3Chan - IRES_EDMA3CHAN_getName> Enter

@3,517,903us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.edma3Chan - IRES_EDMA3CHAN_getName> Exit (Name=ti.sdo.fc.ires.edma3chan)

@3,518,050us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.edma3Chan - IRESMAN_EDMA3CHAN_getProtocolRevision> Enter

@3,518,190us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.edma3Chan - IRES_EDMA3CHAN_getRevision> Enter

@3,518,323us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.edma3Chan - IRES_EDMA3CHAN_getRevision> Exit (Version=(1.0.0))

@3,518,461us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.edma3Chan - IRESMAN_EDMA3CHAN_getProtocolRevision> Exit (version=(1.0.0))

@3,518,602us: [+4 T:0x4001ebe0] ti.sdo.fc.rman - RMAN_register> Registering protocol ti.sdo.fc.ires.edma3chan revision (1.0.0)

@3,518,749us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x347260]

@3,518,917us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x347260]

@3,519,104us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.hdvicp - IRESMAN_HDVICP_getProtocolName> Enter

@3,519,261us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getName> Enter

@3,519,397us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getName> Exit (Name=ti.sdo.fc.ires.hdvicp)

@3,519,533us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.hdvicp - IRESMAN_HDVICP_getProtocolName> Exit (name=ti.sdo.fc.ires.hdvicp)

@3,519,670us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getName> Enter

@3,519,795us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getName> Exit (Name=ti.sdo.fc.ires.hdvicp)

@3,519,935us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.vicp2 - IRESMAN_VICP2_getProtocolName> Enter

@3,520,065us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.vicp2 - IRESMAN_VICP2_getProtocolName> Exit (name=ti.sdo.fc.ires.vicp2)

@3,520,214us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.addrspace - _IRESMAN_ADDRSPACE_getProtocolName> Enter

@3,520,350us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.addrspace - _IRES_ADDRSPACE_getName> Enter

@3,520,510us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.addrspace - _IRES_ADDRSPACE_getName> Exit (Name=ti.sdo.fc.ires.addrspace)

@3,520,657us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.addrspace - _IRESMAN_ADDRSPACE_getProtocolName> Exit (Name=ti.sdo.fc.ires.addrspace)

@3,520,806us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.memtcm - IRESMAN_MEMTCM_getProtocolName> Enter

@3,520,942us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.memtcm - _IRES_MEMTCM_getName> Enter

@3,521,071us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.memtcm - _IRES_MEMTCM_getName> Exit (Name=ti.sdo.fc.ires.examples.resources.memtcm)

@3,521,208us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.memtcm - IRESMAN_MEMTCM_getProtocolName> Exit (Name=ti.sdo.fc.ires.examples.resources.memtcm)

@3,521,348us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.memtcm - _IRES_MEMTCM_getName> Enter

@3,521,477us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.memtcm - _IRES_MEMTCM_getName> Exit (Name=ti.sdo.fc.ires.examples.resources.memtcm)

@3,521,619us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.edma3Chan - IRESMAN_EDMA3CHAN_init> Enter (initArgs=0x2495fc)

@3,521,758us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.edma3Chan - IRESMAN_EDMA3CHAN_init> Exit (status=IRES_OK)

@3,854,819us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x347260]

@3,855,056us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x347260]

@3,855,222us: [+0 T:0x4001ebe0] ti.sdo.fc.rman - RMAN_register> Exit (status=IRES_OK)

@3,855,362us: [+0 T:0x4001ebe0] ti.sdo.fc.rman - RMAN_register> Enter (resmanFxns=0x23be68, initArgs=0x2495e8)

@3,855,513us: [+4 T:0x4001ebe0] ti.sdo.fc.rman - RMAN_register> Registering protocol ti.sdo.fc.ires.examples.resources.grouputils revision (1.0.0)

@3,855,669us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x347260]

@3,855,836us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x347260]

@3,855,993us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.hdvicp - IRESMAN_HDVICP_getProtocolName> Enter

@3,856,181us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getName> Enter

@3,856,315us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getName> Exit (Name=ti.sdo.fc.ires.hdvicp)

@3,856,450us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.hdvicp - IRESMAN_HDVICP_getProtocolName> Exit (name=ti.sdo.fc.ires.hdvicp)

@3,856,589us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getName> Enter

@3,856,715us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.hdvicp - IRES_HDVICP_getName> Exit (Name=ti.sdo.fc.ires.hdvicp)

@3,856,857us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.vicp2 - IRESMAN_VICP2_getProtocolName> Enter

@3,856,992us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.vicp2 - IRESMAN_VICP2_getProtocolName> Exit (name=ti.sdo.fc.ires.vicp2)

@3,857,137us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.addrspace - _IRESMAN_ADDRSPACE_getProtocolName> Enter

@3,857,276us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.addrspace - _IRES_ADDRSPACE_getName> Enter

@3,857,405us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.addrspace - _IRES_ADDRSPACE_getName> Exit (Name=ti.sdo.fc.ires.addrspace)

@3,857,584us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.addrspace - _IRESMAN_ADDRSPACE_getProtocolName> Exit (Name=ti.sdo.fc.ires.addrspace)

@3,857,736us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.memtcm - IRESMAN_MEMTCM_getProtocolName> Enter

@3,857,871us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.memtcm - _IRES_MEMTCM_getName> Enter

@3,857,996us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.memtcm - _IRES_MEMTCM_getName> Exit (Name=ti.sdo.fc.ires.examples.resources.memtcm)

@3,858,136us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.memtcm - IRESMAN_MEMTCM_getProtocolName> Exit (Name=ti.sdo.fc.ires.examples.resources.memtcm)

@3,858,278us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.memtcm - _IRES_MEMTCM_getName> Enter

@3,858,402us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.memtcm - _IRES_MEMTCM_getName> Exit (Name=ti.sdo.fc.ires.examples.resources.memtcm)

@3,858,544us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.edma3Chan - IRESMAN_EDMA3CHAN_getProtocolName> Enter

@3,858,681us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.edma3Chan - IRES_EDMA3CHAN_getName> Enter

@3,858,844us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.edma3Chan - IRES_EDMA3CHAN_getName> Exit (Name=ti.sdo.fc.ires.edma3chan)

@3,858,994us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.edma3Chan - IRESMAN_EDMA3CHAN_getProtocolName> Exit (name=ti.sdo.fc.ires.edma3chan)

@3,859,144us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.edma3Chan - IRES_EDMA3CHAN_getName> Enter

@3,859,275us: [+0 T:0x4001ebe0] ti.sdo.fc.ires.edma3Chan - IRES_EDMA3CHAN_getName> Exit (Name=ti.sdo.fc.ires.edma3chan)

@3,859,418us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.Sem - Sem_create> count: 1

@3,859,626us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.Sem - Leaving Sem_create> sem[0x347418]

@3,859,800us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x347260]

@3,859,981us: [+0 T:0x4001ebe0] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x347260]

@3,860,136us: [+0 T:0x4001ebe0] ti.sdo.fc.rman - RMAN_register> Exit (status=IRES_OK)

@3,860,310us: [+0 T:0x4001ebe0] ti.sdo.fc.rman - RMAN_init> Exit (status=0)

@3,860,487us: [+0 T:0x4001ebe0] ti.sdo.ce.alg - ALG_init> Enter

@4,193,272us: [+0 T:0x4001ebe0] ti.sdo.ce.alg - ALG_init> Exit

@4,193,734us: [+6 T:0x4001ebe0] CE - Engine_init> CE debugging on (CE_DEBUG=2; allowed CE_DEBUG levels: 1=min, 2=good, 3=max)

@4,194,063us: [+0 T:0x4001ebe0] CS - Server_init()

@4,194,236us: [+0 T:0x4001ebe0] CS - Server_init> Global_useLinkArbiter = 0

[capture] VSERVER API: Creating TSKs...DONE

[capture] VSERVER API: Sending START.

[capture] VSERVER MAIN: Recevied CMD = 0x0400, state = 0x0000

[capture]VideoServer Task tid=724, pid=718

[capture]ALG_frcCreate() OK!

[capture]*********intFps 14

[capture]*********decFps 1

[capture]*********virtFps 15

[capture]streamId 0, fps 0

[capture]streamId 1, fps 0

[capture]streamId 2, fps 0

[capture]VIDEO_apiInit() OK

[capture] VSERVER MAIN: Create displayTsk ...

[capture] DISPLAY: Recevied CMD = 0x0300

[capture]Display Task tid=721, pid=718

[capture] DISPLAY: Create...

[capture] DISPLAY: Create...DONE

[capture] VSERVER MAIN: Create aewbTsk ...

[capture] AEWB: Recevied CMD = 0x0300

[capture]Aewb Task tid=722, pid=718

[capture] AEWB: Create...

[capture] AEWB: Create...DONE

[capture] VSERVER MAIN: Create crtlTask ...

[capture] CRTL: Recevied CMD = 0x0300

[capture]Ctrl Task tid=723, pid=718

[capture] CRTL: Create...

[capture] CRTL: Create...DONE

[capture] VSERVER MAIN: Create decodeTask ...

[capture]Decode Task tid=720, pid=718

[capture] Decode: Create...

thread start here

[decode] =========libdecode=version @VVERSION V1.00 build time: Apr 28 2012:14:39:44========

[capture] DECODE: Create...DONE

[decode] (../src/decode.c|create_decoder|97): Engine_open *********************************************

@4,208,909us: [+0 T:0x41e0c490] CE - Engine_open> Enter('encode', 0x0, 0x41e0bdf4)

@4,209,187us: [+0 T:0x41e0c490] CE - Engine_open> return(3439112)

[decode] (../src/decode.c|create_decoder|99): Engine_open *********************************************

@4,209,512us: [+0 T:0x41e0c490] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_create> Enter (engine=0x347a08, name='h264dec', params=0x23e58c)

@4,209,811us: [+0 T:0x41e0c490] CV - VISA_create(0x347a08, 'h264dec', 0x23e58c, 0x2484, 'ti.sdo.ce.video2.IVIDDEC2')

@4,210,007us: [+0 T:0x41e0c490] CV - VISA_create2(0x347a08, 'h264dec', 0x23e58c, 0x38, 0x2484, 'ti.sdo.ce.video2.IVIDDEC2')

@4,210,366us: [+0 T:0x41e0c490] ti.sdo.ce.alg.Algorithm - Algorithm_create> Enter(fxns=0x23c798, idma3Fxns=0x0, iresFxns=0x23c734, params=0x23e58c, attrs=0x41e0bc3c)

@4,210,602us: [+0 T:0x41e0c490] ti.sdo.ce.alg - ALG_create> Enter (scratchId=1, fxns=0x23c798, parentAlg=0x0, params=0x23e58c)

@4,210,834us: [+2 T:0x41e0c490] ti.sdo.ce.alg - ALG_create> algNumAlloc 14 memory recs

@4,211,091us: [+7 T:0x41e0c490] ti.sdo.ce.alg.Algorithm - Algorithm_create> Algorithm creation FAILED; make sure that 1) alg params are correct/appropriate, 2) there is enough internal and external algorithm memory available -- check DSKT2 settings for heap assignments and scratch allocation

@4,211,306us: [+0 T:0x41e0c490] ti.sdo.ce.alg.Algorithm - Algorithm_delete> Enter(alg=0x347ac0)

@4,211,548us: [+0 T:0x41e0c490] ti.sdo.ce.alg.Algorithm - Algorithm_delete> Exit

@4,211,716us: [+0 T:0x41e0c490] ti.sdo.ce.alg.Algorithm - Algorithm_create> return (0x0)

@4,211,866us: [+2 T:0x41e0c490] CV - VISA_create2> FAILED to create local codec.

@4,211,997us: [+0 T:0x41e0c490] CV - VISA_delete(0x347a88)

@4,212,185us: [+5 T:0x41e0c490] CV - VISA_delete> deleting codec (localQueue=0xffff, remoteQueue=0xffff)

@4,212,362us: [+0 T:0x41e0c490] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_create> return (0x0)

[decode] ERROR (../src/h264dec.c|create_h264decoder|132): open decode algorithm Failed: h264dec (0x0)

@4,212,679us: [+0 T:0x41e0c490] CE - Engine_getNumAlgs('encode', 0x41e0bdbc)

[decode] available Alg=7====>>>>

@4,212,916us: [+0 T:0x41e0c490] CE - Engine_getAlgInfo('encode', 0x41e0bdac, 0x0)

[decode] alg[0]: name = h264enc typeTab = ti.sdo.ce.video1.IVIDENC1 Local = 1

@4,545,530us: [+0 T:0x41e0c490] CE - Engine_getAlgInfo('encode', 0x41e0bdac, 0x1)

[decode] alg[1]: name = mpeg4enc typeTab = ti.sdo.ce.video1.IVIDENC1 Local = 1

@4,545,810us: [+0 T:0x41e0c490] CE - Engine_getAlgInfo('encode', 0x41e0bdac, 0x2)

[decode] alg[2]: name = dei typeTab = ti.sdo.ce.video1.IVIDENC1 Local = 1

@4,546,063us: [+0 T:0x41e0c490] CE - Engine_getAlgInfo('encode', 0x41e0bdac, 0x3)

[decode] alg[3]: name = ktnf typeTab = ti.sdo.ce.video1.IVIDENC1 Local = 1

@4,546,307us: [+0 T:0x41e0c490] CE - Engine_getAlgInfo('encode', 0x41e0bdac, 0x4)

[decode] alg[4]: name = jpegenc typeTab = ti.sdo.ce.image1.IIMGENC1 Local = 1

@4,546,547us: [+0 T:0x41e0c490] CE - Engine_getAlgInfo('encode', 0x41e0bdac, 0x5)

[decode] alg[5]: name = mpeg4dec typeTab = ti.sdo.ce.video2.IVIDDEC2 Local = 1

@4,546,838us: [+0 T:0x41e0c490] CE - Engine_getAlgInfo('encode', 0x41e0bdac, 0x6)

[decode] alg[6]: name = h264dec typeTab = ti.sdo.ce.video2.IVIDDEC2 Local = 1

[decode] ERROR (../src/decode.c|create_decoder|118): open decode algorithm Failed: h264dec (0x0)

[decode] ERROR (../src/main.c|decode_thread|74): create encoder err,streamid=0

0 zanget over 13 years ago in reply to zanget

Expert 2150 points

the cfg of the server is attached here :

var osalGlobal = xdc.useModule('ti.sdo.ce.osal.Global');
osalGlobal.runtimeEnv = osalGlobal.LINUX;
xdc.loadPackage('ti.sdo.fc.rman').profile = "debug_trace";
xdc.loadPackage('ti.sdo.fc.edma3').profile = "debug_trace";
environment['xdc.cfg.check.fatal'] = 'false';
var RMAN = xdc.useModule('ti.sdo.fc.rman.RMAN');
RMAN.useDSKT2 = false;
RMAN.persistentAllocFxn = "__ALG_allocMemory";
RMAN.persistentFreeFxn = "__ALG_freeMemory";
RMAN.semCreateFxn = "Sem_create";
RMAN.semDeleteFxn = "Sem_delete";
RMAN.semPendFxn = "Sem_pend";
RMAN.semPostFxn = "Sem_post";
RMAN.tableSize = 10;
RMAN.trace = true;
RMAN.debug = true;
/*
var EDMA3 = xdc.useModule('ti.sdo.fc.edma3.Settings');
EDMA3.globalInit = true;
EDMA3.maxQdmaChannels = [10,10,10,10];
EDMA3.trace = false;
EDMA3.debug = false;
//EDMA3.maxRequests = 256;
EDMA3.maxRequests = 128;
*/
/*var TraceUtil = xdc.useModule('ti.sdo.ce.utils.trace.TraceUtil');*/
var vicp = xdc.useModule('ti.sdo.linuxutils.vicp.VICP');
vicp.debug = false;
var HDVICP = xdc.useModule('ti.sdo.fc.ires.hdvicp.HDVICP');
HDVICP.trace = false;
HDVICP.debug = false;
//Change this to VICP if the chip is not DM355
var VICP = xdc.useModule('ti.sdo.fc.ires.vicp.VICP2');
VICP.debug = false;
var VICPSYNC = xdc.useModule('ti.sdo.fc.vicpsync.VICPSYNC');
VICPSYNC.debug = false;
var GT = xdc.useModule("ti.sdo.utils.trace.GT")

var GTINFRA = xdc.useModule("ti.sdo.fc.utils.gtinfra.GTINFRA");
GTINFRA.runtimeEnv = GTINFRA.NONE;
GT.MALLOCFXN = "myMalloc";
GT.PRINTFXN = "printfCaller";
var HDVICPSYNC = xdc.useModule('ti.sdo.fc.hdvicpsync.HDVICPSYNC');
HDVICPSYNC.debug = false;
HDVICPSYNC.trace = false;
xdc.useModule('ti.sdo.fc.global.Settings').profile = "debug_trace";
var vicp = xdc.useModule('ti.sdo.linuxutils.vicp.VICP');
//Change this to VICP if the chip is not DM355
var VICP = xdc.useModule('ti.sdo.fc.ires.vicp.VICP2');
var VICPSYNC = xdc.useModule('ti.sdo.fc.vicpsync.VICPSYNC');
var VICP = xdc.useModule('ti.sdo.fc.memutils.MEMUTILS');
ADDRSPACE = xdc.useModule('ti.sdo.fc.ires.addrspace.ADDRSPACE');
var MEMTCM = xdc.useModule('ti.sdo.fc.ires.memtcm.MEMTCM');
MEMTCM.cmemBlockId = 1; //Since we use _1 in our insmod command.
var EDMA3CHAN = xdc.useModule('ti.sdo.fc.ires.edma3chan.EDMA3CHAN');
EDMA3CHAN.trace = false;
EDMA3CHAN.debug = false;
var LSP = xdc.useModule('ti.sdo.linuxutils.edma.EDMA');
LSP.debug = false;
var CMEM = xdc.useModule('ti.sdo.linuxutils.cmem.CMEM');
var ALG_MEM = xdc.useModule('ti.sdo.ce.alg.Settings')

ALG_MEM.useHeap = true;
ALG_MEM.useCache = true;

xdc.loadPackage("ti.sdo.ce.video2");
xdc.loadPackage("ti.sdo.fc.hdvicpsync");
/*
* ======== Engine Configuration ========
*/
var MPEG4DEC = xdc.useModule('ti.sdo.codecs.mpeg4dec.ce.MPEG4DEC');
var H264DEC = xdc.useModule('ti.sdo.codecs.h264dec.ce.H264DEC');
var IPRUN = xdc.useModule('ti.sdo.codecs.iprun.ce.IPRUN');

0 Gunjan over 13 years ago in reply to zanget

TI__Expert 7415 points

I just looked at your logs and realized that your trace is already enabled.

Looking at your error and the corresponding code (ti/sdo/ce/alg/alg_create.c) there are 2 things that might fail to get that error:-

- A "malloc" call fails. This is a small allocation to create some data structures to hold the information regarding memory allocations. It is unlikely that this call failed, since we would have probably seen a corresponding error message

- The other thing that might fail, that doesn't return a trace statement, is that the algorithm's algAlloc call returns a value <= 0. This is something maybe you can check in your algorithm's source code.

If you have gdb setup on your device, maybe you can step through the code and try and see why this function returns a <= 0 value (It should ideally return a value that corresponds to the number of memory records that the algorithm needs.

0 zanget over 13 years ago in reply to Gunjan

Expert 2150 points

Dear GunJan.

im sorry to replay very later today because we were in holiday.

i just had a chance to go thought the code of ALG_create(see bellow), wander why does this function return an error code but we cannot see anything useful with CE_DEBUG=3 and all of the debug is opened in server's cfg file.

algAlloc is supposed to fail or else the memory error info will be seen as you tell us above but more important, we don't have changed any code of that function in FC.

/*
 *  ======== ALG_create ========
 */
IALG_Handle ALG_create(IALG_Fxns *fxns, IALG_Handle p, IALG_Params *params)
{
    IALG_MemRec *memTab;
    Int n;
    IALG_Handle alg;
    IALG_Fxns *fxnsPtr;

    if (fxns != NULL) {
        n = fxns->algNumAlloc != NULL ? fxns->algNumAlloc() : IALG_DEFMEMRECS;

        if ((memTab = (IALG_MemRec *)malloc(n * sizeof (IALG_MemRec)))) {

            n = fxns->algAlloc(params, &fxnsPtr, memTab);
            if (n <= 0) {
                return (NULL);
            }

            if (_ALG1_allocMemory(memTab, n)) {
                alg = (IALG_Handle)memTab[0].base;
                alg->fxns = fxns;
                if (fxns->algInit(alg, memTab, p, params) == IALG_EOK) {
                    free(memTab);
                    return (alg);
                }
                fxns->algFree(alg, memTab);
                _ALG1_freeMemory(memTab, n);
            }

            free(memTab);
        }
    }

    return (NULL);
}

your information is very useful i will try to feed you back when it's done with gdb.

regards, Mike.

Thanks in advance.

0 zanget over 13 years ago in reply to zanget

Expert 2150 points

build info:

Auto register ti.sdo.fc.ires.grouputils.GROUPUTILS

will link with ti.sdo.ce.image1:lib/release/imgdec1.av5T;lib/release/imgenc1.av5T

will link with ti.sdo.ce.video1:lib/release/viddec1.av5T;lib/release/videnc1.av5T

will link with ti.sdo.ce.video2:lib/release/viddec2.av5T

will link with ti.sdo.ce:lib/release/ce.av5T

will link with ti.sdo.ce.ipc.linux:lib/release/ipc_linux.av5T

will link with ti.sdo.ce.alg:lib/debug/Algorithm_noOS.av5T;lib/debug/alg.av5T

GDB info:

Breakpoint 3, 0x0004c518 in ALG_create ()

Current language: auto; currently asm

(gdb)

Single stepping until exit from function ALG_create,

which has no line number information.

[Switching to LWP 534]

Breakpoint 2, 0x0014114c in VIDDEC2_create ()

(gdb)

Single stepping until exit from function VIDDEC2_create,

which has no line number information.

(gdb) print i

No symbol "i" in current context.

(gdb) print status

No symbol "status" in current context.

any ideas why i cannot see the info of var ?

regards, Mike

0 Gunjan over 13 years ago in reply to zanget

TI__Expert 7415 points

Looks like you are linking in the debug libs, so you should be seeing some debug info. Let me ask my team..

Are you able to look at the codec sources, and see if they ever return a <=0 value from the codec's algAlloc function implementation ?

0 zanget over 13 years ago in reply to Gunjan

Expert 2150 points

Hi, Gunjan.

This is two things i had tried with your suggestion:

1.

i findout the fxns tab(see below) referring to the algAlloc function in 
video_decode_io2_dm365_x470mv.c

which i guess it must be the one we are using.

but i found everywhere i can in DVSDK without any result of H264VDEC_TI_IH264VDEC.
im now totally lost of which one of algAlloc referring to.

static Engine_AlgDesc engineAlgs0[] = {
    {
        "h264dec",       /* name */
        {2701911697U},   /* uuid */
 &H264VDEC_TI_IH264VDEC, /* fxns */

        NULL,        /* idma3Fxns */
        typeTab_H264VDEC_TI_IH264VDEC,              /* typeTab */
        TRUE,          /* isLocal */
        1,        /* groupId */
        0,      /* protocol */
        &H264VDEC_TI_IRES,         /* iresFxns */
        &ti_sdo_codecs_h264dec_ce_H264DEC_codecClassConfig  /* stub/skel config params */
    },
    {
        "mpeg4dec",       /* name */
        {3351107257U},   /* uuid */
        &MP4VDEC_TI_IMP4VDEC,             /* fxns */
        NULL,        /* idma3Fxns */
        typeTab_MP4VDEC_TI_IMP4VDEC,              /* typeTab */
        TRUE,          /* isLocal */
        1,        /* groupId */
        0,      /* protocol */
        &MP4VDEC_TI_IRES,         /* iresFxns */
        &ti_sdo_codecs_mpeg4dec_ce_MPEG4DEC_codecClassConfig  /* stub/skel config params */
    },
    {NULL},
};
static Engine_AlgDesc engineAlgs1[] = {
    {NULL},
};

/* table of all engines available in this application */
static Engine_Desc engineTab[] = {
    {"decode",      /* engine name */
 engineAlgs0, /* alg table */

      NULL,         /* (optional) server name */
      NULL,        /* (optional) Link config ID */
      2 /* number of algs in alg table */
    },
    {"local",      /* engine name */
      engineAlgs1,    /* alg table */
      NULL,         /* (optional) server name */
      NULL,        /* (optional) Link config ID */
      0 /* number of algs in alg table */
    },
    {NULL, NULL, NULL, 0}       /* NULL-terminate the engine table */
};

Engine_Config Engine_config = {
 engineTab, /* table of all engines */     "local"   /* local RMS engine name */
};

2.

i tried to make the lib of

Algorithm_noOS.av5t

after changed the code of ALG_create to see if any error code i can get from

fxns->algAlloc, 
but i lost here because in dvsdk there is no [Mm]akefile to make the lib

except codec_engine_2_24/examples/ti/sdo/ce/examples

or codec_engine_2_24/packages/ti/sdo/ce/ipc/dsplink/makefile.

please tell me if im doing anything wrong or, there is something i missed.

Regards, Mike.

0 zanget over 13 years ago in reply to zanget

Expert 2150 points

sorry.

it haven't been told in details about how our application is made and run.

compile :

ti CE libs ----------- |

server's cfg (a)+ | make => libdec.a (static lib)

our *.c ------------ |

ti CE libs ----------- |

server's cfg (b)+ | make => libenc.a (static lib)

our *.c ------------ |

libdec.a -- |

| make => VD

libdec.a -- |

run :

the process called vd will going to start a serial of threads contain libdec thread, and libenc thread, etc after start.

libdec thread, and libenc thread will open Engine with the same name and ... create the Algorithm.

but it's always failed after Engine opened.

PS: we tested if server's cfg a =b or a != b, it's the same result.

tested:

CE libs -------------- |

server's cfg (a)+ | make => libdec.a

our *.c ------------ | make => libdec (app)

test.c ------------ |

CE libs -------------- |

server's cfg (b)+ | make => libenc.a

our *.c ------------ | make => libenc (app)

test.c ------------ |

the libdec/libenc app works fine with engine open , create etc. respectively.

regards, Mike

0 Gunjan over 13 years ago in reply to zanget

TI__Expert 7415 points

Mike,

Some notes from the Codec Engine experts:-

- A single process that creates multiple threads that all use the "same engine handle" is a valid scenario. DVSDK decode demo illustrates an example of this scenario. If you haven't already, that would be a good place to look.

- There should be only 1 Engine and 1 corresponding .cfg file. Can you build your application as a single executable ? That would keep things simple, and ensure that you are using a single engine. What is the reason you are building the encoder and decoder threads in two separate libraries. Since this is a DM365 device, no separate server executable is required (no DSP), everythign can build/run as a single executable.

0 Vincent W. over 13 years ago in reply to zanget

TI__Genius 12865 points

Hi Mike,

I just want to stress that there should not be any notion of a 'server' in your scenario, if you are using a device that does not have a DSP. You can think of a server as an executable image that is loaded onto a DSP to serve the ARM. If all your codecs are run locally on the ARM, then all you need to do is to create an app.cfg file that defines the Engine name and the codecs it contains. Assuming your libdec.a.and libenc.a files contain XDM-compliant codecs that were packaged for example using the GenCodecPkg wizard (http://processors.wiki.ti.com/index.php/Codec_Engine_GenCodecPkg_Wizard_FAQ), then you will be all set. When you look at the DVSDK demos as Gunjan suggests, you will see that each Engine defined in the configuration .cfg file contain multiple codecs, which are accessed through multiple threads in the same application process. Here's an example of an Engine definition:

/*
* ======== Engine Configuration ========
*/
var viddec   = xdc.module('ti.sdo.ce.video2.IVIDDEC2');
var auddec   = xdc.module('ti.sdo.ce.audio.IAUDDEC');

var MPEG4DEC = xdc.useModule('ti.sdo.codecs.mpeg4dec.ce.MPEG4DEC');
var H264DEC = xdc.useModule('ti.sdo.codecs.h264dec.ce.H264DEC');
var G711DEC = xdc.useModule('ti.sdo.ce.examples.codecs.g711.G711DEC');

var Engine = xdc.useModule('ti.sdo.ce.Engine');
var myEngine = Engine.create("decode", [
    {name: "mpeg4dec", mod: MPEG4DEC, local: true, groupId: 1},
    {name: "h264dec", mod: H264DEC, local: true, groupId: 1},
    {name: "g711dec", mod: G711DEC, local: true},
]);

In the application code, each thread should make its own Engine_open call to get a handle to the Engine. With this handle, the thread can then access the full collection of codecs.

Best regards,

Vincent

0 Chris Meng over 13 years ago in reply to Vincent W.

TI__Mastermind 37706 points

Due to e2e post issue, I posted below info on behalf of Mike.

i have create two packages to make two static library (say, libdecode, libencode) based on video_copy according to what '' suggest.
the build log can be seen here: 7750.libdecode.rar and here:0537.libencode.rar.

VD : the executable is build with server's cfg.
the build log can be seen here1145.vd_compile.rar.

whenever i start the thread of libdecode, it always failed:
(start libdecode before libencode or otherwise, after)

[decode] ERROR (src/h264dec.c|create_h264decoder|132): open decode algorithm Failed: h264dec (0x0)
[decode] available Alg=3====>>>>
[decode] alg[0]: name = h264dec typeTab = ti.sdo.ce.video2.IVIDDEC2 Local = 1
[decode] alg[1]: name = h264enc typeTab = ti.sdo.ce.video1.IVIDENC1 Local = 1
[decode] alg[2]: name = jpegenc typeTab = ti.sdo.ce.image1.IIMGENC1 Local = 1

CE_DEBUG=3 log can be seen here8551.ce_debug.rar.

thread :

1.libdecode will just VIDDEC2_create h264dec (one time),
2.libencode will just IMGENC1_create jpegenc(one time) and VIDENC1_create h264enc(four times).

one more things im just doubt if it is connected with the error i faced:

{name: "h264dec", mod: H264DEC, local: true, groupId: 5}, /* 26 EDMA channels */
{name: "h264enc", mod: H264ENC, local: true, groupId: 1}, /* 37 EDMA channels */
{name: "jpegenc", mod: JPEGENC, local: true, groupId: 1}, /* 17 EDMA channels */

they cannot use the same groupId cus it will be outof channels.

i have to pack a different groupId of h264dec, if so, h264dec and jpegenc will be created with out error but h264dec always Failed.
Reason:
1. if i choose groupId=1 for h264enc, h264dec, and jpegenc: otherwise:
h264enc VIDENC1_create OK
h264dec VIDDEC2_create Failed
jpegenc IMGENC1_create Failed

if groupId of "h264enc", "jpegenc" are different, one of them Failed.

2. h264dec always Failed whatever the groupID of h264dec is.

PS: does it restrict that VIDDEC2_create and VIDENC1_create used in the same app ???

0 Gunjan over 13 years ago in reply to Chris Meng

TI__Expert 7415 points

It is not clear which combination of group ids causes creation to always fail and which group ids causes it to pass. But here's some information that might be helpful:-

The concept of group ids is to allow codecs to share resources. Using the same group id, implies that the codecs will share framework resources, and hence cannot be run together (since they will using same set of resources). The total number of resources required from the system will be smaller overall.

If different group ids are used, this implies that the codecs will NOT share resources, they all get allocated different resources by the framework and hence these codecs can run simultaneously.

Is your observation consistent with the above explanation ?

The only odd thing is that I don't see any framework components trace from the resource assignment part. Since you have trace enabled, I would expect to see from trace from ti.sdo.fc.rman and ti.sdo.fc.edma3 packages that show that the system is running out of resources.

This is what led me to believe, that maybe you haven't reached that part. That maybe ALG_create fails, even before resources are assigned (i.e, during calls to algAlloc, algNumAlloc and algInit).

0 Chris Meng over 13 years ago in reply to Gunjan

TI__Mastermind 37706 points

On behalf of Mike.

i had compiled the 2 lib(libencode / libdecode) with the suggestion form http://e2e.ti.com/support/embedded/bios/f/355/t/94292.aspx ( or else i get same problem as it told )

i change the the package's xs file of 2 lib(libencode / libdecode)
to make it link with dependence according to http://e2e.ti.com/support/embedded/bios/f/355/t/94292.aspx ( or else i get same problem as Victor told )

and vd app is linked with my two package now:

Auto register ti.sdo.fc.ires.vicp.VICP2
Auto register ti.sdo.fc.ires.grouputils.GROUPUTILS
Auto register ti.sdo.fc.ires.edma3chan.EDMA3CHAN
Auto register ti.sdo.fc.ires.hdvicp.HDVICP
Auto register ti.sdo.fc.ires.addrspace.ADDRSPACE
Auto register ti.sdo.fc.ires.memtcm.MEMTCM

will link with libencode:lib/libencode/debug/libencode.av5T
will link with libdecode:lib/libdecode/debug/libdecode.av5T

    will link with ti.sdo.ce.video:lib/debug/video.av5T
    will link with ti.sdo.ce.image1:lib/debug/imgdec1.av5T;lib/debug/imgenc1.av5T
    will link with ti.sdo.ce.video1:lib/debug/viddec1.av5T;lib/debug/videnc1.av5T
    will link with ti.sdo.ce.video2:lib/debug/viddec2.av5T
    will link with ti.sdo.ce:lib/release/ce.av5T
    will link with ti.sdo.ce.ipc.linux:lib/release/ipc_linux.av5T
    will link with ti.sdo.ce.alg:lib/debug/Algorithm_noOS.av5T;lib/debug/alg.av5T
    will link with ti.sdo.ce.osal.linux:lib/release/osal_linux_470.av5T
    will link with ti.sdo.ce.utils.xdm:lib/release/XdmUtils.av5T
    will link with ti.sdo.ce.node:lib/release/node.av5T
    will link with ti.sdo.fc.acpy3:lib/debug_trace/acpy3.a470MV
    will link with ti.sdo.fc.dman3:lib/de bug_trace/dman3Cfg.a470MV
    will link with ti.sdo.codecs.jpegenc:lib/libjpgenc.a
    will link with ti.sdo.codecs.iprun:lib/libimgproc365.a;lib/libiprun.a;lib/libimx.a;lib/libalgfw.a
    will link with ti.sdo.codecs.mpeg4dec:lib/libmp4dec.a
    will link with ti.sdo.fc.vicpsync:lib/debug_trace/vicpsync.av5T
    will link with ti.sdo.fc.ires.edma3chan:lib/debug_trace/edma3Chan.av5T
    will link with ti.sdo.fc.edma3:lib/debug_trace/edma3.av5T
    will link with ti.sdo.fc.ires.vicp:lib/debug_trace/vicp.av5T
    will link with ti.sdo.fc.ires.grouputils:lib/debug_trace/grouputils.av5T
    will link with ti.sdo.fc.rman:lib/debug_trace/rman.av5T
    will link with ti.sdo.fc.ires.nullresource:lib/debug_trace/nullres.av5T
    will link with ti.sdo.fc.ires.memtcm:lib/debug_trace/memtcm.a v5T
    will link with ti.sdo.codecs.h264dec:lib/h264vdec_ti_arm926.a;lib/h264v_ti_dma_dm365.a
    will link with ti.sdo.codecs.h264enc:lib/h264venc_ti_arm926.a;lib/h264v_ti_dma_dm365.a
    will link with ti.sdo.fc.hdvicpsync:lib/debug_trace/hdvicpsync.av5T
    will link with ti.sdo.fc.ires.hdvicp:lib/debug_trace/hdvicp.av5T
will link with lib/debug_trace/rmm.a470MV;lib/debug_trace/smgr.a470MV;lib/debug_trace/rmmp.a470MV;lib/debug_trace/smgrmp.a470MV;lib/debug_trace/shm.a470MV
    will link with ti.sdo.fc.ires.addrspace:lib/debug_trace/addrspace.av5T
    will link with ti.sdo.fc.memutils:lib/debug_trace/memutils.av5T
    will link with ti.sdo.fc.utils.gtinfra:lib/debug_trace/gt_noOs.av5T
    will link with ti.sdo.linuxutils.vicp:lib/vicp.a470MV
    will link with ti.sdo.linuxutils.edma:lib/edma.a470MV
&n bsp; will link with ti.sdo.linuxutils.cmem:lib/cmem.a470MV
    will link with ti.sdo.utils.trace:lib/release/gt.av5T

and package/cfg/libVideoDaemon_xv5T.xdl is read as: (no idea why it's always a prefix 'lib-' even i use addExecutable in package.bld )

/* this file was generated by linkcmd.xdt from the gnu.targets.arm
* package
*/
INPUT(
    /home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/vd/libencode/lib/libencode/debug/libencode.av5T
    /home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/vd/libdecode/lib/libdecode/debug/libdecode.av5T
    /home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/codec_engine_2_24/packages/ti/sdo/ce/video/lib/debug/video.av5T
    /home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/codec_engine_2_24/packages/ti/sdo/ce/image1/lib/debug/imgdec1.av5T
    /home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/codec_engine_2_24/packages/ti/sdo/ce/image1/lib/debug/imgenc1.av5T
    /home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/codec_engine_2_24/packages/ti/sdo/ce/video1/lib/debug/viddec1.av5T
    /home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/codec_engine_2_24/p ackages/ti/sdo/ce/video1/lib/debug/videnc1.av5T
    /home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/codec_engine_2_24/packages/ti/sdo/ce/video2/lib/debug/viddec2.av5T
    /home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/codec_engine_2_24/packages/ti/sdo/ce/lib/release/ce.av5T
    /home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/codec_engine_2_24/packages/ti/sdo/ce/ipc/linux/lib/release/ipc_linux.av5T
    /home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/codec_engine_2_24/packages/ti/sdo/ce/alg/lib/debug/Algorithm_noOS.av5T
    /home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/codec_engine_2_24/packages/ti/sdo/ce/alg/lib/debug/alg.av5T
    /home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/codec_engine_2_24/packages/ti/sdo/ce/osal/linux/lib/release/osal_linux_470.av5T
    /home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/codec_engine_2_24/pac kages/ti/sdo/ce/utils/xdm/lib/release/XdmUtils.av5T
    /home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/codec_engine_2_24/packages/ti/sdo/ce/node/lib/release/node.av5T
    /home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/acpy3/lib/debug_trace/acpy3.a470MV
    /home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/dman3/lib/debug_trace/dman3Cfg.a470MV
    /home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/dm365_codecs_01_00_06/packages/ti/sdo/codecs/jpegenc/lib/libjpgenc.a
    /home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/dm365_codecs_01_00_06/packages/ti/sdo/codecs/iprun/lib/libimgproc365.a
    /home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/dm365_codecs_01_00_06/packages/ti/sdo/codecs/iprun/lib/libiprun.a
    /home/wen_hao/davinci/dm36x/dvsd k_2_10_01_18/dvsdk/dm365_codecs_01_00_06/packages/ti/sdo/codecs/iprun/lib/libimx.a
    /home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/dm365_codecs_01_00_06/packages/ti/sdo/codecs/iprun/lib/libalgfw.a
    /home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/dm365_codecs_01_00_06/packages/ti/sdo/codecs/mpeg4dec/lib/libmp4dec.a
    /home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/vicpsync/lib/debug_trace/vicpsync.av5T
    /home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/ires/edma3chan/lib/debug_trace/edma3Chan.av5T
    /home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/edma3/lib/debug_trace/edma3.av5T
    /home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/ires/vicp/ lib/debug_trace/vicp.av5T
    /home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/ires/grouputils/lib/debug_trace/grouputils.av5T
    /home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/rman/lib/debug_trace/rman.av5T
    /home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/ires/nullresource/lib/debug_trace/nullres.av5T
    /home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/ires/memtcm/lib/debug_trace/memtcm.av5T
    /home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/dm365_codecs_01_00_06/packages/ti/sdo/codecs/h264dec/lib/h264vdec_ti_arm926.a
    /home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/dm365_codecs_01_00_06/packages/ti/sdo/codecs/h264dec/lib/h264v_ti_dma_dm365.a
    /home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/dm365_codecs_01_00_06/packages/ti/sdo/codecs/h264enc/lib/h264venc_ti_arm926.a
    /home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/dm365_codecs_01_00_06/packages/ti/sdo/codecs/h264enc/lib/h264v_ti_dma_dm365.a
    /home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/hdvicpsync/lib/debug_trace/hdvicpsync.av5T
    /home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/ires/hdvicp/lib/debug_trace/hdvicp.av5T
    /home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/utils/lib/debug_trace/rmm.a470MV
    /home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/utils/lib/debug_trace/smgr.a470MV
    /home/wen_hao/davi nci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/utils/lib/debug_trace/rmmp.a470MV
    /home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/utils/lib/debug_trace/smgrmp.a470MV
    /home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/utils/lib/debug_trace/shm.a470MV
    /home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/ires/addrspace/lib/debug_trace/addrspace.av5T
    /home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/memutils/lib/debug_trace/memutils.av5T
    /home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/fc/utils/gtinfra/lib/debug_trace/gt_noOs.av5T
    /home/wen_hao/davinci/dm36x/dvsd k_2_10_01_18/dvsdk/linuxutils_2_24_03/packages/ti/sdo/linuxutils/vicp/lib/vicp.a470MV
    /home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/linuxutils_2_24_03/packages/ti/sdo/linuxutils/edma/lib/edma.a470MV
    /home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/linuxutils_2_24_03/packages/ti/sdo/linuxutils/cmem/lib/cmem.a470MV
    /home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/framework_components_2_25_00_04/packages/ti/sdo/utils/trace/lib/release/gt.av5T
    /home/wen_hao/davinci/dm36x/dvsdk_2_10_01_18/dvsdk/xdctools_3_15_01_59/packages/gnu/targets/arm/rtsv5T/lib/gnu.targets.arm.rtsv5T.av5T
)

it's now quite good and i think it should works. but the output form vd app tell me otherwise.
the completely CE debug log is read here[View :http://e2e.ti.com/cfs-file.ashx/__key/communityserver-discussions-components-files/356/3404.ce_5F00_debug.rar], you can see RMAN and edma3Chan debug info now.

the decode thread(from libdecode) is alwasy failed to create h264dec when ever it start, what's make me confused that the encode thread(from libencode) is alwasy ok to create h264enc and jpegenc.

regards, Mike

Thinks in advance.

0 Vincent W. over 13 years ago in reply to Chris Meng

TI__Genius 12865 points

Based on the source code in CE's ALG_create.c file, the trace is indicating that the h264dec codec's XDAIS interface function algNumAlloc returned 14, but algAlloc returned NULL. Otherwise we'd have seen the string algAlloc returned numRecs=14 in the trace. This means the h264 decoder is rejecting the creation parameters that were passed in.

At this point, I think Mike needs to double-check his creation params structure and make sure he can create the h264dec by itself after removing the encode thread from his app (most likely the encode thread is not interfering based on his trace output, but simplifying the app is usually a good way to debug). 'h264dec' is a standard TI codec so I'd expect it to work with the right parameters. He can compare his set of parameters with the ones used in the DVSDKs decode demo, which uses the same codec. Check out the function videoThrFxn() in dvsdk_demos_2_10_00_17/dm365/decode/video.c and the extnParams structure passed to Vdec2_create().

One other odd thing I see in the trace is that the thread that called Engine_open is not the same one that called VIDDEC2_create (or VIDENC1_create). Typically I see Engine_open called by the same thread that uses the returned engine handle. But somehow he managed to call the codecs in the encode thread. What was the reason behind having a separate thread calling *just* Engine_open? Maybe he should consider simplifying this by having the decode and encode threads make their own Engine_open calls.

Best regards,

Vincent

0 Chris Meng over 13 years ago in reply to Gunjan

TI__Mastermind 37706 points

On behalf of Mike.

Hi, Vincent and Gunjan

i have tried to do Engine_open in __each__ threads, say libdecode / libencode threads __respectively__.

but Engine_open return different handles according to CE log:

VIDDEC2_create> Enter (engine=0x200908, name='h264dec', params=0x1f308c)

it's always different "engine=" between libencode thread and libdecode thread.

after reading the wiki

http://processors.wiki.ti.com/index.php/Multiple_Threads_using_Codec_Engine_Handle

my understanding is the Engine handle should be opened in __each__ threads __respectively__.

"The order is not important. In homogeneous multicore systems, the slave will be loaded when the first thread calls Engine_open(); other Engine_open() calls will return unique Engine handles, and increment a reference count on the slave device. Once the last thread calls Engine_close(), the reference count will reach zero and the slave will be unloaded/stopped."

and more important, does dm365 follow the above rules? if so, it's odd that Engine_open return different handles in different threads.

-----------------------------------------------------------------------------------------------------------------

PS: cannot post in my threads

it's not only me that cannot post the thread. see http://e2e.ti.com/group/helpcentral/w/e2e/1079.aspx

anyway, i dont konw where to post the issue that i canot post my thread.

i have tried with Mozilla Firefox 3.6.28,

IE 7

IE 8

opera 11.62

chrome 17.0.963.56

All failed: after the post button is pressed, the page is just there without reflash and.. it seems blocked.

the attr of post is read like this:

<span class="field-item-input processing">

<a href="#" class="internal-link save-post disabled">

<span/>

Post

</a>

<span class="processing" style="visibility: hidden;"/>

</span>

is it a problem that "disabled" ??

------------------------------------------------------------------------------------------------------------------

Regards, Mike.

Thanks in advance.

0 Vincent W. over 13 years ago in reply to Chris Meng

TI__Genius 12865 points

Yes, Mike is correct that each thread should independently call Engine_open. Different engine handles are expected to be returned in different threads. This is because each thread maintains its own state for the engine. It's fine.

However, I was referring to the following lines in the trace:

@5,336,340us: [+0 T:0x404c7490 S:0x404c6bd4] CE - Engine_open> Enter('encode', 0x0, 0x404c6db0)

@5,670,682us: [+0 T:0x41e0c490 S:0x41e0bc64] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_create> Enter (engine=0x200908, name='h264dec', params=0x1f308c)

The number next to 'T:' is the thread handle, and this is showing me that the thread that called Engine_open is *not* the same thread that called VIDDEC2_create. I typically see Engine_open in the same thread as VIDDEC2_create. Same problem when creating the encoder:

@6,013,310us: [+0 T:0x406c7490 S:0x406c6cd4] CE - Engine_open> Enter('encode', 0x0, 0x406c6d84)

@8,068,954us: [+0 T:0x42bb5490 S:0x42bb482c] ti.sdo.ce.video1.VIDENC1 - VIDENC1_create> Enter (engine=0x200ab8, name='h264enc', params=0x1f0ecc)

Again I'd expect these two lines to show the same thread handle (which should be different from the thread that created the decoder).

In any event, I'd still recommend double-checking on the creation parameters for the decoder. That is most likely the cause to the failure in VIDDEC2_create.

Best regards,

Vincent

0 Chris Meng over 13 years ago in reply to Gunjan

TI__Mastermind 37706 points

On behalf of Mike.

Hi, Vincent and Gunjan.

I have tested the situation that:

Compile the libdecode with a simple file main.c with a simple main function to call the entire Engine_open function at first.

The simple app works fine with not only open, but also process.

The parameters from decode thread are used for create the h264dec should be fine.

After dug into the thread below,

http://e2e.ti.com/support/embedded/linux/f/354/t/172205.aspx

something comes to my mind that (using pthread_create()) at "default priority" might help.

so I just went back, modified libdecode's thread __priority__ _just_ _as_ the libencode one's (who does Engine_open).

it does the trick now, at least Engine_open works.

why priority? what's that important? is there any document / user guide referred to ?

------------------------------------------------------------------------------------------------------------------

PS:

Since I have seen that the handle are always different at my side, and you told me it’s not a problem,

so I just assume it’s not a problem, even if the wiki told otherwise

------------------------------------------------------------------------------------------------------------------

PS2:

I don’t know if there is anything refer to my situation in Chris Ring's post,

but I need to thank him since I have seen a lot of post from him.

------------------------------------------------------------------------------------------------------------------

Regards, Mike.

Thanks in advance

0 Vincent W. over 13 years ago in reply to Chris Meng

TI__Genius 12865 points

Hi Mike,

The forum post you are referring to talks about TraceUtil_start(). Are you using that API by any chance? As Chris mentioned, it is a deprecated API so it is better to call Engine_open directly.

If I understand well, you now have a working system with one process that creates 2 threads:

decode thread: Calls Engine_open, runs h264dec, default priority

encode thread: Calls Engine_open, runs h264enc and jpegenc, default priority

You may want to generate a CE_DEBUG=3 trace for both before and after you change the priority. Also what were the priorities of the two threads prior to the change and after the change? Print them out if you are unsure. I suspect due to the change in priority some of the setup is now happening in a different order, so it'd be interesting to compare. I am looking at the decode demo in the DVSDK, and I see that the speech thread and the video thread are running at different priorities and both audio and video codecs are in the same engine. Each thread independently calls Engine_open just like you do. So there should be nothing in the Codec Engine framework that requires both threads calling Engine_open directly to be at the same priority level.

Best regards,

Vincent

0 zanget over 13 years ago in reply to Vincent W.

Expert 2150 points

hi,Vincent

you are basically right except the are more then 2 thread there after vd is started.

now the priority of both thread are the same : 98.
completely CE log is here:3034.ce_debug.log

the priority of libdecode thread and libencode thread are 95 and 98 respectively in the past with completely CE log, see post above.

regards, Mike

Thanks in advance.

____________________________________

PS:

Thanks ! i can post to e2e now..

____________________________________

0 Vincent W. over 13 years ago in reply to zanget

TI__Genius 12865 points

Hi Mike,

I looked at your new log and I can see the same thread that is calling Engine_open also calls VIDDEC2_create:

@5,409,693us: [+0 T:0x41e0c490 S:0x41e0bd34] CE - Engine_open> Enter('encode', 0x0, 0x41e0bdec)
@5,409,929us: [+0 T:0x41e0c490 S:0x41e0bd1c] OM - Memory_alloc> Enter(0x2c)
@5,410,171us: [+0 T:0x41e0c490 S:0x41e0bd1c] OM - Memory_alloc> return (0x200a38)
@5,410,367us: [+0 T:0x41e0c490 S:0x41e0bd34] CE - Engine_open> return(2099768)
@5,410,638us: [+0 T:0x41e0c490 S:0x41e0bc64] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_create> Enter (engine=0x200a38, name='h264dec', params=0x1f308c)
@5,410,887us: [+0 T:0x41e0c490 S:0x41e0bc34] CV - VISA_create(0x200a38, 'h264dec', 0x1f308c, 0x2484, 'ti.sdo.ce.video2.IVIDDEC2')

So I think that may be the reason why your code is now working properly. Perhaps your priority change somehow had an impact.

From the trace it looks like you now have a problem creating the encoder after the decoder has been instantiated. I see some edmak errors:

EDMAK Error: dma_ioctl: REQUESTDMA failed: -22

EDMA Error: EDMA_IOCREQUESTDMA error

@100,208,519us: [+0 T:0x43e1e490 S:0x43e1d04c] ti.sdo.fc.edma3 - allocAndSaveResource> Exit (status=FALSE)
@100,208,867us: [+7 T:0x43e1e490 S:0x43e1d09c] ti.sdo.fc.edma3 - allocResource> Resource allocation failed

@135,815,745us: [+7 T:0x43e1e490 S:0x43e1d6b4] ti.sdo.ce.alg.Algorithm - Algorithm_create> Assignment of alg resources through RMAN FAILED (0x7)

you may want to compare your encoder's creation parameters against those used in the encodedecode demo. Looks like the system may be running out of resources to satisfy your settings.

Best regards,

Vincent

0 zanget over 13 years ago in reply to Vincent W.

Expert 2150 points

hi,Vincent.

i just doubt what kinds of an impact it might be.

please note that decode / encode thread dont work if the prioritys are different in any order of creation.

it's also odd that the "REQUESTDMA failed" error arise from the kernel after the vd app running many times(maybe 40 times) with unsuccessfully end killed by kill command with edma module reload each time before start.

but it's not a problem so far that it works again if the system reboot.

regards, Mike

thanks in advace.

0 Gunjan over 13 years ago in reply to zanget

TI__Expert 7415 points

Mike,

I see from your initial trace log that you are using LinuxUtils 2.24.03. A bug regarding EDMA allocation was fixed in LinuxUtils 2.25:-

SDOCM00062704 LinuxUtils EDMA kernel module does not correctly auto-release resources in a multi-threaded application

In fact, for FC 2.25.00.04, the recommended LinuxUtils release to use is 2.25. Could you update to that release and give your app a try ? I wonder if you are seeing an issue that we have already fixed in LinuxUtils.

0 Vincent W. over 13 years ago in reply to zanget

TI__Genius 12865 points

Hi Mike,

Regarding the thread priorities, you said the VIDDEC2_create call will not work if the thread priorities are different, independently of the order of creation. Does it work when you have both threads at priority 95? It'd be interesting to find out if the problem is due to having threads at priority 95 or whether it is due to the fact that the decode and encode threads are at different priorities relative to one another.

If the problem is due to relative priorities between the two threads, could you do an experiment and synchronize the decode and the encode threads (maybe use a semaphore, or even a getchar() call) so that the encode thread stays blocked until the VIDDEC2_create is called? You can vary the location at which the encode thread blocks (first block on entry of the thread, then right before Engine_open, then right after Engine_open, then right before 1st VIDENC1_create, then right after 1st VIDENC1_create, and so on). This way you can figure out which operation in the encode thread is causing VIDDEC2_create to fail.

Also, given you have other threads in the system, try to disable as many of them as possible to eliminate potential interaction between these threads and our threads of interest.

Best regards,

Vincent

Processors

Processors forum

Algorithm_create always failed