Codec engine init and exit called more than once in an application

Jan Pohanka

Hello,

int main()
{
CERuntime_init();
CERuntime_exit();
CERuntime_init();
CERuntime_exit();
return 0;
}

Using codec engine 2.26.02.11 on dm365 with linux 2.6.37 the second call to CERuntime_init() as shown in example above causes segmentation fault. Is this normal behaviour? I'm using codec engine in standalone modules which has to do an init for their proper function, but if the module is called two times or two modules are used from an application the problem arises.

I'm not sure whether it has been fixed in some more recent version, but also I don't know if the 3x versions can be used on dm365.

The SEGFAULT is triggered in SemMP_pend() fucntion called from _DYNAMIC.

best regards
Jan

over 13 years ago

0 GAnthony over 13 years ago

TI__Intellectual 2790 points

Jan:

Can you please enable CE_DEBUG=3

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

and post the resulting log file?

Thanks,

- Gil

0 Jan Pohanka over 13 years ago in reply to GAnthony

Expert 1310 points

Hi Gil,

here is the log...

init1
@0,203,210us: [+4 T:0x40120000 S:0xbe9e4b84] OG - Global_init> This program was built with the following packages:
@0,204,965us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package gnu.targets.arm.rtsv5T (/home/honza/_dev/TI/eye03_sdk/dvsdk/xdctools_3_16_03_36/packages/gnu/targets/arm/rtsv5T/) [1, 0, 0, 0]
@0,205,748us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.linuxutils.vicp (/home/honza/_dev/TI/eye03_sdk/dvsdk/linuxutils_2_26_03_06/packages/ti/sdo/linuxutils/vicp/) [1, 0, 0]
@0,206,498us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.linuxutils.edma (/home/honza/_dev/TI/eye03_sdk/dvsdk/linuxutils_2_26_03_06/packages/ti/sdo/linuxutils/edma/) [1, 0, 0]
@0,207,430us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.linuxutils.cmem (/home/honza/_dev/TI/eye03_sdk/dvsdk/linuxutils_2_26_03_06/packages/ti/sdo/linuxutils/cmem/) [2, 2, 0]
@0,208,142us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.ce.global (/home/honza/_dev/TI/eye03_sdk/dvsdk/codec-engine_2_26_02_11/packages/ti/sdo/ce/global/) [1, 0, 0]
@0,208,886us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.xdais.dm (/home/honza/_dev/TI/eye03_sdk/dvsdk/xdais_6_26_01_03/packages/ti/xdais/dm/) [1, 0, 5]
@0,209,582us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.xdais (/home/honza/_dev/TI/eye03_sdk/dvsdk/xdais_6_26_01_03/packages/ti/xdais/) [1, 2.0, 1]
@0,210,318us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.ce.node (/home/honza/_dev/TI/eye03_sdk/dvsdk/codec-engine_2_26_02_11/packages/ti/sdo/ce/node/) [1, 0, 0]
@0,211,020us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.utils.trace (/home/honza/_dev/TI/eye03_sdk/dvsdk/framework-components_2_26_00_01/packages/ti/sdo/utils/trace/) [1, 0, 0]
@0,211,851us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.ce.utils.xdm (/home/honza/_dev/TI/eye03_sdk/dvsdk/codec-engine_2_26_02_11/packages/ti/sdo/ce/utils/xdm/) [1, 0, 2]
@0,212,554us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.fc.global (/home/honza/_dev/TI/eye03_sdk/dvsdk/framework-components_2_26_00_01/packages/ti/sdo/fc/global/) [1, 0, 0]
@0,212,826us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.fc.memutils (/home/honza/_dev/TI/eye03_sdk/dvsdk/framework-components_2_26_00_01/packages/ti/sdo/fc/memutils/) [1, 0, 0]
@0,212,986us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.fc.ires (/home/honza/_dev/TI/eye03_sdk/dvsdk/framework-components_2_26_00_01/packages/ti/sdo/fc/ires/) [1, 0, 0]
@0,213,119us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.fc.ires.addrspace (/home/honza/_dev/TI/eye03_sdk/dvsdk/framework-components_2_26_00_01/packages/ti/sdo/fc/ires/addrspace/) [1, 0, 0]
@0,213,250us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.fc.utils (/home/honza/_dev/TI/eye03_sdk/dvsdk/framework-components_2_26_00_01/packages/ti/sdo/fc/utils/) [1, 0, 2]
@0,213,380us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.fc.ires.hdvicp (/home/honza/_dev/TI/eye03_sdk/dvsdk/framework-components_2_26_00_01/packages/ti/sdo/fc/ires/hdvicp/) [1, 0, 1]
@0,213,504us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.fc.hdvicpsync (/home/honza/_dev/TI/eye03_sdk/dvsdk/framework-components_2_26_00_01/packages/ti/sdo/fc/hdvicpsync/) [1, 0, 0]
@0,213,627us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.fc.ires.memtcm (/home/honza/_dev/TI/eye03_sdk/dvsdk/framework-components_2_26_00_01/packages/ti/sdo/fc/ires/memtcm/) [1, 0, 0]
@0,213,754us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.codecs.h264enc (/home/honza/_dev/TI/eye03_sdk/dvsdk/codecs-dm365/packages/ti/sdo/codecs/h264enc/) [1, 0, 0]
@0,213,875us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.codecs.mpeg2enc (/home/honza/_dev/TI/eye03_sdk/dvsdk/codecs-dm365/packages/ti/sdo/codecs/mpeg2enc/) [1, 0, 0]
@0,214,000us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.codecs.mpeg4dec_hdvicp (/home/honza/_dev/TI/eye03_sdk/dvsdk/codecs-dm365/packages/ti/sdo/codecs/mpeg4dec_hdvicp/) [1, 0, 0]
@0,214,168us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.codecs.h264dec (/home/honza/_dev/TI/eye03_sdk/dvsdk/codecs-dm365/packages/ti/sdo/codecs/h264dec/) [1, 0, 0]
@0,214,310us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.codecs.mpeg2dec (/home/honza/_dev/TI/eye03_sdk/dvsdk/codecs-dm365/packages/ti/sdo/codecs/mpeg2dec/) [1, 0, 0]
@0,214,438us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.fc.dman3 (/home/honza/_dev/TI/eye03_sdk/dvsdk/framework-components_2_26_00_01/packages/ti/sdo/fc/dman3/) [1, 0, 4]
@0,214,565us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.fc.acpy3 (/home/honza/_dev/TI/eye03_sdk/dvsdk/framework-components_2_26_00_01/packages/ti/sdo/fc/acpy3/) [1, 0, 4]
@0,214,690us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package gnu.targets (/home/honza/_dev/TI/eye03_sdk/dvsdk/xdctools_3_16_03_36/packages/gnu/targets/) [1, 0, 1]
@0,214,815us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package gnu.targets.arm (/home/honza/_dev/TI/eye03_sdk/dvsdk/xdctools_3_16_03_36/packages/gnu/targets/arm/) [1, 0, 0, 0]
@0,214,940us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.fc.ires.nullresource (/home/honza/_dev/TI/eye03_sdk/dvsdk/framework-components_2_26_00_01/packages/ti/sdo/fc/ires/nullresource/) [1, 0, 0]
@0,215,154us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.fc.rman (/home/honza/_dev/TI/eye03_sdk/dvsdk/framework-components_2_26_00_01/packages/ti/sdo/fc/rman/) [2, 0, 0]
@0,215,298us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.fc.edma3 (/home/honza/_dev/TI/eye03_sdk/dvsdk/framework-components_2_26_00_01/packages/ti/sdo/fc/edma3/) [3, 0, 0]
@0,215,427us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.fc.ires.edma3chan (/home/honza/_dev/TI/eye03_sdk/dvsdk/framework-components_2_26_00_01/packages/ti/sdo/fc/ires/edma3chan/) [1, 0, 0]
@0,215,601us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.catalog.arm (/home/honza/_dev/TI/eye03_sdk/dvsdk/xdctools_3_16_03_36/packages/ti/catalog/arm/) [1, 0, 1, 0]
@0,215,734us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.catalog (/home/honza/_dev/TI/eye03_sdk/dvsdk/xdctools_3_16_03_36/packages/ti/catalog/) [1, 0, 0]
@0,215,883us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.platforms.evmDM365 (/home/honza/_dev/TI/eye03_sdk/dvsdk/xdctools_3_16_03_36/packages/ti/platforms/evmDM365/) [1, 0, 0]
@0,216,022us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.ce.osal (/home/honza/_dev/TI/eye03_sdk/dvsdk/codec-engine_2_26_02_11/packages/ti/sdo/ce/osal/) [2, 0, 2]
@0,216,154us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.ce.osal.linux (/home/honza/_dev/TI/eye03_sdk/dvsdk/codec-engine_2_26_02_11/packages/ti/sdo/ce/osal/linux/) [2, 0, 1]
@0,216,278us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.ce.ipc (/home/honza/_dev/TI/eye03_sdk/dvsdk/codec-engine_2_26_02_11/packages/ti/sdo/ce/ipc/) [2, 0, 1]
@0,216,402us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.ce.ipc.linux (/home/honza/_dev/TI/eye03_sdk/dvsdk/codec-engine_2_26_02_11/packages/ti/sdo/ce/ipc/linux/) [2, 0, 1]
@0,216,527us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.ce.alg (/home/honza/_dev/TI/eye03_sdk/dvsdk/codec-engine_2_26_02_11/packages/ti/sdo/ce/alg/) [1, 0, 1]
@0,216,650us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.fc.ires.grouputils (/home/honza/_dev/TI/eye03_sdk/dvsdk/framework-components_2_26_00_01/packages/ti/sdo/fc/ires/grouputils/) [1, 0, 0]
@0,216,777us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.fc.ires.vicp (/home/honza/_dev/TI/eye03_sdk/dvsdk/framework-components_2_26_00_01/packages/ti/sdo/fc/ires/vicp/) [1, 0, 1]
@0,217,993us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.fc.vicpsync (/home/honza/_dev/TI/eye03_sdk/dvsdk/framework-components_2_26_00_01/packages/ti/sdo/fc/vicpsync/) [1, 0, 0]
@0,218,165us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.ce (/home/honza/_dev/TI/eye03_sdk/dvsdk/codec-engine_2_26_02_11/packages/ti/sdo/ce/) [1, 0, 6]
@0,218,339us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.ce.video2 (/home/honza/_dev/TI/eye03_sdk/dvsdk/codec-engine_2_26_02_11/packages/ti/sdo/ce/video2/) [1, 0, 2]
@0,218,489us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.ce.video1 (/home/honza/_dev/TI/eye03_sdk/dvsdk/codec-engine_2_26_02_11/packages/ti/sdo/ce/video1/) [1, 0, 2]
@0,218,620us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.codecs.iprun (/home/honza/_dev/TI/eye03_sdk/dvsdk/codecs-dm365/packages/ti/sdo/codecs/iprun/) [1, 0, 0]
@0,218,748us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.codecs.iprun.ce (/home/honza/_dev/TI/eye03_sdk/dvsdk/codecs-dm365/packages/ti/sdo/codecs/iprun/ce/) [1, 0, 0]
@0,218,873us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.codecs.ktnf (/home/honza/_dev/TI/eye03_sdk/dvsdk/codecs-dm365/packages/ti/sdo/codecs/ktnf/) [1, 0, 0]
@0,218,999us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.codecs.ktnf.ce (/home/honza/_dev/TI/eye03_sdk/dvsdk/codecs-dm365/packages/ti/sdo/codecs/ktnf/ce/) [1, 0, 0]
@0,219,125us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.codecs.mpeg4enc (/home/honza/_dev/TI/eye03_sdk/dvsdk/codecs-dm365/packages/ti/sdo/codecs/mpeg4enc/) [1, 0, 0]
@0,219,248us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.codecs.mpeg4enc.ce (/home/honza/_dev/TI/eye03_sdk/dvsdk/codecs-dm365/packages/ti/sdo/codecs/mpeg4enc/ce/) [1, 0, 0]
@0,219,375us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.codecs.h264enc.ce (/home/honza/_dev/TI/eye03_sdk/dvsdk/codecs-dm365/packages/ti/sdo/codecs/h264enc/ce/) [1, 0, 0]
@0,219,500us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.codecs.mpeg2enc.ce (/home/honza/_dev/TI/eye03_sdk/dvsdk/codecs-dm365/packages/ti/sdo/codecs/mpeg2enc/ce/) [1, 0, 0]
@0,219,628us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.codecs.mpeg4dec_hdvicp.ce (/home/honza/_dev/TI/eye03_sdk/dvsdk/codecs-dm365/packages/ti/sdo/codecs/mpeg4dec_hdvicp/ce/) [1, 0, 0]
@0,219,801us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.codecs.h264dec.ce (/home/honza/_dev/TI/eye03_sdk/dvsdk/codecs-dm365/packages/ti/sdo/codecs/h264dec/ce/) [1, 0, 0]
@0,219,930us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.codecs.mpeg2dec.ce (/home/honza/_dev/TI/eye03_sdk/dvsdk/codecs-dm365/packages/ti/sdo/codecs/mpeg2dec/ce/) [1, 0, 0]
@0,220,057us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package mc_config (/home/honza/_dev/TI/eye03_sdk/eye03_video_server/mc_config/) []
@0,220,284us: [+0 T:0x40120000 S:0xbe9e4b8c] OG - Global_atexit> enter (fxn=0x356988)
@0,220,471us: [+0 T:0x40120000 S:0xbe9e4b8c] OG - Global_atexit> enter (fxn=0x355dd4)
@0,220,633us: [+0 T:0x40120000 S:0xbe9e4b54] OM - Memory_alloc> Enter(0x18)
@0,220,859us: [+0 T:0x40120000 S:0xbe9e4b54] OM - Memory_alloc> return (0x6370f0)
@0,221,041us: [+0 T:0x40120000 S:0xbe9e4b7c] OG - Global_atexit> enter (fxn=0x353f58)
@0,221,479us: [+0 T:0x40120000 S:0xbe9e4b7c] OG - Global_atexit> enter (fxn=0x355438)
@0,221,707us: [+0 T:0x40120000 S:0xbe9e4b8c] OG - Global_atexit> enter (fxn=0x352f08)
@0,221,973us: [+0 T:0x40120000 S:0xbe9e4b4c] ti.sdo.ce.osal.LockMP - LockMP_create> key: 0x524d414e count: 5339576 attrs: 0x51af70
@0,222,148us: [+0 T:0x40120000 S:0xbe9e4b2c] OM - Memory_alloc> Enter(0x10)
@0,222,302us: [+0 T:0x40120000 S:0xbe9e4b2c] OM - Memory_alloc> return (0x637200)
@0,222,477us: [+0 T:0x40120000 S:0xbe9e4b0c] ti.sdo.ce.osal.SemMP - SemMP_create> key: 0x524d414e count: 1
@0,222,629us: [+0 T:0x40120000 S:0xbe9e4aec] OM - Memory_alloc> Enter(0x4)
@0,222,796us: [+0 T:0x40120000 S:0xbe9e4aec] OM - Memory_alloc> return (0x637218)
@0,222,938us: [+0 T:0x40120000 S:0xbe9e4af4] ti.sdo.ce.osal.SemMP - Entered enterCS> key[0x4c41534f]
@0,223,260us: [+0 T:0x40120000 S:0xbe9e4af4] ti.sdo.ce.osal.SemMP - Leaving enterCS> id[0x1130000]
@0,223,630us: [+0 T:0x40120000 S:0xbe9e4b0c] ti.sdo.ce.osal.SemMP - SemMP_create> semId: 0x1128001 refCount: 1
@0,223,820us: [+0 T:0x40120000 S:0xbe9e4af4] ti.sdo.ce.osal.SemMP - Entered exitCS> id[0x1130000]
@0,224,053us: [+0 T:0x40120000 S:0xbe9e4b0c] ti.sdo.ce.osal.SemMP - Leaving exitCS
@0,224,202us: [+0 T:0x40120000 S:0xbe9e4b0c] ti.sdo.ce.osal.SemMP - Leaving SemMP_create> sem[0x637218]
@0,224,339us: [+0 T:0x40120000 S:0xbe9e4b4c] ti.sdo.ce.osal.LockMP - Leaving LockMP_create> lock[0x637200]
@0,224,567us: [+0 T:0x40120000 S:0xbe9e4b0c] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x637200]
@0,224,833us: [+0 T:0x40120000 S:0xbe9e4ae4] ti.sdo.ce.osal.SemMP - Entered SemMP_pend> sem[0x637218] timeout[0xffffffff]
@0,225,043us: [+0 T:0x40120000 S:0xbe9e4ae4] ti.sdo.ce.osal.SemMP - Leaving SemMP_pend> sem[0x637218] status[0]
@0,225,207us: [+0 T:0x40120000 S:0xbe9e4b24] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x637200]
@0,225,406us: [+0 T:0x40120000 S:0xbe9e4b0c] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x637200]
@0,225,556us: [+0 T:0x40120000 S:0xbe9e4aec] ti.sdo.ce.osal.SemMP - Entered SemMP_post> sem[0x637218]
@0,225,720us: [+0 T:0x40120000 S:0xbe9e4aec] ti.sdo.ce.osal.SemMP - Leaving SemMP_post> sem[0x637218]
@0,225,861us: [+0 T:0x40120000 S:0xbe9e4b24] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x637200]
@0,226,140us: [+0 T:0x40120000 S:0xbe9e4b0c] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x637200]
@0,226,315us: [+0 T:0x40120000 S:0xbe9e4ae4] ti.sdo.ce.osal.SemMP - Entered SemMP_pend> sem[0x637218] timeout[0xffffffff]
@0,226,487us: [+0 T:0x40120000 S:0xbe9e4ae4] ti.sdo.ce.osal.SemMP - Leaving SemMP_pend> sem[0x637218] status[0]
@0,226,680us: [+0 T:0x40120000 S:0xbe9e4b24] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x637200]
@0,227,066us: [+0 T:0x40120000 S:0xbe9e4ae4] ti.sdo.ce.osal.LockMP - LockMP_create> key: 0x4956444a count: 870324 attrs: 0x5179b8
@0,227,274us: [+0 T:0x40120000 S:0xbe9e4ac4] OM - Memory_alloc> Enter(0x10)
@0,227,435us: [+0 T:0x40120000 S:0xbe9e4ac4] OM - Memory_alloc> return (0x637228)
@0,227,573us: [+0 T:0x40120000 S:0xbe9e4aa4] ti.sdo.ce.osal.SemMP - SemMP_create> key: 0x4956444a count: 1
@0,227,707us: [+0 T:0x40120000 S:0xbe9e4a84] OM - Memory_alloc> Enter(0x4)
@0,227,847us: [+0 T:0x40120000 S:0xbe9e4a84] OM - Memory_alloc> return (0x637240)
@0,227,979us: [+0 T:0x40120000 S:0xbe9e4a8c] ti.sdo.ce.osal.SemMP - Entered enterCS> key[0x4c41534f]
@0,228,204us: [+0 T:0x40120000 S:0xbe9e4a8c] ti.sdo.ce.osal.SemMP - Leaving enterCS> id[0x1138000]
@0,228,443us: [+0 T:0x40120000 S:0xbe9e4aa4] ti.sdo.ce.osal.SemMP - SemMP_create> semId: 0x1140002 refCount: 1
@0,228,615us: [+0 T:0x40120000 S:0xbe9e4a8c] ti.sdo.ce.osal.SemMP - Entered exitCS> id[0x1138000]
@0,228,791us: [+0 T:0x40120000 S:0xbe9e4aa4] ti.sdo.ce.osal.SemMP - Leaving exitCS
@0,228,933us: [+0 T:0x40120000 S:0xbe9e4aa4] ti.sdo.ce.osal.SemMP - Leaving SemMP_create> sem[0x637240]
@0,229,068us: [+0 T:0x40120000 S:0xbe9e4ae4] ti.sdo.ce.osal.LockMP - Leaving LockMP_create> lock[0x637228]
@0,229,209us: [+0 T:0x40120000 S:0xbe9e4acc] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x637228]
@0,229,344us: [+0 T:0x40120000 S:0xbe9e4aa4] ti.sdo.ce.osal.SemMP - Entered SemMP_pend> sem[0x637240] timeout[0xffffffff]
@0,229,563us: [+0 T:0x40120000 S:0xbe9e4aa4] ti.sdo.ce.osal.SemMP - Leaving SemMP_pend> sem[0x637240] status[0]
@0,229,720us: [+0 T:0x40120000 S:0xbe9e4ae4] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x637228]
@0,230,341us: [+0 T:0x40120000 S:0xbe9e4acc] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x637228]
@0,230,552us: [+0 T:0x40120000 S:0xbe9e4aac] ti.sdo.ce.osal.SemMP - Entered SemMP_post> sem[0x637240]
@0,230,732us: [+0 T:0x40120000 S:0xbe9e4aac] ti.sdo.ce.osal.SemMP - Leaving SemMP_post> sem[0x637240]
@0,230,965us: [+0 T:0x40120000 S:0xbe9e4ae4] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x637228]
@0,231,236us: [+0 T:0x40120000 S:0xbe9e4b0c] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x637200]
@0,231,416us: [+0 T:0x40120000 S:0xbe9e4aec] ti.sdo.ce.osal.SemMP - Entered SemMP_post> sem[0x637218]
@0,231,587us: [+0 T:0x40120000 S:0xbe9e4aec] ti.sdo.ce.osal.SemMP - Leaving SemMP_post> sem[0x637218]
@0,231,735us: [+0 T:0x40120000 S:0xbe9e4b24] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x637200]
@0,231,948us: [+0 T:0x40120000 S:0xbe9e4b0c] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x637200]
@0,232,112us: [+0 T:0x40120000 S:0xbe9e4ae4] ti.sdo.ce.osal.SemMP - Entered SemMP_pend> sem[0x637218] timeout[0xffffffff]
@0,232,341us: [+0 T:0x40120000 S:0xbe9e4ae4] ti.sdo.ce.osal.SemMP - Leaving SemMP_pend> sem[0x637218] status[0]
@0,232,497us: [+0 T:0x40120000 S:0xbe9e4b24] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x637200]
@0,232,644us: [+0 T:0x40120000 S:0xbe9e4abc] ti.sdo.ce.osal.LockMP - LockMP_create> key: 0x50434958 count: 5339576 attrs: 0x636cc8
@0,232,792us: [+0 T:0x40120000 S:0xbe9e4a9c] OM - Memory_alloc> Enter(0x10)
@0,232,942us: [+0 T:0x40120000 S:0xbe9e4a9c] OM - Memory_alloc> return (0x637250)
@0,233,079us: [+0 T:0x40120000 S:0xbe9e4a7c] ti.sdo.ce.osal.SemMP - SemMP_create> key: 0x50434958 count: 1
@0,233,214us: [+0 T:0x40120000 S:0xbe9e4a5c] OM - Memory_alloc> Enter(0x4)
@0,233,353us: [+0 T:0x40120000 S:0xbe9e4a5c] OM - Memory_alloc> return (0x637268)
@0,233,486us: [+0 T:0x40120000 S:0xbe9e4a64] ti.sdo.ce.osal.SemMP - Entered enterCS> key[0x4c41534f]
@0,233,709us: [+0 T:0x40120000 S:0xbe9e4a64] ti.sdo.ce.osal.SemMP - Leaving enterCS> id[0x1148000]
@0,233,943us: [+0 T:0x40120000 S:0xbe9e4a7c] ti.sdo.ce.osal.SemMP - SemMP_create> semId: 0x1150003 refCount: 1
@0,234,115us: [+0 T:0x40120000 S:0xbe9e4a64] ti.sdo.ce.osal.SemMP - Entered exitCS> id[0x1148000]
@0,234,288us: [+0 T:0x40120000 S:0xbe9e4a7c] ti.sdo.ce.osal.SemMP - Leaving exitCS
@0,234,424us: [+0 T:0x40120000 S:0xbe9e4a7c] ti.sdo.ce.osal.SemMP - Leaving SemMP_create> sem[0x637268]
@0,234,554us: [+0 T:0x40120000 S:0xbe9e4abc] ti.sdo.ce.osal.LockMP - Leaving LockMP_create> lock[0x637250]
@0,234,689us: [+0 T:0x40120000 S:0xbe9e4aa4] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x637250]
@0,234,826us: [+0 T:0x40120000 S:0xbe9e4a7c] ti.sdo.ce.osal.SemMP - Entered SemMP_pend> sem[0x637268] timeout[0xffffffff]
@0,235,033us: [+0 T:0x40120000 S:0xbe9e4a7c] ti.sdo.ce.osal.SemMP - Leaving SemMP_pend> sem[0x637268] status[0]
@0,235,191us: [+0 T:0x40120000 S:0xbe9e4abc] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x637250]
@0,235,695us: [+0 T:0x40120000 S:0xbe9e4aa4] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x637250]
@0,235,902us: [+0 T:0x40120000 S:0xbe9e4a84] ti.sdo.ce.osal.SemMP - Entered SemMP_post> sem[0x637268]
@0,236,086us: [+0 T:0x40120000 S:0xbe9e4a84] ti.sdo.ce.osal.SemMP - Leaving SemMP_post> sem[0x637268]
@0,236,241us: [+0 T:0x40120000 S:0xbe9e4abc] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x637250]
@0,236,459us: [+0 T:0x40120000 S:0xbe9e4a6c] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x637250]
@0,236,614us: [+0 T:0x40120000 S:0xbe9e4a44] ti.sdo.ce.osal.SemMP - Entered SemMP_pend> sem[0x637268] timeout[0xffffffff]
@0,236,781us: [+0 T:0x40120000 S:0xbe9e4a44] ti.sdo.ce.osal.SemMP - Leaving SemMP_pend> sem[0x637268] status[0]
@0,237,153us: [+0 T:0x40120000 S:0xbe9e4a84] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x637250]
@0,237,677us: [+0 T:0x40120000 S:0xbe9e4a6c] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x637250]
@0,237,929us: [+0 T:0x40120000 S:0xbe9e4a4c] ti.sdo.ce.osal.SemMP - Entered SemMP_post> sem[0x637268]
@0,238,111us: [+0 T:0x40120000 S:0xbe9e4a4c] ti.sdo.ce.osal.SemMP - Leaving SemMP_post> sem[0x637268]
@0,238,261us: [+0 T:0x40120000 S:0xbe9e4a84] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x637250]
@0,238,455us: [+0 T:0x40120000 S:0xbe9e4b0c] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x637200]
@0,238,627us: [+0 T:0x40120000 S:0xbe9e4aec] ti.sdo.ce.osal.SemMP - Entered SemMP_post> sem[0x637218]
@0,238,797us: [+0 T:0x40120000 S:0xbe9e4aec] ti.sdo.ce.osal.SemMP - Leaving SemMP_post> sem[0x637218]
@0,238,947us: [+0 T:0x40120000 S:0xbe9e4b24] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x637200]
@0,239,132us: [+0 T:0x40120000 S:0xbe9e4b0c] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x637200]
@0,239,289us: [+0 T:0x40120000 S:0xbe9e4ae4] ti.sdo.ce.osal.SemMP - Entered SemMP_pend> sem[0x637218] timeout[0xffffffff]
@0,239,458us: [+0 T:0x40120000 S:0xbe9e4ae4] ti.sdo.ce.osal.SemMP - Leaving SemMP_pend> sem[0x637218] status[0]
@0,239,602us: [+0 T:0x40120000 S:0xbe9e4b24] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x637200]
@0,239,824us: [+0 T:0x40120000 S:0xbe9e4b0c] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x637200]
@0,239,990us: [+0 T:0x40120000 S:0xbe9e4aec] ti.sdo.ce.osal.SemMP - Entered SemMP_post> sem[0x637218]
@0,240,157us: [+0 T:0x40120000 S:0xbe9e4aec] ti.sdo.ce.osal.SemMP - Leaving SemMP_post> sem[0x637218]
@0,240,304us: [+0 T:0x40120000 S:0xbe9e4b24] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x637200]
@0,240,534us: [+0 T:0x40120000 S:0xbe9e4b0c] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x637200]
@0,240,707us: [+0 T:0x40120000 S:0xbe9e4ae4] ti.sdo.ce.osal.SemMP - Entered SemMP_pend> sem[0x637218] timeout[0xffffffff]
@0,240,886us: [+0 T:0x40120000 S:0xbe9e4ae4] ti.sdo.ce.osal.SemMP - Leaving SemMP_pend> sem[0x637218] status[0]
@0,241,032us: [+0 T:0x40120000 S:0xbe9e4b24] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x637200]
@0,241,186us: [+0 T:0x40120000 S:0xbe9e4b0c] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x637200]
@0,241,326us: [+0 T:0x40120000 S:0xbe9e4aec] ti.sdo.ce.osal.SemMP - Entered SemMP_post> sem[0x637218]
@0,241,481us: [+0 T:0x40120000 S:0xbe9e4aec] ti.sdo.ce.osal.SemMP - Leaving SemMP_post> sem[0x637218]
@0,241,620us: [+0 T:0x40120000 S:0xbe9e4b24] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x637200]
@0,241,764us: [+0 T:0x40120000 S:0xbe9e4b0c] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x637200]
@0,241,945us: [+0 T:0x40120000 S:0xbe9e4ae4] ti.sdo.ce.osal.SemMP - Entered SemMP_pend> sem[0x637218] timeout[0xffffffff]
@0,242,122us: [+0 T:0x40120000 S:0xbe9e4ae4] ti.sdo.ce.osal.SemMP - Leaving SemMP_pend> sem[0x637218] status[0]
@0,242,269us: [+0 T:0x40120000 S:0xbe9e4b24] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x637200]
@0,242,423us: [+0 T:0x40120000 S:0xbe9e4acc] ti.sdo.ce.osal.LockMP - LockMP_create> key: 0x4d43544f count: 6516004 attrs: 0x5179b8
@0,242,569us: [+0 T:0x40120000 S:0xbe9e4aac] OM - Memory_alloc> Enter(0x10)
@0,242,718us: [+0 T:0x40120000 S:0xbe9e4aac] OM - Memory_alloc> return (0x637278)
@0,242,851us: [+0 T:0x40120000 S:0xbe9e4a8c] ti.sdo.ce.osal.SemMP - SemMP_create> key: 0x4d43544f count: 1
@0,242,984us: [+0 T:0x40120000 S:0xbe9e4a6c] OM - Memory_alloc> Enter(0x4)
@0,243,120us: [+0 T:0x40120000 S:0xbe9e4a6c] OM - Memory_alloc> return (0x637290)
@0,243,288us: [+0 T:0x40120000 S:0xbe9e4a74] ti.sdo.ce.osal.SemMP - Entered enterCS> key[0x4c41534f]
@0,243,476us: [+0 T:0x40120000 S:0xbe9e4a74] ti.sdo.ce.osal.SemMP - Leaving enterCS> id[0x1158000]
@0,243,707us: [+0 T:0x40120000 S:0xbe9e4a8c] ti.sdo.ce.osal.SemMP - SemMP_create> semId: 0x1160004 refCount: 1
@0,243,876us: [+0 T:0x40120000 S:0xbe9e4a74] ti.sdo.ce.osal.SemMP - Entered exitCS> id[0x1158000]
@0,244,049us: [+0 T:0x40120000 S:0xbe9e4a8c] ti.sdo.ce.osal.SemMP - Leaving exitCS
@0,244,192us: [+0 T:0x40120000 S:0xbe9e4a8c] ti.sdo.ce.osal.SemMP - Leaving SemMP_create> sem[0x637290]
@0,244,328us: [+0 T:0x40120000 S:0xbe9e4acc] ti.sdo.ce.osal.LockMP - Leaving LockMP_create> lock[0x637278]
@0,244,463us: [+0 T:0x40120000 S:0xbe9e4ab4] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x637278]
@0,244,593us: [+0 T:0x40120000 S:0xbe9e4a8c] ti.sdo.ce.osal.SemMP - Entered SemMP_pend> sem[0x637290] timeout[0xffffffff]
@0,244,815us: [+0 T:0x40120000 S:0xbe9e4a8c] ti.sdo.ce.osal.SemMP - Leaving SemMP_pend> sem[0x637290] status[0]
@0,244,986us: [+0 T:0x40120000 S:0xbe9e4acc] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x637278]
@0,245,476us: [+0 T:0x40120000 S:0xbe9e4ab4] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x637278]
@0,245,684us: [+0 T:0x40120000 S:0xbe9e4a94] ti.sdo.ce.osal.SemMP - Entered SemMP_post> sem[0x637290]
@0,245,865us: [+0 T:0x40120000 S:0xbe9e4a94] ti.sdo.ce.osal.SemMP - Leaving SemMP_post> sem[0x637290]
@0,246,055us: [+0 T:0x40120000 S:0xbe9e4acc] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x637278]
@0,246,513us: [+0 T:0x40120000 S:0xbe9e4a7c] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x637278]
@0,246,733us: [+0 T:0x40120000 S:0xbe9e4a54] ti.sdo.ce.osal.SemMP - Entered SemMP_pend> sem[0x637290] timeout[0xffffffff]
@0,247,123us: [+0 T:0x40120000 S:0xbe9e4a54] ti.sdo.ce.osal.SemMP - Leaving SemMP_pend> sem[0x637290] status[0]
@0,247,302us: [+0 T:0x40120000 S:0xbe9e4a94] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x637278]
@0,247,893us: [+0 T:0x40120000 S:0xbe9e4a7c] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x637278]
@0,248,101us: [+0 T:0x40120000 S:0xbe9e4a5c] ti.sdo.ce.osal.SemMP - Entered SemMP_post> sem[0x637290]
@0,248,283us: [+0 T:0x40120000 S:0xbe9e4a5c] ti.sdo.ce.osal.SemMP - Leaving SemMP_post> sem[0x637290]
@0,248,434us: [+0 T:0x40120000 S:0xbe9e4a94] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x637278]
@0,248,585us: [+0 T:0x40120000 S:0xbe9e4b0c] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x637200]
@0,248,728us: [+0 T:0x40120000 S:0xbe9e4aec] ti.sdo.ce.osal.SemMP - Entered SemMP_post> sem[0x637218]
@0,248,941us: [+0 T:0x40120000 S:0xbe9e4aec] ti.sdo.ce.osal.SemMP - Leaving SemMP_post> sem[0x637218]
@0,249,090us: [+0 T:0x40120000 S:0xbe9e4b24] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x637200]
@0,249,237us: [+0 T:0x40120000 S:0xbe9e4b0c] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x637200]
@0,249,373us: [+0 T:0x40120000 S:0xbe9e4ae4] ti.sdo.ce.osal.SemMP - Entered SemMP_pend> sem[0x637218] timeout[0xffffffff]
@0,249,534us: [+0 T:0x40120000 S:0xbe9e4ae4] ti.sdo.ce.osal.SemMP - Leaving SemMP_pend> sem[0x637218] status[0]
@0,249,682us: [+0 T:0x40120000 S:0xbe9e4b24] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x637200]
@0,249,848us: [+0 T:0x40120000 S:0xbe9e4afc] ti.sdo.ce.osal.Sem - Sem_create> count: 1
@0,249,987us: [+0 T:0x40120000 S:0xbe9e4adc] OM - Memory_alloc> Enter(0x14)
@0,250,132us: [+0 T:0x40120000 S:0xbe9e4adc] OM - Memory_alloc> return (0x6372a0)
@0,250,444us: [+0 T:0x40120000 S:0xbe9e4afc] ti.sdo.ce.osal.Sem - Leaving Sem_create> sem[0x6372a0]
@0,250,630us: [+0 T:0x40120000 S:0xbe9e4b0c] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x637200]
@0,250,780us: [+0 T:0x40120000 S:0xbe9e4aec] ti.sdo.ce.osal.SemMP - Entered SemMP_post> sem[0x637218]
@0,250,946us: [+0 T:0x40120000 S:0xbe9e4aec] ti.sdo.ce.osal.SemMP - Leaving SemMP_post> sem[0x637218]
@0,251,086us: [+0 T:0x40120000 S:0xbe9e4b24] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x637200]
@0,251,325us: [+0 T:0x40120000 S:0xbe9e4b74] ti.sdo.ce.alg - ALG_init> Enter
@0,251,484us: [+0 T:0x40120000 S:0xbe9e4b34] ti.sdo.ce.osal.SemMP - SemMP_create> key: 0x4f474c42 count: 1
@0,251,681us: [+0 T:0x40120000 S:0xbe9e4b14] OM - Memory_alloc> Enter(0x4)
@0,251,837us: [+0 T:0x40120000 S:0xbe9e4b14] OM - Memory_alloc> return (0x6372e0)
@0,251,974us: [+0 T:0x40120000 S:0xbe9e4b1c] ti.sdo.ce.osal.SemMP - Entered enterCS> key[0x4c41534f]
@0,252,148us: [+0 T:0x40120000 S:0xbe9e4b1c] ti.sdo.ce.osal.SemMP - Leaving enterCS> id[0x1168000]
@0,252,386us: [+0 T:0x40120000 S:0xbe9e4b34] ti.sdo.ce.osal.SemMP - SemMP_create> semId: 0x1170005 refCount: 1
@0,252,554us: [+0 T:0x40120000 S:0xbe9e4b1c] ti.sdo.ce.osal.SemMP - Entered exitCS> id[0x1168000]
@0,252,728us: [+0 T:0x40120000 S:0xbe9e4b34] ti.sdo.ce.osal.SemMP - Leaving exitCS
@0,252,868us: [+0 T:0x40120000 S:0xbe9e4b34] ti.sdo.ce.osal.SemMP - Leaving SemMP_create> sem[0x6372e0]
@0,253,055us: [+0 T:0x40120000 S:0xbe9e4b34] ti.sdo.ce.osal.SemMP - SemMP_create> key: 0x4f474c43 count: 1
@0,253,222us: [+0 T:0x40120000 S:0xbe9e4b14] OM - Memory_alloc> Enter(0x4)
@0,253,377us: [+0 T:0x40120000 S:0xbe9e4b14] OM - Memory_alloc> return (0x6372f0)
@0,253,512us: [+0 T:0x40120000 S:0xbe9e4b1c] ti.sdo.ce.osal.SemMP - Entered enterCS> key[0x4c41534f]
@0,253,693us: [+0 T:0x40120000 S:0xbe9e4b1c] ti.sdo.ce.osal.SemMP - Leaving enterCS> id[0x1178000]
@0,253,927us: [+0 T:0x40120000 S:0xbe9e4b34] ti.sdo.ce.osal.SemMP - SemMP_create> semId: 0x1180006 refCount: 1
@0,254,091us: [+0 T:0x40120000 S:0xbe9e4b1c] ti.sdo.ce.osal.SemMP - Entered exitCS> id[0x1178000]
@0,254,263us: [+0 T:0x40120000 S:0xbe9e4b34] ti.sdo.ce.osal.SemMP - Leaving exitCS
@0,254,449us: [+0 T:0x40120000 S:0xbe9e4b34] ti.sdo.ce.osal.SemMP - Leaving SemMP_create> sem[0x6372f0]
@0,254,602us: [+0 T:0x40120000 S:0xbe9e4b94] ti.sdo.ce.alg - ALG_init> Exit
@0,254,727us: [+0 T:0x40120000 S:0xbe9e4b8c] OG - Global_atexit> enter (fxn=0x3508a4)
@0,254,894us: [+0 T:0x40120000 S:0xbe9e4b5c] OM - Memory_alloc> Enter(0x18)
@0,255,044us: [+0 T:0x40120000 S:0xbe9e4b5c] OM - Memory_alloc> return (0x637330)
@0,255,186us: [+0 T:0x40120000 S:0xbe9e4b8c] OG - Global_atexit> enter (fxn=0x355928)
@0,255,412us: [+6 T:0x40120000 S:0xbe9e4b7c] CE - Engine_init> CE debugging on (CE_DEBUG=3; allowed CE_DEBUG levels: 1=min, 2=good, 3=max)
@0,255,577us: [+0 T:0x40120000 S:0xbe9e4b6c] OG - Global_atexit> enter (fxn=0x34ad48)
@0,255,761us: [+0 T:0x40120000 S:0xbe9e4b44] OM - Memory_alloc> Enter(0x18)
@0,255,919us: [+0 T:0x40120000 S:0xbe9e4b44] OM - Memory_alloc> return (0x637370)
@0,256,059us: [+0 T:0x40120000 S:0xbe9e4b44] OM - Memory_alloc> Enter(0x18)
@0,256,205us: [+0 T:0x40120000 S:0xbe9e4b44] OM - Memory_alloc> return (0x637390)
@0,256,340us: [+0 T:0x40120000 S:0xbe9e4b44] OM - Memory_alloc> Enter(0x18)
@0,256,479us: [+0 T:0x40120000 S:0xbe9e4b44] OM - Memory_alloc> return (0x6373b0)
@0,256,615us: [+0 T:0x40120000 S:0xbe9e4b6c] ti.sdo.ce.alg.Algorithm - Algorithm_addGroup> Enter(groupId=2)
@0,256,746us: [+0 T:0x40120000 S:0xbe9e4b4c] ti.sdo.ce.alg - ALG_addGroup> Enter. groupId = 2
@0,257,061us: [+0 T:0x40120000 S:0xbe9e4b4c] ti.sdo.ce.alg - ALG_addGroup> Exit. status = 1
@0,257,291us: [+0 T:0x40120000 S:0xbe9e4b6c] ti.sdo.ce.alg.Algorithm - Algorithm_addGroup> Exit (status = 1)
@0,257,434us: [+0 T:0x40120000 S:0xbe9e4b6c] ti.sdo.ce.alg.Algorithm - Algorithm_addGroup> Enter(groupId=2)
@0,257,569us: [+0 T:0x40120000 S:0xbe9e4b4c] ti.sdo.ce.alg - ALG_addGroup> Enter. groupId = 2
@0,257,694us: [+0 T:0x40120000 S:0xbe9e4b4c] ti.sdo.ce.alg - ALG_addGroup> Exit. status = 1
@0,257,820us: [+0 T:0x40120000 S:0xbe9e4b6c] ti.sdo.ce.alg.Algorithm - Algorithm_addGroup> Exit (status = 1)
@0,257,944us: [+0 T:0x40120000 S:0xbe9e4b6c] ti.sdo.ce.alg.Algorithm - Algorithm_addGroup> Enter(groupId=1)
@0,258,070us: [+0 T:0x40120000 S:0xbe9e4b4c] ti.sdo.ce.alg - ALG_addGroup> Enter. groupId = 1
@0,258,195us: [+0 T:0x40120000 S:0xbe9e4b4c] ti.sdo.ce.alg - ALG_addGroup> Exit. status = 1
@0,258,317us: [+0 T:0x40120000 S:0xbe9e4b6c] ti.sdo.ce.alg.Algorithm - Algorithm_addGroup> Exit (status = 1)
@0,258,438us: [+0 T:0x40120000 S:0xbe9e4b6c] ti.sdo.ce.alg.Algorithm - Algorithm_addGroup> Enter(groupId=1)
@0,258,614us: [+0 T:0x40120000 S:0xbe9e4b4c] ti.sdo.ce.alg - ALG_addGroup> Enter. groupId = 1
@0,258,748us: [+0 T:0x40120000 S:0xbe9e4b4c] ti.sdo.ce.alg - ALG_addGroup> Exit. status = 1
@0,258,872us: [+0 T:0x40120000 S:0xbe9e4b6c] ti.sdo.ce.alg.Algorithm - Algorithm_addGroup> Exit (status = 1)
@0,258,999us: [+0 T:0x40120000 S:0xbe9e4b6c] ti.sdo.ce.alg.Algorithm - Algorithm_addGroup> Enter(groupId=1)
@0,259,124us: [+0 T:0x40120000 S:0xbe9e4b4c] ti.sdo.ce.alg - ALG_addGroup> Enter. groupId = 1
@0,259,247us: [+0 T:0x40120000 S:0xbe9e4b4c] ti.sdo.ce.alg - ALG_addGroup> Exit. status = 1
@0,259,371us: [+0 T:0x40120000 S:0xbe9e4b6c] ti.sdo.ce.alg.Algorithm - Algorithm_addGroup> Exit (status = 1)
@0,259,497us: [+0 T:0x40120000 S:0xbe9e4b6c] ti.sdo.ce.alg.Algorithm - Algorithm_addGroup> Enter(groupId=1)
@0,259,620us: [+0 T:0x40120000 S:0xbe9e4b4c] ti.sdo.ce.alg - ALG_addGroup> Enter. groupId = 1
@0,259,773us: [+0 T:0x40120000 S:0xbe9e4b4c] ti.sdo.ce.alg - ALG_addGroup> Exit. status = 1
@0,259,949us: [+0 T:0x40120000 S:0xbe9e4b6c] ti.sdo.ce.alg.Algorithm - Algorithm_addGroup> Exit (status = 1)
@0,260,092us: [+0 T:0x40120000 S:0xbe9e4b6c] ti.sdo.ce.alg.Algorithm - Algorithm_addGroup> Enter(groupId=1)
@0,260,226us: [+0 T:0x40120000 S:0xbe9e4b4c] ti.sdo.ce.alg - ALG_addGroup> Enter. groupId = 1
@0,260,351us: [+0 T:0x40120000 S:0xbe9e4b4c] ti.sdo.ce.alg - ALG_addGroup> Exit. status = 1
@0,260,474us: [+0 T:0x40120000 S:0xbe9e4b6c] ti.sdo.ce.alg.Algorithm - Algorithm_addGroup> Exit (status = 1)
@0,260,703us: [+0 T:0x40120000 S:0xbe9e4b9c] CS - Server_init()
@0,260,853us: [+0 T:0x40120000 S:0xbe9e4b9c] CS - Server_init> Global_useLinkArbiter = 0
@0,260,988us: [+0 T:0x40120000 S:0xbe9e4b9c] OG - Global_atexit> enter (fxn=0x3485b8)
exit1
@0,261,163us: [+0 T:0x40120000 S:0xbe9e4b9c] OG - Global_exit> enter
@0,261,319us: [+2 T:0x40120000 S:0xbe9e4b9c] OG - Global_exit> calling function *0x3485b8()...
@0,261,643us: [+2 T:0x40120000 S:0xbe9e4b9c] OG - Global_exit> calling function *0x34ad48()...
@0,261,826us: [+0 T:0x40120000 S:0xbe9e4b74] ti.sdo.ce.alg.Algorithm - Algorithm_removeGroup> Enter(groupId=2)
@0,261,973us: [+0 T:0x40120000 S:0xbe9e4b5c] ti.sdo.ce.alg - ALG_removeGroup> Enter. groupId = 2
@0,262,103us: [+0 T:0x40120000 S:0xbe9e4b74] ti.sdo.ce.alg - ALG_removeGroup> Exit.
@0,262,223us: [+0 T:0x40120000 S:0xbe9e4b84] ti.sdo.ce.alg.Algorithm - Algorithm_removeGroup> Exit
@0,262,341us: [+0 T:0x40120000 S:0xbe9e4b74] ti.sdo.ce.alg.Algorithm - Algorithm_removeGroup> Enter(groupId=2)
@0,262,466us: [+0 T:0x40120000 S:0xbe9e4b5c] ti.sdo.ce.alg - ALG_removeGroup> Enter. groupId = 2
@0,262,588us: [+0 T:0x40120000 S:0xbe9e4b74] ti.sdo.ce.alg - ALG_removeGroup> Exit.
@0,262,754us: [+0 T:0x40120000 S:0xbe9e4b84] ti.sdo.ce.alg.Algorithm - Algorithm_removeGroup> Exit
@0,262,880us: [+0 T:0x40120000 S:0xbe9e4b74] ti.sdo.ce.alg.Algorithm - Algorithm_removeGroup> Enter(groupId=1)
@0,263,008us: [+0 T:0x40120000 S:0xbe9e4b5c] ti.sdo.ce.alg - ALG_removeGroup> Enter. groupId = 1
@0,263,134us: [+0 T:0x40120000 S:0xbe9e4b74] ti.sdo.ce.alg - ALG_removeGroup> Exit.
@0,263,250us: [+0 T:0x40120000 S:0xbe9e4b84] ti.sdo.ce.alg.Algorithm - Algorithm_removeGroup> Exit
@0,263,366us: [+0 T:0x40120000 S:0xbe9e4b74] ti.sdo.ce.alg.Algorithm - Algorithm_removeGroup> Enter(groupId=1)
@0,263,487us: [+0 T:0x40120000 S:0xbe9e4b5c] ti.sdo.ce.alg - ALG_removeGroup> Enter. groupId = 1
@0,263,609us: [+0 T:0x40120000 S:0xbe9e4b74] ti.sdo.ce.alg - ALG_removeGroup> Exit.
@0,263,726us: [+0 T:0x40120000 S:0xbe9e4b84] ti.sdo.ce.alg.Algorithm - Algorithm_removeGroup> Exit
@0,263,842us: [+0 T:0x40120000 S:0xbe9e4b74] ti.sdo.ce.alg.Algorithm - Algorithm_removeGroup> Enter(groupId=1)
@0,263,963us: [+0 T:0x40120000 S:0xbe9e4b5c] ti.sdo.ce.alg - ALG_removeGroup> Enter. groupId = 1
@0,264,128us: [+0 T:0x40120000 S:0xbe9e4b74] ti.sdo.ce.alg - ALG_removeGroup> Exit.
@0,264,255us: [+0 T:0x40120000 S:0xbe9e4b84] ti.sdo.ce.alg.Algorithm - Algorithm_removeGroup> Exit
@0,264,375us: [+0 T:0x40120000 S:0xbe9e4b74] ti.sdo.ce.alg.Algorithm - Algorithm_removeGroup> Enter(groupId=1)
@0,264,502us: [+0 T:0x40120000 S:0xbe9e4b5c] ti.sdo.ce.alg - ALG_removeGroup> Enter. groupId = 1
@0,264,624us: [+0 T:0x40120000 S:0xbe9e4b74] ti.sdo.ce.alg - ALG_removeGroup> Exit.
@0,264,738us: [+0 T:0x40120000 S:0xbe9e4b84] ti.sdo.ce.alg.Algorithm - Algorithm_removeGroup> Exit
@0,264,855us: [+0 T:0x40120000 S:0xbe9e4b74] ti.sdo.ce.alg.Algorithm - Algorithm_removeGroup> Enter(groupId=1)
@0,264,976us: [+0 T:0x40120000 S:0xbe9e4b5c] ti.sdo.ce.alg - ALG_removeGroup> Enter. groupId = 1
@0,265,093us: [+0 T:0x40120000 S:0xbe9e4b74] ti.sdo.ce.alg - ALG_removeGroup> Exit.
@0,265,208us: [+0 T:0x40120000 S:0xbe9e4b84] ti.sdo.ce.alg.Algorithm - Algorithm_removeGroup> Exit
@0,265,379us: [+0 T:0x40120000 S:0xbe9e4b64] OM - Memory_free> Enter(0x637370, 0x18)
@0,265,590us: [+0 T:0x40120000 S:0xbe9e4b64] OM - Memory_free> return (0x1)
@0,265,750us: [+0 T:0x40120000 S:0xbe9e4b64] OM - Memory_free> Enter(0x637390, 0x18)
@0,265,899us: [+0 T:0x40120000 S:0xbe9e4b64] OM - Memory_free> return (0x1)
@0,266,032us: [+0 T:0x40120000 S:0xbe9e4b64] OM - Memory_free> Enter(0x6373b0, 0x18)
@0,266,169us: [+0 T:0x40120000 S:0xbe9e4b64] OM - Memory_free> return (0x1)
@0,266,302us: [+2 T:0x40120000 S:0xbe9e4b9c] OG - Global_exit> calling function *0x355928()...
@0,266,436us: [+2 T:0x40120000 S:0xbe9e4b9c] OG - Global_exit> calling function *0x3508a4()...
@0,266,568us: [+0 T:0x40120000 S:0xbe9e4b44] ti.sdo.ce.osal.SemMP - Entered SemMP_delete> sem[0x6372e0]
@0,266,707us: [+0 T:0x40120000 S:0xbe9e4b2c] ti.sdo.ce.osal.SemMP - Entered enterCS> key[0x4c41534f]
@0,267,080us: [+0 T:0x40120000 S:0xbe9e4b2c] ti.sdo.ce.osal.SemMP - Leaving enterCS> id[0x1188000]
@0,267,303us: [+0 T:0x40120000 S:0xbe9e4b44] ti.sdo.ce.osal.SemMP - SemMP_delete> sem: 0x1170005, ref count: 1
@0,267,527us: [+1 T:0x40120000 S:0xbe9e4b44] ti.sdo.ce.osal.SemMP - SemMP_delete> Deleted semaphore: 0x1170005
@0,267,690us: [+0 T:0x40120000 S:0xbe9e4b2c] ti.sdo.ce.osal.SemMP - Entered exitCS> id[0x1188000]
@0,267,849us: [+0 T:0x40120000 S:0xbe9e4b44] ti.sdo.ce.osal.SemMP - Leaving exitCS
@0,267,987us: [+0 T:0x40120000 S:0xbe9e4b24] OM - Memory_free> Enter(0x6372e0, 0x4)
@0,268,133us: [+0 T:0x40120000 S:0xbe9e4b24] OM - Memory_free> return (0x1)
@0,268,314us: [+0 T:0x40120000 S:0xbe9e4b44] ti.sdo.ce.osal.SemMP - Leaving SemMP_delete>
@0,268,448us: [+0 T:0x40120000 S:0xbe9e4b44] ti.sdo.ce.osal.SemMP - Entered SemMP_delete> sem[0x6372f0]
@0,268,582us: [+0 T:0x40120000 S:0xbe9e4b2c] ti.sdo.ce.osal.SemMP - Entered enterCS> key[0x4c41534f]
@0,268,752us: [+0 T:0x40120000 S:0xbe9e4b2c] ti.sdo.ce.osal.SemMP - Leaving enterCS> id[0x1190000]
@0,268,916us: [+0 T:0x40120000 S:0xbe9e4b44] ti.sdo.ce.osal.SemMP - SemMP_delete> sem: 0x1180006, ref count: 1
@0,269,122us: [+1 T:0x40120000 S:0xbe9e4b44] ti.sdo.ce.osal.SemMP - SemMP_delete> Deleted semaphore: 0x1180006
@0,269,278us: [+0 T:0x40120000 S:0xbe9e4b2c] ti.sdo.ce.osal.SemMP - Entered exitCS> id[0x1190000]
@0,269,441us: [+0 T:0x40120000 S:0xbe9e4b44] ti.sdo.ce.osal.SemMP - Leaving exitCS
@0,269,574us: [+0 T:0x40120000 S:0xbe9e4b24] OM - Memory_free> Enter(0x6372f0, 0x4)
@0,269,769us: [+0 T:0x40120000 S:0xbe9e4b24] OM - Memory_free> return (0x1)
@0,269,903us: [+0 T:0x40120000 S:0xbe9e4b44] ti.sdo.ce.osal.SemMP - Leaving SemMP_delete>
@0,270,040us: [+0 T:0x40120000 S:0xbe9e4b64] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x637200]
@0,270,182us: [+0 T:0x40120000 S:0xbe9e4b3c] ti.sdo.ce.osal.SemMP - Entered SemMP_pend> sem[0x637218] timeout[0xffffffff]
@0,270,347us: [+0 T:0x40120000 S:0xbe9e4b3c] ti.sdo.ce.osal.SemMP - Leaving SemMP_pend> sem[0x637218] status[0]
@0,270,490us: [+0 T:0x40120000 S:0xbe9e4b7c] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x637200]
@0,270,629us: [+0 T:0x40120000 S:0xbe9e4ae4] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x637228]
@0,270,762us: [+0 T:0x40120000 S:0xbe9e4abc] ti.sdo.ce.osal.SemMP - Entered SemMP_pend> sem[0x637240] timeout[0xffffffff]
@0,270,918us: [+0 T:0x40120000 S:0xbe9e4abc] ti.sdo.ce.osal.SemMP - Leaving SemMP_pend> sem[0x637240] status[0]
@0,271,114us: [+0 T:0x40120000 S:0xbe9e4afc] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x637228]
@0,271,679us: [+0 T:0x40120000 S:0xbe9e4ae4] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x637228]
@0,271,889us: [+0 T:0x40120000 S:0xbe9e4ac4] ti.sdo.ce.osal.SemMP - Entered SemMP_post> sem[0x637240]
@0,272,067us: [+0 T:0x40120000 S:0xbe9e4ac4] ti.sdo.ce.osal.SemMP - Leaving SemMP_post> sem[0x637240]
@0,272,221us: [+0 T:0x40120000 S:0xbe9e4afc] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x637228]
@0,272,356us: [+0 T:0x40120000 S:0xbe9e4b54] ti.sdo.ce.osal.LockMP - Entered LockMP_delete> lock[0x637228]
@0,272,545us: [+0 T:0x40120000 S:0xbe9e4b24] ti.sdo.ce.osal.SemMP - Entered SemMP_delete> sem[0x637240]
@0,272,678us: [+0 T:0x40120000 S:0xbe9e4b0c] ti.sdo.ce.osal.SemMP - Entered enterCS> key[0x4c41534f]
@0,272,867us: [+0 T:0x40120000 S:0xbe9e4b0c] ti.sdo.ce.osal.SemMP - Leaving enterCS> id[0x1198000]
@0,273,032us: [+0 T:0x40120000 S:0xbe9e4b24] ti.sdo.ce.osal.SemMP - SemMP_delete> sem: 0x1140002, ref count: 1
@0,273,248us: [+1 T:0x40120000 S:0xbe9e4b24] ti.sdo.ce.osal.SemMP - SemMP_delete> Deleted semaphore: 0x1140002
@0,273,406us: [+0 T:0x40120000 S:0xbe9e4b0c] ti.sdo.ce.osal.SemMP - Entered exitCS> id[0x1198000]
@0,273,563us: [+0 T:0x40120000 S:0xbe9e4b24] ti.sdo.ce.osal.SemMP - Leaving exitCS
@0,273,696us: [+0 T:0x40120000 S:0xbe9e4b04] OM - Memory_free> Enter(0x637240, 0x4)
@0,273,897us: [+0 T:0x40120000 S:0xbe9e4b04] OM - Memory_free> return (0x1)
@0,274,034us: [+0 T:0x40120000 S:0xbe9e4b24] ti.sdo.ce.osal.SemMP - Leaving SemMP_delete>
@0,274,167us: [+0 T:0x40120000 S:0xbe9e4b34] OM - Memory_free> Enter(0x637228, 0x10)
@0,274,306us: [+0 T:0x40120000 S:0xbe9e4b34] OM - Memory_free> return (0x1)
@0,274,433us: [+0 T:0x40120000 S:0xbe9e4b64] ti.sdo.ce.osal.LockMP - Leaving LockMP_delete>
@0,274,662us: [+0 T:0x40120000 S:0xbe9e4ae4] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x637250]
@0,274,830us: [+0 T:0x40120000 S:0xbe9e4abc] ti.sdo.ce.osal.SemMP - Entered SemMP_pend> sem[0x637268] timeout[0xffffffff]
@0,275,014us: [+0 T:0x40120000 S:0xbe9e4abc] ti.sdo.ce.osal.SemMP - Leaving SemMP_pend> sem[0x637268] status[0]
@0,275,210us: [+0 T:0x40120000 S:0xbe9e4afc] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x637250]
@0,275,615us: [+0 T:0x40120000 S:0xbe9e4ae4] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x637250]
@0,275,812us: [+0 T:0x40120000 S:0xbe9e4ac4] ti.sdo.ce.osal.SemMP - Entered SemMP_post> sem[0x637268]
@0,275,987us: [+0 T:0x40120000 S:0xbe9e4ac4] ti.sdo.ce.osal.SemMP - Leaving SemMP_post> sem[0x637268]
@0,276,137us: [+0 T:0x40120000 S:0xbe9e4afc] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x637250]
@0,276,274us: [+0 T:0x40120000 S:0xbe9e4ae4] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x637250]
@0,276,407us: [+0 T:0x40120000 S:0xbe9e4abc] ti.sdo.ce.osal.SemMP - Entered SemMP_pend> sem[0x637268] timeout[0xffffffff]
@0,276,608us: [+0 T:0x40120000 S:0xbe9e4abc] ti.sdo.ce.osal.SemMP - Leaving SemMP_pend> sem[0x637268] status[0]
@0,276,768us: [+0 T:0x40120000 S:0xbe9e4afc] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x637250]
@0,277,369us: [+0 T:0x40120000 S:0xbe9e4ae4] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x637250]
@0,277,574us: [+0 T:0x40120000 S:0xbe9e4ac4] ti.sdo.ce.osal.SemMP - Entered SemMP_post> sem[0x637268]
@0,277,752us: [+0 T:0x40120000 S:0xbe9e4ac4] ti.sdo.ce.osal.SemMP - Leaving SemMP_post> sem[0x637268]
@0,277,902us: [+0 T:0x40120000 S:0xbe9e4afc] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x637250]
@0,278,086us: [+0 T:0x40120000 S:0xbe9e4b54] ti.sdo.ce.osal.LockMP - Entered LockMP_delete> lock[0x637250]
@0,278,229us: [+0 T:0x40120000 S:0xbe9e4b24] ti.sdo.ce.osal.SemMP - Entered SemMP_delete> sem[0x637268]
@0,278,363us: [+0 T:0x40120000 S:0xbe9e4b0c] ti.sdo.ce.osal.SemMP - Entered enterCS> key[0x4c41534f]
@0,278,533us: [+0 T:0x40120000 S:0xbe9e4b0c] ti.sdo.ce.osal.SemMP - Leaving enterCS> id[0x11a0000]
@0,278,693us: [+0 T:0x40120000 S:0xbe9e4b24] ti.sdo.ce.osal.SemMP - SemMP_delete> sem: 0x1150003, ref count: 1
@0,278,905us: [+1 T:0x40120000 S:0xbe9e4b24] ti.sdo.ce.osal.SemMP - SemMP_delete> Deleted semaphore: 0x1150003
@0,279,065us: [+0 T:0x40120000 S:0xbe9e4b0c] ti.sdo.ce.osal.SemMP - Entered exitCS> id[0x11a0000]
@0,279,224us: [+0 T:0x40120000 S:0xbe9e4b24] ti.sdo.ce.osal.SemMP - Leaving exitCS
@0,279,408us: [+0 T:0x40120000 S:0xbe9e4b04] OM - Memory_free> Enter(0x637268, 0x4)
@0,279,557us: [+0 T:0x40120000 S:0xbe9e4b04] OM - Memory_free> return (0x1)
@0,279,684us: [+0 T:0x40120000 S:0xbe9e4b24] ti.sdo.ce.osal.SemMP - Leaving SemMP_delete>
@0,279,808us: [+0 T:0x40120000 S:0xbe9e4b34] OM - Memory_free> Enter(0x637250, 0x10)
@0,279,946us: [+0 T:0x40120000 S:0xbe9e4b34] OM - Memory_free> return (0x1)
@0,280,076us: [+0 T:0x40120000 S:0xbe9e4b64] ti.sdo.ce.osal.LockMP - Leaving LockMP_delete>
@0,280,440us: [+0 T:0x40120000 S:0xbe9e4ae4] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x637278]
@0,280,658us: [+0 T:0x40120000 S:0xbe9e4abc] ti.sdo.ce.osal.SemMP - Entered SemMP_pend> sem[0x637290] timeout[0xffffffff]
@0,280,904us: [+0 T:0x40120000 S:0xbe9e4abc] ti.sdo.ce.osal.SemMP - Leaving SemMP_pend> sem[0x637290] status[0]
@0,281,061us: [+0 T:0x40120000 S:0xbe9e4afc] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x637278]
@0,281,459us: [+0 T:0x40120000 S:0xbe9e4ae4] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x637278]
@0,281,656us: [+0 T:0x40120000 S:0xbe9e4ac4] ti.sdo.ce.osal.SemMP - Entered SemMP_post> sem[0x637290]
@0,281,836us: [+0 T:0x40120000 S:0xbe9e4ac4] ti.sdo.ce.osal.SemMP - Leaving SemMP_post> sem[0x637290]
@0,281,985us: [+0 T:0x40120000 S:0xbe9e4afc] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x637278]
@0,282,184us: [+0 T:0x40120000 S:0xbe9e4ae4] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x637278]
@0,282,325us: [+0 T:0x40120000 S:0xbe9e4abc] ti.sdo.ce.osal.SemMP - Entered SemMP_pend> sem[0x637290] timeout[0xffffffff]
@0,282,488us: [+0 T:0x40120000 S:0xbe9e4abc] ti.sdo.ce.osal.SemMP - Leaving SemMP_pend> sem[0x637290] status[0]
@0,282,631us: [+0 T:0x40120000 S:0xbe9e4afc] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x637278]
@0,283,023us: [+0 T:0x40120000 S:0xbe9e4ae4] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x637278]
@0,283,219us: [+0 T:0x40120000 S:0xbe9e4ac4] ti.sdo.ce.osal.SemMP - Entered SemMP_post> sem[0x637290]
@0,283,395us: [+0 T:0x40120000 S:0xbe9e4ac4] ti.sdo.ce.osal.SemMP - Leaving SemMP_post> sem[0x637290]
@0,283,602us: [+0 T:0x40120000 S:0xbe9e4afc] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x637278]
@0,283,747us: [+0 T:0x40120000 S:0xbe9e4b54] ti.sdo.ce.osal.LockMP - Entered LockMP_delete> lock[0x637278]
@0,283,878us: [+0 T:0x40120000 S:0xbe9e4b24] ti.sdo.ce.osal.SemMP - Entered SemMP_delete> sem[0x637290]
@0,284,009us: [+0 T:0x40120000 S:0xbe9e4b0c] ti.sdo.ce.osal.SemMP - Entered enterCS> key[0x4c41534f]
@0,284,181us: [+0 T:0x40120000 S:0xbe9e4b0c] ti.sdo.ce.osal.SemMP - Leaving enterCS> id[0x11a8000]
@0,284,346us: [+0 T:0x40120000 S:0xbe9e4b24] ti.sdo.ce.osal.SemMP - SemMP_delete> sem: 0x1160004, ref count: 1
@0,284,554us: [+1 T:0x40120000 S:0xbe9e4b24] ti.sdo.ce.osal.SemMP - SemMP_delete> Deleted semaphore: 0x1160004
@0,284,716us: [+0 T:0x40120000 S:0xbe9e4b0c] ti.sdo.ce.osal.SemMP - Entered exitCS> id[0x11a8000]
@0,284,912us: [+0 T:0x40120000 S:0xbe9e4b24] ti.sdo.ce.osal.SemMP - Leaving exitCS
@0,285,063us: [+0 T:0x40120000 S:0xbe9e4b04] OM - Memory_free> Enter(0x637290, 0x4)
@0,285,216us: [+0 T:0x40120000 S:0xbe9e4b04] OM - Memory_free> return (0x1)
@0,285,345us: [+0 T:0x40120000 S:0xbe9e4b24] ti.sdo.ce.osal.SemMP - Leaving SemMP_delete>
@0,285,471us: [+0 T:0x40120000 S:0xbe9e4b34] OM - Memory_free> Enter(0x637278, 0x10)
@0,285,606us: [+0 T:0x40120000 S:0xbe9e4b34] OM - Memory_free> return (0x1)
@0,285,732us: [+0 T:0x40120000 S:0xbe9e4b64] ti.sdo.ce.osal.LockMP - Leaving LockMP_delete>
@0,285,857us: [+0 T:0x40120000 S:0xbe9e4b64] ti.sdo.ce.osal.Sem - Entered Sem_delete> sem[0x6372a0]
@0,286,043us: [+0 T:0x40120000 S:0xbe9e4b44] OM - Memory_free> Enter(0x6372a0, 0x14)
@0,286,208us: [+0 T:0x40120000 S:0xbe9e4b44] OM - Memory_free> return (0x1)
@0,286,392us: [+0 T:0x40120000 S:0xbe9e4b74] ti.sdo.ce.osal.Sem - Leaving Sem_delete>
@0,286,521us: [+0 T:0x40120000 S:0xbe9e4b64] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x637200]
@0,286,651us: [+0 T:0x40120000 S:0xbe9e4b44] ti.sdo.ce.osal.SemMP - Entered SemMP_post> sem[0x637218]
@0,286,812us: [+0 T:0x40120000 S:0xbe9e4b44] ti.sdo.ce.osal.SemMP - Leaving SemMP_post> sem[0x637218]
@0,287,208us: [+0 T:0x40120000 S:0xbe9e4b7c] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x637200]
@0,287,358us: [+0 T:0x40120000 S:0xbe9e4b6c] ti.sdo.ce.osal.LockMP - Entered LockMP_delete> lock[0x637200]
@0,287,490us: [+0 T:0x40120000 S:0xbe9e4b3c] ti.sdo.ce.osal.SemMP - Entered SemMP_delete> sem[0x637218]
@0,287,681us: [+0 T:0x40120000 S:0xbe9e4b24] ti.sdo.ce.osal.SemMP - Entered enterCS> key[0x4c41534f]
@0,287,867us: [+0 T:0x40120000 S:0xbe9e4b24] ti.sdo.ce.osal.SemMP - Leaving enterCS> id[0x11b0000]
@0,288,038us: [+0 T:0x40120000 S:0xbe9e4b3c] ti.sdo.ce.osal.SemMP - SemMP_delete> sem: 0x1128001, ref count: 1
@0,288,254us: [+1 T:0x40120000 S:0xbe9e4b3c] ti.sdo.ce.osal.SemMP - SemMP_delete> Deleted semaphore: 0x1128001
@0,288,411us: [+0 T:0x40120000 S:0xbe9e4b24] ti.sdo.ce.osal.SemMP - Entered exitCS> id[0x11b0000]
@0,288,573us: [+0 T:0x40120000 S:0xbe9e4b3c] ti.sdo.ce.osal.SemMP - Leaving exitCS
@0,288,709us: [+0 T:0x40120000 S:0xbe9e4b1c] OM - Memory_free> Enter(0x637218, 0x4)
@0,288,851us: [+0 T:0x40120000 S:0xbe9e4b1c] OM - Memory_free> return (0x1)
@0,288,983us: [+0 T:0x40120000 S:0xbe9e4b3c] ti.sdo.ce.osal.SemMP - Leaving SemMP_delete>
@0,289,161us: [+0 T:0x40120000 S:0xbe9e4b4c] OM - Memory_free> Enter(0x637200, 0x10)
@0,289,305us: [+0 T:0x40120000 S:0xbe9e4b4c] OM - Memory_free> return (0x1)
@0,289,432us: [+0 T:0x40120000 S:0xbe9e4b7c] ti.sdo.ce.osal.LockMP - Leaving LockMP_delete>
@0,289,562us: [+2 T:0x40120000 S:0xbe9e4b9c] OG - Global_exit> calling function *0x352f08()...
@0,289,696us: [+2 T:0x40120000 S:0xbe9e4b9c] OG - Global_exit> calling function *0x355438()...
@0,289,879us: [+2 T:0x40120000 S:0xbe9e4b9c] OG - Global_exit> calling function *0x353f58()...
@0,290,035us: [+0 T:0x40120000 S:0xbe9e4b6c] OM - Memory_free> Enter(0x6370f0, 0x18)
@0,290,184us: [+0 T:0x40120000 S:0xbe9e4b6c] OM - Memory_free> return (0x1)
@0,290,317us: [+2 T:0x40120000 S:0xbe9e4b9c] OG - Global_exit> calling function *0x355dd4()...
@0,290,495us: [+2 T:0x40120000 S:0xbe9e4b9c] OG - Global_exit> calling function *0x356988()...
@0,290,631us: [+2 T:0x40120000 S:0xbe9e4b9c] OG - Global_exit> calling function *0x355d34()...
init2
@0,290,929us: [+4 T:0x40120000 S:0xbe9e4b84] OG - Global_init> This program was built with the following packages:
@0,291,153us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package gnu.targets.arm.rtsv5T (/home/honza/_dev/TI/eye03_sdk/dvsdk/xdctools_3_16_03_36/packages/gnu/targets/arm/rtsv5T/) [1, 0, 0, 0]
@0,291,318us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.linuxutils.vicp (/home/honza/_dev/TI/eye03_sdk/dvsdk/linuxutils_2_26_03_06/packages/ti/sdo/linuxutils/vicp/) [1, 0, 0]
@0,291,451us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.linuxutils.edma (/home/honza/_dev/TI/eye03_sdk/dvsdk/linuxutils_2_26_03_06/packages/ti/sdo/linuxutils/edma/) [1, 0, 0]
@0,291,579us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.linuxutils.cmem (/home/honza/_dev/TI/eye03_sdk/dvsdk/linuxutils_2_26_03_06/packages/ti/sdo/linuxutils/cmem/) [2, 2, 0]
@0,291,702us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.ce.global (/home/honza/_dev/TI/eye03_sdk/dvsdk/codec-engine_2_26_02_11/packages/ti/sdo/ce/global/) [1, 0, 0]
@0,291,875us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.xdais.dm (/home/honza/_dev/TI/eye03_sdk/dvsdk/xdais_6_26_01_03/packages/ti/xdais/dm/) [1, 0, 5]
@0,292,008us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.xdais (/home/honza/_dev/TI/eye03_sdk/dvsdk/xdais_6_26_01_03/packages/ti/xdais/) [1, 2.0, 1]
@0,292,140us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.ce.node (/home/honza/_dev/TI/eye03_sdk/dvsdk/codec-engine_2_26_02_11/packages/ti/sdo/ce/node/) [1, 0, 0]
@0,292,266us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.utils.trace (/home/honza/_dev/TI/eye03_sdk/dvsdk/framework-components_2_26_00_01/packages/ti/sdo/utils/trace/) [1, 0, 0]
@0,292,392us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.ce.utils.xdm (/home/honza/_dev/TI/eye03_sdk/dvsdk/codec-engine_2_26_02_11/packages/ti/sdo/ce/utils/xdm/) [1, 0, 2]
@0,292,516us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.fc.global (/home/honza/_dev/TI/eye03_sdk/dvsdk/framework-components_2_26_00_01/packages/ti/sdo/fc/global/) [1, 0, 0]
@0,292,636us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.fc.memutils (/home/honza/_dev/TI/eye03_sdk/dvsdk/framework-components_2_26_00_01/packages/ti/sdo/fc/memutils/) [1, 0, 0]
@0,292,780us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.fc.ires (/home/honza/_dev/TI/eye03_sdk/dvsdk/framework-components_2_26_00_01/packages/ti/sdo/fc/ires/) [1, 0, 0]
@0,292,908us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.fc.ires.addrspace (/home/honza/_dev/TI/eye03_sdk/dvsdk/framework-components_2_26_00_01/packages/ti/sdo/fc/ires/addrspace/) [1, 0, 0]
@0,293,033us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.fc.utils (/home/honza/_dev/TI/eye03_sdk/dvsdk/framework-components_2_26_00_01/packages/ti/sdo/fc/utils/) [1, 0, 2]
@0,293,194us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.fc.ires.hdvicp (/home/honza/_dev/TI/eye03_sdk/dvsdk/framework-components_2_26_00_01/packages/ti/sdo/fc/ires/hdvicp/) [1, 0, 1]
@0,293,333us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.fc.hdvicpsync (/home/honza/_dev/TI/eye03_sdk/dvsdk/framework-components_2_26_00_01/packages/ti/sdo/fc/hdvicpsync/) [1, 0, 0]
@0,293,465us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.fc.ires.memtcm (/home/honza/_dev/TI/eye03_sdk/dvsdk/framework-components_2_26_00_01/packages/ti/sdo/fc/ires/memtcm/) [1, 0, 0]
@0,293,590us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.codecs.h264enc (/home/honza/_dev/TI/eye03_sdk/dvsdk/codecs-dm365/packages/ti/sdo/codecs/h264enc/) [1, 0, 0]
@0,293,711us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.codecs.mpeg2enc (/home/honza/_dev/TI/eye03_sdk/dvsdk/codecs-dm365/packages/ti/sdo/codecs/mpeg2enc/) [1, 0, 0]
@0,293,832us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.codecs.mpeg4dec_hdvicp (/home/honza/_dev/TI/eye03_sdk/dvsdk/codecs-dm365/packages/ti/sdo/codecs/mpeg4dec_hdvicp/) [1, 0, 0]
@0,293,953us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.codecs.h264dec (/home/honza/_dev/TI/eye03_sdk/dvsdk/codecs-dm365/packages/ti/sdo/codecs/h264dec/) [1, 0, 0]
@0,294,074us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.codecs.mpeg2dec (/home/honza/_dev/TI/eye03_sdk/dvsdk/codecs-dm365/packages/ti/sdo/codecs/mpeg2dec/) [1, 0, 0]
@0,294,197us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.fc.dman3 (/home/honza/_dev/TI/eye03_sdk/dvsdk/framework-components_2_26_00_01/packages/ti/sdo/fc/dman3/) [1, 0, 4]
@0,294,324us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.fc.acpy3 (/home/honza/_dev/TI/eye03_sdk/dvsdk/framework-components_2_26_00_01/packages/ti/sdo/fc/acpy3/) [1, 0, 4]
@0,294,447us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package gnu.targets (/home/honza/_dev/TI/eye03_sdk/dvsdk/xdctools_3_16_03_36/packages/gnu/targets/) [1, 0, 1]
@0,294,606us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package gnu.targets.arm (/home/honza/_dev/TI/eye03_sdk/dvsdk/xdctools_3_16_03_36/packages/gnu/targets/arm/) [1, 0, 0, 0]
@0,294,740us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.fc.ires.nullresource (/home/honza/_dev/TI/eye03_sdk/dvsdk/framework-components_2_26_00_01/packages/ti/sdo/fc/ires/nullresource/) [1, 0, 0]
@0,294,868us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.fc.rman (/home/honza/_dev/TI/eye03_sdk/dvsdk/framework-components_2_26_00_01/packages/ti/sdo/fc/rman/) [2, 0, 0]
@0,294,986us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.fc.edma3 (/home/honza/_dev/TI/eye03_sdk/dvsdk/framework-components_2_26_00_01/packages/ti/sdo/fc/edma3/) [3, 0, 0]
@0,295,109us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.fc.ires.edma3chan (/home/honza/_dev/TI/eye03_sdk/dvsdk/framework-components_2_26_00_01/packages/ti/sdo/fc/ires/edma3chan/) [1, 0, 0]
@0,295,233us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.catalog.arm (/home/honza/_dev/TI/eye03_sdk/dvsdk/xdctools_3_16_03_36/packages/ti/catalog/arm/) [1, 0, 1, 0]
@0,295,354us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.catalog (/home/honza/_dev/TI/eye03_sdk/dvsdk/xdctools_3_16_03_36/packages/ti/catalog/) [1, 0, 0]
@0,295,473us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.platforms.evmDM365 (/home/honza/_dev/TI/eye03_sdk/dvsdk/xdctools_3_16_03_36/packages/ti/platforms/evmDM365/) [1, 0, 0]
@0,295,611us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.ce.osal (/home/honza/_dev/TI/eye03_sdk/dvsdk/codec-engine_2_26_02_11/packages/ti/sdo/ce/osal/) [2, 0, 2]
@0,295,738us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.ce.osal.linux (/home/honza/_dev/TI/eye03_sdk/dvsdk/codec-engine_2_26_02_11/packages/ti/sdo/ce/osal/linux/) [2, 0, 1]
@0,295,859us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.ce.ipc (/home/honza/_dev/TI/eye03_sdk/dvsdk/codec-engine_2_26_02_11/packages/ti/sdo/ce/ipc/) [2, 0, 1]
@0,296,024us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.ce.ipc.linux (/home/honza/_dev/TI/eye03_sdk/dvsdk/codec-engine_2_26_02_11/packages/ti/sdo/ce/ipc/linux/) [2, 0, 1]
@0,296,158us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.ce.alg (/home/honza/_dev/TI/eye03_sdk/dvsdk/codec-engine_2_26_02_11/packages/ti/sdo/ce/alg/) [1, 0, 1]
@0,296,278us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.fc.ires.grouputils (/home/honza/_dev/TI/eye03_sdk/dvsdk/framework-components_2_26_00_01/packages/ti/sdo/fc/ires/grouputils/) [1, 0, 0]
@0,296,399us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.fc.ires.vicp (/home/honza/_dev/TI/eye03_sdk/dvsdk/framework-components_2_26_00_01/packages/ti/sdo/fc/ires/vicp/) [1, 0, 1]
@0,296,519us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.fc.vicpsync (/home/honza/_dev/TI/eye03_sdk/dvsdk/framework-components_2_26_00_01/packages/ti/sdo/fc/vicpsync/) [1, 0, 0]
@0,296,638us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.ce (/home/honza/_dev/TI/eye03_sdk/dvsdk/codec-engine_2_26_02_11/packages/ti/sdo/ce/) [1, 0, 6]
@0,296,761us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.ce.video2 (/home/honza/_dev/TI/eye03_sdk/dvsdk/codec-engine_2_26_02_11/packages/ti/sdo/ce/video2/) [1, 0, 2]
@0,297,119us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.ce.video1 (/home/honza/_dev/TI/eye03_sdk/dvsdk/codec-engine_2_26_02_11/packages/ti/sdo/ce/video1/) [1, 0, 2]
@0,297,291us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.codecs.iprun (/home/honza/_dev/TI/eye03_sdk/dvsdk/codecs-dm365/packages/ti/sdo/codecs/iprun/) [1, 0, 0]
@0,297,477us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.codecs.iprun.ce (/home/honza/_dev/TI/eye03_sdk/dvsdk/codecs-dm365/packages/ti/sdo/codecs/iprun/ce/) [1, 0, 0]
@0,297,610us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.codecs.ktnf (/home/honza/_dev/TI/eye03_sdk/dvsdk/codecs-dm365/packages/ti/sdo/codecs/ktnf/) [1, 0, 0]
@0,297,737us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.codecs.ktnf.ce (/home/honza/_dev/TI/eye03_sdk/dvsdk/codecs-dm365/packages/ti/sdo/codecs/ktnf/ce/) [1, 0, 0]
@0,297,861us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.codecs.mpeg4enc (/home/honza/_dev/TI/eye03_sdk/dvsdk/codecs-dm365/packages/ti/sdo/codecs/mpeg4enc/) [1, 0, 0]
@0,297,984us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.codecs.mpeg4enc.ce (/home/honza/_dev/TI/eye03_sdk/dvsdk/codecs-dm365/packages/ti/sdo/codecs/mpeg4enc/ce/) [1, 0, 0]
@0,298,108us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.codecs.h264enc.ce (/home/honza/_dev/TI/eye03_sdk/dvsdk/codecs-dm365/packages/ti/sdo/codecs/h264enc/ce/) [1, 0, 0]
@0,298,228us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.codecs.mpeg2enc.ce (/home/honza/_dev/TI/eye03_sdk/dvsdk/codecs-dm365/packages/ti/sdo/codecs/mpeg2enc/ce/) [1, 0, 0]
@0,298,350us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.codecs.mpeg4dec_hdvicp.ce (/home/honza/_dev/TI/eye03_sdk/dvsdk/codecs-dm365/packages/ti/sdo/codecs/mpeg4dec_hdvicp/ce/) [1, 0, 0]
@0,298,477us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.codecs.h264dec.ce (/home/honza/_dev/TI/eye03_sdk/dvsdk/codecs-dm365/packages/ti/sdo/codecs/h264dec/ce/) [1, 0, 0]
@0,298,599us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package ti.sdo.codecs.mpeg2dec.ce (/home/honza/_dev/TI/eye03_sdk/dvsdk/codecs-dm365/packages/ti/sdo/codecs/mpeg2dec/ce/) [1, 0, 0]
@0,298,791us: [+4 T:0x40120000 S:0xbe9e4b84] OG - package mc_config (/home/honza/_dev/TI/eye03_sdk/eye03_video_server/mc_config/) []
@0,298,952us: [+0 T:0x40120000 S:0xbe9e4b8c] OG - Global_atexit> enter (fxn=0x356988)
@0,299,105us: [+0 T:0x40120000 S:0xbe9e4b8c] OG - Global_atexit> enter (fxn=0x355dd4)
@0,299,242us: [+0 T:0x40120000 S:0xbe9e4b54] OM - Memory_alloc> Enter(0x18)
@0,299,382us: [+0 T:0x40120000 S:0xbe9e4b54] OM - Memory_alloc> return (0x6370f0)
@0,299,537us: [+0 T:0x40120000 S:0xbe9e4b7c] OG - Global_atexit> enter (fxn=0x353f58)
@0,299,691us: [+0 T:0x40120000 S:0xbe9e4b7c] OG - Global_atexit> enter (fxn=0x355438)
@0,299,834us: [+0 T:0x40120000 S:0xbe9e4b8c] OG - Global_atexit> enter (fxn=0x352f08)
@0,299,975us: [+0 T:0x40120000 S:0xbe9e4b4c] ti.sdo.ce.osal.LockMP - LockMP_create> key: 0x524d414e count: 5339576 attrs: 0x51af70
@0,300,149us: [+0 T:0x40120000 S:0xbe9e4b2c] OM - Memory_alloc> Enter(0x10)
@0,300,297us: [+0 T:0x40120000 S:0xbe9e4b2c] OM - Memory_alloc> return (0x637200)
@0,300,428us: [+0 T:0x40120000 S:0xbe9e4b0c] ti.sdo.ce.osal.SemMP - SemMP_create> key: 0x524d414e count: 1
@0,300,561us: [+0 T:0x40120000 S:0xbe9e4aec] OM - Memory_alloc> Enter(0x4)
@0,300,690us: [+0 T:0x40120000 S:0xbe9e4aec] OM - Memory_alloc> return (0x6371c0)
@0,300,818us: [+0 T:0x40120000 S:0xbe9e4af4] ti.sdo.ce.osal.SemMP - Entered enterCS> key[0x4c41534f]
@0,300,984us: [+0 T:0x40120000 S:0xbe9e4af4] ti.sdo.ce.osal.SemMP - Leaving enterCS> id[0x11b8000]
@0,301,209us: [+0 T:0x40120000 S:0xbe9e4b0c] ti.sdo.ce.osal.SemMP - SemMP_create> semId: 0x11c0001 refCount: 1
@0,301,378us: [+0 T:0x40120000 S:0xbe9e4af4] ti.sdo.ce.osal.SemMP - Entered exitCS> id[0x11b8000]
@0,301,591us: [+0 T:0x40120000 S:0xbe9e4b0c] ti.sdo.ce.osal.SemMP - Leaving exitCS
@0,301,737us: [+0 T:0x40120000 S:0xbe9e4b0c] ti.sdo.ce.osal.SemMP - Leaving SemMP_create> sem[0x6371c0]
@0,301,871us: [+0 T:0x40120000 S:0xbe9e4b4c] ti.sdo.ce.osal.LockMP - Leaving LockMP_create> lock[0x637200]
@0,302,005us: [+0 T:0x40120000 S:0xbe9e4b0c] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x637200]
@0,302,138us: [+0 T:0x40120000 S:0xbe9e4ae4] ti.sdo.ce.osal.SemMP - Entered SemMP_pend> sem[0x6371c0] timeout[0xffffffff]
@0,302,300us: [+0 T:0x40120000 S:0xbe9e4ae4] ti.sdo.ce.osal.SemMP - Leaving SemMP_pend> sem[0x6371c0] status[0]
@0,302,441us: [+0 T:0x40120000 S:0xbe9e4b24] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x637200]
@0,302,578us: [+0 T:0x40120000 S:0xbe9e4b0c] ti.sdo.ce.osal.LockMP - Entered LockMP_release>lock[0x637200]
@0,302,705us: [+0 T:0x40120000 S:0xbe9e4aec] ti.sdo.ce.osal.SemMP - Entered SemMP_post> sem[0x6371c0]
@0,302,853us: [+0 T:0x40120000 S:0xbe9e4aec] ti.sdo.ce.osal.SemMP - Leaving SemMP_post> sem[0x6371c0]
@0,303,047us: [+0 T:0x40120000 S:0xbe9e4b24] ti.sdo.ce.osal.LockMP - Leaving LockMP_release>lock[0x637200]
@0,303,217us: [+0 T:0x40120000 S:0xbe9e4b0c] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x637200]
@0,303,364us: [+0 T:0x40120000 S:0xbe9e4ae4] ti.sdo.ce.osal.SemMP - Entered SemMP_pend> sem[0x6371c0] timeout[0xffffffff]
@0,303,528us: [+0 T:0x40120000 S:0xbe9e4ae4] ti.sdo.ce.osal.SemMP - Leaving SemMP_pend> sem[0x6371c0] status[0]
@0,303,674us: [+0 T:0x40120000 S:0xbe9e4b24] ti.sdo.ce.osal.LockMP - Leaving LockMP_acquire> lock[0x637200]
@0,303,814us: [+0 T:0x40120000 S:0xbe9e4acc] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x637228]
@0,303,949us: [+0 T:0x40120000 S:0xbe9e4aa4] ti.sdo.ce.osal.SemMP - Entered SemMP_pend> sem[0x0] timeout[0xffffffff]

thanks
Jan

0 GAnthony over 13 years ago in reply to Jan Pohanka

TI__Intellectual 2790 points

Jan:

I see in the last line of the log:

@0,303,949us: [+0 T:0x40120000 S:0xbe9e4aa4] ti.sdo.ce.osal.SemMP - Entered SemMP_pend> sem[0x0] timeout[0xffffffff]

which shows that a NULL SemMP_Handle is getting passed into SemMP_pend(), and getting dereferenced at line 357 in /packages/ti/sdo/ce/osal/linux/SemMP_posix.c:

if (semop(sem->id, &semBuf, 1) == -1)

The CE_DEBUG=3 trace isn't giving enough info to figure out where this NULL value is coming from. It may be a memory overwrite somewhere.

Do you have any gdb backtrace, so we can see who might be calling SemMP_pend() with a NULL handle?

Here's another data point: One of the Codec Engine developers here ran two test cases, and they both passed:

"I tried to reproduce the segfault seen by calling:

  CERuntime_init();
  CERuntime_exit();
  CERuntime_init();
  CERuntime_exit();

in the main() fuction of one of the Codec Engine 2.26.02.11 examples, running on DM365.

The app (audio_copy) ran fine, no segfault.  I also tried video1_copy with dma, since CERuntime_init() 
 calls DMAN3_init() and ACPY3_init().  This app segfaulted only after processing started
 (because dma registers were not configured properly, I assume), but the CERuntime_init()/CERuntime_exit() code ran fine."

So, I am wondering if there is something due to the codec server configuration? Do you get the same behavior with the CE sample codecs, or just your particular server combination?

There was an earlier bug (SDOCM00075259) in RMAN_init/exit() calls, but that was fixed in FC 2.26.00.01 (which is the version you have!), and I didn't see any FC trace in your log.

You might try turning on FC trace also, to see if something is going awry in the codec server instantiation:

http://processors.wiki.ti.com/index.php/FC_Config_Updates#Debug_and_Trace_settings

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

Regards,

- Gil

0 Jan Pohanka over 13 years ago in reply to GAnthony

Expert 1310 points

Hi Gil,

I have tried to do a more investigation about the problem. Here is gdb backtrace:

#0 0x0034c5b0 in SemMP_pend (sem=0x0, timeout=4294967295) at SemMP_posix.c:357
status = 0
semBuf = {sem_num = 0, sem_op = -1, sem_flg = 4096}
#1 0x003468b8 in LockMP_acquire (lock=0x646230) at LockMP_posix.c:89
self = 1075576832
pid = 243
status = 0
#2 0x003e41d0 in SHM_getObj (lock=0x646230, shmSize=92, key=1230390346, fxn=0x3e2b8c <setInternalState>, shmId=0xbeadcab4) at sharedMem.c:67
shmExists = 1
lockRefCount = 1077513080
shmBase = 0x0
#3 0x003e2ae8 in getInternalState () at iresman_hdvicp.c:789
sharedMemId = -1
#4 0x003e1dd8 in IRESMAN_HDVICP_init (initArgs=0x645a00) at iresman_hdvicp.c:245
resmanArgs = 0x645a00
#5 0x00358a50 in RMAN_register (resmanFxns=0x52b2f0, initArgs=0x645a00) at rman.c:729
index = -1
i = 1
resman = 0x52a268
protocolName = 0x4fe408 "ti.sdo.fc.ires.hdvicp"
status = IRES_OK
revNo = 0x52b308
#6 0x00358364 in RMAN_init () at rman.c:618
i = 0
status = IRES_OK
nullParams = {size = 12, allocFxn = 0x3451c8 <_ALG_allocMemory>, freeFxn = 0x3455cc <_ALG_freeMemory>}
#7 0x00343ec4 in Algorithm_init () at Algorithm_noOS.c:383
No locals.
#8 0x003006d8 in CERuntime_init ()
No symbol table info available.
#9 0x00012cf4 in main () at output-example.c:562
No locals.

I attached also a log with FC messages enabled 0511.celog.txt, but I don't know if it might help.

Here is my config file for XDC tools:

/* Load support for the Codec Engine OSAL */
var osalGlobal = xdc.useModule('ti.sdo.ce.osal.Global');

osalGlobal.runtimeEnv = osalGlobal.LINUX;

/* Load support for the 'Davinci Multimedia Application Interface' module */

environment['xdc.cfg.check.fatal'] = 'false';

var RMAN = xdc.useModule('ti.sdo.fc.rman.RMAN');
RMAN.useDSKT2 = false;
RMAN.persistentAllocFxn = "__ALG_allocMemory";
RMAN.persistentFreeFxn = "__ALG_freeMemory";
RMAN.semCreateFxn = "Sem_create";
RMAN.semDeleteFxn = "Sem_delete";
RMAN.semPendFxn = "Sem_pend";
RMAN.semPostFxn = "Sem_post";
RMAN.tableSize = 10;

var EDMA3 = xdc.useModule('ti.sdo.fc.edma3.Settings');
var vicp = xdc.useModule('ti.sdo.linuxutils.vicp.VICP');
var HDVICP = xdc.useModule('ti.sdo.fc.ires.hdvicp.HDVICP');
var VICP2 = xdc.useModule('ti.sdo.fc.ires.vicp.VICP2');
var VICPSYNC = xdc.useModule('ti.sdo.fc.vicpsync.VICPSYNC');
var HDVICPSYNC = xdc.useModule('ti.sdo.fc.hdvicpsync.HDVICPSYNC');
var MEMUTILS = xdc.useModule('ti.sdo.fc.memutils.MEMUTILS');
var ADDRSPACE = xdc.useModule('ti.sdo.fc.ires.addrspace.ADDRSPACE');
var EDMA3CHAN = xdc.useModule('ti.sdo.fc.ires.edma3chan.EDMA3CHAN');
var EDMA = xdc.useModule('ti.sdo.linuxutils.edma.EDMA');
var CMEM = xdc.useModule('ti.sdo.linuxutils.cmem.CMEM');

var MEMTCM = xdc.useModule('ti.sdo.fc.ires.memtcm.MEMTCM');
MEMTCM.cmemBlockId = 1; //Since we use _1 in our insmod command.

xdc.loadPackage("ti.sdo.ce.video2");
xdc.loadPackage("ti.sdo.fc.hdvicpsync");

/*
* ======== Engine Configuration ========
*/

/*var IPRUN = xdc.useModule('ti.sdo.codecs.iprun.ce.IPRUN');
var KTNF = xdc.useModule('ti.sdo.codecs.ktnf.ce.KTNF');*/
var MPEG4ENC = xdc.useModule('ti.sdo.codecs.mpeg4enc.ce.MPEG4ENC');
var H264ENC = xdc.useModule('ti.sdo.codecs.h264enc.ce.H264ENC');
var MPEG2ENC = xdc.useModule('ti.sdo.codecs.mpeg2enc.ce.MPEG2ENC');
var MPEG4DEC = xdc.useModule('ti.sdo.codecs.mpeg4dec_hdvicp.ce.MPEG4DEC_HDVICP');
var H264DEC = xdc.useModule('ti.sdo.codecs.h264dec.ce.H264DEC');
var MPEG2DEC = xdc.useModule('ti.sdo.codecs.mpeg2dec.ce.MPEG2DEC');

var Engine = xdc.useModule('ti.sdo.ce.Engine');
var myEngineE = Engine.create("encode", [
/*{name: "iprun", mod: IPRUN, local: true, groupId: 2},
{name: "ktnf", mod: KTNF, local: true, groupId: 2},*/
{name: "mpeg4enc", mod: MPEG4ENC, local: true, groupId: 1},
{name: "h264enc", mod: H264ENC, local: true, groupId: 1},
]);

var myEngineD = Engine.create("decode", [
{name: "mpeg4dec", mod: MPEG4DEC, local: true, groupId: 1},
{name: "h264dec", mod: H264DEC, local: true, groupId: 1},
{name: "mpeg2dec", mod: MPEG2DEC, local: true, groupId: 1},
]);

xdc.useModule('ti.sdo.fc.global.Settings').profile = "debug";
xdc.loadPackage('ti.sdo.fc.ires.vicp').profile = "debug_trace";
xdc.loadPackage('ti.sdo.fc.ires.edma3chan').profile = "debug_trace";
xdc.loadPackage('ti.sdo.fc.rman').profile = "debug_trace";
xdc.loadPackage('ti.sdo.fc.edma3').profile = "debug_trace";

Program.main = null;

regards
Jan

0 GAnthony over 13 years ago in reply to Jan Pohanka

TI__Intellectual 2790 points

Jan:

Thanks, the gdb backtrace plus the FC trace log combined has revealed the issue:

In iresman_hdvicp.c, a LockMP_aquire() is occuring on a lock which has already been deleted.

Summarizing the FC log:

@0,440,216us: [+0 T:0x40126000 S:0xbeefbaa4] ti.sdo.ce.osal.LockMP - Leaving LockMP_create> lock[0x646250]

[ ...]

@0,489,976us: [+0 T:0x40126000 S:0xbeefbb14] ti.sdo.ce.osal.LockMP - Entered LockMP_delete> lock[0x646250]

[ ...]

@0,524,721us: [+0 T:0x40126000 S:0xbeefba44] ti.sdo.ce.osal.LockMP - Entered LockMP_acquire> lock[0x646250]
@0,524,870us: [+0 T:0x40126000 S:0xbeefba14] ti.sdo.ce.osal.SemMP - Entered SemMP_pend> sem[0x0] timeout[0xffffffff]

=> Crash.

The issue appears to be in iresman_hdvicp.c, in the IRESMAN_HDVICP_exit() function, where the static variable _HDVICP_lock needs to be set to NULL after getting deleted, so when it is checked again in IRESMAN_HDVICP_init(), it will recreate the lock.

IRES_Status IRESMAN_HDVICP_init(IRESMAN_Params * initArgs)
{
...
    if (NULL == _HDVICP_lock) {
        /* Create a lock for protecting VICP internal state object */
        _HDVICP_lock = LockMP_create(_HDVICP_LOCKID);
...
}

IRES_Status IRESMAN_HDVICP_exit()
{

    if (NULL != _HDVICP_lock) {
        LockMP_delete(_HDVICP_lock);
    }

....
}

So, if you modify the function as follows:

IRES_Status IRESMAN_HDVICP_exit()
{

    if (NULL != _HDVICP_lock) {
        LockMP_delete(_HDVICP_lock);
        _HDVICP_lock = NULL;
    }

....
}

I think that will fix it.

Do you have the FC source code to recompile?  If not, I see that version is not on the download 
page:  http://software-dl.ti.com/dsps/dsps_public_sw/sdo_sb/targetcontent/fc/index.html

So, I checked a newer version of FrameworkComponents (v3.23.01.14), and the issue does not exist as the code was completely rewritten.

You asked about the option of updating to FC v 3.x, but I didn't see explicit support mentioned for your platform in those release notes.

The other option is simply to avoid calling CERuntime_init()/CERuntime_exit() more than once.


I'll check to see if your platform is supported in any MFP 3.x version, but in the meantime, if you don't have the FC source, can you avoid making 
those back to back init()/exit() calls?

Regards,
- Gil

0 GAnthony over 13 years ago in reply to GAnthony

TI__Intellectual 2790 points

Jan:

Actually, the FC source for your version is available, here: http://software-dl.ti.com/dsps/dsps_public_sw/sdo_sb/targetcontent/fc/2_26_00_01/index_FDS.html

So if you wish to go the route of updating the IRESMAN_HDVICP_exit() function and recompiling, that should work.

Regards,

- Gil

0 Jan Pohanka over 13 years ago in reply to GAnthony

Expert 1310 points

Hi Gil,

thank you for the detailed analysis of the problem. I have never get so funded response here on the forum, yet.

I have the FC source but I noticed that no makefile is packaged there. Do any build instructions exist anywhere?

In the mean time I have avoided to multiple calling init and exit, which is probably the best solution. On the other hand I lack a method to ensure if CERuntime_init() was/wasn't called. With this my modules using CE could at least signalize some error on their start when CE was not initialized properly. Do you please now such method?

best regards
Jan

0 Gunjan over 13 years ago in reply to Jan Pohanka

TI__Expert 7415 points

Instructions on rebuilding FC (actually CE, but same apply for FC) are available here for the 2.x stream :- http://processors.wiki.ti.com/index.php/Rebuilding_Codec_Engine

But avoiding multiple calls to init/exit is the best way of avoiding this issue. We will raise a bug for this issue however, and fix it when we make a patch release for the 2.x stream.

Gunjan.

0 GAnthony over 13 years ago in reply to Gunjan

TI__Intellectual 2790 points

Jan:

The only way I can think of to test if CERuntime_init() was called by another module would be to define and export a global variable that is set after CERuntime_init() is called, then the other module can check it (assuming they are in the same process).

Regards,

- Gil

Processors

Processors forum

Codec engine init and exit called more than once in an application