H264 decode problem

david shen47423

Hi,

Does the h264 decoder need an IDR to start decoding or it can start decoding from any random point?

I need the h264 codec can decode from any random point,but when my palyer work on this way,the h264 codec vdec _process always return -1.

And I have use the decode demo made a test,that if the h264 video start from IDR frame,the decode demo work very well,but if I delete the first IDR frame,and then use the decode demo to have a test,it exit abnormally.Bellow is the demo's trace:

./decode -v video1.264 (delete the first IDR frame)
Decode demo started.
Error: Failed to decode video buffer

./decode -v video2.264 (etrire video)
Decode demo started.
Decode demo ARM Load: 57% DSP Load: 14% Display Type: D1 NTSC Video Codec: H.264 BP Video fps: 21 fps Video bit rate: 275 kbps Video resolution: 720x576 Sound codec: N/A Sound bit rate: 0 kbps Sampling freq: 0 Time: 00:00:01
Decode demo ARM Load: 3% DSP Load: 12% Display Type: D1 NTSC Video Codec: H.264 BP Video fps: 30 fps Video bit rate: 8 kbps Video resolution: 720x576 Sound codec: N/A Sound bit rate: 0 kbps Sampling freq: 0 Time: 00:00:02

My environment:

DVSDK-version:dvsdk_2_00_00_22

h264 dec-version:1.00.018

platform:dm6467

David

over 15 years ago

0 Prateek Bansal over 15 years ago

TI__Expert 4510 points

Hi David,

I understand you are using H.264 decoder version 1.00.018 on DM6467.

Can you provide more detailed log running with CE_DEBUG=2 or 3?

With regards to IDR frame, the FAQ column below specifies that v1.10 of the decoder does support decoding of non-IDR frame. At first glance, based on the release notes of v1.00 and v1.10, it looks like v1.00 should also support the non-IDR frame support.

http://processors.wiki.ti.com/index.php/DaVinciHD_Codecs_FAQ#H264_Decoder_FAQs_.28Reference_Codec_Version_--_REL_200_V_H264AVC_D_HP_DM6467_1_10_012.29

Btw, there are later codec versions v1.10 and v1.20 of H.264 decoder available on DM6467 downloadable from links below -

http://software-dl.ti.com/dsps/dsps_public_sw/codecs/DM6467/index_FDS.html

Prateek

0 david shen47423 over 15 years ago in reply to Prateek Bansal

Prodigy 40 points

Hi Parteek,

Thanks for your reply,below is CE_DEBUG:

CE_DEBUG=3 ./decode -v video1.264
Decode demo started.
@0,650,047us: [+4 T:0x4001f950 S:0xbeb38a94] OG - Global_init> This program was built with the following packages:
@0,650,656us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package gnu.targets.rts470MV (/home/colin/dvsdk_2_00_00_22_6467/xdctools_3_10_03/packages/gnu/targets/rts470MV/) [1,0,0,0,1225517438755]
@0,650,888us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package ti.sdo.codecs.mpeg2dec (/mnt/win/PlayerDepend/player_codec_6467_colin/ti/sdo/codecs/mpeg2dec/) [1,0,0,1263292262000]
@0,651,048us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package ti.sdo.codecs.h264dec (/mnt/win/PlayerDepend/player_codec_6467_colin/ti/sdo/codecs/h264dec/) [1,0,0,0]
@0,651,198us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package ti.sdo.codecs.aachedec (/mnt/win/PlayerDepend/player_codec_6467_colin/ti/sdo/codecs/aachedec/) [1,0,0,1263292261000]
@0,651,343us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package ti.sdo.codecs.dolbyac3dec (/mnt/win/PlayerDepend/player_codec_6467_colin/ti/sdo/codecs/dolbyac3dec/) [1,0,0,0]
@0,651,483us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package ti.sdo.codecs.mpeg4dec (/mnt/win/PlayerDepend/player_codec_6467_colin/ti/sdo/codecs/mpeg4dec/) [1,0,0,1263292264000]
@0,651,627us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package ti.sdo.codecs.mp3dec (/mnt/win/PlayerDepend/player_codec_6467_colin/ti/sdo/codecs/mp3dec/) [1,0,0,0]
@0,651,767us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package ti.sdo.ce.global (/home/colin/dvsdk_2_00_00_22_6467/codec_engine_2_23_01/packages/ti/sdo/ce/global/) [1,0,0,1240276693464]
@0,651,911us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package ti.xdais.dm (/home/colin/dvsdk_2_00_00_22_6467/xdais_6_23/packages/ti/xdais/dm/) [1,0,5,1236972452910]
@0,652,049us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package ti.sdo.utils.trace (/home/colin/dvsdk_2_00_00_22_6467/framework_components_2_23_01/packages/ti/sdo/utils/trace/) [1,0,0,1240266922890]
@0,652,196us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package ti.sdo.ce.utils.xdm (/home/colin/dvsdk_2_00_00_22_6467/codec_engine_2_23_01/packages/ti/sdo/ce/utils/xdm/) [1,0,2,1240276919490]
@0,652,341us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package dsplink.gpp (/home/colin/dvsdk_2_00_00_22_6467/dsplink-1_61_03-prebuilt/packages/dsplink/gpp/) [5,0,0,1239931267000]
@0,652,480us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package ti.sdo.linuxutils.cmem (/home/colin/dvsdk_2_00_00_22_6467/linuxutils_2_23_01/packages/ti/sdo/linuxutils/cmem/) [2,2,0,1240253001679]
@0,652,625us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package ti.xdais (/home/colin/dvsdk_2_00_00_22_6467/xdais_6_23/packages/ti/xdais/) [1,2,1,1236972427008]
@0,652,763us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package ti.sdo.ce.node (/home/colin/dvsdk_2_00_00_22_6467/codec_engine_2_23_01/packages/ti/sdo/ce/node/) [1,0,0,1240276734858]
@0,652,904us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package ti.sdo.fc.global (/home/colin/dvsdk_2_00_00_22_6467/framework_components_2_23_01/packages/ti/sdo/fc/global/) [1,0,0,1240266696499]
@0,653,050us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package ti.sdo.fc.dman3 (/home/colin/dvsdk_2_00_00_22_6467/framework_components_2_23_01/packages/ti/sdo/fc/dman3/) [1,0,4,1240266658662]
@0,653,197us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package ti.sdo.fc.memutils (/home/colin/dvsdk_2_00_00_22_6467/framework_components_2_23_01/packages/ti/sdo/fc/memutils/) [1,0,0,1240266801007]
@0,653,343us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package ti.sdo.fc.acpy3 (/home/colin/dvsdk_2_00_00_22_6467/framework_components_2_23_01/packages/ti/sdo/fc/acpy3/) [1,0,4,1240266659108]
@0,653,523us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package gnu.targets (/home/colin/dvsdk_2_00_00_22_6467/xdctools_3_10_03/packages/gnu/targets/) [1,0,1,1225517427930]
@0,653,664us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package ti.catalog.c470 (/home/colin/dvsdk_2_00_00_22_6467/xdctools_3_10_03/packages/ti/catalog/c470/) [1,0,1,0,0]
@0,653,809us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package ti.catalog.c6000 (/home/colin/dvsdk_2_00_00_22_6467/xdctools_3_10_03/packages/ti/catalog/c6000/) [1,0,0,0,0]
@0,654,008us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package ti.platforms.evmDM6467 (/home/colin/dvsdk_2_00_00_22_6467/xdctools_3_10_03/packages/ti/platforms/evmDM6467/) [1,0,0,0,0]
@0,989,117us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package ti.sdo.ce.osal (/home/colin/dvsdk_2_00_00_22_6467/codec_engine_2_23_01/packages/ti/sdo/ce/osal/) [2,0,2,1240276730877]
@0,989,296us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package ti.sdo.ce.osal.linux (/home/colin/dvsdk_2_00_00_22_6467/codec_engine_2_23_01/packages/ti/sdo/ce/osal/linux/) [2,0,1,1240276736840]
@0,989,555us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package ti.sdo.ce.ipc (/home/colin/dvsdk_2_00_00_22_6467/codec_engine_2_23_01/packages/ti/sdo/ce/ipc/) [2,0,1,1240276698961]
@0,989,714us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package ti.sdo.ce.ipc.dsplink (/home/colin/dvsdk_2_00_00_22_6467/codec_engine_2_23_01/packages/ti/sdo/ce/ipc/dsplink/) [2,0,1,1240276714152]
@0,989,864us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package ti.sdo.ce.alg (/home/colin/dvsdk_2_00_00_22_6467/codec_engine_2_23_01/packages/ti/sdo/ce/alg/) [1,0,1,1240276276798]
@0,990,010us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package ti.sdo.ce (/home/colin/dvsdk_2_00_00_22_6467/codec_engine_2_23_01/packages/ti/sdo/ce/) [1,0,6,1240276276491]
@0,990,155us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package ti.sdo.ce.video2 (/home/colin/dvsdk_2_00_00_22_6467/codec_engine_2_23_01/packages/ti/sdo/ce/video2/) [1,0,2,1240276933444]
@0,990,296us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package ti.sdo.codecs.mpeg2dec.ce (/mnt/win/PlayerDepend/player_codec_6467_colin/ti/sdo/codecs/mpeg2dec/ce/) [1,0,0,1263292262000]
@0,990,442us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package ti.sdo.codecs.h264dec.ce (/mnt/win/PlayerDepend/player_codec_6467_colin/ti/sdo/codecs/h264dec/ce/) [1,0,0,0]
@0,990,584us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package ti.sdo.ce.audio1 (/home/colin/dvsdk_2_00_00_22_6467/codec_engine_2_23_01/packages/ti/sdo/ce/audio1/) [1,0,1,1240276296900]
@0,990,726us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package ti.sdo.codecs.aachedec.ce (/mnt/win/PlayerDepend/player_codec_6467_colin/ti/sdo/codecs/aachedec/ce/) [1,0,0,1263292261000]
@0,990,868us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package ti.sdo.codecs.dolbyac3dec.ce (/mnt/win/PlayerDepend/player_codec_6467_colin/ti/sdo/codecs/dolbyac3dec/ce/) [1,0,0,0]
@0,991,008us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package ti.sdo.codecs.mpeg4dec.ce (/mnt/win/PlayerDepend/player_codec_6467_colin/ti/sdo/codecs/mpeg4dec/ce/) [1,0,0,1263292263000]
@0,991,153us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package ti.sdo.codecs.mp3dec.ce (/mnt/win/PlayerDepend/player_codec_6467_colin/ti/sdo/codecs/mp3dec/ce/) [1,0,0,0]
@0,991,293us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package ti.sdo.ce.speech (/home/colin/dvsdk_2_00_00_22_6467/codec_engine_2_23_01/packages/ti/sdo/ce/speech/) [1,0,2,1240276762193]
@0,991,437us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package ti.sdo.ce.speech1 (/home/colin/dvsdk_2_00_00_22_6467/codec_engine_2_23_01/packages/ti/sdo/ce/speech1/) [1,0,1,1240276763928]
@0,991,578us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package ti.sdo.ce.audio (/home/colin/dvsdk_2_00_00_22_6467/codec_engine_2_23_01/packages/ti/sdo/ce/audio/) [1,0,2,1240276296374]
@0,991,721us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package ti.sdo.ce.video (/home/colin/dvsdk_2_00_00_22_6467/codec_engine_2_23_01/packages/ti/sdo/ce/video/) [1,0,3,1240276930771]
@0,991,894us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package ti.sdo.ce.video1 (/home/colin/dvsdk_2_00_00_22_6467/codec_engine_2_23_01/packages/ti/sdo/ce/video1/) [1,0,2,1240276935900]
@0,992,044us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package ti.sdo.ce.image (/home/colin/dvsdk_2_00_00_22_6467/codec_engine_2_23_01/packages/ti/sdo/ce/image/) [1,0,3,1240276700525]
@0,992,190us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package ti.sdo.ce.image1 (/home/colin/dvsdk_2_00_00_22_6467/codec_engine_2_23_01/packages/ti/sdo/ce/image1/) [1,0,1,1240276698808]
@1,327,568us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package ti.sdo.dmai (/home/colin/dvsdk_2_00_00_22_6467/dmai_1_20_00_06/packages/ti/sdo/dmai/) [1,0,0,1271906371000]
@1,327,745us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package ti.sdo.simplewidget (/home/colin/dvsdk_2_00_00_22_6467/dvsdk_demos_2_00_00_07/packages/ti/sdo/simplewidget/) []
@1,327,901us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package ti.sdo.ce.bioslog (/home/colin/dvsdk_2_00_00_22_6467/codec_engine_2_23_01/packages/ti/sdo/ce/bioslog/) [1,0,1,1240276303166]
@1,328,048us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package ti.sdo.ce.utils.trace (/home/colin/dvsdk_2_00_00_22_6467/codec_engine_2_23_01/packages/ti/sdo/ce/utils/trace/) [1,0,1,1240276915917]
@1,328,198us: [+4 T:0x4001f950 S:0xbeb38a94] OG -     package decode_config (/home/colin/dvsdk_2_00_00_22_6467/dvsdk_demos_2_00_00_07/dm6467/decode/decode_config/) []
@1,328,410us: [+0 T:0x4001f950 S:0xbeb38a9c] OG - Global_atexit> enter (fxn=0x32168)
@1,328,583us: [+0 T:0x4001f950 S:0xbeb38a9c] OG - Global_atexit> enter (fxn=0x315b4)
@1,328,841us: [+0 T:0x4001f950 S:0xbeb38a6c] OM - Memory_alloc> Enter(0x18)
@1,329,002us: [+0 T:0x4001f950 S:0xbeb38a6c] OM - Memory_alloc> return (0x620f0)
@1,329,508us: [+0 T:0x4001f950 S:0xbeb38a8c] OG - Global_atexit> enter (fxn=0x2f4e4)
@1,329,763us: [+0 T:0x4001f950 S:0xbeb38a64] OM - Memory_alloc> Enter(0x18)
@1,329,931us: [+0 T:0x4001f950 S:0xbeb38a64] OM - Memory_alloc> return (0x62140)
@1,330,075us: [+0 T:0x4001f950 S:0xbeb38a84] OG - Global_atexit> enter (fxn=0x2e390)
@1,330,336us: [+0 T:0x4001f950 S:0xbeb38a8c] OG - Global_atexit> enter (fxn=0x30c18)
@1,330,595us: [+0 T:0x4001f950 S:0xbeb38a84] ti.sdo.ce.osal.Sem - Sem_create> count: 0
@1,330,749us: [+0 T:0x4001f950 S:0xbeb38a6c] OM - Memory_alloc> Enter(0x14)
@1,330,893us: [+0 T:0x4001f950 S:0xbeb38a6c] OM - Memory_alloc> return (0x621c0)
@1,331,060us: [+0 T:0x4001f950 S:0xbeb38a84] ti.sdo.ce.osal.Sem - Leaving Sem_create> sem[0x621c0]
@1,331,213us: [+0 T:0x4001f950 S:0xbeb38a84] ti.sdo.ce.osal.Sem - Sem_create> count: 0
@1,331,344us: [+0 T:0x4001f950 S:0xbeb38a6c] OM - Memory_alloc> Enter(0x14)
@1,331,483us: [+0 T:0x4001f950 S:0xbeb38a6c] OM - Memory_alloc> return (0x621d8)
@1,331,617us: [+0 T:0x4001f950 S:0xbeb38a84] ti.sdo.ce.osal.Sem - Leaving Sem_create> sem[0x621d8]
@1,331,750us: [+0 T:0x4001f950 S:0xbeb38a6c] OM - Memory_alloc> Enter(0x18)
@1,331,889us: [+0 T:0x4001f950 S:0xbeb38a6c] OM - Memory_alloc> return (0x621f0)
@1,332,023us: [+0 T:0x4001f950 S:0xbeb38a64] OT - Thread_create> Enter (fxn=0x2cb68, attrs=0x0)
@1,332,163us: [+0 T:0x4001f950 S:0xbeb38a4c] OM - Memory_alloc> Enter(0x64)
@1,332,303us: [+0 T:0x4001f950 S:0xbeb38a4c] OM - Memory_alloc> return (0x62210)
@1,333,088us: [+0 T:0x4001f950 S:0xbeb38a64] OT - Thread_create> Exit (task=0x62210)
@1,333,279us: [+0 T:0x4001f950 S:0xbeb38a9c] OG - Global_atexit> enter (fxn=0x2c558)
@1,333,452us: [+0 T:0x4001f950 S:0xbeb38a9c] OG - Global_atexit> enter (fxn=0x2e8dc)
@1,333,678us: [+0 T:0x4001f950 S:0xbeb38a84] ti.sdo.ce.alg - ALG_init> Enter
@1,333,826us: [+0 T:0x4001f950 S:0xbeb38a74] OG - Global_atexit> enter (fxn=0x2c1a4)
@1,333,973us: [+0 T:0x4001f950 S:0xbeb38aa4] ti.sdo.ce.alg - ALG_init> Exit
@1,334,103us: [+0 T:0x4001f950 S:0xbeb38a9c] OG - Global_atexit> enter (fxn=0x2b5d0)
@1,334,305us: [+0 T:0x4001f950 S:0xbeb38a74] OM - Memory_alloc> Enter(0x18)
@1,334,466us: [+0 T:0x4001f950 S:0xbeb38a74] OM - Memory_alloc> return (0x623f0)
@1,334,609us: [+0 T:0x4001f950 S:0xbeb38a9c] OG - Global_atexit> enter (fxn=0x31108)
@1,334,840us: [+6 T:0x4001f950 S:0xbeb38a94] CE - Engine_init> CE debugging on (CE_DEBUG=3; allowed CE_DEBUG levels: 1=min, 2=good, 3=max)
@1,335,012us: [+0 T:0x4001f950 S:0xbeb38a84] OG - Global_atexit> enter (fxn=0x28a64)
@1,668,866us: [+0 T:0x4001f950 S:0xbeb38a64] OM - Memory_alloc> Enter(0x18)
@1,669,050us: [+0 T:0x4001f950 S:0xbeb38a64] OM - Memory_alloc> return (0x62430)
@1,669,202us: [+0 T:0x4001f950 S:0xbeb38a64] OM - Memory_alloc> Enter(0x18)
@1,669,351us: [+0 T:0x4001f950 S:0xbeb38a64] OM - Memory_alloc> return (0x62450)
@1,669,675us: [+0 T:0x4001f950 S:0xbeb38a64] OM - Memory_alloc> Enter(0x18)
@1,669,838us: [+0 T:0x4001f950 S:0xbeb38a64] OM - Memory_alloc> return (0x62470)
@1,670,080us: [+0 T:0x4001f950 S:0xbeb38a9c] CS - Server_init()
@1,670,234us: [+0 T:0x4001f950 S:0xbeb38a9c] CS - Server_init> Global_useLinkArbiter = 0
@1,670,378us: [+0 T:0x4001f950 S:0xbeb38a9c] OG - Global_atexit> enter (fxn=0x26d44)
@1,670,625us: [+0 T:0x4001f950 S:0xbeb38aac] OG - Global_atexit> enter (fxn=0x13994)
TraceUtil_start> note: CE_DEBUG env. var is set, so TraceUtil is not active (unset CE_DEBUG if you need TraceUtil)
@1,673,984us: [+0 T:0x41bc8490 S:0x41bc7ca4] CE - Engine_open> Enter('decode', 0x0, 0x41bc7d14)
@1,674,179us: [+0 T:0x41bc8490 S:0x41bc7c8c] OM - Memory_alloc> Enter(0x2c)
@1,674,366us: [+0 T:0x41bc8490 S:0x41bc7c8c] OM - Memory_alloc> return (0x62ab0)
@1,674,517us: [+0 T:0x41bc8490 S:0x41bc7ca4] CE - rserverOpen('decodeCombo.x64P'), count = 0
@1,674,655us: [+0 T:0x41bc8490 S:0x41bc7c6c] OP - Processor_create> Enter(imageName='decodeCombo.x64P', linkCfg='(null)', attrs=0x41bc7cf8)
@1,675,322us: [+1 T:0x40bc8490 S:0x40bc7d74] OP - daemon> thread created.
@1,675,871us: [+0 T:0x41bc8490 S:0x41bc7c54] OM - Memory_alloc> Enter(0x24)
@1,676,058us: [+0 T:0x41bc8490 S:0x41bc7c54] OM - Memory_alloc> return (0x62ae0)
@1,676,208us: [+0 T:0x41bc8490 S:0x41bc7c4c] OP - doCmd> Enter (cmdId=1, proc=0x62ae0)
@1,676,349us: [+0 T:0x41bc8490 S:0x41bc7c3c] ti.sdo.ce.osal.Sem - Entered Sem_post> sem[0x621c0]
@1,676,571us: [+0 T:0x41bc8490 S:0x41bc7c4c] ti.sdo.ce.osal.Sem - Leaving Sem_post> sem[0x621c0]
@1,676,729us: [+0 T:0x41bc8490 S:0x41bc7c2c] ti.sdo.ce.osal.Sem - Entered Sem_pend> sem[0x621d8] timeout[0xffffffff]
@1,676,940us: [+0 T:0x40bc8490 S:0x40bc7d74] OP - getCmd_d> Enter (proc=0x40bc7de8)
@1,677,105us: [+0 T:0x40bc8490 S:0x40bc7d54] ti.sdo.ce.osal.Sem - Entered Sem_pend> sem[0x621c0] timeout[0xffffffff]
@1,677,292us: [+0 T:0x40bc8490 S:0x40bc7d54] ti.sdo.ce.osal.Sem - Leaving Sem_pend> sem[0x621c0] status[0]
@1,677,438us: [+0 T:0x40bc8490 S:0x40bc7d74] OP - getCmd_d> Exit (result=1)
@1,677,570us: [+0 T:0x40bc8490 S:0x40bc7d74] OP - Processor_create_d> Enter(proc=0x62ae0)
@1,677,703us: [+2 T:0x40bc8490 S:0x40bc7d74] OP - Processor_create_d> Initializing DSP PROC...
@1,677,843us: [+2 T:0x40bc8490 S:0x40bc7d74] OP - Processor_create_d> Using DspLink config data for entry #0 [server 'decodeCombo.x64P']
@1,677,995us: [+0 T:0x40bc8490 S:0x40bc7d5c] OM - Memory_alloc> Enter(0xe0)
@1,678,146us: [+0 T:0x40bc8490 S:0x40bc7d5c] OM - Memory_alloc> return (0x62b08)
@1,678,290us: [+2 T:0x40bc8490 S:0x40bc7d74] OP - Processor_create_d> Adding DSP segment #0 to Link configuration: name='DDR2', startAddress=0x87800000, sizeInBytes=0x700000, shared=1, syncd=0
@1,678,466us: [+2 T:0x40bc8490 S:0x40bc7d74] OP - Processor_create_d> Adding DSP segment #1 to Link configuration: name='DSPLINKMEM', startAddress=0x87f00080, sizeInBytes=0xfff80, shared=1, syncd=0
@1,678,638us: [+2 T:0x40bc8490 S:0x40bc7d74] OP - Processor_create_d> Adding DSP segment #2 to Link configuration: name='RESET_VECTOR', startAddress=0x87f00000, sizeInBytes=0x80, shared=1, syncd=0
@1,678,807us: [+2 T:0x40bc8490 S:0x40bc7d74] OP - Processor_create_d> Adding DSP segment #3 to Link configuration: name='DDRALGHEAP', startAddress=0x86800000, sizeInBytes=0x1000000, shared=0, syncd=0
@1,678,977us: [+2 T:0x40bc8490 S:0x40bc7d74] OP - Processor_create_d> DODSPCTRL was=1; now=0
@1,682,265us: [+2 T:0x40bc8490 S:0x40bc7d74] OP - Processor_create_d> Attaching to DSP PROC...
@1,688,828us: [+2 T:0x40bc8490 S:0x40bc7d74] OP - Processor_create_d> Opening MSGQ pool...
@1,689,783us: [+2 T:0x40bc8490 S:0x40bc7d74] OP - Processor_create_d> Loading decodeCombo.x64P on DSP (1 args)...
@2,327,258us: [+2 T:0x40bc8490 S:0x40bc7d74] OP - Processor_create_d> Starting DSP PROC...
@2,522,506us: [+2 T:0x413c8490 S:0x413c7c2c] ti.sdo.dmai - [Display] Video output set to size 720x480 pitch 720
@2,522,932us: [+2 T:0x413c8490 S:0x413c7ab4] ti.sdo.dmai - [BufTab] Allocating BufTab for 3 buffers
@2,523,563us: [+0 T:0x413c8490 S:0x413c7a84] OM - Memory_getBufferPhysicalAddress> Enter(virtAddr=0x42449000, size=4)
@2,523,757us: [+1 T:0x413c8490 S:0x413c7a84] OM - Memory__getPhysicalAddress> Enter(virtAddr=0x42449000, size=4)
@2,523,909us: [+1 T:0x413c8490 S:0x413c7a84] OM - Memory__getPhysicalAddress> returning physAddr=0x0
@2,524,065us: [+1 T:0x413c8490 S:0x413c7a84] OM - Memory_getBufferPhysicalAddress> CMEM_getPhys(0x42449000) = 0x83000000.
@2,524,229us: [+1 T:0x413c8490 S:0x413c7a3c] OM - Memory__addContigBuf> Enter(virtAddr=0x42449000, size=4, physAddr=0x83000000)
@2,524,380us: [+1 T:0x413c8490 S:0x413c7a3c] OM - Memory__addContigBuf> creating new contigBuf object
@2,524,512us: [+0 T:0x413c8490 S:0x413c7a24] OM - Memory_alloc> Enter(0x10)
@2,524,652us: [+0 T:0x413c8490 S:0x413c7a24] OM - Memory_alloc> return (0x62e20)
@2,524,786us: [+1 T:0x413c8490 S:0x413c7a3c] OM - Memory__addContigBuf> returning: cb->phys=0x83000000, cb->size=4, cb->virt=0x42449000
@2,524,931us: [+0 T:0x413c8490 S:0x413c7a84] OM - Memory_getBufferPhysicalAddress> return (0x83000000)
@2,525,069us: [+2 T:0x413c8490 S:0x413c7abc] ti.sdo.dmai - [Buffer] Set user pointer 0x42449000 (physical 0x83000000)
@2,853,450us: [+2 T:0x413c8490 S:0x413c7adc] ti.sdo.dmai - [Dmai] Driver buffer 0 mapped to 0x42449000 has physical address 0x83000000
@2,854,047us: [+0 T:0x413c8490 S:0x413c7a84] OM - Memory_getBufferPhysicalAddress> Enter(virtAddr=0x4283e000, size=4)
@2,854,243us: [+1 T:0x413c8490 S:0x413c7a84] OM - Memory__getPhysicalAddress> Enter(virtAddr=0x4283e000, size=4)
@2,854,395us: [+1 T:0x413c8490 S:0x413c7a84] OM - Memory__getPhysicalAddress> returning physAddr=0x0
@2,854,552us: [+1 T:0x413c8490 S:0x413c7a84] OM - Memory_getBufferPhysicalAddress> CMEM_getPhys(0x4283e000) = 0x82c00000.
@2,854,718us: [+1 T:0x413c8490 S:0x413c7a3c] OM - Memory__addContigBuf> Enter(virtAddr=0x4283e000, size=4, physAddr=0x82c00000)
@2,854,872us: [+1 T:0x413c8490 S:0x413c7a3c] OM - Memory__addContigBuf> creating new contigBuf object
@2,855,002us: [+0 T:0x413c8490 S:0x413c7a24] OM - Memory_alloc> Enter(0x10)
@2,855,145us: [+0 T:0x413c8490 S:0x413c7a24] OM - Memory_alloc> return (0x62e38)
@2,855,282us: [+1 T:0x413c8490 S:0x413c7a3c] OM - Memory__addContigBuf> returning: cb->phys=0x82c00000, cb->size=4, cb->virt=0x4283e000
@2,855,428us: [+0 T:0x413c8490 S:0x413c7a84] OM - Memory_getBufferPhysicalAddress> return (0x82c00000)
@2,855,566us: [+2 T:0x413c8490 S:0x413c7abc] ti.sdo.dmai - [Buffer] Set user pointer 0x4283e000 (physical 0x82c00000)
@3,184,097us: [+2 T:0x413c8490 S:0x413c7adc] ti.sdo.dmai - [Dmai] Driver buffer 1 mapped to 0x4283e000 has physical address 0x82c00000
@3,184,702us: [+0 T:0x413c8490 S:0x413c7a84] OM - Memory_getBufferPhysicalAddress> Enter(virtAddr=0x42c33000, size=4)
@3,184,892us: [+1 T:0x413c8490 S:0x413c7a84] OM - Memory__getPhysicalAddress> Enter(virtAddr=0x42c33000, size=4)
@3,185,043us: [+1 T:0x413c8490 S:0x413c7a84] OM - Memory__getPhysicalAddress> returning physAddr=0x0
@3,185,206us: [+1 T:0x413c8490 S:0x413c7a84] OM - Memory_getBufferPhysicalAddress> CMEM_getPhys(0x42c33000) = 0x82800000.
@3,185,368us: [+1 T:0x413c8490 S:0x413c7a3c] OM - Memory__addContigBuf> Enter(virtAddr=0x42c33000, size=4, physAddr=0x82800000)
@3,185,526us: [+1 T:0x413c8490 S:0x413c7a3c] OM - Memory__addContigBuf> creating new contigBuf object
@3,185,659us: [+0 T:0x413c8490 S:0x413c7a24] OM - Memory_alloc> Enter(0x10)
@3,185,799us: [+0 T:0x413c8490 S:0x413c7a24] OM - Memory_alloc> return (0x62e50)
@3,185,937us: [+1 T:0x413c8490 S:0x413c7a3c] OM - Memory__addContigBuf> returning: cb->phys=0x82800000, cb->size=4, cb->virt=0x42c33000
@3,186,083us: [+0 T:0x413c8490 S:0x413c7a84] OM - Memory_getBufferPhysicalAddress> return (0x82800000)
@3,186,221us: [+2 T:0x413c8490 S:0x413c7abc] ti.sdo.dmai - [Buffer] Set user pointer 0x42c33000 (physical 0x82800000)
@3,514,607us: [+2 T:0x413c8490 S:0x413c7adc] ti.sdo.dmai - [Dmai] Driver buffer 2 mapped to 0x42c33000 has physical address 0x82800000
@3,515,295us: [+2 T:0x40bc8490 S:0x40bc7d74] OP - Processor_create_d> Opening remote transport...
@3,521,084us: [+2 T:0x40bc8490 S:0x40bc7d74] OP - Processor_create_d> return (1)
@3,521,280us: [+0 T:0x40bc8490 S:0x40bc7d64] ti.sdo.ce.osal.Sem - Entered Sem_post> sem[0x621d8]
@3,521,480us: [+0 T:0x41bc8490 S:0x41bc7c2c] ti.sdo.ce.osal.Sem - Leaving Sem_pend> sem[0x621d8] status[0]
@3,521,644us: [+0 T:0x41bc8490 S:0x41bc7c4c] OP - doCmd> Exit (result=1)
@3,521,777us: [+0 T:0x41bc8490 S:0x41bc7c6c] OP - Processor_create> return (0x62ae0)
@3,521,912us: [+0 T:0x41bc8490 S:0x41bc7ca4] CE - rserverOpen('decodeCombo.x64P'): 0x60f30 done.
@3,522,052us: [+0 T:0x41bc8490 S:0x41bc7c8c] OM - Memory_alloc> Enter(0x24)
@3,522,198us: [+0 T:0x41bc8490 S:0x41bc7c8c] OM - Memory_alloc> return (0x62e88)
@3,522,358us: [+0 T:0x41bc8490 S:0x41bc7c7c] OC - Comm_create> Enter(queueName='decode_1037_0', queue=0x62ac0, attrs=0x0)
@3,522,507us: [+0 T:0x41bc8490 S:0x41bc7c64] OM - Memory_alloc> Enter(0x4)
@3,522,645us: [+0 T:0x41bc8490 S:0x41bc7c64] OM - Memory_alloc> return (0x62eb0)
@3,523,082us: [+0 T:0x41bc8490 S:0x41bc7c7c] OC - Comm_create> return (0x62eb0)
@3,523,296us: [+0 T:0x41bc8490 S:0x41bc7c84] OM - Memory_free> Enter(0x62e88, 0x24)
@3,523,447us: [+0 T:0x41bc8490 S:0x41bc7c84] OM - Memory_free> return (0x1)
@3,523,583us: [+0 T:0x41bc8490 S:0x41bc7c84] OC - Comm_alloc> Enter(poolId=0x0, msg=0x62acc, size=576)
@3,523,764us: [+0 T:0x41bc8490 S:0x41bc7c84] OC - Comm_alloc> msg=0x423d8900, returning (0)
@3,523,915us: [+0 T:0x41bc8490 S:0x41bc7c6c] OC - Comm_locate> Enter(queueName='rmsq', queue=0x62abc)
@3,524,252us: [+0 T:0x40bc8490 S:0x40bc7d74] ti.sdo.ce.osal.Sem - Leaving Sem_post> sem[0x621d8]
@3,524,443us: [+0 T:0x40bc8490 S:0x40bc7d74] OP - getCmd_d> Enter (proc=0x40bc7de8)
@3,524,589us: [+0 T:0x40bc8490 S:0x40bc7d54] ti.sdo.ce.osal.Sem - Entered Sem_pend> sem[0x621c0] timeout[0xffffffff]
@3,527,374us: [+0 T:0x41bc8490 S:0x41bc7c6c] OC - Comm_locate> return (0)
@3,527,559us: [+0 T:0x41bc8490 S:0x41bc7ca4] CE - checkServer(0x62ab0)
@3,527,721us: [+0 T:0x41bc8490 S:0x41bc7c74] OC - Comm_put> Enter(queue=0x0, msg=0x423d8900)
@3,527,929us: [+0 T:0x41bc8490 S:0x41bc7c74] OC - Comm_put> return (0)
@3,528,113us: [+0 T:0x41bc8490 S:0x41bc7c6c] OC - Comm_get> Enter(queue=0x10000, msg=0x41bc7d10, timeout=-1)
@3,528,410us: [+0 T:0x41bc8490 S:0x41bc7c6c] OC - Comm_get> MSGQ_get() status=0x8000, return (0)
@3,528,594us: [+0 T:0x41bc8490 S:0x41bc7ca4] CE - rmsInit> RMS initialized(0x62ab0); CE_DEBUG on, setting DSP trace mask to *+01234567,GT_prefix=12345,GT_time=3
@3,528,759us: [+0 T:0x41bc8490 S:0x41bc7c7c] CE - Engine_setTrace> Enter(engine=0x62ab0, mask='*+01234567,GT_prefix=12345,GT_time=3')
@3,528,967us: [+1 T:0x41bc8490 S:0x41bc7c7c] CE - Engine_setTrace> Requesting DSP set trace ...
@3,529,102us: [+0 T:0x41bc8490 S:0x41bc7c4c] OC - Comm_put> Enter(queue=0x0, msg=0x423d8900)
@3,529,294us: [+0 T:0x41bc8490 S:0x41bc7c4c] OC - Comm_put> return (0)
@3,529,542us: [+0 T:0x41bc8490 S:0x41bc7c44] OC - Comm_get> Enter(queue=0x10000, msg=0x41bc7cc4, timeout=-1)
@3,531,447us: [+0 T:0x41bc8490 S:0x41bc7c44] OC - Comm_get> MSGQ_get() status=0x8000, return (0)
@3,531,666us: [+0 T:0x41bc8490 S:0x41bc7c7c] CE - Engine_setTrace> return(0)
@3,531,838us: [+0 T:0x41bc8490 S:0x41bc7c0c] OC - Comm_put> Enter(queue=0x0, msg=0x423d8900)
@3,532,042us: [+0 T:0x41bc8490 S:0x41bc7c0c] OC - Comm_put> return (0)
@3,532,244us: [+0 T:0x41bc8490 S:0x41bc7c04] OC - Comm_get> Enter(queue=0x10000, msg=0x41bc7ca4, timeout=-1)
@3,532,544us: [+0 T:0x41bc8490 S:0x41bc7c04] OC - Comm_get> MSGQ_get() status=0x8000, return (0)
[DSP] @2,337,362tk: [+0 T:0x8680006c S:0x86803fe4] OG - Global_setSpecialTrace> enter(mask='*+01234567,GT_prefix=12345,GT_time=3')
[DSP] @2,337,453tk: [+4 T:0x8680006c S:0x86803fe4] OG - Global_setSpecialTrace> This program was built with the following packages:
[DSP] @2,337,516tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package ti.targets.rts6000 (/home/colin/dvsdk_2_00_00_22_6467/xdctools_3_10_03/packages/ti/targets/rts6000/) [1,0,0,0,1225517497925]
[DSP] @2,337,601tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package ti.sdo.codecs.mpeg2dec (/home/colin/workdir/player/PlayerDepend/player_codec_6467_colin/ti/sdo/codecs/mpeg2dec/) [1,0,0,1256561562000]
[DSP] @2,337,688tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package ti.sdo.codecs.aachedec (/home/colin/workdir/player/PlayerDepend/player_codec_6467_colin/ti/sdo/codecs/aachedec/) [1,0,0,1256561562000]
[DSP] @2,337,777tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package ti.sdo.codecs.dolbyac3dec (/home/colin/workdir/player/PlayerDepend/player_codec_6467_colin/ti/sdo/codecs/dolbyac3dec/) [1,0,0,0]
[DSP] @2,337,862tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package ti.sdo.codecs.h264dec (/home/colin/workdir/player/PlayerDepend/player_codec_6467_colin/ti/sdo/codecs/h264dec/) [1,0,0,1256561562000]
[DSP] @2,337,949tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package ti.sdo.codecs.hdvicp (/home/colin/workdir/player/PlayerDepend/player_codec_6467_colin/ti/sdo/codecs/hdvicp/) [1,0,0,1256561562000]
[DSP] @2,338,036tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package ti.sdo.codecs.mpeg4dec (/home/colin/workdir/player/PlayerDepend/player_codec_6467_colin/ti/sdo/codecs/mpeg4dec/) [1,0,0,1256561562000]
[DSP] @2,338,124tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package ti.sdo.codecs.mp3dec (/home/colin/workdir/player/PlayerDepend/player_codec_6467_colin/ti/sdo/codecs/mp3dec/) [1,0,0,0]
[DSP] @2,338,206tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package ti.sdo.fc.global (/home/colin/dvsdk_2_00_00_22_6467/framework_components_2_23_01/packages/ti/sdo/fc/global/) [1,0,0,1240266696499]
[DSP] @2,338,293tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package ti.sdo.fc.ires.hdvicp (/home/colin/dvsdk_2_00_00_22_6467/framework_components_2_23_01/packages/ti/sdo/fc/ires/hdvicp/) [1,0,1,1240266763264]
[DSP] @2,338,383tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package ti.sdo.fc.dman3 (/home/colin/dvsdk_2_00_00_22_6467/framework_components_2_23_01/packages/ti/sdo/fc/dman3/) [1,0,4,1240266658662]
[DSP] @2,338,469tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package ti.sdo.edma3.rm (/home/colin/dvsdk_2_00_00_22_6467/edma3_lld_1_05_00/packages/ti/sdo/edma3/rm/) [1,3,3,0]
[DSP] @2,338,547tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package ti.sdo.fc.ires.nullresource (/home/colin/dvsdk_2_00_00_22_6467/framework_components_2_23_01/packages/ti/sdo/fc/ires/nullresource/) [1,0,0,1240266782634]
[DSP] @2,338,641tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package ti.sdo.ce.global (/home/colin/dvsdk_2_00_00_22_6467/codec_engine_2_23_01/packages/ti/sdo/ce/global/) [1,0,0,1240276693464]
[DSP] @2,338,724tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package ti.xdais.dm (/home/colin/dvsdk_2_00_00_22_6467/xdais_6_23/packages/ti/xdais/dm/) [1,0,5,1236972452910]
[DSP] @2,338,801tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package ti.sdo.utils.trace (/home/colin/dvsdk_2_00_00_22_6467/framework_components_2_23_01/packages/ti/sdo/utils/trace/) [1,0,0,1240266922890]
[DSP] @2,338,890tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package ti.sdo.ce.utils.xdm (/home/colin/dvsdk_2_00_00_22_6467/codec_engine_2_23_01/packages/ti/sdo/ce/utils/xdm/) [1,0,2,1240276919490]
[DSP] @2,338,977tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package ti.xdais (/home/colin/dvsdk_2_00_00_22_6467/xdais_6_23/packages/ti/xdais/) [1,2,1,1236972427008]
[DSP] @2,339,051tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package ti.sdo.ce.node (/home/colin/dvsdk_2_00_00_22_6467/codec_engine_2_23_01/packages/ti/sdo/ce/node/) [1,0,0,1240276734858]
[DSP] @2,339,133tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package ti.sdo.ce.ipc.dsplink.dsp (/home/colin/dvsdk_2_00_00_22_6467/codec_engine_2_23_01/packages/ti/sdo/ce/ipc/dsplink/dsp/) [2,0,1,1240276@3,873,397us: [+0 T:0x41bc8490 S:0x41bc7c0c] OC - Comm_put> Enter(queue=0x0, msg=0x423d8900)
@3,873,644us: [+0 T:0x41bc8490 S:0x41bc7c0c] OC - Comm_put> return (0)
@3,873,832us: [+0 T:0x41bc8490 S:0x41bc7c04] OC - Comm_get> Enter(queue=0x10000, msg=0x41bc7ca4, timeout=-1)
@4,210,857us: [+0 T:0x41bc8490 S:0x41bc7c04] OC - Comm_get> MSGQ_get() status=0x8000, return (0)
720026]
[DSP] @2,339,223tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package dsplink.dsp (/home/colin/dvsdk_2_00_00_22_6467/dsplink-1_61_03-prebuilt/packages/dsplink/dsp/) [1,4,0,1239931280000]
[DSP] @2,339,305tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package ti.rtdx (/home/colin/dvsdk_2_00_00_22_6467/bios_5_33_03/packages/ti/rtdx/) [2,0,0,4,1219531643983]
[DSP] @2,339,380tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package ti.psl (/home/colin/dvsdk_2_00_00_22_6467/bios_5_33_03/packages/ti/psl/) [5,0,0,0,0]
[DSP] @2,339,461tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package ti.bios (/home/colin/dvsdk_2_00_00_22_6467/bios_5_33_03/packages/ti/bios/) [5,2,5,6,1227035764854]
[DSP] @2,339,536tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package ti.sdo.fc.dskt2 (/home/colin/dvsdk_2_00_00_22_6467/framework_components_2_23_01/packages/ti/sdo/fc/dskt2/) [1,0,4,1240266678055]
[DSP] @2,339,621tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package ti.sdo.fc.rman (/home/colin/dvsdk_2_00_00_22_6467/framework_components_2_23_01/packages/ti/sdo/fc/rman/) [2,0,0,1240266813533]
[DSP] @2,339,706tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package ti.sdo.fc.hdintc (/home/colin/dvsdk_2_00_00_22_6467/framework_components_2_23_01/packages/ti/sdo/fc/hdintc/) [1,0,4,1240266707594]
[DSP] @2,339,793tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package ti.sdo.fc.memutils (/home/colin/dvsdk_2_00_00_22_6467/framework_components_2_23_01/packages/ti/sdo/fc/memutils/) [1,0,0,1240266801007]
[DSP] @2,339,881tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package ti.sdo.fc.edma3 (/home/colin/dvsdk_2_00_00_22_6467/framework_components_2_23_01/packages/ti/sdo/fc/edma3/) [2,0,0,1240266681138]
[DSP] @2,339,966tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package ti.sdo.fc.ires.edma3chan (/home/colin/dvsdk_2_00_00_22_6467/framework_components_2_23_01/packages/ti/sdo/fc/ires/edma3chan/) [1,0,0,1240266737833]
[DSP] @2,340,059tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package ti.sdo.fc.acpy3 (/home/colin/dvsdk_2_00_00_22_6467/framework_components_2_23_01/packages/ti/sdo/fc/acpy3/) [1,0,4,1240266659108]
[DSP] @2,340,144tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package ti.bios.utils (/home/colin/dvsdk_2_00_00_22_6467/biosutils_1_01_00/packages/ti/bios/utils/) [2,0,1,0,0]
[DSP] @2,340,221tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package ti.catalog.c6000 (/home/colin/dvsdk_2_00_00_22_6467/xdctools_3_10_03/packages/ti/catalog/c6000/) [1,0,0,0,0]
[DSP] @2,340,299tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package ti.platforms.evmDM6467 (/home/colin/dvsdk_2_00_00_22_6467/bios_5_33_03/packages/ti/platforms/evmDM6467/) [1,0,0,0,0]
[DSP] @2,340,381tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package ti.sdo.ce.osal (/home/colin/dvsdk_2_00_00_22_6467/codec_engine_2_23_01/packages/ti/sdo/ce/osal/) [2,0,2,1240276730877]
[DSP] @2,340,463tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package ti.sdo.ce.osal.bios (/home/colin/dvsdk_2_00_00_22_6467/codec_engine_2_23_01/packages/ti/sdo/ce/osal/bios/) [2,0,1,1240276734107]
[DSP] @2,340,549tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package ti.sdo.ce.ipc (/home/colin/dvsdk_2_00_00_22_6467/codec_engine_2_23_01/packages/ti/sdo/ce/ipc/) [2,0,1,1240276698961]
[DSP] @2,340,630tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package ti.sdo.ce.ipc.bios (/home/colin/dvsdk_2_00_00_22_6467/codec_engine_2_23_01/packages/ti/sdo/ce/ipc/bios/) [2,0,1,1240276705217]
[DSP] @2,340,715tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package ti.sdo.ce.alg (/home/colin/dvsdk_2_00_00_22_6467/codec_engine_2_23_01/packages/ti/sdo/ce/alg/) [1,0,1,1240276276798]
[DSP] @2,340,796tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package ti.sdo.ce (/home/colin/dvsdk_2_00_00_22_6467/codec_engine_2_23_01/packages/ti/sdo/ce/) [1,0,6,1240276276491]
[DSP] @2,340,875tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package ti.sdo.ce.bioslog (/home/colin/dvsdk_2_00_00_22_6467/codec_engine_2_23_01/packages/ti/sdo/ce/bioslog/) [1,0,1,1240276303166]
[DSP] @2,340,959tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package ti.sdo.ce.video2 (/home/colin/dvsdk_2_00_00_22_6467/codec_engine_2_23_01/packages/ti/sdo/ce/video2/) [1,0,2,1240276933444]
[DSP] @2,341,042tk: [+4 T:0x8680006c S:0x86@4,550,095us: [+0 T:0x41bc8490 S:0x41bc7c0c] OC - Comm_put> Enter(queue=0x0, msg=0x423d8900)
@4,550,324us: [+0 T:0x41bc8490 S:0x41bc7c0c] OC - Comm_put> return (0)
@4,550,511us: [+0 T:0x41bc8490 S:0x41bc7c04] OC - Comm_get> Enter(queue=0x10000, msg=0x41bc7ca4, timeout=-1)
@4,550,816us: [+0 T:0x41bc8490 S:0x41bc7c04] OC - Comm_get> MSGQ_get() status=0x8000, return (0)
803fe4] OG -     package ti.sdo.codecs.mpeg2dec.ce (/home/colin/workdir/player/PlayerDepend/player_codec_6467_colin/ti/sdo/codecs/mpeg2dec/ce/) [1,0,0,1256561562000]
[DSP] @2,341,132tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package ti.sdo.ce.audio1 (/home/colin/dvsdk_2_00_00_22_6467/codec_engine_2_23_01/packages/ti/sdo/ce/audio1/) [1,0,1,1240276296900]
[DSP] @2,341,217tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package ti.sdo.codecs.aachedec.ce (/home/colin/workdir/player/PlayerDepend/player_codec_6467_colin/ti/sdo/codecs/aachedec/ce/) [1,0,0,1256561562000]
[DSP] @2,341,308tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package ti.sdo.codecs.dolbyac3dec.ce (/home/colin/workdir/player/PlayerDepend/player_codec_6467_colin/ti/sdo/codecs/dolbyac3dec/ce/) [1,0,0,0]
[DSP] @2,341,396tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package ti.sdo.codecs.h264dec.ce (/home/colin/workdir/player/PlayerDepend/player_codec_6467_colin/ti/sdo/codecs/h264dec/ce/) [1,0,0,1256561562000]
[DSP] @2,341,485tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package ti.sdo.codecs.mpeg4dec.ce (/home/colin/workdir/player/PlayerDepend/player_codec_6467_colin/ti/sdo/codecs/mpeg4dec/ce/) [1,0,0,1256561562000]
[DSP] @2,341,576tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package ti.sdo.codecs.mp3dec.ce (/home/colin/workdir/player/PlayerDepend/player_codec_6467_colin/ti/sdo/codecs/mp3dec/ce/) [1,0,0,0]
[DSP] @2,341,660tk: [+4 T:0x8680006c S:0x86803fe4] OG -     package decodeCombo (/home/colin/workdir/player/PlayerDepend/player_codec_6467_colin/ti/sdo/servers/decode/decodeCombo/) []
[DSP] @2,341,744tk: [+0 T:0x8680006c S:0x86803fe4] OG - Global_setSpecialTrace> return
[DSP] @2,343,715tk: [+0 T:0x8680006c S:0x8680402c] CR - processRmsCmd(0x87f03928, 4056): cmd = 5
[DSP] @2,343,772tk: [+0 T:0x8680006c S:0x8680402c] CR - remote time = 0x0, trace buffer size = 4032
[DSP] @3,136,329tk: [+0 T:0x8680006c S:0x8680402c] CR - processRmsCmd(0x87f03928, 4056): cmd = 5
[DSP] @3,136,386tk: [+0 T:0x8680006c S:0x8680402c] CR - remote time = 0x0, trace buffer size = 4032
[DSP] @4,706,443tk: [+0 T:0x8680006c S:0x8680402c] CR - processRmsCmd(0x87f03928, 4056): cmd = 5
[DSP] @4,706,501tk: [+0 T:0x8680006c S:0x8680402c] CR - remote time = 0x0, trace buffer size = 4032
@4,554,139us: [+0 T:0x41bc8490 S:0x41bc7c3c] CE - Engine_fwriteTrace> returning count [10574]
@4,554,287us: [+0 T:0x41bc8490 S:0x41bc7ca4] CE - Engine_open> return(404144)
@4,554,569us: [+0 T:0x41bc8490 S:0x41bc7bf4] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_create> Enter (engine=0x62ab0, name='h264dec', params=0x41bc7d74)
@4,554,770us: [+0 T:0x41bc8490 S:0x41bc7bc4] CV - VISA_create(0x62ab0, 'h264dec', 0x41bc7d74, 0x2484, 'ti.sdo.ce.video2.IVIDDEC2')
@4,554,966us: [+0 T:0x41bc8490 S:0x41bc7ac4] CV - VISA_create2(0x62ab0, 'h264dec', 0x41bc7d74, 0x1c, 0x2484, 'ti.sdo.ce.video2.IVIDDEC2')
@4,555,140us: [+0 T:0x41bc8490 S:0x41bc7aac] OM - Memory_alloc> Enter(0x30)
@4,555,286us: [+0 T:0x41bc8490 S:0x41bc7aac] OM - Memory_alloc> return (0x63090)
@4,555,423us: [+0 T:0x41bc8490 S:0x41bc7a4c] CE - Engine_createNode(0x62ab0, 'h264dec', 2484, 0x41bc7d74, 0x1c, 0x41bc7bbc)
@4,890,606us: [+0 T:0x41bc8490 S:0x41bc7a4c] CE - Engine> allocNode Enter(engine=0x62ab0, impId='h264dec')
@4,890,777us: [+0 T:0x41bc8490 S:0x41bc7a34] OM - Memory_alloc> Enter(0x20)
@4,890,922us: [+0 T:0x41bc8490 S:0x41bc7a34] OM - Memory_alloc> return (0x62e88)
@4,891,078us: [+0 T:0x41bc8490 S:0x41bc7a4c] CE - Engine> allocNode(). Calling Comm_create(gppfromnode_1037_1, 0x62e90, NULL)
@4,891,226us: [+0 T:0x41bc8490 S:0x41bc7a24] OC - Comm_create> Enter(queueName='gppfromnode_1037_1', queue=0x62e90, attrs=0x0)
@4,891,371us: [+0 T:0x41bc8490 S:0x41bc7a0c] OM - Memory_alloc> Enter(0x4)
@4,891,510us: [+0 T:0x41bc8490 S:0x41bc7a0c] OM - Memory_alloc> return (0x630c8)
@4,891,949us: [+0 T:0x41bc8490 S:0x41bc7a24] OC - Comm_create> return (0x630c8)
@4,892,141us: [+0 T:0x41bc8490 S:0x41bc7a2c] OC - Comm_put> Enter(queue=0x0, msg=0x423d8900)
@4,892,352us: [+0 T:0x41bc8490 S:0x41bc7a2c] OC - Comm_put> return (0)
@4,892,497us: [+0 T:0x41bc8490 S:0x41bc7a24] OC - Comm_get> Enter(queue=0x10000, msg=0x41bc7ad4, timeout=-1)
@4,963,422us: [+0 T:0x41bc8490 S:0x41bc7a24] OC - Comm_get> MSGQ_get() status=0x8000, return (0)
@4,963,611us: [+0 T:0x41bc8490 S:0x41bc7a2c] OC - Comm_put> Enter(queue=0x0, msg=0x423d8900)
@4,963,817us: [+0 T:0x41bc8490 S:0x41bc7a2c] OC - Comm_put> return (0)
@4,964,001us: [+0 T:0x41bc8490 S:0x41bc7a24] OC - Comm_get> Enter(queue=0x10000, msg=0x41bc7ad4, timeout=-1)
@4,964,300us: [+0 T:0x41bc8490 S:0x41bc7a24] OC - Comm_get> MSGQ_get() status=0x8000, return (0)
@4,964,470us: [+4 T:0x41bc8490 S:0x41bc7a4c] CE - Engine_createNode> created node(stdIn=0x2, stdOut=0x10001, msgq=0x630c8, algName='h264dec', rmsNode=0x878d3ff8, algHandle=0x878d40e0)
@4,964,657us: [+0 T:0x41bc8490 S:0x41bc79b4] OC - Comm_put> Enter(queue=0x0, msg=0x423d8900)
@4,964,854us: [+0 T:0x41bc8490 S:0x41bc79b4] OC - Comm_put> return (0)
@4,965,032us: [+0 T:0x41bc8490 S:0x41bc79ac] OC - Comm_get> Enter(queue=0x10000, msg=0x41bc7a4c, timeout=-1)
@4,965,330us: [+0 T:0x41bc8490 S:0x41bc79ac] OC - Comm_get> MSGQ_get() status=0x8000, return (0)
[DSP] @5,500,052tk: [+0 T:0x8680006c S:0x8680402c] CR - processRmsCmd(0x87f03928, 4056): cmd = 0
[DSP] @5,500,114tk: [+0 T:0x8680006c S:0x86803f1c] OM - Memory_alloc> Enter(size=0x18)
[DSP] @5,500,168tk: [+0 T:0x8680006c S:0x86803f1c] OM - Memory_alloc> return (0x878d3ff8)
[DSP] @5,500,221tk: [+0 T:0x8680006c S:0x86803f1c] OM - Memory_alloc> Enter(size=0xa)
[DSP] @5,500,268tk: [+0 T:0x8680006c S:0x86803f1c] OM - Memory_alloc> return (0x878d4010)
[DSP] @5,500,325tk: [+0 T:0x8680006c S:0x86803ee4] OM - Memory_alloc> Enter(size=0x20)
[DSP] @5,500,371tk: [+0 T:0x8680006c S:0x86803ee4] OM - Memory_alloc> return (0x878d4020)
[DSP] @5,500,423tk: [+0 T:0x8680006c S:0x86803eb4] OM - Memory_alloc> Enter(size=0x24)
[DSP] @5,500,470tk: [+0 T:0x8680006c S:0x86803eb4] OM - Memory_alloc> return (0x878d4040)
[DSP] @5,500,542tk: [+0 T:0x8680006c S:0x86803ecc] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_create> Enter (engine=0x0, name='h264dec', params=0x87f03940)
[DSP] @5,500,622tk: [+0 T:0x8680006c S:0x86803e94] CV - VISA_create(0x0, 'h264dec', 0x87f03940, 0x2484, 'ti.sdo.ce.video2.IVIDDEC2')
[DSP] @5,500,695tk: [+0 T:0x8680006c S:0x86803d84] CV - VISA_create2(0x0, 'h264dec', 0x87f03940, 0x1c, 0x2484, 'ti.sdo.ce.video2.IVIDDEC2')
[DSP] @5,500,779tk: [+0 T:0x8680006c S:0x86803d1c] CE - Engine_open> Enter('local', 0x86803d6c, 0x87f03940)
[DSP] @5,500,842tk: [+0 T:0x8680006c S:0x86803cfc] OM - Memory_alloc> Enter(size=0x2c)
[DSP] @5,500,890tk: [+0 T:0x8680006c S:0x86803cfc] OM - Memory_alloc> return (0x878d40b0)
[DSP] @5,500,948tk: [+0 T:0x8680006c S:0x86803d1c] CE - Engine_open> return(-2020786000)
[DSP] @5,501,010tk: [+0 T:0x8680006c S:0x86803d64] OM - Memory_alloc> Enter(size=0x30)
[DSP] @5,501,059tk: [+0 T:0x8680006c S:0x86803d64] OM - Memory_alloc> return (0x878d40e0)
[DSP] @5,501,112tk: [+0 T:0x8680006c S:0x86803d3c] ti.sdo.ce.alg.Algorithm - Algorithm_create> Enter(fxns=0x8786a9e0, idma3Fxns=0x0, iresFxns=0x8786a9b8, params=0x87f03940, attrs=0x86803e80)
[DSP] @5,501,208tk: [+0 T:0x8680006c S:0x86803d1c] OM - Memory_alloc> Enter(size=0x10)
[DSP] @5,501,255tk: [+0 T:0x8680006c S:0x86803d1c] OM - Memory_alloc> return (0x878d4110)
[DSP] @5,663,099tk: [+0 T:0x8680006c S:0x86803754] ti.sdo.ce.osal.Sem - Sem_create> count 1
[DSP] @5,663,182tk: [+0 T:0x8680006c S:0x86803754] ti.sdo.ce.osal.Sem - Sem_create> sem: 0x87491abc
[DSP] @5,663,305tk: [+0 T:0x8680006c S:0x86803754] ti.sdo.ce.osal.Sem - Sem_create> count 1
[DSP] @5,663,357tk: [+0 T:0x8680006c S:0x86803754] ti.sdo.ce.osal.Sem - Sem_create> sem: 0x87491aec
[DSP] @5,664,149tk: [+0 T:0x8680006c S:0x86803d3c] ti.sdo.ce.alg.Algorithm - Algorithm_create> return (0x878d4110)
[DSP] @5,664,224tk: [+5 T:0x8680006c S:0x86803d84] CV - VISA_create> local codec created (name='h264dec', handle=0x878d4110)
[DSP] @5,664,292tk: [+0 T:0x8680006c S:0x86803ecc] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_create> return (0x878d40e0)
[DSP] @5,664,361tk: [+4 T:0x8680006c S:0x86803e8c] OT - Thread_create > name: "h264dec#0", pri: -1, stack size: 13096, stack seg: 0
[DSP] @5,665,900tk: [+0 T:0x8680006c S:0x8680402c] CR - processRmsCmd(0x87f03928, 4056): cmd = 1
[DSP] @5,668,285tk: [+0 T:0x8680006c S:0x8680402c] CR - processRmsCmd(0x87f03928, 4056): cmd = 5
[DSP] @5,668,345tk: [+0 T:0x8680006c S:0x8680402c] CR - remote time = 0x0, trace buffer size = 4032
@5,305,639us: [+0 T:0x41bc8490 S:0x41bc79e4] CE - Engine_fwriteTrace> returning count [3310]
@5,305,797us: [+2 T:0x41bc8490 S:0x41bc7a4c] CE - Engine_createNode> Returning 0x62e88
@5,305,939us: [+0 T:0x41bc8490 S:0x41bc7aac] OM - Memory_alloc> Enter(0x4)
@5,306,083us: [+0 T:0x41bc8490 S:0x41bc7aac] OM - Memory_alloc> return (0x630d8)
@5,306,224us: [+0 T:0x41bc8490 S:0x41bc7aa4] OC - Comm_alloc> Enter(poolId=0x0, msg=0x630d8, size=2484)
@5,306,405us: [+0 T:0x41bc8490 S:0x41bc7aa4] OC - Comm_alloc> msg=0x423d9900, returning (0)
@5,306,569us: [+5 T:0x41bc8490 S:0x41bc7ac4] CV - VISA_create> remote codec created (name='h264dec', localQueueID=0x10001, remoteQueueID=0x0002)
@5,306,734us: [+0 T:0x41bc8490 S:0x41bc7bf4] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_create> return (0x63090)
@5,306,877us: [+2 T:0x41bc8490 S:0x41bc7c1c] ti.sdo.dmai - [Vdec2] Video decoder instance created
@5,307,006us: [+0 T:0x41bc8490 S:0x41bc7bac] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_control> Enter (handle=0x63090, id=1, dynParams=0x41bc7d58 (size=0x1c), status=0x41bc7c74 (size=0xb8)
@5,307,173us: [+5 T:0x41bc8490 S:0x41bc7b7c] CV - VISA_allocMsg> Allocating message for messageId=0x00020409
@5,307,316us: [+0 T:0x41bc8490 S:0x41bc7b5c] CV - VISA_call(visa=0x63090, msg=0x423d9900): messageId=0x00020409, command=0x1
@5,307,504us: [+0 T:0x41bc8490 S:0x41bc7b24] OC - Comm_put> Enter(queue=0x2, msg=0x423d9900)
@5,307,704us: [+0 T:0x41bc8490 S:0x41bc7b24] OC - Comm_put> return (0)
@5,307,846us: [+0 T:0x41bc8490 S:0x41bc7b1c] OC - Comm_get> Enter(queue=0x10001, msg=0x41bc7bc4, timeout=-1)
@5,308,192us: [+0 T:0x41bc8490 S:0x41bc7b1c] OC - Comm_get> MSGQ_get() status=0x8000, return (0)
@5,308,377us: [+0 T:0x41bc8490 S:0x41bc7aac] OC - Comm_put> Enter(queue=0x0, msg=0x423d8900)
@5,308,579us: [+0 T:0x41bc8490 S:0x41bc7aac] OC - Comm_put> return (0)
@5,308,757us: [+0 T:0x41bc8490 S:0x41bc7aa4] OC - Comm_get> Enter(queue=0x10000, msg=0x41bc7b44, timeout=-1)
@5,309,053us: [+0 T:0x41bc8490 S:0x41bc7aa4] OC - Comm_get> MSGQ_get() status=0x8000, return (0)
[DSP] @6,463,804tk: [+5 T:0x8749198c S:0x8749548c] CN - NODE> 0x878d4020(h264dec#0) call(algHandle=0x878d40e0, msg=0x87f04900); messageId=0x00020409
[DSP] @6,463,895tk: [+0 T:0x8749198c S:0x87495314] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_control> Enter (handle=0x878d40e0, id=1, dynParams=0x87f04934 (size=0x1c), status=0x87f04950 (size=0xb8)
[DSP] @6,464,002tk: [+5 T:0x8749198c S:0x874952f4] CV - VISA_enter(visa=0x878d40e0): algHandle = 0x878d4110
[DSP] @6,464,064tk: [+0 T:0x8749198c S:0x874952d4] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Enter(alg=0x878d4110)
[DSP] @6,464,178tk: [+0 T:0x8749198c S:0x874952d4] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Exit
[DSP] @6,464,239tk: [+5 T:0x8749198c S:0x874952f4] CV - VISA_exit(visa=0x878d40e0): algHandle = 0x878d4110
[DSP] @6,464,299tk: [+0 T:0x8749198c S:0x874952d4] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> Enter(alg=0x878d4110)
[DSP] @6,464,364tk: [+0 T:0x8749198c S:0x874952d4] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> Exit
[DSP] @6,464,420tk: [+0 T:0x8749198c S:0x87495314] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_control> Exit (handle=0x878d40e0, retVal=0x0)
[DSP] @6,464,487tk: [+5 T:0x8749198c S:0x8749548c] CN - NODE> returned from call(algHandle=0x878d40e0, msg=0x87f04900); messageId=0x00020409
[DSP] @6,465,835tk: [+0 T:0x8680006c S:0x8680402c] CR - processRmsCmd(0x87f03928, 4056): cmd = 5
[DSP] @6,465,892tk: [+0 T:0x8680006c S:0x8680402c] CR - remote time = 0x0, trace buffer size = 4032
@5,646,466us: [+0 T:0x41bc8490 S:0x41bc7adc] CE - Engine_fwriteTrace> returning count [1471]
@5,646,630us: [+0 T:0x41bc8490 S:0x41bc7b5c] CV - VISA_call Completed: messageId=0x00020409, command=0x1, return(status=0)
@5,646,785us: [+5 T:0x41bc8490 S:0x41bc7b64] CV - VISA_freeMsg(0x63090, 0x423d9900): Freeing message with messageId=0x00020409
@5,646,933us: [+0 T:0x41bc8490 S:0x41bc7bac] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_control> Exit (handle=0x63090, retVal=0x0)
@5,647,074us: [+2 T:0x41bc8490 S:0x41bc7c1c] ti.sdo.dmai - [Vdec2] Made XDM_SETPARAMS control call
@5,647,209us: [+0 T:0x41bc8490 S:0x41bc7adc] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_control> Enter (handle=0x63090, id=5, dynParams=0x6301c (size=0x1c), status=0x41bc7b94 (size=0xb8)
@5,647,374us: [+5 T:0x41bc8490 S:0x41bc7aac] CV - VISA_allocMsg> Allocating message for messageId=0x0002040a
@5,647,515us: [+0 T:0x41bc8490 S:0x41bc7a8c] CV - VISA_call(visa=0x63090, msg=0x423d9900): messageId=0x0002040a, command=0x1
@5,647,662us: [+0 T:0x41bc8490 S:0x41bc7a54] OC - Comm_put> Enter(queue=0x2, msg=0x423d9900)
@5,647,857us: [+0 T:0x41bc8490 S:0x41bc7a54] OC - Comm_put> return (0)
@5,648,003us: [+0 T:0x41bc8490 S:0x41bc7a4c] OC - Comm_get> Enter(queue=0x10001, msg=0x41bc7af4, timeout=-1)
@5,648,329us: [+0 T:0x41bc8490 S:0x41bc7a4c] OC - Comm_get> MSGQ_get() status=0x8000, return (0)
@5,648,516us: [+0 T:0x41bc8490 S:0x41bc79dc] OC - Comm_put> Enter(queue=0x0, msg=0x423d8900)
@5,648,745us: [+0 T:0x41bc8490 S:0x41bc79dc] OC - Comm_put> return (0)
@5,648,927us: [+0 T:0x41bc8490 S:0x41bc79d4] OC - Comm_get> Enter(queue=0x10000, msg=0x41bc7a74, timeout=-1)
@5,649,226us: [+0 T:0x41bc8490 S:0x41bc79d4] OC - Comm_get> MSGQ_get() status=0x8000, return (0)
[DSP] @7,253,061tk: [+5 T:0x8749198c S:0x8749548c] CN - NODE> 0x878d4020(h264dec#0) call(algHandle=0x878d40e0, msg=0x87f04900); messageId=0x0002040a
[DSP] @7,253,147tk: [+0 T:0x8749198c S:0x87495314] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_control> Enter (handle=0x878d40e0, id=5, dynParams=0x87f04934 (size=0x1c), status=0x87f04950 (size=0xb8)
[DSP] @7,253,249tk: [+5 T:0x8749198c S:0x874952f4] CV - VISA_enter(visa=0x878d40e0): algHandle = 0x878d4110
[DSP] @7,253,309tk: [+0 T:0x8749198c S:0x874952d4] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Enter(alg=0x878d4110)
[DSP] @7,253,372tk: [+0 T:0x8749198c S:0x874952d4] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Exit
[DSP] @7,253,427tk: [+5 T:0x8749198c S:0x874952f4] CV - VISA_exit(visa=0x878d40e0): algHandle = 0x878d4110
[DSP] @7,253,487tk: [+0 T:0x8749198c S:0x874952d4] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> Enter(alg=0x878d4110)
[DSP] @7,253,549tk: [+0 T:0x8749198c S:0x874952d4] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> Exit
[DSP] @7,253,603tk: [+0 T:0x8749198c S:0x87495314] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_control> Exit (handle=0x878d40e0, retVal=0x0)
[DSP] @7,253,671tk: [+5 T:0x8749198c S:0x8749548c] CN - NODE> returned from call(algHandle=0x878d40e0, msg=0x87f04900); messageId=0x0002040a
[DSP] @7,255,123tk: [+0 T:0x8680006c S:0x8680402c] CR - processRmsCmd(0x87f03928, 4056): cmd = 5
[DSP] @7,255,180tk: [+0 T:0x8680006c S:0x8680402c] CR - remote time = 0x0, trace buffer size = 4032
@5,651,629us: [+0 T:0x41bc8490 S:0x41bc7a0c] CE - Engine_fwriteTrace> returning count [1471]
@5,651,783us: [+0 T:0x41bc8490 S:0x41bc7a8c] CV - VISA_call Completed: messageId=0x0002040a, command=0x1, return(status=0)
@5,651,940us: [+5 T:0x41bc8490 S:0x41bc7a94] CV - VISA_freeMsg(0x63090, 0x423d9900): Freeing message with messageId=0x0002040a
@5,987,264us: [+0 T:0x41bc8490 S:0x41bc7adc] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_control> Exit (handle=0x63090, retVal=0x0)
@5,987,444us: [+2 T:0x41bc8490 S:0x41bc7b4c] ti.sdo.dmai - [Vdec2] Made XDM_GETBUFINFO control call
@5,987,653us: [+2 T:0x41bc8490 S:0x41bc7cdc] ti.sdo.dmai - [BufTab] Allocating BufTab for 5 buffers
@5,987,830us: [+0 T:0x41bc8490 S:0x41bc7c9c] OM - Memory_alloc> Enter(0x310200)
@5,987,977us: [+0 T:0x41bc8490 S:0x41bc7c54] OM - Memory_contigAlloc> Enter(size=3211776, align=-1, cached=FALSE, heap=FALSE)
@5,988,396us: [+4 T:0x41bc8490 S:0x41bc7c54] OM - Memory_contigAlloc> CMEM_alloc(3211776) = 0x43028000.
@5,988,591us: [+4 T:0x41bc8490 S:0x41bc7c54] OM - Memory_contigAlloc> CMEM_getPhys(0x43028000) = 0x861de000.
@5,988,746us: [+1 T:0x41bc8490 S:0x41bc7c0c] OM - Memory__addContigBuf> Enter(virtAddr=0x43028000, size=3211776, physAddr=0x861de000)
@5,988,901us: [+1 T:0x41bc8490 S:0x41bc7c0c] OM - Memory__addContigBuf> creating new contigBuf object
@5,989,032us: [+0 T:0x41bc8490 S:0x41bc7bf4] OM - Memory_alloc> Enter(0x10)
@5,989,172us: [+0 T:0x41bc8490 S:0x41bc7bf4] OM - Memory_alloc> return (0x63188)
@5,989,308us: [+1 T:0x41bc8490 S:0x41bc7c0c] OM - Memory__addContigBuf> returning: cb->phys=0x861de000, cb->size=3211776, cb->virt=0x43028000
@5,989,562us: [+0 T:0x41bc8490 S:0x41bc7c54] OM - Memory_contigAlloc> return (0x43028000)
@5,989,705us: [+0 T:0x41bc8490 S:0x41bc7c9c] OM - Memory_alloc> return (0x43028000)
@5,989,839us: [+0 T:0x41bc8490 S:0x41bc7c7c] OM - Memory_getBufferPhysicalAddress> Enter(virtAddr=0x43028000, size=4)
@5,990,007us: [+1 T:0x41bc8490 S:0x41bc7c7c] OM - Memory__getPhysicalAddress> Enter(virtAddr=0x43028000, size=4)
@5,990,154us: [+1 T:0x41bc8490 S:0x41bc7c7c] OM - Memory__getPhysicalAddress> found in cb(Sc=0x43028000, Ec=0x43338200, Ss=0x43028000, Es=0x43028004, PSc=0x861de000)
@5,990,312us: [+1 T:0x41bc8490 S:0x41bc7c7c] OM - Memory__getPhysicalAddress> returning physAddr=0x861de000
@5,990,446us: [+0 T:0x41bc8490 S:0x41bc7c7c] OM - Memory_getBufferPhysicalAddress> return (0x861de000)
@5,990,581us: [+2 T:0x41bc8490 S:0x41bc7cb4] ti.sdo.dmai - [Buffer] Alloc Buffer of size 3211776 at 0x43028000 (0x861de000 phys)
@5,990,746us: [+0 T:0x41bc8490 S:0x41bc7c9c] OM - Memory_alloc> Enter(0x310200)
@5,990,889us: [+0 T:0x41bc8490 S:0x41bc7c54] OM - Memory_contigAlloc> Enter(size=3211776, align=-1, cached=FALSE, heap=FALSE)
@5,991,316us: [+4 T:0x41bc8490 S:0x41bc7c54] OM - Memory_contigAlloc> CMEM_alloc(3211776) = 0x43339000.
@5,991,516us: [+4 T:0x41bc8490 S:0x41bc7c54] OM - Memory_contigAlloc> CMEM_getPhys(0x43339000) = 0x85ecd000.
@5,991,668us: [+1 T:0x41bc8490 S:0x41bc7c0c] OM - Memory__addContigBuf> Enter(virtAddr=0x43339000, size=3211776, physAddr=0x85ecd000)
@5,991,817us: [+1 T:0x41bc8490 S:0x41bc7c0c] OM - Memory__addContigBuf> creating new contigBuf object
@5,991,948us: [+0 T:0x41bc8490 S:0x41bc7bf4] OM - Memory_alloc> Enter(0x10)
@5,992,087us: [+0 T:0x41bc8490 S:0x41bc7bf4] OM - Memory_alloc> return (0x63210)
@5,992,221us: [+1 T:0x41bc8490 S:0x41bc7c0c] OM - Memory__addContigBuf> returning: cb->phys=0x85ecd000, cb->size=3211776, cb->virt=0x43339000
@5,992,370us: [+0 T:0x41bc8490 S:0x41bc7c54] OM - Memory_contigAlloc> return (0x43339000)
@5,992,502us: [+0 T:0x41bc8490 S:0x41bc7c9c] OM - Memory_alloc> return (0x43339000)
@5,992,635us: [+0 T:0x41bc8490 S:0x41bc7c7c] OM - Memory_getBufferPhysicalAddress> Enter(virtAddr=0x43339000, size=4)
@5,992,803us: [+1 T:0x41bc8490 S:0x41bc7c7c] OM - Memory__getPhysicalAddress> Enter(virtAddr=0x43339000, size=4)
@5,992,950us: [+1 T:0x41bc8490 S:0x41bc7c7c] OM - Memory__getPhysicalAddress> found in cb(Sc=0x43339000, Ec=0x43649200, Ss=0x43339000, Es=0x43339004, PSc=0x85ecd000)
@5,993,106us: [+1 T:0x41bc8490 S:0x41bc7c7c] OM - Memory__getPhysicalAddress> returning physAddr=0x85ecd000
@5,993,239us: [+0 T:0x41bc8490 S:0x41bc7c7c] OM - Memory_getBufferPhysicalAddress> return (0x85ecd000)
@6,328,589us: [+2 T:0x41bc8490 S:0x41bc7cb4] ti.sdo.dmai - [Buffer] Alloc Buffer of size 3211776 at 0x43339000 (0x85ecd000 phys)
@6,328,792us: [+0 T:0x41bc8490 S:0x41bc7c9c] OM - Memory_alloc> Enter(0x310200)
@6,328,943us: [+0 T:0x41bc8490 S:0x41bc7c54] OM - Memory_contigAlloc> Enter(size=3211776, align=-1, cached=FALSE, heap=FALSE)
@6,329,443us: [+4 T:0x41bc8490 S:0x41bc7c54] OM - Memory_contigAlloc> CMEM_alloc(3211776) = 0x4364a000.
@6,329,648us: [+4 T:0x41bc8490 S:0x41bc7c54] OM - Memory_contigAlloc> CMEM_getPhys(0x4364a000) = 0x85bbc000.
@6,329,804us: [+1 T:0x41bc8490 S:0x41bc7c0c] OM - Memory__addContigBuf> Enter(virtAddr=0x4364a000, size=3211776, physAddr=0x85bbc000)
@6,329,959us: [+1 T:0x41bc8490 S:0x41bc7c0c] OM - Memory__addContigBuf> creating new contigBuf object
@6,330,092us: [+0 T:0x41bc8490 S:0x41bc7bf4] OM - Memory_alloc> Enter(0x10)
@6,330,236us: [+0 T:0x41bc8490 S:0x41bc7bf4] OM - Memory_alloc> return (0x63298)
@6,330,368us: [+1 T:0x41bc8490 S:0x41bc7c0c] OM - Memory__addContigBuf> returning: cb->phys=0x85bbc000, cb->size=3211776, cb->virt=0x4364a000
@6,330,515us: [+0 T:0x41bc8490 S:0x41bc7c54] OM - Memory_contigAlloc> return (0x4364a000)
@6,330,646us: [+0 T:0x41bc8490 S:0x41bc7c9c] OM - Memory_alloc> return (0x4364a000)
@6,330,777us: [+0 T:0x41bc8490 S:0x41bc7c7c] OM - Memory_getBufferPhysicalAddress> Enter(virtAddr=0x4364a000, size=4)
@6,330,918us: [+1 T:0x41bc8490 S:0x41bc7c7c] OM - Memory__getPhysicalAddress> Enter(virtAddr=0x4364a000, size=4)
@6,331,056us: [+1 T:0x41bc8490 S:0x41bc7c7c] OM - Memory__getPhysicalAddress> found in cb(Sc=0x4364a000, Ec=0x4395a200, Ss=0x4364a000, Es=0x4364a004, PSc=0x85bbc000)
@6,331,213us: [+1 T:0x41bc8490 S:0x41bc7c7c] OM - Memory__getPhysicalAddress> returning physAddr=0x85bbc000
@6,331,383us: [+0 T:0x41bc8490 S:0x41bc7c7c] OM - Memory_getBufferPhysicalAddress> return (0x85bbc000)
@6,331,524us: [+2 T:0x41bc8490 S:0x41bc7cb4] ti.sdo.dmai - [Buffer] Alloc Buffer of size 3211776 at 0x4364a000 (0x85bbc000 phys)
@6,331,686us: [+0 T:0x41bc8490 S:0x41bc7c9c] OM - Memory_alloc> Enter(0x310200)
@6,331,829us: [+0 T:0x41bc8490 S:0x41bc7c54] OM - Memory_contigAlloc> Enter(size=3211776, align=-1, cached=FALSE, heap=FALSE)
@6,332,259us: [+4 T:0x41bc8490 S:0x41bc7c54] OM - Memory_contigAlloc> CMEM_alloc(3211776) = 0x4395b000.
@6,332,456us: [+4 T:0x41bc8490 S:0x41bc7c54] OM - Memory_contigAlloc> CMEM_getPhys(0x4395b000) = 0x858ab000.
@6,332,607us: [+1 T:0x41bc8490 S:0x41bc7c0c] OM - Memory__addContigBuf> Enter(virtAddr=0x4395b000, size=3211776, physAddr=0x858ab000)
@6,332,761us: [+1 T:0x41bc8490 S:0x41bc7c0c] OM - Memory__addContigBuf> creating new contigBuf object
@6,332,891us: [+0 T:0x41bc8490 S:0x41bc7bf4] OM - Memory_alloc> Enter(0x10)
@6,333,028us: [+0 T:0x41bc8490 S:0x41bc7bf4] OM - Memory_alloc> return (0x63320)
@6,333,164us: [+1 T:0x41bc8490 S:0x41bc7c0c] OM - Memory__addContigBuf> returning: cb->phys=0x858ab000, cb->size=3211776, cb->virt=0x4395b000
@6,333,312us: [+0 T:0x41bc8490 S:0x41bc7c54] OM - Memory_contigAlloc> return (0x4395b000)
@6,333,445us: [+0 T:0x41bc8490 S:0x41bc7c9c] OM - Memory_alloc> return (0x4395b000)
@6,333,577us: [+0 T:0x41bc8490 S:0x41bc7c7c] OM - Memory_getBufferPhysicalAddress> Enter(virtAddr=0x4395b000, size=4)
@6,333,718us: [+1 T:0x41bc8490 S:0x41bc7c7c] OM - Memory__getPhysicalAddress> Enter(virtAddr=0x4395b000, size=4)
@6,333,855us: [+1 T:0x41bc8490 S:0x41bc7c7c] OM - Memory__getPhysicalAddress> found in cb(Sc=0x4395b000, Ec=0x43c6b200, Ss=0x4395b000, Es=0x4395b004, PSc=0x858ab000)
@6,334,008us: [+1 T:0x41bc8490 S:0x41bc7c7c] OM - Memory__getPhysicalAddress> returning physAddr=0x858ab000
@6,334,178us: [+0 T:0x41bc8490 S:0x41bc7c7c] OM - Memory_getBufferPhysicalAddress> return (0x858ab000)
@6,334,318us: [+2 T:0x41bc8490 S:0x41bc7cb4] ti.sdo.dmai - [Buffer] Alloc Buffer of size 3211776 at 0x4395b000 (0x858ab000 phys)
@6,669,887us: [+0 T:0x41bc8490 S:0x41bc7c9c] OM - Memory_alloc> Enter(0x310200)
@6,670,058us: [+0 T:0x41bc8490 S:0x41bc7c54] OM - Memory_contigAlloc> Enter(size=3211776, align=-1, cached=FALSE, heap=FALSE)
@6,670,484us: [+4 T:0x41bc8490 S:0x41bc7c54] OM - Memory_contigAlloc> CMEM_alloc(3211776) = 0x43c6c000.
@6,670,682us: [+4 T:0x41bc8490 S:0x41bc7c54] OM - Memory_contigAlloc> CMEM_getPhys(0x43c6c000) = 0x85289000.
@6,670,834us: [+1 T:0x41bc8490 S:0x41bc7c0c] OM - Memory__addContigBuf> Enter(virtAddr=0x43c6c000, size=3211776, physAddr=0x85289000)
@6,670,990us: [+1 T:0x41bc8490 S:0x41bc7c0c] OM - Memory__addContigBuf> creating new contigBuf object
@6,671,121us: [+0 T:0x41bc8490 S:0x41bc7bf4] OM - Memory_alloc> Enter(0x10)
@6,671,261us: [+0 T:0x41bc8490 S:0x41bc7bf4] OM - Memory_alloc> return (0x633a8)
@6,671,396us: [+1 T:0x41bc8490 S:0x41bc7c0c] OM - Memory__addContigBuf> returning: cb->phys=0x85289000, cb->size=3211776, cb->virt=0x43c6c000
@6,671,544us: [+0 T:0x41bc8490 S:0x41bc7c54] OM - Memory_contigAlloc> return (0x43c6c000)
@6,671,677us: [+0 T:0x41bc8490 S:0x41bc7c9c] OM - Memory_alloc> return (0x43c6c000)
@6,671,808us: [+0 T:0x41bc8490 S:0x41bc7c7c] OM - Memory_getBufferPhysicalAddress> Enter(virtAddr=0x43c6c000, size=4)
@6,671,949us: [+1 T:0x41bc8490 S:0x41bc7c7c] OM - Memory__getPhysicalAddress> Enter(virtAddr=0x43c6c000, size=4)
@6,672,086us: [+1 T:0x41bc8490 S:0x41bc7c7c] OM - Memory__getPhysicalAddress> found in cb(Sc=0x43c6c000, Ec=0x43f7c200, Ss=0x43c6c000, Es=0x43c6c004, PSc=0x85289000)
@6,672,242us: [+1 T:0x41bc8490 S:0x41bc7c7c] OM - Memory__getPhysicalAddress> returning physAddr=0x85289000
@6,672,376us: [+0 T:0x41bc8490 S:0x41bc7c7c] OM - Memory_getBufferPhysicalAddress> return (0x85289000)
@6,672,511us: [+2 T:0x41bc8490 S:0x41bc7cb4] ti.sdo.dmai - [Buffer] Alloc Buffer of size 3211776 at 0x43c6c000 (0x85289000 phys)
@6,672,714us: [+2 T:0x41bc8490 S:0x41bc7cbc] ti.sdo.dmai - [Loader] Creating Loader for video1.264 with buffer size 2697152, window size 1048576, alignment -1
@6,673,698us: [+0 T:0x41bc8490 S:0x41bc7c7c] OM - Memory_alloc> Enter(0x2927c0)
@6,673,884us: [+0 T:0x41bc8490 S:0x41bc7c34] OM - Memory_contigAlloc> Enter(size=2697152, align=-1, cached=FALSE, heap=FALSE)
@6,674,328us: [+4 T:0x41bc8490 S:0x41bc7c34] OM - Memory_contigAlloc> CMEM_alloc(2697152) = 0x43f7d000.
@6,674,527us: [+4 T:0x41bc8490 S:0x41bc7c34] OM - Memory_contigAlloc> CMEM_getPhys(0x43f7d000) = 0x864ef000.
@6,674,682us: [+1 T:0x41bc8490 S:0x41bc7bec] OM - Memory__addContigBuf> Enter(virtAddr=0x43f7d000, size=2697152, physAddr=0x864ef000)
@6,674,837us: [+1 T:0x41bc8490 S:0x41bc7bec] OM - Memory__addContigBuf> creating new contigBuf object
@6,674,968us: [+0 T:0x41bc8490 S:0x41bc7bd4] OM - Memory_alloc> Enter(0x10)
@6,675,109us: [+0 T:0x41bc8490 S:0x41bc7bd4] OM - Memory_alloc> return (0x635c8)
@6,675,241us: [+1 T:0x41bc8490 S:0x41bc7bec] OM - Memory__addContigBuf> returning: cb->phys=0x864ef000, cb->size=2697152, cb->virt=0x43f7d000
@6,675,416us: [+0 T:0x41bc8490 S:0x41bc7c34] OM - Memory_contigAlloc> return (0x43f7d000)
@6,675,554us: [+0 T:0x41bc8490 S:0x41bc7c7c] OM - Memory_alloc> return (0x43f7d000)
@6,675,688us: [+0 T:0x41bc8490 S:0x41bc7c5c] OM - Memory_getBufferPhysicalAddress> Enter(virtAddr=0x43f7d000, size=4)
@6,675,829us: [+1 T:0x41bc8490 S:0x41bc7c5c] OM - Memory__getPhysicalAddress> Enter(virtAddr=0x43f7d000, size=4)
@6,675,970us: [+1 T:0x41bc8490 S:0x41bc7c5c] OM - Memory__getPhysicalAddress> found in cb(Sc=0x43f7d000, Ec=0x4420f7c0, Ss=0x43f7d000, Es=0x43f7d004, PSc=0x864ef000)
@6,676,125us: [+1 T:0x41bc8490 S:0x41bc7c5c] OM - Memory__getPhysicalAddress> returning physAddr=0x864ef000
@6,676,257us: [+0 T:0x41bc8490 S:0x41bc7c5c] OM - Memory_getBufferPhysicalAddress> return (0x864ef000)
@6,676,389us: [+2 T:0x41bc8490 S:0x41bc7c94] ti.sdo.dmai - [Buffer] Alloc Buffer of size 2697152 at 0x43f7d000 (0x864ef000 phys)
@7,011,172us: [+0 T:0x41bc8490 S:0x41bc7ca4] ti.sdo.ce.osal.Sem - Sem_create> count: 0
@7,011,336us: [+0 T:0x41bc8490 S:0x41bc7c8c] OM - Memory_alloc> Enter(0x14)
@7,011,486us: [+0 T:0x41bc8490 S:0x41bc7c8c] OM - Memory_alloc> return (0x63620)
@7,011,623us: [+0 T:0x41bc8490 S:0x41bc7ca4] ti.sdo.ce.osal.Sem - Leaving Sem_create> sem[0x63620]
@7,011,758us: [+0 T:0x41bc8490 S:0x41bc7ca4] ti.sdo.ce.osal.Sem - Sem_create> count: 1
@7,011,883us: [+0 T:0x41bc8490 S:0x41bc7c8c] OM - Memory_alloc> Enter(0x14)
@7,012,023us: [+0 T:0x41bc8490 S:0x41bc7c8c] OM - Memory_alloc> return (0x63638)
@7,012,159us: [+0 T:0x41bc8490 S:0x41bc7ca4] ti.sdo.ce.osal.Sem - Leaving Sem_create> sem[0x63638]
@7,012,292us: [+0 T:0x41bc8490 S:0x41bc7ca4] ti.sdo.ce.osal.Sem - Sem_create> count: 0
@7,012,416us: [+0 T:0x41bc8490 S:0x41bc7c8c] OM - Memory_alloc> Enter(0x14)
@7,012,554us: [+0 T:0x41bc8490 S:0x41bc7c8c] OM - Memory_alloc> return (0x63650)
@7,012,692us: [+0 T:0x41bc8490 S:0x41bc7ca4] ti.sdo.ce.osal.Sem - Leaving Sem_create> sem[0x63650]
@7,013,422us: [+0 T:0x4001f950 S:0xbeb38a14] CE - Engine_open> Enter('decode', 0x0, 0xbeb38a84)
@7,013,624us: [+0 T:0x4001f950 S:0xbeb389fc] OM - Memory_alloc> Enter(0x2c)
@7,013,785us: [+0 T:0x4001f950 S:0xbeb389fc] OM - Memory_alloc> return (0x636f8)
@7,013,971us: [+0 T:0x4001f950 S:0xbeb38a14] CE - rserverOpen('decodeCombo.x64P'), count = 1
@7,014,120us: [+0 T:0x4001f950 S:0xbeb38a14] CE - rserverOpen('decodeCombo.x64P'): 0x60f30 done.
@7,014,272us: [+0 T:0x4001f950 S:0xbeb389fc] OM - Memory_alloc> Enter(0x24)
@7,014,420us: [+0 T:0x4001f950 S:0xbeb389fc] OM - Memory_alloc> return (0x63728)
@7,014,586us: [+0 T:0x4001f950 S:0xbeb389ec] OC - Comm_create> Enter(queueName='decode_1037_2', queue=0x63708, attrs=0x0)
@7,014,742us: [+0 T:0x4001f950 S:0xbeb389d4] OM - Memory_alloc> Enter(0x4)
@7,014,888us: [+0 T:0x4001f950 S:0xbeb389d4] OM - Memory_alloc> return (0x63750)
@7,015,340us: [+0 T:0x4001f950 S:0xbeb389ec] OC - Comm_create> return (0x63750)
@7,015,531us: [+0 T:0x4001f950 S:0xbeb389f4] OM - Memory_free> Enter(0x63728, 0x24)
@7,015,691us: [+0 T:0x4001f950 S:0xbeb389f4] OM - Memory_free> return (0x1)
@7,015,836us: [+0 T:0x4001f950 S:0xbeb389f4] OC - Comm_alloc> Enter(poolId=0x0, msg=0x63714, size=576)
@7,016,024us: [+0 T:0x4001f950 S:0xbeb389f4] OC - Comm_alloc> msg=0x423da900, returning (0)
@7,016,178us: [+0 T:0x4001f950 S:0xbeb389dc] OC - Comm_locate> Enter(queueName='rmsq', queue=0x63704)
@7,016,709us: [+0 T:0x4001f950 S:0xbeb389dc] OC - Comm_locate> return (0)
@7,016,901us: [+0 T:0x4001f950 S:0xbeb38a14] CE - checkServer(0x636f8)
@7,017,074us: [+0 T:0x4001f950 S:0xbeb389e4] OC - Comm_put> Enter(queue=0x0, msg=0x423da900)
@7,017,286us: [+0 T:0x4001f950 S:0xbeb389e4] OC - Comm_put> return (0)
@7,017,481us: [+0 T:0x4001f950 S:0xbeb389dc] OC - Comm_get> Enter(queue=0x10002, msg=0xbeb38a80, timeout=-1)
@7,017,789us: [+0 T:0x4001f950 S:0xbeb389dc] OC - Comm_get> MSGQ_get() status=0x8000, return (0)
@7,017,991us: [+0 T:0x4001f950 S:0xbeb3897c] OC - Comm_put> Enter(queue=0x0, msg=0x423da900)
@7,018,202us: [+0 T:0x4001f950 S:0xbeb3897c] OC - Comm_put> return (0)
@7,018,394us: [+0 T:0x4001f950 S:0xbeb38974] OC - Comm_get> Enter(queue=0x10002, msg=0xbeb38a14, timeout=-1)
@7,018,701us: [+0 T:0x4001f950 S:0xbeb38974] OC - Comm_get> MSGQ_get() status=0x8000, return (0)
[DSP] @10,430,560tk: [+0 T:0x8680006c S:0x8680402c] CR - processRmsCmd(0x87f05928, 4056): cmd = 6
[DSP] @10,432,686tk: [+0 T:0x8680006c S:0x8680402c] CR - processRmsCmd(0x87f05928, 4056): cmd = 5
[DSP] @10,432,745tk: [+0 T:0x8680006c S:0x8680402c] CR - remote time = 0x0, trace buffer size = 4032
@7,019,441us: [+0 T:0x4001f950 S:0xbeb389ac] CE - Engine_fwriteTrace> returning count [297]
@7,019,648us: [+0 T:0x4001f950 S:0xbeb38a14] CE - Engine_open> return(407288)
@7,373,817us: [+0 T:0x41bc8490 S:0x41bc7c8c] OM - Memory_getBufferPhysicalAddress> Enter(virtAddr=0x43f7d000, size=4)
@7,374,010us: [+1 T:0x41bc8490 S:0x41bc7c8c] OM - Memory__getPhysicalAddress> Enter(virtAddr=0x43f7d000, size=4)
@7,374,165us: [+1 T:0x41bc8490 S:0x41bc7c8c] OM - Memory__getPhysicalAddress> found in cb(Sc=0x43f7d000, Ec=0x4420f7c0, Ss=0x43f7d000, Es=0x43f7d004, PSc=0x864ef000)
@7,374,324us: [+1 T:0x41bc8490 S:0x41bc7c8c] OM - Memory__getPhysicalAddress> returning physAddr=0x864ef000
@7,374,460us: [+0 T:0x41bc8490 S:0x41bc7c8c] OM - Memory_getBufferPhysicalAddress> return (0x864ef000)
@7,374,596us: [+2 T:0x41bc8490 S:0x41bc7cc4] ti.sdo.dmai - [Buffer] Set user pointer 0x43f7d000 (physical 0x864ef000)
@7,374,766us: [+0 T:0x41bc8490 S:0x41bc7cc4] ti.sdo.ce.osal.Sem - Entered Sem_post> sem[0x63620]
@7,374,925us: [+0 T:0x41bc8490 S:0x41bc7cd4] ti.sdo.ce.osal.Sem - Leaving Sem_post> sem[0x63620]
@7,375,137us: [+0 T:0x41bc8490 S:0x41bc7354] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_process> Enter (handle=0x63090, inBufs=0x41bc74a4, outBufs=0x41bc7498, inArgs=0x41bc7d08, outArgs=0x41bc7568)
@7,375,420us: [+5 T:0x41bc8490 S:0x41bc72dc] CV - VISA_allocMsg> Allocating message for messageId=0x0002040b
@7,375,609us: [+0 T:0x41bc8490 S:0x41bc72ac] OM - Memory_getBufferPhysicalAddress> Enter(virtAddr=0x43f7d000, size=1048576)
@7,375,770us: [+1 T:0x41bc8490 S:0x41bc72ac] OM - Memory__getPhysicalAddress> Enter(virtAddr=0x43f7d000, size=1048576)
@7,375,919us: [+1 T:0x41bc8490 S:0x41bc72ac] OM - Memory__getPhysicalAddress> found in cb(Sc=0x43f7d000, Ec=0x4420f7c0, Ss=0x43f7d000, Es=0x4407d000, PSc=0x864ef000)
@7,376,082us: [+1 T:0x41bc8490 S:0x41bc72ac] OM - Memory__getPhysicalAddress> returning physAddr=0x864ef000
@7,376,226us: [+0 T:0x41bc8490 S:0x41bc72ac] OM - Memory_getBufferPhysicalAddress> return (0x864ef000)
@7,376,374us: [+0 T:0x41bc8490 S:0x41bc72ac] OM - Memory_getBufferPhysicalAddress> Enter(virtAddr=0x43028000, size=2141184)
@7,376,524us: [+1 T:0x41bc8490 S:0x41bc72ac] OM - Memory__getPhysicalAddress> Enter(virtAddr=0x43028000, size=2141184)
@7,376,672us: [+1 T:0x41bc8490 S:0x41bc72ac] OM - Memory__getPhysicalAddress> found in cb(Sc=0x43028000, Ec=0x43338200, Ss=0x43028000, Es=0x43232c00, PSc=0x861de000)
@7,376,834us: [+1 T:0x41bc8490 S:0x41bc72ac] OM - Memory__getPhysicalAddress> returning physAddr=0x861de000
@7,376,971us: [+0 T:0x41bc8490 S:0x41bc72ac] OM - Memory_getBufferPhysicalAddress> return (0x861de000)
@7,377,116us: [+0 T:0x41bc8490 S:0x41bc72ac] OM - Memory_getBufferPhysicalAddress> Enter(virtAddr=0x43232c00, size=1070592)
@7,377,262us: [+1 T:0x41bc8490 S:0x41bc72ac] OM - Memory__getPhysicalAddress> Enter(virtAddr=0x43232c00, size=1070592)
@7,377,407us: [+1 T:0x41bc8490 S:0x41bc72ac] OM - Memory__getPhysicalAddress> found in cb(Sc=0x43028000, Ec=0x43338200, Ss=0x43232c00, Es=0x43338200, PSc=0x861de000)
@7,377,597us: [+1 T:0x41bc8490 S:0x41bc72ac] OM - Memory__getPhysicalAddress> returning physAddr=0x863e8c00
@7,377,743us: [+0 T:0x41bc8490 S:0x41bc72ac] OM - Memory_getBufferPhysicalAddress> return (0x863e8c00)
@7,377,890us: [+0 T:0x41bc8490 S:0x41bc72f4] CV - VISA_call(visa=0x63090, msg=0x423d9900): messageId=0x0002040b, command=0x0
@7,378,048us: [+0 T:0x41bc8490 S:0x41bc72bc] OC - Comm_put> Enter(queue=0x2, msg=0x423d9900)
@7,378,251us: [+0 T:0x41bc8490 S:0x41bc72bc] OC - Comm_put> return (0)
@7,378,401us: [+0 T:0x41bc8490 S:0x41bc72b4] OC - Comm_get> Enter(queue=0x10001, msg=0x41bc736c, timeout=-1)
@7,378,630us: [+0 T:0x44a8d490 S:0x44a8cd7c] ti.sdo.ce.osal.Sem - Entered Sem_pend> sem[0x63620] timeout[0xffffffff]
@7,378,801us: [+0 T:0x44a8d490 S:0x44a8cd7c] ti.sdo.ce.osal.Sem - Leaving Sem_pend> sem[0x63620] status[0]
@7,378,949us: [+0 T:0x44a8d490 S:0x44a8cd8c] ti.sdo.ce.osal.Sem - Entered Sem_post> sem[0x63620]
@7,379,099us: [+0 T:0x44a8d490 S:0x44a8cd9c] ti.sdo.ce.osal.Sem - Leaving Sem_post> sem[0x63620]
@7,379,321us: [+0 T:0x44a8d490 S:0x44a8cd7c] ti.sdo.ce.osal.Sem - Entered Sem_pend> sem[0x63638] timeout[0xffffffff]
@7,379,585us: [+0 T:0x44a8d490 S:0x44a8cd7c] ti.sdo.ce.osal.Sem - Leaving Sem_pend> sem[0x63638] status[0]
@7,713,349us: [+0 T:0x41bc8490 S:0x41bc72b4] OC - Comm_get> MSGQ_get() status=0x8000, return (0)
@7,713,564us: [+0 T:0x41bc8490 S:0x41bc7244] OC - Comm_put> Enter(queue=0x0, msg=0x423d8900)
@7,713,782us: [+0 T:0x41bc8490 S:0x41bc7244] OC - Comm_put> return (0)
@7,713,974us: [+0 T:0x41bc8490 S:0x41bc723c] OC - Comm_get> Enter(queue=0x10000, msg=0x41bc72dc, timeout=-1)
@7,718,436us: [+0 T:0x44a8d490 S:0x44a8cd8c] ti.sdo.ce.osal.Sem - Entered Sem_post> sem[0x63638]
@7,718,633us: [+0 T:0x44a8d490 S:0x44a8cd9c] ti.sdo.ce.osal.Sem - Leaving Sem_post> sem[0x63638]
@7,718,786us: [+0 T:0x44a8d490 S:0x44a8cd7c] ti.sdo.ce.osal.Sem - Entered Sem_pend> sem[0x63620] timeout[0xffffffff]
@7,718,971us: [+0 T:0x44a8d490 S:0x44a8cd7c] ti.sdo.ce.osal.Sem - Leaving Sem_pend> sem[0x63620] status[0]
@7,719,113us: [+0 T:0x44a8d490 S:0x44a8cd8c] ti.sdo.ce.osal.Sem - Entered Sem_post> sem[0x63620]
@7,719,256us: [+0 T:0x44a8d490 S:0x44a8cd9c] ti.sdo.ce.osal.Sem - Leaving Sem_post> sem[0x63620]
@7,719,483us: [+0 T:0x44a8d490 S:0x44a8cd7c] ti.sdo.ce.osal.Sem - Entered Sem_pend> sem[0x63638] timeout[0xffffffff]
@7,719,665us: [+0 T:0x44a8d490 S:0x44a8cd7c] ti.sdo.ce.osal.Sem - Leaving Sem_pend> sem[0x63638] status[0]
@7,719,813us: [+0 T:0x44a8d490 S:0x44a8cd7c] ti.sdo.ce.osal.Sem - Entered Sem_post> sem[0x63638]
@7,719,962us: [+0 T:0x44a8d490 S:0x44a8cd8c] ti.sdo.ce.osal.Sem - Leaving Sem_post> sem[0x63638]
@7,720,101us: [+0 T:0x44a8d490 S:0x44a8cd7c] ti.sdo.ce.osal.Sem - Entered Sem_pend> sem[0x63650] timeout[0xffffffff]
@7,720,391us: [+0 T:0x41bc8490 S:0x41bc723c] OC - Comm_get> MSGQ_get() status=0x8000, return (0)
[DSP] @11,268,119tk: [+5 T:0x8749198c S:0x8749548c] CN - NODE> 0x878d4020(h264dec#0) call(algHandle=0x878d40e0, msg=0x87f04900); messageId=0x0002040b
[DSP] @11,268,211tk: [+0 T:0x8749198c S:0x87495364] OM - Memory_cacheInv> Enter(addr=0x864ef000, sizeInBytes=1048576)
[DSP] @11,269,410tk: [+0 T:0x8749198c S:0x87495364] OM - Memory_cacheInv> return
[DSP] @11,269,457tk: [+0 T:0x8749198c S:0x87495324] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_process> Enter (handle=0x878d40e0, inBufs=0x874953cc, outBufs=0x87495490, inArgs=0x87f04a78, outArgs=0x87f04a84)
[DSP] @11,269,566tk: [+5 T:0x8749198c S:0x87495304] CV - VISA_enter(visa=0x878d40e0): algHandle = 0x878d4110
[DSP] @11,269,627tk: [+0 T:0x8749198c S:0x874952e4] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Enter(alg=0x878d4110)
[DSP] @11,269,691tk: [+0 T:0x8749198c S:0x874952e4] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Exit
[DSP] @11,270,118tk: [+5 T:0x8749198c S:0x87495304] CV - VISA_exit(visa=0x878d40e0): algHandle = 0x878d4110
[DSP] @11,270,190tk: [+0 T:0x8749198c S:0x874952e4] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> Enter(alg=0x878d4110)
[DSP] @11,270,257tk: [+0 T:0x8749198c S:0x874952e4] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> Exit
[DSP] @11,270,313tk: [+0 T:0x8749198c S:0x87495324] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_process> Exit (handle=0x878d40e0, retVal=0xffffffff)
[DSP] @11,270,406tk: [+5 T:0x8749198c S:0x8749548c] CN - NODE> returned from call(algHandle=0x878d40e0, msg=0x87f04900); messageId=0x0002040b
[DSP] @12,046,665tk: [+0 T:0x8680006c S:0x8680402c] CR - processRmsCmd(0x87f03928, 4056): cmd = 5
[DSP] @12,046,722tk: [+0 T:0x8680006c S:0x8680402c] CR - remote time = 0x0, trace buffer size = 4032
@7,723,013us: [+0 T:0x41bc8490 S:0x41bc7274] CE - Engine_fwriteTrace> returning count [1697]
@7,723,176us: [+0 T:0x41bc8490 S:0x41bc72f4] CV - VISA_call Completed: messageId=0x0002040b, command=0x0, return(status=-1)
@7,723,361us: [+5 T:0x41bc8490 S:0x41bc72cc] CV - VISA_freeMsg(0x63090, 0x423d9900): Freeing message with messageId=0x0002040b
@7,723,517us: [+0 T:0x41bc8490 S:0x41bc7354] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_process> Exit (handle=0x63090, retVal=0xffffffff)
@7,723,669us: [+2 T:0x41bc8490 S:0x41bc73a4] ti.sdo.dmai - [Vdec2] VIDDEC2_process() ret -1 inId 0 inUse 1 consumed 3534
@8,057,396us: [+7 T:0x41bc8490 S:0x41bc73a4] ti.sdo.dmai - [Vdec2] VIDDEC2_process() failed with error (-1 ext: 0x8860)
Error: Failed to decode video buffer
@8,057,944us: [+0 T:0x41bc8490 S:0x41bc7cd4] ti.sdo.ce.osal.Sem - Entered Sem_post> sem[0x63620]
@8,058,136us: [+0 T:0x41bc8490 S:0x41bc7ce4] ti.sdo.ce.osal.Sem - Leaving Sem_post> sem[0x63620]
@8,058,281us: [+0 T:0x41bc8490 S:0x41bc7cd4] ti.sdo.ce.osal.Sem - Entered Sem_post> sem[0x63638]
@8,058,430us: [+0 T:0x41bc8490 S:0x41bc7ce4] ti.sdo.ce.osal.Sem - Leaving Sem_post> sem[0x63638]
@8,058,569us: [+0 T:0x41bc8490 S:0x41bc7cd4] ti.sdo.ce.osal.Sem - Entered Sem_post> sem[0x63650]
@8,058,724us: [+0 T:0x41bc8490 S:0x41bc7ce4] ti.sdo.ce.osal.Sem - Leaving Sem_post> sem[0x63650]
@8,058,913us: [+0 T:0x44a8d490 S:0x44a8cd7c] ti.sdo.ce.osal.Sem - Leaving Sem_pend> sem[0x63650] status[0]
@8,322,976us: [+2 T:0x41bc8490 S:0x41bc7cb4] ti.sdo.dmai - [Buffer] Free Buffer of size 2697152 at 0x43f7d000 (0x864ef000 phys)
@8,323,217us: [+0 T:0x41bc8490 S:0x41bc7c94] OM - Memory_free> Enter(0x43f7d000, 0x2927c0)
@8,323,376us: [+0 T:0x41bc8490 S:0x41bc7c5c] OM - Memory_contigFree> Enter(addr=1140314112, size=2697152)
@8,332,396us: [+1 T:0x41bc8490 S:0x41bc7c34] OM - Memory__removeContigBuf> Enter(virtAddr=0x43f7d000, size=2697152)
@8,332,583us: [+1 T:0x41bc8490 S:0x41bc7c34] OM - Memory__removeContigBuf> removing cb->phys=0x864ef000, cb->size=0x2927c0, cb->virt=0x43f7d000
@8,332,743us: [+0 T:0x41bc8490 S:0x41bc7c3c] OM - Memory_free> Enter(0x635c8, 0x10)
@8,332,888us: [+0 T:0x41bc8490 S:0x41bc7c3c] OM - Memory_free> return (0x1)
@8,333,022us: [+0 T:0x41bc8490 S:0x41bc7c5c] OM - Memory_contigFree> return (0x1)
@8,333,150us: [+0 T:0x41bc8490 S:0x41bc7c94] OM - Memory_free> return (0x1)
@8,333,284us: [+0 T:0x41bc8490 S:0x41bc7cc4] ti.sdo.ce.osal.Sem - Entered Sem_delete> sem[0x63620]
@8,333,453us: [+0 T:0x41bc8490 S:0x41bc7ca4] OM - Memory_free> Enter(0x63620, 0x14)
@8,333,609us: [+0 T:0x41bc8490 S:0x41bc7ca4] OM - Memory_free> return (0x1)
@8,333,744us: [+0 T:0x41bc8490 S:0x41bc7cd4] ti.sdo.ce.osal.Sem - Leaving Sem_delete>
@8,333,870us: [+0 T:0x41bc8490 S:0x41bc7cc4] ti.sdo.ce.osal.Sem - Entered Sem_delete> sem[0x63638]
@8,334,000us: [+0 T:0x41bc8490 S:0x41bc7ca4] OM - Memory_free> Enter(0x63638, 0x14)
@8,334,139us: [+0 T:0x41bc8490 S:0x41bc7ca4] OM - Memory_free> return (0x1)
@8,334,268us: [+0 T:0x41bc8490 S:0x41bc7cd4] ti.sdo.ce.osal.Sem - Leaving Sem_delete>
@8,334,420us: [+0 T:0x41bc8490 S:0x41bc7cc4] ti.sdo.ce.osal.Sem - Entered Sem_delete> sem[0x63650]
@8,334,557us: [+0 T:0x41bc8490 S:0x41bc7ca4] OM - Memory_free> Enter(0x63650, 0x14)
@8,334,696us: [+0 T:0x41bc8490 S:0x41bc7ca4] OM - Memory_free> return (0x1)
@8,334,827us: [+0 T:0x41bc8490 S:0x41bc7cd4] ti.sdo.ce.osal.Sem - Leaving Sem_delete>
@8,334,954us: [+0 T:0x41bc8490 S:0x41bc7ce4] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_delete> Enter (handle=0x63090)
@8,335,096us: [+0 T:0x41bc8490 S:0x41bc7cbc] CV - VISA_delete(0x63090)
@8,335,227us: [+5 T:0x41bc8490 S:0x41bc7cbc] CV - VISA_delete> deleting codec (localQueue=0x10001, remoteQueue=0x2)
@8,335,370us: [+0 T:0x41bc8490 S:0x41bc7c8c] OC - Comm_put> Enter(queue=0x2, msg=0x423d9900)
@8,335,564us: [+0 T:0x41bc8490 S:0x41bc7c8c] OC - Comm_put> return (0)
@8,335,744us: [+0 T:0x41bc8490 S:0x41bc7c94] OC - Comm_get> Enter(queue=0x10001, msg=0x630d8, timeout=-1)
@8,336,115us: [+0 T:0x4001f950 S:0xbeb38a54] CE - Engine_close(0x636f8)
@8,336,311us: [+0 T:0x4001f950 S:0xbeb389bc] OC - Comm_put> Enter(queue=0x0, msg=0x423da900)
@8,336,524us: [+0 T:0x4001f950 S:0xbeb389bc] OC - Comm_put> return (0)
@8,336,700us: [+0 T:0x4001f950 S:0xbeb389b4] OC - Comm_get> Enter(queue=0x10002, msg=0xbeb38a54, timeout=-1)
@8,337,032us: [+0 T:0x41bc8490 S:0x41bc7c94] OC - Comm_get> MSGQ_get() status=0x8000, return (0)
@8,337,240us: [+0 T:0x41bc8490 S:0x41bc7c74] CE - Engine_deleteNode(0x62e88)
@8,337,383us: [+0 T:0x41bc8490 S:0x41bc7c54] OC - Comm_put> Enter(queue=0x0, msg=0x423d8900)
@8,337,578us: [+0 T:0x41bc8490 S:0x41bc7c54] OC - Comm_put> return (0)
@8,337,723us: [+0 T:0x41bc8490 S:0x41bc7c4c] OC - Comm_get> Enter(queue=0x10000, msg=0x41bc7ccc, timeout=-1)
@8,337,936us: [+0 T:0x4001f950 S:0xbeb389b4] OC - Comm_get> MSGQ_get() status=0x8000, return (0)
[DSP] @13,489,412tk: [+0 T:0x8749198c S:0x8749548c] CN - NODE_EXECFXN(0x878d4020): h264dec#0 exiting per request ...
[DSP] @13,491,608tk: [+0 T:0x8680006c S:0x8680402c] CR - processRmsCmd(0x87f05928, 4056): cmd = 5
[DSP] @13,491,663tk: [+0 T:0x8680006c S:0x8680402c] CR - remote time = 0x0, trace buffer size = 4032
@8,338,624us: [+0 T:0x4001f950 S:0xbeb389ec] CE - Engine_fwriteTrace> returning count [316]
@8,338,779us: [+0 T:0x4001f950 S:0xbeb38a44] OC - Comm_free> Enter (msg=0x423da900)
@8,338,952us: [+0 T:0x4001f950 S:0xbeb38a44] OC - Comm_free> return (0)
@8,339,093us: [+0 T:0x4001f950 S:0xbeb38a34] OC - Comm_delete> Enter (comm=0x63750)
@8,339,615us: [+0 T:0x4001f950 S:0xbeb38a14] OM - Memory_free> Enter(0x63750, 0x4)
@8,339,815us: [+0 T:0x4001f950 S:0xbeb38a14] OM - Memory_free> return (0x1)
@8,339,993us: [+0 T:0x4001f950 S:0xbeb38a54] OC - Comm_delete> return
@8,340,133us: [+0 T:0x4001f950 S:0xbeb38a44] OC - Comm_release> Enter(queue=0x0)
@8,675,765us: [+0 T:0x41bc8490 S:0x41bc7c4c] OC - Comm_get> MSGQ_get() status=0x8000, return (0)
@8,675,957us: [+5 T:0x41bc8490 S:0x41bc7c74] CE - Engine_deleteNode(0x62e88): algName = h264dec, algHandle = 0x878d40e0, stack size = 13096, stack used = 1335(11%)
@8,676,134us: [+0 T:0x41bc8490 S:0x41bc7c4c] OC - Comm_delete> Enter (comm=0x630c8)
@8,676,542us: [+0 T:0x41bc8490 S:0x41bc7c2c] OM - Memory_free> Enter(0x630c8, 0x4)
@8,676,736us: [+0 T:0x41bc8490 S:0x41bc7c2c] OM - Memory_free> return (0x1)
@8,676,881us: [+0 T:0x41bc8490 S:0x41bc7c6c] OC - Comm_delete> return
@8,677,011us: [+0 T:0x41bc8490 S:0x41bc7c54] OM - Memory_free> Enter(0x62e88, 0x20)
@8,677,153us: [+0 T:0x41bc8490 S:0x41bc7c54] OM - Memory_free> return (0x1)
@8,677,286us: [+0 T:0x41bc8490 S:0x41bc7cac] OC - Comm_free> Enter (msg=0x423d9900)
@8,677,452us: [+0 T:0x41bc8490 S:0x41bc7cac] OC - Comm_free> return (0)
@8,677,589us: [+0 T:0x41bc8490 S:0x41bc7c9c] OM - Memory_free> Enter(0x630d8, 0x4)
@8,677,732us: [+0 T:0x41bc8490 S:0x41bc7c9c] OM - Memory_free> return (0x1)
@8,677,866us: [+0 T:0x41bc8490 S:0x41bc7c9c] OM - Memory_free> Enter(0x63090, 0x30)
@8,678,003us: [+0 T:0x41bc8490 S:0x41bc7c9c] OM - Memory_free> return (0x1)
@8,678,134us: [+0 T:0x41bc8490 S:0x41bc7cf4] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_delete> return
@8,678,268us: [+0 T:0x41bc8490 S:0x41bc7ce4] CE - Engine_close(0x62ab0)
@8,678,412us: [+0 T:0x41bc8490 S:0x41bc7c4c] OC - Comm_put> Enter(queue=0x0, msg=0x423d8900)
@8,678,643us: [+0 T:0x41bc8490 S:0x41bc7c4c] OC - Comm_put> return (0)
@8,678,826us: [+0 T:0x41bc8490 S:0x41bc7c44] OC - Comm_get> Enter(queue=0x10000, msg=0x41bc7ce4, timeout=-1)
@8,679,041us: [+0 T:0x4001f950 S:0xbeb38a44] OC - Comm_release> return (0)
@8,679,206us: [+0 T:0x4001f950 S:0xbeb38a54] CE - rserverClose(0x60f30), count = 2
@8,679,348us: [+0 T:0x4001f950 S:0xbeb38a54] CE - rserverClose(0x60f30) done.
@8,679,605us: [+0 T:0x4001f950 S:0xbeb38a54] OM - Memory_free> Enter(0x636f8, 0x2c)
@8,679,763us: [+0 T:0x4001f950 S:0xbeb38a54] OM - Memory_free> return (0x1)
@8,680,118us: [+0 T:0x41bc8490 S:0x41bc7c44] OC - Comm_get> MSGQ_get() status=0x8000, return (0)
[DSP] @13,494,053tk: [+0 T:0x8680006c S:0x8680402c] CR - processRmsCmd(0x87f03928, 4056): cmd = 2
[DSP] @13,494,481tk: [+0 T:0x8680006c S:0x86803fe4] CN - NODE_delete(0x878d4020): h264dec#0
[DSP] @13,494,549tk: [+0 T:0x8680006c S:0x86803fc4] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_delete> Enter (handle=0x878d40e0)
[DSP] @13,494,611tk: [+0 T:0x8680006c S:0x86803f94] CV - VISA_delete(0x878d40e0)
[DSP] @13,494,659tk: [+5 T:0x8680006c S:0x86803f94] CV - VISA_delete> deleting codec (localQueue=0xffff, remoteQueue=0xffff)
[DSP] @13,494,721tk: [+5 T:0x8680006c S:0x86803f94] CV - VISA_delete> deleting codec 0x878d4110
[DSP] @13,494,773tk: [+0 T:0x8680006c S:0x86803f74] ti.sdo.ce.alg.Algorithm - Algorithm_delete> Enter(alg=0x878d4110)
[DSP] @13,494,996tk: [+0 T:0x8680006c S:0x86803eb4] ti.sdo.ce.osal.Sem - Sem_delete> sem: 0x87491aec
[DSP] @13,495,283tk: [+0 T:0x8680006c S:0x86803f44] OM - Memory_free> Enter(addr=0x878d4110, size=16)
[DSP] @13,495,345tk: [+0 T:0x8680006c S:0x86803f44] OM - Memory_free> return (0x1)
[DSP] @13,495,390tk: [+0 T:0x8680006c S:0x86803f74] ti.sdo.ce.alg.Algorithm - Algorithm_delete> Exit
[DSP] @13,495,441tk: [+0 T:0x8680006c S:0x86803f64] OM - Memory_free> Enter(addr=0x878d40e0, size=48)
[DSP] @13,495,497tk: [+0 T:0x8680006c S:0x86803f64] OM - Memory_free> return (0x1)
[DSP] @13,495,543tk: [+0 T:0x8680006c S:0x86803fc4] ti.sdo.ce.video2.VIDDEC2 - VIDDEC2_delete> return
[DSP] @13,495,625tk: [+0 T:0x8680006c S:0x86803fac] OM - Memory_free> Enter(addr=0x878d4040, size=36)
[DSP] @13,495,684tk: [+0 T:0x8680006c S:0x86803fac] OM - Memory_free> return (0x1)
[DSP] @13,495,731tk: [+0 T:0x8680006c S:0x86803fb4] OM - Memory_free> Enter(addr=0x878d4020, size=32)
[DSP] @13,495,786tk: [+0 T:0x8680006c S:0x86803fb4] OM - Memory_free> return (0x1)
[DSP] @13,495,832tk: [+0 T:0x8680006c S:0x86803fd4] OM - Memory_free> Enter(addr=0x878d4010, size=10)
[DSP] @13,495,889tk: [+0 T:0x8680006c S:0x86803fd4] OM - Memory_free> return (0x1)
[DSP] @13,495,934tk: [+0 T:0x8680006c S:0x86803fd4] OM - Memory_free> Enter(addr=0x878d3ff8, size=24)
[DSP] @13,495,990tk: [+0 T:0x8680006c S:0x86803fd4] OM - Memory_free> return (0x1)
[DSP] @14,285,440tk: [+0 T:0x8680006c S:0x8680402c] CR - processRmsCmd(0x87f03928, 4056): cmd = 5
[DSP] @14,285,500tk: [+0 T:0x8680006c S:0x8680402c] CR - remote time = 0x0, trace buffer size = 4032
@9,017,948us: [+0 T:0x41bc8490 S:0x41bc7c7c] CE - Engine_fwriteTrace> returning count [2344]
@9,018,112us: [+0 T:0x41bc8490 S:0x41bc7cd4] OC - Comm_free> Enter (msg=0x423d8900)
@9,018,288us: [+0 T:0x41bc8490 S:0x41bc7cd4] OC - Comm_free> return (0)
@9,018,429us: [+0 T:0x41bc8490 S:0x41bc7cc4] OC - Comm_delete> Enter (comm=0x62eb0)
@9,018,835us: [+0 T:0x41bc8490 S:0x41bc7ca4] OM - Memory_free> Enter(0x62eb0, 0x4)
@9,019,028us: [+0 T:0x41bc8490 S:0x41bc7ca4] OM - Memory_free> return (0x1)
@9,019,174us: [+0 T:0x41bc8490 S:0x41bc7ce4] OC - Comm_delete> return
@9,019,308us: [+0 T:0x41bc8490 S:0x41bc7cd4] OC - Comm_release> Enter(queue=0x0)
@9,019,572us: [+0 T:0x41bc8490 S:0x41bc7cd4] OC - Comm_release> return (0)
@9,019,749us: [+0 T:0x41bc8490 S:0x41bc7ce4] CE - rserverClose(0x60f30), count = 1
@9,019,896us: [+0 T:0x41bc8490 S:0x41bc7ccc] OP - Processor_delete> Enter(proc=0x62ae0)
@9,020,032us: [+0 T:0x41bc8490 S:0x41bc7cac] OP - doCmd> Enter (cmdId=2, proc=0x62ae0)
@9,020,168us: [+0 T:0x41bc8490 S:0x41bc7c9c] ti.sdo.ce.osal.Sem - Entered Sem_post> sem[0x621c0]
@9,020,331us: [+0 T:0x41bc8490 S:0x41bc7cac] ti.sdo.ce.osal.Sem - Leaving Sem_post> sem[0x621c0]
@9,020,477us: [+0 T:0x41bc8490 S:0x41bc7c8c] ti.sdo.ce.osal.Sem - Entered Sem_pend> sem[0x621d8] timeout[0xffffffff]
@9,020,665us: [+0 T:0x40bc8490 S:0x40bc7d54] ti.sdo.ce.osal.Sem - Leaving Sem_pend> sem[0x621c0] status[0]
@9,020,828us: [+0 T:0x40bc8490 S:0x40bc7d74] OP - getCmd_d> Exit (result=2)
@9,020,968us: [+0 T:0x40bc8490 S:0x40bc7d5c] OP - Processor_delete_d> Enter (proc=0x62ae0)
@9,021,106us: [+2 T:0x40bc8490 S:0x40bc7d5c] OP - Processor_delete_d> Closing remote transport...
@9,021,571us: [+2 T:0x40bc8490 S:0x40bc7d5c] OP - Processor_delete_d> Stopping DSP...
@9,021,830us: [+2 T:0x40bc8490 S:0x40bc7d5c] OP - Processor_delete_d> Closing pool...
@9,022,441us: [+2 T:0x40bc8490 S:0x40bc7d5c] OP - Processor_delete_d> Detaching from DSP...
@9,027,619us: [+2 T:0x40bc8490 S:0x40bc7d5c] OP - Processor_delete_d> Destroying DSP... (object, that is)
@9,028,686us: [+0 T:0x40bc8490 S:0x40bc7d3c] OM - Memory_free> Enter(0x62b08, 0x0)
@9,028,883us: [+0 T:0x40bc8490 S:0x40bc7d3c] OM - Memory_free> return (0x1)
@9,029,033us: [+0 T:0x40bc8490 S:0x40bc7d74] OP - Processor_delete_d> return
@9,029,174us: [+0 T:0x40bc8490 S:0x40bc7d64] ti.sdo.ce.osal.Sem - Entered Sem_post> sem[0x621d8]
@9,029,441us: [+0 T:0x41bc8490 S:0x41bc7c8c] ti.sdo.ce.osal.Sem - Leaving Sem_pend> sem[0x621d8] status[0]
@9,029,618us: [+0 T:0x41bc8490 S:0x41bc7cac] OP - doCmd> Exit (result=1)
@9,029,759us: [+1 T:0x41bc8490 S:0x41bc7ccc] OP - Processor_delete(0x62ae0) freeing object ...
@9,029,898us: [+0 T:0x41bc8490 S:0x41bc7cac] OM - Memory_free> Enter(0x62ae0, 0x24)
@9,030,038us: [+0 T:0x41bc8490 S:0x41bc7cac] OM - Memory_free> return (0x1)
@9,030,171us: [+0 T:0x41bc8490 S:0x41bc7ce4] OP - Processor_delete> return.
@9,030,299us: [+0 T:0x41bc8490 S:0x41bc7ce4] CE - rserverClose(0x60f30) done.
@9,030,429us: [+0 T:0x41bc8490 S:0x41bc7ce4] OM - Memory_free> Enter(0x62ab0, 0x2c)
@9,030,566us: [+0 T:0x41bc8490 S:0x41bc7ce4] OM - Memory_free> return (0x1)
@9,030,702us: [+2 T:0x41bc8490 S:0x41bc7c94] ti.sdo.dmai - [Buffer] Free Buffer of size 3211776 at 0x43028000 (0x861de000 phys)
@9,363,919us: [+0 T:0x41bc8490 S:0x41bc7c74] OM - Memory_free> Enter(0x43028000, 0x310200)
@9,364,099us: [+0 T:0x41bc8490 S:0x41bc7c3c] OM - Memory_contigFree> Enter(addr=1124237312, size=3211776)
@9,373,127us: [+1 T:0x41bc8490 S:0x41bc7c14] OM - Memory__removeContigBuf> Enter(virtAddr=0x43028000, size=3211776)
@9,373,315us: [+1 T:0x41bc8490 S:0x41bc7c14] OM - Memory__removeContigBuf> removing cb->phys=0x861de000, cb->size=0x310200, cb->virt=0x43028000
@9,373,475us: [+0 T:0x41bc8490 S:0x41bc7c1c] OM - Memory_free> Enter(0x63188, 0x10)
@9,373,619us: [+0 T:0x41bc8490 S:0x41bc7c1c] OM - Memory_free> return (0x1)
@9,373,752us: [+0 T:0x41bc8490 S:0x41bc7c3c] OM - Memory_contigFree> return (0x1)
@9,373,883us: [+0 T:0x41bc8490 S:0x41bc7c74] OM - Memory_free> return (0x1)
@9,374,019us: [+2 T:0x41bc8490 S:0x41bc7c94] ti.sdo.dmai - [Buffer] Free Buffer of size 3211776 at 0x43339000 (0x85ecd000 phys)
@9,374,166us: [+0 T:0x41bc8490 S:0x41bc7c74] OM - Memory_free> Enter(0x43339000, 0x310200)
@9,374,305us: [+0 T:0x41bc8490 S:0x41bc7c3c] OM - Memory_contigFree> Enter(addr=1127452672, size=3211776)
@9,383,308us: [+1 T:0x41bc8490 S:0x41bc7c14] OM - Memory__removeContigBuf> Enter(virtAddr=0x43339000, size=3211776)
@9,383,520us: [+1 T:0x41bc8490 S:0x41bc7c14] OM - Memory__removeContigBuf> removing cb->phys=0x85ecd000, cb->size=0x310200, cb->virt=0x43339000
@9,383,681us: [+0 T:0x41bc8490 S:0x41bc7c1c] OM - Memory_free> Enter(0x63210, 0x10)
@9,383,825us: [+0 T:0x41bc8490 S:0x41bc7c1c] OM - Memory_free> return (0x1)
@9,383,956us: [+0 T:0x41bc8490 S:0x41bc7c3c] OM - Memory_contigFree> return (0x1)
@9,384,085us: [+0 T:0x41bc8490 S:0x41bc7c74] OM - Memory_free> return (0x1)
@9,384,221us: [+2 T:0x41bc8490 S:0x41bc7c94] ti.sdo.dmai - [Buffer] Free Buffer of size 3211776 at 0x4364a000 (0x85bbc000 phys)
@9,384,367us: [+0 T:0x41bc8490 S:0x41bc7c74] OM - Memory_free> Enter(0x4364a000, 0x310200)
@9,384,503us: [+0 T:0x41bc8490 S:0x41bc7c3c] OM - Memory_contigFree> Enter(addr=1130668032, size=3211776)
@9,393,517us: [+1 T:0x41bc8490 S:0x41bc7c14] OM - Memory__removeContigBuf> Enter(virtAddr=0x4364a000, size=3211776)
@9,393,707us: [+1 T:0x41bc8490 S:0x41bc7c14] OM - Memory__removeContigBuf> removing cb->phys=0x85bbc000, cb->size=0x310200, cb->virt=0x4364a000
@9,393,869us: [+0 T:0x41bc8490 S:0x41bc7c1c] OM - Memory_free> Enter(0x63298, 0x10)
@9,394,012us: [+0 T:0x41bc8490 S:0x41bc7c1c] OM - Memory_free> return (0x1)
@9,394,145us: [+0 T:0x41bc8490 S:0x41bc7c3c] OM - Memory_contigFree> return (0x1)
@9,394,276us: [+0 T:0x41bc8490 S:0x41bc7c74] OM - Memory_free> return (0x1)
@9,394,409us: [+2 T:0x41bc8490 S:0x41bc7c94] ti.sdo.dmai - [Buffer] Free Buffer of size 3211776 at 0x4395b000 (0x858ab000 phys)
@9,394,555us: [+0 T:0x41bc8490 S:0x41bc7c74] OM - Memory_free> Enter(0x4395b000, 0x310200)
@9,394,724us: [+0 T:0x41bc8490 S:0x41bc7c3c] OM - Memory_contigFree> Enter(addr=1133883392, size=3211776)
@9,403,755us: [+1 T:0x41bc8490 S:0x41bc7c14] OM - Memory__removeContigBuf> Enter(virtAddr=0x4395b000, size=3211776)
@9,403,941us: [+1 T:0x41bc8490 S:0x41bc7c14] OM - Memory__removeContigBuf> removing cb->phys=0x858ab000, cb->size=0x310200, cb->virt=0x4395b000
@9,404,098us: [+0 T:0x41bc8490 S:0x41bc7c1c] OM - Memory_free> Enter(0x63320, 0x10)
@9,404,246us: [+0 T:0x41bc8490 S:0x41bc7c1c] OM - Memory_free> return (0x1)
@9,404,379us: [+0 T:0x41bc8490 S:0x41bc7c3c] OM - Memory_contigFree> return (0x1)
@9,404,508us: [+0 T:0x41bc8490 S:0x41bc7c74] OM - Memory_free> return (0x1)
@9,404,643us: [+2 T:0x41bc8490 S:0x41bc7c94] ti.sdo.dmai - [Buffer] Free Buffer of size 3211776 at 0x43c6c000 (0x85289000 phys)
@9,404,791us: [+0 T:0x41bc8490 S:0x41bc7c74] OM - Memory_free> Enter(0x43c6c000, 0x310200)
@9,404,925us: [+0 T:0x41bc8490 S:0x41bc7c3c] OM - Memory_contigFree> Enter(addr=1137098752, size=3211776)
@9,413,932us: [+1 T:0x41bc8490 S:0x41bc7c14] OM - Memory__removeContigBuf> Enter(virtAddr=0x43c6c000, size=3211776)
@9,414,118us: [+1 T:0x41bc8490 S:0x41bc7c14] OM - Memory__removeContigBuf> removing cb->phys=0x85289000, cb->size=0x310200, cb->virt=0x43c6c000
@9,414,307us: [+0 T:0x41bc8490 S:0x41bc7c1c] OM - Memory_free> Enter(0x633a8, 0x10)
@9,414,457us: [+0 T:0x41bc8490 S:0x41bc7c1c] OM - Memory_free> return (0x1)
@9,414,591us: [+0 T:0x41bc8490 S:0x41bc7c3c] OM - Memory_contigFree> return (0x1)
@9,414,720us: [+0 T:0x41bc8490 S:0x41bc7c74] OM - Memory_free> return (0x1)
@9,750,175us: [+0 T:0x40bc8490 S:0x40bc7d74] ti.sdo.ce.osal.Sem - Leaving Sem_post> sem[0x621d8]
@9,750,378us: [+0 T:0x40bc8490 S:0x40bc7d74] OP - getCmd_d> Enter (proc=0x40bc7de8)
@9,750,530us: [+0 T:0x40bc8490 S:0x40bc7d54] ti.sdo.ce.osal.Sem - Entered Sem_pend> sem[0x621c0] timeout[0xffffffff]
@9,751,018us: [+0 T:0x4001f950 S:0xbeb38aac] OG - Global_exit> enter
@9,751,197us: [+2 T:0x4001f950 S:0xbeb38aac] OG - Global_exit> calling function *0x13994()...
@9,751,371us: [+2 T:0x4001f950 S:0xbeb38aac] OG - Global_exit> calling function *0x26d44()...
@9,751,524us: [+2 T:0x4001f950 S:0xbeb38aac] OG - Global_exit> calling function *0x28a64()...
@9,751,666us: [+0 T:0x4001f950 S:0xbeb38a7c] OM - Memory_free> Enter(0x62430, 0x18)
@9,751,811us: [+0 T:0x4001f950 S:0xbeb38a7c] OM - Memory_free> return (0x1)
@9,751,947us: [+0 T:0x4001f950 S:0xbeb38a7c] OM - Memory_free> Enter(0x62450, 0x18)
@9,752,084us: [+0 T:0x4001f950 S:0xbeb38a7c] OM - Memory_free> return (0x1)
@9,752,219us: [+0 T:0x4001f950 S:0xbeb38a7c] OM - Memory_free> Enter(0x62470, 0x18)
@9,752,359us: [+0 T:0x4001f950 S:0xbeb38a7c] OM - Memory_free> return (0x1)
@9,752,494us: [+2 T:0x4001f950 S:0xbeb38aac] OG - Global_exit> calling function *0x31108()...
@9,752,633us: [+2 T:0x4001f950 S:0xbeb38aac] OG - Global_exit> calling function *0x2b5d0()...
@9,752,806us: [+2 T:0x4001f950 S:0xbeb38aac] OG - Global_exit> calling function *0x2c1a4()...
@9,752,950us: [+2 T:0x4001f950 S:0xbeb38aac] OG - Global_exit> calling function *0x2e8dc()...
@9,753,087us: [+2 T:0x4001f950 S:0xbeb38aac] OG - Global_exit> calling function *0x2c558()...
@9,753,226us: [+0 T:0x4001f950 S:0xbeb38a7c] OP - doCmd> Enter (cmdId=3, proc=0x0)
@9,753,359us: [+0 T:0x4001f950 S:0xbeb38a6c] ti.sdo.ce.osal.Sem - Entered Sem_post> sem[0x621c0]
@9,753,519us: [+0 T:0x4001f950 S:0xbeb38a7c] ti.sdo.ce.osal.Sem - Leaving Sem_post> sem[0x621c0]
@9,753,665us: [+0 T:0x4001f950 S:0xbeb38a5c] ti.sdo.ce.osal.Sem - Entered Sem_pend> sem[0x621d8] timeout[0xffffffff]
@9,753,851us: [+0 T:0x40bc8490 S:0x40bc7d54] ti.sdo.ce.osal.Sem - Leaving Sem_pend> sem[0x621c0] status[0]
@9,754,014us: [+0 T:0x40bc8490 S:0x40bc7d74] OP - getCmd_d> Exit (result=3)
@9,754,156us: [+0 T:0x40bc8490 S:0x40bc7d64] ti.sdo.ce.osal.Sem - Entered Sem_post> sem[0x621d8]
@9,754,319us: [+0 T:0x40bc8490 S:0x40bc7d74] ti.sdo.ce.osal.Sem - Leaving Sem_post> sem[0x621d8]
@9,754,680us: [+0 T:0x4001f950 S:0xbeb38a5c] ti.sdo.ce.osal.Sem - Leaving Sem_pend> sem[0x621d8] status[0]
@9,754,866us: [+0 T:0x4001f950 S:0xbeb38a7c] OP - doCmd> Exit (result=1)
@9,755,007us: [+0 T:0x4001f950 S:0xbeb38a84] OT - Thread_delete> Enter (task=0x62210)
@9,755,198us: [+4 T:0x4001f950 S:0xbeb38a84] OT - Thread_delete> pthread_cancel (0x3)
@9,755,360us: [+4 T:0x4001f950 S:0xbeb38a84] OT - Thread_delete> pthread_join (0x0)
@9,755,504us: [+0 T:0x4001f950 S:0xbeb38a64] OM - Memory_free> Enter(0x62210, 0x64)
@9,755,687us: [+0 T:0x4001f950 S:0xbeb38a64] OM - Memory_free> return (0x1)
@9,755,825us: [+0 T:0x4001f950 S:0xbeb38a9c] OT - Thread_delete> Exit (task=0x62210)
@9,755,960us: [+0 T:0x4001f950 S:0xbeb38a8c] ti.sdo.ce.osal.Sem - Entered Sem_delete> sem[0x621c0]
@9,756,095us: [+0 T:0x4001f950 S:0xbeb38a6c] OM - Memory_free> Enter(0x621c0, 0x14)
@9,756,238us: [+0 T:0x4001f950 S:0xbeb38a6c] OM - Memory_free> return (0x1)
@9,756,369us: [+0 T:0x4001f950 S:0xbeb38a9c] ti.sdo.ce.osal.Sem - Leaving Sem_delete>
@9,756,496us: [+0 T:0x4001f950 S:0xbeb38a8c] ti.sdo.ce.osal.Sem - Entered Sem_delete> sem[0x621d8]
@9,756,630us: [+0 T:0x4001f950 S:0xbeb38a6c] OM - Memory_free> Enter(0x621d8, 0x14)
@9,756,771us: [+0 T:0x4001f950 S:0xbeb38a6c] OM - Memory_free> return (0x1)
@9,756,904us: [+0 T:0x4001f950 S:0xbeb38a9c] ti.sdo.ce.osal.Sem - Leaving Sem_delete>
@9,757,035us: [+0 T:0x4001f950 S:0xbeb38a7c] OM - Memory_free> Enter(0x621f0, 0x18)
@9,757,177us: [+0 T:0x4001f950 S:0xbeb38a7c] OM - Memory_free> return (0x1)
@10,091,297us: [+2 T:0x4001f950 S:0xbeb38aac] OG - Global_exit> calling function *0x30c18()...
@10,091,512us: [+2 T:0x4001f950 S:0xbeb38aac] OG - Global_exit> calling function *0x2e390()...
@10,091,675us: [+0 T:0x4001f950 S:0xbeb38a8c] OM - Memory_free> Enter(0x62140, 0x18)
@10,091,825us: [+0 T:0x4001f950 S:0xbeb38a8c] OM - Memory_free> return (0x1)
@10,091,964us: [+2 T:0x4001f950 S:0xbeb38aac] OG - Global_exit> calling function *0x2f4e4()...
@10,092,139us: [+0 T:0x4001f950 S:0xbeb38a7c] OM - Memory_free> Enter(0x620f0, 0x18)
@10,092,295us: [+0 T:0x4001f950 S:0xbeb38a7c] OM - Memory_free> return (0x1)
@10,092,438us: [+2 T:0x4001f950 S:0xbeb38aac] OG - Global_exit> calling function *0x315b4()...
@10,092,579us: [+2 T:0x4001f950 S:0xbeb38aac] OG - Global_exit> calling function *0x32168()...
@10,092,719us: [+2 T:0x4001f950 S:0xbeb38aac] OG - Global_exit> calling function *0x31514()...

0 Prateek Bansal over 15 years ago in reply to david shen47423

TI__Expert 4510 points

It looks like the process is failing with extended error code of 0x8860. The low 8-bit LSB as per the codec user guide (page 4-21) implies it is H264D_ERR_IMPL_PPSUNAVAIL eror i.e valid PPS not available. Since this is fatal error, the decoding is terminated. Can you make sure there is valid PPS available for the stream?

0 david shen47423 over 15 years ago in reply to Prateek Bansal

Prodigy 40 points

Yes,I have delete the sps and pps from the header of the stream,if every frame need the correct pps and sps?

0 Kapil Ahuja over 15 years ago in reply to david shen47423

TI__Intellectual 1970 points

Every frame does not require a SPS/PPS. But we need a SPS/PPS at the beginning of the sequence to start decoding. Are you stripping out the SPS/PPS at the beginning of the stream?

Regards,

Kapil

0 david shen47423 over 15 years ago in reply to Kapil Ahuja

Prodigy 40 points

Yes,I stripping out the sps/pps at the beginning of the stream.

'But we need a SPS/PPS at the beginning of the sequence to start decoding'.You means at the beginning of every sequence in h264 stream we need a SPS/PPS,or the whole 264 stream only need one SPS/PPS?

0 Shyam Jagannathan over 15 years ago in reply to david shen47423

TI__Genius 10355 points

Hi David,

In H.264 codec every frame in a sequence refers to an PPS and every PPS refers to an SPS. In case the SPS or PPS is missing for a sequence, the frames cannot be decoded. The H.264 decoder will discard the frames with missing SPS and PPS and proceed till it finds valid SPS PPS headers.

I guess in the bitstream you are using, there is only 1 SPS and PPS. If you remove just the IDR frame and keep the SPS and PPS headers, the decoder should have no problem decoding the frames. Or try a bitstream with multiple SPS and PPS at different intervals, it should work fine without an IDR frame.

Shyam

Processors

Processors forum

H264 decode problem