04 Sep '26
04 Sep '26
See <https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/1/display…>
Changes:
------------------------------------------
[...truncated 109.52 KiB...]
cmtc
MC@29c9d80cf285: Downloading configuration file to all HCs.
construct junitlogger
Initializing `JUnitLogger' (v2.0): JUnitLogger writes JUnit-compatible XML
MC@29c9d80cf285: Configuration file was processed on all HCs.
MC@29c9d80cf285: Creating MTC on host 10.0.2.100.
MC@29c9d80cf285: MTC is created.
MC2> smtc
Executing all items of [EXECUTE] section.
MC2> MTC@29c9d80cf285: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/ttcn3-…> SMF_Tests.TC_tx_echo'.
------ SMF_Tests.TC_tx_echo ------
(13:08:30) load average: 2.37, 0.88, 0.83
/usr/bin/dumpcap -q -s 1520 -n -i any -w "<https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/1/artifac…"> ><https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/1/artifac…> 2>/tmp/cmderr &
Waiting for packet dumper to start... 0
MTC@29c9d80cf285: External command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/ttcn3-…> SMF_Tests.TC_tx_echo' was executed successfully (exit status: 0).
MTC@29c9d80cf285: Test case TC_tx_echo started.
TC_tx_echo-PFCP(6)@29c9d80cf285: f_PFCPEM_conns_bcast_add(): vc_conn mtc subscribed for broadcast
TC_tx_echo-PFCP(6)@29c9d80cf285: 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 := 3997170511 } } } }
TC_tx_echo-PFCP(6)@29c9d80cf285: 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 := 460001, spare2 := '0000'B, mp_or_spare := '0000'B, message_body := { heartbeat_response := { recovery_time_stamp := { elementIdentifier := 96, lengthIndicator := 4, time_value := 3997170509 } } } } }
TC_tx_echo-PFCP(6)@29c9d80cf285: found destination mtc
TC_tx_echo-PFCP(6)@29c9d80cf285: 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 := 1, 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 := 3997170509 }, 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_tx_echo-PFCP(6)@29c9d80cf285: broadcasting to mtc
MTC@29c9d80cf285: setverdict(pass): none -> pass
TC_tx_echo-PFCP(6)@29c9d80cf285: 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 := 1, 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 := 3997170511 }, 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 := '0'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 } } }
[32m08/31 13:08:32.251[0m: [[33msmf[0m] [1;32mINFO[0m: PFCP associated [127.0.0.7]:8805 (../src/smf/pfcp-sm.c:189)
TC_tx_echo-PFCP(6)@29c9d80cf285: f_PFCPEM_conns_bcast_del(): vc_conn mtc unsubscribed from broadcast
TC_tx_echo-PFCP(6)@29c9d80cf285: Rx Heartbeat Req: { s_flag := '0'B, mp := '0'B, spare := '000'B, version := 1, message_type := 1, lengthIndicator := 12, seid := omit, sequence_number := 2, spare2 := '0000'B, mp_or_spare := '0000'B, message_body := { heartbeat_request := { recovery_time_stamp := { elementIdentifier := 96, lengthIndicator := 4, time_value := 3997170509 } } } }
TC_tx_echo(7)@29c9d80cf285: Warning: sizes of 'struct sctp_event_subscribe': compile-time 14, kernel: 14
TC_tx_echo(8)@29c9d80cf285: Warning: sizes of 'struct sctp_event_subscribe': compile-time 14, kernel: 14
TC_tx_echo(9)@29c9d80cf285: Warning: sizes of 'struct sctp_event_subscribe': compile-time 14, kernel: 14
[32m08/31 13:08:32.432[0m: [[33mdiam[0m] [1;32mINFO[0m: CONNECTED TO 'aaa.localdomain' (SCTP,soc#17): (../lib/diameter/common/logger.c:81)
[32m08/31 13:08:33.466[0m: [[33mdiam[0m] [1;32mINFO[0m: CONNECTED TO 'ocs.localdomain' (SCTP,soc#18): (../lib/diameter/common/logger.c:81)
[32m08/31 13:08:33.710[0m: [[33mdiam[0m] [1;32mINFO[0m: CONNECTED TO 'pcrf.localdomain' (SCTP,soc#19): (../lib/diameter/common/logger.c:81)
MTC@29c9d80cf285: setverdict(pass): pass -> pass, component reason not changed
GTP1U_EM(4)@29c9d80cf285: Final verdict of PTC: none
GTP2C_EM(3)@29c9d80cf285: Final verdict of PTC: none
MutexDispCT-TC_tx_echo(5)@29c9d80cf285: Final verdict of PTC: none
[32m08/31 13:08:34.736[0m: [[33mdiam[0m] [1;33mERROR[0m: pid:PSM/aaa.localdomain in fd_psm_change_state@p_psm.c:284: 'STATE_OPEN' -> 'STATE_CLOSED' 'aaa.localdomain'
((null):0)
[32m08/31 13:08:34.736[0m: [[33mdiam[0m] [1;33mERROR[0m: pid:PSM/pcrf.localdomain in fd_psm_change_state@p_psm.c:284: 'STATE_OPEN' -> 'STATE_CLOSED' 'pcrf.localdomain'
((null):0)
TC_tx_echo-PFCP(6)@29c9d80cf285: Final verdict of PTC: none
[32m08/31 13:08:34.736[0m: [[33mdiam[0m] [1;33mERROR[0m: pid:PSM/ocs.localdomain in fd_psm_change_state@p_psm.c:284: 'STATE_OPEN' -> 'STATE_CLOSED' 'ocs.localdomain'
((null):0)
TC_tx_echo(8)@29c9d80cf285: Final verdict of PTC: none
TC_tx_echo(7)@29c9d80cf285: Final verdict of PTC: none
TC_tx_echo(9)@29c9d80cf285: Final verdict of PTC: none
MTC@29c9d80cf285: Setting final verdict of the test case.
MTC@29c9d80cf285: Local verdict of MTC: pass
MTC@29c9d80cf285: Local verdict of PTC GTP2C_EM(3): none (pass -> pass)
MTC@29c9d80cf285: Local verdict of PTC GTP1U_EM(4): none (pass -> pass)
MTC@29c9d80cf285: Local verdict of PTC MutexDispCT-TC_tx_echo(5): none (pass -> pass)
MTC@29c9d80cf285: Local verdict of PTC TC_tx_echo-PFCP(6): none (pass -> pass)
MTC@29c9d80cf285: Local verdict of PTC TC_tx_echo(7): none (pass -> pass)
MTC@29c9d80cf285: Local verdict of PTC TC_tx_echo(8): none (pass -> pass)
MTC@29c9d80cf285: Local verdict of PTC TC_tx_echo(9): none (pass -> pass)
MTC@29c9d80cf285: Test case TC_tx_echo finished. Verdict: pass
MTC@29c9d80cf285: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/ttcn3-…> SMF_Tests.TC_tx_echo pass'.
(13:08:34) load average: 2.26, 0.89, 0.83
[1;32m====== SMF_Tests.TC_tx_echo pass ======[0m
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@29c9d80cf285: External command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/ttcn3-…> SMF_Tests.TC_tx_echo pass' was executed successfully (exit status: 0).
MTC@29c9d80cf285: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/ttcn3-…> SMF_Tests.TC_createSession_deleteSession'.
------ SMF_Tests.TC_createSession_deleteSession ------
(13:08:36) load average: 2.08, 0.87, 0.82
/usr/bin/dumpcap -q -s 1520 -n -i any -w "<https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/1/artifac…"> ><https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/1/artifac…> 2>/tmp/cmderr &
Waiting for packet dumper to start... 0
MTC@29c9d80cf285: External command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/ttcn3-…> SMF_Tests.TC_createSession_deleteSession' was executed successfully (exit status: 0).
MTC@29c9d80cf285: Test case TC_createSession_deleteSession started.
TC_createSession_deleteSession-PFCP(13)@29c9d80cf285: f_PFCPEM_conns_bcast_add(): vc_conn mtc subscribed for broadcast
TC_createSession_deleteSession-PFCP(13)@29c9d80cf285: 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 := 3997170517 } } } }
[32m08/31 13:08:37.889[0m: [[33mpfcp[0m] [1;33mERROR[0m: Remote PFCP restarted [3997170511<3997170517] in Heartbeat REQ (../lib/pfcp/handler.c:39)
[32m08/31 13:08:37.889[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)@29c9d80cf285: 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 := 6510001, spare2 := '0000'B, mp_or_spare := '0000'B, message_body := { heartbeat_response := { recovery_time_stamp := { elementIdentifier := 96, lengthIndicator := 4, time_value := 3997170509 } } } } }
TC_createSession_deleteSession-PFCP(13)@29c9d80cf285: found destination mtc
TC_createSession_deleteSession-PFCP(13)@29c9d80cf285: 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 := 3997170509 }, 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)@29c9d80cf285: broadcasting to mtc
MTC@29c9d80cf285: setverdict(pass): none -> pass
TC_createSession_deleteSession-PFCP(13)@29c9d80cf285: 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 := 3997170517 }, 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 := '0'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 } } }
[32m08/31 13:08:37.891[0m: [[33msmf[0m] [1;32mINFO[0m: PFCP associated [127.0.0.7]:8805 (../src/smf/pfcp-sm.c:189)
[32m08/31 13:08:37.891[0m: [[33msmf[0m] [1;33mERROR[0m: PFCP restoration (../src/smf/pfcp-sm.c:199)
TC_createSession_deleteSession-PFCP(13)@29c9d80cf285: f_PFCPEM_conns_bcast_del(): vc_conn mtc unsubscribed from broadcast
TC_createSession_deleteSession-PFCP(13)@29c9d80cf285: 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 := 3997170509 } } } }
TC_createSession_deleteSession(14)@29c9d80cf285: Warning: sizes of 'struct sctp_event_subscribe': compile-time 14, kernel: 14
TC_createSession_deleteSession(15)@29c9d80cf285: Warning: sizes of 'struct sctp_event_subscribe': compile-time 14, kernel: 14
TC_createSession_deleteSession(16)@29c9d80cf285: Warning: sizes of 'struct sctp_event_subscribe': compile-time 14, kernel: 14
[32m08/31 13:08:38.457[0m: [[33mdiam[0m] [1;32mINFO[0m: CONNECTED TO 'aaa.localdomain' (SCTP,soc#17): (../lib/diameter/common/logger.c:81)
[32m08/31 13:08:39.672[0m: [[33mdiam[0m] [1;32mINFO[0m: CONNECTED TO 'ocs.localdomain' (SCTP,soc#18): (../lib/diameter/common/logger.c:81)
[32m08/31 13:08:40.739[0m: [[33msbi[0m] [1;36mWARNING[0m: [0f859ec4-a53d-41f1-a76c-dda2e2791897] Retry registration with NRF (../lib/sbi/nf-sm.c:282)
[32m08/31 13:08:40.739[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)
[32m08/31 13:08:40.739[0m: [[33msbi[0m] [1;36mWARNING[0m: ogs_sbi_client_handler() failed [-1] (../lib/sbi/path.c:62)
[32m08/31 13:08:40.944[0m: [[33mdiam[0m] [1;32mINFO[0m: CONNECTED TO 'pcrf.localdomain' (SCTP,soc#19): (../lib/diameter/common/logger.c:81)
TC_createSession_deleteSession(14)@29c9d80cf285: Created Expect[0] for '001010123456789'H to be handled at TC_createSession_deleteSession(17)
TC_createSession_deleteSession(15)@29c9d80cf285: Created Expect[0] for '001010123456789'H to be handled at TC_createSession_deleteSession(17)
TC_createSession_deleteSession(16)@29c9d80cf285: Created Expect[0] for '001010123456789'H to be handled at TC_createSession_deleteSession(17)
TC_createSession_deleteSession(17)@29c9d80cf285: f_Mutex_lock(): mutex acquired @ "SMF_Session_CT.ttcn":402
[32m08/31 13:08:42.037[0m: [[33msmf[0m] [1;32mINFO[0m: [Added] Number of SMF-UEs is now 1 (../src/smf/context.c:1069)
[32m08/31 13:08:42.037[0m: [[33msmf[0m] [1;32mINFO[0m: [Added] Number of SMF-Sessions is now 1 (../src/smf/context.c:3612)
[32m08/31 13:08:42.037[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)@29c9d80cf285: 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 := '10760088'O, end_to_end_id := 'CCDEDA22'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 := '736D662E6C6F63616C646F6D61696E3B313738383138313730393B323B6170705F6779'O ("smf.localdomain;1788181709;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(a)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 := 'EE3FFB5A'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)@29c9d80cf285: 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 := '1F84E2D0'O, end_to_end_id := 'CCDEDA21'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 := '736D662E6C6F63616C646F6D61696E3B313738383138313730393B313B6170705F6778'O ("smf.localdomain;1788181709;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)@29c9d80cf285: 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 := '0000000000000FF8'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 := '2D95F3A5'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)@29c9d80cf285: found destination TC_createSession_deleteSession(17)
TC_createSession_deleteSession-PFCP(13)@29c9d80cf285: 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 := '0000000000000FF8'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 } } }
[32m08/31 13:08:42.081[0m: [[33mgtp[0m] [1;32mINFO[0m: gtp_connect() [127.0.0.7]:2152 (../lib/gtp/path.c:60)
TC_createSession_deleteSession(17)@29c9d80cf285: f_Mutex_unlock(): mutex released @ "SMF_Session_CT.ttcn":427
TC_createSession_deleteSession(17)@29c9d80cf285: SMF assigned UE IPv4 address: "10.45.0.2"
TC_createSession_deleteSession-PFCP(13)@29c9d80cf285: 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)@29c9d80cf285: found destination TC_createSession_deleteSession(17)
TC_createSession_deleteSession-PFCP(13)@29c9d80cf285: 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 := '0000000000000FF8'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 := 'EE3FFB5A'O }, end_time := { elementIdentifier := 76, lengthIndicator := 0, end_time := 'EE3FFB5A'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/1/artifac…'][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@29c9d80cf285: setverdict(fail): pass -> fail reason: "Timeout of T_guard", new component reason: "Timeout of T_guard"
MutexDispCT-TC_createSession_deleteSession(12)@29c9d80cf285: Final verdict of PTC: none
TC_createSession_deleteSession(17)@29c9d80cf285: Final verdict of PTC: none
GTP1U_EM(11)@29c9d80cf285: Final verdict of PTC: none
TC_createSession_deleteSession(15)@29c9d80cf285: Final verdict of PTC: none
TC_createSession_deleteSession(14)@29c9d80cf285: Final verdict of PTC: none
TC_createSession_deleteSession(16)@29c9d80cf285: Final verdict of PTC: none
TC_createSession_deleteSession-PFCP(13)@29c9d80cf285: Final verdict of PTC: none
GTP2C_EM(10)@29c9d80cf285: Final verdict of PTC: none
MTC@29c9d80cf285: Setting final verdict of the test case.
MTC@29c9d80cf285: Local verdict of MTC: fail reason: "Timeout of T_guard"
MTC@29c9d80cf285: Local verdict of PTC GTP2C_EM(10): none (fail -> fail)
MTC@29c9d80cf285: Local verdict of PTC GTP1U_EM(11): none (fail -> fail)
MTC@29c9d80cf285: Local verdict of PTC MutexDispCT-TC_createSession_deleteSession(12): none (fail -> fail)
MTC@29c9d80cf285: Local verdict of PTC TC_createSession_deleteSession-PFCP(13): none (fail -> fail)
MTC@29c9d80cf285: Local verdict of PTC TC_createSession_deleteSession(14): none (fail -> fail)
MTC@29c9d80cf285: Local verdict of PTC TC_createSession_deleteSession(15): none (fail -> fail)
MTC@29c9d80cf285: Local verdict of PTC TC_createSession_deleteSession(16): none (fail -> fail)
MTC@29c9d80cf285: Local verdict of PTC TC_createSession_deleteSession(17): none (fail -> fail)
MTC@29c9d80cf285: Test case TC_createSession_deleteSession finished. Verdict: fail reason: Timeout of T_guard
MTC@29c9d80cf285: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/ttcn3-…> SMF_Tests.TC_createSession_deleteSession fail'.
(13:09:37) load average: 1.52, 0.91, 0.84
[1;31m------ SMF_Tests.TC_createSession_deleteSession fail ------[0m
Waiting for packet dumper to finish... 0 (prev_count=-1, count=21240)
MTC@29c9d80cf285: External command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/ttcn3-…> SMF_Tests.TC_createSession_deleteSession fail' was executed successfully (exit status: 0).
MTC@29c9d80cf285: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/ttcn3-…> SMF_Tests.TC_deleteSession_unknown'.
------ SMF_Tests.TC_deleteSession_unknown ------
(13:09:38) load average: 1.52, 0.91, 0.84
/usr/bin/dumpcap -q -s 1520 -n -i any -w "<https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/1/artifac…"> ><https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/1/artifac…> 2>/tmp/cmderr &
Waiting for packet dumper to start... 0
MTC@29c9d80cf285: External command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/ttcn3-…> SMF_Tests.TC_deleteSession_unknown' was executed successfully (exit status: 0).
MTC@29c9d80cf285: Test case TC_deleteSession_unknown started.
TC_deleteSession_unknown-PFCP(21)@29c9d80cf285: f_PFCPEM_conns_bcast_add(): vc_conn mtc subscribed for broadcast
TC_deleteSession_unknown-PFCP(21)@29c9d80cf285: 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 := 3997170580 } } } }
MTC@29c9d80cf285: 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@29c9d80cf285: Stopping testcase execution from "PFCP_Emulation.ttcn":504
GTP1U_EM(19)@29c9d80cf285: Final verdict of PTC: none
GTP2C_EM(18)@29c9d80cf285: Final verdict of PTC: none
MutexDispCT-TC_deleteSession_unknown(20)@29c9d80cf285: Final verdict of PTC: none
TC_deleteSession_unknown-PFCP(21)@29c9d80cf285: Final verdict of PTC: none
MTC@29c9d80cf285: Setting final verdict of the test case.
MTC@29c9d80cf285: 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@29c9d80cf285: Local verdict of PTC GTP2C_EM(18): none (fail -> fail)
MTC@29c9d80cf285: Local verdict of PTC GTP1U_EM(19): none (fail -> fail)
MTC@29c9d80cf285: Local verdict of PTC MutexDispCT-TC_deleteSession_unknown(20): none (fail -> fail)
MTC@29c9d80cf285: Local verdict of PTC TC_deleteSession_unknown-PFCP(21): none (fail -> fail)
MTC@29c9d80cf285: 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@29c9d80cf285: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/ttcn3-…> SMF_Tests.TC_deleteSession_unknown fail'.
(13:09:56) load average: 1.33, 0.90, 0.84
[1;31m------ SMF_Tests.TC_deleteSession_unknown fail ------[0m
Waiting for packet dumper to finish... 0 (prev_count=-1, count=1736)
MTC@29c9d80cf285: External command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/ttcn3-…> SMF_Tests.TC_deleteSession_unknown fail' was executed successfully (exit status: 0).
MTC@29c9d80cf285: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/ttcn3-…> SMF_Tests.TC_gy_ccr_update_rejected'.
------ SMF_Tests.TC_gy_ccr_update_rejected ------
(13:09:57) load average: 1.23, 0.89, 0.83
/usr/bin/dumpcap -q -s 1520 -n -i any -w "<https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/1/artifac…"> ><https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/1/artifac…> 2>/tmp/cmderr &
Waiting for packet dumper to start... 0
[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=/home/osmocom-build/jenkins/workspace', '-e', 'TESTENV_INSTALL_DIR=/', '-e', 'TERM=dumb', '-e', 'PATH=<https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/_teste…>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/_teste…>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/smf>:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-e', 'PYTHONUNBUFFERED=1', '-t', 'testenv-smf-osmocom-latest-20260831-1308-5a804244-1', 'sh', '-c', 'echo; gdb --batch /usr/bin/open5gs-smfd <https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/1/artifac…> -ex bt | tee <https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/1/artifac…;> echo'][0m
[New LWP 209]
[New LWP 185]
[New LWP 188]
[New LWP 186]
[New LWP 193]
[New LWP 194]
[New LWP 195]
[New LWP 197]
[New LWP 198]
[New LWP 200]
[New LWP 199]
[New LWP 184]
[New LWP 189]
[New LWP 187]
[New LWP 191]
[New LWP 201]
[New LWP 190]
[New LWP 192]
[New LWP 196]
[New LWP 182]
[New LWP 348]
[New LWP 205]
[New LWP 202]
[New LWP 208]
[New LWP 203]
[New LWP 358]
[New LWP 353]
[New LWP 204]
[New LWP 357]
[New LWP 207]
[New LWP 206]
[New LWP 352]
[New LWP 349]
[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 0x00007fede2c86f69 in ogs_pfcp_parse_volume_measurement (volume=volume@entry=0x7fed80ff69b0, warning: 591 ../lib/pfcp/types.c: No such file or directory
octet=octet@entry=0x7fed5400c7d0) at ../lib/pfcp/types.c:591
[Current thread is 1 (Thread 0x7fed80ff96c0 (LWP 209))]
#0 0x00007fede2c86f69 in ogs_pfcp_parse_volume_measurement (volume=volume@entry=0x7fed80ff69b0, octet=octet@entry=0x7fed5400c7d0) at ../lib/pfcp/types.c:591
#1 0x000055be55802886 in smf_epc_n4_handle_session_deletion_response (sess=sess@entry=0x7fedcb7a3010, xact=xact@entry=0x7fedcaf3e748, rsp=rsp@entry=0x7fed5400c6b8) at ../src/smf/n4-handler.c:1548
#2 0x000055be557d4738 in smf_gsm_state_wait_pfcp_deletion (s=s@entry=0x7fedcb7a3850, e=e@entry=0x7fed9c000e30) at ../src/smf/gsm-sm.c:2313
#3 0x00007fede3863e24 in ogs_fsm_dispatch (fsm=0x7fedcb7a3850, event=0x7fed9c000e30) at ../lib/core/ogs-fsm.c:127
#4 0x00007fede3863e24 in ogs_fsm_dispatch (fsm=0x55be5d830258, event=0x7fed9c000e30) at ../lib/core/ogs-fsm.c:127
#5 0x000055be557c9dc6 in smf_state_operational (s=s@entry=0x7fed80ff8c20, e=e@entry=0x7fed9c000e30) at ../src/smf/smf-sm.c:441
#6 0x00007fede3863e24 in ogs_fsm_dispatch (fsm=0x7fed80ff8c20, event=0x7fed9c000e30) at ../lib/core/ogs-fsm.c:127
#7 0x000055be557bb859 in smf_main (data=<optimized out>) at ../src/smf/init.c:194
#8 0x00007fede3859005 in thread_worker (arg=0x55be5da6b190) at ../lib/core/ogs-thread.c:67
#9 0x00007fede25beb7b in start_thread (arg=<optimized out>) at ./nptl/pthread_create.c:448
#10 0x00007fede263c7f8 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-20260831-1308-5a804244-1'][0m
testenv-smf-osmocom-latest-20260831-1308-5a804244-1
<https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/1/artifac…>: 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 (1851773)[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/1/artifac… [0m
+ RC=1
+ [ 1 = 0 ]
+ + grep --color=always -o load.*
uptime
[01;31m[Kload average: 1.23, 0.89, 0.83[m[K
+ exit 1
Build step 'Execute shell' marked build as failure
Recording test results
[Checks API] No suitable checks publisher found.
1
4
See <https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs/469/display/redi…>
Changes:
[Oliver Smith] deps/Makefile: error if repo _commit is missing
[Oliver Smith] sgsn: fix running against current stable osmo-sgsn
[Pau Espin Pedrol] deps: Remove unused titan.ProtocolModules.JSON_v07_2006
------------------------------------------
[...truncated 243.38 MiB...]
[32m09/04 09:32:38.545[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] /nnrf-nfm/v1/nf-instances/2c4b24b2-a843-41f1-92c5-2dd8304da01b (../lib/sbi/nghttp2-server.c:1308)
[32m09/04 09:32:38.545[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED: 101 (../lib/sbi/nghttp2-server.c:1312)
[32m09/04 09:32:38.545[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":25}] (../lib/sbi/nghttp2-server.c:1314)
[32m09/04 09:32:38.545[0m: [[33mnrf[0m] [1;37mDEBUG[0m: nrf_state_operational(): OGS_EVENT_NAME_SBI_SERVER (../src/nrf/nrf-sm.c:54)
[32m09/04 09:32:38.545[0m: [[33mnrf[0m] [1;37mDEBUG[0m: nrf_nf_state_registered(): OGS_EVENT_NAME_SBI_SERVER (../src/nrf/nf-sm.c:201)
[32m09/04 09:32:38.545[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [204] (../lib/sbi/nghttp2-server.c:702)
[32m09/04 09:32:38.545[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [561] (../lib/sbi/nghttp2-server.c:1431)
0000: 00000301 05000002 3189f5be ........1...
[32m09/04 09:32:38.545[0m: [[33msbi[0m] [1;37mDEBUG[0m: [204:PATCH] http://127.0.0.10:7777/nnrf-nfm/v1/nf-instances/2c4b24b2-a843-41f1-92c5-2dd… (../lib/sbi/client.c:744)
[32m09/04 09:32:38.545[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755)
[32m09/04 09:32:38.546[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [204] (../lib/sbi/nghttp2-server.c:702)
[32m09/04 09:32:38.546[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [4717] (../lib/sbi/nghttp2-server.c:1431)
0000: 00001a01 05000012 6d89c261 96c361be ........m..a..a.
0010: 94034a6e 2d6a0802 71403f71 915c65e5 ..Jn-j..q(a)?q.\e.
0020: 3168df 1h.
[32m09/04 09:32:38.546[0m: [[33msbi[0m] [1;37mDEBUG[0m: [204:PATCH] http://127.0.0.200:7777/nnrf-nfm/v1/nf-instances/2c4b24b2-a843-41f1-92c5-2d… (../lib/sbi/client.c:744)
[32m09/04 09:32:38.546[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755)
[32m09/04 09:32:38.546[0m: [[33mausf[0m] [1;37mDEBUG[0m: ausf_state_operational(): OGS_EVENT_NAME_SBI_CLIENT (../src/ausf/ausf-sm.c:56)
[32m09/04 09:32:38.546[0m: [[33msbi[0m] [1;37mDEBUG[0m: ogs_sbi_nf_state_registered(): OGS_EVENT_NAME_SBI_CLIENT (../lib/sbi/nf-sm.c:312)
[32m09/04 09:32:38.793[0m: [[33mudm[0m] [1;37mDEBUG[0m: udm_state_operational(): OGS_EVENT_NAME_SBI_TIMER (../src/udm/udm-sm.c:61)
[32m09/04 09:32:38.793[0m: [[33msbi[0m] [1;37mDEBUG[0m: ogs_sbi_nf_state_registered(): OGS_EVENT_NAME_SBI_TIMER (../lib/sbi/nf-sm.c:312)
[32m09/04 09:32:38.793[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] http://127.0.0.200:7777/nnrf-nfm/v1/nf-instances/2c726162-a843-41f1-a7bf-45… (../lib/sbi/client.c:797)
[32m09/04 09:32:38.793[0m: [[33msbi[0m] [1;37mDEBUG[0m: SENDING...[101] (../lib/sbi/client.c:546)
[32m09/04 09:32:38.793[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":25}] (../lib/sbi/client.c:548)
[32m09/04 09:32:38.794[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM added [13009] (../lib/sbi/nghttp2-server.c:1749)
[32m09/04 09:32:38.794[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] /nnrf-nfm/v1/nf-instances/2c726162-a843-41f1-a7bf-455e3de4da2b (../lib/sbi/nghttp2-server.c:1308)
[32m09/04 09:32:38.794[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED: 101 (../lib/sbi/nghttp2-server.c:1312)
[32m09/04 09:32:38.794[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":25}] (../lib/sbi/nghttp2-server.c:1314)
[32m09/04 09:32:38.794[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] http://127.0.0.10:7777/nnrf-nfm/v1/nf-instances/2c726162-a843-41f1-a7bf-455… (../lib/sbi/client.c:797)
[32m09/04 09:32:38.794[0m: [[33msbi[0m] [1;37mDEBUG[0m: SENDING...[101] (../lib/sbi/client.c:546)
[32m09/04 09:32:38.794[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":25}] (../lib/sbi/client.c:548)
[32m09/04 09:32:38.794[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM added [563] (../lib/sbi/nghttp2-server.c:1749)
[32m09/04 09:32:38.794[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] /nnrf-nfm/v1/nf-instances/2c726162-a843-41f1-a7bf-455e3de4da2b (../lib/sbi/nghttp2-server.c:1308)
[32m09/04 09:32:38.794[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED: 101 (../lib/sbi/nghttp2-server.c:1312)
[32m09/04 09:32:38.794[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":25}] (../lib/sbi/nghttp2-server.c:1314)
[32m09/04 09:32:38.794[0m: [[33mnrf[0m] [1;37mDEBUG[0m: nrf_state_operational(): OGS_EVENT_NAME_SBI_SERVER (../src/nrf/nrf-sm.c:54)
[32m09/04 09:32:38.794[0m: [[33mnrf[0m] [1;37mDEBUG[0m: nrf_nf_state_registered(): OGS_EVENT_NAME_SBI_SERVER (../src/nrf/nf-sm.c:201)
[32m09/04 09:32:38.794[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [204] (../lib/sbi/nghttp2-server.c:702)
[32m09/04 09:32:38.794[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [563] (../lib/sbi/nghttp2-server.c:1431)
0000: 00000301 05000002 3389f5be ........3...
[32m09/04 09:32:38.795[0m: [[33msbi[0m] [1;37mDEBUG[0m: [204:PATCH] http://127.0.0.10:7777/nnrf-nfm/v1/nf-instances/2c726162-a843-41f1-a7bf-455… (../lib/sbi/client.c:744)
[32m09/04 09:32:38.795[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755)
[32m09/04 09:32:38.795[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [204] (../lib/sbi/nghttp2-server.c:702)
[32m09/04 09:32:38.795[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [13009] (../lib/sbi/nghttp2-server.c:1431)
0000: 00001a01 05000032 d189c461 96c361be .......2...a..a.
0010: 94034a6e 2d6a0802 71403f71 915c65e5 ..Jn-j..q(a)?q.\e.
0020: 3168df 1h.
[32m09/04 09:32:38.795[0m: [[33msbi[0m] [1;37mDEBUG[0m: [204:PATCH] http://127.0.0.200:7777/nnrf-nfm/v1/nf-instances/2c726162-a843-41f1-a7bf-45… (../lib/sbi/client.c:744)
[32m09/04 09:32:38.795[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755)
[32m09/04 09:32:38.795[0m: [[33mudm[0m] [1;37mDEBUG[0m: udm_state_operational(): OGS_EVENT_NAME_SBI_CLIENT (../src/udm/udm-sm.c:61)
[32m09/04 09:32:38.795[0m: [[33msbi[0m] [1;37mDEBUG[0m: ogs_sbi_nf_state_registered(): OGS_EVENT_NAME_SBI_CLIENT (../lib/sbi/nf-sm.c:312)
[32m09/04 09:32:39.039[0m: [[33mudr[0m] [1;37mDEBUG[0m: udr_state_operational(): OGS_EVENT_NAME_SBI_TIMER (../src/udr/udr-sm.c:53)
[32m09/04 09:32:39.039[0m: [[33msbi[0m] [1;37mDEBUG[0m: ogs_sbi_nf_state_registered(): OGS_EVENT_NAME_SBI_TIMER (../lib/sbi/nf-sm.c:312)
[32m09/04 09:32:39.039[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] http://127.0.0.200:7777/nnrf-nfm/v1/nf-instances/2c97d406-a843-41f1-b6bf-57… (../lib/sbi/client.c:797)
[32m09/04 09:32:39.039[0m: [[33msbi[0m] [1;37mDEBUG[0m: SENDING...[100] (../lib/sbi/client.c:546)
[32m09/04 09:32:39.039[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":0}] (../lib/sbi/client.c:548)
[32m09/04 09:32:39.040[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM added [57] (../lib/sbi/nghttp2-server.c:1749)
[32m09/04 09:32:39.040[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] /nnrf-nfm/v1/nf-instances/2c97d406-a843-41f1-b6bf-57e53ec988f2 (../lib/sbi/nghttp2-server.c:1308)
[32m09/04 09:32:39.040[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED: 100 (../lib/sbi/nghttp2-server.c:1312)
[32m09/04 09:32:39.040[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":0}] (../lib/sbi/nghttp2-server.c:1314)
[32m09/04 09:32:39.040[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] http://127.0.0.10:7777/nnrf-nfm/v1/nf-instances/2c97d406-a843-41f1-b6bf-57e… (../lib/sbi/client.c:797)
[32m09/04 09:32:39.040[0m: [[33msbi[0m] [1;37mDEBUG[0m: SENDING...[100] (../lib/sbi/client.c:546)
[32m09/04 09:32:39.040[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":0}] (../lib/sbi/client.c:548)
[32m09/04 09:32:39.040[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM added [565] (../lib/sbi/nghttp2-server.c:1749)
[32m09/04 09:32:39.040[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] /nnrf-nfm/v1/nf-instances/2c97d406-a843-41f1-b6bf-57e53ec988f2 (../lib/sbi/nghttp2-server.c:1308)
[32m09/04 09:32:39.040[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED: 100 (../lib/sbi/nghttp2-server.c:1312)
[32m09/04 09:32:39.040[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":0}] (../lib/sbi/nghttp2-server.c:1314)
[32m09/04 09:32:39.040[0m: [[33mnrf[0m] [1;37mDEBUG[0m: nrf_state_operational(): OGS_EVENT_NAME_SBI_SERVER (../src/nrf/nrf-sm.c:54)
[32m09/04 09:32:39.040[0m: [[33mnrf[0m] [1;37mDEBUG[0m: nrf_nf_state_registered(): OGS_EVENT_NAME_SBI_SERVER (../src/nrf/nf-sm.c:201)
[32m09/04 09:32:39.040[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [204] (../lib/sbi/nghttp2-server.c:702)
[32m09/04 09:32:39.040[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [565] (../lib/sbi/nghttp2-server.c:1431)
0000: 00001a01 05000002 3589f561 96c361be ........5..a..a.
0010: 94034a6e 2d6a0802 71403f71 915c65f5 ..Jn-j..q(a)?q.\e.
0020: 3168df 1h.
[32m09/04 09:32:39.041[0m: [[33msbi[0m] [1;37mDEBUG[0m: [204:PATCH] http://127.0.0.10:7777/nnrf-nfm/v1/nf-instances/2c97d406-a843-41f1-b6bf-57e… (../lib/sbi/client.c:744)
[32m09/04 09:32:39.041[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755)
[32m09/04 09:32:39.041[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [204] (../lib/sbi/nghttp2-server.c:702)
[32m09/04 09:32:39.041[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [57] (../lib/sbi/nghttp2-server.c:1431)
0000: 00001a01 05000000 3989d161 96c361be ........9..a..a.
0010: 94034a6e 2d6a0802 71403f71 915c65f5 ..Jn-j..q(a)?q.\e.
0020: 3168df 1h.
[32m09/04 09:32:39.041[0m: [[33msbi[0m] [1;37mDEBUG[0m: [204:PATCH] http://127.0.0.200:7777/nnrf-nfm/v1/nf-instances/2c97d406-a843-41f1-b6bf-57… (../lib/sbi/client.c:744)
[32m09/04 09:32:39.041[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755)
[32m09/04 09:32:39.041[0m: [[33mudr[0m] [1;37mDEBUG[0m: udr_state_operational(): OGS_EVENT_NAME_SBI_CLIENT (../src/udr/udr-sm.c:53)
[32m09/04 09:32:39.041[0m: [[33msbi[0m] [1;37mDEBUG[0m: ogs_sbi_nf_state_registered(): OGS_EVENT_NAME_SBI_CLIENT (../lib/sbi/nf-sm.c:312)
[32m09/04 09:32:39.181[0m: [[33mpcf[0m] [1;37mDEBUG[0m: pcf_state_operational(): OGS_EVENT_NAME_SBI_TIMER (../src/pcf/pcf-sm.c:64)
[32m09/04 09:32:39.181[0m: [[33msbi[0m] [1;37mDEBUG[0m: ogs_sbi_nf_state_registered(): OGS_EVENT_NAME_SBI_TIMER (../lib/sbi/nf-sm.c:312)
[32m09/04 09:32:39.181[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] http://127.0.0.200:7777/nnrf-nfm/v1/nf-instances/2cac17a4-a843-41f1-ba5b-95… (../lib/sbi/client.c:797)
[32m09/04 09:32:39.181[0m: [[33msbi[0m] [1;37mDEBUG[0m: SENDING...[101] (../lib/sbi/client.c:546)
[32m09/04 09:32:39.181[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":25}] (../lib/sbi/client.c:548)
[32m09/04 09:32:39.181[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM added [5757] (../lib/sbi/nghttp2-server.c:1749)
[32m09/04 09:32:39.181[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] /nnrf-nfm/v1/nf-instances/2cac17a4-a843-41f1-ba5b-95494485b9a1 (../lib/sbi/nghttp2-server.c:1308)
[32m09/04 09:32:39.181[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED: 101 (../lib/sbi/nghttp2-server.c:1312)
[32m09/04 09:32:39.181[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":25}] (../lib/sbi/nghttp2-server.c:1314)
[32m09/04 09:32:39.182[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] http://127.0.0.10:7777/nnrf-nfm/v1/nf-instances/2cac17a4-a843-41f1-ba5b-954… (../lib/sbi/client.c:797)
[32m09/04 09:32:39.182[0m: [[33msbi[0m] [1;37mDEBUG[0m: SENDING...[101] (../lib/sbi/client.c:546)
[32m09/04 09:32:39.182[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":25}] (../lib/sbi/client.c:548)
[32m09/04 09:32:39.182[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM added [567] (../lib/sbi/nghttp2-server.c:1749)
[32m09/04 09:32:39.182[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] /nnrf-nfm/v1/nf-instances/2cac17a4-a843-41f1-ba5b-95494485b9a1 (../lib/sbi/nghttp2-server.c:1308)
[32m09/04 09:32:39.182[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED: 101 (../lib/sbi/nghttp2-server.c:1312)
[32m09/04 09:32:39.182[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":25}] (../lib/sbi/nghttp2-server.c:1314)
[32m09/04 09:32:39.182[0m: [[33mnrf[0m] [1;37mDEBUG[0m: nrf_state_operational(): OGS_EVENT_NAME_SBI_SERVER (../src/nrf/nrf-sm.c:54)
[32m09/04 09:32:39.182[0m: [[33mnrf[0m] [1;37mDEBUG[0m: nrf_nf_state_registered(): OGS_EVENT_NAME_SBI_SERVER (../src/nrf/nf-sm.c:201)
[32m09/04 09:32:39.182[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [204] (../lib/sbi/nghttp2-server.c:702)
[32m09/04 09:32:39.182[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [567] (../lib/sbi/nghttp2-server.c:1431)
0000: 00000301 05000002 3789f6be ........7...
[32m09/04 09:32:39.182[0m: [[33msbi[0m] [1;37mDEBUG[0m: [204:PATCH] http://127.0.0.10:7777/nnrf-nfm/v1/nf-instances/2cac17a4-a843-41f1-ba5b-954… (../lib/sbi/client.c:744)
[32m09/04 09:32:39.182[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755)
[32m09/04 09:32:39.182[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [204] (../lib/sbi/nghttp2-server.c:702)
[32m09/04 09:32:39.183[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [5757] (../lib/sbi/nghttp2-server.c:1431)
0000: 00001a01 05000016 7d89f861 96c361be ........}..a..a.
0010: 94034a6e 2d6a0802 71403f71 915c65f5 ..Jn-j..q(a)?q.\e.
0020: 3168df 1h.
[32m09/04 09:32:39.183[0m: [[33msbi[0m] [1;37mDEBUG[0m: [204:PATCH] http://127.0.0.200:7777/nnrf-nfm/v1/nf-instances/2cac17a4-a843-41f1-ba5b-95… (../lib/sbi/client.c:744)
[32m09/04 09:32:39.183[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755)
[32m09/04 09:32:39.183[0m: [[33mpcf[0m] [1;37mDEBUG[0m: pcf_state_operational(): OGS_EVENT_NAME_SBI_CLIENT (../src/pcf/pcf-sm.c:64)
[32m09/04 09:32:39.183[0m: [[33msbi[0m] [1;37mDEBUG[0m: ogs_sbi_nf_state_registered(): OGS_EVENT_NAME_SBI_CLIENT (../lib/sbi/nf-sm.c:312)
MTC@e30ab461b77c: External command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs/ws/src/osmo-ttcn…> C5G_Tests.TC_ue_service_request_cm_connected' was executed successfully (exit status: 0).
MTC@e30ab461b77c: Test case TC_ue_service_request_cm_connected started.
GTP1U_EM(841)@e30ab461b77c: Warning: sizes of 'struct sctp_event_subscribe': compile-time 14, kernel: 14
GTP1U_EM(841)@e30ab461b77c: setverdict(fail): none -> fail reason: "Could not connect UECUPS socket, check your configuration", new component reason: "Could not connect UECUPS socket, check your configuration"
GTP1U_EM(841)@e30ab461b77c: Dynamic test case error: testcase.stop
GTP1U_EM(841)@e30ab461b77c: setverdict(error): fail -> error
GTP1U_EM(841)@e30ab461b77c: Final verdict of PTC: error
TC_ue_service_request_cm_connected-NGAP0(842)@e30ab461b77c: Warning: sizes of 'struct sctp_event_subscribe': compile-time 14, kernel: 14
[32m09/04 09:32:39.245[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2 accepted[127.0.0.202]:50000 in ng-path module (../src/amf/ngap-sctp.c:113)
[32m09/04 09:32:39.245[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_ACCEPT (../src/amf/amf-sm.c:84)
[32m09/04 09:32:39.245[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2 accepted[127.0.0.202] in master_sm module (../src/amf/amf-sm.c:822)
[32m09/04 09:32:39.252[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_initial(): ENTRY (../src/amf/ngap-sm.c:28)
[32m09/04 09:32:39.252[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_operational(): ENTRY (../src/amf/ngap-sm.c:55)
[32m09/04 09:32:39.252[0m: [[33mamf[0m] [1;32mINFO[0m: [Added] Number of gNBs is now 1 (../src/amf/context.c:1277)
[32m09/04 09:32:39.252[0m: [[33mamf[0m] [1;37mDEBUG[0m: SCTP_ASSOC_CHANGE:[T:32769, F:0x0, S:0, I/O:64/30] (../src/amf/ngap-sctp.c:152)
[32m09/04 09:32:39.252[0m: [[33mamf[0m] [1;37mDEBUG[0m: SCTP_COMM_UP (../src/amf/ngap-sctp.c:161)
[32m09/04 09:32:39.252[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_SCTP_COMM_UP (../src/amf/amf-sm.c:84)
[32m09/04 09:32:39.252[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2[127.0.0.202] max_num_of_ostreams : 30 (../src/amf/amf-sm.c:869)
TC_ue_service_request_cm_connected-NGAP1(843)@e30ab461b77c: Warning: sizes of 'struct sctp_event_subscribe': compile-time 14, kernel: 14
[32m09/04 09:32:39.254[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2 accepted[127.0.0.202]:50001 in ng-path module (../src/amf/ngap-sctp.c:113)
[32m09/04 09:32:39.254[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_ACCEPT (../src/amf/amf-sm.c:84)
[32m09/04 09:32:39.254[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2 accepted[127.0.0.202] in master_sm module (../src/amf/amf-sm.c:822)
[32m09/04 09:32:39.259[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_initial(): ENTRY (../src/amf/ngap-sm.c:28)
[32m09/04 09:32:39.259[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_operational(): ENTRY (../src/amf/ngap-sm.c:55)
[32m09/04 09:32:39.259[0m: [[33mamf[0m] [1;32mINFO[0m: [Added] Number of gNBs is now 2 (../src/amf/context.c:1277)
[32m09/04 09:32:39.259[0m: [[33mamf[0m] [1;37mDEBUG[0m: SCTP_ASSOC_CHANGE:[T:32769, F:0x0, S:0, I/O:64/30] (../src/amf/ngap-sctp.c:152)
[32m09/04 09:32:39.259[0m: [[33mamf[0m] [1;37mDEBUG[0m: SCTP_COMM_UP (../src/amf/ngap-sctp.c:161)
[32m09/04 09:32:39.259[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_SCTP_COMM_UP (../src/amf/amf-sm.c:84)
[32m09/04 09:32:39.259[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2[127.0.0.202] max_num_of_ostreams : 30 (../src/amf/amf-sm.c:869)
[32m09/04 09:32:39.259[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_MESSAGE (../src/amf/amf-sm.c:84)
[32m09/04 09:32:39.259[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_operational(): AMF_EVENT_NGAP_MESSAGE (../src/amf/ngap-sm.c:55)
[32m09/04 09:32:39.259[0m: [[33mamf[0m] [1;37mDEBUG[0m: NGSetupRequest (../src/amf/ngap-handler.c:288)
[32m09/04 09:32:39.259[0m: [[33mamf[0m] [1;37mDEBUG[0m: IP[127.0.0.202] GNB_ID[0x0] GNB_ID_LENGTH[22] (../src/amf/ngap-handler.c:386)
[32m09/04 09:32:39.259[0m: [[33mamf[0m] [1;37mDEBUG[0m: PLMN_ID[MCC:999 MNC:70] (../src/amf/ngap-handler.c:391)
[32m09/04 09:32:39.259[0m: [[33mamf[0m] [1;37mDEBUG[0m: PagingDRX[0] (../src/amf/ngap-handler.c:395)
[32m09/04 09:32:39.259[0m: [[33mamf[0m] [1;37mDEBUG[0m: TAC[1] (../src/amf/ngap-handler.c:439)
[32m09/04 09:32:39.259[0m: [[33mamf[0m] [1;37mDEBUG[0m: PLMN_ID[MCC:999 MNC:70] (../src/amf/ngap-handler.c:501)
[32m09/04 09:32:39.259[0m: [[33mamf[0m] [1;37mDEBUG[0m: S_NSSAI[SST:1 SD:0xffffff] (../src/amf/ngap-handler.c:562)
[32m09/04 09:32:39.259[0m: [[33mamf[0m] [1;37mDEBUG[0m: TAC[1] (../src/amf/ngap-handler.c:72)
[32m09/04 09:32:39.259[0m: [[33mamf[0m] [1;37mDEBUG[0m: PLMN_ID[MCC:999 MNC:70] (../src/amf/ngap-handler.c:73)
[32m09/04 09:32:39.259[0m: [[33mamf[0m] [1;37mDEBUG[0m: SERVED_TAI_INDEX[0] (../src/amf/ngap-handler.c:78)
[32m09/04 09:32:39.259[0m: [[33mamf[0m] [1;37mDEBUG[0m: PLMN_ID[MCC:999 MNC:70] (../src/amf/ngap-handler.c:100)
[32m09/04 09:32:39.259[0m: [[33mamf[0m] [1;37mDEBUG[0m: S_NSSAI[SST:1 SD:0xffffff] (../src/amf/ngap-handler.c:105)
[32m09/04 09:32:39.259[0m: [[33mamf[0m] [1;37mDEBUG[0m: NG-Setup response (../src/amf/ngap-path.c:356)
[32m09/04 09:32:39.259[0m: [[33mamf[0m] [1;37mDEBUG[0m: NGSetupResponse (../src/amf/ngap-build.c:91)
[32m09/04 09:32:39.259[0m: [[33mamf[0m] [1;37mDEBUG[0m: IP[127.0.0.202] RAN_ID[0] (../src/amf/ngap-path.c:64)
MTC@e30ab461b77c: setverdict(pass): none -> pass
MTC@e30ab461b77c: Dynamic test case error: Error message was received from MC: The connect operation refers to test component with component reference 841, which has already terminated.
MTC@e30ab461b77c: setverdict(error): pass -> error
TC_ue_service_request_cm_connected0(844)@e30ab461b77c: Final verdict of PTC: none
[32m09/04 09:32:39.266[0m: [[33mamf[0m] [1;37mDEBUG[0m: SCTP_SHUTDOWN_EVENT:[T:32773, F:0x0, L:12] (../src/amf/ngap-sctp.c:193)
[32m09/04 09:32:39.266[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_CONNREFUSED (../src/amf/amf-sm.c:84)
[32m09/04 09:32:39.266[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2[127.0.0.202] connection refused!!! (../src/amf/amf-sm.c:882)
[32m09/04 09:32:39.266[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_operational(): EXIT (../src/amf/ngap-sm.c:55)
[32m09/04 09:32:39.266[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_final(): EXIT (../src/amf/ngap-sm.c:37)
[32m09/04 09:32:39.266[0m: [[33mamf[0m] [1;32mINFO[0m: [Removed] Number of gNBs is now 1 (../src/amf/context.c:1305)
[32m09/04 09:32:39.266[0m: [[33mamf[0m] [1;37mDEBUG[0m: SCTP_SHUTDOWN_EVENT:[T:32773, F:0x0, L:12] (../src/amf/ngap-sctp.c:193)
[32m09/04 09:32:39.266[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_CONNREFUSED (../src/amf/amf-sm.c:84)
[32m09/04 09:32:39.266[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2[127.0.0.202] connection refused!!! (../src/amf/amf-sm.c:882)
[32m09/04 09:32:39.266[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_operational(): EXIT (../src/amf/ngap-sm.c:55)
[32m09/04 09:32:39.266[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_final(): EXIT (../src/amf/ngap-sm.c:37)
[32m09/04 09:32:39.266[0m: [[33mamf[0m] [1;32mINFO[0m: [Removed] Number of gNBs is now 0 (../src/amf/context.c:1305)
TC_ue_service_request_cm_connected-NGAP1(843)@e30ab461b77c: Final verdict of PTC: none
TC_ue_service_request_cm_connected-NGAP0(842)@e30ab461b77c: Final verdict of PTC: none
MTC@e30ab461b77c: Setting final verdict of the test case.
MTC@e30ab461b77c: Local verdict of MTC: error
MTC@e30ab461b77c: Local verdict of PTC GTP1U_EM(841): error (error -> error)
MTC@e30ab461b77c: Local verdict of PTC TC_ue_service_request_cm_connected-NGAP0(842): none (error -> error)
MTC@e30ab461b77c: Local verdict of PTC TC_ue_service_request_cm_connected-NGAP1(843): none (error -> error)
MTC@e30ab461b77c: Local verdict of PTC TC_ue_service_request_cm_connected0(844): none (error -> error)
MTC@e30ab461b77c: Test case TC_ue_service_request_cm_connected finished. Verdict: error
MTC@e30ab461b77c: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs/ws/src/osmo-ttcn…> C5G_Tests.TC_ue_service_request_cm_connected error'.
(09:32:39) load average: 1.86, 2.01, 1.43
[1;31m------ C5G_Tests.TC_ue_service_request_cm_connected error ------[0m
Waiting for packet dumper to finish... 0 (prev_count=-1, count=1284)
[32m09/04 09:32:39.453[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): OGS_EVENT_NAME_SBI_TIMER (../src/amf/amf-sm.c:84)
[32m09/04 09:32:39.453[0m: [[33msbi[0m] [1;37mDEBUG[0m: ogs_sbi_nf_state_registered(): OGS_EVENT_NAME_SBI_TIMER (../lib/sbi/nf-sm.c:312)
[32m09/04 09:32:39.454[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] http://127.0.0.200:7777/nnrf-nfm/v1/nf-instances/2cdb9240-a843-41f1-9f0d-01… (../lib/sbi/client.c:797)
[32m09/04 09:32:39.454[0m: [[33msbi[0m] [1;37mDEBUG[0m: SENDING...[100] (../lib/sbi/client.c:546)
[32m09/04 09:32:39.454[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":0}] (../lib/sbi/client.c:548)
[32m09/04 09:32:39.454[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM added [22351] (../lib/sbi/nghttp2-server.c:1749)
[32m09/04 09:32:39.454[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] /nnrf-nfm/v1/nf-instances/2cdb9240-a843-41f1-9f0d-01a9933afe85 (../lib/sbi/nghttp2-server.c:1308)
[32m09/04 09:32:39.454[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED: 100 (../lib/sbi/nghttp2-server.c:1312)
[32m09/04 09:32:39.454[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":0}] (../lib/sbi/nghttp2-server.c:1314)
[32m09/04 09:32:39.454[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] http://127.0.0.10:7777/nnrf-nfm/v1/nf-instances/2cdb9240-a843-41f1-9f0d-01a… (../lib/sbi/client.c:797)
[32m09/04 09:32:39.454[0m: [[33msbi[0m] [1;37mDEBUG[0m: SENDING...[100] (../lib/sbi/client.c:546)
[32m09/04 09:32:39.454[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":0}] (../lib/sbi/client.c:548)
[32m09/04 09:32:39.454[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM added [569] (../lib/sbi/nghttp2-server.c:1749)
[32m09/04 09:32:39.455[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] /nnrf-nfm/v1/nf-instances/2cdb9240-a843-41f1-9f0d-01a9933afe85 (../lib/sbi/nghttp2-server.c:1308)
[32m09/04 09:32:39.455[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED: 100 (../lib/sbi/nghttp2-server.c:1312)
[32m09/04 09:32:39.455[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":0}] (../lib/sbi/nghttp2-server.c:1314)
[32m09/04 09:32:39.455[0m: [[33mnrf[0m] [1;37mDEBUG[0m: nrf_state_operational(): OGS_EVENT_NAME_SBI_SERVER (../src/nrf/nrf-sm.c:54)
[32m09/04 09:32:39.455[0m: [[33mnrf[0m] [1;37mDEBUG[0m: nrf_nf_state_registered(): OGS_EVENT_NAME_SBI_SERVER (../src/nrf/nf-sm.c:201)
[32m09/04 09:32:39.455[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [204] (../lib/sbi/nghttp2-server.c:702)
[32m09/04 09:32:39.455[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [569] (../lib/sbi/nghttp2-server.c:1431)
0000: 00000301 05000002 3989f6be ........9...
[32m09/04 09:32:39.455[0m: [[33msbi[0m] [1;37mDEBUG[0m: [204:PATCH] http://127.0.0.10:7777/nnrf-nfm/v1/nf-instances/2cdb9240-a843-41f1-9f0d-01a… (../lib/sbi/client.c:744)
[32m09/04 09:32:39.455[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755)
[32m09/04 09:32:39.455[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [204] (../lib/sbi/nghttp2-server.c:702)
[32m09/04 09:32:39.455[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [22351] (../lib/sbi/nghttp2-server.c:1431)
0000: 00001a01 05000057 4f89c461 96c361be .......WO..a..a.
0010: 94034a6e 2d6a0802 71403f71 915c65f5 ..Jn-j..q(a)?q.\e.
0020: 3168df 1h.
[32m09/04 09:32:39.455[0m: [[33msbi[0m] [1;37mDEBUG[0m: [204:PATCH] http://127.0.0.200:7777/nnrf-nfm/v1/nf-instances/2cdb9240-a843-41f1-9f0d-01… (../lib/sbi/client.c:744)
[32m09/04 09:32:39.455[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755)
[32m09/04 09:32:39.455[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): OGS_EVENT_NAME_SBI_CLIENT (../src/amf/amf-sm.c:84)
[32m09/04 09:32:39.456[0m: [[33msbi[0m] [1;37mDEBUG[0m: ogs_sbi_nf_state_registered(): OGS_EVENT_NAME_SBI_CLIENT (../lib/sbi/nf-sm.c:312)
Waiting for packet dumper to finish... 1 (prev_count=1284, count=10080)
MTC@e30ab461b77c: External command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs/ws/src/osmo-ttcn…> C5G_Tests.TC_ue_service_request_cm_connected error' was executed successfully (exit status: 0).
MTC@e30ab461b77c: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs/ws/src/osmo-ttcn…> C5G_Tests.TC_net_triggered_service_req'.
------ C5G_Tests.TC_net_triggered_service_req ------
(09:32:41) load average: 1.86, 2.01, 1.43
/usr/bin/dumpcap -q -s 1520 -n -i any -w "<https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs/469/artifact/log…"> ><https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs/469/artifact/log…> 2>/tmp/cmderr &
Waiting for packet dumper to start... 0
[1;34m[testenv] Stopping podman container[0m
[0;94m[testenv] + ['podman', 'kill', 'testenv-5gc-osmocom-nightly-20260904-0929-ecec0382-1'][0m
testenv-5gc-osmocom-nightly-20260904-0929-ecec0382-1
[0;94m[testenv] Skipping clean up scripts, podman container has already stopped[0m
[1;34m[testenv] Stopping testsuite (2671508)[0m
Error: container has already been removed
[0;94m[testenv] feed_watchdog_loop: podman container has stopped[0m
[1;34m[testenv] Logs saved to: https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs/469/artifact/log… [0m
+ RC=1
+ [ 1 = 0 ]
+ uptime
+ grep --color=always -o load.*
[01;31m[Kload average: 1.79, 1.99, 1.43[m[K
+ exit 1
Build step 'Execute shell' marked build as failure
Recording test results
[Checks API] No suitable checks publisher found.
1
0
Build failed in Jenkins: Osmocom-repo-install-debian11 » nightly,qemu #1557
by jenkins＠lists.osmocom.org 04 Sep '26
by jenkins＠lists.osmocom.org 04 Sep '26
04 Sep '26
See <https://jenkins.osmocom.org/jenkins/job/Osmocom-repo-install-debian11/feed=…>
Changes:
------------------------------------------
[...truncated 3.50 KiB...]
+ LOG_FILE=_repo_install_test_data/qemu.log
+ check_usage
+ local i
+ [ debian11 = centos8 ]
+ [ debian11 = debian10 ]
+ [ debian11 = debian11 ]
+ return
+ FEED=nightly
+ PROJ=osmocom:nightly
+ [ -z ]
+ TESTS=
test_conflict
install_repo_packages
test_binaries
services_check
+ [ -z ]
+ PROJ_CONFLICT=osmocom:latest
+ clean_up
+ [ -n ]
+ [ -e _repo_install_test_data/qemu.pid ]
+ rm -f _repo_install_test_data/temp.qcow2
+ trap clean_up_trap EXIT INT TERM 0
+ qemu_start
+ [ -n ]
+ prepare_img
+ dirname _repo_install_test_data/temp.qcow2
+ mkdir -p _repo_install_test_data
+ get_backing_img_path
+ local ret=
+ ret=/opt/qemu/debian-11.qcow2
+ [ -e /opt/qemu/debian-11.qcow2 ]
+ echo /opt/qemu/debian-11.qcow2
+ qemu-img create -f qcow2 -b /opt/qemu/debian-11.qcow2 -F qcow2 _repo_install_test_data/temp.qcow2
Formatting '_repo_install_test_data/temp.qcow2', fmt=qcow2 cluster_size=65536 extended_l2=off compression_type=zlib size=6442450944 backing_file=/opt/qemu/debian-11.qcow2 backing_fmt=qcow2 lazy_refcounts=off refcount_bits=16
+ find_free_ssh_port
+ echo (1077489 % 1000) + 22022
+ bc
+ SSH_PORT=22511
+ nc -z 127.0.0.1 22511
+ echo 22511
+ qemu_ssh_wait
+ set +x
Waiting for VM to boot up...
+ sleep 1
+ timeout 1h qemu-system-x86_64 -cpu host -device virtio-net-pci,netdev=net -display none -drive file=_repo_install_test_data/temp.qcow2,format=qcow2 -enable-kvm -m 1024 -netdev user,id=net,hostfwd=tcp:127.0.0.1:22511-:22 -nodefaults -pidfile _repo_install_test_data/qemu.pid -serial stdio -smp 16
+ cat _repo_install_test_data/qemu.pid
+ local pid=1077508
+ seq 1 6
+ [ -z 1077508 ]
+ kill -0 1077508
+ TIMEOUT=10s qemu_ssh true
+ timeout 10s sshpass -p root ssh -p 22511 -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null root(a)127.0.0.1 -- true
+ sleep 1
+ [ -z 1077508 ]
+ kill -0 1077508
+ TIMEOUT=10s qemu_ssh true
+ timeout 10s sshpass -p root ssh -p 22511 -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null root(a)127.0.0.1 -- true
Warning: Permanently added '[127.0.0.1]:22511' (ED25519) to the list of known hosts.
+ return
+ set +x
VM is running!
+ qemu_prepare_vm
+ qemu_run_test_script
+ cat
+ qemu_ssh rm -rf /repo-install-test/
+ timeout 1m sshpass -p root ssh -p 22511 -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null root(a)127.0.0.1 -- rm -rf /repo-install-test/
Warning: Permanently added '[127.0.0.1]:22511' (ED25519) to the list of known hosts.
+ qemu_ssh mkdir /repo-install-test
+ timeout 1m sshpass -p root ssh -p 22511 -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null root(a)127.0.0.1 -- mkdir /repo-install-test
Warning: Permanently added '[127.0.0.1]:22511' (ED25519) to the list of known hosts.
+ qemu_scp -r scripts/repo-install-test/run-inside-env.sh scripts/repo-install-test/run-inside.sh root@127.0.0.1:/repo-install-test
+ timeout 1m sshpass -p root scp -P 22511 -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -r scripts/repo-install-test/run-inside-env.sh scripts/repo-install-test/run-inside.sh root@127.0.0.1:/repo-install-test
Warning: Permanently added '[127.0.0.1]:22511' (ED25519) to the list of known hosts.
+ TIMEOUT=1h qemu_ssh sh -ex /repo-install-test/run-inside-env.sh
+ timeout 1h sshpass -p root ssh -p 22511 -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null root(a)127.0.0.1 -- sh -ex /repo-install-test/run-inside-env.sh
Warning: Permanently added '[127.0.0.1]:22511' (ED25519) to the list of known hosts.
+ export DISTRO=debian11
+ export DOMAIN=downloads.osmocom.org
+ export FEED=nightly
+ export PROJ=osmocom:nightly
+ export PROJ_CONFLICT=osmocom:latest
+ export SKIP_PREPARE_VM=
+ export TESTS=
test_conflict
install_repo_packages
test_binaries
services_check
+ /repo-install-test/run-inside.sh
+ SERVICES=
osmo-bsc
osmo-bts-virtual
osmo-cbc
osmo-e1d
osmo-gbproxy
osmo-ggsn
osmo-gtphub
osmo-hlr
osmo-hnbgw
osmo-hnodeb
osmo-mgw
osmo-msc
osmo-pcap-client
osmo-pcap-server
osmo-pcu
osmo-remsim-bankd
osmo-remsim-server
osmo-sgsn
osmo-sip-connector
osmo-smlc
osmo-stp
+ SERVICES_NIGHTLY=
osmo-bsc-nat
+ distro_obsdir
+ echo Debian_11
+ DISTRO_OBSDIR=Debian_11
Checking feed: nightly
Checking project: osmocom:nightly
Checking conflicting project: osmocom:latest
Linux distribution: debian11
Enabled tests:
test_conflict
install_repo_packages
test_binaries
services_check
+ check_env
+ [ -n nightly ]
+ echo Checking feed: nightly
+ [ -n osmocom:nightly ]
+ echo Checking project: osmocom:nightly
+ [ -n osmocom:latest ]
+ echo Checking conflicting project: osmocom:latest
+ [ -n debian11 ]
+ echo Linux distribution: debian11
+ [ -n
test_conflict
install_repo_packages
test_binaries
services_check
]
+ echo Enabled tests:
test_conflict
install_repo_packages
test_binaries
services_check
+ prepare_vm
+ [ -n ]
+ prepare_vm_debian
+ ln -sf /bin/true /usr/bin/fmtutil
+ echo path-exclude=/usr/bin/fmtutil
+ apt-get update --allow-releaseinfo-change
Get:1 http://security.debian.org/debian-security bullseye-security InRelease [27.2 kB]
Get:2 http://deb.debian.org/debian bullseye InRelease [75.1 kB]
Get:3 http://deb.debian.org/debian bullseye-updates InRelease [44.0 kB]
Get:4 http://security.debian.org/debian-security bullseye-security/main Sources [311 kB]
Get:5 http://security.debian.org/debian-security bullseye-security/main amd64 Packages [475 kB]
Get:6 http://security.debian.org/debian-security bullseye-security/main Translation-en [318 kB]
Get:7 http://deb.debian.org/debian bullseye/main Sources [8,500 kB]
Get:8 http://deb.debian.org/debian bullseye-updates/main Sources.diff/Index [26.3 kB]
Get:9 http://deb.debian.org/debian bullseye-updates/main amd64 Packages.diff/Index [26.3 kB]
Ign:9 http://deb.debian.org/debian bullseye-updates/main amd64 Packages.diff/Index
Get:10 http://deb.debian.org/debian bullseye-updates/main Translation-en.diff/Index [12.8 kB]
Get:11 http://deb.debian.org/debian bullseye-updates/main Sources T-2023-12-29-1403.39-F-2022-01-24-2024.03.pdiff [7,722 B]
Get:11 http://deb.debian.org/debian bullseye-updates/main Sources T-2023-12-29-1403.39-F-2022-01-24-2024.03.pdiff [7,722 B]
Get:12 http://deb.debian.org/debian bullseye-updates/main Translation-en T-2025-07-21-2004.39-F-2022-09-22-1635.40.pdiff [8,550 B]
Get:12 http://deb.debian.org/debian bullseye-updates/main Translation-en T-2025-07-21-2004.39-F-2022-09-22-1635.40.pdiff [8,550 B]
Get:13 http://deb.debian.org/debian bullseye/main amd64 Packages [8,066 kB]
Get:14 http://deb.debian.org/debian bullseye/main Translation-en [6,235 kB]
Get:15 http://deb.debian.org/debian bullseye-updates/main amd64 Packages [18.8 kB]
Get:16 http://deb.debian.org/debian bullseye-updates/main Sources [7,908 B]
Get:17 http://deb.debian.org/debian bullseye-updates/main Translation-en [10.5 kB]
Fetched 24.2 MB in 2s (11.0 MB/s)
Reading package lists...
+ apt-get install -y --no-install-recommends aptitude ca-certificates gnupg2 wget
Reading package lists...
Building dependency tree...
Reading state information...
The following additional packages will be installed:
aptitude-common dirmngr gnupg gnupg-l10n gnupg-utils gpg gpg-agent
gpg-wks-client gpg-wks-server gpgconf gpgsm gpgv libassuan0
libboost-iostreams1.74.0 libcwidget4 libksba8 libnpth0 libsigc++-2.0-0v5
libxapian30 pinentry-curses
Suggested packages:
apt-xapian-index aptitude-doc-en | aptitude-doc debtags dbus-user-session
pinentry-gnome3 tor parcimonie xloadimage scdaemon libcwidget-dev
xapian-tools pinentry-doc
Recommended packages:
libdpkg-perl
The following NEW packages will be installed:
aptitude aptitude-common dirmngr gnupg gnupg-l10n gnupg-utils gnupg2 gpg
gpg-agent gpg-wks-client gpg-wks-server gpgconf gpgsm libassuan0
libboost-iostreams1.74.0 libcwidget4 libksba8 libnpth0 libsigc++-2.0-0v5
libxapian30 pinentry-curses
The following packages will be upgraded:
ca-certificates gpgv wget
3 upgraded, 21 newly installed, 0 to remove and 148 not upgraded.
Need to get 14.7 MB of archives.
After this operation, 37.0 MB of additional disk space will be used.
Get:1 http://security.debian.org/debian-security bullseye-security/main amd64 gpgv amd64 2.2.27-2+deb11u3 [626 kB]
Get:2 http://deb.debian.org/debian bullseye/main amd64 aptitude-common all 0.8.13-3 [1,776 kB]
Err:3 http://security.debian.org/debian-security bullseye-security/main amd64 ca-certificates all 20250419~deb12u1~deb11u1
404 Not Found [IP: 146.75.122.132 80]
Get:4 http://security.debian.org/debian-security bullseye-security/main amd64 wget amd64 1.21-1+deb11u2 [964 kB]
Get:5 http://security.debian.org/debian-security bullseye-security/main amd64 gpgconf amd64 2.2.27-2+deb11u3 [548 kB]
Get:6 http://deb.debian.org/debian bullseye/main amd64 libboost-iostreams1.74.0 amd64 1.74.0-9 [264 kB]
Get:7 http://deb.debian.org/debian bullseye/main amd64 libsigc++-2.0-0v5 amd64 2.10.4-2 [31.3 kB]
Get:8 http://deb.debian.org/debian bullseye/main amd64 libcwidget4 amd64 0.5.18-5 [315 kB]
Get:9 http://security.debian.org/debian-security bullseye-security/main amd64 dirmngr amd64 2.2.27-2+deb11u3 [763 kB]
Get:10 http://deb.debian.org/debian bullseye/main amd64 libxapian30 amd64 1.4.18-3+deb11u1 [1,089 kB]
Get:11 http://security.debian.org/debian-security bullseye-security/main amd64 gnupg-l10n all 2.2.27-2+deb11u3 [1,084 kB]
Get:12 http://security.debian.org/debian-security bullseye-security/main amd64 gnupg-utils amd64 2.2.27-2+deb11u3 [905 kB]
Get:13 http://security.debian.org/debian-security bullseye-security/main amd64 gpg amd64 2.2.27-2+deb11u3 [928 kB]
Get:14 http://security.debian.org/debian-security bullseye-security/main amd64 gpg-agent amd64 2.2.27-2+deb11u3 [670 kB]
Get:15 http://deb.debian.org/debian bullseye/main amd64 aptitude amd64 0.8.13-3 [1,349 kB]
Get:16 http://deb.debian.org/debian bullseye/main amd64 libassuan0 amd64 2.5.3-7.1 [50.5 kB]
Get:17 http://deb.debian.org/debian bullseye/main amd64 libksba8 amd64 1.5.0-3+deb11u2 [123 kB]
Get:18 http://deb.debian.org/debian bullseye/main amd64 libnpth0 amd64 1.6-3 [19.0 kB]
Get:19 http://deb.debian.org/debian bullseye/main amd64 pinentry-curses amd64 1.1.0-4 [64.9 kB]
Get:20 http://security.debian.org/debian-security bullseye-security/main amd64 gpg-wks-client amd64 2.2.27-2+deb11u3 [524 kB]
Get:21 http://security.debian.org/debian-security bullseye-security/main amd64 gpg-wks-server amd64 2.2.27-2+deb11u3 [516 kB]
Get:22 http://security.debian.org/debian-security bullseye-security/main amd64 gpgsm amd64 2.2.27-2+deb11u3 [645 kB]
Get:23 http://security.debian.org/debian-security bullseye-security/main amd64 gnupg all 2.2.27-2+deb11u3 [825 kB]
Get:24 http://security.debian.org/debian-security bullseye-security/main amd64 gnupg2 all 2.2.27-2+deb11u3 [435 kB]
Fetched 14.5 MB in 0s (79.8 MB/s)
E: Failed to fetch http://security.debian.org/debian-security/pool/updates/main/c/ca-certifica… 404 Not Found [IP: 146.75.122.132 80]
E: Unable to fetch some archives, maybe run apt-get update or try with --fix-missing?
+ clean_up_trap
+ [ -n ]
+ set +x
### Clean up ###
+ trap - EXIT INT TERM 0
+ clean_up
+ [ -n ]
+ [ -e _repo_install_test_data/qemu.pid ]
+ cat _repo_install_test_data/qemu.pid
+ kill 1077508
+ rm -f _repo_install_test_data/temp.qcow2
Build step 'Execute shell' marked build as failure
1
0
Build failed in Jenkins: Osmocom-repo-install-debian11 » latest,qemu #1557
by jenkins＠lists.osmocom.org 04 Sep '26
by jenkins＠lists.osmocom.org 04 Sep '26
04 Sep '26
See <https://jenkins.osmocom.org/jenkins/job/Osmocom-repo-install-debian11/feed=…>
Changes:
------------------------------------------
[...truncated 3.49 KiB...]
+ LOG_FILE=_repo_install_test_data/qemu.log
+ check_usage
+ local i
+ [ debian11 = centos8 ]
+ [ debian11 = debian10 ]
+ [ debian11 = debian11 ]
+ return
+ FEED=latest
+ PROJ=osmocom:latest
+ [ -z ]
+ TESTS=
test_conflict
install_repo_packages
test_binaries
services_check
+ [ -z ]
+ PROJ_CONFLICT=osmocom:nightly
+ clean_up
+ [ -n ]
+ [ -e _repo_install_test_data/qemu.pid ]
+ rm -f _repo_install_test_data/temp.qcow2
+ trap clean_up_trap EXIT INT TERM 0
+ qemu_start
+ [ -n ]
+ prepare_img
+ dirname _repo_install_test_data/temp.qcow2
+ mkdir -p _repo_install_test_data
+ get_backing_img_path
+ local ret=
+ ret=/opt/qemu/debian-11.qcow2
+ [ -e /opt/qemu/debian-11.qcow2 ]
+ echo /opt/qemu/debian-11.qcow2
+ qemu-img create -f qcow2 -b /opt/qemu/debian-11.qcow2 -F qcow2 _repo_install_test_data/temp.qcow2
Formatting '_repo_install_test_data/temp.qcow2', fmt=qcow2 cluster_size=65536 extended_l2=off compression_type=zlib size=6442450944 backing_file=/opt/qemu/debian-11.qcow2 backing_fmt=qcow2 lazy_refcounts=off refcount_bits=16
+ find_free_ssh_port
+ echo (2024954 % 1000) + 22022
+ bc
+ SSH_PORT=22976
+ nc -z 127.0.0.1 22976
+ echo 22976
+ qemu_ssh_wait
+ set +x
Waiting for VM to boot up...
+ sleep 1
+ timeout 1h qemu-system-x86_64 -cpu host -device virtio-net-pci,netdev=net -display none -drive file=_repo_install_test_data/temp.qcow2,format=qcow2 -enable-kvm -m 1024 -netdev user,id=net,hostfwd=tcp:127.0.0.1:22976-:22 -nodefaults -pidfile _repo_install_test_data/qemu.pid -serial stdio -smp 16
+ cat _repo_install_test_data/qemu.pid
+ local pid=2024978
+ seq 1 6
+ [ -z 2024978 ]
+ kill -0 2024978
+ TIMEOUT=10s qemu_ssh true
+ timeout 10s sshpass -p root ssh -p 22976 -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null root(a)127.0.0.1 -- true
+ sleep 1
+ [ -z 2024978 ]
+ kill -0 2024978
+ TIMEOUT=10s qemu_ssh true
+ timeout 10s sshpass -p root ssh -p 22976 -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null root(a)127.0.0.1 -- true
Warning: Permanently added '[127.0.0.1]:22976' (ED25519) to the list of known hosts.
+ return
+ set +x
VM is running!
+ qemu_prepare_vm
+ qemu_run_test_script
+ cat
+ qemu_ssh rm -rf /repo-install-test/
+ timeout 1m sshpass -p root ssh -p 22976 -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null root(a)127.0.0.1 -- rm -rf /repo-install-test/
Warning: Permanently added '[127.0.0.1]:22976' (ED25519) to the list of known hosts.
+ qemu_ssh mkdir /repo-install-test
+ timeout 1m sshpass -p root ssh -p 22976 -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null root(a)127.0.0.1 -- mkdir /repo-install-test
Warning: Permanently added '[127.0.0.1]:22976' (ED25519) to the list of known hosts.
+ qemu_scp -r scripts/repo-install-test/run-inside-env.sh scripts/repo-install-test/run-inside.sh root@127.0.0.1:/repo-install-test
+ timeout 1m sshpass -p root scp -P 22976 -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -r scripts/repo-install-test/run-inside-env.sh scripts/repo-install-test/run-inside.sh root@127.0.0.1:/repo-install-test
Warning: Permanently added '[127.0.0.1]:22976' (ED25519) to the list of known hosts.
+ TIMEOUT=1h qemu_ssh sh -ex /repo-install-test/run-inside-env.sh
+ timeout 1h sshpass -p root ssh -p 22976 -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null root(a)127.0.0.1 -- sh -ex /repo-install-test/run-inside-env.sh
Warning: Permanently added '[127.0.0.1]:22976' (ED25519) to the list of known hosts.
+ export DISTRO=debian11
+ export DOMAIN=downloads.osmocom.org
+ export FEED=latest
+ export PROJ=osmocom:latest
+ export PROJ_CONFLICT=osmocom:nightly
+ export SKIP_PREPARE_VM=
+ export TESTS=
test_conflict
install_repo_packages
test_binaries
services_check
+ /repo-install-test/run-inside.sh
+ SERVICES=
osmo-bsc
osmo-bts-virtual
osmo-cbc
osmo-e1d
osmo-gbproxy
osmo-ggsn
osmo-gtphub
osmo-hlr
osmo-hnbgw
osmo-hnodeb
osmo-mgw
osmo-msc
osmo-pcap-client
osmo-pcap-server
osmo-pcu
osmo-remsim-bankd
osmo-remsim-server
osmo-sgsn
osmo-sip-connector
osmo-smlc
osmo-stp
+ SERVICES_NIGHTLY=
osmo-bsc-nat
+ distro_obsdir
+ echo Debian_11
+ DISTRO_OBSDIR=Debian_11
+ check_env
+ [ -n latest ]
+ echo Checking feed: latest
+ Checking feed: latest
Checking project: osmocom:latest
Checking conflicting project: osmocom:nightly
[ -n osmocom:latest ]
+ echo Checking project: osmocom:latest
+ [ -n osmocom:nightly ]
+ echo Checking conflicting project: osmocom:nightly
+ [ -n debian11Linux distribution: debian11
Enabled tests:
test_conflict
install_repo_packages
test_binaries
services_check
]
+ echo Linux distribution: debian11
+ [ -n
test_conflict
install_repo_packages
test_binaries
services_check
]
+ echo Enabled tests:
test_conflict
install_repo_packages
test_binaries
services_check
+ prepare_vm
+ [ -n ]
+ prepare_vm_debian
+ ln -sf /bin/true /usr/bin/fmtutil
+ echo path-exclude=/usr/bin/fmtutil
+ apt-get update --allow-releaseinfo-change
Get:1 http://security.debian.org/debian-security bullseye-security InRelease [27.2 kB]
Get:2 http://deb.debian.org/debian bullseye InRelease [75.1 kB]
Get:3 http://deb.debian.org/debian bullseye-updates InRelease [44.0 kB]
Get:4 http://security.debian.org/debian-security bullseye-security/main Sources [311 kB]
Get:5 http://security.debian.org/debian-security bullseye-security/main amd64 Packages [475 kB]
Get:6 http://security.debian.org/debian-security bullseye-security/main Translation-en [318 kB]
Get:7 http://deb.debian.org/debian bullseye/main Sources [8,500 kB]
Get:8 http://deb.debian.org/debian bullseye-updates/main Sources.diff/Index [26.3 kB]
Get:9 http://deb.debian.org/debian bullseye-updates/main amd64 Packages.diff/Index [26.3 kB]
Ign:9 http://deb.debian.org/debian bullseye-updates/main amd64 Packages.diff/Index
Get:10 http://deb.debian.org/debian bullseye-updates/main Translation-en.diff/Index [12.8 kB]
Get:11 http://deb.debian.org/debian bullseye-updates/main Sources T-2023-12-29-1403.39-F-2022-01-24-2024.03.pdiff [7,722 B]
Get:11 http://deb.debian.org/debian bullseye-updates/main Sources T-2023-12-29-1403.39-F-2022-01-24-2024.03.pdiff [7,722 B]
Get:12 http://deb.debian.org/debian bullseye-updates/main Translation-en T-2025-07-21-2004.39-F-2022-09-22-1635.40.pdiff [8,550 B]
Get:12 http://deb.debian.org/debian bullseye-updates/main Translation-en T-2025-07-21-2004.39-F-2022-09-22-1635.40.pdiff [8,550 B]
Get:13 http://deb.debian.org/debian bullseye/main amd64 Packages [8,066 kB]
Get:14 http://deb.debian.org/debian bullseye/main Translation-en [6,235 kB]
Get:15 http://deb.debian.org/debian bullseye-updates/main amd64 Packages [18.8 kB]
Get:16 http://deb.debian.org/debian bullseye-updates/main Sources [7,908 B]
Get:17 http://deb.debian.org/debian bullseye-updates/main Translation-en [10.5 kB]
Fetched 24.2 MB in 2s (10.4 MB/s)
Reading package lists...
+ apt-get install -y --no-install-recommends aptitude ca-certificates gnupg2 wget
Reading package lists...
Building dependency tree...
Reading state information...
The following additional packages will be installed:
aptitude-common dirmngr gnupg gnupg-l10n gnupg-utils gpg gpg-agent
gpg-wks-client gpg-wks-server gpgconf gpgsm gpgv libassuan0
libboost-iostreams1.74.0 libcwidget4 libksba8 libnpth0 libsigc++-2.0-0v5
libxapian30 pinentry-curses
Suggested packages:
apt-xapian-index aptitude-doc-en | aptitude-doc debtags dbus-user-session
pinentry-gnome3 tor parcimonie xloadimage scdaemon libcwidget-dev
xapian-tools pinentry-doc
Recommended packages:
libdpkg-perl
The following NEW packages will be installed:
aptitude aptitude-common dirmngr gnupg gnupg-l10n gnupg-utils gnupg2 gpg
gpg-agent gpg-wks-client gpg-wks-server gpgconf gpgsm libassuan0
libboost-iostreams1.74.0 libcwidget4 libksba8 libnpth0 libsigc++-2.0-0v5
libxapian30 pinentry-curses
The following packages will be upgraded:
ca-certificates gpgv wget
3 upgraded, 21 newly installed, 0 to remove and 148 not upgraded.
Need to get 14.7 MB of archives.
After this operation, 37.0 MB of additional disk space will be used.
Get:1 http://deb.debian.org/debian bullseye/main amd64 aptitude-common all 0.8.13-3 [1,776 kB]
Get:2 http://security.debian.org/debian-security bullseye-security/main amd64 gpgv amd64 2.2.27-2+deb11u3 [626 kB]
Err:3 http://security.debian.org/debian-security bullseye-security/main amd64 ca-certificates all 20250419~deb12u1~deb11u1
404 Not Found [IP: 146.75.122.132 80]
Get:4 http://deb.debian.org/debian bullseye/main amd64 libboost-iostreams1.74.0 amd64 1.74.0-9 [264 kB]
Get:5 http://security.debian.org/debian-security bullseye-security/main amd64 wget amd64 1.21-1+deb11u2 [964 kB]
Get:6 http://deb.debian.org/debian bullseye/main amd64 libsigc++-2.0-0v5 amd64 2.10.4-2 [31.3 kB]
Get:7 http://deb.debian.org/debian bullseye/main amd64 libcwidget4 amd64 0.5.18-5 [315 kB]
Get:8 http://deb.debian.org/debian bullseye/main amd64 libxapian30 amd64 1.4.18-3+deb11u1 [1,089 kB]
Get:9 http://security.debian.org/debian-security bullseye-security/main amd64 gpgconf amd64 2.2.27-2+deb11u3 [548 kB]
Get:10 http://deb.debian.org/debian bullseye/main amd64 aptitude amd64 0.8.13-3 [1,349 kB]
Get:11 http://security.debian.org/debian-security bullseye-security/main amd64 dirmngr amd64 2.2.27-2+deb11u3 [763 kB]
Get:12 http://deb.debian.org/debian bullseye/main amd64 libassuan0 amd64 2.5.3-7.1 [50.5 kB]
Get:13 http://deb.debian.org/debian bullseye/main amd64 libksba8 amd64 1.5.0-3+deb11u2 [123 kB]
Get:14 http://deb.debian.org/debian bullseye/main amd64 libnpth0 amd64 1.6-3 [19.0 kB]
Get:15 http://deb.debian.org/debian bullseye/main amd64 pinentry-curses amd64 1.1.0-4 [64.9 kB]
Get:16 http://security.debian.org/debian-security bullseye-security/main amd64 gnupg-l10n all 2.2.27-2+deb11u3 [1,084 kB]
Get:17 http://security.debian.org/debian-security bullseye-security/main amd64 gnupg-utils amd64 2.2.27-2+deb11u3 [905 kB]
Get:18 http://security.debian.org/debian-security bullseye-security/main amd64 gpg amd64 2.2.27-2+deb11u3 [928 kB]
Get:19 http://security.debian.org/debian-security bullseye-security/main amd64 gpg-agent amd64 2.2.27-2+deb11u3 [670 kB]
Get:20 http://security.debian.org/debian-security bullseye-security/main amd64 gpg-wks-client amd64 2.2.27-2+deb11u3 [524 kB]
Get:21 http://security.debian.org/debian-security bullseye-security/main amd64 gpg-wks-server amd64 2.2.27-2+deb11u3 [516 kB]
Get:22 http://security.debian.org/debian-security bullseye-security/main amd64 gpgsm amd64 2.2.27-2+deb11u3 [645 kB]
Get:23 http://security.debian.org/debian-security bullseye-security/main amd64 gnupg all 2.2.27-2+deb11u3 [825 kB]
Get:24 http://security.debian.org/debian-security bullseye-security/main amd64 gnupg2 all 2.2.27-2+deb11u3 [435 kB]
Fetched 14.5 MB in 0s (71.6 MB/s)
E: Failed to fetch http://security.debian.org/debian-security/pool/updates/main/c/ca-certifica… 404 Not Found [IP: 146.75.122.132 80]
E: Unable to fetch some archives, maybe run apt-get update or try with --fix-missing?
+ clean_up_trap
+ [ -n ]
+ set +x
### Clean up ###
+ trap - EXIT INT TERM 0
+ clean_up
+ [ -n ]
+ [ -e _repo_install_test_data/qemu.pid ]
+ cat _repo_install_test_data/qemu.pid
+ kill 2024978
+ rm -f _repo_install_test_data/temp.qcow2
Build step 'Execute shell' marked build as failure
1
0
04 Sep '26
See <https://jenkins.osmocom.org/jenkins/job/ttcn3-bts-test-asan/812/display/red…>
Changes:
------------------------------------------
[...truncated 12.42 MiB...]
<failure type='fail-verdict'>"BTS_Tests.ttcn:732 : Tguard timeout"
BTS_Tests.ttcn:9557 BTS_Tests control part
BTS_Tests.ttcn:7525 TC_rll_rel_req testcase
</failure>
</testcase>
<testcase classname='BTS_Tests:hopping' name='TC_rll_unit_data_req_DCCH' time='2.373098'/>
<testcase classname='BTS_Tests:hopping' name='TC_rll_unit_data_req_ACCH' time='5.330908'/>
<testcase classname='BTS_Tests:hopping' name='TC_rll_unit_data_ind_DCCH' time='2.902570'/>
<testcase classname='BTS_Tests:hopping' name='TC_rll_unit_data_ind_ACCH' time='10.589984'/>
<testcase classname='BTS_Tests:hopping' name='TC_chan_act_a51' time='2.644789'/>
<testcase classname='BTS_Tests:hopping' name='TC_chan_act_a52' time='2.732014'/>
<testcase classname='BTS_Tests:hopping' name='TC_chan_act_a53' time='2.719138'/>
<testcase classname='BTS_Tests:hopping' name='TC_chan_act_a54' time='2.778889'/>
<testcase classname='BTS_Tests:hopping' name='TC_encr_cmd_a51' time='5.245847'/>
<testcase classname='BTS_Tests:hopping' name='TC_encr_cmd_a52' time='5.225019'/>
<testcase classname='BTS_Tests:hopping' name='TC_encr_cmd_a53' time='5.217072'/>
<testcase classname='BTS_Tests:hopping' name='TC_encr_cmd_a54' time='5.235731'/>
<testcase classname='BTS_Tests:hopping' name='TC_err_rep_wrong_mdisc' time='1.231386'/>
<testcase classname='BTS_Tests:hopping' name='TC_err_rep_wrong_msg_type' time='1.293920'/>
<testcase classname='BTS_Tests:hopping' name='TC_err_rep_wrong_sequence' time='1.287850'/>
<testcase classname='BTS_Tests:hopping' name='TC_lapdm_selftest' time='0.024934'/>
<testcase classname='BTS_Tests:hopping' name='TC_tch_sign_l2_fill_frame' time='18.414402'/>
<testcase classname='BTS_Tests:hopping' name='TC_tch_sign_l2_fill_frame_dtxd' time='2.135621'>
<failure type='fail-verdict'>Received DTX TCH fill frame with bad frame number: 175 (mod 104: 71) (mod 26: 19)
BTS_Tests.ttcn:9579 BTS_Tests control part
BTS_Tests.ttcn:8110 TC_tch_sign_l2_fill_frame_dtxd testcase
</failure>
</testcase>
<testcase classname='BTS_Tests:hopping' name='TC_chopped_ipa_ping' time='24.721468'/>
<testcase classname='BTS_Tests:hopping' name='TC_chopped_ipa_payload' time='42.610791'/>
<testcase classname='BTS_Tests:hopping' name='TC_ms_pwr_ctrl_constant' time='16.890380'/>
<testcase classname='BTS_Tests:hopping' name='TC_ms_pwr_ctrl_pf_ewma' time='32.004907'/>
<testcase classname='BTS_Tests:hopping' name='TC_speech_no_rtp_tchf' time='7.418268'/>
<testcase classname='BTS_Tests:hopping' name='TC_speech_no_rtp_tchh' time='5.450701'/>
<testcase classname='BTS_Tests:hopping' name='TC_speech_rtp_tchf' time='7.712212'/>
<testcase classname='BTS_Tests:hopping' name='TC_speech_rtp_tchh' time='5.472445'/>
<testcase classname='BTS_Tests:hopping' name='TC_speech_osmux_tchf' time='3.389859'/>
<testcase classname='BTS_Tests:hopping' name='TC_speech_osmux_tchh' time='3.446470'/>
<testcase classname='BTS_Tests:hopping' name='TC_data_rtp_tchf144' time='2.085045'/>
<testcase classname='BTS_Tests:hopping' name='TC_data_rtp_tchf96' time='2.225284'/>
<testcase classname='BTS_Tests:hopping' name='TC_data_rtp_tchf48' time='2.226826'/>
<testcase classname='BTS_Tests:hopping' name='TC_data_rtp_tchh48' time='5.303300'>
<failure type='fail-verdict'>Timeout waiting for matching Downlink data frame
BTS_Tests.ttcn:9597 BTS_Tests control part
BTS_Tests.ttcn:8880 TC_data_rtp_tchh48 testcase
</failure>
</testcase>
<testcase classname='BTS_Tests:hopping' name='TC_data_rtp_tchf24' time='1.810828'/>
<testcase classname='BTS_Tests:hopping' name='TC_data_rtp_tchh24' time='5.346409'>
<failure type='fail-verdict'>Timeout waiting for matching Downlink data frame
BTS_Tests.ttcn:9599 BTS_Tests control part
BTS_Tests.ttcn:8949 TC_data_rtp_tchh24 testcase
</failure>
</testcase>
<testcase classname='BTS_Tests:hopping' name='TC_data_facch_tchf144' time='2.003592'/>
<testcase classname='BTS_Tests:hopping' name='TC_data_facch_tchf96' time='2.025395'/>
<testcase classname='BTS_Tests:hopping' name='TC_data_facch_tchf48' time='2.038871'/>
<testcase classname='BTS_Tests:hopping' name='TC_data_facch_tchh48' time='2.168423'/>
<testcase classname='BTS_Tests:hopping' name='TC_data_facch_tchf24' time='2.114960'/>
<testcase classname='BTS_Tests:hopping' name='TC_data_facch_tchh24' time='2.183181'/>
<testcase classname='BTS_Tests:hopping' name='TC_early_immediate_assignment' time='3.565384'/>
<testcase classname='BTS_Tests:hopping' name='TC_acch_overpower_rxqual_thresh' time='9.085471'>
<failure type='fail-verdict'>Timeout waiting for L1CTL DATA.ind
BTS_Tests.ttcn:9610 BTS_Tests control part
BTS_Tests.ttcn:9244 TC_acch_overpower_rxqual_thresh testcase
</failure>
</testcase>
<testcase classname='BTS_Tests:hopping' name='TC_acch_overpower_rxqual_thresh_dtx' time='9.038736'>
<failure type='fail-verdict'>Timeout waiting for L1CTL DATA.ind
BTS_Tests.ttcn:9611 BTS_Tests control part
BTS_Tests.ttcn:9285 TC_acch_overpower_rxqual_thresh_dtx testcase
</failure>
</testcase>
<testcase classname='BTS_Tests:hopping' name='TC_acch_overpower_always_on_facch' time='9.025439'>
<failure type='fail-verdict'>Timeout waiting for L1CTL DATA.ind
BTS_Tests.ttcn:9612 BTS_Tests control part
BTS_Tests.ttcn:9317 TC_acch_overpower_always_on_facch testcase
</failure>
</testcase>
<testcase classname='BTS_Tests:hopping' name='TC_acch_overpower_always_on_sacch' time='9.048964'>
<failure type='fail-verdict'>Timeout waiting for L1CTL DATA.ind
BTS_Tests.ttcn:9613 BTS_Tests control part
BTS_Tests.ttcn:9349 TC_acch_overpower_always_on_sacch testcase
</failure>
</testcase>
<testcase classname='BTS_Tests:hopping' name='TC_acch_overpower_limit' time='9.047440'>
<failure type='fail-verdict'>Timeout waiting for L1CTL DATA.ind
BTS_Tests.ttcn:9614 BTS_Tests control part
BTS_Tests.ttcn:9380 TC_acch_overpower_limit testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_cbch_load_idle_no_cbch' time='11.165898'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_1block' time='14.263999'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_2block' time='14.201127'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_3block' time='14.218588'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_4block' time='14.225706'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_multi' time='20.222077'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_schedule' time='14.143212'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_default_only' time='16.211399'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_default_and_normal' time='17.193154'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_default_then_null' time='26.174242'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_cbc_sdcch4_load_idle' time='16.058773'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_cbc_sdcch4_load_overload' time='18.220937'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch8_1block' time='14.258377'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch8_2block' time='14.228796'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch8_3block' time='14.211236'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch8_4block' time='14.210475'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch8_multi' time='20.207725'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch8_schedule' time='14.197506'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch8_default_only' time='16.235928'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch8_default_and_normal' time='17.243045'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_cbc_sdcch8_load_idle' time='16.269774'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_cbc_sdcch8_load_overload' time='18.173098'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_etws_p1ro' time='12.184144'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_etws_p1ro_end' time='16.171305'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_etws_pcu' time='1.160426'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_ua_dcch_sapi0' time='2.898619'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_ua_dcch_sapi0_nopayload' time='21.410426'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_ua_dcch_sapi3' time='21.378200'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_ua_dcch_sapi4' time='21.367184'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_contention' time='21.355938'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_retransmit' time='3.157656'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_retransmit_bts' time='81.479848'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_invalid_resp' time='23.381008'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_dm' time='14.016584'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_establish_ign_first_sabm' time='8.181511'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_iframe_seq_and_ack' time='29.586355'>
<failure type='fail-verdict'>Received RSL ERROR IND { msg_disc := { msg_group := RSL_MDISC_RLL (1), transparent := false }, msg_type := RSL_MT_ERROR_IND (3), ies := { { iei := RSL_IE_CHAN_NR (1), body := { chan_nr := { u := { sdcch8 := { tag := '01'B, sub_chan := 4 } }, tn := 6 } } }, { iei := RSL_IE_LINK_IDENT (2), body := { link_id := { c := FACCH_SDCCH (0), na := false, prio := SAPI0_PRIO_NORMAL (0), sapi := 0 } } }, { iei := RSL_IE_RLM_CAUSE (22), body := { rlm_cause := { len := 1, payload := '01'O } } } } }
BTS_Tests_LAPDm.ttcn:1484 BTS_Tests_LAPDm control part
BTS_Tests_LAPDm.ttcn:579 TC_iframe_seq_and_ack testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_iframe_timer_recovery' time='24.609387'>
<failure type='fail-verdict'>Received RSL ERROR IND { msg_disc := { msg_group := RSL_MDISC_RLL (1), transparent := false }, msg_type := RSL_MT_ERROR_IND (3), ies := { { iei := RSL_IE_CHAN_NR (1), body := { chan_nr := { u := { sdcch8 := { tag := '01'B, sub_chan := 4 } }, tn := 6 } } }, { iei := RSL_IE_LINK_IDENT (2), body := { link_id := { c := FACCH_SDCCH (0), na := false, prio := SAPI0_PRIO_NORMAL (0), sapi := 0 } } }, { iei := RSL_IE_RLM_CAUSE (22), body := { rlm_cause := { len := 1, payload := '01'O } } } } }
BTS_Tests_LAPDm.ttcn:1485 BTS_Tests_LAPDm control part
BTS_Tests_LAPDm.ttcn:685 TC_iframe_timer_recovery testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_ns_seq_error' time='29.620082'>
<failure type='fail-verdict'>"BTS_Tests.ttcn:732 : Tguard timeout"
BTS_Tests_LAPDm.ttcn:1486 BTS_Tests_LAPDm control part
BTS_Tests_LAPDm.ttcn:768 TC_ns_seq_error testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_nr_seq_error' time='2.891266'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_rec_invalid_frame' time='119.187293'>
<failure type='fail-verdict'>"BTS_Tests_LAPDm.ttcn:909 : Missing LAPDm_RR RSP"
BTS_Tests_LAPDm.ttcn:1488 BTS_Tests_LAPDm control part
BTS_Tests_LAPDm.ttcn:961 TC_rec_invalid_frame testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_segm_concat_dcch' time='13.491471'>
<failure type='fail-verdict'>"BTS_Tests_LAPDm.ttcn:1023 : received unexpected LAPDm { sacch := false, sapi := 0, lapdm := { ab := { addr := { spare := '0'B, lpd := 0, sapi := 0, c_r := false, ea := true }, ctrl := { s := { n_r := 1, p_f := false, s := '10'B, spare := '01'B } }, len := 0, m := false, el := 1, payload := ''O, padding := '2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B'O (\"++++++++++++++++++++\") } } }"
BTS_Tests_LAPDm.ttcn:1489 BTS_Tests_LAPDm control part
BTS_Tests_LAPDm.ttcn:1088 TC_segm_concat_dcch testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_segm_concat_sacch' time='48.543256'>
<failure type='fail-verdict'>"BTS_Tests_LAPDm.ttcn:1023 : received unexpected LAPDm { sacch := true, sapi := 0, lapdm := { ab := { addr := { spare := '0'B, lpd := 0, sapi := 0, c_r := false, ea := true }, ctrl := { u := { u := '011'B, p_f := true, u2 := '00'B, spare := '11'B } }, len := 0, m := false, el := 1, payload := ''O, padding := '2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B'O (\"++++++++++++++++++\") } } }"
BTS_Tests_LAPDm.ttcn:1490 BTS_Tests_LAPDm control part
BTS_Tests_LAPDm.ttcn:1093 TC_segm_concat_sacch testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_t200_n200' time='39.841087'>
<failure type='fail-verdict'>Missing RSL RLL ERROR INDICATION
BTS_Tests_LAPDm.ttcn:1491 BTS_Tests_LAPDm control part
BTS_Tests_LAPDm.ttcn:1182 TC_t200_n200 testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_rr_response_frame_loss' time='6.702552'>
<failure type='fail-verdict'>Timeout waiting for RR or REJ
BTS_Tests_LAPDm.ttcn:1492 BTS_Tests_LAPDm control part
BTS_Tests_LAPDm.ttcn:1239 TC_rr_response_frame_loss testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_incorrect_cr' time='15.828958'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_incorrect_c' time='23.319016'>
<failure type='fail-verdict'>"BTS_Tests.ttcn:732 : Tguard timeout"
BTS_Tests_LAPDm.ttcn:1494 BTS_Tests_LAPDm control part
BTS_Tests_LAPDm.ttcn:1341 TC_sabm_incorrect_c testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_normal_reestablishment' time='16.129681'>
<failure type='fail-verdict'>"BTS_Tests_LAPDm.ttcn:1023 : received unexpected LAPDm { sacch := false, sapi := 0, lapdm := { ab := { addr := { spare := '0'B, lpd := 0, sapi := 0, c_r := false, ea := true }, ctrl := { u := { u := '011'B, p_f := true, u2 := '00'B, spare := '11'B } }, len := 5, m := false, el := 1, payload := '933879BAFD'O, padding := '2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B'O (\"+++++++++++++++\") } } }"
BTS_Tests_LAPDm.ttcn:1495 BTS_Tests_LAPDm control part
BTS_Tests_LAPDm.ttcn:1403 TC_normal_reestablishment testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_normal_reestablishment_state_unacked' time='30.155476'>
<failure type='fail-verdict'>"BTS_Tests.ttcn:732 : Tguard timeout"
BTS_Tests_LAPDm.ttcn:1496 BTS_Tests_LAPDm control part
BTS_Tests_LAPDm.ttcn:1470 TC_normal_reestablishment_state_unacked testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_vff' time='2.326826'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_vhh' time='2.573868'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_hvhh' time='2.602642'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_dyn_ipa_vff' time='2.534408'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_dyn_osmo_vff' time='2.551156'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_dyn_osmo_vhh' time='2.565129'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_dyn_osmo_hvhh' time='2.596324'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_vff' time='1.442022'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_vhh' time='1.424091'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_hvhh' time='1.396116'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_dyn_ipa_vff' time='1.403146'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_dyn_osmo_vff' time='1.409918'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_dyn_osmo_vhh' time='1.395328'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_dyn_osmo_hvhh' time='1.397584'/>
<testcase classname='BTS_Tests_ASCI:hopping' name='TC_vbs_notification' time='4.699305'/>
<testcase classname='BTS_Tests_ASCI:hopping' name='TC_vgcs_uplink_free_and_busy' time='1.863686'/>
<testcase classname='BTS_Tests_ASCI:hopping' name='TC_vgcs_talker_fail' time='17.033063'/>
<testcase classname='BTS_Tests_ASCI:hopping' name='TC_vgcs_talker_est_rel' time='1.443104'/>
<testcase classname='BTS_Tests_ASCI:hopping' name='TC_vgcs_listener_det' time='3.398710'/>
</testsuite>
<?xml version="1.0"?>
<testsuite name='Titan' tests='17' failures='0' errors='0' skipped='0' inconc='0' time='96.00'>
<testcase classname='BTS_Tests_OML' name='TC_wrong_mdisc' time='10.539216'/>
<testcase classname='BTS_Tests_OML' name='TC_wrong_msgtype' time='0.720563'/>
<testcase classname='BTS_Tests_OML' name='TC_short_length' time='0.731551'/>
<testcase classname='BTS_Tests_OML' name='TC_long_length' time='0.664386'/>
<testcase classname='BTS_Tests_OML' name='TC_wrong_placement' time='0.712925'/>
<testcase classname='BTS_Tests_OML' name='TC_wrong_seq' time='0.716765'/>
<testcase classname='BTS_Tests_OML' name='TC_wrong_obj_class' time='0.728146'/>
<testcase classname='BTS_Tests_OML' name='TC_wrong_bts_nr' time='0.711867'/>
<testcase classname='BTS_Tests_OML' name='TC_wrong_trx_nr' time='0.723465'/>
<testcase classname='BTS_Tests_OML' name='TC_radio_carrier_opstart_noattr' time='0.712367'/>
<testcase classname='BTS_Tests_OML' name='TC_bts_opstart' time='0.719342'/>
<testcase classname='BTS_Tests_OML' name='TC_bts_opstart_noattr' time='0.712622'/>
<testcase classname='BTS_Tests_OML' name='TC_ts_opstart' time='0.676661'/>
<testcase classname='BTS_Tests_OML' name='TC_ts_opstart_noattr' time='0.710295'/>
<testcase classname='BTS_Tests_OML' name='TC_initial_state_reports' time='0.698709'/>
<testcase classname='BTS_Tests_OML' name='TC_ipa_osmo_pcu_anr_fwd' time='0.713347'/>
<testcase classname='BTS_Tests_OML' name='TC_ipa_rsl_connect_nack' time='0.726393'/>
</testsuite>
+ check_respawn_count
+ set +x
[0;31m
================================================================
WARNING: osmo-bts was respawned 4 times (expected: 3)! (OS#6794)
================================================================
[0m+ RC=1
+ uptime
+ grep --color=always -o load.*
[01;31m[Kload average: 0.31, 0.45, 0.51[m[K
+ exit 1
Build step 'Execute shell' marked build as failure
Recording test results
[Checks API] No suitable checks publisher found.
1
3
04 Sep '26
See <https://jenkins.osmocom.org/jenkins/job/ttcn3-bts-test-latest/2899/display/…>
Changes:
------------------------------------------
[...truncated 12.34 MiB...]
<failure type='fail-verdict'>"BTS_Tests.ttcn:732 : Tguard timeout"
BTS_Tests.ttcn:9557 BTS_Tests control part
BTS_Tests.ttcn:7525 TC_rll_rel_req testcase
</failure>
</testcase>
<testcase classname='BTS_Tests:hopping' name='TC_rll_unit_data_req_DCCH' time='2.350348'/>
<testcase classname='BTS_Tests:hopping' name='TC_rll_unit_data_req_ACCH' time='5.312582'/>
<testcase classname='BTS_Tests:hopping' name='TC_rll_unit_data_ind_DCCH' time='2.933278'/>
<testcase classname='BTS_Tests:hopping' name='TC_rll_unit_data_ind_ACCH' time='10.543431'/>
<testcase classname='BTS_Tests:hopping' name='TC_chan_act_a51' time='2.664050'/>
<testcase classname='BTS_Tests:hopping' name='TC_chan_act_a52' time='2.737015'/>
<testcase classname='BTS_Tests:hopping' name='TC_chan_act_a53' time='2.732869'/>
<testcase classname='BTS_Tests:hopping' name='TC_chan_act_a54' time='2.737905'/>
<testcase classname='BTS_Tests:hopping' name='TC_encr_cmd_a51' time='5.214061'/>
<testcase classname='BTS_Tests:hopping' name='TC_encr_cmd_a52' time='5.214297'/>
<testcase classname='BTS_Tests:hopping' name='TC_encr_cmd_a53' time='5.216595'/>
<testcase classname='BTS_Tests:hopping' name='TC_encr_cmd_a54' time='5.222033'/>
<testcase classname='BTS_Tests:hopping' name='TC_err_rep_wrong_mdisc' time='1.222618'/>
<testcase classname='BTS_Tests:hopping' name='TC_err_rep_wrong_msg_type' time='1.271931'/>
<testcase classname='BTS_Tests:hopping' name='TC_err_rep_wrong_sequence' time='1.259939'/>
<testcase classname='BTS_Tests:hopping' name='TC_lapdm_selftest' time='0.005402'/>
<testcase classname='BTS_Tests:hopping' name='TC_tch_sign_l2_fill_frame' time='18.454871'/>
<testcase classname='BTS_Tests:hopping' name='TC_tch_sign_l2_fill_frame_dtxd' time='2.116021'>
<failure type='fail-verdict'>Received DTX TCH fill frame with bad frame number: 175 (mod 104: 71) (mod 26: 19)
BTS_Tests.ttcn:9579 BTS_Tests control part
BTS_Tests.ttcn:8110 TC_tch_sign_l2_fill_frame_dtxd testcase
</failure>
</testcase>
<testcase classname='BTS_Tests:hopping' name='TC_chopped_ipa_ping' time='24.731854'/>
<testcase classname='BTS_Tests:hopping' name='TC_chopped_ipa_payload' time='42.619299'/>
<testcase classname='BTS_Tests:hopping' name='TC_ms_pwr_ctrl_constant' time='16.900886'/>
<testcase classname='BTS_Tests:hopping' name='TC_ms_pwr_ctrl_pf_ewma' time='31.994556'/>
<testcase classname='BTS_Tests:hopping' name='TC_speech_no_rtp_tchf' time='7.418810'/>
<testcase classname='BTS_Tests:hopping' name='TC_speech_no_rtp_tchh' time='5.519996'/>
<testcase classname='BTS_Tests:hopping' name='TC_speech_rtp_tchf' time='7.695605'/>
<testcase classname='BTS_Tests:hopping' name='TC_speech_rtp_tchh' time='5.479838'/>
<testcase classname='BTS_Tests:hopping' name='TC_speech_osmux_tchf' time='3.423534'/>
<testcase classname='BTS_Tests:hopping' name='TC_speech_osmux_tchh' time='3.452673'/>
<testcase classname='BTS_Tests:hopping' name='TC_data_rtp_tchf144' time='2.165231'/>
<testcase classname='BTS_Tests:hopping' name='TC_data_rtp_tchf96' time='2.098496'/>
<testcase classname='BTS_Tests:hopping' name='TC_data_rtp_tchf48' time='2.143761'/>
<testcase classname='BTS_Tests:hopping' name='TC_data_rtp_tchh48' time='5.307768'>
<failure type='fail-verdict'>Timeout waiting for matching Downlink data frame
BTS_Tests.ttcn:9597 BTS_Tests control part
BTS_Tests.ttcn:8880 TC_data_rtp_tchh48 testcase
</failure>
</testcase>
<testcase classname='BTS_Tests:hopping' name='TC_data_rtp_tchf24' time='1.759004'/>
<testcase classname='BTS_Tests:hopping' name='TC_data_rtp_tchh24' time='5.322843'>
<failure type='fail-verdict'>Timeout waiting for matching Downlink data frame
BTS_Tests.ttcn:9599 BTS_Tests control part
BTS_Tests.ttcn:8949 TC_data_rtp_tchh24 testcase
</failure>
</testcase>
<testcase classname='BTS_Tests:hopping' name='TC_data_facch_tchf144' time='2.015812'/>
<testcase classname='BTS_Tests:hopping' name='TC_data_facch_tchf96' time='2.016561'/>
<testcase classname='BTS_Tests:hopping' name='TC_data_facch_tchf48' time='2.041092'/>
<testcase classname='BTS_Tests:hopping' name='TC_data_facch_tchh48' time='2.137796'/>
<testcase classname='BTS_Tests:hopping' name='TC_data_facch_tchf24' time='1.996336'/>
<testcase classname='BTS_Tests:hopping' name='TC_data_facch_tchh24' time='2.207798'/>
<testcase classname='BTS_Tests:hopping' name='TC_early_immediate_assignment' time='3.533310'/>
<testcase classname='BTS_Tests:hopping' name='TC_acch_overpower_rxqual_thresh' time='9.055108'>
<failure type='fail-verdict'>Timeout waiting for L1CTL DATA.ind
BTS_Tests.ttcn:9610 BTS_Tests control part
BTS_Tests.ttcn:9244 TC_acch_overpower_rxqual_thresh testcase
</failure>
</testcase>
<testcase classname='BTS_Tests:hopping' name='TC_acch_overpower_rxqual_thresh_dtx' time='9.021965'>
<failure type='fail-verdict'>Timeout waiting for L1CTL DATA.ind
BTS_Tests.ttcn:9611 BTS_Tests control part
BTS_Tests.ttcn:9285 TC_acch_overpower_rxqual_thresh_dtx testcase
</failure>
</testcase>
<testcase classname='BTS_Tests:hopping' name='TC_acch_overpower_always_on_facch' time='9.025921'>
<failure type='fail-verdict'>Timeout waiting for L1CTL DATA.ind
BTS_Tests.ttcn:9612 BTS_Tests control part
BTS_Tests.ttcn:9317 TC_acch_overpower_always_on_facch testcase
</failure>
</testcase>
<testcase classname='BTS_Tests:hopping' name='TC_acch_overpower_always_on_sacch' time='9.019321'>
<failure type='fail-verdict'>Timeout waiting for L1CTL DATA.ind
BTS_Tests.ttcn:9613 BTS_Tests control part
BTS_Tests.ttcn:9349 TC_acch_overpower_always_on_sacch testcase
</failure>
</testcase>
<testcase classname='BTS_Tests:hopping' name='TC_acch_overpower_limit' time='9.009830'>
<failure type='fail-verdict'>Timeout waiting for L1CTL DATA.ind
BTS_Tests.ttcn:9614 BTS_Tests control part
BTS_Tests.ttcn:9380 TC_acch_overpower_limit testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_cbch_load_idle_no_cbch' time='11.166961'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_1block' time='14.235028'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_2block' time='14.195155'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_3block' time='14.199097'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_4block' time='14.202855'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_multi' time='20.206520'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_schedule' time='14.170128'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_default_only' time='16.222548'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_default_and_normal' time='17.198893'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_default_then_null' time='26.165863'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_cbc_sdcch4_load_idle' time='16.064825'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_cbc_sdcch4_load_overload' time='18.160379'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch8_1block' time='14.230958'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch8_2block' time='14.223744'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch8_3block' time='14.221568'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch8_4block' time='14.223689'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch8_multi' time='20.216867'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch8_schedule' time='14.189136'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch8_default_only' time='16.209399'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch8_default_and_normal' time='17.231693'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_cbc_sdcch8_load_idle' time='16.205172'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_cbc_sdcch8_load_overload' time='18.218403'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_etws_p1ro' time='12.188580'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_etws_p1ro_end' time='16.164099'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_etws_pcu' time='1.129672'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_ua_dcch_sapi0' time='2.872576'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_ua_dcch_sapi0_nopayload' time='21.548459'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_ua_dcch_sapi3' time='21.318412'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_ua_dcch_sapi4' time='21.362744'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_contention' time='21.301266'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_retransmit' time='3.183150'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_retransmit_bts' time='81.480276'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_invalid_resp' time='23.370132'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_dm' time='14.019642'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_establish_ign_first_sabm' time='7.970048'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_iframe_seq_and_ack' time='29.583402'>
<failure type='fail-verdict'>Received RSL ERROR IND { msg_disc := { msg_group := RSL_MDISC_RLL (1), transparent := false }, msg_type := RSL_MT_ERROR_IND (3), ies := { { iei := RSL_IE_CHAN_NR (1), body := { chan_nr := { u := { sdcch8 := { tag := '01'B, sub_chan := 4 } }, tn := 6 } } }, { iei := RSL_IE_LINK_IDENT (2), body := { link_id := { c := FACCH_SDCCH (0), na := false, prio := SAPI0_PRIO_NORMAL (0), sapi := 0 } } }, { iei := RSL_IE_RLM_CAUSE (22), body := { rlm_cause := { len := 1, payload := '01'O } } } } }
BTS_Tests_LAPDm.ttcn:1484 BTS_Tests_LAPDm control part
BTS_Tests_LAPDm.ttcn:579 TC_iframe_seq_and_ack testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_iframe_timer_recovery' time='24.608991'>
<failure type='fail-verdict'>Received RSL ERROR IND { msg_disc := { msg_group := RSL_MDISC_RLL (1), transparent := false }, msg_type := RSL_MT_ERROR_IND (3), ies := { { iei := RSL_IE_CHAN_NR (1), body := { chan_nr := { u := { sdcch8 := { tag := '01'B, sub_chan := 4 } }, tn := 6 } } }, { iei := RSL_IE_LINK_IDENT (2), body := { link_id := { c := FACCH_SDCCH (0), na := false, prio := SAPI0_PRIO_NORMAL (0), sapi := 0 } } }, { iei := RSL_IE_RLM_CAUSE (22), body := { rlm_cause := { len := 1, payload := '01'O } } } } }
BTS_Tests_LAPDm.ttcn:1485 BTS_Tests_LAPDm control part
BTS_Tests_LAPDm.ttcn:685 TC_iframe_timer_recovery testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_ns_seq_error' time='29.835194'>
<failure type='fail-verdict'>"BTS_Tests.ttcn:732 : Tguard timeout"
BTS_Tests_LAPDm.ttcn:1486 BTS_Tests_LAPDm control part
BTS_Tests_LAPDm.ttcn:768 TC_ns_seq_error testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_nr_seq_error' time='2.891384'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_rec_invalid_frame' time='119.148479'>
<failure type='fail-verdict'>"BTS_Tests_LAPDm.ttcn:909 : Missing LAPDm_RR RSP"
BTS_Tests_LAPDm.ttcn:1488 BTS_Tests_LAPDm control part
BTS_Tests_LAPDm.ttcn:961 TC_rec_invalid_frame testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_segm_concat_dcch' time='13.532764'>
<failure type='fail-verdict'>"BTS_Tests_LAPDm.ttcn:1023 : received unexpected LAPDm { sacch := false, sapi := 0, lapdm := { ab := { addr := { spare := '0'B, lpd := 0, sapi := 0, c_r := false, ea := true }, ctrl := { s := { n_r := 1, p_f := false, s := '10'B, spare := '01'B } }, len := 0, m := false, el := 1, payload := ''O, padding := '2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B'O (\"++++++++++++++++++++\") } } }"
BTS_Tests_LAPDm.ttcn:1489 BTS_Tests_LAPDm control part
BTS_Tests_LAPDm.ttcn:1088 TC_segm_concat_dcch testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_segm_concat_sacch' time='48.521164'>
<failure type='fail-verdict'>"BTS_Tests_LAPDm.ttcn:1023 : received unexpected LAPDm { sacch := true, sapi := 0, lapdm := { ab := { addr := { spare := '0'B, lpd := 0, sapi := 0, c_r := false, ea := true }, ctrl := { u := { u := '011'B, p_f := true, u2 := '00'B, spare := '11'B } }, len := 0, m := false, el := 1, payload := ''O, padding := '2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B'O (\"++++++++++++++++++\") } } }"
BTS_Tests_LAPDm.ttcn:1490 BTS_Tests_LAPDm control part
BTS_Tests_LAPDm.ttcn:1093 TC_segm_concat_sacch testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_t200_n200' time='39.830872'>
<failure type='fail-verdict'>Missing RSL RLL ERROR INDICATION
BTS_Tests_LAPDm.ttcn:1491 BTS_Tests_LAPDm control part
BTS_Tests_LAPDm.ttcn:1182 TC_t200_n200 testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_rr_response_frame_loss' time='6.685968'>
<failure type='fail-verdict'>Timeout waiting for RR or REJ
BTS_Tests_LAPDm.ttcn:1492 BTS_Tests_LAPDm control part
BTS_Tests_LAPDm.ttcn:1239 TC_rr_response_frame_loss testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_incorrect_cr' time='15.739364'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_incorrect_c' time='23.320257'>
<failure type='fail-verdict'>"BTS_Tests.ttcn:732 : Tguard timeout"
BTS_Tests_LAPDm.ttcn:1494 BTS_Tests_LAPDm control part
BTS_Tests_LAPDm.ttcn:1341 TC_sabm_incorrect_c testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_normal_reestablishment' time='16.145310'>
<failure type='fail-verdict'>"BTS_Tests_LAPDm.ttcn:1023 : received unexpected LAPDm { sacch := false, sapi := 0, lapdm := { ab := { addr := { spare := '0'B, lpd := 0, sapi := 0, c_r := false, ea := true }, ctrl := { u := { u := '011'B, p_f := true, u2 := '00'B, spare := '11'B } }, len := 5, m := false, el := 1, payload := '73AD749F72'O, padding := '2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B'O (\"+++++++++++++++\") } } }"
BTS_Tests_LAPDm.ttcn:1495 BTS_Tests_LAPDm control part
BTS_Tests_LAPDm.ttcn:1403 TC_normal_reestablishment testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_normal_reestablishment_state_unacked' time='12.386010'>
<failure type='fail-verdict'>"BTS_Tests_LAPDm.ttcn:1023 : received unexpected LAPDm { sacch := false, sapi := 0, lapdm := { ab := { addr := { spare := '0'B, lpd := 0, sapi := 0, c_r := false, ea := true }, ctrl := { s := { n_r := 1, p_f := false, s := '10'B, spare := '01'B } }, len := 0, m := false, el := 1, payload := ''O, padding := '2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B'O (\"++++++++++++++++++++\") } } }"
BTS_Tests_LAPDm.ttcn:1496 BTS_Tests_LAPDm control part
BTS_Tests_LAPDm.ttcn:1470 TC_normal_reestablishment_state_unacked testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_vff' time='2.463909'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_vhh' time='2.587186'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_hvhh' time='2.522888'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_dyn_ipa_vff' time='2.537688'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_dyn_osmo_vff' time='2.549299'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_dyn_osmo_vhh' time='2.596033'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_dyn_osmo_hvhh' time='2.569035'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_vff' time='1.342184'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_vhh' time='1.396333'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_hvhh' time='1.328221'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_dyn_ipa_vff' time='1.343292'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_dyn_osmo_vff' time='1.345475'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_dyn_osmo_vhh' time='1.362589'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_dyn_osmo_hvhh' time='1.339957'/>
<testcase classname='BTS_Tests_ASCI:hopping' name='TC_vbs_notification' time='4.669504'/>
<testcase classname='BTS_Tests_ASCI:hopping' name='TC_vgcs_uplink_free_and_busy' time='1.793044'/>
<testcase classname='BTS_Tests_ASCI:hopping' name='TC_vgcs_talker_fail' time='16.554646'/>
<testcase classname='BTS_Tests_ASCI:hopping' name='TC_vgcs_talker_est_rel' time='1.383257'/>
<testcase classname='BTS_Tests_ASCI:hopping' name='TC_vgcs_listener_det' time='3.366684'/>
</testsuite>
<?xml version="1.0"?>
<testsuite name='Titan' tests='17' failures='0' errors='0' skipped='0' inconc='0' time='95.00'>
<testcase classname='BTS_Tests_OML' name='TC_wrong_mdisc' time='10.893808'/>
<testcase classname='BTS_Tests_OML' name='TC_wrong_msgtype' time='0.696791'/>
<testcase classname='BTS_Tests_OML' name='TC_short_length' time='0.701760'/>
<testcase classname='BTS_Tests_OML' name='TC_long_length' time='0.684165'/>
<testcase classname='BTS_Tests_OML' name='TC_wrong_placement' time='0.680512'/>
<testcase classname='BTS_Tests_OML' name='TC_wrong_seq' time='0.692670'/>
<testcase classname='BTS_Tests_OML' name='TC_wrong_obj_class' time='0.691187'/>
<testcase classname='BTS_Tests_OML' name='TC_wrong_bts_nr' time='0.676402'/>
<testcase classname='BTS_Tests_OML' name='TC_wrong_trx_nr' time='0.690015'/>
<testcase classname='BTS_Tests_OML' name='TC_radio_carrier_opstart_noattr' time='0.693577'/>
<testcase classname='BTS_Tests_OML' name='TC_bts_opstart' time='0.707775'/>
<testcase classname='BTS_Tests_OML' name='TC_bts_opstart_noattr' time='0.691416'/>
<testcase classname='BTS_Tests_OML' name='TC_ts_opstart' time='0.692375'/>
<testcase classname='BTS_Tests_OML' name='TC_ts_opstart_noattr' time='0.675833'/>
<testcase classname='BTS_Tests_OML' name='TC_initial_state_reports' time='0.691598'/>
<testcase classname='BTS_Tests_OML' name='TC_ipa_osmo_pcu_anr_fwd' time='0.718732'/>
<testcase classname='BTS_Tests_OML' name='TC_ipa_rsl_connect_nack' time='0.680379'/>
</testsuite>
+ check_respawn_count
+ set +x
[0;31m
================================================================
WARNING: osmo-bts was respawned 5 times (expected: 3)! (OS#6794)
================================================================
[0m+ RC=1
+ uptime
+ grep --color=always -o load.*
[01;31m[Kload average: 0.50, 0.53, 0.55[m[K
+ exit 1
Build step 'Execute shell' marked build as failure
Recording test results
[Checks API] No suitable checks publisher found.
1
2
See <https://jenkins.osmocom.org/jenkins/job/ttcn3-bts-test/3253/display/redirect>
Changes:
------------------------------------------
[...truncated 12.61 MiB...]
<testcase classname='BTS_Tests:hopping' name='TC_rll_unit_data_req_DCCH' time='2.362138'/>
<testcase classname='BTS_Tests:hopping' name='TC_rll_unit_data_req_ACCH' time='5.310589'/>
<testcase classname='BTS_Tests:hopping' name='TC_rll_unit_data_ind_DCCH' time='2.962768'/>
<testcase classname='BTS_Tests:hopping' name='TC_rll_unit_data_ind_ACCH' time='11.507693'/>
<testcase classname='BTS_Tests:hopping' name='TC_chan_act_a51' time='2.660208'/>
<testcase classname='BTS_Tests:hopping' name='TC_chan_act_a52' time='2.694232'/>
<testcase classname='BTS_Tests:hopping' name='TC_chan_act_a53' time='2.722300'/>
<testcase classname='BTS_Tests:hopping' name='TC_chan_act_a54' time='2.732358'/>
<testcase classname='BTS_Tests:hopping' name='TC_encr_cmd_a51' time='5.233887'/>
<testcase classname='BTS_Tests:hopping' name='TC_encr_cmd_a52' time='5.201316'/>
<testcase classname='BTS_Tests:hopping' name='TC_encr_cmd_a53' time='5.218450'/>
<testcase classname='BTS_Tests:hopping' name='TC_encr_cmd_a54' time='5.233411'/>
<testcase classname='BTS_Tests:hopping' name='TC_err_rep_wrong_mdisc' time='1.243760'/>
<testcase classname='BTS_Tests:hopping' name='TC_err_rep_wrong_msg_type' time='1.294122'/>
<testcase classname='BTS_Tests:hopping' name='TC_err_rep_wrong_sequence' time='1.253848'/>
<testcase classname='BTS_Tests:hopping' name='TC_lapdm_selftest' time='0.005677'/>
<testcase classname='BTS_Tests:hopping' name='TC_tch_sign_l2_fill_frame' time='18.482168'/>
<testcase classname='BTS_Tests:hopping' name='TC_tch_sign_l2_fill_frame_dtxd' time='2.123890'>
<failure type='fail-verdict'>Received DTX TCH fill frame with bad frame number: 175 (mod 104: 71) (mod 26: 19)
BTS_Tests.ttcn:9579 BTS_Tests control part
BTS_Tests.ttcn:8110 TC_tch_sign_l2_fill_frame_dtxd testcase
</failure>
</testcase>
<testcase classname='BTS_Tests:hopping' name='TC_chopped_ipa_ping' time='24.729338'/>
<testcase classname='BTS_Tests:hopping' name='TC_chopped_ipa_payload' time='42.624546'/>
<testcase classname='BTS_Tests:hopping' name='TC_ms_pwr_ctrl_constant' time='16.921291'/>
<testcase classname='BTS_Tests:hopping' name='TC_ms_pwr_ctrl_pf_ewma' time='31.538792'/>
<testcase classname='BTS_Tests:hopping' name='TC_speech_no_rtp_tchf' time='7.399658'/>
<testcase classname='BTS_Tests:hopping' name='TC_speech_no_rtp_tchh' time='5.435021'/>
<testcase classname='BTS_Tests:hopping' name='TC_speech_rtp_tchf' time='7.646737'/>
<testcase classname='BTS_Tests:hopping' name='TC_speech_rtp_tchh' time='5.564752'/>
<testcase classname='BTS_Tests:hopping' name='TC_speech_osmux_tchf' time='3.441128'/>
<testcase classname='BTS_Tests:hopping' name='TC_speech_osmux_tchh' time='3.513000'/>
<testcase classname='BTS_Tests:hopping' name='TC_data_rtp_tchf144' time='2.191179'/>
<testcase classname='BTS_Tests:hopping' name='TC_data_rtp_tchf96' time='2.100743'/>
<testcase classname='BTS_Tests:hopping' name='TC_data_rtp_tchf48' time='2.209684'/>
<testcase classname='BTS_Tests:hopping' name='TC_data_rtp_tchh48' time='5.241434'>
<failure type='fail-verdict'>Timeout waiting for matching Downlink data frame
BTS_Tests.ttcn:9597 BTS_Tests control part
BTS_Tests.ttcn:8880 TC_data_rtp_tchh48 testcase
</failure>
</testcase>
<testcase classname='BTS_Tests:hopping' name='TC_data_rtp_tchf24' time='1.791748'/>
<testcase classname='BTS_Tests:hopping' name='TC_data_rtp_tchh24' time='5.291150'>
<failure type='fail-verdict'>Timeout waiting for matching Downlink data frame
BTS_Tests.ttcn:9599 BTS_Tests control part
BTS_Tests.ttcn:8949 TC_data_rtp_tchh24 testcase
</failure>
</testcase>
<testcase classname='BTS_Tests:hopping' name='TC_data_facch_tchf144' time='1.944481'/>
<testcase classname='BTS_Tests:hopping' name='TC_data_facch_tchf96' time='2.037149'/>
<testcase classname='BTS_Tests:hopping' name='TC_data_facch_tchf48' time='2.032078'/>
<testcase classname='BTS_Tests:hopping' name='TC_data_facch_tchh48' time='2.154447'/>
<testcase classname='BTS_Tests:hopping' name='TC_data_facch_tchf24' time='2.032406'/>
<testcase classname='BTS_Tests:hopping' name='TC_data_facch_tchh24' time='2.176167'/>
<testcase classname='BTS_Tests:hopping' name='TC_early_immediate_assignment' time='3.555515'/>
<testcase classname='BTS_Tests:hopping' name='TC_acch_overpower_rxqual_thresh' time='9.086187'>
<failure type='fail-verdict'>Timeout waiting for L1CTL DATA.ind
BTS_Tests.ttcn:9610 BTS_Tests control part
BTS_Tests.ttcn:9244 TC_acch_overpower_rxqual_thresh testcase
</failure>
</testcase>
<testcase classname='BTS_Tests:hopping' name='TC_acch_overpower_rxqual_thresh_dtx' time='9.041500'>
<failure type='fail-verdict'>Timeout waiting for L1CTL DATA.ind
BTS_Tests.ttcn:9611 BTS_Tests control part
BTS_Tests.ttcn:9285 TC_acch_overpower_rxqual_thresh_dtx testcase
</failure>
</testcase>
<testcase classname='BTS_Tests:hopping' name='TC_acch_overpower_always_on_facch' time='9.035845'>
<failure type='fail-verdict'>Timeout waiting for L1CTL DATA.ind
BTS_Tests.ttcn:9612 BTS_Tests control part
BTS_Tests.ttcn:9317 TC_acch_overpower_always_on_facch testcase
</failure>
</testcase>
<testcase classname='BTS_Tests:hopping' name='TC_acch_overpower_always_on_sacch' time='9.067327'>
<failure type='fail-verdict'>Timeout waiting for L1CTL DATA.ind
BTS_Tests.ttcn:9613 BTS_Tests control part
BTS_Tests.ttcn:9349 TC_acch_overpower_always_on_sacch testcase
</failure>
</testcase>
<testcase classname='BTS_Tests:hopping' name='TC_acch_overpower_limit' time='9.036235'>
<failure type='fail-verdict'>Timeout waiting for L1CTL DATA.ind
BTS_Tests.ttcn:9614 BTS_Tests control part
BTS_Tests.ttcn:9380 TC_acch_overpower_limit testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_cbch_load_idle_no_cbch' time='11.162509'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_1block' time='14.242780'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_2block' time='14.197098'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_3block' time='14.231047'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_4block' time='14.214239'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_multi' time='20.219069'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_schedule' time='14.176361'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_default_only' time='16.236399'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_default_and_normal' time='17.201927'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_default_then_null' time='26.240165'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_cbc_sdcch4_load_idle' time='16.082399'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_cbc_sdcch4_load_overload' time='18.265347'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch8_1block' time='14.234067'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch8_2block' time='14.239223'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch8_3block' time='14.250513'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch8_4block' time='14.250632'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch8_multi' time='20.249281'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch8_schedule' time='14.181159'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch8_default_only' time='16.331881'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch8_default_and_normal' time='17.168484'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_cbc_sdcch8_load_idle' time='16.139705'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_cbc_sdcch8_load_overload' time='18.184435'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_etws_p1ro' time='12.170730'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_etws_p1ro_end' time='16.151972'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_etws_pcu' time='1.119104'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_ua_dcch_sapi0' time='2.899001'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_ua_dcch_sapi0_nopayload' time='21.529266'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_ua_dcch_sapi3' time='21.359242'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_ua_dcch_sapi4' time='21.306477'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_contention' time='21.238274'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_retransmit' time='3.149510'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_retransmit_bts' time='81.468029'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_invalid_resp' time='23.367669'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_dm' time='14.025744'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_establish_ign_first_sabm' time='8.174421'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_iframe_seq_and_ack' time='29.583312'>
<failure type='fail-verdict'>Received RSL ERROR IND { msg_disc := { msg_group := RSL_MDISC_RLL (1), transparent := false }, msg_type := RSL_MT_ERROR_IND (3), ies := { { iei := RSL_IE_CHAN_NR (1), body := { chan_nr := { u := { sdcch8 := { tag := '01'B, sub_chan := 4 } }, tn := 6 } } }, { iei := RSL_IE_LINK_IDENT (2), body := { link_id := { c := FACCH_SDCCH (0), na := false, prio := SAPI0_PRIO_NORMAL (0), sapi := 0 } } }, { iei := RSL_IE_RLM_CAUSE (22), body := { rlm_cause := { len := 1, payload := '01'O } } } } }
BTS_Tests_LAPDm.ttcn:1484 BTS_Tests_LAPDm control part
BTS_Tests_LAPDm.ttcn:579 TC_iframe_seq_and_ack testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_iframe_timer_recovery' time='24.911618'>
<failure type='fail-verdict'>Received RSL ERROR IND { msg_disc := { msg_group := RSL_MDISC_RLL (1), transparent := false }, msg_type := RSL_MT_ERROR_IND (3), ies := { { iei := RSL_IE_CHAN_NR (1), body := { chan_nr := { u := { sdcch8 := { tag := '01'B, sub_chan := 4 } }, tn := 6 } } }, { iei := RSL_IE_LINK_IDENT (2), body := { link_id := { c := FACCH_SDCCH (0), na := false, prio := SAPI0_PRIO_NORMAL (0), sapi := 0 } } }, { iei := RSL_IE_RLM_CAUSE (22), body := { rlm_cause := { len := 1, payload := '01'O } } } } }
BTS_Tests_LAPDm.ttcn:1485 BTS_Tests_LAPDm control part
BTS_Tests_LAPDm.ttcn:685 TC_iframe_timer_recovery testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_ns_seq_error' time='34.210126'>
<failure type='fail-verdict'>"BTS_Tests.ttcn:732 : Tguard timeout"
BTS_Tests_LAPDm.ttcn:1486 BTS_Tests_LAPDm control part
BTS_Tests_LAPDm.ttcn:768 TC_ns_seq_error testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_nr_seq_error' time='5.371976'>
<failure type='fail-verdict'>Missing DISC from BTS
BTS_Tests_LAPDm.ttcn:1487 BTS_Tests_LAPDm control part
BTS_Tests_LAPDm.ttcn:831 TC_nr_seq_error testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_rec_invalid_frame' time='87.384835'>
<failure type='fail-verdict'>"BTS_Tests_LAPDm.ttcn:913 : Missing LAPDm_RR RSP"
BTS_Tests_LAPDm.ttcn:1488 BTS_Tests_LAPDm control part
BTS_Tests_LAPDm.ttcn:961 TC_rec_invalid_frame testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_segm_concat_dcch' time='10.639287'>
<failure type='fail-verdict'>"BTS_Tests_LAPDm.ttcn:1023 : received unexpected LAPDm { sacch := false, sapi := 0, lapdm := { ab := { addr := { spare := '0'B, lpd := 0, sapi := 0, c_r := false, ea := true }, ctrl := { s := { n_r := 1, p_f := false, s := '10'B, spare := '01'B } }, len := 0, m := false, el := 1, payload := ''O, padding := '2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B'O (\"++++++++++++++++++++\") } } }"
BTS_Tests_LAPDm.ttcn:1489 BTS_Tests_LAPDm control part
BTS_Tests_LAPDm.ttcn:1088 TC_segm_concat_dcch testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_segm_concat_sacch' time='50.794623'>
<failure type='fail-verdict'>"BTS_Tests_LAPDm.ttcn:1023 : received unexpected LAPDm { sacch := true, sapi := 0, lapdm := { ab := { addr := { spare := '0'B, lpd := 0, sapi := 0, c_r := false, ea := true }, ctrl := { u := { u := '011'B, p_f := true, u2 := '00'B, spare := '11'B } }, len := 0, m := false, el := 1, payload := ''O, padding := '2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B'O (\"++++++++++++++++++\") } } }"
BTS_Tests_LAPDm.ttcn:1490 BTS_Tests_LAPDm control part
BTS_Tests_LAPDm.ttcn:1093 TC_segm_concat_sacch testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_t200_n200' time='40.134733'>
<failure type='fail-verdict'>Missing RSL RLL ERROR INDICATION
BTS_Tests_LAPDm.ttcn:1491 BTS_Tests_LAPDm control part
BTS_Tests_LAPDm.ttcn:1182 TC_t200_n200 testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_rr_response_frame_loss' time='11.391153'>
<failure type='fail-verdict'>Timeout waiting for RR or REJ
BTS_Tests_LAPDm.ttcn:1492 BTS_Tests_LAPDm control part
BTS_Tests_LAPDm.ttcn:1239 TC_rr_response_frame_loss testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_incorrect_cr' time='15.899311'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_incorrect_c' time='23.408331'>
<failure type='fail-verdict'>"BTS_Tests.ttcn:732 : Tguard timeout"
BTS_Tests_LAPDm.ttcn:1494 BTS_Tests_LAPDm control part
BTS_Tests_LAPDm.ttcn:1341 TC_sabm_incorrect_c testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_normal_reestablishment' time='25.904644'>
<failure type='fail-verdict'>"BTS_Tests_LAPDm.ttcn:1023 : received unexpected LAPDm { sacch := false, sapi := 0, lapdm := { ab := { addr := { spare := '0'B, lpd := 0, sapi := 0, c_r := false, ea := true }, ctrl := { s := { n_r := 3, p_f := false, s := '10'B, spare := '01'B } }, len := 0, m := false, el := 1, payload := ''O, padding := '2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B'O (\"++++++++++++++++++++\") } } }"
BTS_Tests_LAPDm.ttcn:1495 BTS_Tests_LAPDm control part
BTS_Tests_LAPDm.ttcn:1403 TC_normal_reestablishment testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_normal_reestablishment_state_unacked' time='12.179504'>
<failure type='fail-verdict'>"BTS_Tests_LAPDm.ttcn:1023 : received unexpected LAPDm { sacch := false, sapi := 0, lapdm := { ab := { addr := { spare := '0'B, lpd := 0, sapi := 0, c_r := false, ea := true }, ctrl := { s := { n_r := 1, p_f := false, s := '10'B, spare := '01'B } }, len := 0, m := false, el := 1, payload := ''O, padding := '2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B'O (\"++++++++++++++++++++\") } } }"
BTS_Tests_LAPDm.ttcn:1496 BTS_Tests_LAPDm control part
BTS_Tests_LAPDm.ttcn:1470 TC_normal_reestablishment_state_unacked testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_vff' time='2.594911'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_vhh' time='2.543865'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_hvhh' time='2.588056'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_dyn_ipa_vff' time='2.540875'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_dyn_osmo_vff' time='5.365692'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_dyn_osmo_vhh' time='2.632379'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_dyn_osmo_hvhh' time='2.530126'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_vff' time='1.293024'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_vhh' time='1.385812'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_hvhh' time='1.347317'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_dyn_ipa_vff' time='1.388949'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_dyn_osmo_vff' time='1.342311'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_dyn_osmo_vhh' time='1.386609'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_dyn_osmo_hvhh' time='1.530889'/>
<testcase classname='BTS_Tests_ASCI:hopping' name='TC_vbs_notification' time='4.707824'/>
<testcase classname='BTS_Tests_ASCI:hopping' name='TC_vgcs_uplink_free_and_busy' time='1.830457'/>
<testcase classname='BTS_Tests_ASCI:hopping' name='TC_vgcs_talker_fail' time='16.520069'/>
<testcase classname='BTS_Tests_ASCI:hopping' name='TC_vgcs_talker_est_rel' time='1.383390'/>
<testcase classname='BTS_Tests_ASCI:hopping' name='TC_vgcs_listener_det' time='3.361224'/>
</testsuite>
<?xml version="1.0"?>
<testsuite name='Titan' tests='17' failures='0' errors='0' skipped='0' inconc='0' time='96.00'>
<testcase classname='BTS_Tests_OML' name='TC_wrong_mdisc' time='10.647169'/>
<testcase classname='BTS_Tests_OML' name='TC_wrong_msgtype' time='0.707657'/>
<testcase classname='BTS_Tests_OML' name='TC_short_length' time='0.701020'/>
<testcase classname='BTS_Tests_OML' name='TC_long_length' time='0.711224'/>
<testcase classname='BTS_Tests_OML' name='TC_wrong_placement' time='0.718254'/>
<testcase classname='BTS_Tests_OML' name='TC_wrong_seq' time='0.712302'/>
<testcase classname='BTS_Tests_OML' name='TC_wrong_obj_class' time='0.707085'/>
<testcase classname='BTS_Tests_OML' name='TC_wrong_bts_nr' time='0.722222'/>
<testcase classname='BTS_Tests_OML' name='TC_wrong_trx_nr' time='0.700993'/>
<testcase classname='BTS_Tests_OML' name='TC_radio_carrier_opstart_noattr' time='0.707281'/>
<testcase classname='BTS_Tests_OML' name='TC_bts_opstart' time='0.708083'/>
<testcase classname='BTS_Tests_OML' name='TC_bts_opstart_noattr' time='0.706045'/>
<testcase classname='BTS_Tests_OML' name='TC_ts_opstart' time='0.716567'/>
<testcase classname='BTS_Tests_OML' name='TC_ts_opstart_noattr' time='0.697146'/>
<testcase classname='BTS_Tests_OML' name='TC_initial_state_reports' time='0.711034'/>
<testcase classname='BTS_Tests_OML' name='TC_ipa_osmo_pcu_anr_fwd' time='0.740870'/>
<testcase classname='BTS_Tests_OML' name='TC_ipa_rsl_connect_nack' time='0.733082'/>
</testsuite>
+ check_respawn_count
+ set +x
[0;31m
================================================================
WARNING: osmo-bts was respawned 5 times (expected: 3)! (OS#6794)
================================================================
[0m+ RC=1
+ uptime
+ grep --color=always -o load.*
[01;31m[Kload average: 11.64, 10.36, 5.92[m[K
+ exit 1
Build step 'Execute shell' marked build as failure
Recording test results
[Checks API] No suitable checks publisher found.
1
0
04 Sep '26
See <https://jenkins.osmocom.org/jenkins/job/ttcn3-bts-test-io_uring/842/display…>
Changes:
------------------------------------------
[...truncated 12.51 MiB...]
<testcase classname='BTS_Tests:hopping' name='TC_rll_unit_data_req_DCCH' time='2.352162'/>
<testcase classname='BTS_Tests:hopping' name='TC_rll_unit_data_req_ACCH' time='5.323591'/>
<testcase classname='BTS_Tests:hopping' name='TC_rll_unit_data_ind_DCCH' time='2.934030'/>
<testcase classname='BTS_Tests:hopping' name='TC_rll_unit_data_ind_ACCH' time='10.551472'/>
<testcase classname='BTS_Tests:hopping' name='TC_chan_act_a51' time='2.629719'/>
<testcase classname='BTS_Tests:hopping' name='TC_chan_act_a52' time='2.711688'/>
<testcase classname='BTS_Tests:hopping' name='TC_chan_act_a53' time='2.704810'/>
<testcase classname='BTS_Tests:hopping' name='TC_chan_act_a54' time='2.713245'/>
<testcase classname='BTS_Tests:hopping' name='TC_encr_cmd_a51' time='5.229384'/>
<testcase classname='BTS_Tests:hopping' name='TC_encr_cmd_a52' time='5.198403'/>
<testcase classname='BTS_Tests:hopping' name='TC_encr_cmd_a53' time='5.220090'/>
<testcase classname='BTS_Tests:hopping' name='TC_encr_cmd_a54' time='5.191755'/>
<testcase classname='BTS_Tests:hopping' name='TC_err_rep_wrong_mdisc' time='1.231767'/>
<testcase classname='BTS_Tests:hopping' name='TC_err_rep_wrong_msg_type' time='1.277127'/>
<testcase classname='BTS_Tests:hopping' name='TC_err_rep_wrong_sequence' time='1.248554'/>
<testcase classname='BTS_Tests:hopping' name='TC_lapdm_selftest' time='0.006552'/>
<testcase classname='BTS_Tests:hopping' name='TC_tch_sign_l2_fill_frame' time='18.437830'/>
<testcase classname='BTS_Tests:hopping' name='TC_tch_sign_l2_fill_frame_dtxd' time='2.111796'>
<failure type='fail-verdict'>Received DTX TCH fill frame with bad frame number: 175 (mod 104: 71) (mod 26: 19)
BTS_Tests.ttcn:9579 BTS_Tests control part
BTS_Tests.ttcn:8110 TC_tch_sign_l2_fill_frame_dtxd testcase
</failure>
</testcase>
<testcase classname='BTS_Tests:hopping' name='TC_chopped_ipa_ping' time='24.691903'/>
<testcase classname='BTS_Tests:hopping' name='TC_chopped_ipa_payload' time='42.606635'/>
<testcase classname='BTS_Tests:hopping' name='TC_ms_pwr_ctrl_constant' time='16.881380'/>
<testcase classname='BTS_Tests:hopping' name='TC_ms_pwr_ctrl_pf_ewma' time='31.518543'/>
<testcase classname='BTS_Tests:hopping' name='TC_speech_no_rtp_tchf' time='7.441254'/>
<testcase classname='BTS_Tests:hopping' name='TC_speech_no_rtp_tchh' time='5.486200'/>
<testcase classname='BTS_Tests:hopping' name='TC_speech_rtp_tchf' time='7.564330'/>
<testcase classname='BTS_Tests:hopping' name='TC_speech_rtp_tchh' time='5.488457'/>
<testcase classname='BTS_Tests:hopping' name='TC_speech_osmux_tchf' time='3.357788'/>
<testcase classname='BTS_Tests:hopping' name='TC_speech_osmux_tchh' time='3.479367'/>
<testcase classname='BTS_Tests:hopping' name='TC_data_rtp_tchf144' time='2.143675'/>
<testcase classname='BTS_Tests:hopping' name='TC_data_rtp_tchf96' time='2.158744'/>
<testcase classname='BTS_Tests:hopping' name='TC_data_rtp_tchf48' time='2.156043'/>
<testcase classname='BTS_Tests:hopping' name='TC_data_rtp_tchh48' time='5.313418'>
<failure type='fail-verdict'>Timeout waiting for matching Downlink data frame
BTS_Tests.ttcn:9597 BTS_Tests control part
BTS_Tests.ttcn:8880 TC_data_rtp_tchh48 testcase
</failure>
</testcase>
<testcase classname='BTS_Tests:hopping' name='TC_data_rtp_tchf24' time='1.803527'/>
<testcase classname='BTS_Tests:hopping' name='TC_data_rtp_tchh24' time='5.318136'>
<failure type='fail-verdict'>Timeout waiting for matching Downlink data frame
BTS_Tests.ttcn:9599 BTS_Tests control part
BTS_Tests.ttcn:8949 TC_data_rtp_tchh24 testcase
</failure>
</testcase>
<testcase classname='BTS_Tests:hopping' name='TC_data_facch_tchf144' time='1.998204'/>
<testcase classname='BTS_Tests:hopping' name='TC_data_facch_tchf96' time='2.079991'/>
<testcase classname='BTS_Tests:hopping' name='TC_data_facch_tchf48' time='2.032359'/>
<testcase classname='BTS_Tests:hopping' name='TC_data_facch_tchh48' time='2.189105'/>
<testcase classname='BTS_Tests:hopping' name='TC_data_facch_tchf24' time='2.004521'/>
<testcase classname='BTS_Tests:hopping' name='TC_data_facch_tchh24' time='2.163063'/>
<testcase classname='BTS_Tests:hopping' name='TC_early_immediate_assignment' time='3.539632'/>
<testcase classname='BTS_Tests:hopping' name='TC_acch_overpower_rxqual_thresh' time='9.087180'>
<failure type='fail-verdict'>Timeout waiting for L1CTL DATA.ind
BTS_Tests.ttcn:9610 BTS_Tests control part
BTS_Tests.ttcn:9244 TC_acch_overpower_rxqual_thresh testcase
</failure>
</testcase>
<testcase classname='BTS_Tests:hopping' name='TC_acch_overpower_rxqual_thresh_dtx' time='9.035129'>
<failure type='fail-verdict'>Timeout waiting for L1CTL DATA.ind
BTS_Tests.ttcn:9611 BTS_Tests control part
BTS_Tests.ttcn:9285 TC_acch_overpower_rxqual_thresh_dtx testcase
</failure>
</testcase>
<testcase classname='BTS_Tests:hopping' name='TC_acch_overpower_always_on_facch' time='9.020660'>
<failure type='fail-verdict'>Timeout waiting for L1CTL DATA.ind
BTS_Tests.ttcn:9612 BTS_Tests control part
BTS_Tests.ttcn:9317 TC_acch_overpower_always_on_facch testcase
</failure>
</testcase>
<testcase classname='BTS_Tests:hopping' name='TC_acch_overpower_always_on_sacch' time='9.017240'>
<failure type='fail-verdict'>Timeout waiting for L1CTL DATA.ind
BTS_Tests.ttcn:9613 BTS_Tests control part
BTS_Tests.ttcn:9349 TC_acch_overpower_always_on_sacch testcase
</failure>
</testcase>
<testcase classname='BTS_Tests:hopping' name='TC_acch_overpower_limit' time='9.004315'>
<failure type='fail-verdict'>Timeout waiting for L1CTL DATA.ind
BTS_Tests.ttcn:9614 BTS_Tests control part
BTS_Tests.ttcn:9380 TC_acch_overpower_limit testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_cbch_load_idle_no_cbch' time='11.138838'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_1block' time='14.244959'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_2block' time='14.190248'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_3block' time='14.200864'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_4block' time='14.190282'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_multi' time='20.207344'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_schedule' time='14.136335'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_default_only' time='16.198972'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_default_and_normal' time='17.169634'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_default_then_null' time='26.158310'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_cbc_sdcch4_load_idle' time='16.054818'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_cbc_sdcch4_load_overload' time='18.191411'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch8_1block' time='14.229966'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch8_2block' time='14.211196'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch8_3block' time='14.216843'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch8_4block' time='14.184326'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch8_multi' time='20.214878'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch8_schedule' time='14.167216'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch8_default_only' time='16.146007'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch8_default_and_normal' time='17.162824'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_cbc_sdcch8_load_idle' time='16.148869'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_cbc_sdcch8_load_overload' time='18.184280'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_etws_p1ro' time='12.160964'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_etws_p1ro_end' time='16.199412'/>
<testcase classname='BTS_Tests_SMSCB:hopping' name='TC_etws_pcu' time='1.119640'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_ua_dcch_sapi0' time='2.912880'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_ua_dcch_sapi0_nopayload' time='9.743033'>
<failure type='fail-verdict'>"BTS_Tests.ttcn:792 : Timeout expecting RF CHAN REL"
BTS_Tests_LAPDm.ttcn:1475 BTS_Tests_LAPDm control part
BTS_Tests_LAPDm.ttcn:157 TC_sabm_ua_dcch_sapi0_nopayload testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_ua_dcch_sapi3' time='21.548906'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_ua_dcch_sapi4' time='21.326336'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_contention' time='21.256453'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_retransmit' time='3.160734'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_retransmit_bts' time='81.458687'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_invalid_resp' time='23.368160'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_dm' time='14.020458'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_establish_ign_first_sabm' time='8.157510'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_iframe_seq_and_ack' time='29.618707'>
<failure type='fail-verdict'>Received RSL ERROR IND { msg_disc := { msg_group := RSL_MDISC_RLL (1), transparent := false }, msg_type := RSL_MT_ERROR_IND (3), ies := { { iei := RSL_IE_CHAN_NR (1), body := { chan_nr := { u := { sdcch8 := { tag := '01'B, sub_chan := 4 } }, tn := 6 } } }, { iei := RSL_IE_LINK_IDENT (2), body := { link_id := { c := FACCH_SDCCH (0), na := false, prio := SAPI0_PRIO_NORMAL (0), sapi := 0 } } }, { iei := RSL_IE_RLM_CAUSE (22), body := { rlm_cause := { len := 1, payload := '01'O } } } } }
BTS_Tests_LAPDm.ttcn:1484 BTS_Tests_LAPDm control part
BTS_Tests_LAPDm.ttcn:579 TC_iframe_seq_and_ack testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_iframe_timer_recovery' time='24.626010'>
<failure type='fail-verdict'>Received RSL ERROR IND { msg_disc := { msg_group := RSL_MDISC_RLL (1), transparent := false }, msg_type := RSL_MT_ERROR_IND (3), ies := { { iei := RSL_IE_CHAN_NR (1), body := { chan_nr := { u := { sdcch8 := { tag := '01'B, sub_chan := 4 } }, tn := 6 } } }, { iei := RSL_IE_LINK_IDENT (2), body := { link_id := { c := FACCH_SDCCH (0), na := false, prio := SAPI0_PRIO_NORMAL (0), sapi := 0 } } }, { iei := RSL_IE_RLM_CAUSE (22), body := { rlm_cause := { len := 1, payload := '01'O } } } } }
BTS_Tests_LAPDm.ttcn:1485 BTS_Tests_LAPDm control part
BTS_Tests_LAPDm.ttcn:685 TC_iframe_timer_recovery testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_ns_seq_error' time='29.638110'>
<failure type='fail-verdict'>"BTS_Tests.ttcn:732 : Tguard timeout"
BTS_Tests_LAPDm.ttcn:1486 BTS_Tests_LAPDm control part
BTS_Tests_LAPDm.ttcn:768 TC_ns_seq_error testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_nr_seq_error' time='2.911839'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_rec_invalid_frame' time='119.182699'>
<failure type='fail-verdict'>"BTS_Tests_LAPDm.ttcn:909 : Missing LAPDm_RR RSP"
BTS_Tests_LAPDm.ttcn:1488 BTS_Tests_LAPDm control part
BTS_Tests_LAPDm.ttcn:961 TC_rec_invalid_frame testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_segm_concat_dcch' time='13.569010'>
<failure type='fail-verdict'>"BTS_Tests_LAPDm.ttcn:1023 : received unexpected LAPDm { sacch := false, sapi := 0, lapdm := { ab := { addr := { spare := '0'B, lpd := 0, sapi := 0, c_r := false, ea := true }, ctrl := { s := { n_r := 1, p_f := false, s := '10'B, spare := '01'B } }, len := 0, m := false, el := 1, payload := ''O, padding := '2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B'O (\"++++++++++++++++++++\") } } }"
BTS_Tests_LAPDm.ttcn:1489 BTS_Tests_LAPDm control part
BTS_Tests_LAPDm.ttcn:1088 TC_segm_concat_dcch testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_segm_concat_sacch' time='48.538719'>
<failure type='fail-verdict'>"BTS_Tests_LAPDm.ttcn:1023 : received unexpected LAPDm { sacch := true, sapi := 0, lapdm := { ab := { addr := { spare := '0'B, lpd := 0, sapi := 0, c_r := false, ea := true }, ctrl := { u := { u := '011'B, p_f := true, u2 := '00'B, spare := '11'B } }, len := 0, m := false, el := 1, payload := ''O, padding := '2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B'O (\"++++++++++++++++++\") } } }"
BTS_Tests_LAPDm.ttcn:1490 BTS_Tests_LAPDm control part
BTS_Tests_LAPDm.ttcn:1093 TC_segm_concat_sacch testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_t200_n200' time='39.851576'>
<failure type='fail-verdict'>Missing RSL RLL ERROR INDICATION
BTS_Tests_LAPDm.ttcn:1491 BTS_Tests_LAPDm control part
BTS_Tests_LAPDm.ttcn:1182 TC_t200_n200 testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_rr_response_frame_loss' time='6.696472'>
<failure type='fail-verdict'>Timeout waiting for RR or REJ
BTS_Tests_LAPDm.ttcn:1492 BTS_Tests_LAPDm control part
BTS_Tests_LAPDm.ttcn:1239 TC_rr_response_frame_loss testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_incorrect_cr' time='15.798639'/>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_incorrect_c' time='23.314936'>
<failure type='fail-verdict'>"BTS_Tests.ttcn:732 : Tguard timeout"
BTS_Tests_LAPDm.ttcn:1494 BTS_Tests_LAPDm control part
BTS_Tests_LAPDm.ttcn:1341 TC_sabm_incorrect_c testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_normal_reestablishment' time='16.122836'>
<failure type='fail-verdict'>"BTS_Tests_LAPDm.ttcn:1023 : received unexpected LAPDm { sacch := false, sapi := 0, lapdm := { ab := { addr := { spare := '0'B, lpd := 0, sapi := 0, c_r := false, ea := true }, ctrl := { u := { u := '011'B, p_f := true, u2 := '00'B, spare := '11'B } }, len := 5, m := false, el := 1, payload := 'C3A513771C'O, padding := '2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B'O (\"+++++++++++++++\") } } }"
BTS_Tests_LAPDm.ttcn:1495 BTS_Tests_LAPDm control part
BTS_Tests_LAPDm.ttcn:1403 TC_normal_reestablishment testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_LAPDm:hopping' name='TC_normal_reestablishment_state_unacked' time='12.363489'>
<failure type='fail-verdict'>"BTS_Tests_LAPDm.ttcn:1023 : received unexpected LAPDm { sacch := false, sapi := 0, lapdm := { ab := { addr := { spare := '0'B, lpd := 0, sapi := 0, c_r := false, ea := true }, ctrl := { s := { n_r := 1, p_f := false, s := '10'B, spare := '01'B } }, len := 0, m := false, el := 1, payload := ''O, padding := '2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B'O (\"++++++++++++++++++++\") } } }"
BTS_Tests_LAPDm.ttcn:1496 BTS_Tests_LAPDm control part
BTS_Tests_LAPDm.ttcn:1470 TC_normal_reestablishment_state_unacked testcase
</failure>
</testcase>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_vff' time='2.507948'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_vhh' time='2.570512'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_hvhh' time='2.596853'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_dyn_ipa_vff' time='2.556073'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_dyn_osmo_vff' time='2.552723'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_dyn_osmo_vhh' time='2.605013'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_dyn_osmo_hvhh' time='2.558373'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_vff' time='1.353136'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_vhh' time='1.343980'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_hvhh' time='1.370663'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_dyn_ipa_vff' time='1.312874'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_dyn_osmo_vff' time='1.359758'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_dyn_osmo_vhh' time='1.373840'/>
<testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_dyn_osmo_hvhh' time='1.406952'/>
<testcase classname='BTS_Tests_ASCI:hopping' name='TC_vbs_notification' time='4.665799'/>
<testcase classname='BTS_Tests_ASCI:hopping' name='TC_vgcs_uplink_free_and_busy' time='1.828415'/>
<testcase classname='BTS_Tests_ASCI:hopping' name='TC_vgcs_talker_fail' time='16.564002'/>
<testcase classname='BTS_Tests_ASCI:hopping' name='TC_vgcs_talker_est_rel' time='1.430531'/>
<testcase classname='BTS_Tests_ASCI:hopping' name='TC_vgcs_listener_det' time='3.378516'/>
</testsuite>
<?xml version="1.0"?>
<testsuite name='Titan' tests='17' failures='0' errors='0' skipped='0' inconc='0' time='96.00'>
<testcase classname='BTS_Tests_OML' name='TC_wrong_mdisc' time='10.801116'/>
<testcase classname='BTS_Tests_OML' name='TC_wrong_msgtype' time='0.713144'/>
<testcase classname='BTS_Tests_OML' name='TC_short_length' time='0.734492'/>
<testcase classname='BTS_Tests_OML' name='TC_long_length' time='0.735724'/>
<testcase classname='BTS_Tests_OML' name='TC_wrong_placement' time='0.685059'/>
<testcase classname='BTS_Tests_OML' name='TC_wrong_seq' time='0.715781'/>
<testcase classname='BTS_Tests_OML' name='TC_wrong_obj_class' time='0.678666'/>
<testcase classname='BTS_Tests_OML' name='TC_wrong_bts_nr' time='0.694581'/>
<testcase classname='BTS_Tests_OML' name='TC_wrong_trx_nr' time='0.726992'/>
<testcase classname='BTS_Tests_OML' name='TC_radio_carrier_opstart_noattr' time='0.688302'/>
<testcase classname='BTS_Tests_OML' name='TC_bts_opstart' time='0.713138'/>
<testcase classname='BTS_Tests_OML' name='TC_bts_opstart_noattr' time='0.721712'/>
<testcase classname='BTS_Tests_OML' name='TC_ts_opstart' time='0.702979'/>
<testcase classname='BTS_Tests_OML' name='TC_ts_opstart_noattr' time='0.680584'/>
<testcase classname='BTS_Tests_OML' name='TC_initial_state_reports' time='0.671856'/>
<testcase classname='BTS_Tests_OML' name='TC_ipa_osmo_pcu_anr_fwd' time='0.704370'/>
<testcase classname='BTS_Tests_OML' name='TC_ipa_rsl_connect_nack' time='0.678657'/>
</testsuite>
+ check_respawn_count
+ set +x
[0;31m
================================================================
WARNING: osmo-bts was respawned 4 times (expected: 3)! (OS#6794)
================================================================
[0m+ RC=1
+ uptime
+ grep --color=always -o load.*
[01;31m[Kload average: 1.73, 2.60, 2.51[m[K
+ exit 1
Build step 'Execute shell' marked build as failure
Recording test results
[Checks API] No suitable checks publisher found.
1
3
Build failed in Jenkins: master-osmo_dia2gsup » a1=default,a2=default,a3=default,a4=default,osmocom-master #1095
by jenkins＠lists.osmocom.org 04 Sep '26
by jenkins＠lists.osmocom.org 04 Sep '26
04 Sep '26
See <https://jenkins.osmocom.org/jenkins/job/master-osmo_dia2gsup/a1=default,a2=…>
Changes:
------------------------------------------
Started by upstream project "master-osmo_dia2gsup" build number 1095
originally caused by:
Started by timer
Running as SYSTEM
Building remotely on build4-deb12build-ansible (ttcn3 obs osmo-gsm-tester-build qemu io_uring linux-shallow-clone osmocom-gerrit coverity osmocom-master) in workspace <https://jenkins.osmocom.org/jenkins/job/master-osmo_dia2gsup/a1=default,a2=…>
[ssh-agent] Looking for ssh-agent implementation...
$ ssh-agent
SSH_AUTH_SOCK=/tmp/ssh-9PGjiMihryN9/agent.1122537
SSH_AGENT_PID=1122541
[ssh-agent] Started.
Running ssh-add (command line suppressed)
[ssh-agent] Using credentials docs (OS#5798)
The recommended git tool is: NONE
No credentials specified
Cloning the remote Git repository
Cloning repository https://gerrit.osmocom.org/erlang/osmo_dia2gsup
> git init <https://jenkins.osmocom.org/jenkins/job/master-osmo_dia2gsup/a1=default,a2=…> # timeout=10
Fetching upstream changes from https://gerrit.osmocom.org/erlang/osmo_dia2gsup
> git --version # timeout=10
> git --version # 'git version 2.39.5'
> git fetch --tags --force --progress -- https://gerrit.osmocom.org/erlang/osmo_dia2gsup +refs/heads/*:refs/remotes/origin/* # timeout=10
> git config remote.origin.url https://gerrit.osmocom.org/erlang/osmo_dia2gsup # timeout=10
> git config --add remote.origin.fetch +refs/heads/*:refs/remotes/origin/* # timeout=10
Avoid second fetch
Checking out Revision 63c6606d70a29fe56680185348764ce76c4aa2bf (origin/master)
> git config core.sparsecheckout # timeout=10
> git checkout -f 63c6606d70a29fe56680185348764ce76c4aa2bf # timeout=10
Commit message: "Set Item-Number in UTRAN and GERAN vectors."
> git rev-list --no-walk 63c6606d70a29fe56680185348764ce76c4aa2bf # timeout=10
[osmocom-master] $ /bin/sh -xe /tmp/jenkins5211934736230357652.sh
+ readlink -f /tmp/ssh-9PGjiMihryN9/agent.1122537
+ docker run --rm=true --cap-add SYS_PTRACE --security-opt seccomp=/home/osmocom-build/osmo-ci/_docker_playground/seccomp_profile.json --ulimit fsize=1000000000 -e ASCIIDOC_WARNINGS_CHECK=1 -e CFLAGS=-g -e HOME=/build -e IS_MASTER_BUILD=1 -e JOB_NAME=master-osmo_dia2gsup/a1=default,a2=default,a3=default,a4=default,label=osmocom-master -e MAKE=make -e OSMOPY_DEBUG_TCP_SOCKETS=1 -e OSMO_GSM_MANUALS_DIR=/opt/osmo-gsm-manuals -e PARALLEL_MAKE=-j 12 -e PUBLISH=1 -e SSH_AUTH_SOCK=/ssh-agent -e WITH_MANUALS=1 -w /build -i -u build -v /tmp/ssh-9PGjiMihryN9/agent.1122537:/ssh-agent -v /home/osmocom-build/osmo-ci:/osmo-ci:ro -v <https://jenkins.osmocom.org/jenkins/job/master-osmo_dia2gsup/a1=default,a2=…>:/build -v /home/osmocom-build/.ssh:/home/build/.ssh:ro osmocom-build/debian-bookworm-erlang /usr/bin/timeout 30m /build/contrib/jenkins.sh
+ rebar3 compile
===> Fetching rebar3_diameter_compiler (from {git,"https://github.com/carlosedp/rebar3_diameter_compiler.git",
{tag,"0.8.0"}})
===> Errors loading plugin {rebar3_diameter_compiler,
{git,
"https://github.com/carlosedp/rebar3_diameter_compiler.git",
{tag,"0.8.0"}}}. Run rebar3 with DEBUG=1 set to see errors.
===> Fetching rebar3_diameter_compiler (from {git,"https://github.com/carlosedp/rebar3_diameter_compiler.git",
{tag,"0.8.0"}})
===> Errors loading plugin {rebar3_diameter_compiler,
{git,
"https://github.com/carlosedp/rebar3_diameter_compiler.git",
{tag,"0.8.0"}}}. Run rebar3 with DEBUG=1 set to see errors.
===> Verifying dependencies...
===> Fetching lager (from {git,"https://github.com/erlang-lager/lager",
{ref,"459a3b2cdd9eadd29e5a7ce5c43932f5ccd6eb88"}})
===> Failed to fetch and copy dep: {git,"https://github.com/erlang-lager/lager",
{ref,
"459a3b2cdd9eadd29e5a7ce5c43932f5ccd6eb88"}}
Build step 'Execute shell' marked build as failure
$ ssh-agent -k
unset SSH_AUTH_SOCK;
unset SSH_AGENT_PID;
echo Agent pid 1122541 killed;
[ssh-agent] Stopped.
Archiving artifacts
1
1