- Ask a related questionWhat is a related question?A related question is a question created from another question. When the related question is created, it will be automatically linked to the original question.
Hi All:
I runing encode demo with dvsdk_demos_2_00_00_07 in dvsdk_2_00_00_22,
But system no respond after capture and encode some frame,
It seems the DSP hand after runing with CE_DEBUG=2
Lots of error message come out after press Ctrl+C.
Below is the detail:
1, run: loadmodules.sh
CMEMK module: built on Sep 21 2009 at 15:23:09
Reference Linux version 2.6.18
File /mnt/sdb/dvsdk_2_00_00_22/linuxutils_2_23_01/packages/ti/sdo/linuxutils/cmem/src/module/cmemk.c
ioremap_nocache(0x87600000, 10485760)=0xc8000000
allocated heap buffer 0xc8000000 of size 0x2c000
cmem initialized 5 pools between 0x87600000 and 0x88000000
dsplinkk: no version for "struct_module" found: kernel tainted.
DSPLINK Module (1.61.03) created on Date: Jul 17 2009 Time: 00:44:40
2, run : ./encode -v test.264
Encode demo started.
imageWidth:720 imageHeight:480
Cap: Complete cap 00 frame at:86F00000 691200
Cap: Complete cap 01 frame at:85000000 691200
Video: Complete get 00 frame at:87BC6000 691200
Start Venc1, In:87BC6000 Out:87965000
Venc1 completed successful.
Write: Complete get 00 frame at:87965000 345600 Write End.
Cap: Complete cap 02 frame at:85100000 691200
Video: Complete get 01 frame at:87AFB000 691200
Start Venc1, In:87AFB000 Out:8789A000
Venc1 completed successful.
Write: Complete get 01 frame at:8789A000 345600 Write End.
...
Write: Complete get 12 frame at:87965000 345600 Write End.
Cap: Complete cap 14 frame at:85100000 691200
Video: Complete get 13 frame at:87AFB000 691200
Start Venc1, In:87AFB000 Out:8789A000
Cap: Complete cap 15 frame at:86F00000 691200
*** Halt here, below message come out after press Ctrl+C ***
Unable to handle kernel paging request at virtual address c797e010
pgd = c6ea0000
[c797e010] *pgd=80442011, *pte=00000000, *ppte=00000000
Internal error: Oops: 7 [#1]
Modules linked in: dsplinkk cmemk davinci_capture mt9t001 tvp5146
CPU: 0
PC is at SYNC_WaitSEM+0x234/0x32c [dsplinkk]
LR is at flush_signals+0x64/0x90
pc : [<bf01d710>] lr : [<c0053ae4>] Tainted: GF
sp : c53fbe38 ip : c53fbe20 fp : c53fbe84
r10: ffffffff r9 : c0041680 r8 : c797e008
r7 : c53fbe44 r6 : c797e000 r5 : c53fa000 r4 : 00000002
r3 : 00000001 r2 : 00000000 r1 : c6503254 r0 : c6503254
Flags: nZCv IRQs on FIQs on Mode SVC_32 Segment user
Control: 5317F
Table: 86EA0000 DAC: 00000015
Process encode (pid: 941, stack limit = 0xc53fa258)
Stack: (0xc53fbe38 to 0xc53fc000)
be20: c7a09c80 00008000
be40: c53fbe5c 00000001 c0544d60 c0041680 c797e008 c797e008 bf025784 00000000
be60: 00008000 c53fbef4 c797a000 ffffffff 80008051 bf0325c8 c53fbeb4 c53fbe88
be80: bf021810 bf01d4ec bf021938 00006f0b 43641a94 00006f0b 00000008 c64fd180
bea0: c53fa000 00000000 c53fbec4 c53fbeb8 bf018a7c bf021790 c53fbf24 c53fbec8
bec0: bf018400 bf018a7c 00000026 c53fbef0 c53fbf24 c53fbee0 c013f988 c013e108
bee0: c53fbf04 c0142cfc c74f600c 00000026 00000049 00000000 00008000 00000000
bf00: 00010003 ffffffff 00000000 8789a000 c64fd180 43641a94 c53fbf44 c53fbf28
bf20: c00a169c bf01809c c6458558 c64fd180 00000008 43641a94 c53fbf74 c53fbf48
bf40: c00a197c c00a1630 c008c878 c00ba328 00000000 00000000 43641a94 00006f0b
bf60: c64fd180 c53fa000 c53fbfa4 c53fbf78 c00a1a08 c00a16c8 00000000 00000001
bf80: c53fbfac ffffffff 0005d5ac 43641b3c 00000036 c0031fa8 00000000 c53fbfa8
bfa0: c0031e00 c00a19b4 ffffffff 0005d5ac 00000008 00006f0b 43641a94 00000008
bfc0: ffffffff 0005d5ac 43641b3c 00000036 00010003 00001d68 0005df68 43641a7c
bfe0: 0005f314 436419a8 00031c10 4026650c 60000010 00000008 300c0363 353c33e6
Backtrace:
[<bf01d4dc>] (SYNC_WaitSEM+0x0/0x32c [dsplinkk]) from [<bf021810>] (LDRV_MSGQ_get+0x90/0xd0 [dsplinkk])
[<bf021780>] (LDRV_MSGQ_get+0x0/0xd0 [dsplinkk]) from [<bf018a7c>] (PMGR_MSGQ_get+0x10/0x14 [dsplinkk])
[<bf018a6c>] (PMGR_MSGQ_get+0x0/0x14 [dsplinkk]) from [<bf018400>] (DRV_Ioctl+0x374/0x9a0 [dsplinkk])
[<bf01808c>] (DRV_Ioctl+0x0/0x9a0 [dsplinkk]) from [<c00a169c>] (do_ioctl+0x7c/0x98)
r5 = 43641A94 r4 = C64FD180
[<c00a1620>] (do_ioctl+0x0/0x98) from [<c00a197c>] (vfs_ioctl+0x2c4/0x2ec)
r6 = 43641A94 r5 = 00000008 r4 = C64FD180
[<c00a16b8>] (vfs_ioctl+0x0/0x2ec) from [<c00a1a08>] (sys_ioctl+0x64/0x8c)
r9 = C53FA000 r8 = C64FD180 r6 = 00006F0B r5 = 43641A94
r4 = 00000000
[<c00a19a4>] (sys_ioctl+0x0/0x8c) from [<c0031e00>] (ret_fast_syscall+0x0/0x2c)
r8 = C0031FA8 r7 = 00000036 r6 = 43641B3C r5 = 0005D5AC
r4 = FFFFFFFF
Code: e5834000 e5953004 e2833001 e5853004 (e5963010)
<6>note: encode[941] exited with preempt_count 1
prev->state: 2 != TASK_RUNNING??
encode/941[CPU#0]: BUG in __schedule at kernel/sched.c:3826
root@10.9.11.17:/dm6446#
Below are the message with CE_DEBUG=2
CE_DEBUG=2 ./encode -v test.264
Encode demo started.
@0,426,725us: [+4 T:0x4001e950] OG - Global_init> This program was built with the following packages:
@0,427,327us: [+4 T:0x4001e950] OG - package gnu.targets.rts470MV (/mnt/sdb/Ti/xdctools_3_10_03/packages/gnu/targets/rts470MV/) [1,0,0,0,1225517438755]
@0,427,555us: [+4 T:0x4001e950] OG - package ti.sdo.codecs.h264enc (/mnt/sdb/dvsdk_2_00_00_22/dm6446_dvsdk_combos_2_05/packages/ti/sdo/codecs/h264enc/) [1,0,0,1247800706000]
@0,427,702us: [+4 T:0x4001e950] OG - package ti.sdo.codecs.mpeg4enc (/mnt/sdb/dvsdk_2_00_00_22/dm6446_dvsdk_combos_2_05/packages/ti/sdo/codecs/mpeg4enc/) [1,0,0,1247800734000]
@0,427,833us: [+4 T:0x4001e950] OG - package ti.sdo.codecs.g711enc (/mnt/sdb/dvsdk_2_00_00_22/dm6446_dvsdk_combos_2_05/packages/ti/sdo/codecs/g711enc/) [1,0,0,1247800687000]
@0,428,023us: [+4 T:0x4001e950] OG - package ti.sdo.ce.global (/mnt/sdb/dvsdk_2_00_00_22/codec_engine_2_23_01/packages/ti/sdo/ce/global/) [1,0,0,1240276693464]
@0,428,165us: [+4 T:0x4001e950] OG - package ti.xdais.dm (/mnt/sdb/dvsdk_2_00_00_22/xdais_6_23/packages/ti/xdais/dm/) [1,0,5,1236972452910]
@0,428,291us: [+4 T:0x4001e950] OG - package ti.sdo.utils.trace (/mnt/sdb/dvsdk_2_00_00_22/framework_components_2_23_01/packages/ti/sdo/utils/trace/) [1,0,0,1240266922890]
@0,428,425us: [+4 T:0x4001e950] OG - package ti.sdo.ce.utils.xdm (/mnt/sdb/dvsdk_2_00_00_22/codec_engine_2_23_01/packages/ti/sdo/ce/utils/xdm/) [1,0,2,1240276919490]
@0,428,553us: [+4 T:0x4001e950] OG - package dsplink.gpp (/mnt/sdb/dvsdk_2_00_00_22/dsplink-1_61_03-prebuilt/packages/dsplink/gpp/) [5,0,0,1239931267000]
@0,428,680us: [+4 T:0x4001e950] OG - package ti.sdo.linuxutils.cmem (/mnt/sdb/dvsdk_2_00_00_22/linuxutils_2_23_01/packages/ti/sdo/linuxutils/cmem/) [2,2,0,1240253001679]
@0,428,806us: [+4 T:0x4001e950] OG - package ti.xdais (/mnt/sdb/dvsdk_2_00_00_22/xdais_6_23/packages/ti/xdais/) [1,2,1,1236972427008]
@0,428,928us: [+4 T:0x4001e950] OG - package ti.sdo.ce.node (/mnt/sdb/dvsdk_2_00_00_22/codec_engine_2_23_01/packages/ti/sdo/ce/node/) [1,0,0,1240276734858]
@0,429,051us: [+4 T:0x4001e950] OG - package ti.sdo.fc.global (/mnt/sdb/dvsdk_2_00_00_22/framework_components_2_23_01/packages/ti/sdo/fc/global/) [1,0,0,1240266696499]
@0,429,183us: [+4 T:0x4001e950] OG - package ti.sdo.fc.dman3 (/mnt/sdb/dvsdk_2_00_00_22/framework_components_2_23_01/packages/ti/sdo/fc/dman3/) [1,0,4,1240266658662]
@0,429,312us: [+4 T:0x4001e950] OG - package ti.sdo.fc.memutils (/mnt/sdb/dvsdk_2_00_00_22/framework_components_2_23_01/packages/ti/sdo/fc/memutils/) [1,0,0,1240266801007]
@0,429,438us: [+4 T:0x4001e950] OG - package ti.sdo.fc.acpy3 (/mnt/sdb/dvsdk_2_00_00_22/framework_components_2_23_01/packages/ti/sdo/fc/acpy3/) [1,0,4,1240266659108]
@0,429,679us: [+4 T:0x4001e950] OG - package gnu.targets (/mnt/sdb/Ti/xdctools_3_10_03/packages/gnu/targets/) [1,0,1,1225517427930]
@0,429,837us: [+4 T:0x4001e950] OG - package ti.catalog.c470 (/mnt/sdb/Ti/xdctools_3_10_03/packages/ti/catalog/c470/) [1,0,1,0,0]
@0,429,975us: [+4 T:0x4001e950] OG - package ti.platforms.evmDM6446 (/mnt/sdb/Ti/xdctools_3_10_03/packages/ti/platforms/evmDM6446/) [1,0,0,1,0]
@0,430,103us: [+4 T:0x4001e950] OG - package ti.sdo.ce.osal (/mnt/sdb/dvsdk_2_00_00_22/codec_engine_2_23_01/packages/ti/sdo/ce/osal/) [2,0,2,1240276730877]
@0,430,229us: [+4 T:0x4001e950] OG - package ti.sdo.ce.osal.linux (/mnt/sdb/dvsdk_2_00_00_22/codec_engine_2_23_01/packages/ti/sdo/ce/osal/linux/) [2,0,1,1240276736840]
@0,430,359us: [+4 T:0x4001e950] OG - package ti.sdo.ce.ipc (/mnt/sdb/dvsdk_2_00_00_22/codec_engine_2_23_01/packages/ti/sdo/ce/ipc/) [2,0,1,1240276698961]
@0,430,484us: [+4 T:0x4001e950] OG - package ti.sdo.ce.ipc.dsplink (/mnt/sdb/dvsdk_2_00_00_22/codec_engine_2_23_01/packages/ti/sdo/ce/ipc/dsplink/) [2,0,1,1240276714152]
@0,430,612us: [+4 T:0x4001e950] OG - package ti.sdo.ce.alg (/mnt/sdb/dvsdk_2_00_00_22/codec_engine_2_23_01/packages/ti/sdo/ce/alg/) [1,0,1,1240276276798]
@0,430,738us: [+4 T:0x4001e950] OG - package ti.sdo.ce (/mnt/sdb/dvsdk_2_00_00_22/codec_engine_2_23_01/packages/ti/sdo/ce/) [1,0,6,1240276276491]
@0,889,537us: [+4 T:0x4001e950] OG - package ti.sdo.ce.video1 (/mnt/sdb/dvsdk_2_00_00_22/codec_engine_2_23_01/packages/ti/sdo/ce/video1/) [1,0,2,1240276935900]
@0,889,808us: [+4 T:0x4001e950] OG - package ti.sdo.codecs.h264enc.ce (/mnt/sdb/dvsdk_2_00_00_22/dm6446_dvsdk_combos_2_05/packages/ti/sdo/codecs/h264enc/ce/) [1,0,0,1247800710000]
@0,889,959us: [+4 T:0x4001e950] OG - package ti.sdo.codecs.mpeg4enc.ce (/mnt/sdb/dvsdk_2_00_00_22/dm6446_dvsdk_combos_2_05/packages/ti/sdo/codecs/mpeg4enc/ce/) [1,0,0,1247800738000]
@0,890,096us: [+4 T:0x4001e950] OG - package ti.sdo.ce.speech1 (/mnt/sdb/dvsdk_2_00_00_22/codec_engine_2_23_01/packages/ti/sdo/ce/speech1/) [1,0,1,1240276763928]
@0,890,228us: [+4 T:0x4001e950] OG - package ti.sdo.codecs.g711enc.ce (/mnt/sdb/dvsdk_2_00_00_22/dm6446_dvsdk_combos_2_05/packages/ti/sdo/codecs/g711enc/ce/) [1,0,0,1247800691000]
@0,890,358us: [+4 T:0x4001e950] OG - package ti.sdo.ce.speech (/mnt/sdb/dvsdk_2_00_00_22/codec_engine_2_23_01/packages/ti/sdo/ce/speech/) [1,0,2,1240276762193]
@0,890,484us: [+4 T:0x4001e950] OG - package ti.sdo.ce.audio (/mnt/sdb/dvsdk_2_00_00_22/codec_engine_2_23_01/packages/ti/sdo/ce/audio/) [1,0,2,1240276296374]
@0,890,610us: [+4 T:0x4001e950] OG - package ti.sdo.ce.audio1 (/mnt/sdb/dvsdk_2_00_00_22/codec_engine_2_23_01/packages/ti/sdo/ce/audio1/) [1,0,1,1240276296900]
@0,890,735us: [+4 T:0x4001e950] OG - package ti.sdo.ce.video (/mnt/sdb/dvsdk_2_00_00_22/codec_engine_2_23_01/packages/ti/sdo/ce/video/) [1,0,3,1240276930771]
@0,890,893us: [+4 T:0x4001e950] OG - package ti.sdo.ce.video2 (/mnt/sdb/dvsdk_2_00_00_22/codec_engine_2_23_01/packages/ti/sdo/ce/video2/) [1,0,2,1240276933444]
@0,891,027us: [+4 T:0x4001e950] OG - package ti.sdo.ce.image (/mnt/sdb/dvsdk_2_00_00_22/codec_engine_2_23_01/packages/ti/sdo/ce/image/) [1,0,3,1240276700525]
@0,891,163us: [+4 T:0x4001e950] OG - package ti.sdo.ce.image1 (/mnt/sdb/dvsdk_2_00_00_22/codec_engine_2_23_01/packages/ti/sdo/ce/image1/) [1,0,1,1240276698808]
@0,891,290us: [+4 T:0x4001e950] OG - package ti.sdo.dmai (/mnt/sdb/dvsdk_2_00_00_22/dmai_1_20_00_06/packages/ti/sdo/dmai/) [1,0,0,1253517803000]
@0,891,413us: [+4 T:0x4001e950] OG - package ti.sdo.simplewidget (/mnt/sdb/dvsdk_2_00_00_22/dvsdk_demos_2_00_00_07/packages/ti/sdo/simplewidget/) []
@0,891,537us: [+4 T:0x4001e950] OG - package ti.sdo.ce.bioslog (/mnt/sdb/dvsdk_2_00_00_22/codec_engine_2_23_01/packages/ti/sdo/ce/bioslog/) [1,0,1,1240276303166]
@0,891,661us: [+4 T:0x4001e950] OG - package ti.sdo.ce.utils.trace (/mnt/sdb/dvsdk_2_00_00_22/codec_engine_2_23_01/packages/ti/sdo/ce/utils/trace/) [1,0,1,1240276915917]
@0,891,787us: [+4 T:0x4001e950] OG - package encode_config (/mnt/sdb/dvsdk_2_00_00_22/dvsdk_demos_2_00_00_07/dm6446/encode/encode_config/) []
@0,892,726us: [+0 T:0x4001e950] ti.sdo.ce.osal.Sem - Sem_create> count: 0
@0,893,003us: [+0 T:0x4001e950] ti.sdo.ce.osal.Sem - Leaving Sem_create> sem[0x601c0]
@0,893,166us: [+0 T:0x4001e950] ti.sdo.ce.osal.Sem - Sem_create> count: 0
@0,893,303us: [+0 T:0x4001e950] ti.sdo.ce.osal.Sem - Leaving Sem_create> sem[0x601d8]
@0,893,447us: [+0 T:0x4001e950] OT - Thread_create> Enter (fxn=0x2b144, attrs=0x0)
@0,894,327us: [+0 T:0x4001e950] OT - Thread_create> Exit (task=0x60210)
@0,894,634us: [+0 T:0x4001e950] ti.sdo.ce.alg - ALG_init> Enter
@0,894,791us: [+0 T:0x4001e950] ti.sdo.ce.alg - ALG_init> Exit
@0,899,532us: [+1 T:0x40baf490] OP - daemon> thread created.
@0,900,497us: [+0 T:0x40baf490] OP - getCmd_d> Enter (proc=0x40baedf0)
@0,900,659us: [+0 T:0x40baf490] ti.sdo.ce.osal.Sem - Entered Sem_pend> sem[0x601c0] timeout[0xffffffff]
@0,901,514us: [+6 T:0x4001e950] CE - Engine_init> CE debugging on (CE_DEBUG=2; allowed CE_DEBUG levels: 1=min, 2=good, 3=max)
@0,901,919us: [+0 T:0x4001e950] CS - Server_init()
@0,902,067us: [+0 T:0x4001e950] CS - Server_init> Global_useLinkArbiter = 0
TraceUtil_start> note: CE_DEBUG env. var is set, so TraceUtil is not active (unset CE_DEBUG if you need TraceUtil)
imageWidth:720 imageHeight:480
@1,250,586us: [+2 T:0x413af490] ti.sdo.dmai - [Capture] Set video standard to V4L2_STD_MT9T001_480p_30FPS
@1,250,835us: [+2 T:0x413af490] ti.sdo.dmai - [Capture] Capture input set to COMPOSITE
@1,255,039us: [+2 T:0x413af490] ti.sdo.dmai - [Capture] Video input connected size 720x480 pitch 1440
@1,255,217us: [+7 T:0x413af490] ti.sdo.dmai - [Capture] Using V4L2 driver's buffer...
@1,255,505us: [+2 T:0x413af490] ti.sdo.dmai - [BufTab] Allocating BufTab for 3 buffers
@1,256,071us: [+2 T:0x413af490] ti.sdo.dmai - [Buffer] Set user pointer 0x413b0000 (physical 0x86f00000)
@1,272,103us: [+2 T:0x413af490] ti.sdo.dmai - [Dmai] Driver buffer 0 mapped to 0x413b0000 has physical address 0x86f00000
@1,272,618us: [+2 T:0x413af490] ti.sdo.dmai - [Buffer] Set user pointer 0x41461000 (physical 0x85000000)
@1,288,520us: [+2 T:0x413af490] ti.sdo.dmai - [Dmai] Driver buffer 1 mapped to 0x41461000 has physical address 0x85000000
@1,288,973us: [+2 T:0x413af490] ti.sdo.dmai - [Buffer] Set user pointer 0x41512000 (physical 0x85100000)
@1,304,938us: [+2 T:0x413af490] ti.sdo.dmai - [Dmai] Driver buffer 2 mapped to 0x41512000 has physical address 0x85100000
@1,318,417us: [+0 T:0x41dc2490] CE - Engine_open> Enter('encode', 0x0, 0x41dc1d34)
@1,318,637us: [+0 T:0x41dc2490] CE - rserverOpen('encodeCombo.x64P'), count = 0
@1,318,791us: [+0 T:0x41dc2490] OP - Processor_create> Enter(imageName='encodeCombo.x64P', linkCfg='(null)', attrs=0x41dc1d18)
@1,320,369us: [+0 T:0x41dc2490] OP - doCmd> Enter (cmdId=1, proc=0x60bc0)
@1,320,562us: [+0 T:0x41dc2490] ti.sdo.ce.osal.Sem - Entered Sem_post> sem[0x601c0]
@1,320,783us: [+0 T:0x41dc2490] ti.sdo.ce.osal.Sem - Leaving Sem_post> sem[0x601c0]
@1,320,939us: [+0 T:0x41dc2490] ti.sdo.ce.osal.Sem - Entered Sem_pend> sem[0x601d8] timeout[0xffffffff]
@1,321,126us: [+0 T:0x40baf490] ti.sdo.ce.osal.Sem - Leaving Sem_pend> sem[0x601c0] status[0]
@1,321,283us: [+0 T:0x40baf490] OP - getCmd_d> Exit (result=1)
@1,321,411us: [+0 T:0x40baf490] OP - Processor_create_d> Enter(proc=0x60bc0)
@1,321,536us: [+2 T:0x40baf490] OP - Processor_create_d> Initializing DSP PROC...
@1,321,671us: [+2 T:0x40baf490] OP - Processor_create_d> Using DspLink config data for entry #0 [server 'encodeCombo.x64P']
@1,321,948us: [+2 T:0x40baf490] OP - Processor_create_d> Adding DSP segment #0 to Link configuration: name='DDR2', startAddress=0x8fa00000, sizeInBytes=0x400000, shared=1, syncd=0
@1,322,153us: [+2 T:0x40baf490] OP - Processor_create_d> Adding DSP segment #1 to Link configuration: name='DSPLINKMEM', startAddress=0x8fe00000, sizeInBytes=0x100000, shared=1, syncd=0
@1,322,332us: [+2 T:0x40baf490] OP - Processor_create_d> Adding DSP segment #2 to Link configuration: name='RESET_VECTOR', startAddress=0x8ff00000, sizeInBytes=0x80, shared=1, syncd=0
@1,322,501us: [+2 T:0x40baf490] OP - Processor_create_d> Adding DSP segment #3 to Link configuration: name='DDRALGHEAP', startAddress=0x88000000, sizeInBytes=0x7a00000, shared=0, syncd=0
@1,322,669us: [+2 T:0x40baf490] OP - Processor_create_d> DODSPCTRL was=1; now=0
@1,332,338us: [+2 T:0x40baf490] OP - Processor_create_d> Attaching to DSP PROC...
@1,342,855us: [+2 T:0x40baf490] OP - Processor_create_d> Opening MSGQ pool...
@1,343,629us: [+2 T:0x40baf490] OP - Processor_create_d> Loading encodeCombo.x64P on DSP (1 args)...
@1,545,869us: [+2 T:0x40baf490] OP - Processor_create_d> Starting DSP PROC...
@1,600,005us: [+2 T:0x40baf490] OP - Processor_create_d> Opening remote transport...
@1,601,085us: [+2 T:0x40baf490] OP - Processor_create_d> return (1)
@1,601,288us: [+0 T:0x40baf490] ti.sdo.ce.osal.Sem - Entered Sem_post> sem[0x601d8]
@1,601,500us: [+0 T:0x41dc2490] ti.sdo.ce.osal.Sem - Leaving Sem_pend> sem[0x601d8] status[0]
@1,601,663us: [+0 T:0x41dc2490] OP - doCmd> Exit (result=1)
@1,601,794us: [+0 T:0x41dc2490] OP - Processor_create> return (0x60bc0)
@1,601,954us: [+0 T:0x41dc2490] CE - rserverOpen('encodeCombo.x64P'): 0x5f2b4 done.
@1,603,032us: [+0 T:0x40baf490] ti.sdo.ce.osal.Sem - Leaving Sem_post> sem[0x601d8]
@1,603,231us: [+0 T:0x40baf490] OP - getCmd_d> Enter (proc=0x40baedf0)
@1,603,408us: [+0 T:0x40baf490] ti.sdo.ce.osal.Sem - Entered Sem_pend> sem[0x601c0] timeout[0xffffffff]
@1,603,842us: [+0 T:0x41dc2490] CE - checkServer(0x60b90)
@1,604,361us: [+0 T:0x41dc2490] CE - rmsInit> RMS initialized(0x60b90); CE_DEBUG on, setting DSP trace mask to *+01234567,CR=67,ti.sdo.fc.dman3-2,ti.sdo.fc.dskt2-2,GT_prefix=1235,GT_time=3
@1,604,574us: [+0 T:0x41dc2490] CE - Engine_setTrace> Enter(engine=0x60b90, mask='*+01234567,CR=67,ti.sdo.fc.dman3-2,ti.sdo.fc.dskt2-2,GT_prefix=1235,GT_time=3')
@1,604,794us: [+1 T:0x41dc2490] CE - Engine_setTrace> Requesting DSP set trace ...
@1,606,451us: [+0 T:0x41dc2490] CE - Engine_setTrace> return(0)
[DSP] @0,011,862tk: [+0 T:0x8fa424e4] OG - Global_setSpecialTrace> enter(mask='*+01234567,CR=67,ti.sdo.fc.dman3-2,ti.sdo.fc.dskt2-2,GT_prefix=1235,GT_time=3')
[DSP] @0,011,969tk: [+4 T:0x8fa424e4] OG - Global_setSpecialTrace> This program was built with the following packages:
[DSP] @0,012,021tk: [+4 T:0x8fa424e4] OG - package ti.targets.rts6000 (/mnt/sdb/Ti/xdctools_3_10_03/packages/ti/targets/rts6000/) [1,0,0,0,1225517497925]
[DSP] @0,012,095tk: [+4 T:0x8fa424e4] OG - package ti.sdo.codecs.h264enc (/mnt/sdb/dvsdk_2_00_00_22/dm6446_dvsdk_combos_2_05/packages/ti/sdo/codecs/h264enc/) [1,0,0,1247800706000]
[DSP] @0,012,171tk: [+4 T:0x8fa424e4] OG - package ti.sdo.codecs.mpeg4enc (/mnt/sdb/dvsdk_2_00_00_22/dm6446_dvsdk_combos_2_05/packages/ti/sdo/codecs/mpeg4enc/) [1,0,0,1247800734000]
[DSP] @0,012,247tk: [+4 T:0x8fa424e4] OG - package ti.sdo.codecs.g711enc (/mnt/sdb/dvsdk_2_00_00_22/dm6446_dvsdk_combos_2_05/packages/ti/sdo/codecs/g711enc/) [1,0,0,1247800687000]
[DSP] @0,012,323tk: [+4 T:0x8fa424e4] OG - package ti.sdo.fc.global (/mnt/sdb/dvsdk_2_00_00_22/framework_components_2_23_01/packages/ti/sdo/fc/global/) [1,0,0,1240266696499]
[DSP] @0,012,396tk: [+4 T:0x8fa424e4] OG - package ti.sdo.fc.dman3 (/mnt/sdb/dvsdk_2_00_00_22/framework_components_2_23_01/packages/ti/sdo/fc/dman3/) [1,0,4,1240266658662]
[DSP] @0,012,469tk: [+4 T:0x8fa424e4] OG - package ti.rtdx (/mnt/sdb/Ti/bios_5_33_03/packages/ti/rtdx/) [2,0,0,4,1219531643983]
[DSP] @0,012,525tk: [+4 T:0x8fa424e4] OG - package ti.psl (/mnt/sdb/Ti/bios_5_33_03/packages/ti/psl/) [5,0,0,0,0]
[DSP] @0,012,577tk: [+4 T:0x8fa424e4] OG - package ti.bios (/mnt/sdb/Ti/bios_5_33_03/packages/ti/bios/) [5,2,5,6,1227035764854]
[DSP] @0,012,633tk: [+4 T:0x8fa424e4] OG - package ti.sdo.fc.dskt2 (/mnt/sdb/dvsdk_2_00_00_22/framework_components_2_23_01/packages/ti/sdo/fc/dskt2/) [1,0,4,1240266678055]
[DSP] @0,012,705tk: [+4 T:0x8fa424e4] OG - package ti.sdo.ce.global (/mnt/sdb/dvsdk_2_00_00_22/codec_engine_2_23_01/packages/ti/sdo/ce/global/) [1,0,0,1240276693464]
[DSP] @0,012,776tk: [+4 T:0x8fa424e4] OG - package ti.xdais.dm (/mnt/sdb/dvsdk_2_00_00_22/xdais_6_23/packages/ti/xdais/dm/) [1,0,5,1236972452910]
[DSP] @0,012,839tk: [+4 T:0x8fa424e4] OG - package ti.xdais (/mnt/sdb/dvsdk_2_00_00_22/xdais_6_23/packages/ti/xdais/) [1,2,1,1236972427008]
[DSP] @0,012,899tk: [+4 T:0x8fa424e4] OG - package ti.sdo.ce.node (/mnt/sdb/dvsdk_2_00_00_22/codec_engine_2_23_01/packages/ti/sdo/ce/node/) [1,0,0,1240276734858]
[DSP] @0,012,969tk: [+4 T:0x8fa424e4] OG - package ti.sdo.ce.ipc.dsplink.dsp (/mnt/sdb/dvsdk_2_00_00_22/codec_engine_2_23_01/packages/ti/sdo/ce/ipc/dsplink/dsp/) [2,0,1,1240276720026]
[DSP] @0,013,046tk: [+4 T:0x8fa424e4] OG - package ti.sdo.utils.trace (/mnt/sdb/dvsdk_2_00_00_22/framework_components_2_23_01/packages/ti/sdo/utils/trace/) [1,0,0,1240266922890]
[DSP] @0,013,121tk: [+4 T:0x8fa424e4] OG - package ti.sdo.ce.utils.xdm (/mnt/sdb/dvsdk_2_00_00_22/codec_engine_2_23_01/packages/ti/sdo/ce/utils/xdm/) [1,0,2,1240276919490]
[DSP] @0,013,193tk: [+4 T:0x8fa424e4] OG - package ti.sdo.fc.memutils (/mnt/sdb/dvsdk_2_00_00_22/framework_components_2_23_01/packages/ti/sdo/fc/memutils/) [1,0,0,1240266801007]
[DSP] @0,013,268tk: [+4 T:0x8fa424e4] OG - package ti.sdo.fc.acpy3 (/mnt/sdb/dvsdk_2_00_00_22/framework_components_2_23_01/packages/ti/sdo/fc/acpy3/) [1,0,4,1240266659108]
[DSP] @0,013,341tk: [+4 T:0x8fa424e4] OG - package dsplink.dsp (/mnt/sdb/dvsdk_2_00_00_22/dsplink-1_61_03-prebuilt/packages/dsplink/dsp/) [1,4,0,1239931280000]
[DSP] @0,013,410tk: [+4 T:0x8fa424e4] OG - package ti.bios.utils (/mnt/sdb/dvsdk_2_00_00_22/biosutils_1_01_00/packages/ti/bios/utils/) [2,0,1,0,0]
[DSP] @0,013,472tk: [+4 T:0x8fa424e4] OG - package ti.catalog.c6000 (/mnt/sdb/Ti/xdctools_3_10_03/packages/ti/catalog/c6000/) [1,0,0,0,0]
[DSP] @0,013,533tk: [+4 T:0x8fa424e4] OG - package ti.platforms.evmDM6446 (/mnt/sdb/Ti/bios_5_33_03/packages/ti/platforms/evmDM6446/) [1,0,0,1,0]
[DSP] @0,013,597tk: [+4 T:0x8fa424e4] OG - package ti.sdo.ce.osal (/mnt/sdb/dvsdk_2_00_00_22/codec_engine_2_23_01/packages/ti/sdo/ce/osal/) [2,0,2,1240276730877]
[DSP] @0,013,666tk: [+4 T:0x8fa424e4] OG - package ti.sdo.ce.osal.bios (/mnt/sdb/dvsdk_2_00_00_22/codec_engine_2_23_01/packages/ti/sdo/ce/osal/bios/) [2,0,1,1240276734107]
[DSP] @0,013,738tk: [+4 T:0x8fa424e4] OG - package ti.sdo.ce.ipc (/mnt/sdb/dvsdk_2_00_00_22/codec_engine_2_23_01/packages/ti/sdo/ce/ipc/) [2,0,1,1240276698961]
[DSP] @0,013,806tk: [+4 T:0x8fa424e4] OG - package ti.sdo.ce.ipc.bios (/mnt/sdb/dvsdk_2_00_00_22/codec_engine_2_23_01/packages/ti/sdo/ce/ipc/bios/) [2,0,1,1240276705217]
[DSP] @0,013,878tk: [+4 T:0x8fa424e4] OG - package ti.sdo.ce.alg (/mnt/sdb/dvsdk_2_00_00_22/codec_engine_2_23_01/packages/ti/sdo/ce/alg/) [1,0,1,1240276276798]
[DSP] @0,013,946tk: [+4 T:0x8fa424e4] OG - package ti.sdo.ce (/mnt/sdb/dvsdk_2_00_00_22/codec_engine_2_23_01/packages/ti/sdo/ce/) [1,0,6,1240276276491]
[DSP] @0,014,012tk: [+4 T:0x8fa424e4] OG - package ti.sdo.ce.bioslog (/mnt/sdb/dvsdk_2_00_00_22/codec_engine_2_23_01/packages/ti/sdo/ce/bioslog/) [1,0,1,1240276303166]
[DSP] @0,014,082tk: [+4 T:0x8fa424e4] OG - package ti.sdo.ce.video1 (/mnt/sdb/dvsdk_2_00_00_22/codec_engine_2_23_01/packages/ti/sdo/ce/video1/) [1,0,2,1240276935900]
[DSP] @0,014,153tk: [+4 T:0x8fa424e4] OG - package ti.sdo.codecs.h264enc.ce (/mnt/sdb/dvsdk_2_00_00_22/dm6446_dvsdk_combos_2_05/packages/ti/sdo/codecs/h264enc/ce/) [1,0,0,1247800710000]
[DSP] @0,014,230tk: [+4 T:0x8fa424e4] OG - package ti.sdo.codecs.mpeg4enc.ce (/mnt/sdb/dvsdk_2_00_00_22/dm6446_dvsdk_combos_2_05/packages/ti/sdo/codecs/mpeg4enc/ce/) [1,0,0,1247800738000]
[DSP] @0,014,309tk: [+4 T:0x8fa424e4] OG - package ti.sdo.ce.speech1 (/mnt/sdb/dvsdk_2_00_00_22/codec_engine_2_23_01/packages/ti/sdo/ce/speech1/) [1,0,1,1240276763928]
[DSP] @0,014,380tk: [+4 T:0x8fa424e4] OG - package ti.sdo.codecs.g711enc.ce (/mnt/sdb/dvsdk_2_00_00_22/dm6446_dvsdk_combos_2_05/packages/ti/sdo/codecs/g711enc/ce/) [1,0,0,1247800691000]
[DSP] @0,014,463tk: [+4 T:0x8fa424e4] OG - package ti.sdo.servers.encode (/mnt/sdb/dvsdk_2_00_00_22/dm6446_dvsdk_combos_2_05/packages/ti/sdo/servers/encode/) []
[DSP] @0,014,532tk: [+0 T:0x8fa424e4] OG - Global_setSpecialTrace> return
@1,957,270us: [+0 T:0x41dc2490] CE - Engine_fwriteTrace> returning count [6368]
@2,297,499us: [+0 T:0x41dc2490] CE - Engine_open> return(396176)
@2,297,689us: [+2 T:0x41dc2490] ti.sdo.dmai - [Venc1] Creating encoder h264enc for max 720x480 bitrate 2000000 ratectrl 4
@2,297,966us: [+0 T:0x41dc2490] ti.sdo.ce.video1.VIDENC1 - VIDENC1_create> Enter (engine=0x60b90, name='h264enc', params=0x41dc1dd8)
@2,298,162us: [+0 T:0x41dc2490] CV - VISA_create(0x60b90, 'h264enc', 0x41dc1dd8, 0x2496, 'ti.sdo.ce.video1.IVIDENC1')
@2,298,326us: [+0 T:0x41dc2490] CV - VISA_create2(0x60b90, 'h264enc', 0x41dc1dd8, 0x30, 0x2496, 'ti.sdo.ce.video1.IVIDENC1')
@2,298,515us: [+0 T:0x41dc2490] CE - Engine_createNode(0x60b90, 'h264enc', 2496, 0x41dc1dd8, 0x30, 0x41dc1bec)
@2,298,674us: [+0 T:0x41dc2490] CE - Engine> allocNode Enter(engine=0x60b90, impId='h264enc')
@2,298,881us: [+0 T:0x41dc2490] CE - Engine> allocNode(). Calling Comm_create(gppfromnode_927_1, 0x60e28, NULL)
@2,310,514us: [+4 T:0x41dc2490] CE - Engine_createNode> created node(stdIn=0x2, stdOut=0x10001, msgq=0x60fc8, algName='h264enc', rmsNode=0x8fa46fa0, algHandle=0x8fa47088)
[DSP] @1,623,337tk: [+0 T:0x8fa424e4] OM - Memory_alloc> Enter(size=0x18)
[DSP] @1,623,376tk: [+0 T:0x8fa424e4] OM - Memory_alloc> return (0x8fa46fa0)
[DSP] @1,623,419tk: [+0 T:0x8fa424e4] OM - Memory_alloc> Enter(size=0xa)
[DSP] @1,623,455tk: [+0 T:0x8fa424e4] OM - Memory_alloc> return (0x8fa46fb8)
[DSP] @1,623,502tk: [+0 T:0x8fa424e4] OM - Memory_alloc> Enter(size=0x20)
[DSP] @1,623,539tk: [+0 T:0x8fa424e4] OM - Memory_alloc> return (0x8fa46fc8)
[DSP] @1,623,581tk: [+0 T:0x8fa424e4] OM - Memory_alloc> Enter(size=0x24)
[DSP] @1,623,619tk: [+0 T:0x8fa424e4] OM - Memory_alloc> return (0x8fa46fe8)
[DSP] @1,623,685tk: [+0 T:0x8fa424e4] ti.sdo.ce.video1.VIDENC1 - VIDENC1_create> Enter (engine=0x0, name='h264enc', params=0x8fe05cc0)
[DSP] @1,623,755tk: [+0 T:0x8fa424e4] CV - VISA_create(0x0, 'h264enc', 0x8fe05cc0, 0x2496, 'ti.sdo.ce.video1.IVIDENC1')
[DSP] @1,623,817tk: [+0 T:0x8fa424e4] CV - VISA_create2(0x0, 'h264enc', 0x8fe05cc0, 0x30, 0x2496, 'ti.sdo.ce.video1.IVIDENC1')
[DSP] @1,623,894tk: [+0 T:0x8fa424e4] CE - Engine_open> Enter('local', 0x8fa461e4, 0x8fe05cc0)
[DSP] @1,623,944tk: [+0 T:0x8fa424e4] OM - Memory_alloc> Enter(size=0x2c)
[DSP] @1,623,982tk: [+0 T:0x8fa424e4] OM - Memory_alloc> return (0x8fa47058)
[DSP] @1,624,031tk: [+0 T:0x8fa424e4] CE - Engine_open> return(-1885048744)
[DSP] @1,624,081tk: [+0 T:0x8fa424e4] OM - Memory_alloc> Enter(size=0x30)
[DSP] @1,624,117tk: [+0 T:0x8fa424e4] OM - Memory_alloc> return (0x8fa47088)
[DSP] @1,624,162tk: [+0 T:0x8fa424e4] ti.sdo.ce.alg.Algorithm - Algorithm_create> Enter(fxns=0x8fa62f38, idma3Fxns=0x8fa62f64, iresFxns=0x0, params=0x8fe05cc0, attrs=0x8fa462f8)
[DSP] @1,624,249tk: [+0 T:0x8fa424e4] OM - Memory_alloc> Enter(size=0x10)
[DSP] @1,624,286tk: [+0 T:0x8fa424e4] OM - Memory_alloc> return (0x8fa470b8)
[DSP] @1,647,314tk: [+0 T:0x8fa424e4] ti.sdo.ce.alg.Algorithm - Algorithm_create> return (0x8fa470b8)
[DSP] @1,647,370tk: [+5 T:0x8fa424e4] CV - VISA_create> local codec created (name='h264enc', handle=0x8fa470b8)
[DSP] @1,647,426tk: [+0 T:0x8fa424e4] ti.sdo.ce.video1.VIDENC1 - VIDENC1_create> return (0x8fa47088)
[DSP] @1,647,483tk: [+4 T:0x8fa424e4] OT - Thread_create > name: "h264enc#0", pri: -1, stack size: 16384, stack seg: 0
@2,314,261us: [+0 T:0x41dc2490] CE - Engine_fwriteTrace> returning count [2223]
@2,314,421us: [+2 T:0x41dc2490] CE - Engine_createNode> Returning 0x60e20
@2,314,681us: [+5 T:0x41dc2490] CV - VISA_create> remote codec created (name='h264enc', localQueueID=0x10001, remoteQueueID=0x0002)
@2,314,866us: [+0 T:0x41dc2490] ti.sdo.ce.video1.VIDENC1 - VIDENC1_create> return (0x60f90)
@2,315,009us: [+2 T:0x41dc2490] ti.sdo.dmai - [Venc1] Setting dynParams size 720x480 bitrate 2000000
@2,315,153us: [+0 T:0x41dc2490] ti.sdo.ce.video1.VIDENC1 - VIDENC1_control> Enter (handle=0x60f90, id=1, dynParams=0x41dc1da8 (size=0x30), status=0x41dc1cb0 (size=0x9c)
@2,315,320us: [+5 T:0x41dc2490] CV - VISA_allocMsg> Allocating message for messageId=0x00023815
@2,655,907us: [+0 T:0x41dc2490] CV - VISA_call(visa=0x60f90, msg=0x425d0c80): messageId=0x00023815, command=0x1
[DSP] @2,450,863tk: [+5 T:0x8fa47524] CN - NODE> 0x8fa46fc8(h264enc#0) call(algHandle=0x8fa47088, msg=0x8fe06c80); messageId=0x00023815
[DSP] @2,450,945tk: [+0 T:0x8fa47524] ti.sdo.ce.video1.VIDENC1 - VIDENC1_control> Enter (handle=0x8fa47088, id=1, dynParams=0x8fe06cb4 (size=0x30), status=0x8fe06ce4 (size=0x9c)
[DSP] @2,451,037tk: [+5 T:0x8fa47524] CV - VISA_enter(visa=0x8fa47088): algHandle = 0x8fa470b8
[DSP] @2,451,089tk: [+0 T:0x8fa47524] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Enter(alg=0x8fa470b8)
[DSP] @2,451,211tk: [+0 T:0x8fa47524] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Exit
[DSP] @2,451,305tk: [+5 T:0x8fa47524] CV - VISA_exit(visa=0x8fa47088): algHandle = 0x8fa470b8
[DSP] @2,451,367tk: [+0 T:0x8fa47524] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> Enter(alg=0x8fa470b8)
[DSP] @2,451,424tk: [+0 T:0x8fa47524] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> Exit
[DSP] @2,451,468tk: [+0 T:0x8fa47524] ti.sdo.ce.video1.VIDENC1 - VIDENC1_control> Exit (handle=0x8fa47088, retVal=0x0)
[DSP] @2,451,529tk: [+5 T:0x8fa47524] CN - NODE> returned from call(algHandle=0x8fa47088, msg=0x8fe06c80); messageId=0x00023815
@2,658,608us: [+0 T:0x41dc2490] CE - Engine_fwriteTrace> returning count [1144]
@2,658,800us: [+0 T:0x41dc2490] CV - VISA_call Completed: messageId=0x00023815, command=0x1, return(status=0)
@2,658,964us: [+5 T:0x41dc2490] CV - VISA_freeMsg(0x60f90, 0x425d0c80): Freeing message with messageId=0x00023815
@2,659,115us: [+0 T:0x41dc2490] ti.sdo.ce.video1.VIDENC1 - VIDENC1_control> Exit (handle=0x60f90, retVal=0x0)
@2,659,256us: [+2 T:0x41dc2490] ti.sdo.dmai - [Venc1] Made XDM_SETPARAMS control call
@2,659,378us: [+0 T:0x41dc2490] ti.sdo.ce.video1.VIDENC1 - VIDENC1_control> Enter (handle=0x60f90, id=5, dynParams=0x41dc1da8 (size=0x30), status=0x41dc1cb0 (size=0x9c)
@2,659,542us: [+5 T:0x41dc2490] CV - VISA_allocMsg> Allocating message for messageId=0x00023816
@2,659,808us: [+0 T:0x41dc2490] CV - VISA_call(visa=0x60f90, msg=0x425d0c80): messageId=0x00023816, command=0x1
[DSP] @2,459,831tk: [+5 T:0x8fa47524] CN - NODE> 0x8fa46fc8(h264enc#0) call(algHandle=0x8fa47088, msg=0x8fe06c80); messageId=0x00023816
[DSP] @2,459,903tk: [+0 T:0x8fa47524] ti.sdo.ce.video1.VIDENC1 - VIDENC1_control> Enter (handle=0x8fa47088, id=5, dynParams=0x8fe06cb4 (size=0x30), status=0x8fe06ce4 (size=0x9c)
[DSP] @2,459,993tk: [+5 T:0x8fa47524] CV - VISA_enter(visa=0x8fa47088): algHandle = 0x8fa470b8
[DSP] @2,460,045tk: [+0 T:0x8fa47524] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Enter(alg=0x8fa470b8)
[DSP] @2,460,096tk: [+0 T:0x8fa47524] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Exit
[DSP] @2,460,147tk: [+5 T:0x8fa47524] CV - VISA_exit(visa=0x8fa47088): algHandle = 0x8fa470b8
[DSP] @2,460,198tk: [+0 T:0x8fa47524] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> Enter(alg=0x8fa470b8)
[DSP] @2,460,250tk: [+0 T:0x8fa47524] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> Exit
[DSP] @2,460,291tk: [+0 T:0x8fa47524] ti.sdo.ce.video1.VIDENC1 - VIDENC1_control> Exit (handle=0x8fa47088, retVal=0x0)
[DSP] @2,460,347tk: [+5 T:0x8fa47524] CN - NODE> returned from call(algHandle=0x8fa47088, msg=0x8fe06c80); messageId=0x00023816
@2,662,389us: [+0 T:0x41dc2490] CE - Engine_fwriteTrace> returning count [1144]
@2,662,549us: [+0 T:0x41dc2490] CV - VISA_call Completed: messageId=0x00023816, command=0x1, return(status=0)
@2,662,708us: [+5 T:0x41dc2490] CV - VISA_freeMsg(0x60f90, 0x425d0c80): Freeing message with messageId=0x00023816
@2,662,858us: [+0 T:0x41dc2490] ti.sdo.ce.video1.VIDENC1 - VIDENC1_control> Exit (handle=0x60f90, retVal=0x0)
@2,663,118us: [+2 T:0x41dc2490] ti.sdo.dmai - [BufTab] Allocating BufTab for 3 buffers
@2,663,518us: [+4 T:0x41dc2490] OM - Memory_contigAlloc> CMEM_alloc(691200) = 0x42640000.
@3,004,406us: [+4 T:0x41dc2490] OM - Memory_contigAlloc> CMEM_getPhys(0x42640000) = 0x87bc6000.
@3,004,620us: [+2 T:0x41dc2490] ti.sdo.dmai - [Buffer] Alloc Buffer of size 691200 at 0x42640000 (0x87bc6000 phys)
@3,004,961us: [+4 T:0x41dc2490] OM - Memory_contigAlloc> CMEM_alloc(691200) = 0x42f0b000.
@3,005,172us: [+4 T:0x41dc2490] OM - Memory_contigAlloc> CMEM_getPhys(0x42f0b000) = 0x87afb000.
@3,005,350us: [+2 T:0x41dc2490] ti.sdo.dmai - [Buffer] Alloc Buffer of size 691200 at 0x42f0b000 (0x87afb000 phys)
@3,005,740us: [+4 T:0x41dc2490] OM - Memory_contigAlloc> CMEM_alloc(691200) = 0x42fd6000.
@3,005,955us: [+4 T:0x41dc2490] OM - Memory_contigAlloc> CMEM_getPhys(0x42fd6000) = 0x87a30000.
@3,006,143us: [+2 T:0x41dc2490] ti.sdo.dmai - [Buffer] Alloc Buffer of size 691200 at 0x42fd6000 (0x87a30000 phys)
@3,006,750us: [+2 T:0x413af490] ti.sdo.dmai - [Smooth] Configuring smooth job of size 720x480
@3,007,175us: [+2 T:0x42f0a490] ti.sdo.dmai - [BufTab] Allocating BufTab for 2 buffers
@3,007,559us: [+4 T:0x42f0a490] OM - Memory_contigAlloc> CMEM_alloc(345600) = 0x430a1000.
@3,007,779us: [+4 T:0x42f0a490] OM - Memory_contigAlloc> CMEM_getPhys(0x430a1000) = 0x87965000.
@3,007,962us: [+2 T:0x42f0a490] ti.sdo.dmai - [Buffer] Alloc Buffer of size 345600 at 0x430a1000 (0x87965000 phys)
@3,008,325us: [+4 T:0x42f0a490] OM - Memory_contigAlloc> CMEM_alloc(345600) = 0x4316c000.
@3,008,569us: [+4 T:0x42f0a490] OM - Memory_contigAlloc> CMEM_getPhys(0x4316c000) = 0x8789a000.
@3,008,759us: [+2 T:0x42f0a490] ti.sdo.dmai - [Buffer] Alloc Buffer of size 345600 at 0x4316c000 (0x8789a000 phys)
Cap: Complete cap 00 frame at:86F00000 691200
Cap: Complete cap 01 frame at:85000000 691200
Video: Complete get 00 frame at:87BC6000 691200
Start Venc1, In:87BC6000 Out:87965000
@3,173,352us: [+0 T:0x41dc2490] ti.sdo.ce.video1.VIDENC1 - VIDENC1_process> Enter (handle=0x60f90, inBufs=0x41dc1c78, outBufs=0x41dc1c6c, inArgs=0x41dc1c5c, outArgs=0x41dc1be4)
@3,173,596us: [+5 T:0x41dc2490] CV - VISA_allocMsg> Allocating message for messageId=0x00023817
@3,218,856us: [+0 T:0x41dc2490] CV - VISA_call(visa=0x60f90, msg=0x425d0c80): messageId=0x00023817, command=0x0
[DSP] @3,757,042tk: [+5 T:0x8fa47524] CN - NODE> 0x8fa46fc8(h264enc#0) call(algHandle=0x8fa47088, msg=0x8fe06c80); messageId=0x00023817
[DSP] @3,757,120tk: [+0 T:0x8fa47524] OM - Memory_cacheInv> Enter(addr=0x87bc6000, sizeInBytes=691200)
[DSP] @3,757,915tk: [+0 T:0x8fa47524] OM - Memory_cacheInv> return
[DSP] @3,757,954tk: [+0 T:0x8fa47524] ti.sdo.ce.video1.VIDENC1 - VIDENC1_process> Enter (handle=0x8fa47088, inBufs=0x8fa4b824, outBufs=0x8fa4b8f4, inArgs=0x8fe06e04, outArgs=0x8fe06e10)
[DSP] @3,758,051tk: [+5 T:0x8fa47524] CV - VISA_enter(visa=0x8fa47088): algHandle = 0x8fa470b8
[DSP] @3,758,100tk: [+0 T:0x8fa47524] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Enter(alg=0x8fa470b8)
[DSP] @3,758,152tk: [+0 T:0x8fa47524] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Exit
[DSP] @3,800,734tk: [+5 T:0x8fa47524] CV - VISA_exit(visa=0x8fa47088): algHandle = 0x8fa470b8
[DSP] @3,800,814tk: [+0 T:0x8fa47524] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> Enter(alg=0x8fa470b8)
[DSP] @3,800,875tk: [+0 T:0x8fa47524] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> Exit
[DSP] @3,800,921tk: [+0 T:0x8fa47524] ti.sdo.ce.video1.VIDENC1 - VIDENC1_process> Exit (handle=0x8fa47088, retVal=0x0)
[DSP] @3,800,985tk: [+0 T:0x8fa47524] OM - Memory_cacheWb> Enter(addr=0x87965000, sizeInBytes=345600)
[DSP] @3,801,475tk: [+0 T:0x8fa47524] OM - Memory_cacheWb> return
[DSP] @3,801,511tk: [+5 T:0x8fa47524] CN - NODE> returned from call(algHandle=0x8fa47088, msg=0x8fe06c80); messageId=0x00023817
@3,241,800us: [+0 T:0x41dc2490] CE - Engine_fwriteTrace> returning count [1490]
@3,241,969us: [+0 T:0x41dc2490] CV - VISA_call Completed: messageId=0x00023817, command=0x0, return(status=0)
@3,242,148us: [+5 T:0x41dc2490] CV - VISA_freeMsg(0x60f90, 0x425d0c80): Freeing message with messageId=0x00023817
@3,242,302us: [+0 T:0x41dc2490] ti.sdo.ce.video1.VIDENC1 - VIDENC1_process> Exit (handle=0x60f90, retVal=0x0)
@3,242,440us: [+2 T:0x41dc2490] ti.sdo.dmai - [Venc1] VIDENC1_process() ret 0 generated 5473 bytes
Venc1 completed successful.
Write: Complete get 00 frame at:87965000 345600 Write End.
Cap: Complete cap 02 frame at:85100000 691200
Video: Complete get 01 frame at:87AFB000 691200
Start Venc1, In:87AFB000 Out:8789A000
@3,336,971us: [+0 T:0x41dc2490] ti.sdo.ce.video1.VIDENC1 - VIDENC1_process> Enter (handle=0x60f90, inBufs=0x41dc1c78, outBufs=0x41dc1c6c, inArgs=0x41dc1c5c, outArgs=0x41dc1be4)
@3,382,090us: [+5 T:0x41dc2490] CV - VISA_allocMsg> Allocating message for messageId=0x00023818
@3,382,324us: [+0 T:0x41dc2490] CV - VISA_call(visa=0x60f90, msg=0x425d0c80): messageId=0x00023818, command=0x0
[DSP] @4,136,320tk: [+5 T:0x8fa47524] CN - NODE> 0x8fa46fc8(h264enc#0) call(algHandle=0x8fa47088, msg=0x8fe06c80); messageId=0x00023818
[DSP] @4,136,401tk: [+0 T:0x8fa47524] OM - Memory_cacheInv> Enter(addr=0x87afb000, sizeInBytes=691200)
[DSP] @4,137,200tk: [+0 T:0x8fa47524] OM - Memory_cacheInv> return
[DSP] @4,137,239tk: [+0 T:0x8fa47524] ti.sdo.ce.video1.VIDENC1 - VIDENC1_process> Enter (handle=0x8fa47088, inBufs=0x8fa4b824, outBufs=0x8fa4b8f4, inArgs=0x8fe06e04, outArgs=0x8fe06e10)
[DSP] @4,137,336tk: [+5 T:0x8fa47524] CV - VISA_enter(visa=0x8fa47088): algHandle = 0x8fa470b8
[DSP] @4,137,389tk: [+0 T:0x8fa47524] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Enter(alg=0x8fa470b8)
[DSP] @4,137,444tk: [+0 T:0x8fa47524] ti.sdo.ce.alg.Algorithm - Algorithm_activate> Exit
[DSP] @4,193,231tk: [+5 T:0x8fa47524] CV - VISA_exit(visa=0x8fa47088): algHandle = 0x8fa470b8
[DSP] @4,193,320tk: [+0 T:0x8fa47524] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> Enter(alg=0x8fa470b8)
[DSP] @4,193,384tk: [+0 T:0x8fa47524] ti.sdo.ce.alg.Algorithm - Algorithm_deactivate> Exit
[DSP] @4,193,429tk: [+0 T:0x8fa47524] ti.sdo.ce.video1.VIDENC1 - VIDENC1_process> Exit (handle=0x8fa47088, retVal=0x0)
[DSP] @4,193,536tk: [+0 T:0x8fa47524] OM - Memory_cacheWb> Enter(addr=0x8789a000, sizeInBytes=345600)
[DSP] @4,194,019tk: [+0 T:0x8fa47524] OM - Memory_cacheWb> return
[DSP] @4,194,056tk: [+5 T:0x8fa47524] CN - NODE> returned from call(algHandle=0x8fa47088, msg=0x8fe06c80); messageId=0x00023818
@3,410,861us: [+0 T:0x41dc2490] CE - Engine_fwriteTrace> returning count [1490]
@3,411,030us: [+0 T:0x41dc2490] CV - VISA_call Completed: messageId=0x00023818, command=0x0, return(status=0)
@3,411,206us: [+5 T:0x41dc2490] CV - VISA_freeMsg(0x60f90, 0x425d0c80): Freeing message with messageId=0x00023818
@3,411,360us: [+0 T:0x41dc2490] ti.sdo.ce.video1.VIDENC1 - VIDENC1_process> Exit (handle=0x60f90, retVal=0x0)
@3,411,497us: [+2 T:0x41dc2490] ti.sdo.dmai - [Venc1] VIDENC1_process() ret 0 generated 10577 bytes
Venc1 completed successful.
Write: Complete get 01 frame at:8789A000 345600 Write End.
Cap: Complete cap 03 frame at:86F00000 691200
Video: Complete get 02 frame at:87A30000 691200
Start Venc1, In:87A30000 Out:87965000
@3,502,339us: [+0 T:0x41dc2490] ti.sdo.ce.video1.VIDENC1 - VIDENC1_process> Enter (handle=0x60f90, inBufs=0x41dc1c78, outBufs=0x41dc1c6c, inArgs=0x41dc1c5c, outArgs=0x41dc1be4)
@3,503,199us: [+5 T:0x41dc2490] CV - VISA_allocMsg> Allocating message for messageId=0x00023819
@3,503,397us: [+0 T:0x41dc2490] CV - VISA_call(visa=0x60f90, msg=0x425d0c80): messageId=0x00023819, command=0x0
Cap: Complete cap 04 frame at:85000000 691200
*** Halt here, below message come out after press Ctrl+C ***
Unable to handle kernel paging request at virtual address c796e010
@15,249,789us: [pgd = c5238000
+0 T:0x4001e950][c796e010] *pgd=80442011 OP - doCmd> Ent, *pte=00000000er (cmdId=3, pro, *ppte=00000000c=0x0)
@15,250,
007us: [+0 T:0x4Internal error: Oops: 7 [#1]
Modules linked in: dsplinkk cmemk davinci_capture mt9t001 tvp5146
CPU: 0
PC is at SYNC_WaitSEM+0x234/0x32c [dsplinkk]
LR is at flush_signals+0x64/0x90
pc : [<bf01d710>] lr : [<c0053ae4>] Tainted: GF
sp : c52d5e38 ip : c52d5e20 fp : c52d5e84
r10: ffffffff r9 : c0041680 r8 : c796e008
r7 : c52d5e44 r6 : c796e000 r5 : c52d4000 r4 : 00000002
r3 : 00000001 r2 : 00000000 r1 : c05466b4 r0 : c05466b4
Flags: nZCv IRQs on FIQs on Mode SVC_32 Segment user
Control: 5317F
Table: 85238000 DAC: 00000015
Process encode (pid: 930, stack limit = 0xc52d4258)
Stack: (0xc52d5e38 to 0xc52d6000)
5e20: c7a06c80 00008000
5e40: c52d5e5c 00000001 c046b2e0 c0041680 c796e008 c796e008 bf025784 00000000
5e60: 00008000 c52d5ef4 c796a000 ffffffff 80008051 bf0325c8 c52d5eb4 c52d5e88
5e80: bf021810 bf01d4ec bf021938 00006f0b 41dc1a94 00006f0b 0000000e c66e40c0
5ea0: c52d4000 00000000 c52d5ec4 c52d5eb8 bf018a7c bf021790 c52d5f24 c52d5ec8
5ec0: bf018400 bf018a7c 00000070 c52d5ef0 c52d5f24 c52d5ee0 c013f988 c013e108
5ee0: c52d5f04 c0142cfc c70ae80c 00000070 00000040 00000000 00008000 41dc1c5c
5f00: 00010001 ffffffff 00000000 00000004 c66e40c0 41dc1a94 c52d5f44 c52d5f28
5f20: c00a169c bf01809c c64ed358 c66e40c0 0000000e 41dc1a94 c52d5f74 c52d5f48
5f40: c00a197c c00a1630 c008c878 c00ba328 00000000 00000000 41dc1a94 00006f0b
5f60: c66e40c0 c52d4000 c52d5fa4 c52d5f78 c00a1a08 c00a16c8 00000000 00000001
5f80: 00000040 ffffffff 0005d5ac 41dc1b3c 00000036 c0031fa8 00000000 c52d5fa8
5fa0: c0031e00 c00a19b4 ffffffff 0005d5ac 0000000e 00006f0b 41dc1a94 0000000e
5fc0: ffffffff 0005d5ac 41dc1b3c 00000036 00010001 00001d68 0005df68 41dc1a7c
5fe0: 0005f314 41dc19a8 00031c10 4026650c 60000010 0000000e 00000000 00000000
Backtrace:
[<bf01d4dc>] (SYNC_WaitSEM+0x0/0x32c [dsplinkk]) from [<bf021810>] (LDRV_MSGQ_get+0x90/0xd0 [dsplinkk])
[<bf021780>] (LDRV_MSGQ_get+0x0/0xd0 [dsplinkk]) from [<bf018a7c>] (PMGR_MSGQ_get+0x10/0x14 [dsplinkk])
[<bf018a6c>] (PMGR_MSGQ_get+0x0/0x14 [dsplinkk]) from [<bf018400>] (DRV_Ioctl+0x374/0x9a0 [dsplinkk])
[<bf01808c>] (DRV_Ioctl+0x0/0x9a0 [dsplinkk]) from [<c00a169c>] (do_ioctl+0x7c/0x98)
r5 = 41DC1A94 r4 = C66E40C0
[<c00a1620>] (do_ioctl+0x0/0x98) from [<c00a197c>] (vfs_ioctl+0x2c4/0x2ec)
r6 = 41DC1A94 r5 = 0000000E r4 = C66E40C0
[<c00a16b8>] (vfs_ioctl+0x0/0x2ec) from [<c00a1a08>] (sys_ioctl+0x64/0x8c)
r9 = C52D4000 r8 = C66E40C0 r6 = 00006F0B r5 = 41DC1A94
r4 = 00000000
[<c00a19a4>] (sys_ioctl+0x0/0x8c) from [<c0031e00>] (ret_fast_syscall+0x0/0x2c)
r8 = C0031FA8 r7 = 00000036 r6 = 41DC1B3C r5 = 0005D5AC
r4 = FFFFFFFF
Code: e5834000 e5953004 e2833001 e5853004 (e5963010)
001e950] ti.sdo.<6>note: encode[930] exited with preempt_count 1
ce.osal.Sem - Enprev->state: 2 != TASK_RUNNING??
encode/930[CPU#0]: BUG in __schedule at kernel/sched.c:3826
tered Sem_post> sem[0x601c0]
@15,250,222us: [+0 T:0x40baf490] ti.sdo.ce.osal.Sem - Leaving Sem_pend> sem[0x601c0] status[0]
@15,250,385us: [+0 T:0x40baf490] OP - getCmd_d> Exit (result=3)
@15,250,519us: [+0 T:0x40baf490] ti.sdo.ce.osal.Sem - Entered Sem_post> sem[0x601d8]
@15,250,664us: [+0 T:0x40baf490] ti.sdo.ce.osal.Sem - Leaving Sem_post> sem[0x601d8]
@15,251,033us: [+0 T:0x4001e950] ti.sdo.ce.osal.Sem - Leaving Sem_post> sem[0x601c0]
@15,251,219us: [+0 T:0x4001e950] ti.sdo.ce.osal.Sem - Entered Sem_pend> sem[0x601d8] timeout[0xffffffff]
@15,251,369us: [+0 T:0x4001e950] ti.sdo.ce.osal.Sem - Leaving Sem_pend> sem[0x601d8] status[0]
@15,251,501us: [+0 T:0x4001e950] OP - doCmd> Exit (result=1)
@15,251,621us: [+0 T:0x4001e950] OT - Thread_delete> Enter (task=0x60210)
@15,251,811us: [+4 T:0x4001e950] OT - Thread_delete> pthread_cancel (0x3)
@15,251,967us: [+4 T:0x4001e950] OT - Thread_delete> pthread_join (0x0)
@15,252,111us: [+0 T:0x4001e950] OT - Thread_delete> Exit (task=0x60210)
@15,252,237us: [+0 T:0x4001e950] ti.sdo.ce.osal.Sem - Entered Sem_delete> sem[0x601c0]
@15,252,406us: [+0 T:0x4001e950] ti.sdo.ce.osal.Sem - Leaving Sem_delete>
@15,252,541us: [+0 T:0x4001e950] ti.sdo.ce.osal.Sem - Entered Sem_delete> sem[0x601d8]
@15,252,676us: [+0 T:0x4001e950] ti.sdo.ce.osal.Sem - Leaving Sem_delete>
root@10.9.11.17:/dm6446#
