See https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/297/display/redirect
Changes:
------------------------------------------ [...truncated 251.28 MiB...] [32m09/05 13:47:00.976[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":24}] (../lib/sbi/client.c:548) [32m09/05 13:47:00.976[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM added [607] (../lib/sbi/nghttp2-server.c:1737) [32m09/05 13:47:00.977[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] /nnrf-nfm/v1/nf-instances/d3e6d77e-a92f-41f1-baa9-339c3efa7f1a (../lib/sbi/nghttp2-server.c:1308) [32m09/05 13:47:00.977[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED: 101 (../lib/sbi/nghttp2-server.c:1312) [32m09/05 13:47:00.977[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":24}] (../lib/sbi/nghttp2-server.c:1314) [32m09/05 13:47:00.977[0m: [[33mnrf[0m] [1;37mDEBUG[0m: nrf_state_operational(): OGS_EVENT_NAME_SBI_SERVER (../src/nrf/nrf-sm.c:54) [32m09/05 13:47:00.977[0m: [[33mnrf[0m] [1;37mDEBUG[0m: nrf_nf_state_registered(): OGS_EVENT_NAME_SBI_SERVER (../src/nrf/nf-sm.c:201) [32m09/05 13:47:00.977[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [204] (../lib/sbi/nghttp2-server.c:702) [32m09/05 13:47:00.977[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [607] (../lib/sbi/nghttp2-server.c:1419) 0000: 00000301 05000002 5f89fbbe ........_... [32m09/05 13:47:00.977[0m: [[33msbi[0m] [1;37mDEBUG[0m: [204:PATCH] http://127.0.0.10:7777/nnrf-nfm/v1/nf-instances/d3e6d77e-a92f-41f1-baa9-339c... (../lib/sbi/client.c:744) [32m09/05 13:47:00.977[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755) [32m09/05 13:47:00.977[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [204] (../lib/sbi/nghttp2-server.c:702) [32m09/05 13:47:00.977[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [5771] (../lib/sbi/nghttp2-server.c:1419) 0000: 00001a01 05000016 8b89c161 96dc34fd ...........a..4. 0010: 2806d4dc 5ad41004 e28166e3 4edc0014 (...Z.....f.N... 0020: c5a37f ... [32m09/05 13:47:00.977[0m: [[33msbi[0m] [1;37mDEBUG[0m: [204:PATCH] http://127.0.0.200:7777/nnrf-nfm/v1/nf-instances/d3e6d77e-a92f-41f1-baa9-339... (../lib/sbi/client.c:744) [32m09/05 13:47:00.977[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755) [32m09/05 13:47:00.977[0m: [[33mpcf[0m] [1;37mDEBUG[0m: pcf_state_operational(): OGS_EVENT_NAME_SBI_CLIENT (../src/pcf/pcf-sm.c:64) [32m09/05 13:47:00.977[0m: [[33msbi[0m] [1;37mDEBUG[0m: ogs_sbi_nf_state_registered(): OGS_EVENT_NAME_SBI_CLIENT (../lib/sbi/nf-sm.c:358) Waiting for packet dumper to finish... 1 (prev_count=4320, count=13096) [32m09/05 13:47:01.160[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): OGS_EVENT_NAME_SBI_TIMER (../src/amf/amf-sm.c:83) [32m09/05 13:47:01.160[0m: [[33msbi[0m] [1;37mDEBUG[0m: ogs_sbi_nf_state_registered(): OGS_EVENT_NAME_SBI_TIMER (../lib/sbi/nf-sm.c:358) [32m09/05 13:47:01.160[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] http://127.0.0.200:7777/nnrf-nfm/v1/nf-instances/d40427ca-a92f-41f1-b8dd-df7... (../lib/sbi/client.c:797) [32m09/05 13:47:01.160[0m: [[33msbi[0m] [1;37mDEBUG[0m: SENDING...[100] (../lib/sbi/client.c:546) [32m09/05 13:47:01.160[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":0}] (../lib/sbi/client.c:548) [32m09/05 13:47:01.161[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM added [23947] (../lib/sbi/nghttp2-server.c:1737) [32m09/05 13:47:01.161[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] /nnrf-nfm/v1/nf-instances/d40427ca-a92f-41f1-b8dd-df70ec584a7d (../lib/sbi/nghttp2-server.c:1308) [32m09/05 13:47:01.161[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED: 100 (../lib/sbi/nghttp2-server.c:1312) [32m09/05 13:47:01.161[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/05 13:47:01.161[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] http://127.0.0.10:7777/nnrf-nfm/v1/nf-instances/d40427ca-a92f-41f1-b8dd-df70... (../lib/sbi/client.c:797) [32m09/05 13:47:01.161[0m: [[33msbi[0m] [1;37mDEBUG[0m: SENDING...[100] (../lib/sbi/client.c:546) [32m09/05 13:47:01.161[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":0}] (../lib/sbi/client.c:548) [32m09/05 13:47:01.161[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM added [609] (../lib/sbi/nghttp2-server.c:1737) [32m09/05 13:47:01.161[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] /nnrf-nfm/v1/nf-instances/d40427ca-a92f-41f1-b8dd-df70ec584a7d (../lib/sbi/nghttp2-server.c:1308) [32m09/05 13:47:01.161[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED: 100 (../lib/sbi/nghttp2-server.c:1312) [32m09/05 13:47:01.161[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/05 13:47:01.161[0m: [[33mnrf[0m] [1;37mDEBUG[0m: nrf_state_operational(): OGS_EVENT_NAME_SBI_SERVER (../src/nrf/nrf-sm.c:54) [32m09/05 13:47:01.162[0m: [[33mnrf[0m] [1;37mDEBUG[0m: nrf_nf_state_registered(): OGS_EVENT_NAME_SBI_SERVER (../src/nrf/nf-sm.c:201) [32m09/05 13:47:01.162[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [204] (../lib/sbi/nghttp2-server.c:702) [32m09/05 13:47:01.162[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [609] (../lib/sbi/nghttp2-server.c:1419) 0000: 00001a01 05000002 6189fb61 96dc34fd ........a..a..4. 0010: 2806d4dc 5ad41004 e28166e3 4edc0054 (...Z.....f.N..T 0020: c5a37f ... [32m09/05 13:47:01.162[0m: [[33msbi[0m] [1;37mDEBUG[0m: [204:PATCH] http://127.0.0.10:7777/nnrf-nfm/v1/nf-instances/d40427ca-a92f-41f1-b8dd-df70... (../lib/sbi/client.c:744) [32m09/05 13:47:01.162[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755) [32m09/05 13:47:01.162[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [204] (../lib/sbi/nghttp2-server.c:702) [32m09/05 13:47:01.162[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [23947] (../lib/sbi/nghttp2-server.c:1419) [32m09/05 13:47:01.162[0m: [[33msbi[0m] [1;37mDEBUG[0m: [204:PATCH] http://127.0.0.200:7777/nnrf-nfm/v1/nf-instances/d40427ca-a92f-41f1-b8dd-df7... (../lib/sbi/client.c:744) [32m09/05 13:47:01.162[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755) [32m09/05 13:47:01.162[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): OGS_EVENT_NAME_SBI_CLIENT (../src/amf/amf-sm.c:83) [32m09/05 13:47:01.162[0m: [[33msbi[0m] [1;37mDEBUG[0m: ogs_sbi_nf_state_registered(): OGS_EVENT_NAME_SBI_CLIENT (../lib/sbi/nf-sm.c:358) 0000: 00001a01 0500005d 8b89c961 96dc34fd .......]...a..4. 0010: 2806d4dc 5ad41004 e28166e3 4edc0054 (...Z.....f.N..T 0020: c5a37f ... MTC@34589de6d3da: External command `https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/ws/src/osmo-ttcn3-hacks/ttcn3-tcpdump-stop.sh C5G_Tests.TC_normal_reg_emergency_services error' was executed successfully (exit status: 0). MTC@34589de6d3da: Starting external command `https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/ws/src/osmo-ttcn3-hacks/ttcn3-tcpdump-start.sh C5G_Tests.TC_normal_reg_emergency_services_fallback'. ------ C5G_Tests.TC_normal_reg_emergency_services_fallback ------ (13:47:02) load average: 3.73, 2.82, 2.36 /usr/bin/dumpcap -q -s 1520 -n -i any -w "https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/297/artifact/logs/testsuite/C5G_Tests.TC_normal_reg_emergency_services_fallback.pcap" >https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/297/artifact/logs/testsuite/C5G_Tests.TC_normal_reg_emergency_services_fallback.pcap.stdout 2>/tmp/cmderr & Waiting for packet dumper to start... 0 MTC@34589de6d3da: External command `https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/ws/src/osmo-ttcn3-hacks/ttcn3-tcpdump-start.sh C5G_Tests.TC_normal_reg_emergency_services_fallback' was executed successfully (exit status: 0). MTC@34589de6d3da: Test case TC_normal_reg_emergency_services_fallback started. GTP1U_EM(861)@34589de6d3da: Warning: sizes of 'struct sctp_event_subscribe': compile-time 14, kernel: 14 GTP1U_EM(861)@34589de6d3da: 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(861)@34589de6d3da: Dynamic test case error: testcase.stop GTP1U_EM(861)@34589de6d3da: setverdict(error): fail -> error GTP1U_EM(861)@34589de6d3da: Final verdict of PTC: error TC_normal_reg_emergency_services_fallback-NGAP0(862)@34589de6d3da: Warning: sizes of 'struct sctp_event_subscribe': compile-time 14, kernel: 14 [32m09/05 13:47:03.286[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/05 13:47:03.286[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_ACCEPT (../src/amf/amf-sm.c:83) [32m09/05 13:47:03.286[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2 accepted[127.0.0.202] in master_sm module (../src/amf/amf-sm.c:818) [32m09/05 13:47:03.291[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_initial(): ENTRY (../src/amf/ngap-sm.c:28) [32m09/05 13:47:03.291[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_operational(): ENTRY (../src/amf/ngap-sm.c:55) [32m09/05 13:47:03.291[0m: [[33mamf[0m] [1;32mINFO[0m: [Added] Number of gNBs is now 1 (../src/amf/context.c:1277) [32m09/05 13:47:03.291[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/05 13:47:03.291[0m: [[33mamf[0m] [1;37mDEBUG[0m: SCTP_COMM_UP (../src/amf/ngap-sctp.c:161) [32m09/05 13:47:03.291[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_SCTP_COMM_UP (../src/amf/amf-sm.c:83) [32m09/05 13:47:03.291[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2[127.0.0.202] max_num_of_ostreams : 30 (../src/amf/amf-sm.c:865) TC_normal_reg_emergency_services_fallback-NGAP1(863)@34589de6d3da: Warning: sizes of 'struct sctp_event_subscribe': compile-time 14, kernel: 14 [32m09/05 13:47:03.299[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/05 13:47:03.299[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_ACCEPT (../src/amf/amf-sm.c:83) [32m09/05 13:47:03.299[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2 accepted[127.0.0.202] in master_sm module (../src/amf/amf-sm.c:818) [32m09/05 13:47:03.303[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_initial(): ENTRY (../src/amf/ngap-sm.c:28) [32m09/05 13:47:03.303[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_operational(): ENTRY (../src/amf/ngap-sm.c:55) [32m09/05 13:47:03.303[0m: [[33mamf[0m] [1;32mINFO[0m: [Added] Number of gNBs is now 2 (../src/amf/context.c:1277) [32m09/05 13:47:03.303[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/05 13:47:03.303[0m: [[33mamf[0m] [1;37mDEBUG[0m: SCTP_COMM_UP (../src/amf/ngap-sctp.c:161) [32m09/05 13:47:03.303[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_SCTP_COMM_UP (../src/amf/amf-sm.c:83) [32m09/05 13:47:03.303[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2[127.0.0.202] max_num_of_ostreams : 30 (../src/amf/amf-sm.c:865) [32m09/05 13:47:03.307[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_MESSAGE (../src/amf/amf-sm.c:83) [32m09/05 13:47:03.307[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_operational(): AMF_EVENT_NGAP_MESSAGE (../src/amf/ngap-sm.c:55) [32m09/05 13:47:03.307[0m: [[33mamf[0m] [1;37mDEBUG[0m: NGSetupRequest (../src/amf/ngap-handler.c:288) [32m09/05 13:47:03.307[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/05 13:47:03.307[0m: [[33mamf[0m] [1;37mDEBUG[0m: PLMN_ID[MCC:999 MNC:70] (../src/amf/ngap-handler.c:391) [32m09/05 13:47:03.307[0m: [[33mamf[0m] [1;37mDEBUG[0m: PagingDRX[0] (../src/amf/ngap-handler.c:395) [32m09/05 13:47:03.307[0m: [[33mamf[0m] [1;37mDEBUG[0m: TAC[1] (../src/amf/ngap-handler.c:439) [32m09/05 13:47:03.307[0m: [[33mamf[0m] [1;37mDEBUG[0m: PLMN_ID[MCC:999 MNC:70] (../src/amf/ngap-handler.c:501) [32m09/05 13:47:03.307[0m: [[33mamf[0m] [1;37mDEBUG[0m: S_NSSAI[SST:1 SD:0xffffff] (../src/amf/ngap-handler.c:562) [32m09/05 13:47:03.307[0m: [[33mamf[0m] [1;37mDEBUG[0m: TAC[1] (../src/amf/ngap-handler.c:72) [32m09/05 13:47:03.307[0m: [[33mamf[0m] [1;37mDEBUG[0m: PLMN_ID[MCC:999 MNC:70] (../src/amf/ngap-handler.c:73) [32m09/05 13:47:03.307[0m: [[33mamf[0m] [1;37mDEBUG[0m: SERVED_TAI_INDEX[0] (../src/amf/ngap-handler.c:78) [32m09/05 13:47:03.307[0m: [[33mamf[0m] [1;37mDEBUG[0m: PLMN_ID[MCC:999 MNC:70] (../src/amf/ngap-handler.c:100) [32m09/05 13:47:03.307[0m: [[33mamf[0m] [1;37mDEBUG[0m: S_NSSAI[SST:1 SD:0xffffff] (../src/amf/ngap-handler.c:105) [32m09/05 13:47:03.307[0m: [[33mamf[0m] [1;37mDEBUG[0m: NG-Setup response (../src/amf/ngap-path.c:356) [32m09/05 13:47:03.307[0m: [[33mamf[0m] [1;37mDEBUG[0m: NGSetupResponse (../src/amf/ngap-build.c:91) [32m09/05 13:47:03.307[0m: [[33mamf[0m] [1;37mDEBUG[0m: IP[127.0.0.202] RAN_ID[0] (../src/amf/ngap-path.c:64) MTC@34589de6d3da: setverdict(pass): none -> pass MTC@34589de6d3da: Dynamic test case error: Error message was received from MC: The connect operation refers to test component with component reference 861, which has already terminated. MTC@34589de6d3da: setverdict(error): pass -> error TC_normal_reg_emergency_services_fallback0(864)@34589de6d3da: Final verdict of PTC: none [32m09/05 13:47:03.315[0m: [[33mamf[0m] [1;37mDEBUG[0m: SCTP_SHUTDOWN_EVENT:[T:32773, F:0x0, L:12] (../src/amf/ngap-sctp.c:193) [32m09/05 13:47:03.315[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_CONNREFUSED (../src/amf/amf-sm.c:83) [32m09/05 13:47:03.315[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2[127.0.0.202] connection refused!!! (../src/amf/amf-sm.c:878) [32m09/05 13:47:03.315[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_operational(): EXIT (../src/amf/ngap-sm.c:55) [32m09/05 13:47:03.315[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_final(): EXIT (../src/amf/ngap-sm.c:37) [32m09/05 13:47:03.315[0m: [[33mamf[0m] [1;32mINFO[0m: [Removed] Number of gNBs is now 1 (../src/amf/context.c:1305) [32m09/05 13:47:03.315[0m: [[33mamf[0m] [1;37mDEBUG[0m: SCTP_SHUTDOWN_EVENT:[T:32773, F:0x0, L:12] (../src/amf/ngap-sctp.c:193) [32m09/05 13:47:03.315[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_CONNREFUSED (../src/amf/amf-sm.c:83) [32m09/05 13:47:03.315[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2[127.0.0.202] connection refused!!! (../src/amf/amf-sm.c:878) [32m09/05 13:47:03.315[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_operational(): EXIT (../src/amf/ngap-sm.c:55) [32m09/05 13:47:03.315[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_final(): EXIT (../src/amf/ngap-sm.c:37) [32m09/05 13:47:03.315[0m: [[33mamf[0m] [1;32mINFO[0m: [Removed] Number of gNBs is now 0 (../src/amf/context.c:1305) TC_normal_reg_emergency_services_fallback-NGAP1(863)@34589de6d3da: Final verdict of PTC: none TC_normal_reg_emergency_services_fallback-NGAP0(862)@34589de6d3da: Final verdict of PTC: none MTC@34589de6d3da: Setting final verdict of the test case. MTC@34589de6d3da: Local verdict of MTC: error MTC@34589de6d3da: Local verdict of PTC GTP1U_EM(861): error (error -> error) MTC@34589de6d3da: Local verdict of PTC TC_normal_reg_emergency_services_fallback-NGAP0(862): none (error -> error) MTC@34589de6d3da: Local verdict of PTC TC_normal_reg_emergency_services_fallback-NGAP1(863): none (error -> error) MTC@34589de6d3da: Local verdict of PTC TC_normal_reg_emergency_services_fallback0(864): none (error -> error) MTC@34589de6d3da: Test case TC_normal_reg_emergency_services_fallback finished. Verdict: error MTC@34589de6d3da: Starting external command `https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/ws/src/osmo-ttcn3-hacks/ttcn3-tcpdump-stop.sh C5G_Tests.TC_normal_reg_emergency_services_fallback error'. (13:47:03) load average: 3.51, 2.79, 2.35 [1;31m------ C5G_Tests.TC_normal_reg_emergency_services_fallback error ------[0m
Waiting for packet dumper to finish... 0 (prev_count=-1, count=224) Waiting for packet dumper to finish... 1 (prev_count=224, count=4788) MTC@34589de6d3da: External command `https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/ws/src/osmo-ttcn3-hacks/ttcn3-tcpdump-stop.sh C5G_Tests.TC_normal_reg_emergency_services_fallback error' was executed successfully (exit status: 0). MTC@34589de6d3da: Starting external command `https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/ws/src/osmo-ttcn3-hacks/ttcn3-tcpdump-start.sh C5G_Tests.TC_emergency_registration'. ------ C5G_Tests.TC_emergency_registration ------ (13:47:05) load average: 3.51, 2.79, 2.35 /usr/bin/dumpcap -q -s 1520 -n -i any -w "https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/297/artifact/logs/testsuite/C5G_Tests.TC_emergency_registration.pcap" >https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/297/artifact/logs/testsuite/C5G_Tests.TC_emergency_registration.pcap.stdout 2>/tmp/cmderr & Waiting for packet dumper to start... 0 MTC@34589de6d3da: External command `https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/ws/src/osmo-ttcn3-hacks/ttcn3-tcpdump-start.sh C5G_Tests.TC_emergency_registration' was executed successfully (exit status: 0). MTC@34589de6d3da: Test case TC_emergency_registration started. GTP1U_EM(865)@34589de6d3da: Warning: sizes of 'struct sctp_event_subscribe': compile-time 14, kernel: 14 GTP1U_EM(865)@34589de6d3da: 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(865)@34589de6d3da: Dynamic test case error: testcase.stop GTP1U_EM(865)@34589de6d3da: setverdict(error): fail -> error GTP1U_EM(865)@34589de6d3da: Final verdict of PTC: error TC_emergency_registration-NGAP0(866)@34589de6d3da: Warning: sizes of 'struct sctp_event_subscribe': compile-time 14, kernel: 14 [32m09/05 13:47:06.471[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/05 13:47:06.471[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_ACCEPT (../src/amf/amf-sm.c:83) [32m09/05 13:47:06.471[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2 accepted[127.0.0.202] in master_sm module (../src/amf/amf-sm.c:818) [32m09/05 13:47:06.476[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_initial(): ENTRY (../src/amf/ngap-sm.c:28) [32m09/05 13:47:06.476[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_operational(): ENTRY (../src/amf/ngap-sm.c:55) [32m09/05 13:47:06.476[0m: [[33mamf[0m] [1;32mINFO[0m: [Added] Number of gNBs is now 1 (../src/amf/context.c:1277) [32m09/05 13:47:06.476[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/05 13:47:06.476[0m: [[33mamf[0m] [1;37mDEBUG[0m: SCTP_COMM_UP (../src/amf/ngap-sctp.c:161) [32m09/05 13:47:06.476[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_SCTP_COMM_UP (../src/amf/amf-sm.c:83) [32m09/05 13:47:06.476[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2[127.0.0.202] max_num_of_ostreams : 30 (../src/amf/amf-sm.c:865) TC_emergency_registration-NGAP1(867)@34589de6d3da: Warning: sizes of 'struct sctp_event_subscribe': compile-time 14, kernel: 14 [32m09/05 13:47:06.480[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/05 13:47:06.480[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_ACCEPT (../src/amf/amf-sm.c:83) [32m09/05 13:47:06.480[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2 accepted[127.0.0.202] in master_sm module (../src/amf/amf-sm.c:818) [32m09/05 13:47:06.484[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_initial(): ENTRY (../src/amf/ngap-sm.c:28) [32m09/05 13:47:06.484[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_operational(): ENTRY (../src/amf/ngap-sm.c:55) [32m09/05 13:47:06.484[0m: [[33mamf[0m] [1;32mINFO[0m: [Added] Number of gNBs is now 2 (../src/amf/context.c:1277) [32m09/05 13:47:06.484[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/05 13:47:06.484[0m: [[33mamf[0m] [1;37mDEBUG[0m: SCTP_COMM_UP (../src/amf/ngap-sctp.c:161) [32m09/05 13:47:06.484[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_SCTP_COMM_UP (../src/amf/amf-sm.c:83) [32m09/05 13:47:06.484[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2[127.0.0.202] max_num_of_ostreams : 30 (../src/amf/amf-sm.c:865) [32m09/05 13:47:06.488[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_MESSAGE (../src/amf/amf-sm.c:83) [32m09/05 13:47:06.488[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_operational(): AMF_EVENT_NGAP_MESSAGE (../src/amf/ngap-sm.c:55) [32m09/05 13:47:06.488[0m: [[33mamf[0m] [1;37mDEBUG[0m: NGSetupRequest (../src/amf/ngap-handler.c:288) [32m09/05 13:47:06.488[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/05 13:47:06.488[0m: [[33mamf[0m] [1;37mDEBUG[0m: PLMN_ID[MCC:999 MNC:70] (../src/amf/ngap-handler.c:391) [32m09/05 13:47:06.488[0m: [[33mamf[0m] [1;37mDEBUG[0m: PagingDRX[0] (../src/amf/ngap-handler.c:395) [32m09/05 13:47:06.488[0m: [[33mamf[0m] [1;37mDEBUG[0m: TAC[1] (../src/amf/ngap-handler.c:439) [32m09/05 13:47:06.488[0m: [[33mamf[0m] [1;37mDEBUG[0m: PLMN_ID[MCC:999 MNC:70] (../src/amf/ngap-handler.c:501) [32m09/05 13:47:06.488[0m: [[33mamf[0m] [1;37mDEBUG[0m: S_NSSAI[SST:1 SD:0xffffff] (../src/amf/ngap-handler.c:562) [32m09/05 13:47:06.488[0m: [[33mamf[0m] [1;37mDEBUG[0m: TAC[1] (../src/amf/ngap-handler.c:72) [32m09/05 13:47:06.488[0m: [[33mamf[0m] [1;37mDEBUG[0m: PLMN_ID[MCC:999 MNC:70] (../src/amf/ngap-handler.c:73) [32m09/05 13:47:06.488[0m: [[33mamf[0m] [1;37mDEBUG[0m: SERVED_TAI_INDEX[0] (../src/amf/ngap-handler.c:78) [32m09/05 13:47:06.488[0m: [[33mamf[0m] [1;37mDEBUG[0m: PLMN_ID[MCC:999 MNC:70] (../src/amf/ngap-handler.c:100) [32m09/05 13:47:06.488[0m: [[33mamf[0m] [1;37mDEBUG[0m: S_NSSAI[SST:1 SD:0xffffff] (../src/amf/ngap-handler.c:105) [32m09/05 13:47:06.488[0m: [[33mamf[0m] [1;37mDEBUG[0m: NG-Setup response (../src/amf/ngap-path.c:356) [32m09/05 13:47:06.488[0m: [[33mamf[0m] [1;37mDEBUG[0m: NGSetupResponse (../src/amf/ngap-build.c:91) [32m09/05 13:47:06.488[0m: [[33mamf[0m] [1;37mDEBUG[0m: IP[127.0.0.202] RAN_ID[0] (../src/amf/ngap-path.c:64) MTC@34589de6d3da: setverdict(pass): none -> pass MTC@34589de6d3da: Dynamic test case error: Error message was received from MC: The connect operation refers to test component with component reference 865, which has already terminated. MTC@34589de6d3da: setverdict(error): pass -> error [32m09/05 13:47:06.496[0m: [[33mamf[0m] [1;37mDEBUG[0m: SCTP_SHUTDOWN_EVENT:[T:32773, F:0x0, L:12] (../src/amf/ngap-sctp.c:193) [32m09/05 13:47:06.496[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_CONNREFUSED (../src/amf/amf-sm.c:83) [32m09/05 13:47:06.496[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2[127.0.0.202] connection refused!!! (../src/amf/amf-sm.c:878) [32m09/05 13:47:06.496[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_operational(): EXIT (../src/amf/ngap-sm.c:55) [32m09/05 13:47:06.496[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_final(): EXIT (../src/amf/ngap-sm.c:37) TC_emergency_registration0(868)@34589de6d3da: Final verdict of PTC: none [32m09/05 13:47:06.496[0m: [[33mamf[0m] [1;32mINFO[0m: [Removed] Number of gNBs is now 1 (../src/amf/context.c:1305) [32m09/05 13:47:06.496[0m: [[33mamf[0m] [1;37mDEBUG[0m: SCTP_SHUTDOWN_EVENT:[T:32773, F:0x0, L:12] (../src/amf/ngap-sctp.c:193) [32m09/05 13:47:06.496[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_CONNREFUSED (../src/amf/amf-sm.c:83) [32m09/05 13:47:06.496[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2[127.0.0.202] connection refused!!! (../src/amf/amf-sm.c:878) [32m09/05 13:47:06.496[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_operational(): EXIT (../src/amf/ngap-sm.c:55) [32m09/05 13:47:06.496[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_final(): EXIT (../src/amf/ngap-sm.c:37) [32m09/05 13:47:06.496[0m: [[33mamf[0m] [1;32mINFO[0m: [Removed] Number of gNBs is now 0 (../src/amf/context.c:1305) TC_emergency_registration-NGAP1(867)@34589de6d3da: Final verdict of PTC: none TC_emergency_registration-NGAP0(866)@34589de6d3da: Final verdict of PTC: none MTC@34589de6d3da: Setting final verdict of the test case. MTC@34589de6d3da: Local verdict of MTC: error MTC@34589de6d3da: Local verdict of PTC GTP1U_EM(865): error (error -> error) MTC@34589de6d3da: Local verdict of PTC TC_emergency_registration-NGAP0(866): none (error -> error) MTC@34589de6d3da: Local verdict of PTC TC_emergency_registration-NGAP1(867): none (error -> error) MTC@34589de6d3da: Local verdict of PTC TC_emergency_registration0(868): none (error -> error) MTC@34589de6d3da: Test case TC_emergency_registration finished. Verdict: error MTC@34589de6d3da: Starting external command `https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/ws/src/osmo-ttcn3-hacks/ttcn3-tcpdump-stop.sh C5G_Tests.TC_emergency_registration error'. (13:47:06) load average: 3.51, 2.79, 2.35 [1;31m------ C5G_Tests.TC_emergency_registration error ------[0m
Waiting for packet dumper to finish... 0 (prev_count=-1, count=440) Waiting for packet dumper to finish... 1 (prev_count=440, count=4676) MTC@34589de6d3da: External command `https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/ws/src/osmo-ttcn3-hacks/ttcn3-tcpdump-stop.sh C5G_Tests.TC_emergency_registration error' was executed successfully (exit status: 0). MTC@34589de6d3da: Starting external command `https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/ws/src/osmo-ttcn3-hacks/ttcn3-tcpdump-start.sh C5G_Tests.TC_handover_inter_ngran_n2'. ------ C5G_Tests.TC_handover_inter_ngran_n2 ------ (13:47:08) load average: 3.31, 2.76, 2.34 /usr/bin/dumpcap -q -s 1520 -n -i any -w "https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/297/artifact/logs/testsuite/C5G_Tests.TC_handover_inter_ngran_n2.pcap" >https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/297/artifact/logs/testsuite/C5G_Tests.TC_handover_inter_ngran_n2.pcap.stdout 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-latest-20260905-1343-7b528284-1'][0m testenv-5gc-osmocom-latest-20260905-1343-7b528284-1 [0;94m[testenv] Skipping clean up scripts, podman container has already stopped[0m [1;34m[testenv] Stopping testsuite (725954)[0m Error: container has already been removed Error: container has already been removed Error: container has already been removed Error: container has already been removed 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-latest/297/artifa... [0m + RC=1 + [ 1 = 0 ] + uptime + grep --color=always -o load.* [01;31m[Kload average: 3.31, 2.76, 2.34[m[K + exit 1 Build step 'Execute shell' marked build as failure Recording test results [Checks API] No suitable checks publisher found.