2010/Dec/08 18:02:30.317367 EXECUTOR - TTCN-3 Parallel Test Component started on debian. Component reference: Comp0(3), component type: TitanLogTest.TitanTest_CT, component name: Comp0. Version: 1.8.pl4. 2010/Dec/08 18:02:30.318067 EXECUTOR - TTCN Logger v2.2 options: TimeStampFormat:=DateTime; LogEntityName:=Yes; LogEventTypes:=Yes; SourceInfoFormat:=Stack; *.FileMask:=LOG_ALL; *.ConsoleMask:=ACTION | ERROR | EXECUTOR | TESTCASE | STATISTICS | VERDICTOP; LogFileSize:=0; LogFileNumber:=1; DiskFullAction:=Error 2010/Dec/08 18:02:30.320191 EXECUTOR - Connected to MC. 2010/Dec/08 18:02:30.322647 PARALLEL - Initializing variables, timers and ports of component type TitanLogTest.TitanTest_CT inside testcase Titan_Logging_BasicTest. 2010/Dec/08 18:02:30.322942 PORTEVENT - Port EPTF_Base_MgmtIf was started. 2010/Dec/08 18:02:30.323002 PORTEVENT - Port sync_PCO was started. 2010/Dec/08 18:02:30.323048 PARALLEL - Component type TitanLogTest.TitanTest_CT was initialized. 2010/Dec/08 18:02:30.325191 PORTEVENT - Port sync_PCO has established the connection with mtc:sync_PCO using transport type UNIX. 2010/Dec/08 18:02:30.327247 PARALLEL - Starting function f_EPTF_TitanTest_behavior(0). 2010/Dec/08 18:02:30.327333 USER TitanLogTest.ttcn:160(function:f_EPTF_TitanTest_behavior) "Start behaviour - comp0" 2010/Dec/08 18:02:30.327537 PORTEVENT TitanLogTest.ttcn:162(function:f_EPTF_TitanTest_behavior) Sent on sync_PCO to mtc @TitanLogTest.StartOk : { senderNum := 0 } 2010/Dec/08 18:02:30.327679 TIMEROP TitanLogTest.ttcn:163(function:f_EPTF_TitanTest_behavior) Start timer T_testDuration: 5 s 2010/Dec/08 18:02:30.327846 USER TitanLogTest.ttcn:164(function:f_EPTF_TitanTest_behavior) "before Alt in comp0" 2010/Dec/08 18:02:30.380316 PORTEVENT TitanLogTest.ttcn:165(function:f_EPTF_TitanTest_behavior) Message enqueued on sync_PCO from mtc @TitanLogTest.StartOk : { senderNum := 0 } id 1 2010/Dec/08 18:02:30.380909 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:108(altstep:as_EPTF_TitanTest_receive1) Receive operation on port sync_PCO succeeded, message from mtc: @TitanLogTest.StartOk : { senderNum := 0 } id 1 2010/Dec/08 18:02:30.380983 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:108(altstep:as_EPTF_TitanTest_receive1) Message with id 1 was extracted from the queue of sync_PCO. 2010/Dec/08 18:02:30.381034 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:109(altstep:as_EPTF_TitanTest_receive1) "Receive happens1 - StartOK - comp0" 2010/Dec/08 18:02:30.381102 TIMEROP TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:111(altstep:as_EPTF_TitanTest_receive1) Start timer T_send: 0.01 s 2010/Dec/08 18:02:30.393837 TIMEROP TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:112(altstep:as_EPTF_TitanTest_receive1) Timeout T_send: 0.01 s 2010/Dec/08 18:02:30.393962 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:113(altstep:as_EPTF_TitanTest_receive1)->TitanLogTest.ttcn:69(function:f_EPTF_TitanTest_sendResponse) "Send happens - comp0" 2010/Dec/08 18:02:30.394027 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:113(altstep:as_EPTF_TitanTest_receive1)->TitanLogTest.ttcn:88(function:f_EPTF_TitanTest_sendResponse) Sent on sync_PCO to mtc @TitanLogTest.Data2 : { senderNum := 0, receiverNum := 1, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 0.000000 } 2010/Dec/08 18:02:30.419479 PORTEVENT TitanLogTest.ttcn:165(function:f_EPTF_TitanTest_behavior) Message enqueued on sync_PCO from mtc @TitanLogTest.Data1 : { senderNum := 1, receiverNum := 0, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } id 2 2010/Dec/08 18:02:30.419597 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:97(altstep:as_EPTF_TitanTest_receive1) Receive operation on port sync_PCO succeeded, message from mtc: @TitanLogTest.Data1 : { senderNum := 1, receiverNum := 0, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } id 2 2010/Dec/08 18:02:30.419723 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:97(altstep:as_EPTF_TitanTest_receive1) Message with id 2 was extracted from the queue of sync_PCO. 2010/Dec/08 18:02:30.419762 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:98(altstep:as_EPTF_TitanTest_receive1) "Receive happens1 - comp0" 2010/Dec/08 18:02:30.419805 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:100(altstep:as_EPTF_TitanTest_receive1) xxxxxxxxxx 2010/Dec/08 18:02:30.419843 TIMEROP TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:103(altstep:as_EPTF_TitanTest_receive1) Start timer T_send: 0.01 s 2010/Dec/08 18:02:30.429894 TIMEROP TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:104(altstep:as_EPTF_TitanTest_receive1) Timeout T_send: 0.01 s 2010/Dec/08 18:02:30.429990 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:105(altstep:as_EPTF_TitanTest_receive1)->TitanLogTest.ttcn:69(function:f_EPTF_TitanTest_sendResponse) "Send happens - comp0" 2010/Dec/08 18:02:30.430041 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:105(altstep:as_EPTF_TitanTest_receive1)->TitanLogTest.ttcn:88(function:f_EPTF_TitanTest_sendResponse) Sent on sync_PCO to mtc @TitanLogTest.Data2 : { senderNum := 0, receiverNum := 2, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 1.000000 } 2010/Dec/08 18:02:30.467405 PORTEVENT TitanLogTest.ttcn:165(function:f_EPTF_TitanTest_behavior) Message enqueued on sync_PCO from mtc @TitanLogTest.Data1 : { senderNum := 2, receiverNum := 0, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } id 3 2010/Dec/08 18:02:30.467491 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:97(altstep:as_EPTF_TitanTest_receive1) Receive operation on port sync_PCO succeeded, message from mtc: @TitanLogTest.Data1 : { senderNum := 2, receiverNum := 0, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } id 3 2010/Dec/08 18:02:30.467540 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:97(altstep:as_EPTF_TitanTest_receive1) Message with id 3 was extracted from the queue of sync_PCO. 2010/Dec/08 18:02:30.467587 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:98(altstep:as_EPTF_TitanTest_receive1) "Receive happens1 - comp0" 2010/Dec/08 18:02:30.467630 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:100(altstep:as_EPTF_TitanTest_receive1) xxxxxxxxxx 2010/Dec/08 18:02:30.467686 TIMEROP TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:103(altstep:as_EPTF_TitanTest_receive1) Start timer T_send: 0.01 s 2010/Dec/08 18:02:30.479020 TIMEROP TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:104(altstep:as_EPTF_TitanTest_receive1) Timeout T_send: 0.01 s 2010/Dec/08 18:02:30.479115 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:105(altstep:as_EPTF_TitanTest_receive1)->TitanLogTest.ttcn:69(function:f_EPTF_TitanTest_sendResponse) "Send happens - comp0" 2010/Dec/08 18:02:30.479166 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:105(altstep:as_EPTF_TitanTest_receive1)->TitanLogTest.ttcn:85(function:f_EPTF_TitanTest_sendResponse) Sent on sync_PCO to mtc @TitanLogTest.Data1 : { senderNum := 0, receiverNum := 1, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } 2010/Dec/08 18:02:30.563847 PORTEVENT TitanLogTest.ttcn:165(function:f_EPTF_TitanTest_behavior) Message enqueued on sync_PCO from mtc @TitanLogTest.Data2 : { senderNum := 1, receiverNum := 0, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 2.000000 } id 4 2010/Dec/08 18:02:30.563912 PORTEVENT TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:128(altstep:as_EPTF_TitanTest_receive2) Receive operation on port sync_PCO succeeded, message from mtc: @TitanLogTest.Data2 : { senderNum := 1, receiverNum := 0, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 2.000000 } id 4 2010/Dec/08 18:02:30.564033 PORTEVENT TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:128(altstep:as_EPTF_TitanTest_receive2) Message with id 4 was extracted from the queue of sync_PCO. 2010/Dec/08 18:02:30.564071 USER TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:129(altstep:as_EPTF_TitanTest_receive2) "Receive happens2 - comp0" 2010/Dec/08 18:02:30.564115 USER TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:132(altstep:as_EPTF_TitanTest_receive2) yyyyyyyyyy 2010/Dec/08 18:02:30.564152 TIMEROP TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:136(altstep:as_EPTF_TitanTest_receive2) Start timer T_send: 0.01 s 2010/Dec/08 18:02:30.574206 TIMEROP TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:137(altstep:as_EPTF_TitanTest_receive2) Timeout T_send: 0.01 s 2010/Dec/08 18:02:30.574348 USER TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:138(altstep:as_EPTF_TitanTest_receive2)->TitanLogTest.ttcn:69(function:f_EPTF_TitanTest_sendResponse) "Send happens - comp0" 2010/Dec/08 18:02:30.574404 PORTEVENT TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:138(altstep:as_EPTF_TitanTest_receive2)->TitanLogTest.ttcn:85(function:f_EPTF_TitanTest_sendResponse) Sent on sync_PCO to mtc @TitanLogTest.Data1 : { senderNum := 0, receiverNum := 2, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } 2010/Dec/08 18:02:30.609037 PORTEVENT TitanLogTest.ttcn:165(function:f_EPTF_TitanTest_behavior) Message enqueued on sync_PCO from mtc @TitanLogTest.Data2 : { senderNum := 2, receiverNum := 0, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 2.000000 } id 5 2010/Dec/08 18:02:30.609101 PORTEVENT TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:128(altstep:as_EPTF_TitanTest_receive2) Receive operation on port sync_PCO succeeded, message from mtc: @TitanLogTest.Data2 : { senderNum := 2, receiverNum := 0, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 2.000000 } id 5 2010/Dec/08 18:02:30.609152 PORTEVENT TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:128(altstep:as_EPTF_TitanTest_receive2) Message with id 5 was extracted from the queue of sync_PCO. 2010/Dec/08 18:02:30.609189 USER TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:129(altstep:as_EPTF_TitanTest_receive2) "Receive happens2 - comp0" 2010/Dec/08 18:02:30.609232 USER TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:132(altstep:as_EPTF_TitanTest_receive2) yyyyyyyyyy 2010/Dec/08 18:02:30.609269 TIMEROP TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:136(altstep:as_EPTF_TitanTest_receive2) Start timer T_send: 0.01 s 2010/Dec/08 18:02:30.619320 TIMEROP TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:137(altstep:as_EPTF_TitanTest_receive2) Timeout T_send: 0.01 s 2010/Dec/08 18:02:30.619416 USER TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:138(altstep:as_EPTF_TitanTest_receive2)->TitanLogTest.ttcn:69(function:f_EPTF_TitanTest_sendResponse) "Send happens - comp0" 2010/Dec/08 18:02:30.619466 PORTEVENT TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:138(altstep:as_EPTF_TitanTest_receive2)->TitanLogTest.ttcn:88(function:f_EPTF_TitanTest_sendResponse) Sent on sync_PCO to mtc @TitanLogTest.Data2 : { senderNum := 0, receiverNum := 1, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 4.000000 } 2010/Dec/08 18:02:30.703369 PORTEVENT TitanLogTest.ttcn:165(function:f_EPTF_TitanTest_behavior) Message enqueued on sync_PCO from mtc @TitanLogTest.Data1 : { senderNum := 1, receiverNum := 0, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } id 6 2010/Dec/08 18:02:30.703491 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:97(altstep:as_EPTF_TitanTest_receive1) Receive operation on port sync_PCO succeeded, message from mtc: @TitanLogTest.Data1 : { senderNum := 1, receiverNum := 0, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } id 6 2010/Dec/08 18:02:30.703542 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:97(altstep:as_EPTF_TitanTest_receive1) Message with id 6 was extracted from the queue of sync_PCO. 2010/Dec/08 18:02:30.703739 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:98(altstep:as_EPTF_TitanTest_receive1) "Receive happens1 - comp0" 2010/Dec/08 18:02:30.703782 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:100(altstep:as_EPTF_TitanTest_receive1) xxxxxxxxxx 2010/Dec/08 18:02:30.703821 TIMEROP TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:103(altstep:as_EPTF_TitanTest_receive1) Start timer T_send: 0.01 s 2010/Dec/08 18:02:30.713879 TIMEROP TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:104(altstep:as_EPTF_TitanTest_receive1) Timeout T_send: 0.01 s 2010/Dec/08 18:02:30.714015 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:105(altstep:as_EPTF_TitanTest_receive1)->TitanLogTest.ttcn:69(function:f_EPTF_TitanTest_sendResponse) "Send happens - comp0" 2010/Dec/08 18:02:30.714072 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:105(altstep:as_EPTF_TitanTest_receive1)->TitanLogTest.ttcn:88(function:f_EPTF_TitanTest_sendResponse) Sent on sync_PCO to mtc @TitanLogTest.Data2 : { senderNum := 0, receiverNum := 2, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 5.000000 } 2010/Dec/08 18:02:30.748306 PORTEVENT TitanLogTest.ttcn:165(function:f_EPTF_TitanTest_behavior) Message enqueued on sync_PCO from mtc @TitanLogTest.Data1 : { senderNum := 2, receiverNum := 0, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } id 7 2010/Dec/08 18:02:30.748399 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:97(altstep:as_EPTF_TitanTest_receive1) Receive operation on port sync_PCO succeeded, message from mtc: @TitanLogTest.Data1 : { senderNum := 2, receiverNum := 0, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } id 7 2010/Dec/08 18:02:30.748449 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:97(altstep:as_EPTF_TitanTest_receive1) Message with id 7 was extracted from the queue of sync_PCO. 2010/Dec/08 18:02:30.748487 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:98(altstep:as_EPTF_TitanTest_receive1) "Receive happens1 - comp0" 2010/Dec/08 18:02:30.748529 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:100(altstep:as_EPTF_TitanTest_receive1) xxxxxxxxxx 2010/Dec/08 18:02:30.748581 TIMEROP TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:103(altstep:as_EPTF_TitanTest_receive1) Start timer T_send: 0.01 s 2010/Dec/08 18:02:30.758632 TIMEROP TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:104(altstep:as_EPTF_TitanTest_receive1) Timeout T_send: 0.01 s 2010/Dec/08 18:02:30.758761 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:105(altstep:as_EPTF_TitanTest_receive1)->TitanLogTest.ttcn:69(function:f_EPTF_TitanTest_sendResponse) "Send happens - comp0" 2010/Dec/08 18:02:30.758851 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:105(altstep:as_EPTF_TitanTest_receive1)->TitanLogTest.ttcn:85(function:f_EPTF_TitanTest_sendResponse) Sent on sync_PCO to mtc @TitanLogTest.Data1 : { senderNum := 0, receiverNum := 1, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } 2010/Dec/08 18:02:30.839548 PORTEVENT TitanLogTest.ttcn:165(function:f_EPTF_TitanTest_behavior) Message enqueued on sync_PCO from mtc @TitanLogTest.Data2 : { senderNum := 1, receiverNum := 0, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 6.000000 } id 8 2010/Dec/08 18:02:30.839655 PORTEVENT TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:128(altstep:as_EPTF_TitanTest_receive2) Receive operation on port sync_PCO succeeded, message from mtc: @TitanLogTest.Data2 : { senderNum := 1, receiverNum := 0, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 6.000000 } id 8 2010/Dec/08 18:02:30.839706 PORTEVENT TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:128(altstep:as_EPTF_TitanTest_receive2) Message with id 8 was extracted from the queue of sync_PCO. 2010/Dec/08 18:02:30.839743 USER TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:129(altstep:as_EPTF_TitanTest_receive2) "Receive happens2 - comp0" 2010/Dec/08 18:02:30.839785 USER TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:132(altstep:as_EPTF_TitanTest_receive2) yyyyyyyyyy 2010/Dec/08 18:02:30.839822 TIMEROP TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:136(altstep:as_EPTF_TitanTest_receive2) Start timer T_send: 0.01 s 2010/Dec/08 18:02:30.849872 TIMEROP TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:137(altstep:as_EPTF_TitanTest_receive2) Timeout T_send: 0.01 s 2010/Dec/08 18:02:30.849987 USER TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:138(altstep:as_EPTF_TitanTest_receive2)->TitanLogTest.ttcn:69(function:f_EPTF_TitanTest_sendResponse) "Send happens - comp0" 2010/Dec/08 18:02:30.850038 PORTEVENT TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:138(altstep:as_EPTF_TitanTest_receive2)->TitanLogTest.ttcn:85(function:f_EPTF_TitanTest_sendResponse) Sent on sync_PCO to mtc @TitanLogTest.Data1 : { senderNum := 0, receiverNum := 2, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } 2010/Dec/08 18:02:30.884549 PORTEVENT TitanLogTest.ttcn:165(function:f_EPTF_TitanTest_behavior) Message enqueued on sync_PCO from mtc @TitanLogTest.Data2 : { senderNum := 2, receiverNum := 0, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 6.000000 } id 9 2010/Dec/08 18:02:30.884613 PORTEVENT TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:128(altstep:as_EPTF_TitanTest_receive2) Receive operation on port sync_PCO succeeded, message from mtc: @TitanLogTest.Data2 : { senderNum := 2, receiverNum := 0, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 6.000000 } id 9 2010/Dec/08 18:02:30.884664 PORTEVENT TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:128(altstep:as_EPTF_TitanTest_receive2) Message with id 9 was extracted from the queue of sync_PCO. 2010/Dec/08 18:02:30.884701 USER TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:129(altstep:as_EPTF_TitanTest_receive2) "Receive happens2 - comp0" 2010/Dec/08 18:02:30.884743 USER TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:132(altstep:as_EPTF_TitanTest_receive2) yyyyyyyyyy 2010/Dec/08 18:02:30.884780 TIMEROP TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:136(altstep:as_EPTF_TitanTest_receive2) Start timer T_send: 0.01 s 2010/Dec/08 18:02:30.894831 TIMEROP TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:137(altstep:as_EPTF_TitanTest_receive2) Timeout T_send: 0.01 s 2010/Dec/08 18:02:30.894940 USER TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:138(altstep:as_EPTF_TitanTest_receive2)->TitanLogTest.ttcn:69(function:f_EPTF_TitanTest_sendResponse) "Send happens - comp0" 2010/Dec/08 18:02:30.894991 PORTEVENT TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:138(altstep:as_EPTF_TitanTest_receive2)->TitanLogTest.ttcn:88(function:f_EPTF_TitanTest_sendResponse) Sent on sync_PCO to mtc @TitanLogTest.Data2 : { senderNum := 0, receiverNum := 1, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 8.000000 } 2010/Dec/08 18:02:30.981167 PORTEVENT TitanLogTest.ttcn:165(function:f_EPTF_TitanTest_behavior) Message enqueued on sync_PCO from mtc @TitanLogTest.Data1 : { senderNum := 1, receiverNum := 0, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } id 10 2010/Dec/08 18:02:30.981291 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:97(altstep:as_EPTF_TitanTest_receive1) Receive operation on port sync_PCO succeeded, message from mtc: @TitanLogTest.Data1 : { senderNum := 1, receiverNum := 0, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } id 10 2010/Dec/08 18:02:30.981356 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:97(altstep:as_EPTF_TitanTest_receive1) Message with id 10 was extracted from the queue of sync_PCO. 2010/Dec/08 18:02:30.981405 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:98(altstep:as_EPTF_TitanTest_receive1) "Receive happens1 - comp0" 2010/Dec/08 18:02:30.981461 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:100(altstep:as_EPTF_TitanTest_receive1) xxxxxxxxxx 2010/Dec/08 18:02:30.981511 TIMEROP TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:103(altstep:as_EPTF_TitanTest_receive1) Start timer T_send: 0.01 s 2010/Dec/08 18:02:30.993856 TIMEROP TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:104(altstep:as_EPTF_TitanTest_receive1) Timeout T_send: 0.01 s 2010/Dec/08 18:02:30.993971 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:105(altstep:as_EPTF_TitanTest_receive1)->TitanLogTest.ttcn:69(function:f_EPTF_TitanTest_sendResponse) "Send happens - comp0" 2010/Dec/08 18:02:30.994034 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:105(altstep:as_EPTF_TitanTest_receive1)->TitanLogTest.ttcn:88(function:f_EPTF_TitanTest_sendResponse) Sent on sync_PCO to mtc @TitanLogTest.Data2 : { senderNum := 0, receiverNum := 2, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 9.000000 } 2010/Dec/08 18:02:31.027621 PORTEVENT TitanLogTest.ttcn:165(function:f_EPTF_TitanTest_behavior) Message enqueued on sync_PCO from mtc @TitanLogTest.Data1 : { senderNum := 2, receiverNum := 0, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } id 11 2010/Dec/08 18:02:31.027736 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:97(altstep:as_EPTF_TitanTest_receive1) Receive operation on port sync_PCO succeeded, message from mtc: @TitanLogTest.Data1 : { senderNum := 2, receiverNum := 0, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } id 11 2010/Dec/08 18:02:31.027799 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:97(altstep:as_EPTF_TitanTest_receive1) Message with id 11 was extracted from the queue of sync_PCO. 2010/Dec/08 18:02:31.027848 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:98(altstep:as_EPTF_TitanTest_receive1) "Receive happens1 - comp0" 2010/Dec/08 18:02:31.027902 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:100(altstep:as_EPTF_TitanTest_receive1) xxxxxxxxxx 2010/Dec/08 18:02:31.027952 TIMEROP TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:103(altstep:as_EPTF_TitanTest_receive1) Start timer T_send: 0.01 s 2010/Dec/08 18:02:31.038086 TIMEROP TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:104(altstep:as_EPTF_TitanTest_receive1) Timeout T_send: 0.01 s 2010/Dec/08 18:02:31.038266 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:105(altstep:as_EPTF_TitanTest_receive1)->TitanLogTest.ttcn:69(function:f_EPTF_TitanTest_sendResponse) "Send happens - comp0" 2010/Dec/08 18:02:31.038413 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:105(altstep:as_EPTF_TitanTest_receive1)->TitanLogTest.ttcn:85(function:f_EPTF_TitanTest_sendResponse) Sent on sync_PCO to mtc @TitanLogTest.Data1 : { senderNum := 0, receiverNum := 1, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } 2010/Dec/08 18:02:31.122291 PORTEVENT TitanLogTest.ttcn:165(function:f_EPTF_TitanTest_behavior) Message enqueued on sync_PCO from mtc @TitanLogTest.Data2 : { senderNum := 1, receiverNum := 0, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 11.000000 } id 12 2010/Dec/08 18:02:31.122392 PORTEVENT TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:128(altstep:as_EPTF_TitanTest_receive2) Receive operation on port sync_PCO succeeded, message from mtc: @TitanLogTest.Data2 : { senderNum := 1, receiverNum := 0, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 11.000000 } id 12 2010/Dec/08 18:02:31.122456 PORTEVENT TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:128(altstep:as_EPTF_TitanTest_receive2) Message with id 12 was extracted from the queue of sync_PCO. 2010/Dec/08 18:02:31.122506 USER TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:129(altstep:as_EPTF_TitanTest_receive2) "Receive happens2 - comp0" 2010/Dec/08 18:02:31.122562 USER TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:132(altstep:as_EPTF_TitanTest_receive2) yyyyyyyyyy 2010/Dec/08 18:02:31.122613 TIMEROP TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:136(altstep:as_EPTF_TitanTest_receive2) Start timer T_send: 0.01 s 2010/Dec/08 18:02:31.135279 TIMEROP TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:137(altstep:as_EPTF_TitanTest_receive2) Timeout T_send: 0.01 s 2010/Dec/08 18:02:31.135418 USER TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:138(altstep:as_EPTF_TitanTest_receive2)->TitanLogTest.ttcn:69(function:f_EPTF_TitanTest_sendResponse) "Send happens - comp0" 2010/Dec/08 18:02:31.135484 PORTEVENT TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:138(altstep:as_EPTF_TitanTest_receive2)->TitanLogTest.ttcn:85(function:f_EPTF_TitanTest_sendResponse) Sent on sync_PCO to mtc @TitanLogTest.Data1 : { senderNum := 0, receiverNum := 2, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } 2010/Dec/08 18:02:31.174241 PORTEVENT TitanLogTest.ttcn:165(function:f_EPTF_TitanTest_behavior) Message enqueued on sync_PCO from mtc @TitanLogTest.Data2 : { senderNum := 2, receiverNum := 0, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 11.000000 } id 13 2010/Dec/08 18:02:31.174323 PORTEVENT TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:128(altstep:as_EPTF_TitanTest_receive2) Receive operation on port sync_PCO succeeded, message from mtc: @TitanLogTest.Data2 : { senderNum := 2, receiverNum := 0, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 11.000000 } id 13 2010/Dec/08 18:02:31.174385 PORTEVENT TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:128(altstep:as_EPTF_TitanTest_receive2) Message with id 13 was extracted from the queue of sync_PCO. 2010/Dec/08 18:02:31.174435 USER TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:129(altstep:as_EPTF_TitanTest_receive2) "Receive happens2 - comp0" 2010/Dec/08 18:02:31.174491 USER TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:132(altstep:as_EPTF_TitanTest_receive2) yyyyyyyyyy 2010/Dec/08 18:02:31.174541 TIMEROP TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:136(altstep:as_EPTF_TitanTest_receive2) Start timer T_send: 0.01 s 2010/Dec/08 18:02:31.185881 TIMEROP TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:137(altstep:as_EPTF_TitanTest_receive2) Timeout T_send: 0.01 s 2010/Dec/08 18:02:31.186053 USER TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:138(altstep:as_EPTF_TitanTest_receive2)->TitanLogTest.ttcn:69(function:f_EPTF_TitanTest_sendResponse) "Send happens - comp0" 2010/Dec/08 18:02:31.186134 PORTEVENT TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:138(altstep:as_EPTF_TitanTest_receive2)->TitanLogTest.ttcn:88(function:f_EPTF_TitanTest_sendResponse) Sent on sync_PCO to mtc @TitanLogTest.Data2 : { senderNum := 0, receiverNum := 1, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 13.000000 } 2010/Dec/08 18:02:31.267509 PORTEVENT TitanLogTest.ttcn:165(function:f_EPTF_TitanTest_behavior) Message enqueued on sync_PCO from mtc @TitanLogTest.Data1 : { senderNum := 1, receiverNum := 0, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } id 14 2010/Dec/08 18:02:31.267621 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:97(altstep:as_EPTF_TitanTest_receive1) Receive operation on port sync_PCO succeeded, message from mtc: @TitanLogTest.Data1 : { senderNum := 1, receiverNum := 0, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } id 14 2010/Dec/08 18:02:31.267683 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:97(altstep:as_EPTF_TitanTest_receive1) Message with id 14 was extracted from the queue of sync_PCO. 2010/Dec/08 18:02:31.267733 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:98(altstep:as_EPTF_TitanTest_receive1) "Receive happens1 - comp0" 2010/Dec/08 18:02:31.267788 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:100(altstep:as_EPTF_TitanTest_receive1) xxxxxxxxxx 2010/Dec/08 18:02:31.267838 TIMEROP TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:103(altstep:as_EPTF_TitanTest_receive1) Start timer T_send: 0.01 s 2010/Dec/08 18:02:31.277981 TIMEROP TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:104(altstep:as_EPTF_TitanTest_receive1) Timeout T_send: 0.01 s 2010/Dec/08 18:02:31.278133 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:105(altstep:as_EPTF_TitanTest_receive1)->TitanLogTest.ttcn:69(function:f_EPTF_TitanTest_sendResponse) "Send happens - comp0" 2010/Dec/08 18:02:31.278202 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:105(altstep:as_EPTF_TitanTest_receive1)->TitanLogTest.ttcn:88(function:f_EPTF_TitanTest_sendResponse) Sent on sync_PCO to mtc @TitanLogTest.Data2 : { senderNum := 0, receiverNum := 2, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 14.000000 } 2010/Dec/08 18:02:31.315801 PORTEVENT TitanLogTest.ttcn:165(function:f_EPTF_TitanTest_behavior) Message enqueued on sync_PCO from mtc @TitanLogTest.Data1 : { senderNum := 2, receiverNum := 0, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } id 15 2010/Dec/08 18:02:31.315903 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:97(altstep:as_EPTF_TitanTest_receive1) Receive operation on port sync_PCO succeeded, message from mtc: @TitanLogTest.Data1 : { senderNum := 2, receiverNum := 0, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } id 15 2010/Dec/08 18:02:31.315965 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:97(altstep:as_EPTF_TitanTest_receive1) Message with id 15 was extracted from the queue of sync_PCO. 2010/Dec/08 18:02:31.316018 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:98(altstep:as_EPTF_TitanTest_receive1) "Receive happens1 - comp0" 2010/Dec/08 18:02:31.316074 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:100(altstep:as_EPTF_TitanTest_receive1) xxxxxxxxxx 2010/Dec/08 18:02:31.316125 TIMEROP TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:103(altstep:as_EPTF_TitanTest_receive1) Start timer T_send: 0.01 s 2010/Dec/08 18:02:31.326394 TIMEROP TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:104(altstep:as_EPTF_TitanTest_receive1) Timeout T_send: 0.01 s 2010/Dec/08 18:02:31.326499 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:105(altstep:as_EPTF_TitanTest_receive1)->TitanLogTest.ttcn:69(function:f_EPTF_TitanTest_sendResponse) "Send happens - comp0" 2010/Dec/08 18:02:31.326561 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:105(altstep:as_EPTF_TitanTest_receive1)->TitanLogTest.ttcn:85(function:f_EPTF_TitanTest_sendResponse) Sent on sync_PCO to mtc @TitanLogTest.Data1 : { senderNum := 0, receiverNum := 1, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } 2010/Dec/08 18:02:31.407515 PORTEVENT TitanLogTest.ttcn:165(function:f_EPTF_TitanTest_behavior) Message enqueued on sync_PCO from mtc @TitanLogTest.Data2 : { senderNum := 1, receiverNum := 0, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 15.000000 } id 16 2010/Dec/08 18:02:31.407594 PORTEVENT TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:128(altstep:as_EPTF_TitanTest_receive2) Receive operation on port sync_PCO succeeded, message from mtc: @TitanLogTest.Data2 : { senderNum := 1, receiverNum := 0, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 15.000000 } id 16 2010/Dec/08 18:02:31.407672 PORTEVENT TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:128(altstep:as_EPTF_TitanTest_receive2) Message with id 16 was extracted from the queue of sync_PCO. 2010/Dec/08 18:02:31.407721 USER TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:129(altstep:as_EPTF_TitanTest_receive2) "Receive happens2 - comp0" 2010/Dec/08 18:02:31.407775 USER TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:132(altstep:as_EPTF_TitanTest_receive2) yyyyyyyyyy 2010/Dec/08 18:02:31.407825 TIMEROP TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:136(altstep:as_EPTF_TitanTest_receive2) Start timer T_send: 0.01 s 2010/Dec/08 18:02:31.417888 TIMEROP TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:137(altstep:as_EPTF_TitanTest_receive2) Timeout T_send: 0.01 s 2010/Dec/08 18:02:31.418037 USER TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:138(altstep:as_EPTF_TitanTest_receive2)->TitanLogTest.ttcn:69(function:f_EPTF_TitanTest_sendResponse) "Send happens - comp0" 2010/Dec/08 18:02:31.418104 PORTEVENT TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:138(altstep:as_EPTF_TitanTest_receive2)->TitanLogTest.ttcn:85(function:f_EPTF_TitanTest_sendResponse) Sent on sync_PCO to mtc @TitanLogTest.Data1 : { senderNum := 0, receiverNum := 2, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } 2010/Dec/08 18:02:31.458064 PORTEVENT TitanLogTest.ttcn:165(function:f_EPTF_TitanTest_behavior) Message enqueued on sync_PCO from mtc @TitanLogTest.Data2 : { senderNum := 2, receiverNum := 0, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 15.000000 } id 17 2010/Dec/08 18:02:31.458145 PORTEVENT TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:128(altstep:as_EPTF_TitanTest_receive2) Receive operation on port sync_PCO succeeded, message from mtc: @TitanLogTest.Data2 : { senderNum := 2, receiverNum := 0, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 15.000000 } id 17 2010/Dec/08 18:02:31.458208 PORTEVENT TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:128(altstep:as_EPTF_TitanTest_receive2) Message with id 17 was extracted from the queue of sync_PCO. 2010/Dec/08 18:02:31.458258 USER TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:129(altstep:as_EPTF_TitanTest_receive2) "Receive happens2 - comp0" 2010/Dec/08 18:02:31.458313 USER TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:132(altstep:as_EPTF_TitanTest_receive2) yyyyyyyyyy 2010/Dec/08 18:02:31.458404 TIMEROP TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:136(altstep:as_EPTF_TitanTest_receive2) Start timer T_send: 0.01 s 2010/Dec/08 18:02:31.469948 TIMEROP TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:137(altstep:as_EPTF_TitanTest_receive2) Timeout T_send: 0.01 s 2010/Dec/08 18:02:31.470051 USER TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:138(altstep:as_EPTF_TitanTest_receive2)->TitanLogTest.ttcn:69(function:f_EPTF_TitanTest_sendResponse) "Send happens - comp0" 2010/Dec/08 18:02:31.470102 PORTEVENT TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:138(altstep:as_EPTF_TitanTest_receive2)->TitanLogTest.ttcn:88(function:f_EPTF_TitanTest_sendResponse) Sent on sync_PCO to mtc @TitanLogTest.Data2 : { senderNum := 0, receiverNum := 1, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 17.000000 } 2010/Dec/08 18:02:31.552421 PORTEVENT TitanLogTest.ttcn:165(function:f_EPTF_TitanTest_behavior) Message enqueued on sync_PCO from mtc @TitanLogTest.Data1 : { senderNum := 1, receiverNum := 0, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } id 18 2010/Dec/08 18:02:31.552527 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:97(altstep:as_EPTF_TitanTest_receive1) Receive operation on port sync_PCO succeeded, message from mtc: @TitanLogTest.Data1 : { senderNum := 1, receiverNum := 0, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } id 18 2010/Dec/08 18:02:31.552578 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:97(altstep:as_EPTF_TitanTest_receive1) Message with id 18 was extracted from the queue of sync_PCO. 2010/Dec/08 18:02:31.552617 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:98(altstep:as_EPTF_TitanTest_receive1) "Receive happens1 - comp0" 2010/Dec/08 18:02:31.552659 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:100(altstep:as_EPTF_TitanTest_receive1) xxxxxxxxxx 2010/Dec/08 18:02:31.552697 TIMEROP TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:103(altstep:as_EPTF_TitanTest_receive1) Start timer T_send: 0.01 s 2010/Dec/08 18:02:31.562750 TIMEROP TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:104(altstep:as_EPTF_TitanTest_receive1) Timeout T_send: 0.01 s 2010/Dec/08 18:02:31.562847 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:105(altstep:as_EPTF_TitanTest_receive1)->TitanLogTest.ttcn:69(function:f_EPTF_TitanTest_sendResponse) "Send happens - comp0" 2010/Dec/08 18:02:31.562897 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:105(altstep:as_EPTF_TitanTest_receive1)->TitanLogTest.ttcn:88(function:f_EPTF_TitanTest_sendResponse) Sent on sync_PCO to mtc @TitanLogTest.Data2 : { senderNum := 0, receiverNum := 2, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 18.000000 } 2010/Dec/08 18:02:31.602517 PORTEVENT TitanLogTest.ttcn:165(function:f_EPTF_TitanTest_behavior) Message enqueued on sync_PCO from mtc @TitanLogTest.Data1 : { senderNum := 2, receiverNum := 0, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } id 19 2010/Dec/08 18:02:31.602675 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:97(altstep:as_EPTF_TitanTest_receive1) Receive operation on port sync_PCO succeeded, message from mtc: @TitanLogTest.Data1 : { senderNum := 2, receiverNum := 0, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } id 19 2010/Dec/08 18:02:31.602730 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:97(altstep:as_EPTF_TitanTest_receive1) Message with id 19 was extracted from the queue of sync_PCO. 2010/Dec/08 18:02:31.602770 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:98(altstep:as_EPTF_TitanTest_receive1) "Receive happens1 - comp0" 2010/Dec/08 18:02:31.602912 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:100(altstep:as_EPTF_TitanTest_receive1) xxxxxxxxxx 2010/Dec/08 18:02:31.602954 TIMEROP TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:103(altstep:as_EPTF_TitanTest_receive1) Start timer T_send: 0.01 s 2010/Dec/08 18:02:31.613920 TIMEROP TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:104(altstep:as_EPTF_TitanTest_receive1) Timeout T_send: 0.01 s 2010/Dec/08 18:02:31.614012 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:105(altstep:as_EPTF_TitanTest_receive1)->TitanLogTest.ttcn:69(function:f_EPTF_TitanTest_sendResponse) "Send happens - comp0" 2010/Dec/08 18:02:31.614063 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:105(altstep:as_EPTF_TitanTest_receive1)->TitanLogTest.ttcn:85(function:f_EPTF_TitanTest_sendResponse) Sent on sync_PCO to mtc @TitanLogTest.Data1 : { senderNum := 0, receiverNum := 1, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } 2010/Dec/08 18:02:31.703823 PORTEVENT TitanLogTest.ttcn:165(function:f_EPTF_TitanTest_behavior) Message enqueued on sync_PCO from mtc @TitanLogTest.Data2 : { senderNum := 1, receiverNum := 0, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 19.000000 } id 20 2010/Dec/08 18:02:31.703887 PORTEVENT TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:128(altstep:as_EPTF_TitanTest_receive2) Receive operation on port sync_PCO succeeded, message from mtc: @TitanLogTest.Data2 : { senderNum := 1, receiverNum := 0, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 19.000000 } id 20 2010/Dec/08 18:02:31.703938 PORTEVENT TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:128(altstep:as_EPTF_TitanTest_receive2) Message with id 20 was extracted from the queue of sync_PCO. 2010/Dec/08 18:02:31.703975 USER TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:129(altstep:as_EPTF_TitanTest_receive2) "Receive happens2 - comp0" 2010/Dec/08 18:02:31.704018 USER TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:132(altstep:as_EPTF_TitanTest_receive2) yyyyyyyyyy 2010/Dec/08 18:02:31.704055 TIMEROP TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:136(altstep:as_EPTF_TitanTest_receive2) Start timer T_send: 0.01 s 2010/Dec/08 18:02:31.714628 TIMEROP TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:137(altstep:as_EPTF_TitanTest_receive2) Timeout T_send: 0.01 s 2010/Dec/08 18:02:31.714722 USER TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:138(altstep:as_EPTF_TitanTest_receive2)->TitanLogTest.ttcn:69(function:f_EPTF_TitanTest_sendResponse) "Send happens - comp0" 2010/Dec/08 18:02:31.714789 PORTEVENT TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:138(altstep:as_EPTF_TitanTest_receive2)->TitanLogTest.ttcn:85(function:f_EPTF_TitanTest_sendResponse) Sent on sync_PCO to mtc @TitanLogTest.Data1 : { senderNum := 0, receiverNum := 2, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } 2010/Dec/08 18:02:31.748878 PORTEVENT TitanLogTest.ttcn:165(function:f_EPTF_TitanTest_behavior) Message enqueued on sync_PCO from mtc @TitanLogTest.Data2 : { senderNum := 2, receiverNum := 0, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 19.000000 } id 21 2010/Dec/08 18:02:31.748943 PORTEVENT TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:128(altstep:as_EPTF_TitanTest_receive2) Receive operation on port sync_PCO succeeded, message from mtc: @TitanLogTest.Data2 : { senderNum := 2, receiverNum := 0, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 19.000000 } id 21 2010/Dec/08 18:02:31.748994 PORTEVENT TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:128(altstep:as_EPTF_TitanTest_receive2) Message with id 21 was extracted from the queue of sync_PCO. 2010/Dec/08 18:02:31.749080 USER TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:129(altstep:as_EPTF_TitanTest_receive2) "Receive happens2 - comp0" 2010/Dec/08 18:02:31.749123 USER TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:132(altstep:as_EPTF_TitanTest_receive2) yyyyyyyyyy 2010/Dec/08 18:02:31.749161 TIMEROP TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:136(altstep:as_EPTF_TitanTest_receive2) Start timer T_send: 0.01 s 2010/Dec/08 18:02:31.761810 TIMEROP TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:137(altstep:as_EPTF_TitanTest_receive2) Timeout T_send: 0.01 s 2010/Dec/08 18:02:31.761907 USER TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:138(altstep:as_EPTF_TitanTest_receive2)->TitanLogTest.ttcn:69(function:f_EPTF_TitanTest_sendResponse) "Send happens - comp0" 2010/Dec/08 18:02:31.761958 PORTEVENT TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:138(altstep:as_EPTF_TitanTest_receive2)->TitanLogTest.ttcn:88(function:f_EPTF_TitanTest_sendResponse) Sent on sync_PCO to mtc @TitanLogTest.Data2 : { senderNum := 0, receiverNum := 1, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 22.000000 } 2010/Dec/08 18:02:31.844569 PORTEVENT TitanLogTest.ttcn:165(function:f_EPTF_TitanTest_behavior) Message enqueued on sync_PCO from mtc @TitanLogTest.Data1 : { senderNum := 1, receiverNum := 0, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } id 22 2010/Dec/08 18:02:31.844653 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:97(altstep:as_EPTF_TitanTest_receive1) Receive operation on port sync_PCO succeeded, message from mtc: @TitanLogTest.Data1 : { senderNum := 1, receiverNum := 0, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } id 22 2010/Dec/08 18:02:31.844703 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:97(altstep:as_EPTF_TitanTest_receive1) Message with id 22 was extracted from the queue of sync_PCO. 2010/Dec/08 18:02:31.844741 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:98(altstep:as_EPTF_TitanTest_receive1) "Receive happens1 - comp0" 2010/Dec/08 18:02:31.844783 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:100(altstep:as_EPTF_TitanTest_receive1) xxxxxxxxxx 2010/Dec/08 18:02:31.844821 TIMEROP TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:103(altstep:as_EPTF_TitanTest_receive1) Start timer T_send: 0.01 s 2010/Dec/08 18:02:31.854908 TIMEROP TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:104(altstep:as_EPTF_TitanTest_receive1) Timeout T_send: 0.01 s 2010/Dec/08 18:02:31.855011 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:105(altstep:as_EPTF_TitanTest_receive1)->TitanLogTest.ttcn:69(function:f_EPTF_TitanTest_sendResponse) "Send happens - comp0" 2010/Dec/08 18:02:31.855063 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:105(altstep:as_EPTF_TitanTest_receive1)->TitanLogTest.ttcn:88(function:f_EPTF_TitanTest_sendResponse) Sent on sync_PCO to mtc @TitanLogTest.Data2 : { senderNum := 0, receiverNum := 2, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 23.000000 } 2010/Dec/08 18:02:31.891223 PORTEVENT TitanLogTest.ttcn:165(function:f_EPTF_TitanTest_behavior) Message enqueued on sync_PCO from mtc @TitanLogTest.Data1 : { senderNum := 2, receiverNum := 0, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } id 23 2010/Dec/08 18:02:31.891348 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:97(altstep:as_EPTF_TitanTest_receive1) Receive operation on port sync_PCO succeeded, message from mtc: @TitanLogTest.Data1 : { senderNum := 2, receiverNum := 0, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } id 23 2010/Dec/08 18:02:31.891971 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:97(altstep:as_EPTF_TitanTest_receive1) Message with id 23 was extracted from the queue of sync_PCO. 2010/Dec/08 18:02:31.892015 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:98(altstep:as_EPTF_TitanTest_receive1) "Receive happens1 - comp0" 2010/Dec/08 18:02:31.892059 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:100(altstep:as_EPTF_TitanTest_receive1) xxxxxxxxxx 2010/Dec/08 18:02:31.892097 TIMEROP TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:103(altstep:as_EPTF_TitanTest_receive1) Start timer T_send: 0.01 s 2010/Dec/08 18:02:31.902153 TIMEROP TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:104(altstep:as_EPTF_TitanTest_receive1) Timeout T_send: 0.01 s 2010/Dec/08 18:02:31.902243 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:105(altstep:as_EPTF_TitanTest_receive1)->TitanLogTest.ttcn:69(function:f_EPTF_TitanTest_sendResponse) "Send happens - comp0" 2010/Dec/08 18:02:31.902293 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:105(altstep:as_EPTF_TitanTest_receive1)->TitanLogTest.ttcn:85(function:f_EPTF_TitanTest_sendResponse) Sent on sync_PCO to mtc @TitanLogTest.Data1 : { senderNum := 0, receiverNum := 1, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } 2010/Dec/08 18:02:31.988728 PORTEVENT TitanLogTest.ttcn:165(function:f_EPTF_TitanTest_behavior) Message enqueued on sync_PCO from mtc @TitanLogTest.Data2 : { senderNum := 1, receiverNum := 0, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 24.000000 } id 24 2010/Dec/08 18:02:31.988798 PORTEVENT TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:128(altstep:as_EPTF_TitanTest_receive2) Receive operation on port sync_PCO succeeded, message from mtc: @TitanLogTest.Data2 : { senderNum := 1, receiverNum := 0, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 24.000000 } id 24 2010/Dec/08 18:02:31.988849 PORTEVENT TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:128(altstep:as_EPTF_TitanTest_receive2) Message with id 24 was extracted from the queue of sync_PCO. 2010/Dec/08 18:02:31.988886 USER TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:129(altstep:as_EPTF_TitanTest_receive2) "Receive happens2 - comp0" 2010/Dec/08 18:02:31.988929 USER TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:132(altstep:as_EPTF_TitanTest_receive2) yyyyyyyyyy 2010/Dec/08 18:02:31.988966 TIMEROP TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:136(altstep:as_EPTF_TitanTest_receive2) Start timer T_send: 0.01 s 2010/Dec/08 18:02:32.002688 TIMEROP TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:137(altstep:as_EPTF_TitanTest_receive2) Timeout T_send: 0.01 s 2010/Dec/08 18:02:32.002843 USER TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:138(altstep:as_EPTF_TitanTest_receive2)->TitanLogTest.ttcn:69(function:f_EPTF_TitanTest_sendResponse) "Send happens - comp0" 2010/Dec/08 18:02:32.002896 PORTEVENT TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:138(altstep:as_EPTF_TitanTest_receive2)->TitanLogTest.ttcn:85(function:f_EPTF_TitanTest_sendResponse) Sent on sync_PCO to mtc @TitanLogTest.Data1 : { senderNum := 0, receiverNum := 2, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } 2010/Dec/08 18:02:32.036723 PORTEVENT TitanLogTest.ttcn:165(function:f_EPTF_TitanTest_behavior) Message enqueued on sync_PCO from mtc @TitanLogTest.Data2 : { senderNum := 2, receiverNum := 0, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 24.000000 } id 25 2010/Dec/08 18:02:32.036790 PORTEVENT TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:128(altstep:as_EPTF_TitanTest_receive2) Receive operation on port sync_PCO succeeded, message from mtc: @TitanLogTest.Data2 : { senderNum := 2, receiverNum := 0, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 24.000000 } id 25 2010/Dec/08 18:02:32.036962 PORTEVENT TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:128(altstep:as_EPTF_TitanTest_receive2) Message with id 25 was extracted from the queue of sync_PCO. 2010/Dec/08 18:02:32.037013 USER TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:129(altstep:as_EPTF_TitanTest_receive2) "Receive happens2 - comp0" 2010/Dec/08 18:02:32.037068 USER TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:132(altstep:as_EPTF_TitanTest_receive2) yyyyyyyyyy 2010/Dec/08 18:02:32.037119 TIMEROP TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:136(altstep:as_EPTF_TitanTest_receive2) Start timer T_send: 0.01 s 2010/Dec/08 18:02:32.051539 TIMEROP TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:137(altstep:as_EPTF_TitanTest_receive2) Timeout T_send: 0.01 s 2010/Dec/08 18:02:32.051664 USER TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:138(altstep:as_EPTF_TitanTest_receive2)->TitanLogTest.ttcn:69(function:f_EPTF_TitanTest_sendResponse) "Send happens - comp0" 2010/Dec/08 18:02:32.051730 PORTEVENT TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:138(altstep:as_EPTF_TitanTest_receive2)->TitanLogTest.ttcn:88(function:f_EPTF_TitanTest_sendResponse) Sent on sync_PCO to mtc @TitanLogTest.Data2 : { senderNum := 0, receiverNum := 1, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 26.000000 } 2010/Dec/08 18:02:32.132006 PORTEVENT TitanLogTest.ttcn:165(function:f_EPTF_TitanTest_behavior) Message enqueued on sync_PCO from mtc @TitanLogTest.Data1 : { senderNum := 1, receiverNum := 0, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } id 26 2010/Dec/08 18:02:32.132109 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:97(altstep:as_EPTF_TitanTest_receive1) Receive operation on port sync_PCO succeeded, message from mtc: @TitanLogTest.Data1 : { senderNum := 1, receiverNum := 0, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } id 26 2010/Dec/08 18:02:32.132172 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:97(altstep:as_EPTF_TitanTest_receive1) Message with id 26 was extracted from the queue of sync_PCO. 2010/Dec/08 18:02:32.132234 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:98(altstep:as_EPTF_TitanTest_receive1) "Receive happens1 - comp0" 2010/Dec/08 18:02:32.132289 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:100(altstep:as_EPTF_TitanTest_receive1) xxxxxxxxxx 2010/Dec/08 18:02:32.132340 TIMEROP TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:103(altstep:as_EPTF_TitanTest_receive1) Start timer T_send: 0.01 s 2010/Dec/08 18:02:32.142486 TIMEROP TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:104(altstep:as_EPTF_TitanTest_receive1) Timeout T_send: 0.01 s 2010/Dec/08 18:02:32.142666 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:105(altstep:as_EPTF_TitanTest_receive1)->TitanLogTest.ttcn:69(function:f_EPTF_TitanTest_sendResponse) "Send happens - comp0" 2010/Dec/08 18:02:32.142739 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:105(altstep:as_EPTF_TitanTest_receive1)->TitanLogTest.ttcn:88(function:f_EPTF_TitanTest_sendResponse) Sent on sync_PCO to mtc @TitanLogTest.Data2 : { senderNum := 0, receiverNum := 2, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 27.000000 } 2010/Dec/08 18:02:32.179307 PORTEVENT TitanLogTest.ttcn:165(function:f_EPTF_TitanTest_behavior) Message enqueued on sync_PCO from mtc @TitanLogTest.Data1 : { senderNum := 2, receiverNum := 0, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } id 27 2010/Dec/08 18:02:32.179530 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:97(altstep:as_EPTF_TitanTest_receive1) Receive operation on port sync_PCO succeeded, message from mtc: @TitanLogTest.Data1 : { senderNum := 2, receiverNum := 0, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } id 27 2010/Dec/08 18:02:32.179593 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:97(altstep:as_EPTF_TitanTest_receive1) Message with id 27 was extracted from the queue of sync_PCO. 2010/Dec/08 18:02:32.179642 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:98(altstep:as_EPTF_TitanTest_receive1) "Receive happens1 - comp0" 2010/Dec/08 18:02:32.179729 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:100(altstep:as_EPTF_TitanTest_receive1) xxxxxxxxxx 2010/Dec/08 18:02:32.179780 TIMEROP TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:103(altstep:as_EPTF_TitanTest_receive1) Start timer T_send: 0.01 s 2010/Dec/08 18:02:32.189891 TIMEROP TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:104(altstep:as_EPTF_TitanTest_receive1) Timeout T_send: 0.01 s 2010/Dec/08 18:02:32.190005 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:105(altstep:as_EPTF_TitanTest_receive1)->TitanLogTest.ttcn:69(function:f_EPTF_TitanTest_sendResponse) "Send happens - comp0" 2010/Dec/08 18:02:32.190068 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:105(altstep:as_EPTF_TitanTest_receive1)->TitanLogTest.ttcn:85(function:f_EPTF_TitanTest_sendResponse) Sent on sync_PCO to mtc @TitanLogTest.Data1 : { senderNum := 0, receiverNum := 1, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } 2010/Dec/08 18:02:32.271587 PORTEVENT TitanLogTest.ttcn:165(function:f_EPTF_TitanTest_behavior) Message enqueued on sync_PCO from mtc @TitanLogTest.Data2 : { senderNum := 1, receiverNum := 0, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 28.000000 } id 28 2010/Dec/08 18:02:32.271666 PORTEVENT TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:128(altstep:as_EPTF_TitanTest_receive2) Receive operation on port sync_PCO succeeded, message from mtc: @TitanLogTest.Data2 : { senderNum := 1, receiverNum := 0, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 28.000000 } id 28 2010/Dec/08 18:02:32.271751 PORTEVENT TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:128(altstep:as_EPTF_TitanTest_receive2) Message with id 28 was extracted from the queue of sync_PCO. 2010/Dec/08 18:02:32.271801 USER TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:129(altstep:as_EPTF_TitanTest_receive2) "Receive happens2 - comp0" 2010/Dec/08 18:02:32.271856 USER TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:132(altstep:as_EPTF_TitanTest_receive2) yyyyyyyyyy 2010/Dec/08 18:02:32.271905 TIMEROP TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:136(altstep:as_EPTF_TitanTest_receive2) Start timer T_send: 0.01 s 2010/Dec/08 18:02:32.284563 TIMEROP TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:137(altstep:as_EPTF_TitanTest_receive2) Timeout T_send: 0.01 s 2010/Dec/08 18:02:32.284695 USER TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:138(altstep:as_EPTF_TitanTest_receive2)->TitanLogTest.ttcn:69(function:f_EPTF_TitanTest_sendResponse) "Send happens - comp0" 2010/Dec/08 18:02:32.284759 PORTEVENT TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:138(altstep:as_EPTF_TitanTest_receive2)->TitanLogTest.ttcn:85(function:f_EPTF_TitanTest_sendResponse) Sent on sync_PCO to mtc @TitanLogTest.Data1 : { senderNum := 0, receiverNum := 2, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } 2010/Dec/08 18:02:32.319758 PORTEVENT TitanLogTest.ttcn:165(function:f_EPTF_TitanTest_behavior) Message enqueued on sync_PCO from mtc @TitanLogTest.Data2 : { senderNum := 2, receiverNum := 0, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 28.000000 } id 29 2010/Dec/08 18:02:32.319839 PORTEVENT TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:128(altstep:as_EPTF_TitanTest_receive2) Receive operation on port sync_PCO succeeded, message from mtc: @TitanLogTest.Data2 : { senderNum := 2, receiverNum := 0, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 28.000000 } id 29 2010/Dec/08 18:02:32.319903 PORTEVENT TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:128(altstep:as_EPTF_TitanTest_receive2) Message with id 29 was extracted from the queue of sync_PCO. 2010/Dec/08 18:02:32.319953 USER TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:129(altstep:as_EPTF_TitanTest_receive2) "Receive happens2 - comp0" 2010/Dec/08 18:02:32.320008 USER TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:132(altstep:as_EPTF_TitanTest_receive2) yyyyyyyyyy 2010/Dec/08 18:02:32.320058 TIMEROP TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:136(altstep:as_EPTF_TitanTest_receive2) Start timer T_send: 0.01 s 2010/Dec/08 18:02:32.330177 TIMEROP TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:137(altstep:as_EPTF_TitanTest_receive2) Timeout T_send: 0.01 s 2010/Dec/08 18:02:32.330485 USER TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:138(altstep:as_EPTF_TitanTest_receive2)->TitanLogTest.ttcn:69(function:f_EPTF_TitanTest_sendResponse) "Send happens - comp0" 2010/Dec/08 18:02:32.330568 PORTEVENT TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:138(altstep:as_EPTF_TitanTest_receive2)->TitanLogTest.ttcn:88(function:f_EPTF_TitanTest_sendResponse) Sent on sync_PCO to mtc @TitanLogTest.Data2 : { senderNum := 0, receiverNum := 1, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 30.000000 } 2010/Dec/08 18:02:32.412414 PORTEVENT TitanLogTest.ttcn:165(function:f_EPTF_TitanTest_behavior) Message enqueued on sync_PCO from mtc @TitanLogTest.Data1 : { senderNum := 1, receiverNum := 0, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } id 30 2010/Dec/08 18:02:32.412512 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:97(altstep:as_EPTF_TitanTest_receive1) Receive operation on port sync_PCO succeeded, message from mtc: @TitanLogTest.Data1 : { senderNum := 1, receiverNum := 0, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } id 30 2010/Dec/08 18:02:32.412574 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:97(altstep:as_EPTF_TitanTest_receive1) Message with id 30 was extracted from the queue of sync_PCO. 2010/Dec/08 18:02:32.412623 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:98(altstep:as_EPTF_TitanTest_receive1) "Receive happens1 - comp0" 2010/Dec/08 18:02:32.412677 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:100(altstep:as_EPTF_TitanTest_receive1) xxxxxxxxxx 2010/Dec/08 18:02:32.412726 TIMEROP TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:103(altstep:as_EPTF_TitanTest_receive1) Start timer T_send: 0.01 s 2010/Dec/08 18:02:32.422791 TIMEROP TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:104(altstep:as_EPTF_TitanTest_receive1) Timeout T_send: 0.01 s 2010/Dec/08 18:02:32.422901 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:105(altstep:as_EPTF_TitanTest_receive1)->TitanLogTest.ttcn:69(function:f_EPTF_TitanTest_sendResponse) "Send happens - comp0" 2010/Dec/08 18:02:32.422963 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:105(altstep:as_EPTF_TitanTest_receive1)->TitanLogTest.ttcn:88(function:f_EPTF_TitanTest_sendResponse) Sent on sync_PCO to mtc @TitanLogTest.Data2 : { senderNum := 0, receiverNum := 2, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 31.000000 } 2010/Dec/08 18:02:32.459526 PORTEVENT TitanLogTest.ttcn:165(function:f_EPTF_TitanTest_behavior) Message enqueued on sync_PCO from mtc @TitanLogTest.Data1 : { senderNum := 2, receiverNum := 0, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } id 31 2010/Dec/08 18:02:32.459638 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:97(altstep:as_EPTF_TitanTest_receive1) Receive operation on port sync_PCO succeeded, message from mtc: @TitanLogTest.Data1 : { senderNum := 2, receiverNum := 0, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } id 31 2010/Dec/08 18:02:32.459700 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:97(altstep:as_EPTF_TitanTest_receive1) Message with id 31 was extracted from the queue of sync_PCO. 2010/Dec/08 18:02:32.459749 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:98(altstep:as_EPTF_TitanTest_receive1) "Receive happens1 - comp0" 2010/Dec/08 18:02:32.459815 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:100(altstep:as_EPTF_TitanTest_receive1) xxxxxxxxxx 2010/Dec/08 18:02:32.459865 TIMEROP TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:103(altstep:as_EPTF_TitanTest_receive1) Start timer T_send: 0.01 s 2010/Dec/08 18:02:32.469929 TIMEROP TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:104(altstep:as_EPTF_TitanTest_receive1) Timeout T_send: 0.01 s 2010/Dec/08 18:02:32.470038 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:105(altstep:as_EPTF_TitanTest_receive1)->TitanLogTest.ttcn:69(function:f_EPTF_TitanTest_sendResponse) "Send happens - comp0" 2010/Dec/08 18:02:32.470101 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:105(altstep:as_EPTF_TitanTest_receive1)->TitanLogTest.ttcn:85(function:f_EPTF_TitanTest_sendResponse) Sent on sync_PCO to mtc @TitanLogTest.Data1 : { senderNum := 0, receiverNum := 1, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } 2010/Dec/08 18:02:32.552471 PORTEVENT TitanLogTest.ttcn:165(function:f_EPTF_TitanTest_behavior) Message enqueued on sync_PCO from mtc @TitanLogTest.Data2 : { senderNum := 1, receiverNum := 0, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 33.000000 } id 32 2010/Dec/08 18:02:32.552555 PORTEVENT TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:128(altstep:as_EPTF_TitanTest_receive2) Receive operation on port sync_PCO succeeded, message from mtc: @TitanLogTest.Data2 : { senderNum := 1, receiverNum := 0, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 33.000000 } id 32 2010/Dec/08 18:02:32.552619 PORTEVENT TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:128(altstep:as_EPTF_TitanTest_receive2) Message with id 32 was extracted from the queue of sync_PCO. 2010/Dec/08 18:02:32.552670 USER TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:129(altstep:as_EPTF_TitanTest_receive2) "Receive happens2 - comp0" 2010/Dec/08 18:02:32.552725 USER TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:132(altstep:as_EPTF_TitanTest_receive2) yyyyyyyyyy 2010/Dec/08 18:02:32.552775 TIMEROP TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:136(altstep:as_EPTF_TitanTest_receive2) Start timer T_send: 0.01 s 2010/Dec/08 18:02:32.562845 TIMEROP TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:137(altstep:as_EPTF_TitanTest_receive2) Timeout T_send: 0.01 s 2010/Dec/08 18:02:32.562953 USER TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:138(altstep:as_EPTF_TitanTest_receive2)->TitanLogTest.ttcn:69(function:f_EPTF_TitanTest_sendResponse) "Send happens - comp0" 2010/Dec/08 18:02:32.563091 PORTEVENT TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:138(altstep:as_EPTF_TitanTest_receive2)->TitanLogTest.ttcn:85(function:f_EPTF_TitanTest_sendResponse) Sent on sync_PCO to mtc @TitanLogTest.Data1 : { senderNum := 0, receiverNum := 2, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } 2010/Dec/08 18:02:32.602103 PORTEVENT TitanLogTest.ttcn:165(function:f_EPTF_TitanTest_behavior) Message enqueued on sync_PCO from mtc @TitanLogTest.Data2 : { senderNum := 2, receiverNum := 0, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 33.000000 } id 33 2010/Dec/08 18:02:32.602183 PORTEVENT TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:128(altstep:as_EPTF_TitanTest_receive2) Receive operation on port sync_PCO succeeded, message from mtc: @TitanLogTest.Data2 : { senderNum := 2, receiverNum := 0, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 33.000000 } id 33 2010/Dec/08 18:02:32.602246 PORTEVENT TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:128(altstep:as_EPTF_TitanTest_receive2) Message with id 33 was extracted from the queue of sync_PCO. 2010/Dec/08 18:02:32.602296 USER TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:129(altstep:as_EPTF_TitanTest_receive2) "Receive happens2 - comp0" 2010/Dec/08 18:02:32.602351 USER TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:132(altstep:as_EPTF_TitanTest_receive2) yyyyyyyyyy 2010/Dec/08 18:02:32.602401 TIMEROP TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:136(altstep:as_EPTF_TitanTest_receive2) Start timer T_send: 0.01 s 2010/Dec/08 18:02:32.615269 TIMEROP TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:137(altstep:as_EPTF_TitanTest_receive2) Timeout T_send: 0.01 s 2010/Dec/08 18:02:32.615392 USER TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:138(altstep:as_EPTF_TitanTest_receive2)->TitanLogTest.ttcn:69(function:f_EPTF_TitanTest_sendResponse) "Send happens - comp0" 2010/Dec/08 18:02:32.615456 PORTEVENT TitanLogTest.ttcn:168(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:138(altstep:as_EPTF_TitanTest_receive2)->TitanLogTest.ttcn:88(function:f_EPTF_TitanTest_sendResponse) Sent on sync_PCO to mtc @TitanLogTest.Data2 : { senderNum := 0, receiverNum := 1, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 35.000000 } 2010/Dec/08 18:02:32.696039 PORTEVENT TitanLogTest.ttcn:165(function:f_EPTF_TitanTest_behavior) Message enqueued on sync_PCO from mtc @TitanLogTest.Data1 : { senderNum := 1, receiverNum := 0, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } id 34 2010/Dec/08 18:02:32.696137 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:97(altstep:as_EPTF_TitanTest_receive1) Receive operation on port sync_PCO succeeded, message from mtc: @TitanLogTest.Data1 : { senderNum := 1, receiverNum := 0, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } id 34 2010/Dec/08 18:02:32.696199 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:97(altstep:as_EPTF_TitanTest_receive1) Message with id 34 was extracted from the queue of sync_PCO. 2010/Dec/08 18:02:32.696249 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:98(altstep:as_EPTF_TitanTest_receive1) "Receive happens1 - comp0" 2010/Dec/08 18:02:32.696304 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:100(altstep:as_EPTF_TitanTest_receive1) xxxxxxxxxx 2010/Dec/08 18:02:32.696354 TIMEROP TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:103(altstep:as_EPTF_TitanTest_receive1) Start timer T_send: 0.01 s 2010/Dec/08 18:02:32.706417 TIMEROP TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:104(altstep:as_EPTF_TitanTest_receive1) Timeout T_send: 0.01 s 2010/Dec/08 18:02:32.706619 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:105(altstep:as_EPTF_TitanTest_receive1)->TitanLogTest.ttcn:69(function:f_EPTF_TitanTest_sendResponse) "Send happens - comp0" 2010/Dec/08 18:02:32.706683 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:105(altstep:as_EPTF_TitanTest_receive1)->TitanLogTest.ttcn:88(function:f_EPTF_TitanTest_sendResponse) Sent on sync_PCO to mtc @TitanLogTest.Data2 : { senderNum := 0, receiverNum := 2, chdata := "-+=!@#$%^&*01234567890abcdefghijklmnopqrstuvwxyz~~", fldata := 36.000000 } 2010/Dec/08 18:02:32.740816 PORTEVENT TitanLogTest.ttcn:165(function:f_EPTF_TitanTest_behavior) Message enqueued on sync_PCO from mtc @TitanLogTest.Data1 : { senderNum := 2, receiverNum := 0, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } id 35 2010/Dec/08 18:02:32.740920 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:97(altstep:as_EPTF_TitanTest_receive1) Receive operation on port sync_PCO succeeded, message from mtc: @TitanLogTest.Data1 : { senderNum := 2, receiverNum := 0, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } id 35 2010/Dec/08 18:02:32.740982 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:97(altstep:as_EPTF_TitanTest_receive1) Message with id 35 was extracted from the queue of sync_PCO. 2010/Dec/08 18:02:32.741031 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:98(altstep:as_EPTF_TitanTest_receive1) "Receive happens1 - comp0" 2010/Dec/08 18:02:32.741086 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:100(altstep:as_EPTF_TitanTest_receive1) xxxxxxxxxx 2010/Dec/08 18:02:32.741135 TIMEROP TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:103(altstep:as_EPTF_TitanTest_receive1) Start timer T_send: 0.01 s 2010/Dec/08 18:02:32.753997 TIMEROP TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:104(altstep:as_EPTF_TitanTest_receive1) Timeout T_send: 0.01 s 2010/Dec/08 18:02:32.754103 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:105(altstep:as_EPTF_TitanTest_receive1)->TitanLogTest.ttcn:69(function:f_EPTF_TitanTest_sendResponse) "Send happens - comp0" 2010/Dec/08 18:02:32.754370 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:105(altstep:as_EPTF_TitanTest_receive1)->TitanLogTest.ttcn:85(function:f_EPTF_TitanTest_sendResponse) Sent on sync_PCO to mtc @TitanLogTest.Data1 : { senderNum := 0, receiverNum := 1, chdata := "+-=!@#$%^&*abcdefghijklmnopqrstuvwxyz01234567890~~" } 2010/Dec/08 18:02:33.258570 PORTEVENT TitanLogTest.ttcn:165(function:f_EPTF_TitanTest_behavior) Message enqueued on sync_PCO from mtc @TitanLogTest.StopOk : { senderNum := 0 } id 36 2010/Dec/08 18:02:33.258675 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:116(altstep:as_EPTF_TitanTest_receive1) Receive operation on port sync_PCO succeeded, message from mtc: @TitanLogTest.StopOk : { senderNum := 0 } id 36 2010/Dec/08 18:02:33.258731 PORTEVENT TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:116(altstep:as_EPTF_TitanTest_receive1) Message with id 36 was extracted from the queue of sync_PCO. 2010/Dec/08 18:02:33.258780 USER TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:117(altstep:as_EPTF_TitanTest_receive1) "StopOk happens1 - comp0" 2010/Dec/08 18:02:33.258834 TIMEROP TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:119(altstep:as_EPTF_TitanTest_receive1) Start timer T_send: 0.01 s 2010/Dec/08 18:02:33.269883 TIMEROP TitanLogTest.ttcn:167(function:f_EPTF_TitanTest_behavior)->TitanLogTest.ttcn:120(altstep:as_EPTF_TitanTest_receive1) Timeout T_send: 0.01 s 2010/Dec/08 18:02:33.269973 USER TitanLogTest.ttcn:170(function:f_EPTF_TitanTest_behavior) "Finished behaviour - comp0" 2010/Dec/08 18:02:33.270100 PARALLEL - Function f_EPTF_TitanTest_behavior finished. PTC terminates. 2010/Dec/08 18:02:33.270150 PARALLEL - Terminating component type TitanLogTest.TitanTest_CT. 2010/Dec/08 18:02:33.270193 TIMEROP - Stop timer T_testDuration: 5 s 2010/Dec/08 18:02:33.270275 PORTEVENT - Port EPTF_Base_MgmtIf was stopped. 2010/Dec/08 18:02:33.270332 PORTEVENT - Removing unterminated connection between port sync_PCO and mtc:sync_PCO. 2010/Dec/08 18:02:33.270469 PORTEVENT - Port sync_PCO was stopped. 2010/Dec/08 18:02:33.270514 PARALLEL - Component type TitanLogTest.TitanTest_CT was shut down inside testcase Titan_Logging_BasicTest. 2010/Dec/08 18:02:33.270594 VERDICTOP - Final verdict of PTC: none 2010/Dec/08 18:02:33.272961 EXECUTOR - Disconnected from MC. 2010/Dec/08 18:02:33.273044 EXECUTOR - TTCN-3 Parallel Test Component finished.