Project

General

Profile

Actions

Bug #6503

open

TTCN3 race condition on shutdown

Added by daniel about 10 hours ago. Updated about 10 hours ago.

Status:
New
Priority:
Normal
Assignee:
Target version:
-
Start date:
07/17/2024
Due date:
% Done:

0%

Spec Reference:

Description

This is a variation of #6273

This time the SGSN sends an ALIVE message back right as we shut down the components. See the log excerpt (also attached):

05:03:21.066002 mtc RAW_NS.ttcnpp:106 Creating new alive PTC with component type NS_Provider_IPL4.NS_Provider_IPL4_CT, component name: -provIP0.
05:03:21.074156 6 - Warning: A plug-in from the same path `libjunitlogger2' is already active, skipping plug-in
05:03:21.074302 6 - TTCN-3 Parallel Test Component started on cdb21e693772. Component reference: -provIP0(6), component type: NS_Provider_IPL4.NS_Provider_IPL4_CT, component name: -provIP0. Version: 9.0.0.
[...]
05:03:21.083621 mtc RAW_NS.ttcnpp:395 Sent on NSCP[0] to -provIP0(6) @NS_Types.PDU_NS : { pDU_SNS_Config_Ack := { nsPduType := '10'O, nSEI_NS := { iEI := '04'O, ext := '1'B, lengthIndicator := { length1 := 2 }, nSEI := '0060'O }, causeNS := omit } }
05:03:21.083658 mtc SGSN_Tests_NS.ttcn:90 setverdict(pass): none -> pass
05:03:21.083670 6 NS_Provider_IPL4.ttcn:149 Message enqueued on NSVC[0] from mtc @NS_Types.PDU_NS : { pDU_SNS_Config_Ack := { nsPduType := '10'O, nSEI_NS := { iEI := '04'O, ext := '1'B, lengthIndicator := { length1 := 2 }, nSEI := '0060'O }, causeNS := omit } } id 3
05:03:21.083685 mtc SGSN_Tests_NS.ttcn:90 Terminating component type SGSN_Tests_NS.RAW_Test_CT.
05:03:21.083696 6 NS_Provider_IPL4.ttcn:169 Matching on port NSVC[0] succeeded:  matched
05:03:21.083705 mtc SGSN_Tests_NS.ttcn:90 Default with id 1 (altstep as_Tguard) was deactivated.
05:03:21.083710 6 NS_Provider_IPL4.ttcn:169 Receive operation on port NSVC[0] succeeded, message from mtc: @NS_Types.PDU_NS : { pDU_SNS_Config_Ack := { nsPduType := '10'O, nSEI_NS := { iEI := '04'O, ext := '1'B, lengthIndicator := { length1 := 2 }, nSEI := '0060'O }, causeNS := omit } } id 3
05:03:21.083723 6 NS_Provider_IPL4.ttcn:169 Message with id 3 was extracted from the queue of NSVC[0].
05:03:21.083724 mtc SGSN_Tests_NS.ttcn:90 Stop timer g_T_guard: 10 s
05:03:21.083743 6 NS_Provider_IPL4.ttcn:179 Sent on IPL4 to system @IPL4asp_Types.ASP_SendTo : { connId := 1, remName := "172.18.150.10", remPort := 23000, proto := { udp := { } }, msg := '1004820060'O }
[...]
05:03:21.083959 mtc SGSN_Tests_NS.ttcn:90 Removing unterminated connection between port NSCP[0] and -provIP0(6):NSVC[0].
05:03:21.083998 6 NS_Provider_IPL4.ttcn:149 Connection of port NSVC[0] to mtc:NSCP[0] was closed unexpectedly by the peer.
05:03:21.084004 mtc SGSN_Tests_NS.ttcn:90 Port NSCP[0] was stopped.
05:03:21.084016 6 NS_Provider_IPL4.ttcn:149 Port NSVC[0] was disconnected from mtc:NSCP[0].
[...]
05:03:21.084132 mtc SGSN_Tests_NS.ttcn:90 Component type SGSN_Tests_NS.RAW_Test_CT was shut down inside testcase TC_SNS_config_sgsn.
05:03:21.084144 6 NS_Provider_IPL4.ttcn:149 Message enqueued on IPL4 from system @IPL4asp_Types.ASP_RecvFrom : { connId := 1, remName := "172.18.150.10", remPort := 23000, locName := "172.18.150.103", locPort := 21010, proto := { udp := { } }, userData := 0, msg := '0A'O ("\n") } id 4
05:03:21.084152 mtc SGSN_Tests_NS.ttcn:90 Waiting for PTCs to finish.
05:03:21.084160 6 NS_Provider_IPL4.ttcn:151 Matching on port IPL4 succeeded:  matched
05:03:21.084174 6 NS_Provider_IPL4.ttcn:151 Receive operation on port IPL4 succeeded, message from system(): @IPL4asp_Types.ASP_RecvFrom : { connId := 1, remName := "172.18.150.10", remPort := 23000, locName := "172.18.150.103", locPort := 21010, proto := { udp := { } }, userData := 0, msg := '0A'O ("\n") } id 4
05:03:21.084185 6 NS_Provider_IPL4.ttcn:151 Message with id 4 was extracted from the queue of IPL4.
05:03:21.084209 6 NS_Provider_IPL4.ttcn:159 Dynamic test case error: Port NSVC[0] has neither connections nor mappings. Message cannot be sent on it.
05:03:21.084226 6 NS_Provider_IPL4.ttcn:159 setverdict(error): none -> error
05:03:21.084258 6 NS_Provider_IPL4.ttcn:159 Performing error recovery.

As far as I can see the components are created "alive", the log also seems to back that up.


Files

No data to display

Actions

Also available in: Atom PDF

Add picture from clipboard (Maximum size: 48.8 MB)