See https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/4/display/redirect?page=changes
Changes:
[Pau Espin Pedrol] stp: m3ua: Fix array size
[Oliver Smith] deps/Makefile: error if repo _commit is missing
[Oliver Smith] sgsn: fix running against current stable osmo-sgsn
------------------------------------------ [...truncated 115.84 KiB...] Waiting for packet dumper to finish... 0 (prev_count=-1, count=8896) Waiting for packet dumper to finish... 1 (prev_count=8896, count=9852) MTC@67af13c350bb: External command `https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/src/osmo-ttcn3-hacks/ttcn3-tcpdump-stop.sh SMF_Tests.TC_tx_echo pass' was executed successfully (exit status: 0). MTC@67af13c350bb: Starting external command `https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/src/osmo-ttcn3-hacks/ttcn3-tcpdump-start.sh SMF_Tests.TC_createSession_deleteSession'. ------ SMF_Tests.TC_createSession_deleteSession ------ (13:08:28) load average: 0.77, 0.50, 0.83 /usr/bin/dumpcap -q -s 1520 -n -i any -w "https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/4/artifact/logs/testsuite/SMF_Tests.TC_createSession_deleteSession.pcap" >https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/4/artifact/logs/testsuite/SMF_Tests.TC_createSession_deleteSession.pcap.stdout 2>/tmp/cmderr & Waiting for packet dumper to start... 0 MTC@67af13c350bb: External command `https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/src/osmo-ttcn3-hacks/ttcn3-tcpdump-start.sh SMF_Tests.TC_createSession_deleteSession' was executed successfully (exit status: 0). MTC@67af13c350bb: Test case TC_createSession_deleteSession started. TC_createSession_deleteSession-PFCP(13)@67af13c350bb: f_PFCPEM_conns_bcast_add(): vc_conn mtc subscribed for broadcast TC_createSession_deleteSession-PFCP(13)@67af13c350bb: PFCP_Emulation main() CLIENT.receive from mtc: { s_flag := '0'B, mp := '0'B, spare := '000'B, version := 1, message_type := 0, lengthIndicator := 0, seid := omit, sequence_number := 0, spare2 := '0000'B, mp_or_spare := '0000'B, message_body := { heartbeat_request := { recovery_time_stamp := { elementIdentifier := 96, lengthIndicator := 0, time_value := 3997429709 } } } } [32m09/03 13:08:29.743[0m: [[33mpfcp[0m] [1;33mERROR[0m: Remote PFCP restarted [3997429702<3997429709] in Heartbeat REQ (../lib/pfcp/handler.c:39) [32m09/03 13:08:29.743[0m: [[33msmf[0m] [1;32mINFO[0m: PFCP de-associated [127.0.0.7]:8805 (../src/smf/pfcp-sm.c:205) TC_createSession_deleteSession-PFCP(13)@67af13c350bb: PFCP_Emulation main() PFCP.receive: { peer := { conn_id := 1, remote_name := "127.0.0.4", remote_port := 8805 }, pdu := { s_flag := '0'B, mp := '0'B, spare := '000'B, version := 1, message_type := 2, lengthIndicator := 12, seid := omit, sequence_number := 7480001, spare2 := '0000'B, mp_or_spare := '0000'B, message_body := { heartbeat_response := { recovery_time_stamp := { elementIdentifier := 96, lengthIndicator := 4, time_value := 3997429701 } } } } } TC_createSession_deleteSession-PFCP(13)@67af13c350bb: found destination mtc TC_createSession_deleteSession-PFCP(13)@67af13c350bb: PFCP_Emulation main() PFCP.receive: { peer := { conn_id := 1, remote_name := "127.0.0.4", remote_port := 8805 }, pdu := { s_flag := '0'B, mp := '0'B, spare := '000'B, version := 1, message_type := 5, lengthIndicator := 26, seid := omit, sequence_number := 3, spare2 := '0000'B, mp_or_spare := '0000'B, message_body := { pfcp_association_setup_request := { node_id := { elementIdentifier := 60, lengthIndicator := 5, node_id_type := 0, spare := '0000'B, node_id_value := '7F000004'O }, time_stamp := { elementIdentifier := 96, lengthIndicator := 4, time_value := 3997429701 }, up_function_features := omit, cp_function_features := { elementIdentifier := 89, lengthIndicator := 1, load := '0'B, ovrl := '0'B, epfar := '0'B, sset := '0'B, bundl := '0'B, mpas := '0'B, ardr := '0'B, uiaur := '0'B, psucc := omit, rpgur := omit, papfd := omit, spare6_4 := omit, spare6_5 := omit, spare6_6 := omit, spare6_7 := omit, spare6_8 := omit }, UP_IP_resource_list := omit } } } } TC_createSession_deleteSession-PFCP(13)@67af13c350bb: broadcasting to mtc MTC@67af13c350bb: setverdict(pass): none -> pass TC_createSession_deleteSession-PFCP(13)@67af13c350bb: PFCP_Emulation main() CLIENT.receive from mtc: { s_flag := '0'B, mp := '0'B, spare := '000'B, version := 1, message_type := 0, lengthIndicator := 0, seid := omit, sequence_number := 3, spare2 := '0000'B, mp_or_spare := '0000'B, message_body := { pfcp_association_setup_response := { node_id := { elementIdentifier := 60, lengthIndicator := 0, node_id_type := 0, spare := '0000'B, node_id_value := '7F000007'O }, cause := { elementIdentifier := 19, lengthIndicator := 0, causeValue := '01'O }, time_stamp := { elementIdentifier := 96, lengthIndicator := 0, time_value := 3997429709 }, up_function_features := { elementIdentifier := 43, lengthIndicator := 0, bucp := '0'B, ddnd := '0'B, dlbd := '0'B, trst := '0'B, ftup := '1'B, pfdm := '0'B, heeu := '0'B, treu := '0'B, empu := '0'B, pdiu := '0'B, udbc := '0'B, quoac := '0'B, trace := '0'B, frrt := '0'B, pfde := '0'B, epfar := '0'B, dpdra := '0'B, adpdp := '0'B, ueip := '0'B, sset := '0'B, mnop := '0'B, mte := '0'B, bundl := '0'B, gcom := '0'B, mpas := '0'B, rttl := '0'B, vtime := '1'B, norp := '0'B, iptv := '0'B, ip6pl := '0'B, tsn := '0'B, mptcp := '0'B, atsss_ll := '0'B, qfqm := '0'B, gpqm := '0'B, mt_edt := '0'B, ciot := '0'B, ethar := '0'B, ddds := '0'B, rds := '0'B, rttwp := '0'B, quasf := '0'B, nspoc := '0'B, l2tp := '0'B, upber := '0'B, resps := '0'B, iprep := '0'B, dnsts := '0'B, drqos := '0'B, mbsn4 := '0'B, psuprm := '0'B, epppi := '0'B, ratp := '0'B, upidp := '0'B, afsfc := '0'B, mquic_udp := '0'B, redsm := '0'B, dbdm := '0'B, tscts := '0'B, drtsc := '0'B, n6jedb := '0'B, qmcon := '0'B, detnet := '0'B, eml4s := '0'B, pdusm := '0'B, cn_tl := '0'B, qmdrm := '0'B, edbnc := '0'B, mt_sdt := '0'B, upsbies := '0'B, umn6ip := '0'B, un6tu := '0'B, mbsch := '0'B, un6dm := '0'B, natpub := '0'B, ushph := '0'B, mpquic_ip := '0'B, mpquic_e := '0'B, dyntr := '0'B, muxmf := '0'B, conudp := '0'B, moq := '0'B, ulm := '0'B, psitlm := '0'B, udpopt := '0'B, qmabr := '0'B, papfd := '0'B, spare := '0'B }, cp_function_features := omit, UP_IP_resource_list := omit } } } [32m09/03 13:08:29.747[0m: [[33msmf[0m] [1;32mINFO[0m: PFCP associated [127.0.0.7]:8805 (../src/smf/pfcp-sm.c:189) [32m09/03 13:08:29.747[0m: [[33msmf[0m] [1;33mERROR[0m: PFCP restoration (../src/smf/pfcp-sm.c:199) TC_createSession_deleteSession-PFCP(13)@67af13c350bb: f_PFCPEM_conns_bcast_del(): vc_conn mtc unsubscribed from broadcast TC_createSession_deleteSession-PFCP(13)@67af13c350bb: Rx Heartbeat Req: { s_flag := '0'B, mp := '0'B, spare := '000'B, version := 1, message_type := 1, lengthIndicator := 12, seid := omit, sequence_number := 4, spare2 := '0000'B, mp_or_spare := '0000'B, message_body := { heartbeat_request := { recovery_time_stamp := { elementIdentifier := 96, lengthIndicator := 4, time_value := 3997429701 } } } } TC_createSession_deleteSession(14)@67af13c350bb: Warning: sizes of 'struct sctp_event_subscribe': compile-time 14, kernel: 14 TC_createSession_deleteSession(15)@67af13c350bb: Warning: sizes of 'struct sctp_event_subscribe': compile-time 14, kernel: 14 TC_createSession_deleteSession(16)@67af13c350bb: Warning: sizes of 'struct sctp_event_subscribe': compile-time 14, kernel: 14 [32m09/03 13:08:30.266[0m: [[33mdiam[0m] [1;32mINFO[0m: CONNECTED TO 'ocs.localdomain' (SCTP,soc#16): (../lib/diameter/common/logger.c:81) [32m09/03 13:08:31.476[0m: [[33mdiam[0m] [1;32mINFO[0m: CONNECTED TO 'pcrf.localdomain' (SCTP,soc#17): (../lib/diameter/common/logger.c:81) [32m09/03 13:08:32.550[0m: [[33msbi[0m] [1;36mWARNING[0m: [89e20c22-a798-41f1-8a3c-256f2de7478d] Retry registration with NRF (../lib/sbi/nf-sm.c:282) [32m09/03 13:08:32.551[0m: [[33msbi[0m] [1;36mWARNING[0m: Could not connect to server (7): Failed to connect to 127.0.1.10 port 7777 after 0 ms: Could not connect to server (../lib/sbi/client.c:767) [32m09/03 13:08:32.551[0m: [[33msbi[0m] [1;36mWARNING[0m: ogs_sbi_client_handler() failed [-1] (../lib/sbi/path.c:62) [32m09/03 13:08:32.754[0m: [[33mdiam[0m] [1;32mINFO[0m: CONNECTED TO 'aaa.localdomain' (SCTP,soc#19): (../lib/diameter/common/logger.c:81) TC_createSession_deleteSession(14)@67af13c350bb: Created Expect[0] for '001010123456789'H to be handled at TC_createSession_deleteSession(17) TC_createSession_deleteSession(15)@67af13c350bb: Created Expect[0] for '001010123456789'H to be handled at TC_createSession_deleteSession(17) TC_createSession_deleteSession(16)@67af13c350bb: Created Expect[0] for '001010123456789'H to be handled at TC_createSession_deleteSession(17) TC_createSession_deleteSession(17)@67af13c350bb: f_Mutex_lock(): mutex acquired @ "SMF_Session_CT.ttcn":413 [32m09/03 13:08:33.815[0m: [[33msmf[0m] [1;32mINFO[0m: [Added] Number of SMF-UEs is now 1 (../src/smf/context.c:1069) [32m09/03 13:08:33.815[0m: [[33msmf[0m] [1;32mINFO[0m: [Added] Number of SMF-Sessions is now 1 (../src/smf/context.c:3612) [32m09/03 13:08:33.815[0m: [[33msmf[0m] [1;32mINFO[0m: UE IMSI[001010123456789] APN[internet] IPv4[10.45.0.2] IPv6[] (../src/smf/s5c-handler.c:312) TC_createSession_deleteSession(15)@67af13c350bb: Found Expect[0] for { version := 1, message_length := 712, RPETxxxx := '11000000'B, command_code := Credit_Control (272), application_id := '00000004'O, hop_by_hop_id := '27C9DCF4'O, end_to_end_id := '14584DAE'O, avps := { { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_BASE_NONE_Session_Id (263) }, VMPxxxxx := '01000000'B, avp_length := 43, vendor_id := omit }, avp_data := { avp_BASE_NONE_Session_Id := '736D662E6C6F63616C646F6D61696E3B313738383434303930313B323B6170705F6779'O ("smf.localdomain;1788440901;2;app_gy") } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_BASE_NONE_Origin_Host (264) }, VMPxxxxx := '01000000'B, avp_length := 23, vendor_id := omit }, avp_data := { avp_BASE_NONE_Origin_Host := "smf.localdomain" } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_BASE_NONE_Origin_Realm (296) }, VMPxxxxx := '01000000'B, avp_length := 19, vendor_id := omit }, avp_data := { avp_BASE_NONE_Origin_Realm := "localdomain" } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_BASE_NONE_Destination_Realm (283) }, VMPxxxxx := '01000000'B, avp_length := 19, vendor_id := omit }, avp_data := { avp_BASE_NONE_Destination_Realm := "localdomain" } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_BASE_NONE_Auth_Application_Id (258) }, VMPxxxxx := '01000000'B, avp_length := 12, vendor_id := omit }, avp_data := { avp_BASE_NONE_Auth_Application_Id := '00000004'O } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DCC_NONE_Service_Context_Id (461) }, VMPxxxxx := '01000000'B, avp_length := 22, vendor_id := omit }, avp_data := { avp_DCC_NONE_Service_Context_Id := '333232353140336770702E6F7267'O ("32251@3gpp.org") } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DCC_NONE_CC_Request_Type (416) }, VMPxxxxx := '01000000'B, avp_length := 12, vendor_id := omit }, avp_data := { avp_DCC_NONE_CC_Request_Type := INITIAL_REQUEST (1) } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DCC_NONE_CC_Request_Number (415) }, VMPxxxxx := '01000000'B, avp_length := 12, vendor_id := omit }, avp_data := { avp_DCC_NONE_CC_Request_Number := '00000000'O } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_BASE_NONE_Event_Timestamp (55) }, VMPxxxxx := '01000000'B, avp_length := 12, vendor_id := omit }, avp_data := { avp_BASE_NONE_Event_Timestamp := 'EE43EFD1'O } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DCC_NONE_Subscription_Id (443) }, VMPxxxxx := '01000000'B, avp_length := 44, vendor_id := omit }, avp_data := { avp_DCC_NONE_Subscription_Id := { { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DCC_NONE_Subscription_Id_Type (450) }, VMPxxxxx := '01000000'B, avp_length := 12, vendor_id := omit }, avp_data := { avp_DCC_NONE_Subscription_Id_Type := END_USER_IMSI (1) } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DCC_NONE_Subscription_Id_Data (444) }, VMPxxxxx := '01000000'B, avp_length := 23, vendor_id := omit }, avp_data := { avp_DCC_NONE_Subscription_Id_Data := '303031303130313233343536373839'O ("001010123456789") } } } } } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DCC_NONE_Subscription_Id (443) }, VMPxxxxx := '01000000'B, avp_length := 32, vendor_id := omit }, avp_data := { avp_DCC_NONE_Subscription_Id := { { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DCC_NONE_Subscription_Id_Type (450) }, VMPxxxxx := '01000000'B, avp_length := 12, vendor_id := omit }, avp_data := { avp_DCC_NONE_Subscription_Id_Type := END_USER_E164 (0) } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DCC_NONE_Subscription_Id_Data (444) }, VMPxxxxx := '01000000'B, avp_length := 12, vendor_id := omit }, avp_data := { avp_DCC_NONE_Subscription_Id_Data := '31323334'O ("1234") } } } } } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DCC_NONE_Requested_Action (436) }, VMPxxxxx := '01000000'B, avp_length := 12, vendor_id := omit }, avp_data := { avp_DCC_NONE_Requested_Action := DIRECT_DEBITING (0) } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_DCA_3GPP_AoC_Request_Type (2055) }, VMPxxxxx := '11000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_DCA_3GPP_AoC_Request_Type := AoC_FULL (1) } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DCC_NONE_Multiple_Services_Indicator (455) }, VMPxxxxx := '01000000'B, avp_length := 12, vendor_id := omit }, avp_data := { avp_DCC_NONE_Multiple_Services_Indicator := MULTIPLE_SERVICES_NOT_SUPPORTED (0) } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DCC_NONE_Multiple_Services_Credit_Control (456) }, VMPxxxxx := '01000000'B, avp_length := 152, vendor_id := omit }, avp_data := { avp_DCC_NONE_Multiple_Services_Credit_Control := { { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DCC_NONE_Requested_Service_Unit (437) }, VMPxxxxx := '01000000'B, avp_length := 24, vendor_id := omit }, avp_data := { avp_DCC_NONE_Requested_Service_Unit := { { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DCC_NONE_CC_Total_Octets (421) }, VMPxxxxx := '01000000'B, avp_length := 16, vendor_id := omit }, avp_data := { avp_DCC_NONE_CC_Total_Octets := '00000000000F4240'O } } } } } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_PCC_3GPP_QoS_Information (1016) }, VMPxxxxx := '11000000'B, avp_length := 120, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_PCC_3GPP_QoS_Information := { { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_PCC_3GPP_QoS_Class_Identifier (1028) }, VMPxxxxx := '11000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_PCC_3GPP_QoS_Class_Identifier := '00000009'O } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_PCC_3GPP_Allocation_Retention_Priority (1034) }, VMPxxxxx := '10000000'B, avp_length := 60, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_PCC_3GPP_Allocation_Retention_Priority := { { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_PCC_3GPP_Priority_Level (1046) }, VMPxxxxx := '10000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_PCC_3GPP_Priority_Level := '00000009'O } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_PCC_3GPP_Pre_emption_Capability (1047) }, VMPxxxxx := '10000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_PCC_3GPP_Pre_emption_Capability := PRE_EMPTION_CAPABILITY_ENABLED (0) } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_PCC_3GPP_Pre_emption_Vulnerability (1048) }, VMPxxxxx := '10000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_PCC_3GPP_Pre_emption_Vulnerability := PRE_EMPTION_VULNERABILITY_ENABLED (0) } } } } } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_PCC_3GPP_APN_Aggregate_Max_Bitrate_UL (1041) }, VMPxxxxx := '10000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_PCC_3GPP_APN_Aggregate_Max_Bitrate_UL := '061A8000'O } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_PCC_3GPP_APN_Aggregate_Max_Bitrate_DL (1040) }, VMPxxxxx := '10000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_PCC_3GPP_APN_Aggregate_Max_Bitrate_DL := '061A8000'O } } } } } } } } } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_DCA_3GPP_Service_Information (873) }, VMPxxxxx := '11000000'B, avp_length := 244, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_DCA_3GPP_Service_Information := { { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_DCA_3GPP_PS_Information (874) }, VMPxxxxx := '11000000'B, avp_length := 232, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_DCA_3GPP_PS_Information := { { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_GI_3GPP_3GPP_Charging_Id (2) }, VMPxxxxx := '11000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_GI_3GPP_3GPP_Charging_Id := '00000001'O } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_GI_3GPP_3GPP_PDP_Type (3) }, VMPxxxxx := '11000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_GI_3GPP_3GPP_PDP_Type := IPv4 (0) } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_DCA_3GPP_PDP_Address (1227) }, VMPxxxxx := '11000000'B, avp_length := 18, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_DCA_3GPP_PDP_Address := { address_type := IP (1), address_data := '0A2D0002'O } } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_DCA_3GPP_SGSN_Address (1228) }, VMPxxxxx := '11000000'B, avp_length := 18, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_DCA_3GPP_SGSN_Address := { address_type := IP (1), address_data := '7F0000CA'O } } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_DCA_3GPP_GGSN_Address (847) }, VMPxxxxx := '11000000'B, avp_length := 18, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_DCA_3GPP_GGSN_Address := { address_type := IP (1), address_data := '7F000004'O } } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DNAS_NONE_Called_Station_Id (30) }, VMPxxxxx := '01000000'B, avp_length := 16, vendor_id := omit }, avp_data := { avp_DNAS_NONE_Called_Station_Id := '696E7465726E6574'O ("internet") } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_GI_3GPP_3GPP_Selection_Mode (12) }, VMPxxxxx := '11000000'B, avp_length := 13, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_GI_3GPP_3GPP_Selection_Mode := '30'O ("0") } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_GI_3GPP_3GPP_Charging_Characteristics (13) }, VMPxxxxx := '11000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_GI_3GPP_3GPP_Charging_Characteristics := '30303030'O ("0000") } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_GI_3GPP_3GPP_SGSN_MCC_MNC (18) }, VMPxxxxx := '11000000'B, avp_length := 17, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_GI_3GPP_3GPP_SGSN_MCC_MNC := '3030313031'O ("00101") } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_GI_3GPP_3GPP_NSAPI (10) }, VMPxxxxx := '11000000'B, avp_length := 13, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_GI_3GPP_3GPP_NSAPI := '05'O } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_GI_3GPP_3GPP_User_Location_Info (22) }, VMPxxxxx := '11000000'B, avp_length := 25, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_GI_3GPP_3GPP_User_Location_Info := '8200F110000100F11000000017'O } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_GI_3GPP_3GPP_RAT_Type (21) }, VMPxxxxx := '11000000'B, avp_length := 13, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_GI_3GPP_3GPP_RAT_Type := '06'O } } } } } } } } } } } } } handled at TC_createSession_deleteSession(17) TC_createSession_deleteSession(14)@67af13c350bb: Found Expect[0] for { version := 1, message_length := 668, RPETxxxx := '11000000'B, command_code := Credit_Control (272), application_id := '01000016'O, hop_by_hop_id := '3A9ADD6C'O, end_to_end_id := '14584DAD'O, avps := { { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_BASE_NONE_Session_Id (263) }, VMPxxxxx := '01000000'B, avp_length := 43, vendor_id := omit }, avp_data := { avp_BASE_NONE_Session_Id := '736D662E6C6F63616C646F6D61696E3B313738383434303930313B313B6170705F6778'O ("smf.localdomain;1788440901;1;app_gx") } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_BASE_NONE_Origin_Host (264) }, VMPxxxxx := '01000000'B, avp_length := 23, vendor_id := omit }, avp_data := { avp_BASE_NONE_Origin_Host := "smf.localdomain" } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_BASE_NONE_Origin_Realm (296) }, VMPxxxxx := '01000000'B, avp_length := 19, vendor_id := omit }, avp_data := { avp_BASE_NONE_Origin_Realm := "localdomain" } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_BASE_NONE_Destination_Realm (283) }, VMPxxxxx := '01000000'B, avp_length := 19, vendor_id := omit }, avp_data := { avp_BASE_NONE_Destination_Realm := "localdomain" } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_BASE_NONE_Auth_Application_Id (258) }, VMPxxxxx := '01000000'B, avp_length := 12, vendor_id := omit }, avp_data := { avp_BASE_NONE_Auth_Application_Id := '01000016'O } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DCC_NONE_CC_Request_Type (416) }, VMPxxxxx := '01000000'B, avp_length := 12, vendor_id := omit }, avp_data := { avp_DCC_NONE_CC_Request_Type := INITIAL_REQUEST (1) } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DCC_NONE_CC_Request_Number (415) }, VMPxxxxx := '01000000'B, avp_length := 12, vendor_id := omit }, avp_data := { avp_DCC_NONE_CC_Request_Number := '00000000'O } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DCC_NONE_Subscription_Id (443) }, VMPxxxxx := '01000000'B, avp_length := 44, vendor_id := omit }, avp_data := { avp_DCC_NONE_Subscription_Id := { { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DCC_NONE_Subscription_Id_Type (450) }, VMPxxxxx := '01000000'B, avp_length := 12, vendor_id := omit }, avp_data := { avp_DCC_NONE_Subscription_Id_Type := END_USER_IMSI (1) } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DCC_NONE_Subscription_Id_Data (444) }, VMPxxxxx := '01000000'B, avp_length := 23, vendor_id := omit }, avp_data := { avp_DCC_NONE_Subscription_Id_Data := '303031303130313233343536373839'O ("001010123456789") } } } } } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DCC_NONE_Subscription_Id (443) }, VMPxxxxx := '01000000'B, avp_length := 32, vendor_id := omit }, avp_data := { avp_DCC_NONE_Subscription_Id := { { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DCC_NONE_Subscription_Id_Type (450) }, VMPxxxxx := '01000000'B, avp_length := 12, vendor_id := omit }, avp_data := { avp_DCC_NONE_Subscription_Id_Type := END_USER_E164 (0) } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DCC_NONE_Subscription_Id_Data (444) }, VMPxxxxx := '01000000'B, avp_length := 12, vendor_id := omit }, avp_data := { avp_DCC_NONE_Subscription_Id_Data := '31323334'O ("1234") } } } } } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_CxDx_3GPP_Supported_Features (628) }, VMPxxxxx := '10000000'B, avp_length := 56, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_CxDx_3GPP_Supported_Features := { { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_BASE_NONE_Vendor_Id (266) }, VMPxxxxx := '01000000'B, avp_length := 12, vendor_id := omit }, avp_data := { avp_BASE_NONE_Vendor_Id := '000028AF'O } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_CxDx_3GPP_Feature_List_ID (629) }, VMPxxxxx := '10000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_CxDx_3GPP_Feature_List_ID := '00000001'O } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_CxDx_3GPP_Feature_List (630) }, VMPxxxxx := '10000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_CxDx_3GPP_Feature_List := '0000000B'O } } } } } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_PCC_3GPP_Network_Request_Support (1024) }, VMPxxxxx := '11000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_PCC_3GPP_Network_Request_Support := NETWORK_REQUEST_SUPPORTED (1) } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DNAS_NONE_Framed_IP_Address (8) }, VMPxxxxx := '01000000'B, avp_length := 12, vendor_id := omit }, avp_data := { avp_DNAS_NONE_Framed_IP_Address := '0A2D0002'O } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_PCC_3GPP_IP_CAN_Type (1027) }, VMPxxxxx := '11000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_PCC_3GPP_IP_CAN_Type := threeGPP_EPS (5) } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_PCC_3GPP_RAT_Type (1032) }, VMPxxxxx := '10000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_PCC_3GPP_RAT_Type := EUTRAN (1004) } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_PCC_3GPP_QoS_Information (1016) }, VMPxxxxx := '11000000'B, avp_length := 44, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_PCC_3GPP_QoS_Information := { { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_PCC_3GPP_APN_Aggregate_Max_Bitrate_UL (1041) }, VMPxxxxx := '10000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_PCC_3GPP_APN_Aggregate_Max_Bitrate_UL := '061A8000'O } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_PCC_3GPP_APN_Aggregate_Max_Bitrate_DL (1040) }, VMPxxxxx := '10000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_PCC_3GPP_APN_Aggregate_Max_Bitrate_DL := '061A8000'O } } } } } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_PCC_3GPP_Default_EPS_Bearer_QoS (1049) }, VMPxxxxx := '10000000'B, avp_length := 88, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_PCC_3GPP_Default_EPS_Bearer_QoS := { { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_PCC_3GPP_QoS_Class_Identifier (1028) }, VMPxxxxx := '11000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_PCC_3GPP_QoS_Class_Identifier := '00000009'O } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_PCC_3GPP_Allocation_Retention_Priority (1034) }, VMPxxxxx := '10000000'B, avp_length := 60, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_PCC_3GPP_Allocation_Retention_Priority := { { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_PCC_3GPP_Priority_Level (1046) }, VMPxxxxx := '10000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_PCC_3GPP_Priority_Level := '00000009'O } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_PCC_3GPP_Pre_emption_Capability (1047) }, VMPxxxxx := '10000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_PCC_3GPP_Pre_emption_Capability := PRE_EMPTION_CAPABILITY_ENABLED (0) } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_PCC_3GPP_Pre_emption_Vulnerability (1048) }, VMPxxxxx := '10000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_PCC_3GPP_Pre_emption_Vulnerability := PRE_EMPTION_VULNERABILITY_ENABLED (0) } } } } } } } } } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_GI_3GPP_3GPP_User_Location_Info (22) }, VMPxxxxx := '11000000'B, avp_length := 25, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_GI_3GPP_3GPP_User_Location_Info := '8200F110000100F11000000017'O } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_GI_3GPP_3GPP_SGSN_MCC_MNC (18) }, VMPxxxxx := '11000000'B, avp_length := 17, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_GI_3GPP_3GPP_SGSN_MCC_MNC := '3030313031'O ("00101") } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_PCC_3GPP_AN_GW_Address (1050) }, VMPxxxxx := '10000000'B, avp_length := 18, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_PCC_3GPP_AN_GW_Address := { address_type := IP (1), address_data := '7F0000CA'O } } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_GI_3GPP_3GPP_Charging_Characteristics (13) }, VMPxxxxx := '11000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_GI_3GPP_3GPP_Charging_Characteristics := '30303030'O ("0000") } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DNAS_NONE_Called_Station_Id (30) }, VMPxxxxx := '01000000'B, avp_length := 16, vendor_id := omit }, avp_data := { avp_DNAS_NONE_Called_Station_Id := '696E7465726E6574'O ("internet") } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_PCC_3GPP_Online (1009) }, VMPxxxxx := '11000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_PCC_3GPP_Online := DISABLE_ONLINE (0) } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_PCC_3GPP_Offline (1008) }, VMPxxxxx := '11000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_PCC_3GPP_Offline := ENABLE_OFFLINE (1) } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_RX_3GPP_Access_Network_Charging_Address (501) }, VMPxxxxx := '11000000'B, avp_length := 18, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_RX_3GPP_Access_Network_Charging_Address := { address_type := IP (1), address_data := '7F000004'O } } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_PCC_3GPP_Access_Network_Charging_Identifier_Gx (1022) }, VMPxxxxx := '11000000'B, avp_length := 28, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_PCC_3GPP_Access_Network_Charging_Identifier_Gx := { { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_RX_3GPP_Access_Network_Charging_Identifier_Value (503) }, VMPxxxxx := '11000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_RX_3GPP_Access_Network_Charging_Identifier_Value := '00000001'O } } } } } } } } } handled at TC_createSession_deleteSession(17) TC_createSession_deleteSession-PFCP(13)@67af13c350bb: PFCP_Emulation main() PFCP.receive: { peer := { conn_id := 1, remote_name := "127.0.0.4", remote_port := 8805 }, pdu := { s_flag := '1'B, mp := '0'B, spare := '000'B, version := 1, message_type := 50, lengthIndicator := 608, seid := '0000000000000000'O, sequence_number := 5, spare2 := '0000'B, mp_or_spare := '0000'B, message_body := { pfcp_session_establishment_request := { node_id := { elementIdentifier := 60, lengthIndicator := 5, node_id_type := 0, spare := '0000'B, node_id_value := '7F000004'O }, CP_F_SEID := { elementIdentifier := 57, lengthIndicator := 13, v6 := '0'B, v4 := '1'B, spare := '000000'B, seid := '00000000000002A2'O, ipv4_address := '7F000004'O, ipv6_address := omit }, create_PDR_list := { { elementIdentifier := 1, lengthIndicator := 74, grouped_ie := { pdr_id := { elementIdentifier := 56, lengthIndicator := 2, rule_id := '0001'O }, precedence := { elementIdentifier := 29, lengthIndicator := 4, precedence_value := 65535 }, pdi := { elementIdentifier := 2, lengthIndicator := 32, grouped_ie := { source_interface := { elementIdentifier := 20, lengthIndicator := 1, interfacevalue := 1, spare := '0000'B }, local_F_TEID := omit, pdn_instance := { elementIdentifier := 22, lengthIndicator := 9, pdn_instance := '08696E7465726E6574'O ("\binternet") }, ue_ip_address := { elementIdentifier := 93, lengthIndicator := 5, v6 := '0'B, v4 := '1'B, sd := '1'B, spare := '00000'B, ipv4_address := '0A2D0002'O, ipv6_address := omit }, traffic_endpoint_id := omit, sdf_filter_list := omit, application_id := omit, ethernet_packet_filter_list := omit, qfi_list := omit, source_interface_type := { elementIdentifier := 160, lengthIndicator := 1, interfacetypevalue := 17, spare := '00'B } } }, outer_header_removal := omit, fAR_ID_list := { { elementIdentifier := 108, lengthIndicator := 4, id_value := 1 } }, uRR_ID_list := { { elementIdentifier := 81, lengthIndicator := 4, id_value := 1 } }, qER_ID_list := { { elementIdentifier := 109, lengthIndicator := 4, id_value := 1 } }, activate_predefined_rules := omit } }, { elementIdentifier := 1, lengthIndicator := 85, grouped_ie := { pdr_id := { elementIdentifier := 56, lengthIndicator := 2, rule_id := '0002'O }, precedence := { elementIdentifier := 29, lengthIndicator := 4, precedence_value := 65535 }, pdi := { elementIdentifier := 2, lengthIndicator := 38, grouped_ie := { source_interface := { elementIdentifier := 20, lengthIndicator := 1, interfacevalue := 0, spare := '0000'B }, local_F_TEID := { elementIdentifier := 21, lengthIndicator := 2, v4 := '1'B, v6 := '1'B, ch := '1'B, chid := '1'B, spare := '0000'B, teid := omit, ipv4_address := omit, ipv6_address := omit, choose_id := '05'O }, pdn_instance := { elementIdentifier := 22, lengthIndicator := 9, pdn_instance := '08696E7465726E6574'O ("\binternet") }, ue_ip_address := { elementIdentifier := 93, lengthIndicator := 5, v6 := '0'B, v4 := '1'B, sd := '0'B, spare := '00000'B, ipv4_address := '0A2D0002'O, ipv6_address := omit }, traffic_endpoint_id := omit, sdf_filter_list := omit, application_id := omit, ethernet_packet_filter_list := omit, qfi_list := omit, source_interface_type := { elementIdentifier := 160, lengthIndicator := 1, interfacetypevalue := 11, spare := '00'B } } }, outer_header_removal := { elementIdentifier := 95, lengthIndicator := 1, ohc_description := 6 }, fAR_ID_list := { { elementIdentifier := 108, lengthIndicator := 4, id_value := 2 } }, uRR_ID_list := { { elementIdentifier := 81, lengthIndicator := 4, id_value := 1 } }, qER_ID_list := { { elementIdentifier := 109, lengthIndicator := 4, id_value := 1 } }, activate_predefined_rules := omit } }, { elementIdentifier := 1, lengthIndicator := 41, grouped_ie := { pdr_id := { elementIdentifier := 56, lengthIndicator := 2, rule_id := '0003'O }, precedence := { elementIdentifier := 29, lengthIndicator := 4, precedence_value := 255 }, pdi := { elementIdentifier := 2, lengthIndicator := 10, grouped_ie := { source_interface := { elementIdentifier := 20, lengthIndicator := 1, interfacevalue := 3, spare := '0000'B }, local_F_TEID := { elementIdentifier := 21, lengthIndicator := 1, v4 := '1'B, v6 := '1'B, ch := '1'B, chid := '0'B, spare := '0000'B, teid := omit, ipv4_address := omit, ipv6_address := omit, choose_id := omit }, pdn_instance := omit, ue_ip_address := omit, traffic_endpoint_id := omit, sdf_filter_list := omit, application_id := omit, ethernet_packet_filter_list := omit, qfi_list := omit, source_interface_type := omit } }, outer_header_removal := { elementIdentifier := 95, lengthIndicator := 1, ohc_description := 6 }, fAR_ID_list := { { elementIdentifier := 108, lengthIndicator := 4, id_value := 1 } }, uRR_ID_list := omit, qER_ID_list := omit, activate_predefined_rules := omit } }, { elementIdentifier := 1, lengthIndicator := 110, grouped_ie := { pdr_id := { elementIdentifier := 56, lengthIndicator := 2, rule_id := '0004'O }, precedence := { elementIdentifier := 29, lengthIndicator := 4, precedence_value := 255 }, pdi := { elementIdentifier := 2, lengthIndicator := 79, grouped_ie := { source_interface := { elementIdentifier := 20, lengthIndicator := 1, interfacevalue := 0, spare := '0000'B }, local_F_TEID := { elementIdentifier := 21, lengthIndicator := 2, v4 := '1'B, v6 := '1'B, ch := '1'B, chid := '1'B, spare := '0000'B, teid := omit, ipv4_address := omit, ipv6_address := omit, choose_id := '05'O }, pdn_instance := { elementIdentifier := 22, lengthIndicator := 9, pdn_instance := '08696E7465726E6574'O ("\binternet") }, ue_ip_address := omit, traffic_endpoint_id := omit, sdf_filter_list := { { elementIdentifier := 23, lengthIndicator := 46, fd := '1'B, ttc := '0'B, spi := '0'B, fl := '0'B, bid := '0'B, spare := '00000000000'B, lengthFlowDesc := 42, flow_description := '7065726D6974206F75742035382066726F6D20666630323A3A322F31323820746F2061737369676E6564'O ("permit out 58 from ff02::2/128 to assigned"), ToS_Traffic_Class := omit, security_parameter_index := omit, flow_label := omit, SDF_filter_id := omit } }, application_id := omit, ethernet_packet_filter_list := omit, qfi_list := omit, source_interface_type := { elementIdentifier := 160, lengthIndicator := 1, interfacetypevalue := 11, spare := '00'B } } }, outer_header_removal := { elementIdentifier := 95, lengthIndicator := 1, ohc_description := 6 }, fAR_ID_list := { { elementIdentifier := 108, lengthIndicator := 4, id_value := 3 } }, uRR_ID_list := omit, qER_ID_list := omit, activate_predefined_rules := omit } } }, create_FAR_list := { { elementIdentifier := 3, lengthIndicator := 55, grouped_ie := { far_id := { elementIdentifier := 108, lengthIndicator := 4, id_value := 1 }, apply_action := { elementIdentifier := 44, lengthIndicator := 2, drop := '0'B, forw := '1'B, buff := '0'B, nocp := '0'B, dupl := '0'B, ipma := '0'B, ipmd := '0'B, dfrt := '0'B, edrt := '0'B, bdpn := '0'B, ddpn := '0'B, fssm := '0'B, mbsu := '0'B, spare := '000'B }, forwarding_parameters := { elementIdentifier := 4, lengthIndicator := 37, grouped_ie := { destination_interface := { elementIdentifier := 42, lengthIndicator := 1, interface_value := 0, spare := '0000'B }, pdn_Instance := { elementIdentifier := 22, lengthIndicator := 9, pdn_instance := '08696E7465726E6574'O ("\binternet") }, redirect_information := omit, outer_header_creation := { elementIdentifier := 84, lengthIndicator := 10, ohc_description_oct5 := '00000001'B, ohc_description_oct6 := '00000000'B, teid := '61CC915E'O, ipv4 := '7F000014'O, ipv6 := omit, udp_port := omit }, transport_level_marking := omit, forwarding_policy := omit, header_enrichment := omit, traffic_endpoint_ID := omit, destination_interface_type := { elementIdentifier := 160, lengthIndicator := 1, interfacetypevalue := 11, spare := '00'B } } }, duplicating_parameters := omit, bar_id := omit } }, { elementIdentifier := 3, lengthIndicator := 41, grouped_ie := { far_id := { elementIdentifier := 108, lengthIndicator := 4, id_value := 2 }, apply_action := { elementIdentifier := 44, lengthIndicator := 2, drop := '0'B, forw := '1'B, buff := '0'B, nocp := '0'B, dupl := '0'B, ipma := '0'B, ipmd := '0'B, dfrt := '0'B, edrt := '0'B, bdpn := '0'B, ddpn := '0'B, fssm := '0'B, mbsu := '0'B, spare := '000'B }, forwarding_parameters := { elementIdentifier := 4, lengthIndicator := 23, grouped_ie := { destination_interface := { elementIdentifier := 42, lengthIndicator := 1, interface_value := 1, spare := '0000'B }, pdn_Instance := { elementIdentifier := 22, lengthIndicator := 9, pdn_instance := '08696E7465726E6574'O ("\binternet") }, redirect_information := omit, outer_header_creation := omit, transport_level_marking := omit, forwarding_policy := omit, header_enrichment := omit, traffic_endpoint_ID := omit, destination_interface_type := { elementIdentifier := 160, lengthIndicator := 1, interfacetypevalue := 17, spare := '00'B } } }, duplicating_parameters := omit, bar_id := omit } }, { elementIdentifier := 3, lengthIndicator := 37, grouped_ie := { far_id := { elementIdentifier := 108, lengthIndicator := 4, id_value := 3 }, apply_action := { elementIdentifier := 44, lengthIndicator := 2, drop := '0'B, forw := '1'B, buff := '0'B, nocp := '0'B, dupl := '0'B, ipma := '0'B, ipmd := '0'B, dfrt := '0'B, edrt := '0'B, bdpn := '0'B, ddpn := '0'B, fssm := '0'B, mbsu := '0'B, spare := '000'B }, forwarding_parameters := { elementIdentifier := 4, lengthIndicator := 19, grouped_ie := { destination_interface := { elementIdentifier := 42, lengthIndicator := 1, interface_value := 3, spare := '0000'B }, pdn_Instance := omit, redirect_information := omit, outer_header_creation := { elementIdentifier := 84, lengthIndicator := 10, ohc_description_oct5 := '00000001'B, ohc_description_oct6 := '00000000'B, teid := '00000001'O, ipv4 := '7F000004'O, ipv6 := omit, udp_port := omit }, transport_level_marking := omit, forwarding_policy := omit, header_enrichment := omit, traffic_endpoint_ID := omit, destination_interface_type := omit } }, duplicating_parameters := omit, bar_id := omit } } }, create_URR_list := { { elementIdentifier := 6, lengthIndicator := 20, grouped_ie := { urr_id := { elementIdentifier := 81, lengthIndicator := 4, id_value := 1 }, measurement_method := { elementIdentifier := 62, lengthIndicator := 1, durat := '0'B, volum := '0'B, event := '0'B, spare := '00000'B }, reporting_triggers := { elementIdentifier := 37, lengthIndicator := 3, perio := '0'B, volth := '0'B, timth := '0'B, quhti := '0'B, start_ := '0'B, stop_ := '0'B, droth := '0'B, liusa := '0'B, volqu := '0'B, timqu := '0'B, envcl := '0'B, macar := '0'B, eveth := '0'B, evequ := '0'B, ipmjl := '0'B, quvti := '0'B, upint := '0'B, reemr := '0'B, spare := '000000'B }, measurement_period := omit, volume_threshold := omit, volume_quota := omit, time_threshold := omit, time_quota := omit, quota_holding_time := omit, dropped_dl_traffic_threshold := omit, quota_validity_time := omit, monitoring_time := omit, subsequent_volume_threshold := omit, subsequent_time_threshold := omit, subsequent_volume_quota := omit, subsequent_time_quota := omit, inactivity_detection_time := omit, linked_urr_id := omit, measurement_information := omit, time_qouta_mechanism := omit, aggregated_urrs := omit } } }, create_QER_list := { { elementIdentifier := 7, lengthIndicator := 27, grouped_ie := { qer_id := { elementIdentifier := 109, lengthIndicator := 4, id_value := 1 }, qer_correlation_id := omit, gate_status := { elementIdentifier := 25, lengthIndicator := 1, dl_gate := 0, ul_gate := 0, spare := '0000'B }, mbr := { elementIdentifier := 26, lengthIndicator := 10, ul_mbr := '0000008313'O, dl_mbr := '000000CCCD'O }, gbr := omit, packet_rate := omit, dl_transport_level_marking := omit, qfi := omit, rqi := omit } } }, create_BAR := { elementIdentifier := 85, lengthIndicator := 5, grouped_ie := { bar_id := { elementIdentifier := 88, lengthIndicator := 1, id_value := '01'O }, downlink_data_notification_delay := omit } }, create_traffic_endpoint_list := omit, pdn_type := { elementIdentifier := 113, lengthIndicator := 1, pdn_type := 1, spare := '00000'B }, node_list := omit, up_inactivity_timer := omit, user_id := { elementIdentifier := 141, lengthIndicator := 13, imsif := '1'B, imeif := '0'B, msisdnf := '1'B, naif := '0'B, supif := '0'B, gpsif := '0'B, peif := '0'B, spare := '0'B, imsi_length := 8, imsi := '00010121436587F9'O, imei_length := omit, imei := omit, msisdn_length := 2, msisdn := '2143'O ("!C"), nai_length := omit, nai := omit, supi_length := omit, supi := omit, gpsi_length := omit, gpsi := omit, pei_length := omit, pei := omit }, apn_dnn := { elementIdentifier := 159, lengthIndicator := 9, apn_dnn := '08696E7465726E6574'O ("\binternet") } } } } } TC_createSession_deleteSession-PFCP(13)@67af13c350bb: found destination TC_createSession_deleteSession(17) TC_createSession_deleteSession-PFCP(13)@67af13c350bb: PFCP_Emulation main() CLIENT.receive from TC_createSession_deleteSession(17): { s_flag := '0'B, mp := '0'B, spare := '000'B, version := 1, message_type := 0, lengthIndicator := 0, seid := '00000000000002A2'O, sequence_number := 5, spare2 := '0000'B, mp_or_spare := '0000'B, message_body := { pfcp_session_establishment_response := { node_id := { elementIdentifier := 60, lengthIndicator := 0, node_id_type := 0, spare := '0000'B, node_id_value := '7F000007'O }, cause := { elementIdentifier := 19, lengthIndicator := 0, causeValue := '01'O }, offending_ie := omit, UP_F_SEID := { elementIdentifier := 57, lengthIndicator := 0, v6 := '0'B, v4 := '1'B, spare := '000000'B, seid := '0000000000000001'O, ipv4_address := '7F000007'O, ipv6_address := omit }, created_PDR_list := { { elementIdentifier := 8, lengthIndicator := 0, grouped_ie := { pdr_id := { elementIdentifier := 56, lengthIndicator := 2, rule_id := '0001'O }, local_F_TEID := { elementIdentifier := 21, lengthIndicator := 0, v4 := '1'B, v6 := '0'B, ch := '0'B, chid := '0'B, spare := '0000'B, teid := '00002001'O, ipv4_address := '7F000007'O, ipv6_address := omit, choose_id := omit } } }, { elementIdentifier := 8, lengthIndicator := 0, grouped_ie := { pdr_id := { elementIdentifier := 56, lengthIndicator := 2, rule_id := '0002'O }, local_F_TEID := { elementIdentifier := 21, lengthIndicator := 0, v4 := '1'B, v6 := '0'B, ch := '0'B, chid := '0'B, spare := '0000'B, teid := '00000001'O, ipv4_address := '7F000007'O, ipv6_address := omit, choose_id := omit } } }, { elementIdentifier := 8, lengthIndicator := 0, grouped_ie := { pdr_id := { elementIdentifier := 56, lengthIndicator := 2, rule_id := '0003'O }, local_F_TEID := { elementIdentifier := 21, lengthIndicator := 0, v4 := '1'B, v6 := '0'B, ch := '0'B, chid := '0'B, spare := '0000'B, teid := '00002001'O, ipv4_address := '7F000007'O, ipv6_address := omit, choose_id := omit } } }, { elementIdentifier := 8, lengthIndicator := 0, grouped_ie := { pdr_id := { elementIdentifier := 56, lengthIndicator := 2, rule_id := '0004'O }, local_F_TEID := { elementIdentifier := 21, lengthIndicator := 0, v4 := '1'B, v6 := '0'B, ch := '0'B, chid := '0'B, spare := '0000'B, teid := '00000001'O, ipv4_address := '7F000007'O, ipv6_address := omit, choose_id := omit } } } }, load_control_information := omit, overload_control_information := omit, node_list := omit, failed_rule_id := omit, created_traffic_endpoint_list := omit } } } [32m09/03 13:08:33.831[0m: [[33mgtp[0m] [1;32mINFO[0m: gtp_connect() [127.0.0.7]:2152 (../lib/gtp/path.c:60) TC_createSession_deleteSession(17)@67af13c350bb: f_Mutex_unlock(): mutex released @ "SMF_Session_CT.ttcn":438 TC_createSession_deleteSession(17)@67af13c350bb: SMF assigned UE IPv4 address: "10.45.0.2" TC_createSession_deleteSession-PFCP(13)@67af13c350bb: PFCP_Emulation main() PFCP.receive: { peer := { conn_id := 1, remote_name := "127.0.0.4", remote_port := 8805 }, pdu := { s_flag := '1'B, mp := '0'B, spare := '000'B, version := 1, message_type := 54, lengthIndicator := 12, seid := '0000000000000001'O, sequence_number := 6, spare2 := '0000'B, mp_or_spare := '0000'B, message_body := { pfcp_session_deletion_request := { } } } } TC_createSession_deleteSession-PFCP(13)@67af13c350bb: found destination TC_createSession_deleteSession(17) TC_createSession_deleteSession-PFCP(13)@67af13c350bb: PFCP_Emulation main() CLIENT.receive from TC_createSession_deleteSession(17): { s_flag := '0'B, mp := '0'B, spare := '000'B, version := 1, message_type := 0, lengthIndicator := 0, seid := '00000000000002A2'O, sequence_number := 6, spare2 := '0000'B, mp_or_spare := '0000'B, message_body := { pfcp_session_deletion_response := { cause := { elementIdentifier := 19, lengthIndicator := 0, causeValue := '01'O }, offending_IE := omit, load_control_information := omit, overload_control_information := omit, usage_report := { { elementIdentifier := 79, lengthIndicator := 0, grouped_ie := { urr_id := { elementIdentifier := 81, lengthIndicator := 4, id_value := 1 }, ur_seqn := { elementIdentifier := 104, lengthIndicator := 0, ur_seqn := 0 }, usage_report_trigger := { elementIdentifier := 63, lengthIndicator := 0, perio := '0'B, volth := '0'B, timth := '0'B, quhti := '0'B, start_ := '0'B, stop_ := '0'B, droth := '0'B, immer := '0'B, volqu := '0'B, timqu := '0'B, liusa := '0'B, termr := '1'B, monit := '0'B, envcl := '0'B, macar := '0'B, eveth := '0'B, evequ := '0'B, tebur := '0'B, ipmjl := '0'B, quvti := '0'B, emrre := '0'B, upint := '0'B, spare7_7 := '0'B, spare7_8 := '0'B }, start_time := { elementIdentifier := 75, lengthIndicator := 0, start_time := 'EE43EFD1'O }, end_time := { elementIdentifier := 76, lengthIndicator := 0, end_time := 'EE43EFD1'O }, volume_measurement := omit, duration_measurement := omit, time_of_first_packet := omit, time_of_last_packet := omit, usage_information := omit } } } } } } Segmentation fault (core dumped) [1;34m[testenv] Looking for a coredump file[0m [0;94m[testenv] + ['file', 'https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/4/artifact/logs/smf/core'][0m [0;94m[testenv] Coredump file and execfn found[0m [1;91m[testenv] smf unexpected exit during SMF_Tests.TC_createSession_deleteSession! rc=0[0m [0;94m[testenv] Waiting until test has stopped...[0m MTC@67af13c350bb: setverdict(fail): pass -> fail reason: "Timeout of T_guard", new component reason: "Timeout of T_guard" MutexDispCT-TC_createSession_deleteSession(12)@67af13c350bb: Final verdict of PTC: none TC_createSession_deleteSession(15)@67af13c350bb: Final verdict of PTC: none GTP1U_EM(11)@67af13c350bb: Final verdict of PTC: none GTP2C_EM(10)@67af13c350bb: Final verdict of PTC: none TC_createSession_deleteSession(16)@67af13c350bb: Final verdict of PTC: none TC_createSession_deleteSession(14)@67af13c350bb: Final verdict of PTC: none TC_createSession_deleteSession-PFCP(13)@67af13c350bb: Final verdict of PTC: none TC_createSession_deleteSession(17)@67af13c350bb: Final verdict of PTC: none MTC@67af13c350bb: Setting final verdict of the test case. MTC@67af13c350bb: Local verdict of MTC: fail reason: "Timeout of T_guard" MTC@67af13c350bb: Local verdict of PTC GTP2C_EM(10): none (fail -> fail) MTC@67af13c350bb: Local verdict of PTC GTP1U_EM(11): none (fail -> fail) MTC@67af13c350bb: Local verdict of PTC MutexDispCT-TC_createSession_deleteSession(12): none (fail -> fail) MTC@67af13c350bb: Local verdict of PTC TC_createSession_deleteSession-PFCP(13): none (fail -> fail) MTC@67af13c350bb: Local verdict of PTC TC_createSession_deleteSession(14): none (fail -> fail) MTC@67af13c350bb: Local verdict of PTC TC_createSession_deleteSession(15): none (fail -> fail) MTC@67af13c350bb: Local verdict of PTC TC_createSession_deleteSession(16): none (fail -> fail) MTC@67af13c350bb: Local verdict of PTC TC_createSession_deleteSession(17): none (fail -> fail) MTC@67af13c350bb: Test case TC_createSession_deleteSession finished. Verdict: fail reason: Timeout of T_guard MTC@67af13c350bb: Starting external command `https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/src/osmo-ttcn3-hacks/ttcn3-tcpdump-stop.sh SMF_Tests.TC_createSession_deleteSession fail'. (13:09:29) load average: 0.85, 0.57, 0.84 [1;31m------ SMF_Tests.TC_createSession_deleteSession fail ------[0m
Waiting for packet dumper to finish... 0 (prev_count=-1, count=21504) MTC@67af13c350bb: External command `https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/src/osmo-ttcn3-hacks/ttcn3-tcpdump-stop.sh SMF_Tests.TC_createSession_deleteSession fail' was executed successfully (exit status: 0). MTC@67af13c350bb: Starting external command `https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/src/osmo-ttcn3-hacks/ttcn3-tcpdump-start.sh SMF_Tests.TC_deleteSession_unknown'. ------ SMF_Tests.TC_deleteSession_unknown ------ (13:09:30) load average: 0.85, 0.57, 0.84 /usr/bin/dumpcap -q -s 1520 -n -i any -w "https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/4/artifact/logs/testsuite/SMF_Tests.TC_deleteSession_unknown.pcap" >https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/4/artifact/logs/testsuite/SMF_Tests.TC_deleteSession_unknown.pcap.stdout 2>/tmp/cmderr & Waiting for packet dumper to start... 0 MTC@67af13c350bb: External command `https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/src/osmo-ttcn3-hacks/ttcn3-tcpdump-start.sh SMF_Tests.TC_deleteSession_unknown' was executed successfully (exit status: 0). MTC@67af13c350bb: Test case TC_deleteSession_unknown started. TC_deleteSession_unknown-PFCP(21)@67af13c350bb: f_PFCPEM_conns_bcast_add(): vc_conn mtc subscribed for broadcast TC_deleteSession_unknown-PFCP(21)@67af13c350bb: PFCP_Emulation main() CLIENT.receive from mtc: { s_flag := '0'B, mp := '0'B, spare := '000'B, version := 1, message_type := 0, lengthIndicator := 0, seid := omit, sequence_number := 0, spare2 := '0000'B, mp_or_spare := '0000'B, message_body := { heartbeat_request := { recovery_time_stamp := { elementIdentifier := 96, lengthIndicator := 0, time_value := 3997429771 } } } } MTC@67af13c350bb: setverdict(fail): none -> fail reason: ""PFCP_Emulation.ttcn:504 : Timeout waiting for PFCP { s_flag := ?, mp := ?, spare := ?, version := 1, message_type := ?, lengthIndicator := ?, seid := *, sequence_number := ?, spare2 := ?, mp_or_spare := ?, message_body := { pfcp_association_setup_request := { node_id := ?, time_stamp := ?, up_function_features := *, cp_function_features := *, UP_IP_resource_list := * } } }"", new component reason: ""PFCP_Emulation.ttcn:504 : Timeout waiting for PFCP { s_flag := ?, mp := ?, spare := ?, version := 1, message_type := ?, lengthIndicator := ?, seid := *, sequence_number := ?, spare2 := ?, mp_or_spare := ?, message_body := { pfcp_association_setup_request := { node_id := ?, time_stamp := ?, up_function_features := *, cp_function_features := *, UP_IP_resource_list := * } } }"" MTC@67af13c350bb: Stopping testcase execution from "PFCP_Emulation.ttcn":504 GTP2C_EM(18)@67af13c350bb: Final verdict of PTC: none GTP1U_EM(19)@67af13c350bb: Final verdict of PTC: none MutexDispCT-TC_deleteSession_unknown(20)@67af13c350bb: Final verdict of PTC: none TC_deleteSession_unknown-PFCP(21)@67af13c350bb: Final verdict of PTC: none MTC@67af13c350bb: Setting final verdict of the test case. MTC@67af13c350bb: Local verdict of MTC: fail reason: ""PFCP_Emulation.ttcn:504 : Timeout waiting for PFCP { s_flag := ?, mp := ?, spare := ?, version := 1, message_type := ?, lengthIndicator := ?, seid := *, sequence_number := ?, spare2 := ?, mp_or_spare := ?, message_body := { pfcp_association_setup_request := { node_id := ?, time_stamp := ?, up_function_features := *, cp_function_features := *, UP_IP_resource_list := * } } }"" MTC@67af13c350bb: Local verdict of PTC GTP2C_EM(18): none (fail -> fail) MTC@67af13c350bb: Local verdict of PTC GTP1U_EM(19): none (fail -> fail) MTC@67af13c350bb: Local verdict of PTC MutexDispCT-TC_deleteSession_unknown(20): none (fail -> fail) MTC@67af13c350bb: Local verdict of PTC TC_deleteSession_unknown-PFCP(21): none (fail -> fail) MTC@67af13c350bb: Test case TC_deleteSession_unknown finished. Verdict: fail reason: "PFCP_Emulation.ttcn:504 : Timeout waiting for PFCP { s_flag := ?, mp := ?, spare := ?, version := 1, message_type := ?, lengthIndicator := ?, seid := *, sequence_number := ?, spare2 := ?, mp_or_spare := ?, message_body := { pfcp_association_setup_request := { node_id := ?, time_stamp := ?, up_function_features := *, cp_function_features := *, UP_IP_resource_list := * } } }" MTC@67af13c350bb: Starting external command `https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/src/osmo-ttcn3-hacks/ttcn3-tcpdump-stop.sh SMF_Tests.TC_deleteSession_unknown fail'. (13:09:47) load average: 0.80, 0.58, 0.84 [1;31m------ SMF_Tests.TC_deleteSession_unknown fail ------[0m
Waiting for packet dumper to finish... 0 (prev_count=-1, count=1736) MTC@67af13c350bb: External command `https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/src/osmo-ttcn3-hacks/ttcn3-tcpdump-stop.sh SMF_Tests.TC_deleteSession_unknown fail' was executed successfully (exit status: 0). MTC@67af13c350bb: Starting external command `https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/src/osmo-ttcn3-hacks/ttcn3-tcpdump-start.sh SMF_Tests.TC_gy_charging_cc_time'. ------ SMF_Tests.TC_gy_charging_cc_time ------ (13:09:48) load average: 0.74, 0.57, 0.83 /usr/bin/dumpcap -q -s 1520 -n -i any -w "https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/4/artifact/logs/testsuite/SMF_Tests.TC_gy_charging_cc_time.pcap" >https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/4/artifact/logs/testsuite/SMF_Tests.TC_gy_charging_cc_time.pcap.stdout 2>/tmp/cmderr & Waiting for packet dumper to start... 0 MTC@67af13c350bb: External command `https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/src/osmo-ttcn3-hacks/ttcn3-tcpdump-start.sh SMF_Tests.TC_gy_charging_cc_time' was executed successfully (exit status: 0). MTC@67af13c350bb: Test case TC_gy_charging_cc_time started. TC_gy_charging_cc_time-PFCP(25)@67af13c350bb: f_PFCPEM_conns_bcast_add(): vc_conn mtc subscribed for broadcast TC_gy_charging_cc_time-PFCP(25)@67af13c350bb: PFCP_Emulation main() CLIENT.receive from mtc: { s_flag := '0'B, mp := '0'B, spare := '000'B, version := 1, message_type := 0, lengthIndicator := 0, seid := omit, sequence_number := 0, spare2 := '0000'B, mp_or_spare := '0000'B, message_body := { heartbeat_request := { recovery_time_stamp := { elementIdentifier := 96, lengthIndicator := 0, time_value := 3997429790 } } } } MTC@67af13c350bb: setverdict(fail): none -> fail reason: ""PFCP_Emulation.ttcn:504 : Timeout waiting for PFCP { s_flag := ?, mp := ?, spare := ?, version := 1, message_type := ?, lengthIndicator := ?, seid := *, sequence_number := ?, spare2 := ?, mp_or_spare := ?, message_body := { pfcp_association_setup_request := { node_id := ?, time_stamp := ?, up_function_features := *, cp_function_features := *, UP_IP_resource_list := * } } }"", new component reason: ""PFCP_Emulation.ttcn:504 : Timeout waiting for PFCP { s_flag := ?, mp := ?, spare := ?, version := 1, message_type := ?, lengthIndicator := ?, seid := *, sequence_number := ?, spare2 := ?, mp_or_spare := ?, message_body := { pfcp_association_setup_request := { node_id := ?, time_stamp := ?, up_function_features := *, cp_function_features := *, UP_IP_resource_list := * } } }"" MTC@67af13c350bb: Stopping testcase execution from "PFCP_Emulation.ttcn":504 GTP1U_EM(23)@67af13c350bb: Final verdict of PTC: none GTP2C_EM(22)@67af13c350bb: Final verdict of PTC: none MutexDispCT-TC_gy_charging_cc_time(24)@67af13c350bb: Final verdict of PTC: none TC_gy_charging_cc_time-PFCP(25)@67af13c350bb: Final verdict of PTC: none MTC@67af13c350bb: Setting final verdict of the test case. MTC@67af13c350bb: Local verdict of MTC: fail reason: ""PFCP_Emulation.ttcn:504 : Timeout waiting for PFCP { s_flag := ?, mp := ?, spare := ?, version := 1, message_type := ?, lengthIndicator := ?, seid := *, sequence_number := ?, spare2 := ?, mp_or_spare := ?, message_body := { pfcp_association_setup_request := { node_id := ?, time_stamp := ?, up_function_features := *, cp_function_features := *, UP_IP_resource_list := * } } }"" MTC@67af13c350bb: Local verdict of PTC GTP2C_EM(22): none (fail -> fail) MTC@67af13c350bb: Local verdict of PTC GTP1U_EM(23): none (fail -> fail) MTC@67af13c350bb: Local verdict of PTC MutexDispCT-TC_gy_charging_cc_time(24): none (fail -> fail) MTC@67af13c350bb: Local verdict of PTC TC_gy_charging_cc_time-PFCP(25): none (fail -> fail) MTC@67af13c350bb: Test case TC_gy_charging_cc_time finished. Verdict: fail reason: "PFCP_Emulation.ttcn:504 : Timeout waiting for PFCP { s_flag := ?, mp := ?, spare := ?, version := 1, message_type := ?, lengthIndicator := ?, seid := *, sequence_number := ?, spare2 := ?, mp_or_spare := ?, message_body := { pfcp_association_setup_request := { node_id := ?, time_stamp := ?, up_function_features := *, cp_function_features := *, UP_IP_resource_list := * } } }" MTC@67af13c350bb: Starting external command `https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/src/osmo-ttcn3-hacks/ttcn3-tcpdump-stop.sh SMF_Tests.TC_gy_charging_cc_time fail'. (13:10:06) load average: 0.78, 0.59, 0.83 [1;31m------ SMF_Tests.TC_gy_charging_cc_time fail ------[0m
Waiting for packet dumper to finish... 0 (prev_count=-1, count=1724) MTC@67af13c350bb: External command `https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/src/osmo-ttcn3-hacks/ttcn3-tcpdump-stop.sh SMF_Tests.TC_gy_charging_cc_time fail' was executed successfully (exit status: 0). MTC@67af13c350bb: Starting external command `https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/src/osmo-ttcn3-hacks/ttcn3-tcpdump-start.sh SMF_Tests.TC_gy_ccr_update_rejected'. ------ SMF_Tests.TC_gy_ccr_update_rejected ------ (13:10:07) load average: 0.78, 0.59, 0.83 /usr/bin/dumpcap -q -s 1520 -n -i any -w "https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/4/artifact/logs/testsuite/SMF_Tests.TC_gy_ccr_update_rejected.pcap" >https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/4/artifact/logs/testsuite/SMF_Tests.TC_gy_ccr_update_rejected.pcap.stdout 2>/tmp/cmderr & Waiting for packet dumper to start... 0 MTC@67af13c350bb: External command `https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/src/osmo-ttcn3-hacks/ttcn3-tcpdump-start.sh SMF_Tests.TC_gy_ccr_update_rejected' was executed successfully (exit status: 0). MTC@67af13c350bb: Test case TC_gy_ccr_update_rejected started. TC_gy_ccr_update_rejected-PFCP(29)@67af13c350bb: f_PFCPEM_conns_bcast_add(): vc_conn mtc subscribed for broadcast TC_gy_ccr_update_rejected-PFCP(29)@67af13c350bb: PFCP_Emulation main() CLIENT.receive from mtc: { s_flag := '0'B, mp := '0'B, spare := '000'B, version := 1, message_type := 0, lengthIndicator := 0, seid := omit, sequence_number := 0, spare2 := '0000'B, mp_or_spare := '0000'B, message_body := { heartbeat_request := { recovery_time_stamp := { elementIdentifier := 96, lengthIndicator := 0, time_value := 3997429808 } } } } MTC@67af13c350bb: setverdict(fail): none -> fail reason: ""PFCP_Emulation.ttcn:504 : Timeout waiting for PFCP { s_flag := ?, mp := ?, spare := ?, version := 1, message_type := ?, lengthIndicator := ?, seid := *, sequence_number := ?, spare2 := ?, mp_or_spare := ?, message_body := { pfcp_association_setup_request := { node_id := ?, time_stamp := ?, up_function_features := *, cp_function_features := *, UP_IP_resource_list := * } } }"", new component reason: ""PFCP_Emulation.ttcn:504 : Timeout waiting for PFCP { s_flag := ?, mp := ?, spare := ?, version := 1, message_type := ?, lengthIndicator := ?, seid := *, sequence_number := ?, spare2 := ?, mp_or_spare := ?, message_body := { pfcp_association_setup_request := { node_id := ?, time_stamp := ?, up_function_features := *, cp_function_features := *, UP_IP_resource_list := * } } }"" MTC@67af13c350bb: Stopping testcase execution from "PFCP_Emulation.ttcn":504 GTP1U_EM(27)@67af13c350bb: Final verdict of PTC: none GTP2C_EM(26)@67af13c350bb: Final verdict of PTC: none MutexDispCT-TC_gy_ccr_update_rejected(28)@67af13c350bb: Final verdict of PTC: none TC_gy_ccr_update_rejected-PFCP(29)@67af13c350bb: Final verdict of PTC: none MTC@67af13c350bb: Setting final verdict of the test case. MTC@67af13c350bb: Local verdict of MTC: fail reason: ""PFCP_Emulation.ttcn:504 : Timeout waiting for PFCP { s_flag := ?, mp := ?, spare := ?, version := 1, message_type := ?, lengthIndicator := ?, seid := *, sequence_number := ?, spare2 := ?, mp_or_spare := ?, message_body := { pfcp_association_setup_request := { node_id := ?, time_stamp := ?, up_function_features := *, cp_function_features := *, UP_IP_resource_list := * } } }"" MTC@67af13c350bb: Local verdict of PTC GTP2C_EM(26): none (fail -> fail) MTC@67af13c350bb: Local verdict of PTC GTP1U_EM(27): none (fail -> fail) MTC@67af13c350bb: Local verdict of PTC MutexDispCT-TC_gy_ccr_update_rejected(28): none (fail -> fail) MTC@67af13c350bb: Local verdict of PTC TC_gy_ccr_update_rejected-PFCP(29): none (fail -> fail) MTC@67af13c350bb: Test case TC_gy_ccr_update_rejected finished. Verdict: fail reason: "PFCP_Emulation.ttcn:504 : Timeout waiting for PFCP { s_flag := ?, mp := ?, spare := ?, version := 1, message_type := ?, lengthIndicator := ?, seid := *, sequence_number := ?, spare2 := ?, mp_or_spare := ?, message_body := { pfcp_association_setup_request := { node_id := ?, time_stamp := ?, up_function_features := *, cp_function_features := *, UP_IP_resource_list := * } } }" MTC@67af13c350bb: Starting external command `https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/src/osmo-ttcn3-hacks/ttcn3-tcpdump-stop.sh SMF_Tests.TC_gy_ccr_update_rejected fail'. (13:10:24) load average: 0.72, 0.58, 0.83 [1;31m------ SMF_Tests.TC_gy_ccr_update_rejected fail ------[0m
Waiting for packet dumper to finish... 0 (prev_count=-1, count=2000) MTC@67af13c350bb: External command `https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/src/osmo-ttcn3-hacks/ttcn3-tcpdump-stop.sh SMF_Tests.TC_gy_ccr_update_rejected fail' was executed successfully (exit status: 0). MTC@67af13c350bb: Starting external command `https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/src/osmo-ttcn3-hacks/ttcn3-tcpdump-start.sh SMF_Tests.TC_s2b_createSession_v4_noapco'. ------ SMF_Tests.TC_s2b_createSession_v4_noapco ------ (13:10:25) load average: 0.72, 0.58, 0.83 /usr/bin/dumpcap -q -s 1520 -n -i any -w "https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/4/artifact/logs/testsuite/SMF_Tests.TC_s2b_createSession_v4_noapco.pcap" >https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/4/artifact/logs/testsuite/SMF_Tests.TC_s2b_createSession_v4_noapco.pcap.stdout 2>/tmp/cmderr & Waiting for packet dumper to start... 0 MTC@67af13c350bb: External command `https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/src/osmo-ttcn3-hacks/ttcn3-tcpdump-start.sh SMF_Tests.TC_s2b_createSession_v4_noapco' was executed successfully (exit status: 0). MTC@67af13c350bb: Test case TC_s2b_createSession_v4_noapco started. TC_s2b_createSession_v4_noapco-PFCP(33)@67af13c350bb: f_PFCPEM_conns_bcast_add(): vc_conn mtc subscribed for broadcast TC_s2b_createSession_v4_noapco-PFCP(33)@67af13c350bb: PFCP_Emulation main() CLIENT.receive from mtc: { s_flag := '0'B, mp := '0'B, spare := '000'B, version := 1, message_type := 0, lengthIndicator := 0, seid := omit, sequence_number := 0, spare2 := '0000'B, mp_or_spare := '0000'B, message_body := { heartbeat_request := { recovery_time_stamp := { elementIdentifier := 96, lengthIndicator := 0, time_value := 3997429826 } } } } [1;91m[testenv] Timeout in wait_until_test_stopped()[0m [1;34m[testenv] Running gdb to get a backtrace[0m [0;94m[testenv] + ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/cache', '-e', 'TESTENV_SRC_DIR=https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/src', '-e', 'TESTENV_INSTALL_DIR=/', '-e', 'TERM=dumb', '-e', 'PATH=https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/src/osmo-ttcn3-hacks/_testenv/data/scripts:https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/src/osmo-ttcn3-hacks/_testenv/data/scripts/qemu:https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/src/osmo-ttcn3-hacks/smf:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-e', 'PYTHONUNBUFFERED=1', '-t', 'testenv-smf-osmocom-latest-20260903-1308-9e5ca84a-1', 'sh', '-c', 'echo; gdb --batch /usr/bin/open5gs-smfd https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/4/artifact/logs/smf/core -ex bt | tee https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/4/artifact/logs/smf/core.backtrace; echo'][0m
[New LWP 212] [New LWP 186] [New LWP 185] [New LWP 188] [New LWP 187] [New LWP 189] [New LWP 190] [New LWP 192] [New LWP 191] [New LWP 193] [New LWP 194] [New LWP 196] [New LWP 198] [New LWP 197] [New LWP 199] [New LWP 184] [New LWP 182] [New LWP 200] [New LWP 195] [New LWP 201] [New LWP 203] [New LWP 205] [New LWP 202] [New LWP 204] [New LWP 206] [New LWP 207] [New LWP 208] [New LWP 348] [New LWP 349] [New LWP 352] [New LWP 353] [New LWP 357] [New LWP 358] [Thread debugging using libthread_db enabled] Using host libthread_db library "/lib/x86_64-linux-gnu/libthread_db.so.1". Core was generated by `/usr/bin/open5gs-smfd -c open5gs-smf.yaml'. Program terminated with signal SIGSEGV, Segmentation fault.
#0 0x00007f1f20911f69 in ogs_pfcp_parse_volume_measurement (volume=volume@entry=0x7f1ead7f79b0, octet=octet@entry=0x7f1e8000c7d0) at ../lib/pfcp/types.c:591 warning: 591 ../lib/pfcp/types.c: No such file or directory [Current thread is 1 (Thread 0x7f1ead7fa6c0 (LWP 212))] #0 0x00007f1f20911f69 in ogs_pfcp_parse_volume_measurement (volume=volume@entry=0x7f1ead7f79b0, octet=octet@entry=0x7f1e8000c7d0) at ../lib/pfcp/types.c:591 #1 0x0000555ee9580886 in smf_epc_n4_handle_session_deletion_response (sess=sess@entry=0x7f1f0940c010, xact=xact@entry=0x7f1f08ba7748, rsp=rsp@entry=0x7f1e8000c6b8) at ../src/smf/n4-handler.c:1548 #2 0x0000555ee9552738 in smf_gsm_state_wait_pfcp_deletion (s=s@entry=0x7f1f0940c850, e=e@entry=0x7f1ee8000e30) at ../src/smf/gsm-sm.c:2313 #3 0x00007f1f214cbe24 in ogs_fsm_dispatch (fsm=0x7f1f0940c850, event=0x7f1ee8000e30) at ../lib/core/ogs-fsm.c:127 #4 0x00007f1f214cbe24 in ogs_fsm_dispatch (fsm=0x555f014e0258, event=0x7f1ee8000e30) at ../lib/core/ogs-fsm.c:127 #5 0x0000555ee9547dc6 in smf_state_operational (s=s@entry=0x7f1ead7f9c20, e=e@entry=0x7f1ee8000e30) at ../src/smf/smf-sm.c:441 #6 0x00007f1f214cbe24 in ogs_fsm_dispatch (fsm=0x7f1ead7f9c20, event=0x7f1ee8000e30) at ../lib/core/ogs-fsm.c:127 #7 0x0000555ee9539859 in smf_main (data=<optimized out>) at ../src/smf/init.c:194 #8 0x00007f1f214c1005 in thread_worker (arg=0x555f0171b190) at ../lib/core/ogs-thread.c:67 #9 0x00007f1f20206b7b in start_thread (arg=<optimized out>) at ./nptl/pthread_create.c:448 #10 0x00007f1f202847f8 in __GI___clone3 () at ../sysdeps/unix/sysv/linux/x86_64/clone3.S:78
[1;34m[testenv] Stopping podman container[0m [0;94m[testenv] + ['podman', 'kill', 'testenv-smf-osmocom-latest-20260903-1308-9e5ca84a-1'][0m testenv-smf-osmocom-latest-20260903-1308-9e5ca84a-1 https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/4/artifact/logs/smf/core: ELF 64-bit LSB core file, x86-64, version 1 (SYSV), SVR4-style, from 'open5gs-smfd -c open5gs-smf.yaml', real uid: 0, effective uid: 0, real gid: 0, effective gid: 0, execfn: '/usr/bin/open5gs-smfd', platform: 'x86_64' [1;34m[testenv] Stopping testsuite (94348)[0m [0;94m[testenv] feed_watchdog_loop: podman container has stopped[0m [1;34m[testenv] Logs saved to: https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/4/artifact... [0m + RC=1 + [ 1 = 0 ] + uptime + grep --color=always -o load.* [01;31m[Kload average: 0.75, 0.60, 0.83[m[K + exit 1 Build step 'Execute shell' marked build as failure Recording test results [Checks API] No suitable checks publisher found.