This thread has been locked.

If you have a related question, please click the "Ask a related question" button in the top right corner. The newly created question will be automatically linked to this question.

dm6467, replace h264 codec with videnc1_copy and viddec2_copy.

hello, every one!

           I want to replace the h264 codec with videnc1_copy and viddec2_copy,  and do some simple image processing.  The encodedecode demo uses the video.x64P I built.

 in the video_copy.cfg, I add

var  VIDDEC2_COPY = xdc.useModule('ti.sdo.ce.examples.codecs.viddec2_copy.VIDDEC2_COPY');

var  VIDENC1_COPY = xdc.useModule('ti.sdo.ce.examples.codecs.videnc1_copy.VIDENC1_COPY');

Server.algs = [   

    {name: "h264enc", mod: H264ENC,groupId:0,threadAttrs: {
                    stackMemId: 0, priority: Server.MINPRI + 1}
    },
    {name: "h264dec", mod: H264DEC,groupId:1,threadAttrs: {
                stackMemId: 0, priority: Server.MINPRI + 1}
    },
    {name: "viddec2_copy", mod: VIDDEC2_COPY, threadAttrs: {
        stackMemId: 0, priority: Server.MINPRI + 2}, groupId : 0,
    },
    {name: "videnc1_copy", mod: VIDENC1_COPY, threadAttrs: {
        stackMemId: 0, priority: Server.MINPRI + 2}, groupId : 0,
    }

];

Server.algs = [   

    {name: "h264enc", mod: H264ENC,groupId:0,threadAttrs: {
                    stackMemId: 0, priority: Server.MINPRI + 1}
    },
    {name: "h264dec", mod: H264DEC,groupId:1,threadAttrs: {
                stackMemId: 0, priority: Server.MINPRI + 1}
    },
    {name: "viddec2_copy", mod: VIDDEC2_COPY, threadAttrs: {
        stackMemId: 0, priority: Server.MINPRI + 2}, groupId : 0,
    },
    {name: "videnc1_copy", mod: VIDENC1_COPY, threadAttrs: {
        stackMemId: 0, priority: Server.MINPRI + 2}, groupId : 0,
    }

];

but when I run the CE_DEBUG=3 ./encodedecode, the error are as follows

failed to encode the video buffer

can anybody help me?

thankyou!

zhiqiang li

  • Hi Zhiqiang,

    Could you please post the full trace that appears on the console? Also post the trace that results from setting DMAI_DEBUG=2. These could help people on the list to better assist you.

    Thanks and best regards,

    Vincent

     

  • Hi, Vincent,

    The DMAI_DEBUG trace that appears on the console are as follows:

    root@192.168.5.127:/opt/dvsdk# DMAI_DEBUG=2 ./encodedecode
    @0x0002df06:[T:0x40018528] ti.sdo.dmai - [Dmai] Dmai log level set to '2'. Note that calling CERuntime_init after this point may cause unexpected change to DMAI tracing behavior.
    Encodedecode demo started.
    @0x0005c775:[T:0x40018528] ti.sdo.dmai - [Buffer] Alloc Buffer of size 30720 at 0x42352000 (0x8804e000 phys)
    @0x00099ef2:[T:0x40018528] ti.sdo.dmai - [Buffer] Alloc Buffer of size 30720 at 0x42361000 (0x8803f000 phys)
    VPIF_OPEN: LINE: 2430.
    @0x000fdc9a:[T:0x43b70b60] ti.sdo.dmai - [Venc1] Creating encoder videnc1_copy for max 720x480 bitrate 2000000 ratectrl 4
    @0x000fe31d:[T:0x43b70b60] ti.sdo.dmai - [Venc1] Setting dynParams size 720x480 bitrate 2000000
    @0x000fe4e9:[T:0x43b70b60] ti.sdo.dmai - [Venc1] Made XDM_SETPARAMS control call
    @0x000feabe:[T:0x43b70b60] ti.sdo.dmai - [Vdec2] Video decoder instance created
    @0x000fecac:[T:0x43b70b60] ti.sdo.dmai - [Vdec2] Made XDM_SETPARAMS control call
    @0x000fee29:[T:0x43b70b60] ti.sdo.dmai - [Vdec2] Made XDM_GETBUFINFO control call
    @0x000fef88:[T:0x43b70b60] ti.sdo.dmai - [Buffer] Alloc Buffer of size 1 at 0x43b71000 (0x88030000 phys)
    @0x000ff061:[T:0x43b70b60] ti.sdo.dmai - [BufTab] Allocating BufTab for 8 buffers
    @0x000ff1da:[T:0x43b70b60] ti.sdo.dmai - [Buffer] Alloc Buffer of size 572960 at 0x43b80000 (0x886b1000 phys)
    @0x000ff38b:[T:0x43b70b60] ti.sdo.dmai - [Buffer] Alloc Buffer of size 572960 at 0x43c22000 (0x8860f000 phys)
    @0x000ff542:[T:0x43b70b60] ti.sdo.dmai - [Buffer] Alloc Buffer of size 572960 at 0x43cc4000 (0x8856d000 phys)
    @0x000ff6eb:[T:0x43b70b60] ti.sdo.dmai - [Buffer] Alloc Buffer of size 572960 at 0x43d66000 (0x884cb000 phys)
    @0x000ff87c:[T:0x43b70b60] ti.sdo.dmai - [Buffer] Alloc Buffer of size 572960 at 0x43e08000 (0x88429000 phys)
    @0x000ffa0f:[T:0x43b70b60] ti.sdo.dmai - [Buffer] Alloc Buffer of size 572960 at 0x43eaa000 (0x88387000 phys)
    @0x000ffba1:[T:0x43b70b60] ti.sdo.dmai - [Buffer] Alloc Buffer of size 572960 at 0x43f4c000 (0x882e5000 phys)
    @0x000ffd49:[T:0x43b70b60] ti.sdo.dmai - [Buffer] Alloc Buffer of size 572960 at 0x43fee000 (0x88243000 phys)
    ctrlFxnFUN: FILE: ../ctrl.c, LINE: 319.
    ctrlFxnFUN: FILE: ../ctrl.c, LINE: 335.
    in Display_v412_create, FILE: linux/Display_v4l2.c, LINE: 148.
    @0x00269c2a:[T:0x42b6fb60] ti.sdo.dmai - [Display] Video output set to size 720x480 pitch 720
    in Display_v412_create, FILE: linux/Display_v4l2.c, LINE: 163.
    @0x00269de2:[T:0x42b6fb60] ti.sdo.dmai - [BufTab] Allocating BufTab for 3 buffers
    @0x002b66a6:[T:0x42b6fb60] ti.sdo.dmai - [Dmai] Driver buffer 0 mapped to 0x44090000 has physical address 0x83400000
    @0x00302f6f:[T:0x42b6fb60] ti.sdo.dmai - [Dmai] Driver buffer 1 mapped to 0x44485000 has physical address 0x83800000
    @0x0034f7e2:[T:0x42b6fb60] ti.sdo.dmai - [Dmai] Driver buffer 2 mapped to 0x4487a000 has physical address 0x83c00000
    @0x005519ae:[T:0x4336fb60] ti.sdo.dmai - [Capture] Capture input set to COMPOSITE
    @0x00551b09:[T:0x4336fb60] ti.sdo.dmai - [Capture] Video input connected size 720x480 pitch 720
    @0x00551c2a:[T:0x4336fb60] ti.sdo.dmai - [BufTab] Allocating BufTab for 4 buffers
    @0x0059e5cc:[T:0x4336fb60] ti.sdo.dmai - [Dmai] Driver buffer 0 mapped to 0x44c6f000 has physical address 0x80800000
    @0x005eaee4:[T:0x4336fb60] ti.sdo.dmai - [Dmai] Driver buffer 1 mapped to 0x45064000 has physical address 0x80c00000
    @0x00637814:[T:0x4336fb60] ti.sdo.dmai - [Dmai] Driver buffer 2 mapped to 0x45459000 has physical address 0x84000000
    @0x006841be:[T:0x4336fb60] ti.sdo.dmai - [Dmai] Driver buffer 3 mapped to 0x4584e000 has physical address 0x84400000
    ctrlFxnFUN: FILE: ../ctrl.c, LINE: 343.
    ctrlFxnFUN: FILE: ../ctrl.c, LINE: 349.
    @0x006913ca:[T:0x43b70b60] OM - Memory_getBufferPhysicalAddress> invalid buffer size provided (0)
    @0x006914b9:[T:0x43b70b60] ti.sdo.dmai - [Venc1] VIDENC1_process() ret -1 generated 184 bytes
    @0x00691563:[T:0x43b70b60] ti.sdo.dmai - [Venc1] VIDENC1_process() failed with a fatal error -1 (0)
    Error: Failed to encode video buffer
    @0x0069e674:[T:0x43b70b60] ti.sdo.dmai - [Buffer] Free Buffer of size 1 at 0x43b71000 (0x88030000 phys)
    @0x006b7626:[T:0x43b70b60] ti.sdo.dmai - [Buffer] Free Buffer of size 572960 at 0x43b80000 (0x886b1000 phys)
    @0x006ba121:[T:0x43b70b60] ti.sdo.dmai - [Buffer] Free Buffer of size 572960 at 0x43c22000 (0x8860f000 phys)
    @0x006ba4f6:[T:0x43b70b60] ti.sdo.dmai - [Buffer] Free Buffer of size 572960 at 0x43cc4000 (0x8856d000 phys)
    @0x006ba87f:[T:0x43b70b60] ti.sdo.dmai - [Buffer] Free Buffer of size 572960 at 0x43d66000 (0x884cb000 phys)
    @0x006babc1:[T:0x43b70b60] ti.sdo.dmai - [Buffer] Free Buffer of size 572960 at 0x43e08000 (0x88429000 phys)
    @0x006baf12:[T:0x43b70b60] ti.sdo.dmai - [Buffer] Free Buffer of size 572960 at 0x43eaa000 (0x88387000 phys)
    @0x006bb24a:[T:0x43b70b60] ti.sdo.dmai - [Buffer] Free Buffer of size 572960 at 0x43f4c000 (0x882e5000 phys)
    @0x006bb578:[T:0x43b70b60] ti.sdo.dmai - [Buffer] Free Buffer of size 572960 at 0x43fee000 (0x88243000 phys)
    @0x006bb9f8:[T:0x40018528] ti.sdo.dmai - [Buffer] Free Buffer of size 30720 at 0x42352000 (0x8804e000 phys)
    @0x006bbb88:[T:0x40018528] ti.sdo.dmai - [Buffer] Free Buffer of size 30720 at 0x42361000 (0x8803f000 phys)                                                  

     

     

    The full CE_DEBUG trace are as follows:

     

    root@192.168.5.127:/opt/dvsdk# CE_DEBUG=3 ./encodedecode
    @0,934,223us: [+4 T:0x40018528 S:0xbefffb0c] OG - Global_init> This program was built with the following packages:
    @0,935,173us: [+4 T:0x40018528 S:0xbefffb0c] OG -     package gnu.targets.rts470MV (/opt/dvsdk_1_40_02_33/xdc_3_00_06/packages/gnu/targets/rts470MV/) [1,0,0,0,1203621000516]
    @0,935,542us: [+4 T:0x40018528 S:0xbefffb0c] OG -     package ti.sdo.codecs.h264enc (/opt/dvsdk_1_40_02_33/dm6467_dvsdk_combos_1_17/packages/ti/sdo/codecs/h264enc/) [1,0,0,1221669049000]
    @0,935,796us: [+4 T:0x40018528 S:0xbefffb0c] OG -     package ti.sdo.codecs.h264dec (/opt/dvsdk_1_40_02_33/dm6467_dvsdk_combos_1_17/packages/ti/sdo/codecs/h264dec/) [1,0,0,1221669049000]
    @0,936,036us: [+4 T:0x40018528 S:0xbefffb0c] OG -     package ti.xdais.dm.examples.viddec2_copy (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/examples/ti/xdais/dm/examples/viddec2_copy/) [2,0,0,1372427230000]
    @0,936,353us: [+4 T:0x40018528 S:0xbefffb0c] OG -     package ti.xdais.dm.examples.videnc1_copy (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/examples/ti/xdais/dm/examples/videnc1_copy/) [2,0,0,1372399186000]
    @0,936,609us: [+4 T:0x40018528 S:0xbefffb0c] OG -     package ti.xdais.dm (/opt/dvsdk_1_40_02_33/xdais_6_10_01/packages/ti/xdais/dm/) [1,0,4,1210262746529]
    @0,936,821us: [+4 T:0x40018528 S:0xbefffb0c] OG -     package ti.xdais (/opt/dvsdk_1_40_02_33/xdais_6_10_01/packages/ti/xdais/) [1,2,1,1210262742149]
    @0,937,026us: [+4 T:0x40018528 S:0xbefffb0c] OG -     package ti.sdo.utils.trace (/opt/dvsdk_1_40_02_33/framework_components_2_10_02/packages/ti/sdo/utils/trace/) [1,0,0,1220943366422]
    @0,937,258us: [+4 T:0x40018528 S:0xbefffb0c] OG -     package ti.sdo.ce.utils.xdm (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/utils/xdm/) [1,0,1,1220951322616]
    @0,937,483us: [+4 T:0x40018528 S:0xbefffb0c] OG -     package ti.sdo.fc.dman3 (/opt/dvsdk_1_40_02_33/framework_components_2_10_02/packages/ti/sdo/fc/dman3/) [1,0,3,1220943053023]
    @0,937,706us: [+4 T:0x40018528 S:0xbefffb0c] OG -     package ti.sdo.fc.acpy3 (/opt/dvsdk_1_40_02_33/framework_components_2_10_02/packages/ti/sdo/fc/acpy3/) [1,0,2,1220943014135]
    @0,937,929us: [+4 T:0x40018528 S:0xbefffb0c] OG -     package dsplink.gpp (/opt/dvsdk_1_40_02_33/dsplink-davinci-v1.50-prebuilt/packages/dsplink/gpp/) [3,0,0]@0,938,139us: [+4 T:0x40018528 S:0xbefffb0c] OG -     package ti.sdo.linuxutils.cmem (/opt/dvsdk_1_40_02_33/cmem_2_10/packages/ti/sdo/linuxutils/cmem/) [2,0,1,1204929560755]
    @0,938,393us: [+4 T:0x40018528 S:0xbefffb0c] OG -     package ti.catalog.c470 (/opt/dvsdk_1_40_02_33/xdc_3_00_06/packages/ti/catalog/c470/) [1,0,1,0,1203561761475]
    @0,938,610us: [+4 T:0x40018528 S:0xbefffb0c] OG -     package ti.catalog.c6000 (/opt/dvsdk_1_40_02_33/xdc_3_00_06/packages/ti/catalog/c6000/) [1,0,0,0,1203561781695]
    @0,938,828us: [+4 T:0x40018528 S:0xbefffb0c] OG -     package ti.platforms.evmDM6467 (/opt/dvsdk_1_40_02_33/xdc_3_00_06/packages/ti/platforms/evmDM6467/) [1,0,0,0,1203562062227]
    @0,939,051us: [+4 T:0x40018528 S:0xbefffb0c] OG -     package ti.sdo.ce.osal (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/osal/) [2,0,2,1220951127739]
    @0,939,269us: [+4 T:0x40018528 S:0xbefffb0c] OG -     package ti.sdo.ce.ipc (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/ipc/) [2,0,1,1220951085787]
    @0,939,485us: [+4 T:0x40018528 S:0xbefffb0c] OG -     package ti.sdo.ce.osal.linux (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/osal/linux/)
     [2,0,1,1220951140072]
    @0,939,709us: [+4 T:0x40018528 S:0xbefffb0c] OG -     package ti.sdo.ce.ipc.dsplink (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/ipc/dsplink/) [2,0,1,1220951096818]
    @0,939,934us: [+4 T:0x40018528 S:0xbefffb0c] OG -     package ti.sdo.ce.alg (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/alg/) [1,0,1,1220950781938]
    @0,940,220us: [+4 T:0x40018528 S:0xbefffb0c] OG -     package ti.sdo.ce (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/) [1,0,6,1220950771473]@0,940,447us: [+4 T:0x40018528 S:0xbefffb0c] OG -     package ti.sdo.ce.video1 (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/video1/) [1,0,2,1220951340734]
    @1,273,852us: [+4 T:0x40018528 S:0xbefffb0c] OG -     package ti.sdo.codecs.h264enc.ce (/opt/dvsdk_1_40_02_33/dm6467_dvsdk_combos_1_17/packages/ti/sdo/codecs/h264enc/ce/) [1,0,0,1221669049000]
    @1,274,132us: [+4 T:0x40018528 S:0xbefffb0c] OG -     package ti.sdo.ce.video2 (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/video2/) [1,0,2,1220951347240]
    @1,274,365us: [+4 T:0x40018528 S:0xbefffb0c] OG -     package ti.sdo.codecs.h264dec.ce (/opt/dvsdk_1_40_02_33/dm6467_dvsdk_combos_1_17/packages/ti/sdo/codecs/h264dec/ce/) [1,0,0,1221669049000]
    @1,274,602us: [+4 T:0x40018528 S:0xbefffb0c] OG -     package ti.sdo.ce.examples.codecs.viddec2_copy (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/examples/ti/sdo/ce/examples/codecs/viddec2_copy/) []
    @1,274,841us: [+4 T:0x40018528 S:0xbefffb0c] OG -     package ti.sdo.ce.examples.codecs.videnc1_copy (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/examples/ti/sdo/ce/examples/codecs/videnc1_copy/) []
    @1,275,081us: [+4 T:0x40018528 S:0xbefffb0c] OG -     package ti.sdo.ce.speech (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/speech/) [1,0,2,1220951161404]
    @1,275,302us: [+4 T:0x40018528 S:0xbefffb0c] OG -     package ti.sdo.ce.speech1 (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/speech1/) [1,0,1,1220951167662]
    @1,275,522us: [+4 T:0x40018528 S:0xbefffb0c] OG -     package ti.sdo.ce.audio (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/audio/) [1,0,2,1220950788137]
    @1,275,741us: [+4 T:0x40018528 S:0xbefffb0c] OG -     package ti.sdo.ce.video (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/video/) [1,0,3,1220951334122]
    @1,275,959us: [+4 T:0x40018528 S:0xbefffb0c] OG -     package ti.sdo.dmai (/opt/dvsdk_1_40_02_33/dmai_1_10_00_06/packages/ti/sdo/dmai/) [1,0,0,1372533611000]
    @1,276,167us: [+4 T:0x40018528 S:0xbefffb0c] OG -     package ti.sdo.simplewidget (/opt/dvsdk_1_40_02_33/dvsdk_demos_1_40_00_18/packages/ti/sdo/simplewidget/)
     []
    @1,276,376us: [+4 T:0x40018528 S:0xbefffb0c] OG -     package ti.sdo.ce.bioslog (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/bioslog/) [1,0,1,1220950800329]
    @1,276,626us: [+4 T:0x40018528 S:0xbefffb0c] OG -     package ti.sdo.ce.utils.trace (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/utils/trace/) [1,0,1,1220951317087]
    @1,276,857us: [+4 T:0x40018528 S:0xbefffb0c] OG -     package encodedecode_config (/opt/dvsdk_1_40_02_33/dvsdk_demos_1_40_00_18/dm6467/encodedecode/encodedecode_config/) []
    @1,277,178us: [+0 T:0x40018528 S:0xbefffb14] OG - Global_atexit> enter (fxn=0x2d22c)
    @1,277,383us: [+0 T:0x40018528 S:0xbefffb14] OG - Global_atexit> enter (fxn=0x2c6c4)
    @1,277,676us: [+0 T:0x40018528 S:0xbefffae4] OM - Memory_alloc> Enter(0x18)
    @1,277,867us: [+0 T:0x40018528 S:0xbefffae4] OM - Memory_alloc> return (0x590f0)
    @1,278,268us: [+0 T:0x40018528 S:0xbefffb04] OG - Global_atexit> enter (fxn=0x2afac)
    @1,278,551us: [+0 T:0x40018528 S:0xbefffae4] OM - Memory_alloc> Enter(0x18)
    @1,278,745us: [+0 T:0x40018528 S:0xbefffae4] OM - Memory_alloc> return (0x59140)
    @1,278,920us: [+0 T:0x40018528 S:0xbefffb04] OG - Global_atexit> enter (fxn=0x28bf8)
    @1,279,189us: [+0 T:0x40018528 S:0xbefffb0c] OG - Global_atexit> enter (fxn=0x2bd48)
    @1,279,479us: [+0 T:0x40018528 S:0xbefffaf4] ti.sdo.ce.osal.Sem - Sem_create> count: 0
    @1,279,672us: [+0 T:0x40018528 S:0xbefffadc] OM - Memory_alloc> Enter(0x14)
    @1,279,847us: [+0 T:0x40018528 S:0xbefffadc] OM - Memory_alloc> return (0x591c0)
    @1,280,101us: [+0 T:0x40018528 S:0xbefffaf4] ti.sdo.ce.osal.Sem - Leaving Sem_create> sem[0x591c0]
    @1,280,304us: [+0 T:0x40018528 S:0xbefffaf4] ti.sdo.ce.osal.Sem - Sem_create> count: 0
    @1,280,474us: [+0 T:0x40018528 S:0xbefffadc] OM - Memory_alloc> Enter(0x14)
    @1,280,642us: [+0 T:0x40018528 S:0xbefffadc] OM - Memory_alloc> return (0x591d8)
    @1,280,807us: [+0 T:0x40018528 S:0xbefffaf4] ti.sdo.ce.osal.Sem - Leaving Sem_create> sem[0x591d8]
    @1,615,020us: [+0 T:0x40018528 S:0xbefffadc] OM - Memory_alloc> Enter(0x18)
    @1,615,221us: [+0 T:0x40018528 S:0xbefffadc] OM - Memory_alloc> return (0x591f0)
    @1,615,400us: [+0 T:0x40018528 S:0xbefffadc] OT - Thread_create> Enter (fxn=0x27d38, attrs=0x0)
    @1,615,582us: [+0 T:0x40018528 S:0xbefffac4] OM - Memory_alloc> Enter(0x64)
    @1,615,747us: [+0 T:0x40018528 S:0xbefffac4] OM - Memory_alloc> return (0x59210)
    @1,616,454us: [+0 T:0x40018528 S:0xbefffadc] OT - Thread_create> Exit (task=0x59210)
    @1,616,675us: [+0 T:0x40018528 S:0xbefffb14] OG - Global_atexit> enter (fxn=0x278f8)
    @1,616,882us: [+0 T:0x40018528 S:0xbefffb14] OG - Global_atexit> enter (fxn=0x299ec)
    @1,617,174us: [+0 T:0x40018528 S:0xbefffafc] ti.sdo.ce.alg - ALG_init> Enter
    @1,617,358us: [+0 T:0x40018528 S:0xbefffaec] OG - Global_atexit> enter (fxn=0x26e40)
    @1,617,540us: [+0 T:0x40018528 S:0xbefffb1c] ti.sdo.ce.alg - ALG_init> Exit
    @1,617,725us: [+0 T:0x40018528 S:0xbefffb14] OG - Global_atexit> enter (fxn=0x268e0)
    @1,617,924us: [+0 T:0x40018528 S:0xbefffaec] OM - Memory_alloc> Enter(0x18)
    @1,618,105us: [+0 T:0x40018528 S:0xbefffaec] OM - Memory_alloc> return (0x593a8)
    @1,618,282us: [+0 T:0x40018528 S:0xbefffb14] OG - Global_atexit> enter (fxn=0x2beac)
    @1,618,547us: [+6 T:0x40018528 S:0xbefffb14] CE - Engine_init> CE debugging on (CE_DEBUG=3; allowed CE_DEBUG levels: 1=min, 2=good, 3=max)
    @1,618,776us: [+0 T:0x40018528 S:0xbefffb04] OG - Global_atexit> enter (fxn=0x23c64)
    @1,618,954us: [+0 T:0x40018528 S:0xbefffae4] OM - Memory_alloc> Enter(0x18)
    @1,619,127us: [+0 T:0x40018528 S:0xbefffae4] OM - Memory_alloc> return (0x593e8)
    @1,619,296us: [+0 T:0x40018528 S:0xbefffae4] OM - Memory_alloc> Enter(0x18)
    @1,619,468us: [+0 T:0x40018528 S:0xbefffae4] OM - Memory_alloc> return (0x59408)
    @1,619,639us: [+0 T:0x40018528 S:0xbefffae4] OM - Memory_alloc> Enter(0x18)
    @1,619,807us: [+0 T:0x40018528 S:0xbefffae4] OM - Memory_alloc> return (0x59428)
    @1,620,100us: [+0 T:0x40018528 S:0xbefffb14] CS - Server_init()
    @1,620,278us: [+0 T:0x40018528 S:0xbefffb14] CS - Server_init> Global_useLinkArbiter = 0
    @1,620,460us: [+0 T:0x40018528 S:0xbefffb14] OG - Global_atexit> enter (fxn=0x227b4)
    @1,620,762us: [+0 T:0x40018528 S:0xbefffb24] OG - Global_atexit> enter (fxn=0x11b44)
    Encodedecode demo started.
    TraceUtil_start> note: CE_DEBUG env. var is set, so TraceUtil is not active (unset CE_DEBUG if you need TraceUtil)
    @1,623,622us: [+1 T:0x40acab60 S:0x40aca52c] OP - daemon> thread created.
    @1,623,894us: [+0 T:0x40acab60 S:0x40aca52c] OP - getCmd_d> Enter (proc=0x40aca5a4)
    @1,624,559us: [+0 T:0x40acab60 S:0x40aca50c] ti.sdo.ce.osal.Sem - Entered Sem_pend> sem[0x591c0] timeout[0xffffffff]
    @1,627,467us: [+0 T:0x40018528 S:0xbefffa54] OM - Memory_alloc> Enter(0x7800)
    @1,627,692us: [+0 T:0x40018528 S:0xbefffa04] OM - Memory_contigAlloc> Enter(size=30720, align=-1, cached=FALSE, heap=FALSE)
    @1,628,052us: [+4 T:0x40018528 S:0xbefffa04] OM - Memory_contigAlloc> CMEM_alloc(30720) = 0x40ad3000.
    @1,628,298us: [+4 T:0x40018528 S:0xbefffa04] OM - Memory_contigAlloc> CMEM_getPhys(0x40ad3000) = 0x8803f000.
    @1,628,500us: [+1 T:0x40018528 S:0xbefff9ac] OM - Memory__addContigBuf> Enter(virtAddr=0x40ad3000, size=30720, physAddr=0x8803f000)
    @1,628,713us: [+1 T:0x40018528 S:0xbefff9ac] OM - Memory__addContigBuf> creating new contigBuf object
    @1,628,887us: [+0 T:0x40018528 S:0xbefff994] OM - Memory_alloc> Enter(0x10)
    @1,629,099us: [+0 T:0x40018528 S:0xbefff994] OM - Memory_alloc> return (0x617b0)
    @1,629,277us: [+1 T:0x40018528 S:0xbefff9ac] OM - Memory__addContigBuf> returning: cb->phys=0x8803f000, cb->size=30720, cb->virt=0x40ad3000
    @1,629,490us: [+0 T:0x40018528 S:0xbefffa04] OM - Memory_contigAlloc> return (0x40ad3000)
    @1,629,658us: [+0 T:0x40018528 S:0xbefffa54] OM - Memory_alloc> return (0x40ad3000)
    @1,629,824us: [+0 T:0x40018528 S:0xbefffa2c] OM - Memory_getBufferPhysicalAddress> Enter(virtAddr=0x40ad3000, size=4)
    @1,630,084us: [+1 T:0x40018528 S:0xbefffa2c] OM - Memory__getPhysicalAddress> Enter(virtAddr=0x40ad3000, size=4)
    @1,964,672us: [+1 T:0x40018528 S:0xbefffa2c] OM - Memory__getPhysicalAddress> found in cb(Sc=0x40ad3000, Ec=0x40ada800, Ss=0x40ad3000, Es=0x40ad3004, PSc=0x8803f000)
    @1,964,953us: [+1 T:0x40018528 S:0xbefffa2c] OM - Memory__getPhysicalAddress> returning physAddr=0x8803f000
    @1,965,145us: [+0 T:0x40018528 S:0xbefffa2c] OM - Memory_getBufferPhysicalAddress> return (0x8803f000)
    @1,965,330us: [+2 T:0x40018528 S:0xbefffa6c] ti.sdo.dmai - [Buffer] Alloc Buffer of size 30720 at 0x40ad3000 (0x8803f000 phys)
    @2,217,208us: [+0 T:0x40018528 S:0xbefffa54] OM - Memory_alloc> Enter(0x7800)
    @2,217,449us: [+0 T:0x40018528 S:0xbefffa04] OM - Memory_contigAlloc> Enter(size=30720, align=-1, cached=FALSE, heap=FALSE)
    @2,217,801us: [+4 T:0x40018528 S:0xbefffa04] OM - Memory_contigAlloc> CMEM_alloc(30720) = 0x40ae2000.
    @2,218,046us: [+4 T:0x40018528 S:0xbefffa04] OM - Memory_contigAlloc> CMEM_getPhys(0x40ae2000) = 0x8804e000.
    @2,218,254us: [+1 T:0x40018528 S:0xbefff9ac] OM - Memory__addContigBuf> Enter(virtAddr=0x40ae2000, size=30720, physAddr=0x8804e000)
    @2,218,467us: [+1 T:0x40018528 S:0xbefff9ac] OM - Memory__addContigBuf> creating new contigBuf object
    @2,218,642us: [+0 T:0x40018528 S:0xbefff994] OM - Memory_alloc> Enter(0x10)
    @2,218,812us: [+0 T:0x40018528 S:0xbefff994] OM - Memory_alloc> return (0x61838)
    @2,218,984us: [+1 T:0x40018528 S:0xbefff9ac] OM - Memory__addContigBuf> returning: cb->phys=0x8804e000, cb->size=30720, cb->virt=0x40ae2000
    @2,219,196us: [+0 T:0x40018528 S:0xbefffa04] OM - Memory_contigAlloc> return (0x40ae2000)
    @2,219,365us: [+0 T:0x40018528 S:0xbefffa54] OM - Memory_alloc> return (0x40ae2000)
    @2,219,531us: [+0 T:0x40018528 S:0xbefffa2c] OM - Memory_getBufferPhysicalAddress> Enter(virtAddr=0x40ae2000, size=4)
    @2,219,722us: [+1 T:0x40018528 S:0xbefffa2c] OM - Memory__getPhysicalAddress> Enter(virtAddr=0x40ae2000, size=4)
    @2,219,908us: [+1 T:0x40018528 S:0xbefffa2c] OM - Memory__getPhysicalAddress> found in cb(Sc=0x40ae2000, Ec=0x40ae9800, Ss=0x40ae2000, Es=0x40ae2004, PSc=0x8804e000)
    @2,220,211us: [+1 T:0x40018528 S:0xbefffa2c] OM - Memory__getPhysicalAddress> returning physAddr=0x8804e000
    @2,220,409us: [+0 T:0x40018528 S:0xbefffa2c] OM - Memory_getBufferPhysicalAddress> return (0x8804e000)
    @2,220,624us: [+2 T:0x40018528 S:0xbefffa6c] ti.sdo.dmai - [Buffer] Alloc Buffer of size 30720 at 0x40ae2000 (0x8804e000 phys)
    VPIF_OPEN: LINE: 2430.
    @2,624,248us: [+0 T:0x422f0b60 S:0x422f0404] CE - Engine_open> Enter('encodedecode', 0x0, 0x422f044c)
    @2,624,575us: [+0 T:0x422f0b60 S:0x422f03ec] OM - Memory_alloc> Enter(0x2c)
    @2,624,781us: [+0 T:0x422f0b60 S:0x422f03ec] OM - Memory_alloc> return (0x61e90)
    @2,625,007us: [+0 T:0x422f0b60 S:0x422f0404] CE - rserverOpen('video_copy.x64P'), count = 0
    @2,625,202us: [+0 T:0x422f0b60 S:0x422f03cc] OP - Processor_create> Enter(imageName='video_copy.x64P', linkCfg='(null)', attrs=0x422f0450)
    @2,625,765us: [+0 T:0x40018528 S:0xbefffa74] CE - Engine_open> Enter('encodedecode', 0x0, 0xbefffabc)
    @2,626,308us: [+0 T:0x422f0b60 S:0x422f03b4] OM - Memory_alloc> Enter(0x24)
    @2,626,546us: [+0 T:0x422f0b60 S:0x422f03b4] OM - Memory_alloc> return (0x61ec0)
    @2,626,737us: [+0 T:0x422f0b60 S:0x422f03ac] OP - doCmd> Enter (cmdId=1, proc=0x61ec0)
    @2,626,927us: [+0 T:0x422f0b60 S:0x422f039c] ti.sdo.ce.osal.Sem - Entered Sem_post> sem[0x591c0]
    @2,627,172us: [+0 T:0x422f0b60 S:0x422f03ac] ti.sdo.ce.osal.Sem - Leaving Sem_post> sem[0x591c0]
    @2,627,379us: [+0 T:0x422f0b60 S:0x422f038c] ti.sdo.ce.osal.Sem - Entered Sem_pend> sem[0x591d8] timeout[0xffffffff]
    @2,627,651us: [+0 T:0x40018528 S:0xbefffa5c] OM - Memory_alloc> Enter(0x2c)
    @2,627,851us: [+0 T:0x40018528 S:0xbefffa5c] OM - Memory_alloc> return (0x61ee8)
    @2,628,443us: [+0 T:0x40acab60 S:0x40aca50c] ti.sdo.ce.osal.Sem - Leaving Sem_pend> sem[0x591c0] status[0]
    @2,629,147us: [+0 T:0x40acab60 S:0x40aca52c] OP - getCmd_d> Exit (result=1)
    @2,630,137us: [+0 T:0x40acab60 S:0x40aca52c] OP - Processor_create_d> Enter(proc=0x61ec0)
    @2,630,840us: [+2 T:0x40acab60 S:0x40aca52c] OP - Processor_create_d> Initializing DSP PROC...
    @2,652,388us: [+2 T:0x40acab60 S:0x40aca52c] OP - Processor_create_d> Using DspLink config data for entry #0 [server 'video_copy.x64P']
    @2,653,222us: [+0 T:0x40acab60 S:0x40aca514] OM - Memory_alloc> Enter(0xe0)
    @2,653,451us: [+0 T:0x40acab60 S:0x40aca514] OM - Memory_alloc> return (0x61f18)
    @2,653,644us: [+2 T:0x40acab60 S:0x40aca52c] OP - Processor_create_d> Adding DSP segment #0 to Link configuration: name='DDR2', startAddress=0x8fa00000, sizeInBytes=0x400000, shared=1
    @2,653,911us: [+2 T:0x40acab60 S:0x40aca52c] OP - Processor_create_d> Adding DSP segment #1 to Link configuration: name='DSPLINKMEM', startAddress=0x8fe00000,
     sizeInBytes=0x100000, shared=1
    @2,654,172us: [+2 T:0x40acab60 S:0x40aca52c] OP - Processor_create_d> Adding DSP segment #2 to Link configuration: name='RESET_VECTOR', startAddress=0x8ff00000, sizeInBytes=0x80, shared=0
    @2,654,433us: [+2 T:0x40acab60 S:0x40aca52c] OP - Processor_create_d> Adding DSP segment #3 to Link configuration: name='DDRALGHEAP', startAddress=0x8ba00000,
     sizeInBytes=0x4000000, shared=0
    @2,654,692us: [+2 T:0x40acab60 S:0x40aca52c] OP - Processor_create_d> DOPOWERCONTROL was=0; now=0
    in Display_v412_create, FILE: linux/Display_v4l2.c, LINE: 148.
    @4,114,358us: [+2 T:0x412f0b60 S:0x412f03fc] ti.sdo.dmai - [Display] Video output set to size 720x480 pitch 720
    in Display_v412_create, FILE: linux/Display_v4l2.c, LINE: 163.
    @4,114,857us: [+2 T:0x412f0b60 S:0x412f024c] ti.sdo.dmai - [BufTab] Allocating BufTab for 3 buffers
    @4,428,609us: [+2 T:0x412f0b60 S:0x412f029c] ti.sdo.dmai - [Dmai] Driver buffer 0 mapped to 0x422f1000 has physical address 0x83400000
    @4,742,143us: [+2 T:0x412f0b60 S:0x412f029c] ti.sdo.dmai - [Dmai] Driver buffer 1 mapped to 0x426e6000 has physical address 0x83800000
    @5,055,652us: [+2 T:0x412f0b60 S:0x412f029c] ti.sdo.dmai - [Dmai] Driver buffer 2 mapped to 0x42adb000 has physical address 0x83c00000
    @7,132,635us: [+2 T:0x41af0b60 S:0x41af0254] ti.sdo.dmai - [Capture] Capture input set to COMPOSITE
    @7,133,027us: [+2 T:0x41af0b60 S:0x41af02f4] ti.sdo.dmai - [Capture] Video input connected size 720x480 pitch 720
    @7,133,378us: [+2 T:0x41af0b60 S:0x41af0144] ti.sdo.dmai - [BufTab] Allocating BufTab for 4 buffers
    @7,447,054us: [+2 T:0x41af0b60 S:0x41af0194] ti.sdo.dmai - [Dmai] Driver buffer 0 mapped to 0x42ed0000 has physical address 0x80800000
    @7,760,754us: [+2 T:0x41af0b60 S:0x41af0194] ti.sdo.dmai - [Dmai] Driver buffer 1 mapped to 0x432c5000 has physical address 0x80c00000
    @8,074,421us: [+2 T:0x41af0b60 S:0x41af0194] ti.sdo.dmai - [Dmai] Driver buffer 2 mapped to 0x436ba000 has physical address 0x84000000
    @8,388,110us: [+2 T:0x41af0b60 S:0x41af0194] ti.sdo.dmai - [Dmai] Driver buffer 3 mapped to 0x43aaf000 has physical address 0x84400000
    @8,404,977us: [+2 T:0x40acab60 S:0x40aca52c] OP - Processor_create_d> Attaching to DSP PROC...
    @8,414,360us: [+2 T:0x40acab60 S:0x40aca52c] OP - Processor_create_d> Opening MSGQ pool...
    @8,415,071us: [+2 T:0x40acab60 S:0x40aca52c] OP - Processor_create_d> Loading video_copy.x64P on DSP (1 args)...
    @8,752,806us: [+2 T:0x40acab60 S:0x40aca52c] OP - Processor_create_d> Starting DSP PROC...
    @8,836,658us: [+2 T:0x40acab60 S:0x40aca52c] OP - Processor_create_d> Opening remote transport...
    @8,898,134us: [+2 T:0x40acab60 S:0x40aca52c] OP - Processor_create_d> return (1)
    @8,909,693us: [+0 T:0x40acab60 S:0x40aca51c] ti.sdo.ce.osal.Sem - Entered Sem_post> sem[0x591d8]
    @8,910,074us: [+0 T:0x422f0b60 S:0x422f038c] ti.sdo.ce.osal.Sem - Leaving Sem_pend> sem[0x591d8] status[0]
    @8,910,472us: [+0 T:0x422f0b60 S:0x422f03ac] OP - doCmd> Exit (result=1)
    @8,910,668us: [+0 T:0x422f0b60 S:0x422f03cc] OP - Processor_create> return (0x61ec0)
    @8,910,856us: [+0 T:0x422f0b60 S:0x422f0404] CE - rserverOpen('video_copy.x64P'): 0x57d30 done.
    @8,911,076us: [+0 T:0x422f0b60 S:0x422f03ec] OM - Memory_alloc> Enter(0x20)
    @8,911,274us: [+0 T:0x422f0b60 S:0x422f03ec] OM - Memory_alloc> return (0x623d0)
    @8,911,479us: [+0 T:0x422f0b60 S:0x422f03dc] OC - Comm_create> Enter(queueName='encodedecode_0', queue=0x61ea0, attrs=0x0)
    @8,911,695us: [+0 T:0x422f0b60 S:0x422f03c4] OM - Memory_alloc> Enter(0x4)
    @8,911,873us: [+0 T:0x422f0b60 S:0x422f03c4] OM - Memory_alloc> return (0x623f8)
    @8,912,350us: [+0 T:0x422f0b60 S:0x422f03dc] OC - Comm_create> return (0x623f8)
    @8,912,583us: [+0 T:0x422f0b60 S:0x422f03dc] OM - Memory_free> Enter(0x623d0, 0x20)
    @8,912,786us: [+0 T:0x422f0b60 S:0x422f03dc] OM - Memory_free> return (0x1)
    @8,912,964us: [+0 T:0x422f0b60 S:0x422f03e4] OC - Comm_alloc> Enter(poolId=0x0, msg=0x61eac, size=576)
    @8,913,236us: [+0 T:0x422f0b60 S:0x422f03e4] OC - Comm_alloc> msg=0x446b2880, returning (0)
    @8,913,436us: [+0 T:0x422f0b60 S:0x422f03c4] OC - Comm_locate> Enter(queueName='rmsq', queue=0x61e9c)
    @8,919,711us: [+0 T:0x422f0b60 S:0x422f03c4] OC - Comm_locate> return (0)
    @8,919,959us: [+0 T:0x422f0b60 S:0x422f0404] CE - checkServer(0x61e90)
    @8,920,237us: [+0 T:0x422f0b60 S:0x422f03d4] OC - Comm_put> Enter(queue=0x0, msg=0x446b2880)
    @8,920,500us: [+0 T:0x422f0b60 S:0x422f03d4] OC - Comm_put> return (0)
    @8,920,752us: [+0 T:0x422f0b60 S:0x422f03cc] OC - Comm_get> Enter(queue=0x10000, msg=0x422f0448, timeout=-1)
    @8,920,989us: [+0 T:0x422f0b60 S:0x422f03cc] OC - Comm_get> MSGQ_Get() status=0x8000, return (0)
    @8,921,198us: [+0 T:0x422f0b60 S:0x422f0404] CE - rmsInit> RMS initialized(0x61e90); CE_DEBUG on, setting DSP trace mask to *+01234567,GT_prefix=12345,GT_time=3
    @8,921,469us: [+0 T:0x422f0b60 S:0x422f03d4] CE - Engine_setTrace> Enter(engine=0x61e90, mask='*+01234567,GT_prefix=12345,GT_time=3')
    @8,921,715us: [+1 T:0x422f0b60 S:0x422f03d4] CE - Engine_setTrace> Requesting DSP set trace ...
    @8,921,904us: [+0 T:0x422f0b60 S:0x422f03a4] OC - Comm_put> Enter(queue=0x0, msg=0x446b2880)
    @8,922,144us: [+0 T:0x422f0b60 S:0x422f03a4] OC - Comm_put> return (0)
    @8,922,327us: [+0 T:0x422f0b60 S:0x422f039c] OC - Comm_get> Enter(queue=0x10000, msg=0x422f040c, timeout=-1)
    @8,922,584us: [+0 T:0x40018528 S:0xbefffa74] CE - rserverOpen('video_copy.x64P'), count = 1
    @8,922,784us: [+0 T:0x40018528 S:0xbefffa74] CE - rserverOpen('video_copy.x64P'): 0x57d30 done.
    @8,922,984us: [+0 T:0x40018528 S:0xbefffa5c] OM - Memory_alloc> Enter(0x20)
    @8,923,165us: [+0 T:0x40018528 S:0xbefffa5c] OM - Memory_alloc> return (0x623d0)
    @8,923,353us: [+0 T:0x40018528 S:0xbefffa4c] OC - Comm_create> Enter(queueName='encodedecode_1', queue=0x61ef8, attrs=0x0)
    @8,923,554us: [+0 T:0x40018528 S:0xbefffa34] OM - Memory_alloc> Enter(0x4)
    @8,923,719us: [+0 T:0x40018528 S:0xbefffa34] OM - Memory_alloc> return (0x62408)
    @8,924,326us: [+0 T:0x422f0b60 S:0x422f039c] OC - Comm_get> MSGQ_Get() status=0x8000, return (0)
    @8,924,578us: [+0 T:0x422f0b60 S:0x422f03d4] CE - Engine_setTrace> return(0)
    @8,924,792us: [+0 T:0x422f0b60 S:0x422f0364] OC - Comm_put> Enter(queue=0x0, msg=0x446b2880)
    @8,925,039us: [+0 T:0x422f0b60 S:0x422f0364] OC - Comm_put> return (0)
    @8,925,291us: [+0 T:0x422f0b60 S:0x422f035c] OC - Comm_get> Enter(queue=0x10000, msg=0x422f03f0, timeout=-1)
    @8,925,534us: [+0 T:0x422f0b60 S:0x422f035c] OC - Comm_get> MSGQ_Get() status=0x8000, return (0)
    [DSP] @0,198,572tk: [+0 T:0x8ba0006c S:0x8ba03fdc] OG - Global_setSpecialTrace> enter(mask='*+01234567,GT_prefix=12345,GT_time=3')
    [DSP] @0,198,682tk: [+4 T:0x8ba0006c S:0x8ba03fdc] OG - Global_setSpecialTrace> This program was built with the following packages:
    [DSP] @0,198,761tk: [+4 T:0x8ba0006c S:0x8ba03fdc] OG -     package ti.targets.rts6000 (/opt/dvsdk_1_40_02_33/xdc_3_00_06/packages/ti/targets/rts6000/) [1,0,0,0,1203621186754]
    [DSP] @0,198,860tk: [+4 T:0x8ba0006c S:0x8ba03fdc] OG -     package ti.sdo.codecs.h264enc (/opt/dvsdk_1_40_02_33/dm6467_dvsdk_combos_1_17/packages/ti/sdo/codecs/h264enc/) [1,0,0,1221669049000]
    [DSP] @0,198,967tk: [+4 T:0x8ba0006c S:0x8ba03fdc] OG -     package ti.sdo.codecs.h264dec (/opt/dvsdk_1_40_02_33/dm6467_dvsdk_combos_1_17/packages/ti/sdo/codecs/h264dec/) [1,0,0,1221669049000]
    [DSP] @0,199,074tk: [+4 T:0x8ba0006c S:0x8ba03fdc] OG -     package ti.sdo.codecs.hdvicp (/opt/dvsdk_1_40_02_33/dm6467_dvsdk_combos_1_17/packages/ti/sdo/codecs/hdvicp/) [1,0,0,1221669049000]
    [DSP] @0,199,180tk: [+4 T:0x8ba0006c S:0x8ba03fdc] OG -     package ti.xdais.dm.examples.viddec2_copy (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/examples/ti/xdais/dm/examples/viddec2_copy/) [2,0,0,1372427230000]
    [DSP] @0,199,296tk: [+4 T:0x8ba0006c S:0x8ba03fdc] OG -     package ti.xdais.dm.examples.videnc1_copy (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/examples/ti/xdais/dm/examples/videnc1_copy/) [2,0,0,1372399186000]
    [DSP] @0,199,413tk: [+4 T:0x8ba0006c S:0x8ba03fdc] OG -     package ti.sdo.fc.ires.hdvicp (/opt/dvsdk_1_40_02_33/framework_components_2_10_02/packages/ti/sdo/fc/ires/hdvicp/) [1,0,0,1220943209717]
    [DSP] @0,199,522tk: [+4 T:0x8ba0006c S:0x8ba03fdc] OG -     package ti.sdo.fc.hdintc (/opt/dvsdk_1_40_02_33/framework_components_2_10_02/packages/ti/sdo/fc/hdintc/) [1,0,3,1220943133917]
    [DSP] @0,199,626tk: [+4 T:0x8ba0006c S:0x8ba03fdc] OG -     package ti.sdo.edma3.rm (/opt/dvsdk_1_40_02_33/edma3_lld_1_04_00/packages/ti/sdo/edma3/rm/) [1,3,2,1201524583021]
    [DSP] @0,199,724tk: [+4 T:0x8ba0006c S:0x8ba03fdc] OG -     package ti.sdo.fc.ires.nullresource (/opt/dvsdk_1_40_02_33/framework_components_2_10_02/packages/ti/sdo/fc/ires/nullresource/) [1,0,0,1220943221523]
    [DSP] @0,199,839tk: [+4 T:0x8ba0006c S:0x8ba03fdc] OG -     package ti.xdais.dm (/opt/dvsdk_1_40_02_33/xdais_6_10_01/packages/ti/xdais/dm/) [1,0,4,1210262746529]
    [DSP] @0,199,931tk: [+4 T:0x8ba0006c S:0x8ba03fdc] OG -     package ti.xdais (/opt/dvsdk_1_40_02_33/xdais_6_10_01/packages/ti/xdais/) [1,2,1,1210262742149]
    [DSP] @0,200,020tk: [+4 T:0x8ba0006c S:0x8ba03fdc] OG -     package ti.sdo.ce.node (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/node/) [1,0,0,1220951122176]
    [DSP] @0,200,129tk: [+4 T:0x8ba0006c S:0x8ba03fdc] OG -     package ti.sdo.ce.ipc.dsplink.dsp (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/ipc/dsplink/dsp/) [2,0,1,1220951102696]
    [DSP] @0,200,238tk: [+4 T:0x8ba0006c S:0x8ba03fdc] OG -     package ti.sdo.utils.trace (/opt/dvsdk_1_40_02_33/framework_components_2_10_02/packages/ti/sdo/utils/trace/) [1,0,0,1220943366422]
    [DSP] @0,200,344tk: [+4 T:0x8ba0006c S:0x8ba03fdc] OG -     package ti.sdo.ce.utils.xdm (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/utils/xdm/) [1,0,1,1220951322616]
    [DSP] @0,200,447tk: [+4 T:0x8ba0006c S:0x8ba03fdc] OG -     package ti.sdo.fc.dman3 (/opt/dvsdk_1_40_02_33/framework_components_2_10_02/packages/ti/sdo/fc/dman3/) [1,0,3,1220943053023]
    [DSP] @0,200,550tk: [+4 T:0x8ba0006c S:0x8ba03fdc] OG -     package ti.sdo.fc.acpy3 (/opt/dvsdk_1_40_02_33/framework_components_2_10_02/packages/ti/sdo/fc/acpy3/) [1,0,2,1220943014135]
    [DSP] @0,200,654tk: [+4 T:0x8ba0006c S:0x8ba03fdc] OG -     package dsplink.dsp (/opt/dvsdk_1_40_02_33/dsplink-davinci-v1.50-prebuilt/packages/dsplink/dsp/) [1,3,0]
    [DSP] @0,200,747tk: [+4 T:0x8ba0006c S:0x8ba03fdc] OG -     package ti.rtdx (/opt/dvsdk_1_40_02_33/bios_5_32_01/packages/ti/rtdx/) [2,0,0,1,1194024051755]
    [DSP] @0,200,836tk: [+4 T:0x8ba0006c S:0x8ba03fdc] OG -     package ti.psl (/opt/dvsdk_1_40_02_33/bios_5_32_01/packages/ti/psl/) [5,0,0,0,0]
    [DSP] @0,200,918tk: [+4 T:0x8ba0006c S:0x8ba03fdc] OG -     package ti@8,927,012us: [+0 T:0x40018528 S:0xbefffa4c] OC - Comm_create> return (0x62408)
    @9,268,045us: [+0 T:0x40018528 S:0xbefffa4c] OM - Memory_free> Enter(0x623d0, 0x20)
    @9,268,245us: [+0 T:0x40018528 S:0xbefffa4c] OM - Memory_free> return (0x1)
    @9,603,318us: [+0 T:0x422f0b60 S:0x422f0364] OC - Comm_put> Enter(queue=0x0, msg=0x446b2880)
    @9,603,632us: [+0 T:0x422f0b60 S:0x422f0364] OC - Comm_put> return (0)
    @9,603,915us: [+0 T:0x422f0b60 S:0x422f035c] OC - Comm_get> Enter(queue=0x10000, msg=0x422f03f0, timeout=-1)
    @9,604,162us: [+0 T:0x422f0b60 S:0x422f035c] OC - Comm_get> MSGQ_Get() status=0x8000, return (0)
    .bios (/opt/dvsdk_1_40_02_33/bios_5_32_01/packages/ti/bios/) [5,2,4,9,1193960176642]
    [DSP] @0,201,007tk: [+4 T:0x8ba0006c S:0x8ba03fdc] OG -     package ti.sdo.fc.dskt2 (/opt/dvsdk_1_40_02_33/framework_components_2_10_02/packages/ti/sdo/fc/dskt2/) [1,0,4,1220943095081]
    [DSP] @0,201,109tk: [+4 T:0x8ba0006c S:0x8ba03fdc] OG -     package ti.sdo.fc.rman (/opt/dvsdk_1_40_02_33/framework_components_2_10_02/packages/ti/sdo/fc/rman/) [2,0,0,1220943245318]
    [DSP] @0,201,212tk: [+4 T:0x8ba0006c S:0x8ba03fdc] OG -     package ti.sdo.fc.edma3 (/opt/dvsdk_1_40_02_33/framework_components_2_10_02/packages/ti/sdo/fc/edma3/) [2,0,0,1220943121256]
    [DSP] @0,201,315tk: [+4 T:0x8ba0006c S:0x8ba03fdc] OG -     package ti.sdo.fc.ires.edma3chan (/opt/dvsdk_1_40_02_33/framework_components_2_10_02/packages/ti/sdo/fc/ires/edma3chan/) [1,0,0,1220943162542]
    [DSP] @0,201,427tk: [+4 T:0x8ba0006c S:0x8ba03fdc] OG -     package ti.bios.utils (/opt/dvsdk_1_40_02_33/biosutils_1_01_00/packages/ti/bios/utils/) [2,0,1,0,1191891830609]
    [DSP] @0,201,524tk: [+4 T:0x8ba0006c S:0x8ba03fdc] OG -     package ti.catalog.c6000 (/opt/dvsdk_1_40_02_33/xdc_3_00_06/packages/ti/catalog/c6000/) [1,0,0,0,1203561781695]
    [DSP] @0,201,621tk: [+4 T:0x8ba0006c S:0x8ba03fdc] OG -     package ti.platforms.evmDM6467 (/opt/dvsdk_1_40_02_33/bios_5_32_01/packages/ti/platforms/evmDM6467/) [1,0,0,0,1192229633217]
    [DSP] @0,201,724tk: [+4 T:0x8ba0006c S:0x8ba03fdc] OG -     package ti.sdo.ce.osal (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/osal/) [2,0,2,1220951127739]
    [DSP] @0,201,822tk: [+4 T:0x8ba0006c S:0x8ba03fdc] OG -     package ti.sdo.ce.ipc (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/ipc/) [2,0,1,1220951085787]
    [DSP] @0,201,920tk: [+4 T:0x8ba0006c S:0x8ba03fdc] OG -     package ti.sdo.ce.alg (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/alg/) [1,0,1,1220950781938]
    [DSP] @0,202,017tk: [+4 T:0x8ba0006c S:0x8ba03fdc] OG -     package ti.sdo.ce.osal.bios (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/osal/bios/) [2,0,1,1220951133740]
    [DSP] @0,202,120tk: [+4 T:0x8ba0006c S:0x8ba03fdc] OG -     package ti.sdo.ce.ipc.bios (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/ipc/bios/) [2,0,1,1220951091097]
    [DSP] @0,202,222tk: [+4 T:0x8ba0006c S:0x8ba03fdc] OG -     package ti.sdo.ce (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/) [1,0,6,1220950771473]
    [DSP] @0,202,316tk: [+4 T:0x8ba0006c S:0x8ba03fdc] OG -     package ti.sdo.ce.bioslog (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/bioslog/)
     [1,0,1,1220950800329]
    [DSP] @0,202,420tk: [+4 T:0x8ba0006c S:0x8ba03fdc] OG -     package ti.sdo.ce.video1 (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/video1/) [1,0,2,1220951340734]
    [DSP] @0,202,521tk: [+4 T:0x8ba0006c S:0x8ba03fdc] OG -     package ti.sdo.codecs.h264enc.ce (/opt/dvsdk_1_40_02_33/dm6467_dvsdk_combos_1_17/packages/ti/sdo/codecs/h264enc/ce/) [1,0,0,1221669049000]
    [DSP] @0,202,631tk: [+4 T:0x8ba0006c S:0x8ba03fdc] OG -     package ti.sdo.ce.video2 (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/packages/ti/sdo/ce/video2/) [1,0,2,1220951347240]
    [DSP] @0,202,731tk: [+4 T:0x8ba0006c S:0x8ba03fdc] OG -     package ti.sdo.codecs.h264dec.ce (/opt/dvsdk_1_40_02_33/dm6467_dvsdk_combos_1_17/packages/ti/sdo/codecs/h264dec/ce/) [1,0,0,1221669049000]
    [DSP] @0,202,841tk: [+4 T:0x8ba0006c S:0x8ba03fdc] OG -     package ti.sdo.ce.examples.codecs.viddec2_copy (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/examples/ti/sdo/ce/examples/codecs/viddec2_copy/) []
    [DSP] @0,202,953tk: [+4 T:0x8ba0006c S:0x8ba03fdc] OG -     package ti.sdo.ce.examples.codecs.videnc1_copy (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/examples/ti/sdo/ce/examples/codecs/videnc1_copy/) []
    [DSP] @0,203,066tk: [+4 T:0x8ba0006c S:0x8ba03fdc] OG -     package video_copy (/opt/dvsdk_1_40_02_33/codec_engine_2_10_02/examples/ti/sdo/ce/examples/servers/video_copy/evmDM6467/video_copy/) []
    [DSP] @0,203,174tk: [+0 T:0x8ba0006c S:0x8ba03fdc] OG - Global_setSpecialTrace> return
    [DSP] @0,205,257tk: [+0 T:0x8ba0006c S:0x8ba04024] CR - processRmsCmd(0x8fe038a8, 4056): cmd = 5
    [DSP] @0,205,333tk: [+0 T:0@9,611,021us: [+0 T:0x40018528 S:0xbefffa54] OC - Comm_alloc> Enter(poolId=0x0, msg=0x61f04, size=576)
    @9,947,046us: [+0 T:0x40018528 S:0xbefffa54] OC - Comm_alloc> msg=0x446b3880, returning (0)
    @9,947,248us: [+0 T:0x40018528 S:0xbefffa34] OC - Comm_locate> Enter(queueName='rmsq', queue=0x61ef4)
    @9,954,383us: [+0 T:0x40018528 S:0xbefffa34] OC - Comm_locate> return (0)
    @9,954,603us: [+0 T:0x40018528 S:0xbefffa74] CE - checkServer(0x61ee8)
    @9,954,803us: [+0 T:0x40018528 S:0xbefffa44] OC - Comm_put> Enter(queue=0x0, msg=0x446b3880)
    @9,955,046us: [+0 T:0x40018528 S:0xbefffa44] OC - Comm_put> return (0)
    @9,955,295us: [+0 T:0x40018528 S:0xbefffa3c] OC - Comm_get> Enter(queue=0x10001, msg=0xbefffab8, timeout=-1)
    @9,955,522us: [+0 T:0x40018528 S:0xbefffa3c] OC - Comm_get> MSGQ_Get() status=0x8000, return (0)
    @9,960,108us: [+0 T:0x40acab60 S:0x40aca52c] ti.sdo.ce.osal.Sem - Leaving Sem_post> sem[0x591d8]
    @9,960,385us: [+0 T:0x422f0b60 S:0x422f0364] OC - Comm_put> Enter(queue=0x0, msg=0x446b2880)
    @9,960,666us: [+0 T:0x422f0b60 S:0x422f0364] OC - Comm_put> return (0)
    @9,960,946us: [+0 T:0x422f0b60 S:0x422f035c] OC - Comm_get> Enter(queue=0x10000, msg=0x422f03f0, timeout=-1)
    @9,961,185us: [+0 T:0x422f0b60 S:0x422f035c] OC - Comm_get> MSGQ_Get() status=0x8000, return (0)
    x8ba0006c S:0x8ba04024] CR - remote time = 0x0, trace buffer size = 4032
    [DSP] @1,779,784tk: [+0 T:0x8ba0006c S:0x8ba04024] CR - processRmsCmd(0x8fe038a8, 4056): cmd = 5
    [DSP] @1,779,854tk: [+0 T:0x8ba0006c S:0x8ba04024] CR - remote time = 0x0, trace buffer size = 4032
    [DSP] @2,595,183tk: [+0 T:0x8ba0006c S:0x8ba04024] CR - processRmsCmd(0x8fe048a8, 4056): cmd = 6
    [DSP] @2,608,214tk: [+0 T:0x8ba0006c S:0x8ba04024] CR - processRmsCmd(0x8fe038a8, 4056): cmd = 5
    [DSP] @2,608,282tk: [+0 T:0x8ba0006c S:0x8ba04024] CR - remote time = 0x0, trace buffer size = 4032
    @9,962,521us: [+0 T:0x422f0b60 S:0x422f0394] CE - Engine_fwriteTrace> returning count [8916]
    @9,962,745us: [+0 T:0x422f0b60 S:0x422f0404] CE - Engine_open> return(401040)
    @9,962,999us: [+2 T:0x422f0b60 S:0x422f038c] ti.sdo.dmai - [Venc1] Creating encoder videnc1_copy for max 720x480 bitrate 2000000 ratectrl 4
    @9,963,318us: [+0 T:0x422f0b60 S:0x422f036c] ti.sdo.ce.video1.VIDENC1 - VIDENC1_create> Enter (engine=0x61e90, name='videnc1_copy', params=0x422f0568)
    @9,963,593us: [+0 T:0x422f0b60 S:0x422f033c] CV - VISA_create(0x61e90, 'videnc1_copy', 0x422f0568, 0x2496, 'ti.sdo.ce.video1.IVIDENC1')
    @9,963,828us: [+0 T:0x422f0b60 S:0x422f0264] CV - VISA_create2(0x61e90, 'videnc1_copy', 0x422f0568, 0x30, 0x2496, 'ti.sdo.ce.video1.IVIDENC1')
    @9,964,086us: [+0 T:0x422f0b60 S:0x422f024c] OM - Memory_alloc> Enter(0x30)
    @9,964,278us: [+0 T:0x422f0b60 S:0x422f024c] OM - Memory_alloc> return (0x62550)
    @9,964,460us: [+0 T:0x422f0b60 S:0x422f01f4] CE - Engine_createNode(0x61e90, 'videnc1_copy', 2496, 0x422f0568, 0x30, 0x422f0368)
    @9,964,687us: [+0 T:0x422f0b60 S:0x422f01dc] OM - Memory_alloc> Enter(0x20)
    @9,964,864us: [+0 T:0x422f0b60 S:0x422f01dc] OM - Memory_alloc> return (0x623d0)
    @9,965,091us: [+0 T:0x422f0b60 S:0x422f01cc] OC - Comm_create> Enter(queueName='gppfromnode_2', queue=0x623d8, attrs=0x0)
    @9,965,311us: [+0 T:0x422f0b60 S:0x422f01b4] OM - Memory_alloc> Enter(0x4)
    @9,965,490us: [+0 T:0x422f0b60 S:0x422f01b4] OM - Memory_alloc> return (0x62588)
    @10,301,999us: [+0 T:0x422f0b60 S:0x422f01cc] OC - Comm_create> return (0x62588)
    @10,302,245us: [+0 T:0x422f0b60 S:0x422f01d4] OC - Comm_put> Enter(queue=0x0, msg=0x446b2880)
    @10,302,502us: [+0 T:0x422f0b60 S:0x422f01d4] OC - Comm_put> return (0)
    @10,302,691us: [+0 T:0x422f0b60 S:0x422f01cc] OC - Comm_get> Enter(queue=0x10000, msg=0x422f024c, timeout=-1)
    @10,302,950us: [+0 T:0x40018528 S:0xbefff9d4] OC - Comm_put> Enter(queue=0x0, msg=0x446b3880)
    @10,303,223us: [+0 T:0x40018528 S:0xbefff9d4] OC - Comm_put> return (0)
    @10,303,622us: [+0 T:0x422f0b60 S:0x422f01cc] OC - Comm_get> MSGQ_Get() status=0x8000, return (0)
    @10,303,861us: [+0 T:0x422f0b60 S:0x422f01d4] OC - Comm_put> Enter(queue=0x0, msg=0x446b2880)
    @10,304,112us: [+0 T:0x422f0b60 S:0x422f01d4] OC - Comm_put> return (0)
    @10,304,391us: [+0 T:0x422f0b60 S:0x422f01cc] OC - Comm_get> Enter(queue=0x10000, msg=0x422f024c, timeout=-1)
    @10,304,650us: [+0 T:0x422f0b60 S:0x422f01cc] OC - Comm_get> MSGQ_Get() status=0x8000, return (0)
    @10,304,859us: [+4 T:0x422f0b60 S:0x422f01f4] CE - Engine_createNode> created node(stdIn=0x2, stdOut=0x10002, msgq=0x62588, algName='videnc1_copy', rmsNode=0x8fa70678, algHandle=0x8fa70760)
    @10,305,170us: [+0 T:0x40018528 S:0xbefff9cc] OC - Comm_get> Enter(queue=0x10001, msg=0xbefffa60, timeout=-1)
    @10,305,407us: [+0 T:0x40018528 S:0xbefff9cc] OC - Comm_get> MSGQ_Get() status=0x8000, return (0)
    [DSP] @3,401,392tk: [+0 T:0x8ba0006c S:0x8ba04024] CR - processRmsCmd(0x8fe038a8, 4056): cmd = 0
    [DSP] @3,401,471tk: [+0 T:0x8ba0006c S:0x8ba03f14] OM - Memory_alloc> Enter(size=0x18)
    [DSP] @3,401,535tk: [+0 T:0x8ba0006c S:0x8ba03f14] OM - Memory_alloc> return (0x8fa70678)
    [DSP] @3,401,600tk: [+0 T:0x8ba0006c S:0x8ba03f14] OM - Memory_alloc> Enter(size=0xf)
    [DSP] @3,401,657tk: [+0 T:0x8ba0006c S:0x8ba03f14] OM - Memory_alloc> return (0x8fa70690)
    [DSP] @3,401,725tk: [+0 T:0x8ba0006c S:0x8ba03edc] OM - Memory_alloc> Enter(size=0x20)
    [DSP] @3,401,782tk: [+0 T:0x8ba0006c S:0x8ba03edc] OM - Memory_alloc> return (0x8fa706a0)
    [DSP] @3,401,842tk: [+0 T:0x8ba0006c S:0x8ba03eac] OM - Memory_alloc> Enter(size=0x24)
    [DSP] @3,401,900tk: [+0 T:0x8ba0006c S:0x8ba03eac] OM - Memory_alloc> return (0x8fa706c0)
    [DSP] @3,401,986tk: [+0 T:0x8ba0006c S:0x8ba03ec4] ti.sdo.ce.video1.VIDENC1 - VIDENC1_create> Enter (engine=0x0, name='videnc1_copy', params=0x8fe038c0)
    [DSP] @3,402,087tk: [+0 T:0x8ba0006c S:0x8ba03e8c] CV - VISA_create(0x0, 'videnc1_copy', 0x8fe038c0, 0x2496, 'ti.sdo.ce.video1.IVIDENC1')
    [DSP] @3,402,190tk: [+0 T:0x8ba0006c S:0x8ba03d7c] CV - VISA_create2(0x0, 'videnc1_copy', 0x8fe038c0, 0x30, 0x2496, 'ti.sdo.ce.video1.IVIDENC1')
    [DSP] @3,402,293tk: [+0 T:0x8ba0006c S:0x8ba03d14] CE - Engine_open> Enter('local', 0x8ba03d6c, 0x9c0)
    [DSP] @3,402,366tk: [+0 T:0x8ba0006c S:0x8ba03cf4] OM - Memory_alloc> Enter(size=0x2c)
    [DSP] @3,402,424tk: [+0 T:0x8ba0006c S:0x8ba03cf4] OM - Memory_alloc> return (0x8fa70730)
    [DSP] @3,402,492tk: [+0 T:0x8ba0006c S:0x8ba03d14] CE - Engine_open> return(-1884879056)
    [DSP] @3,402,564tk: [+0 T:0x8ba0006c S:0x8ba03d5c] OM - Memory_alloc> Enter(size=0x30)
    [DSP] @3,402,622tk: [+0 T:0x8ba0006c S:0x8ba03d5c] OM - Memory_alloc> return (0x8fa70760)
    [DSP] @3,402,685tk: [+0 T:0x8ba0006c S:0x8ba03d34] ti.sdo.ce.alg.Algorithm - Algorithm_create> Enter(fxns=0x8fa6fdb0, idma3Fxns=0x0, params=0x8fe038c0, attrs=0x8ba03e80)
    [DSP] @3,402,791tk: [+0 T:0x8ba0006c S:0x8ba03d14] OM - Memory_alloc> Enter(size=0x10)
    [DSP] @3,402,848tk: [+0 T:0x8ba0006c S:0x8ba03d14] OM - Memory_alloc> return (0x8fa70790)
    [DSP] @3,402,983tk: [+0 T:0x8ba0006c S:0x8ba03d34] ti.sdo.ce.alg.Algorithm - Algorithm_create> return (0x8fa70790)
    [DSP] @3,403,131tk: [+0 T:0x8ba0006c S:0x8ba03ec4] ti.sdo.ce.video1.VIDENC1 - VIDENC1_create> return (0x8fa70760)
    [DSP] @3,403,207tk: [+4 T:0x8ba0006c S:0x8ba03e84] OT - Thread_create > name: "videnc1_copy#0", pri:  -1, stack size:  11096, stack seg: 0
    [DSP] @3,403,441tk: [+0 T:0x8ba0006c S:0x8ba04024] CR - processRmsCmd(0x8fe048a8, 4056): cmd = 5
    [DSP] @3,403,514tk: [+0 T:0x8ba0006c S:0x8ba04024] CR - remote time = 0x0, trace buffer size = 4032
    @10,330,319us: [+0 T:0x40acab60 S:0x40aca52c] OP - getCmd_d> Enter (proc=0x40aca5a4)
    @10,670,373us: [+0 T:0x40018528 S:0xbefffa04] CE - Engine_fwriteTrace> returning count [2698]
    @10,670,635us: [+0 T:0x422f0b60 S:0x422f0154] OC - Comm_put> Enter(queue=0x0, msg=0x446b2880)
    @10,670,901us: [+0 T:0x422f0b60 S:0x422f0154] OC - Comm_put> return (0)
    @10,671,160us: [+0 T:0x422f0b60 S:0x422f014c] OC - Comm_get> Enter(queue=0x10000, msg=0x422f01e0, timeout=-1)
    @10,671,399us: [+0 T:0x422f0b60 S:0x422f014c] OC - Comm_get> MSGQ_Get() status=0x8000, return (0)
    [DSP] @3,405,139tk: [+0 T:0x8ba0006c S:0x8ba04024] CR - processRmsCmd(0x8fe038a8, 4056): cmd = 1
    [DSP] @4,256,190tk: [+0 T:0x8ba0006c S:0x8ba04024] CR - processRmsCmd(0x8fe038a8, 4056): cmd = 5
    [DSP] @4,256,259tk: [+0 T:0x8ba0006c S:0x8ba04024] CR - remote time = 0x0, trace buffer size = 4032
    @10,672,230us: [+0 T:0x422f0b60 S:0x422f0184] CE - Engine_fwriteTrace> returning count [294]
    @10,672,438us: [+2 T:0x422f0b60 S:0x422f01f4] CE - Engine_createNode> Returning 0x623d0
    @10,672,627us: [+0 T:0x422f0b60 S:0x422f024c] OM - Memory_alloc> Enter(0x4)
    @10,672,814us: [+0 T:0x422f0b60 S:0x422f024c] OM - Memory_alloc> return (0x62598)
    @10,672,996us: [+0 T:0x422f0b60 S:0x422f0244] OC - Comm_alloc> Enter(poolId=0x0, msg=0x62598, size=2496)
    @10,673,227us: [+0 T:0x422f0b60 S:0x422f0244] OC - Comm_alloc> msg=0x446b4880, returning (0)
    @10,673,444us: [+5 T:0x422f0b60 S:0x422f0264] CV - VISA_create> remote codec created (name='videnc1_copy', localQueueID=0x10002, remoteQueueID=0x0002)
    @10,673,692us: [+0 T:0x422f0b60 S:0x422f036c] ti.sdo.ce.video1.VIDENC1 - VIDENC1_create> return (0x62550)
    @10,673,890us: [+2 T:0x422f0b60 S:0x422f038c] ti.sdo.dmai - [Venc1] Setting dynParams size 720x480 bitrate 2000000
    @10,674,144us: [+0 T:0x422f0b60 S:0x422f0304] ti.sdo.ce.video1.VIDENC1 - VIDENC1_control> Enter (handle=0x62550, id=1, params=0x422f04a8 (size=0x3c), status=0x422f03d8 (size=0x9c)
    @10,674,424us: [+5 T:0x422f0b60 S:0x422f02cc] CV - VISA_allocMsg> Allocating message for messageId=0x00026cc2
    @10,674,660us: [+0 T:0x422f0b60 S:0x422f02ac] CV - VISA_call(visa=0x62550, msg=0x446b4880): messageId=0x00026cc2, command=0x1
    @10,674,888us: [+0 T:0x422f0b60 S:0x422f027c] OC - Comm_put> Enter(queue=0x2, msg=0x446b4880)
    @10,675,130us: [+0 T:0x422f0b60 S:0x422f027c] OC - Comm_put> return (0)
    @10,675,314us: [+0 T:0x422f0b60 S:0x422f0274] OC - Comm_get> Enter(queue=0x10002, msg=0x422f030c, timeout=-1)
    @10,675,603us: [+0 T:0x422f0b60 S:0x422f0274] OC - Comm_get> MSGQ_Get() status=0x8000, return (0)
    @10,675,832us: [+0 T:0x422f0b60 S:0x422f01fc] OC - Comm_put> Enter(queue=0x0, msg=0x446b2880)
    @10,676,074us: [+0 T:0x422f0b60 S:0x422f01fc] OC - Comm_put> return (0)
    @10,676,322us: [+0 T:0x422f0b60 S:0x422f01f4] OC - Comm_get> Enter(queue=0x10000, msg=0x422f0288, timeout=-1)
    @10,676,557us: [+0 T:0x422f0b60 S:0x422f01f4] OC - Comm_get> MSGQ_Get() status=0x8000, return (0)
    [DSP] @4,266,007tk: [+5 T:0x8ba051fc S:0x8ba07d14] CN - NODE> 0x8fa706a0(videnc1_copy#0) call(algHandle=0x8fa70760, msg=0x8fe05880); messageId=0x00026cc2
    [DSP] @4,266,122tk: [+0 T:0x8ba051fc S:0x8ba07b6c] ti.sdo.ce.video1.VIDENC1 - VIDENC1_control> Enter (handle=0x8fa70760, id=1, params=0x8fe058b4 (size=0x3c),
    status=0x8fe058f0 (size=0x9c)
    [DSP] @4,266,249tk: [+5 T:0x8ba051fc S:0x8ba07b4c] CV - VISA_enter(visa=0x8fa70760): algHandle = 0x8fa70790
    [DSP] @4,266,324tk: [+0 T:0x8ba051fc S:0x8ba07b2c] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Enter(handle=0x8fa70790)
    [DSP] @4,266,408tk: [+0 T:0x8ba051fc S:0x8ba07b2c] ti.sdo.ce.alg.Algorithm - Algorithm_activate> return
    [DSP] @4,266,480tk: [+5 T:0x8ba051fc S:0x8ba07b4c] CV - VISA_exit(visa=0x8fa70760): algHandle = 0x8fa70790
    [DSP] @4,266,555tk: [+0 T:0x8ba051fc S:0x8ba07b2c] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> Enter(handle=0x8fa70790)
    [DSP] @4,266,634tk: [+0 T:0x8ba051fc S:0x8ba07b2c] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> return
    [DSP] @4,266,701tk: [+0 T:0x8ba051fc S:0x8ba07b6c] ti.sdo.ce.video1.VIDENC1 - VIDENC1_control> Exit (handle=0x8fa70760, retVal=0x0)
    [DSP] @4,266,788tk: [+5 T:0x8ba051fc S:0x8ba07d14] CN - NODE> returned from call(algHandle=0x8fa70760, msg=0x8fe05880); messageId=0x00026cc2
    [DSP] @4,268,207tk: [+0 T:0x8ba0006c S:0x8ba04024] CR - processRmsCmd(0x8fe038a8, 4056): cmd = 5
    [DSP] @4,268,280tk: [+0 T:0x8ba0006c S:0x8ba04024] CR - remote time = 0x0, trace buffer size = 4032
    @10,678,910us: [+0 T:0x40018528 S:0xbefffa74] CE - Engine_open> return(401128)
    ctrlFxnFUN: FILE: ../ctrl.c, LINE: 319.
    ctrlFxnFUN: FILE: ../ctrl.c, LINE: 335.
    @11,031,470us: [+0 T:0x40acab60 S:0x40aca50c] ti.sdo.ce.osal.Sem - Entered Sem_pend> sem[0x591c0] timeout[0xffffffff]
    @11,031,912us: [+0 T:0x422f0b60 S:0x422f022c] CE - Engine_fwriteTrace> returning count [1483]
    @11,032,143us: [+0 T:0x422f0b60 S:0x422f02ac] CV - VISA_call Completed: messageId=0x00026cc2, command=0x1, return(status=0)
    @11,032,378us: [+5 T:0x422f0b60 S:0x422f02bc] CV - VISA_freeMsg(0x62550, 0x446b4880): Freeing message with messageId=0x00026cc2
    @11,032,605us: [+0 T:0x422f0b60 S:0x422f0304] ti.sdo.ce.video1.VIDENC1 - VIDENC1_control> Exit (handle=0x62550, retVal=0x0)
    @11,032,819us: [+2 T:0x422f0b60 S:0x422f038c] ti.sdo.dmai - [Venc1] Made XDM_SETPARAMS control call
    @11,033,008us: [+0 T:0x422f0b60 S:0x422f0304] ti.sdo.ce.video1.VIDENC1 - VIDENC1_control> Enter (handle=0x62550, id=5, params=0x422f04a8 (size=0x3c), status=0x422f03d8 (size=0x9c)
    @11,033,275us: [+5 T:0x422f0b60 S:0x422f02cc] CV - VISA_allocMsg> Allocating message for messageId=0x00026cc3
    @11,033,478us: [+0 T:0x422f0b60 S:0x422f02ac] CV - VISA_call(visa=0x62550, msg=0x446b4880): messageId=0x00026cc3, command=0x1
    @11,033,729us: [+0 T:0x422f0b60 S:0x422f027c] OC - Comm_put> Enter(queue=0x2, msg=0x446b4880)
    @11,033,980us: [+0 T:0x422f0b60 S:0x422f027c] OC - Comm_put> return (0)
    @11,034,170us: [+0 T:0x422f0b60 S:0x422f0274] OC - Comm_get> Enter(queue=0x10002, msg=0x422f030c, timeout=-1)
    @11,034,471us: [+0 T:0x422f0b60 S:0x422f0274] OC - Comm_get> MSGQ_Get() status=0x8000, return (0)
    @11,034,702us: [+0 T:0x422f0b60 S:0x422f01fc] OC - Comm_put> Enter(queue=0x0, msg=0x446b2880)
    @11,034,946us: [+0 T:0x422f0b60 S:0x422f01fc] OC - Comm_put> return (0)
    @11,035,190us: [+0 T:0x422f0b60 S:0x422f01f4] OC - Comm_get> Enter(queue=0x10000, msg=0x422f0288, timeout=-1)
    @11,035,427us: [+0 T:0x422f0b60 S:0x422f01f4] OC - Comm_get> MSGQ_Get() status=0x8000, return (0)
    [DSP] @5,098,650tk: [+5 T:0x8ba051fc S:0x8ba07d14] CN - NODE> 0x8fa706a0(videnc1_copy#0) call(algHandle=0x8fa70760, msg=0x8fe05880); messageId=0x00026cc3
    [DSP] @5,098,756tk: [+0 T:0x8ba051fc S:0x8ba07b6c] ti.sdo.ce.video1.VIDENC1 - VIDENC1_control> Enter (handle=0x8fa70760, id=5, params=0x8fe058b4 (size=0x3c),
    status=0x8fe058f0 (size=0x9c)
    [DSP] @5,098,879tk: [+5 T:0x8ba051fc S:0x8ba07b4c] CV - VISA_enter(visa=0x8fa70760): algHandle = 0x8fa70790
    [DSP] @5,098,952tk: [+0 T:0x8ba051fc S:0x8ba07b2c] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Enter(handle=0x8fa70790)
    [DSP] @5,099,030tk: [+0 T:0x8ba051fc S:0x8ba07b2c] ti.sdo.ce.alg.Algorithm - Algorithm_activate> return
    [DSP] @5,099,096tk: [+5 T:0x8ba051fc S:0x8ba07b4c] CV - VISA_exit(visa=0x8fa70760): algHandle = 0x8fa70790
    [DSP] @5,099,169tk: [+0 T:0x8ba051fc S:0x8ba07b2c] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> Enter(handle=0x8fa70790)
    [DSP] @5,099,245tk: [+0 T:0x8ba051fc S:0x8ba07b2c] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> return
    [DSP] @5,099,311tk: [+0 T:0x8ba051fc S:0x8ba07b6c] ti.sdo.ce.video1.VIDENC1 - VIDENC1_control> Exit (handle=0x8fa70760, retVal=0x0)
    [DSP] @5,099,396tk: [+5 T:0x8ba051fc S:0x8ba07d14] CN - NODE> returned from call(algHandle=0x8fa70760, msg=0x8fe05880); messageId=0x00026cc3
    [DSP] @5,100,902tk: [+0 T:0x8ba0006c S:0x8ba04024] CR - processRmsCmd(0x8fe038a8, 4056): cmd = 5
    [DSP] @5,100,973tk: [+0 T:0x8ba0006c S:0x8ba04024] CR - remote time = 0x0, trace buffer size = 4032
    @11,038,456us: [+0 T:0x422f0b60 S:0x422f022c] CE - Engine_fwriteTrace> returning count [1483]
    @11,038,670us: [+0 T:0x422f0b60 S:0x422f02ac] CV - VISA_call Completed: messageId=0x00026cc3, command=0x1, return(status=0)
    @11,372,821us: [+5 T:0x422f0b60 S:0x422f02bc] CV - VISA_freeMsg(0x62550, 0x446b4880): Freeing message with messageId=0x00026cc3
    @11,373,082us: [+0 T:0x422f0b60 S:0x422f0304] ti.sdo.ce.video1.VIDENC1 - VIDENC1_control> Exit (handle=0x62550, retVal=0x0)
    @11,373,352us: [+0 T:0x422f0b60 S:0x422f036c] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_create> Enter (engine=0x61e90, name='viddec2_copy', params=0x422f05b8)
    @11,373,603us: [+0 T:0x422f0b60 S:0x422f033c] CV - VISA_create(0x61e90, 'viddec2_copy', 0x422f05b8, 0x2484, 'ti.sdo.ce.video2.IVIDDEC2')
    @11,373,834us: [+0 T:0x422f0b60 S:0x422f0264] CV - VISA_create2(0x61e90, 'viddec2_copy', 0x422f05b8, 0x1c, 0x2484, 'ti.sdo.ce.video2.IVIDDEC2')
    @11,374,083us: [+0 T:0x422f0b60 S:0x422f024c] OM - Memory_alloc> Enter(0x30)
    @11,374,277us: [+0 T:0x422f0b60 S:0x422f024c] OM - Memory_alloc> return (0x62770)
    @11,374,460us: [+0 T:0x422f0b60 S:0x422f01f4] CE - Engine_createNode(0x61e90, 'viddec2_copy', 2484, 0x422f05b8, 0x1c, 0x422f0368)
    @11,374,687us: [+0 T:0x422f0b60 S:0x422f01dc] OM - Memory_alloc> Enter(0x20)
    @11,374,866us: [+0 T:0x422f0b60 S:0x422f01dc] OM - Memory_alloc> return (0x627a8)
    @11,375,063us: [+0 T:0x422f0b60 S:0x422f01cc] OC - Comm_create> Enter(queueName='gppfromnode_3', queue=0x627b0, attrs=0x0)
    @11,375,278us: [+0 T:0x422f0b60 S:0x422f01b4] OM - Memory_alloc> Enter(0x4)
    @11,375,486us: [+0 T:0x422f0b60 S:0x422f01b4] OM - Memory_alloc> return (0x627d0)
    @11,375,992us: [+0 T:0x422f0b60 S:0x422f01cc] OC - Comm_create> return (0x627d0)
    @11,376,236us: [+0 T:0x422f0b60 S:0x422f01d4] OC - Comm_put> Enter(queue=0x0, msg=0x446b2880)
    @11,376,496us: [+0 T:0x422f0b60 S:0x422f01d4] OC - Comm_put> return (0)
    @11,376,680us: [+0 T:0x422f0b60 S:0x422f01cc] OC - Comm_get> Enter(queue=0x10000, msg=0x422f024c, timeout=-1)
    @11,377,322us: [+0 T:0x422f0b60 S:0x422f01cc] OC - Comm_get> MSGQ_Get() status=0x8000, return (0)
    @11,377,547us: [+0 T:0x422f0b60 S:0x422f01d4] OC - Comm_put> Enter(queue=0x0, msg=0x446b2880)
    @11,377,786us: [+0 T:0x422f0b60 S:0x422f01d4] OC - Comm_put> return (0)
    @11,378,038us: [+0 T:0x422f0b60 S:0x422f01cc] OC - Comm_get> Enter(queue=0x10000, msg=0x422f024c, timeout=-1)
    @11,378,306us: [+0 T:0x422f0b60 S:0x422f01cc] OC - Comm_get> MSGQ_Get() status=0x8000, return (0)
    @11,378,516us: [+4 T:0x422f0b60 S:0x422f01f4] CE - Engine_createNode> created node(stdIn=0x3, stdOut=0x10003, msgq=0x627d0, algName='viddec2_copy', rmsNode=0x8fa70b78, algHandle=0x8fa70c18)
    @11,378,806us: [+0 T:0x422f0b60 S:0x422f0154] OC - Comm_put> Enter(queue=0x0, msg=0x446b2880)
    @11,379,061us: [+0 T:0x422f0b60 S:0x422f0154] OC - Comm_put> return (0)
    @11,379,314us: [+0 T:0x422f0b60 S:0x422f014c] OC - Comm_get> Enter(queue=0x10000, msg=0x422f01e0, timeout=-1)
    @11,379,551us: [+0 T:0x422f0b60 S:0x422f014c] OC - Comm_get> MSGQ_Get() status=0x8000, return (0)
    [DSP] @5,893,391tk: [+0 T:0x8ba0006c S:0x8ba04024] CR - processRmsCmd(0x8fe038a8, 4056): cmd = 0
    [DSP] @5,893,468tk: [+0 T:0x8ba0006c S:0x8ba03f14] OM - Memory_alloc> Enter(size=0x18)
    [DSP] @5,893,529tk: [+0 T:0x8ba0006c S:0x8ba03f14] OM - Memory_alloc> return (0x8fa70b78)
    [DSP] @5,893,592tk: [+0 T:0x8ba0006c S:0x8ba03f14] OM - Memory_alloc> Enter(size=0xf)
    [DSP] @5,893,649tk: [+0 T:0x8ba0006c S:0x8ba03f14] OM - Memory_alloc> return (0x8fa70b90)
    [DSP] @5,893,711tk: [+0 T:0x8ba0006c S:0x8ba03edc] OM - Memory_alloc> Enter(size=0x20)
    [DSP] @5,893,769tk: [+0 T:0x8ba0006c S:0x8ba03edc] OM - Memory_alloc> return (0x8fa70ba0)
    [DSP] @5,893,832tk: [+0 T:0x8ba0006c S:0x8ba03eac] OM - Memory_alloc> Enter(size=0x24)
    [DSP] @5,893,889tk: [+0 T:0x8ba0006c S:0x8ba03eac] OM - Memory_alloc> return (0x8fa70bc0)
    [DSP] @5,893,977tk: [+0 T:0x8ba0006c S:0x8ba03ec4] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_create> Enter (engine=0x0, name='viddec2_copy', params=0x8fe038c0)
    [DSP] @5,894,069tk: [+0 T:0x8ba0006c S:0x8ba03e8c] CV - VISA_create(0x0, 'viddec2_copy', 0x8fe038c0, 0x2484, 'ti.sdo.ce.video2.IVIDDEC2')
    [DSP] @5,894,174tk: [+0 T:0x8ba0006c S:0x8ba03d7c] CV - VISA_create2(0x0, 'viddec2_copy', 0x8fe038c0, 0x1c, 0x2484, 'ti.sdo.ce.video2.IVIDDEC2')
    [DSP] @5,894,286tk: [+0 T:0x8ba0006c S:0x8ba03d5c] OM - Memory_alloc> Enter(size=0x30)
    [DSP] @5,894,343tk: [+0 T:0x8ba0006c S:0x8ba03d5c] OM - Memory_alloc> return (0x8fa70c18)
    [DSP] @5,894,406tk: [+0 T:0x8ba0006c S:0x8ba03d34] ti.sdo.ce.alg.Algorithm - Algorithm_create> Enter(fxns=0x8fa6febc, idma3Fxns=0x0, params=0x8fe038c0, attrs=0x8ba03e80)
    [DSP] @5,894,512tk: [+0 T:0x8ba0006c S:0x8ba03d14] OM - Memory_alloc> Enter(size=0x10)
    [DSP] @5,894,569tk: [+0 T:0x8ba0006c S:0x8ba03d14] OM - Memory_alloc> return (0x8fa70c48)
    [DSP] @5,894,678tk: [+0 T:0x8ba0006c S:0x8ba03d34] ti.sdo.ce.alg.Algorithm - Algorithm_create> return (0x8fa70c48)
    [DSP] @5,894,755tk: [+0 T:0x8ba0006c S:0x8ba03ec4] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_create> return (0x8fa70c18)
    [DSP] @5,894,834tk: [+4 T:0x8ba0006c S:0x8ba03e84] OT - Thread_create > name: "viddec2_copy#1", pri:  -1, stack size:  11096, stack seg: 0
    [DSP] @5,896,406tk: [+0 T:0x8ba0006c S:0x8ba04024] CR - processRmsCmd(0x8fe038a8, 4056): cmd = 1
    [DSP] @5,899,349tk: [+0 T:0x8ba0006c S:0x8ba04024] CR - processRmsCmd(0x8fe038a8, 4056): cmd = 5
    [DSP] @5,899,418tk: [+0 T:0x8ba0006c S:0x8ba04024] CR - remote time = 0x0, trace buffer size = 4032
    @11,719,321us: [+0 T:0x422f0b60 S:0x422f0184] CE - Engine_fwriteTrace> returning count [2426]
    @11,719,581us: [+2 T:0x422f0b60 S:0x422f01f4] CE - Engine_createNode> Returning 0x627a8
    @11,719,775us: [+0 T:0x422f0b60 S:0x422f024c] OM - Memory_alloc> Enter(0x4)
    @11,719,962us: [+0 T:0x422f0b60 S:0x422f024c] OM - Memory_alloc> return (0x627e0)
    @11,720,211us: [+0 T:0x422f0b60 S:0x422f0244] OC - Comm_alloc> Enter(poolId=0x0, msg=0x627e0, size=2484)
    @11,720,456us: [+0 T:0x422f0b60 S:0x422f0244] OC - Comm_alloc> msg=0x446b5880, returning (0)
    @11,720,677us: [+5 T:0x422f0b60 S:0x422f0264] CV - VISA_create> remote codec created (name='viddec2_copy', localQueueID=0x10003, remoteQueueID=0x0003)
    @11,720,927us: [+0 T:0x422f0b60 S:0x422f036c] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_create> return (0x62770)
    @11,721,127us: [+2 T:0x422f0b60 S:0x422f038c] ti.sdo.dmai - [Vdec2] Video decoder instance created
    @11,721,316us: [+0 T:0x422f0b60 S:0x422f0314] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_control> Enter (handle=0x62770, id=1, params=0x422f0598 (size=0x1c), status=0x422f03c0 (size=0xb8)
    @11,721,584us: [+5 T:0x422f0b60 S:0x422f02dc] CV - VISA_allocMsg> Allocating message for messageId=0x0003e79e
    @11,721,785us: [+0 T:0x422f0b60 S:0x422f02bc] CV - VISA_call(visa=0x62770, msg=0x446b5880): messageId=0x0003e79e, command=0x1
    @11,722,008us: [+0 T:0x422f0b60 S:0x422f028c] OC - Comm_put> Enter(queue=0x3, msg=0x446b5880)
    @11,722,271us: [+0 T:0x422f0b60 S:0x422f028c] OC - Comm_put> return (0)
    @11,722,461us: [+0 T:0x422f0b60 S:0x422f0284] OC - Comm_get> Enter(queue=0x10003, msg=0x422f031c, timeout=-1)
    @11,722,778us: [+0 T:0x422f0b60 S:0x422f0284] OC - Comm_get> MSGQ_Get() status=0x8000, return (0)
    @11,723,005us: [+0 T:0x422f0b60 S:0x422f020c] OC - Comm_put> Enter(queue=0x0, msg=0x446b2880)
    @11,723,250us: [+0 T:0x422f0b60 S:0x422f020c] OC - Comm_put> return (0)
    @11,723,494us: [+0 T:0x422f0b60 S:0x422f0204] OC - Comm_get> Enter(queue=0x10000, msg=0x422f0298, timeout=-1)
    @11,723,730us: [+0 T:0x422f0b60 S:0x422f0204] OC - Comm_get> MSGQ_Get() status=0x8000, return (0)
    [DSP] @6,695,640tk: [+5 T:0x8ba07dbc S:0x8ba0a8d4] CN - NODE> 0x8fa70ba0(viddec2_copy#1) call(algHandle=0x8fa70c18, msg=0x8fe06880); messageId=0x0003e79e
    [DSP] @6,695,750tk: [+0 T:0x8ba07dbc S:0x8ba0a754] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_control> Enter (handle=0x8fa70c18, id=1, params=0x8fe068b4 (size=0x1c),
    status=0x8fe068d0 (size=0xb8)
    [DSP] @6,695,875tk: [+5 T:0x8ba07dbc S:0x8ba0a734] CV - VISA_enter(visa=0x8fa70c18): algHandle = 0x8fa70c48
    [DSP] @6,695,949tk: [+0 T:0x8ba07dbc S:0x8ba0a714] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Enter(handle=0x8fa70c48)
    [DSP] @6,696,029tk: [+0 T:0x8ba07dbc S:0x8ba0a714] ti.sdo.ce.alg.Algorithm - Algorithm_activate> return
    [DSP] @6,696,098tk: [+5 T:0x8ba07dbc S:0x8ba0a734] CV - VISA_exit(visa=0x8fa70c18): algHandle = 0x8fa70c48
    [DSP] @6,696,170tk: [+0 T:0x8ba07dbc S:0x8ba0a714] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> Enter(handle=0x8fa70c48)
    [DSP] @6,696,250tk: [+0 T:0x8ba07dbc S:0x8ba0a714] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> return
    [DSP] @6,696,316tk: [+0 T:0x8ba07dbc S:0x8ba0a754] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_control> Exit (handle=0x8fa70c18, retVal=0x0)
    [DSP] @6,696,402tk: [+5 T:0x8ba07dbc S:0x8ba0a8d4] CN - NODE> returned from call(algHandle=0x8fa70c18, msg=0x8fe06880); messageId=0x0003e79e
    [DSP] @6,697,976tk: [+0 T:0x8ba0006c S:0x8ba04024] CR - processRmsCmd(0x8fe038a8, 4056): cmd = 5
    [DSP] @6,698,046tk: [+0 T:0x8ba0006c S:0x8ba04024] CR - remote time = 0x0, trace buffer size = 4032
    @12,059,911us: [+0 T:0x422f0b60 S:0x422f023c] CE - Engine_fwriteTrace> returning count [1483]
    @12,060,180us: [+0 T:0x422f0b60 S:0x422f02bc] CV - VISA_call Completed: messageId=0x0003e79e, command=0x1, return(status=0)
    @12,060,414us: [+5 T:0x422f0b60 S:0x422f02cc] CV - VISA_freeMsg(0x62770, 0x446b5880): Freeing message with messageId=0x0003e79e
    @12,060,666us: [+0 T:0x422f0b60 S:0x422f0314] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_control> Exit (handle=0x62770, retVal=0x0)
    @12,060,884us: [+2 T:0x422f0b60 S:0x422f038c] ti.sdo.dmai - [Vdec2] Made XDM_SETPARAMS control call
    @12,061,075us: [+0 T:0x422f0b60 S:0x422f023c] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_control> Enter (handle=0x62770, id=5, params=0x6271c (size=0x1c), status=0x422f02e8 (size=0xb8)
    @12,061,343us: [+5 T:0x422f0b60 S:0x422f0204] CV - VISA_allocMsg> Allocating message for messageId=0x0003e79f
    @12,061,546us: [+0 T:0x422f0b60 S:0x422f01e4] CV - VISA_call(visa=0x62770, msg=0x446b5880): messageId=0x0003e79f, command=0x1
    @12,061,766us: [+0 T:0x422f0b60 S:0x422f01b4] OC - Comm_put> Enter(queue=0x3, msg=0x446b5880)
    @12,062,009us: [+0 T:0x422f0b60 S:0x422f01b4] OC - Comm_put> return (0)
    @12,062,201us: [+0 T:0x422f0b60 S:0x422f01ac] OC - Comm_get> Enter(queue=0x10003, msg=0x422f0244, timeout=-1)
    @12,062,506us: [+0 T:0x422f0b60 S:0x422f01ac] OC - Comm_get> MSGQ_Get() status=0x8000, return (0)
    @12,062,738us: [+0 T:0x422f0b60 S:0x422f0134] OC - Comm_put> Enter(queue=0x0, msg=0x446b2880)
    @12,062,980us: [+0 T:0x422f0b60 S:0x422f0134] OC - Comm_put> return (0)
    @12,063,249us: [+0 T:0x422f0b60 S:0x422f012c] OC - Comm_get> Enter(queue=0x10000, msg=0x422f01c0, timeout=-1)
    @12,063,513us: [+0 T:0x422f0b60 S:0x422f012c] OC - Comm_get> MSGQ_Get() status=0x8000, return (0)
    [DSP] @7,483,995tk: [+5 T:0x8ba07dbc S:0x8ba0a8d4] CN - NODE> 0x8fa70ba0(viddec2_copy#1) call(algHandle=0x8fa70c18, msg=0x8fe06880); messageId=0x0003e79f
    [DSP] @7,484,101tk: [+0 T:0x8ba07dbc S:0x8ba0a754] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_control> Enter (handle=0x8fa70c18, id=5, params=0x8fe068b4 (size=0x1c),
    status=0x8fe068d0 (size=0xb8)
    [DSP] @7,484,227tk: [+5 T:0x8ba07dbc S:0x8ba0a734] CV - VISA_enter(visa=0x8fa70c18): algHandle = 0x8fa70c48
    [DSP] @7,484,300tk: [+0 T:0x8ba07dbc S:0x8ba0a714] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Enter(handle=0x8fa70c48)
    [DSP] @7,484,378tk: [+0 T:0x8ba07dbc S:0x8ba0a714] ti.sdo.ce.alg.Algorithm - Algorithm_activate> return
    [DSP] @7,484,446tk: [+5 T:0x8ba07dbc S:0x8ba0a734] CV - VISA_exit(visa=0x8fa70c18): algHandle = 0x8fa70c48
    [DSP] @7,484,519tk: [+0 T:0x8ba07dbc S:0x8ba0a714] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> Enter(handle=0x8fa70c48)
    [DSP] @7,484,596tk: [+0 T:0x8ba07dbc S:0x8ba0a714] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> return
    [DSP] @7,484,661tk: [+0 T:0x8ba07dbc S:0x8ba0a754] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_control> Exit (handle=0x8fa70c18, retVal=0x0)
    [DSP] @7,484,745tk: [+5 T:0x8ba07dbc S:0x8ba0a8d4] CN - NODE> returned from call(algHandle=0x8fa70c18, msg=0x8fe06880); messageId=0x0003e79f
    [DSP] @7,486,255tk: [+0 T:0x8ba0006c S:0x8ba04024] CR - processRmsCmd(0x8fe038a8, 4056): cmd = 5
    [DSP] @7,486,328tk: [+0 T:0x8ba0006c S:0x8ba04024] CR - remote time = 0x0, trace buffer size = 4032
    @12,399,781us: [+0 T:0x422f0b60 S:0x422f0164] CE - Engine_fwriteTrace> returning count [1483]
    @12,399,994us: [+0 T:0x422f0b60 S:0x422f01e4] CV - VISA_call Completed: messageId=0x0003e79f, command=0x1, return(status=0)
    @12,400,276us: [+5 T:0x422f0b60 S:0x422f01f4] CV - VISA_freeMsg(0x62770, 0x446b5880): Freeing message with messageId=0x0003e79f
    @12,400,504us: [+0 T:0x422f0b60 S:0x422f023c] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_control> Exit (handle=0x62770, retVal=0x0)
    @12,400,715us: [+2 T:0x422f0b60 S:0x422f02b4] ti.sdo.dmai - [Vdec2] Made XDM_GETBUFINFO control call
    @12,400,921us: [+0 T:0x422f0b60 S:0x422f042c] OM - Memory_alloc> Enter(0x1)
    @12,401,110us: [+0 T:0x422f0b60 S:0x422f03dc] OM - Memory_contigAlloc> Enter(size=1, align=-1, cached=FALSE, heap=FALSE)
    @12,401,442us: [+4 T:0x422f0b60 S:0x422f03dc] OM - Memory_contigAlloc> CMEM_alloc(1) = 0x44722000.
    @12,401,693us: [+4 T:0x422f0b60 S:0x422f03dc] OM - Memory_contigAlloc> CMEM_getPhys(0x44722000) = 0x88030000.
    @12,401,941us: [+1 T:0x422f0b60 S:0x422f0384] OM - Memory__addContigBuf> Enter(virtAddr=0x44722000, size=1, physAddr=0x88030000)
    @12,402,161us: [+1 T:0x422f0b60 S:0x422f0384] OM - Memory__addContigBuf> creating new contigBuf object
    @12,402,349us: [+0 T:0x422f0b60 S:0x422f036c] OM - Memory_alloc> Enter(0x10)
    @12,402,528us: [+0 T:0x422f0b60 S:0x422f036c] OM - Memory_alloc> return (0x62860)
    @12,402,705us: [+1 T:0x422f0b60 S:0x422f0384] OM - Memory__addContigBuf> returning: cb->phys=0x88030000, cb->size=1, cb->virt=0x44722000
    @12,402,928us: [+0 T:0x422f0b60 S:0x422f03dc] OM - Memory_contigAlloc> return (0x44722000)
    @12,403,110us: [+0 T:0x422f0b60 S:0x422f042c] OM - Memory_alloc> return (0x44722000)
    @12,403,289us: [+0 T:0x422f0b60 S:0x422f0404] OM - Memory_getBufferPhysicalAddress> Enter(virtAddr=0x44722000, size=4)
    @12,403,494us: [+1 T:0x422f0b60 S:0x422f0404] OM - Memory__getPhysicalAddress> Enter(virtAddr=0x44722000, size=4)
    @12,403,695us: [+1 T:0x422f0b60 S:0x422f0404] OM - Memory__getPhysicalAddress> returning physAddr=0x0
    @12,403,900us: [+1 T:0x422f0b60 S:0x422f0404] OM - Memory_getBufferPhysicalAddress> CMEM_getPhys(0x44722000) = 0x88030000.
    @12,404,125us: [+1 T:0x422f0b60 S:0x422f03ac] OM - Memory__addContigBuf> Enter(virtAddr=0x44722000, size=4, physAddr=0x88030000)
    @12,404,344us: [+1 T:0x422f0b60 S:0x422f03ac] OM - Memory__addContigBuf> case 2 (Sc=0x88030000, Ec=0x88030001, Ss=0x88030000, Es=0x88030004
    @12,404,594us: [+1 T:0x422f0b60 S:0x422f03ac] OM - Memory__addContigBuf> returning: cb->phys=0x88030000, cb->size=4, cb->virt=0x44722000
    @12,404,823us: [+0 T:0x422f0b60 S:0x422f0404] OM - Memory_getBufferPhysicalAddress> return (0x88030000)
    @12,405,020us: [+2 T:0x422f0b60 S:0x422f0444] ti.sdo.dmai - [Buffer] Alloc Buffer of size 1 at 0x44722000 (0x88030000 phys)
    @12,405,255us: [+2 T:0x422f0b60 S:0x422f0424] ti.sdo.dmai - [BufTab] Allocating BufTab for 8 buffers
    @12,405,469us: [+0 T:0x422f0b60 S:0x422f0404] OM - Memory_alloc> Enter(0x8be20)
    @12,405,656us: [+0 T:0x422f0b60 S:0x422f03b4] OM - Memory_contigAlloc> Enter(size=572960, align=-1, cached=FALSE, heap=FALSE)
    @12,406,023us: [+4 T:0x422f0b60 S:0x422f03b4] OM - Memory_contigAlloc> CMEM_alloc(572960) = 0x44731000.
    @12,406,279us: [+4 T:0x422f0b60 S:0x422f03b4] OM - Memory_contigAlloc> CMEM_getPhys(0x44731000) = 0x88243000.
    @12,406,493us: [+1 T:0x422f0b60 S:0x422f035c] OM - Memory__addContigBuf> Enter(virtAddr=0x44731000, size=572960, physAddr=0x88243000)
    @12,740,232us: [+1 T:0x422f0b60 S:0x422f035c] OM - Memory__addContigBuf> creating new contigBuf object
    @12,740,471us: [+0 T:0x422f0b60 S:0x422f0344] OM - Memory_alloc> Enter(0x10)
    @12,740,665us: [+0 T:0x422f0b60 S:0x422f0344] OM - Memory_alloc> return (0x62928)
    @12,740,846us: [+1 T:0x422f0b60 S:0x422f035c] OM - Memory__addContigBuf> returning: cb->phys=0x88243000, cb->size=572960, cb->virt=0x44731000
    @12,741,073us: [+0 T:0x422f0b60 S:0x422f03b4] OM - Memory_contigAlloc> return (0x44731000)
    @12,741,260us: [+0 T:0x422f0b60 S:0x422f0404] OM - Memory_alloc> return (0x44731000)
    @12,741,435us: [+0 T:0x422f0b60 S:0x422f03dc] OM - Memory_getBufferPhysicalAddress> Enter(virtAddr=0x44731000, size=4)
    @12,741,638us: [+1 T:0x422f0b60 S:0x422f03dc] OM - Memory__getPhysicalAddress> Enter(virtAddr=0x44731000, size=4)
    @12,741,836us: [+1 T:0x422f0b60 S:0x422f03dc] OM - Memory__getPhysicalAddress> found in cb(Sc=0x44731000, Ec=0x447bce20, Ss=0x44731000, Es=0x44731004, PSc=0x88243000)
    @12,742,084us: [+1 T:0x422f0b60 S:0x422f03dc] OM - Memory__getPhysicalAddress> returning physAddr=0x88243000
    @12,742,278us: [+0 T:0x422f0b60 S:0x422f03dc] OM - Memory_getBufferPhysicalAddress> return (0x88243000)
    @12,742,471us: [+2 T:0x422f0b60 S:0x422f041c] ti.sdo.dmai - [Buffer] Alloc Buffer of size 572960 at 0x44731000 (0x88243000 phys)
    @12,742,709us: [+0 T:0x422f0b60 S:0x422f0404] OM - Memory_alloc> Enter(0x8be20)
    @12,742,891us: [+0 T:0x422f0b60 S:0x422f03b4] OM - Memory_contigAlloc> Enter(size=572960, align=-1, cached=FALSE, heap=FALSE)
    @12,743,313us: [+4 T:0x422f0b60 S:0x422f03b4] OM - Memory_contigAlloc> CMEM_alloc(572960) = 0x447d3000.
    @12,743,571us: [+4 T:0x422f0b60 S:0x422f03b4] OM - Memory_contigAlloc> CMEM_getPhys(0x447d3000) = 0x882e5000.
    @12,743,786us: [+1 T:0x422f0b60 S:0x422f035c] OM - Memory__addContigBuf> Enter(virtAddr=0x447d3000, size=572960, physAddr=0x882e5000)
    @12,744,014us: [+1 T:0x422f0b60 S:0x422f035c] OM - Memory__addContigBuf> creating new contigBuf object
    @12,744,206us: [+0 T:0x422f0b60 S:0x422f0344] OM - Memory_alloc> Enter(0x10)
    @12,744,389us: [+0 T:0x422f0b60 S:0x422f0344] OM - Memory_alloc> return (0x629b0)
    @12,744,566us: [+1 T:0x422f0b60 S:0x422f035c] OM - Memory__addContigBuf> returning: cb->phys=0x882e5000, cb->size=572960, cb->virt=0x447d3000
    @12,744,792us: [+0 T:0x422f0b60 S:0x422f03b4] OM - Memory_contigAlloc> return (0x447d3000)
    @12,744,975us: [+0 T:0x422f0b60 S:0x422f0404] OM - Memory_alloc> return (0x447d3000)
    @12,745,151us: [+0 T:0x422f0b60 S:0x422f03dc] OM - Memory_getBufferPhysicalAddress> Enter(virtAddr=0x447d3000, size=4)
    @12,745,355us: [+1 T:0x422f0b60 S:0x422f03dc] OM - Memory__getPhysicalAddress> Enter(virtAddr=0x447d3000, size=4)
    @12,745,551us: [+1 T:0x422f0b60 S:0x422f03dc] OM - Memory__getPhysicalAddress> found in cb(Sc=0x447d3000, Ec=0x4485ee20, Ss=0x447d3000, Es=0x447d3004, PSc=0x882e5000)
    @12,745,830us: [+1 T:0x422f0b60 S:0x422f03dc] OM - Memory__getPhysicalAddress> returning physAddr=0x882e5000
    @12,746,028us: [+0 T:0x422f0b60 S:0x422f03dc] OM - Memory_getBufferPhysicalAddress> return (0x882e5000)
    @12,746,226us: [+2 T:0x422f0b60 S:0x422f041c] ti.sdo.dmai - [Buffer] Alloc Buffer of size 572960 at 0x447d3000 (0x882e5000 phys)
    @12,746,466us: [+0 T:0x422f0b60 S:0x422f0404] OM - Memory_alloc> Enter(0x8be20)
    @12,746,652us: [+0 T:0x422f0b60 S:0x422f03b4] OM - Memory_contigAlloc> Enter(size=572960, align=-1, cached=FALSE, heap=FALSE)
    @12,747,020us: [+4 T:0x422f0b60 S:0x422f03b4] OM - Memory_contigAlloc> CMEM_alloc(572960) = 0x44875000.
    @12,747,289us: [+4 T:0x422f0b60 S:0x422f03b4] OM - Memory_contigAlloc> CMEM_getPhys(0x44875000) = 0x88387000.
    @12,747,500us: [+1 T:0x422f0b60 S:0x422f035c] OM - Memory__addContigBuf> Enter(virtAddr=0x44875000, size=572960, physAddr=0x88387000)
    @12,747,726us: [+1 T:0x422f0b60 S:0x422f035c] OM - Memory__addContigBuf> creating new contigBuf object
    @13,081,375us: [+0 T:0x422f0b60 S:0x422f0344] OM - Memory_alloc> Enter(0x10)
    @13,081,594us: [+0 T:0x422f0b60 S:0x422f0344] OM - Memory_alloc> return (0x62a38)
    @13,081,785us: [+1 T:0x422f0b60 S:0x422f035c] OM - Memory__addContigBuf> returning: cb->phys=0x88387000, cb->size=572960, cb->virt=0x44875000
    @13,082,016us: [+0 T:0x422f0b60 S:0x422f03b4] OM - Memory_contigAlloc> return (0x44875000)
    @13,082,205us: [+0 T:0x422f0b60 S:0x422f0404] OM - Memory_alloc> return (0x44875000)
    @13,082,387us: [+0 T:0x422f0b60 S:0x422f03dc] OM - Memory_getBufferPhysicalAddress> Enter(virtAddr=0x44875000, size=4)
    @13,082,594us: [+1 T:0x422f0b60 S:0x422f03dc] OM - Memory__getPhysicalAddress> Enter(virtAddr=0x44875000, size=4)
    @13,082,798us: [+1 T:0x422f0b60 S:0x422f03dc] OM - Memory__getPhysicalAddress> found in cb(Sc=0x44875000, Ec=0x44900e20, Ss=0x44875000, Es=0x44875004, PSc=0x88387000)
    @13,083,046us: [+1 T:0x422f0b60 S:0x422f03dc] OM - Memory__getPhysicalAddress> returning physAddr=0x88387000
    @13,083,243us: [+0 T:0x422f0b60 S:0x422f03dc] OM - Memory_getBufferPhysicalAddress> return (0x88387000)
    @13,083,438us: [+2 T:0x422f0b60 S:0x422f041c] ti.sdo.dmai - [Buffer] Alloc Buffer of size 572960 at 0x44875000 (0x88387000 phys)
    @13,083,675us: [+0 T:0x422f0b60 S:0x422f0404] OM - Memory_alloc> Enter(0x8be20)
    @13,083,862us: [+0 T:0x422f0b60 S:0x422f03b4] OM - Memory_contigAlloc> Enter(size=572960, align=-1, cached=FALSE, heap=FALSE)
    @13,084,264us: [+4 T:0x422f0b60 S:0x422f03b4] OM - Memory_contigAlloc> CMEM_alloc(572960) = 0x44917000.
    @13,084,525us: [+4 T:0x422f0b60 S:0x422f03b4] OM - Memory_contigAlloc> CMEM_getPhys(0x44917000) = 0x88429000.
    @13,084,740us: [+1 T:0x422f0b60 S:0x422f035c] OM - Memory__addContigBuf> Enter(virtAddr=0x44917000, size=572960, physAddr=0x88429000)
    @13,084,971us: [+1 T:0x422f0b60 S:0x422f035c] OM - Memory__addContigBuf> creating new contigBuf object
    @13,085,159us: [+0 T:0x422f0b60 S:0x422f0344] OM - Memory_alloc> Enter(0x10)
    @13,085,345us: [+0 T:0x422f0b60 S:0x422f0344] OM - Memory_alloc> return (0x62ac0)
    @13,085,527us: [+1 T:0x422f0b60 S:0x422f035c] OM - Memory__addContigBuf> returning: cb->phys=0x88429000, cb->size=572960, cb->virt=0x44917000
    @13,085,755us: [+0 T:0x422f0b60 S:0x422f03b4] OM - Memory_contigAlloc> return (0x44917000)
    @13,085,938us: [+0 T:0x422f0b60 S:0x422f0404] OM - Memory_alloc> return (0x44917000)
    @13,086,115us: [+0 T:0x422f0b60 S:0x422f03dc] OM - Memory_getBufferPhysicalAddress> Enter(virtAddr=0x44917000, size=4)
    @13,086,324us: [+1 T:0x422f0b60 S:0x422f03dc] OM - Memory__getPhysicalAddress> Enter(virtAddr=0x44917000, size=4)
    @13,086,529us: [+1 T:0x422f0b60 S:0x422f03dc] OM - Memory__getPhysicalAddress> found in cb(Sc=0x44917000, Ec=0x449a2e20, Ss=0x44917000, Es=0x44917004, PSc=0x88429000)
    @13,086,783us: [+1 T:0x422f0b60 S:0x422f03dc] OM - Memory__getPhysicalAddress> returning physAddr=0x88429000
    @13,087,008us: [+0 T:0x422f0b60 S:0x422f03dc] OM - Memory_getBufferPhysicalAddress> return (0x88429000)
    @13,087,210us: [+2 T:0x422f0b60 S:0x422f041c] ti.sdo.dmai - [Buffer] Alloc Buffer of size 572960 at 0x44917000 (0x88429000 phys)
    @13,087,449us: [+0 T:0x422f0b60 S:0x422f0404] OM - Memory_alloc> Enter(0x8be20)
    @13,087,636us: [+0 T:0x422f0b60 S:0x422f03b4] OM - Memory_contigAlloc> Enter(size=572960, align=-1, cached=FALSE, heap=FALSE)
    @13,088,035us: [+4 T:0x422f0b60 S:0x422f03b4] OM - Memory_contigAlloc> CMEM_alloc(572960) = 0x449b9000.
    @13,088,299us: [+4 T:0x422f0b60 S:0x422f03b4] OM - Memory_contigAlloc> CMEM_getPhys(0x449b9000) = 0x884cb000.
    @13,088,518us: [+1 T:0x422f0b60 S:0x422f035c] OM - Memory__addContigBuf> Enter(virtAddr=0x449b9000, size=572960, physAddr=0x884cb000)
    @13,088,751us: [+1 T:0x422f0b60 S:0x422f035c] OM - Memory__addContigBuf> creating new contigBuf object
    @13,088,939us: [+0 T:0x422f0b60 S:0x422f0344] OM - Memory_alloc> Enter(0x10)
    @13,089,124us: [+0 T:0x422f0b60 S:0x422f0344] OM - Memory_alloc> return (0x62b48)
    @13,422,613us: [+1 T:0x422f0b60 S:0x422f035c] OM - Memory__addContigBuf> returning: cb->phys=0x884cb000, cb->size=572960, cb->virt=0x449b9000
    @13,422,886us: [+0 T:0x422f0b60 S:0x422f03b4] OM - Memory_contigAlloc> return (0x449b9000)
    @13,423,083us: [+0 T:0x422f0b60 S:0x422f0404] OM - Memory_alloc> return (0x449b9000)
    @13,423,272us: [+0 T:0x422f0b60 S:0x422f03dc] OM - Memory_getBufferPhysicalAddress> Enter(virtAddr=0x449b9000, size=4)
    @13,423,477us: [+1 T:0x422f0b60 S:0x422f03dc] OM - Memory__getPhysicalAddress> Enter(virtAddr=0x449b9000, size=4)
    @13,423,678us: [+1 T:0x422f0b60 S:0x422f03dc] OM - Memory__getPhysicalAddress> found in cb(Sc=0x449b9000, Ec=0x44a44e20, Ss=0x449b9000, Es=0x449b9004, PSc=0x884cb000)
    @13,423,927us: [+1 T:0x422f0b60 S:0x422f03dc] OM - Memory__getPhysicalAddress> returning physAddr=0x884cb000
    @13,424,122us: [+0 T:0x422f0b60 S:0x422f03dc] OM - Memory_getBufferPhysicalAddress> return (0x884cb000)
    @13,424,313us: [+2 T:0x422f0b60 S:0x422f041c] ti.sdo.dmai - [Buffer] Alloc Buffer of size 572960 at 0x449b9000 (0x884cb000 phys)
    @13,424,578us: [+0 T:0x422f0b60 S:0x422f0404] OM - Memory_alloc> Enter(0x8be20)
    @13,424,762us: [+0 T:0x422f0b60 S:0x422f03b4] OM - Memory_contigAlloc> Enter(size=572960, align=-1, cached=FALSE, heap=FALSE)
    @13,425,134us: [+4 T:0x422f0b60 S:0x422f03b4] OM - Memory_contigAlloc> CMEM_alloc(572960) = 0x44a5b000.
    @13,425,417us: [+4 T:0x422f0b60 S:0x422f03b4] OM - Memory_contigAlloc> CMEM_getPhys(0x44a5b000) = 0x8856d000.
    @13,425,630us: [+1 T:0x422f0b60 S:0x422f035c] OM - Memory__addContigBuf> Enter(virtAddr=0x44a5b000, size=572960, physAddr=0x8856d000)
    @13,425,857us: [+1 T:0x422f0b60 S:0x422f035c] OM - Memory__addContigBuf> creating new contigBuf object
    @13,426,043us: [+0 T:0x422f0b60 S:0x422f0344] OM - Memory_alloc> Enter(0x10)
    @13,426,230us: [+0 T:0x422f0b60 S:0x422f0344] OM - Memory_alloc> return (0x62bd0)
    @13,426,407us: [+1 T:0x422f0b60 S:0x422f035c] OM - Memory__addContigBuf> returning: cb->phys=0x8856d000, cb->size=572960, cb->virt=0x44a5b000
    @13,426,631us: [+0 T:0x422f0b60 S:0x422f03b4] OM - Memory_contigAlloc> return (0x44a5b000)
    @13,426,812us: [+0 T:0x422f0b60 S:0x422f0404] OM - Memory_alloc> return (0x44a5b000)
    @13,426,990us: [+0 T:0x422f0b60 S:0x422f03dc] OM - Memory_getBufferPhysicalAddress> Enter(virtAddr=0x44a5b000, size=4)
    @13,427,194us: [+1 T:0x422f0b60 S:0x422f03dc] OM - Memory__getPhysicalAddress> Enter(virtAddr=0x44a5b000, size=4)
    @13,427,392us: [+1 T:0x422f0b60 S:0x422f03dc] OM - Memory__getPhysicalAddress> found in cb(Sc=0x44a5b000, Ec=0x44ae6e20, Ss=0x44a5b000, Es=0x44a5b004, PSc=0x8856d000)
    @13,427,638us: [+1 T:0x422f0b60 S:0x422f03dc] OM - Memory__getPhysicalAddress> returning physAddr=0x8856d000
    @13,427,830us: [+0 T:0x422f0b60 S:0x422f03dc] OM - Memory_getBufferPhysicalAddress> return (0x8856d000)
    @13,428,021us: [+2 T:0x422f0b60 S:0x422f041c] ti.sdo.dmai - [Buffer] Alloc Buffer of size 572960 at 0x44a5b000 (0x8856d000 phys)
    @13,428,293us: [+0 T:0x422f0b60 S:0x422f0404] OM - Memory_alloc> Enter(0x8be20)
    @13,428,482us: [+0 T:0x422f0b60 S:0x422f03b4] OM - Memory_contigAlloc> Enter(size=572960, align=-1, cached=FALSE, heap=FALSE)
    @13,428,857us: [+4 T:0x422f0b60 S:0x422f03b4] OM - Memory_contigAlloc> CMEM_alloc(572960) = 0x44afd000.
    @13,429,110us: [+4 T:0x422f0b60 S:0x422f03b4] OM - Memory_contigAlloc> CMEM_getPhys(0x44afd000) = 0x8860f000.
    @13,429,323us: [+1 T:0x422f0b60 S:0x422f035c] OM - Memory__addContigBuf> Enter(virtAddr=0x44afd000, size=572960, physAddr=0x8860f000)
    @13,429,549us: [+1 T:0x422f0b60 S:0x422f035c] OM - Memory__addContigBuf> creating new contigBuf object
    @13,429,736us: [+0 T:0x422f0b60 S:0x422f0344] OM - Memory_alloc> Enter(0x10)
    @13,429,916us: [+0 T:0x422f0b60 S:0x422f0344] OM - Memory_alloc> return (0x62c58)
    @13,430,150us: [+1 T:0x422f0b60 S:0x422f035c] OM - Memory__addContigBuf> returning: cb->phys=0x8860f000, cb->size=572960, cb->virt=0x44afd000
    @13,763,765us: [+0 T:0x422f0b60 S:0x422f03b4] OM - Memory_contigAlloc> return (0x44afd000)
    @13,763,984us: [+0 T:0x422f0b60 S:0x422f0404] OM - Memory_alloc> return (0x44afd000)
    @13,764,171us: [+0 T:0x422f0b60 S:0x422f03dc] OM - Memory_getBufferPhysicalAddress> Enter(virtAddr=0x44afd000, size=4)
    @13,764,382us: [+1 T:0x422f0b60 S:0x422f03dc] OM - Memory__getPhysicalAddress> Enter(virtAddr=0x44afd000, size=4)
    @13,764,582us: [+1 T:0x422f0b60 S:0x422f03dc] OM - Memory__getPhysicalAddress> found in cb(Sc=0x44afd000, Ec=0x44b88e20, Ss=0x44afd000, Es=0x44afd004, PSc=0x8860f000)
    @13,764,846us: [+1 T:0x422f0b60 S:0x422f03dc] OM - Memory__getPhysicalAddress> returning physAddr=0x8860f000
    @13,765,043us: [+0 T:0x422f0b60 S:0x422f03dc] OM - Memory_getBufferPhysicalAddress> return (0x8860f000)
    @13,765,239us: [+2 T:0x422f0b60 S:0x422f041c] ti.sdo.dmai - [Buffer] Alloc Buffer of size 572960 at 0x44afd000 (0x8860f000 phys)
    @13,765,476us: [+0 T:0x422f0b60 S:0x422f0404] OM - Memory_alloc> Enter(0x8be20)
    @13,765,660us: [+0 T:0x422f0b60 S:0x422f03b4] OM - Memory_contigAlloc> Enter(size=572960, align=-1, cached=FALSE, heap=FALSE)
    @13,766,062us: [+4 T:0x422f0b60 S:0x422f03b4] OM - Memory_contigAlloc> CMEM_alloc(572960) = 0x44b9f000.
    @13,766,322us: [+4 T:0x422f0b60 S:0x422f03b4] OM - Memory_contigAlloc> CMEM_getPhys(0x44b9f000) = 0x886b1000.
    @13,766,565us: [+1 T:0x422f0b60 S:0x422f035c] OM - Memory__addContigBuf> Enter(virtAddr=0x44b9f000, size=572960, physAddr=0x886b1000)
    @13,766,797us: [+1 T:0x422f0b60 S:0x422f035c] OM - Memory__addContigBuf> creating new contigBuf object
    @13,766,985us: [+0 T:0x422f0b60 S:0x422f0344] OM - Memory_alloc> Enter(0x10)
    @13,767,170us: [+0 T:0x422f0b60 S:0x422f0344] OM - Memory_alloc> return (0x62ce0)
    @13,767,348us: [+1 T:0x422f0b60 S:0x422f035c] OM - Memory__addContigBuf> returning: cb->phys=0x886b1000, cb->size=572960, cb->virt=0x44b9f000
    @13,767,575us: [+0 T:0x422f0b60 S:0x422f03b4] OM - Memory_contigAlloc> return (0x44b9f000)
    @13,767,756us: [+0 T:0x422f0b60 S:0x422f0404] OM - Memory_alloc> return (0x44b9f000)
    @13,767,933us: [+0 T:0x422f0b60 S:0x422f03dc] OM - Memory_getBufferPhysicalAddress> Enter(virtAddr=0x44b9f000, size=4)
    @13,768,138us: [+1 T:0x422f0b60 S:0x422f03dc] OM - Memory__getPhysicalAddress> Enter(virtAddr=0x44b9f000, size=4)
    @13,768,337us: [+1 T:0x422f0b60 S:0x422f03dc] OM - Memory__getPhysicalAddress> found in cb(Sc=0x44b9f000, Ec=0x44c2ae20, Ss=0x44b9f000, Es=0x44b9f004, PSc=0x886b1000)
    @13,768,583us: [+1 T:0x422f0b60 S:0x422f03dc] OM - Memory__getPhysicalAddress> returning physAddr=0x886b1000
    @13,768,774us: [+0 T:0x422f0b60 S:0x422f03dc] OM - Memory_getBufferPhysicalAddress> return (0x886b1000)
    @13,768,964us: [+2 T:0x422f0b60 S:0x422f041c] ti.sdo.dmai - [Buffer] Alloc Buffer of size 572960 at 0x44b9f000 (0x886b1000 phys)
    ctrlFxnFUN: FILE: ../ctrl.c, LINE: 343.
    ctrlFxnFUN: FILE: ../ctrl.c, LINE: 349.
    @13,773,776us: [+0 T:0x422f0b60 S:0x422f0224] ti.sdo.ce.video1.VIDENC1 - VIDENC1_process> Enter (handle=0x62550, inBufs=0x422f0380, outBufs=0x422f0370, inArgs=0x422f0358, outArgs=0x422f02e0)
    @13,774,351us: [+5 T:0x422f0b60 S:0x422f01a4] CV - VISA_allocMsg> Allocating message for messageId=0x00026cc4
    @13,774,594us: [+0 T:0x422f0b60 S:0x422f0174] OM - Memory_getBufferPhysicalAddress> Enter(virtAddr=0x44731000, size=0)
    @13,774,832us: [+6 T:0x422f0b60 S:0x422f0174] OM - Memory_getBufferPhysicalAddress> invalid buffer size provided (0)
    @13,775,063us: [+0 T:0x422f0b60 S:0x422f0174] OM - Memory_getBufferPhysicalAddress> return (0x0)
    @13,775,265us: [+5 T:0x422f0b60 S:0x422f0194] CV - VISA_freeMsg(0x62550, 0x446b4880): Freeing message with messageId=0x00026cc4
    @13,775,490us: [+0 T:0x422f0b60 S:0x422f0224] ti.sdo.ce.video1.VIDENC1 - VIDENC1_process> Exit (handle=0x62550, retVal=0xffffffff)
    @13,775,709us: [+2 T:0x422f0b60 S:0x422f027c] ti.sdo.dmai - [Venc1] VIDENC1_process() ret -1 generated 353944 bytes
    @14,110,667us: [+7 T:0x422f0b60 S:0x422f027c] ti.sdo.dmai - [Venc1] VIDENC1_process() failed with a fatal error -1 (0x2c2b8)
    Error: Failed to encode video buffer                                                                                                            

     

     can anyone help me?     thank you.

    Zhiqiang LI

  • Hi Zhiqiang,

    I see this error in your trace:

    @13,774,832us: [+6 T:0x422f0b60 S:0x422f0174] OM - Memory_getBufferPhysicalAddress> invalid buffer size provided (0)

    This points to the fact that the bufSize field in either your inBuf or outBuf descriptors to the VIDENC1_process() call is not set correctly. The size should really be set to the buffer size and not be 0. To find out which bufSize field(s) is set incorrectly, you can add some printf's in Venc1_process() in dmai\packages\ti\sdo\dmai\ce\venc1.c and then recompile it and link it against your encodedecode application. Dmai sets these values based largely on numbers returned by the codec itself in the Venc1_create() and Venc1_process() functions. To my knowledge the videnc1_copy codec has never been validated in the context of the encodedecode application (others may wish to chime in), so it is possible that videnc1_copy is not reporting correct buffer sizes, and that you will need to modify it to do so.

    Best regards,

    Vincent

  • Hi Vincent,

    thank you for your reply.

    you said that "the videnc1_copy codec has never been validated in the context of the encodedecode application ",  whether it means that I have to modify lots of things in the encodedecode application, videnc1_copy and viddec2_copy ?

    it seems that I go the wrong way.

    I just want to do some processing on the video stream, not just an image.

    Now I can read an image and do some simple processing over it ( like graying) , but now I want to do the processing on the video stream and display the result on the screen.

    Could you please suggest one way to do it quickly and simply?

    thank you for your attention!

    Zhiqiang

     

  • Hi Zhiqiang,

    If I understood well, your intent was to modify the videnc1_copy/viddec2_copy algorithm to do your image processing once you could get them to run in encodedecode. While I expect the changes to videnc1_copy and viddec2_copy to be relatively small, as an algorithm developer you should learn the semantics of the Xdais/XDM standard to ensure you implement the correct interface for your algorithm. The XDM standard comes with a set of "canned" interfaces for video/audio/imaging/speech encoder and decoder, etc.  It is important to pick the one that is suitable for your algorithm, because the existing interfaces come with parameters and return values that are specific to the type of algorithm being implemented, and may not make much sense for yours.  Trying to disguise an algorithm that is not a video encoder as one is a hack - when doing so, unless you are very experienced with the IVIDDEC2/IVIDENC1 interface, you leave yourself vulnerable to uninitialized or wrongly set variables that are otherwise important in an encode/decode setting. Based on the description of your algorithm, your algorithm is not a video encoder or decoder (probably closer to an imaging codec in fact), so a better approach would be to implement the IUNIVERSAL interface (see http://processors.wiki.ti.com/index.php/Getting_started_with_IUNIVERSAL), which is a flexible generic interface for algorithms that do not fit under existing abstractions.

    After implementing the interface, you need to learn how to invoke it from the ARM (the above wiki has example code to show you how to do this). Once you understand how to invoke your algorithm on the ARM, you should be able to modify the encodedecode demo to call your algorithm in the video thread (ie. video.c). 

    Other resources that may be useful:

    Encodedecode demo design doc (a bit old, but in general still applies): http://focus.ti.com/general/docs/litabsmultiplefilelist.tsp?literatureNumber=spraah0a

    XDM semantics: http://processors.wiki.ti.com/index.php/XDM_1.x_Semantics

    Hope this helps,

    Vincent

     

  • Hi Vincent,

    I have solved the problem.

    In fact I want to do the license plate recognition  on the DM6467.

    But I didn't  modify the h264 codec, ( I didn't use the videnc1_copy and viddec2_copy to replace the h264 codec ) .

    I added the videnc_copy codec to the server, then I got the display buffer address in the display.c ( using  Buffer_getUserPtr( hDstBuf ) ), and do the graying processing on the buffer.

    thank you for your help!

    Zhiqiang LI

  • Hi

    Zhiqiang ,

                   I am a beginner about DM6467,You said you had done some simple processing over it (like graying), could you send your programs to me ,thank you very much, I am just in deep trouble without an example to study, if you could, my email pdgphm@163.com

  • Hello, Li

    I find that we are doing the same application coincidentally. Do you mind exchanging ideas with me in future research?

    We can communicate with QQ and MSN, and my email: tianbin.cas@gmail.com. Looking forward to your reply. Thank you! :) 

     

    Bin Tian