See https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs/396/display/redirect
Changes:
------------------------------------------ [...truncated 249.78 MiB...] [32m06/23 09:32:46.362[0m: [[33msbi[0m] [1;37mDEBUG[0m: ogs_sbi_nf_state_registered(): OGS_EVENT_NAME_SBI_TIMER (../lib/sbi/nf-sm.c:358) [32m06/23 09:32:46.362[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] http://127.0.0.200:7777/nnrf-nfm/v1/nf-instances/12d7574e-6ee6-41f1-b79d-7b2... (../lib/sbi/client.c:797) [32m06/23 09:32:46.362[0m: [[33msbi[0m] [1;37mDEBUG[0m: SENDING...[101] (../lib/sbi/client.c:546) [32m06/23 09:32:46.362[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":25}] (../lib/sbi/client.c:548) [32m06/23 09:32:46.362[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM added [5759] (../lib/sbi/nghttp2-server.c:1737) [32m06/23 09:32:46.362[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] /nnrf-nfm/v1/nf-instances/12d7574e-6ee6-41f1-b79d-7b207f810b0f (../lib/sbi/nghttp2-server.c:1308) [32m06/23 09:32:46.362[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED: 101 (../lib/sbi/nghttp2-server.c:1312) [32m06/23 09:32:46.362[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":25}] (../lib/sbi/nghttp2-server.c:1314) [32m06/23 09:32:46.362[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] http://127.0.0.10:7777/nnrf-nfm/v1/nf-instances/12d7574e-6ee6-41f1-b79d-7b20... (../lib/sbi/client.c:797) [32m06/23 09:32:46.362[0m: [[33msbi[0m] [1;37mDEBUG[0m: SENDING...[101] (../lib/sbi/client.c:546) [32m06/23 09:32:46.362[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":25}] (../lib/sbi/client.c:548) [32m06/23 09:32:46.362[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM added [587] (../lib/sbi/nghttp2-server.c:1737) [32m06/23 09:32:46.362[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] /nnrf-nfm/v1/nf-instances/12d7574e-6ee6-41f1-b79d-7b207f810b0f (../lib/sbi/nghttp2-server.c:1308) [32m06/23 09:32:46.362[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED: 101 (../lib/sbi/nghttp2-server.c:1312) [32m06/23 09:32:46.362[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":25}] (../lib/sbi/nghttp2-server.c:1314) [32m06/23 09:32:46.362[0m: [[33mnrf[0m] [1;37mDEBUG[0m: nrf_state_operational(): OGS_EVENT_NAME_SBI_SERVER (../src/nrf/nrf-sm.c:54) [32m06/23 09:32:46.362[0m: [[33mnrf[0m] [1;37mDEBUG[0m: nrf_nf_state_registered(): OGS_EVENT_NAME_SBI_SERVER (../src/nrf/nf-sm.c:201) [32m06/23 09:32:46.362[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [204] (../lib/sbi/nghttp2-server.c:702) [32m06/23 09:32:46.362[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [587] (../lib/sbi/nghttp2-server.c:1419) 0000: 00000301 05000002 4b89f9be ........K... [32m06/23 09:32:46.362[0m: [[33msbi[0m] [1;37mDEBUG[0m: [204:PATCH] http://127.0.0.10:7777/nnrf-nfm/v1/nf-instances/12d7574e-6ee6-41f1-b79d-7b20... (../lib/sbi/client.c:744) [32m06/23 09:32:46.362[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755) [32m06/23 09:32:46.362[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [204] (../lib/sbi/nghttp2-server.c:702) [32m06/23 09:32:46.362[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [5759] (../lib/sbi/nghttp2-server.c:1419) 0000: 00001a01 05000016 7f89f861 96df697e ...........a..i~ 0010: 94132a65 b6a50401 38a01fb8 c8ae34e2 ..*e....8.....4. 0020: 98b46f ..o [32m06/23 09:32:46.362[0m: [[33msbi[0m] [1;37mDEBUG[0m: [204:PATCH] http://127.0.0.200:7777/nnrf-nfm/v1/nf-instances/12d7574e-6ee6-41f1-b79d-7b2... (../lib/sbi/client.c:744) [32m06/23 09:32:46.362[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755) [32m06/23 09:32:46.362[0m: [[33mpcf[0m] [1;37mDEBUG[0m: pcf_state_operational(): OGS_EVENT_NAME_SBI_CLIENT (../src/pcf/pcf-sm.c:64) [32m06/23 09:32:46.362[0m: [[33msbi[0m] [1;37mDEBUG[0m: ogs_sbi_nf_state_registered(): OGS_EVENT_NAME_SBI_CLIENT (../lib/sbi/nf-sm.c:358) [32m06/23 09:32:46.402[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): OGS_EVENT_NAME_SBI_TIMER (../src/amf/amf-sm.c:83) [32m06/23 09:32:46.402[0m: [[33msbi[0m] [1;37mDEBUG[0m: ogs_sbi_nf_state_registered(): OGS_EVENT_NAME_SBI_TIMER (../lib/sbi/nf-sm.c:358) [32m06/23 09:32:46.402[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] http://127.0.0.200:7777/nnrf-nfm/v1/nf-instances/12e14fe2-6ee6-41f1-a2a1-5fd... (../lib/sbi/client.c:797) [32m06/23 09:32:46.402[0m: [[33msbi[0m] [1;37mDEBUG[0m: SENDING...[100] (../lib/sbi/client.c:546) [32m06/23 09:32:46.402[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":0}] (../lib/sbi/client.c:548) [32m06/23 09:32:46.402[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM added [23909] (../lib/sbi/nghttp2-server.c:1737) [32m06/23 09:32:46.402[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] /nnrf-nfm/v1/nf-instances/12e14fe2-6ee6-41f1-a2a1-5fd55ad3e85e (../lib/sbi/nghttp2-server.c:1308) [32m06/23 09:32:46.402[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED: 100 (../lib/sbi/nghttp2-server.c:1312) [32m06/23 09:32:46.402[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":0}] (../lib/sbi/nghttp2-server.c:1314) [32m06/23 09:32:46.402[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] http://127.0.0.10:7777/nnrf-nfm/v1/nf-instances/12e14fe2-6ee6-41f1-a2a1-5fd5... (../lib/sbi/client.c:797) [32m06/23 09:32:46.402[0m: [[33msbi[0m] [1;37mDEBUG[0m: SENDING...[100] (../lib/sbi/client.c:546) [32m06/23 09:32:46.402[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":0}] (../lib/sbi/client.c:548) [32m06/23 09:32:46.402[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM added [589] (../lib/sbi/nghttp2-server.c:1737) [32m06/23 09:32:46.402[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] /nnrf-nfm/v1/nf-instances/12e14fe2-6ee6-41f1-a2a1-5fd55ad3e85e (../lib/sbi/nghttp2-server.c:1308) [32m06/23 09:32:46.402[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED: 100 (../lib/sbi/nghttp2-server.c:1312) [32m06/23 09:32:46.402[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":0}] (../lib/sbi/nghttp2-server.c:1314) [32m06/23 09:32:46.402[0m: [[33mnrf[0m] [1;37mDEBUG[0m: nrf_state_operational(): OGS_EVENT_NAME_SBI_SERVER (../src/nrf/nrf-sm.c:54) [32m06/23 09:32:46.402[0m: [[33mnrf[0m] [1;37mDEBUG[0m: nrf_nf_state_registered(): OGS_EVENT_NAME_SBI_SERVER (../src/nrf/nf-sm.c:201) [32m06/23 09:32:46.402[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [204] (../lib/sbi/nghttp2-server.c:702) [32m06/23 09:32:46.402[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [589] (../lib/sbi/nghttp2-server.c:1419) 0000: 00000301 05000002 4d89f9be ........M... [32m06/23 09:32:46.402[0m: [[33msbi[0m] [1;37mDEBUG[0m: [204:PATCH] http://127.0.0.10:7777/nnrf-nfm/v1/nf-instances/12e14fe2-6ee6-41f1-a2a1-5fd5... (../lib/sbi/client.c:744) [32m06/23 09:32:46.402[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755) [32m06/23 09:32:46.402[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [204] (../lib/sbi/nghttp2-server.c:702) [32m06/23 09:32:46.402[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [23909] (../lib/sbi/nghttp2-server.c:1419) 0000: 00001a01 0500005d 6589c461 96df697e .......]e..a..i~ 0010: 94132a65 b6a50401 38a01fb8 c8ae34e2 ..*e....8.....4. 0020: 98b46f ..o [32m06/23 09:32:46.402[0m: [[33msbi[0m] [1;37mDEBUG[0m: [204:PATCH] http://127.0.0.200:7777/nnrf-nfm/v1/nf-instances/12e14fe2-6ee6-41f1-a2a1-5fd... (../lib/sbi/client.c:744) [32m06/23 09:32:46.402[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755) [32m06/23 09:32:46.402[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): OGS_EVENT_NAME_SBI_CLIENT (../src/amf/amf-sm.c:83) [32m06/23 09:32:46.402[0m: [[33msbi[0m] [1;37mDEBUG[0m: ogs_sbi_nf_state_registered(): OGS_EVENT_NAME_SBI_CLIENT (../lib/sbi/nf-sm.c:358) MTC@6a13cb020152: External command `https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs/ws/ttcn3-tcpdump-start.sh C5G_Tests.TC_normal_reg_emergency_services' was executed successfully (exit status: 0). MTC@6a13cb020152: Test case TC_normal_reg_emergency_services started. GTP1U_EM(857)@6a13cb020152: Warning: sizes of 'struct sctp_event_subscribe': compile-time 14, kernel: 14 GTP1U_EM(857)@6a13cb020152: 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(857)@6a13cb020152: Dynamic test case error: testcase.stop GTP1U_EM(857)@6a13cb020152: setverdict(error): fail -> error GTP1U_EM(857)@6a13cb020152: Final verdict of PTC: error TC_normal_reg_emergency_services-NGAP0(858)@6a13cb020152: Warning: sizes of 'struct sctp_event_subscribe': compile-time 14, kernel: 14 [32m06/23 09:32:47.277[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2 accepted[127.0.0.202]:50000 in ng-path module (../src/amf/ngap-sctp.c:113) [32m06/23 09:32:47.277[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_ACCEPT (../src/amf/amf-sm.c:83) [32m06/23 09:32:47.277[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2 accepted[127.0.0.202] in master_sm module (../src/amf/amf-sm.c:818) [32m06/23 09:32:47.283[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_initial(): ENTRY (../src/amf/ngap-sm.c:28) [32m06/23 09:32:47.283[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_operational(): ENTRY (../src/amf/ngap-sm.c:55) [32m06/23 09:32:47.283[0m: [[33mamf[0m] [1;32mINFO[0m: [Added] Number of gNBs is now 1 (../src/amf/context.c:1277) [32m06/23 09:32:47.283[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) [32m06/23 09:32:47.283[0m: [[33mamf[0m] [1;37mDEBUG[0m: SCTP_COMM_UP (../src/amf/ngap-sctp.c:161) [32m06/23 09:32:47.283[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_SCTP_COMM_UP (../src/amf/amf-sm.c:83) [32m06/23 09:32:47.283[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-NGAP1(859)@6a13cb020152: Warning: sizes of 'struct sctp_event_subscribe': compile-time 14, kernel: 14 [32m06/23 09:32:47.287[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2 accepted[127.0.0.202]:50001 in ng-path module (../src/amf/ngap-sctp.c:113) [32m06/23 09:32:47.287[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_ACCEPT (../src/amf/amf-sm.c:83) [32m06/23 09:32:47.287[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2 accepted[127.0.0.202] in master_sm module (../src/amf/amf-sm.c:818) [32m06/23 09:32:47.292[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_initial(): ENTRY (../src/amf/ngap-sm.c:28) [32m06/23 09:32:47.292[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_operational(): ENTRY (../src/amf/ngap-sm.c:55) [32m06/23 09:32:47.292[0m: [[33mamf[0m] [1;32mINFO[0m: [Added] Number of gNBs is now 2 (../src/amf/context.c:1277) [32m06/23 09:32:47.292[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) [32m06/23 09:32:47.292[0m: [[33mamf[0m] [1;37mDEBUG[0m: SCTP_COMM_UP (../src/amf/ngap-sctp.c:161) [32m06/23 09:32:47.292[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_MESSAGE (../src/amf/amf-sm.c:83) [32m06/23 09:32:47.292[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_operational(): AMF_EVENT_NGAP_MESSAGE (../src/amf/ngap-sm.c:55) [32m06/23 09:32:47.292[0m: [[33mamf[0m] [1;37mDEBUG[0m: NGSetupRequest (../src/amf/ngap-handler.c:288) [32m06/23 09:32:47.292[0m: [[33mamf[0m] [1;37mDEBUG[0m: IP[127.0.0.202] GNB_ID[0x0] GNB_ID_LENGTH[22] (../src/amf/ngap-handler.c:386) [32m06/23 09:32:47.292[0m: [[33mamf[0m] [1;37mDEBUG[0m: PLMN_ID[MCC:999 MNC:70] (../src/amf/ngap-handler.c:391) [32m06/23 09:32:47.292[0m: [[33mamf[0m] [1;37mDEBUG[0m: PagingDRX[0] (../src/amf/ngap-handler.c:395) [32m06/23 09:32:47.292[0m: [[33mamf[0m] [1;37mDEBUG[0m: TAC[1] (../src/amf/ngap-handler.c:439) [32m06/23 09:32:47.292[0m: [[33mamf[0m] [1;37mDEBUG[0m: PLMN_ID[MCC:999 MNC:70] (../src/amf/ngap-handler.c:501) [32m06/23 09:32:47.292[0m: [[33mamf[0m] [1;37mDEBUG[0m: S_NSSAI[SST:1 SD:0xffffff] (../src/amf/ngap-handler.c:562) [32m06/23 09:32:47.292[0m: [[33mamf[0m] [1;37mDEBUG[0m: TAC[1] (../src/amf/ngap-handler.c:72) [32m06/23 09:32:47.292[0m: [[33mamf[0m] [1;37mDEBUG[0m: PLMN_ID[MCC:999 MNC:70] (../src/amf/ngap-handler.c:73) [32m06/23 09:32:47.292[0m: [[33mamf[0m] [1;37mDEBUG[0m: SERVED_TAI_INDEX[0] (../src/amf/ngap-handler.c:78) [32m06/23 09:32:47.292[0m: [[33mamf[0m] [1;37mDEBUG[0m: PLMN_ID[MCC:999 MNC:70] (../src/amf/ngap-handler.c:100) [32m06/23 09:32:47.292[0m: [[33mamf[0m] [1;37mDEBUG[0m: S_NSSAI[SST:1 SD:0xffffff] (../src/amf/ngap-handler.c:105) [32m06/23 09:32:47.292[0m: [[33mamf[0m] [1;37mDEBUG[0m: NG-Setup response (../src/amf/ngap-path.c:356) [32m06/23 09:32:47.292[0m: [[33mamf[0m] [1;37mDEBUG[0m: NGSetupResponse (../src/amf/ngap-build.c:91) [32m06/23 09:32:47.292[0m: [[33mamf[0m] [1;37mDEBUG[0m: IP[127.0.0.202] RAN_ID[0] (../src/amf/ngap-path.c:64) [32m06/23 09:32:47.292[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_SCTP_COMM_UP (../src/amf/amf-sm.c:83) [32m06/23 09:32:47.292[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2[127.0.0.202] max_num_of_ostreams : 30 (../src/amf/amf-sm.c:865) MTC@6a13cb020152: setverdict(pass): none -> pass MTC@6a13cb020152: Dynamic test case error: Error message was received from MC: The connect operation refers to test component with component reference 857, which has already terminated. MTC@6a13cb020152: setverdict(error): pass -> error [32m06/23 09:32:47.300[0m: [[33mamf[0m] [1;37mDEBUG[0m: SCTP_SHUTDOWN_EVENT:[T:32773, F:0x0, L:12] (../src/amf/ngap-sctp.c:193) [32m06/23 09:32:47.300[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_CONNREFUSED (../src/amf/amf-sm.c:83) [32m06/23 09:32:47.300[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2[127.0.0.202] connection refused!!! (../src/amf/amf-sm.c:878) [32m06/23 09:32:47.300[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_operational(): EXIT (../src/amf/ngap-sm.c:55) [32m06/23 09:32:47.300[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_final(): EXIT (../src/amf/ngap-sm.c:37) [32m06/23 09:32:47.300[0m: [[33mamf[0m] [1;32mINFO[0m: [Removed] Number of gNBs is now 1 (../src/amf/context.c:1305) TC_normal_reg_emergency_services0(860)@6a13cb020152: Final verdict of PTC: none TC_normal_reg_emergency_services-NGAP0(858)@6a13cb020152: Final verdict of PTC: none [32m06/23 09:32:47.300[0m: [[33mamf[0m] [1;37mDEBUG[0m: SCTP_SHUTDOWN_EVENT:[T:32773, F:0x0, L:12] (../src/amf/ngap-sctp.c:193) [32m06/23 09:32:47.300[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_CONNREFUSED (../src/amf/amf-sm.c:83) [32m06/23 09:32:47.300[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2[127.0.0.202] connection refused!!! (../src/amf/amf-sm.c:878) [32m06/23 09:32:47.300[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_operational(): EXIT (../src/amf/ngap-sm.c:55) [32m06/23 09:32:47.300[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_final(): EXIT (../src/amf/ngap-sm.c:37) [32m06/23 09:32:47.300[0m: [[33mamf[0m] [1;32mINFO[0m: [Removed] Number of gNBs is now 0 (../src/amf/context.c:1305) TC_normal_reg_emergency_services-NGAP1(859)@6a13cb020152: Final verdict of PTC: none MTC@6a13cb020152: Setting final verdict of the test case. MTC@6a13cb020152: Local verdict of MTC: error MTC@6a13cb020152: Local verdict of PTC GTP1U_EM(857): error (error -> error) MTC@6a13cb020152: Local verdict of PTC TC_normal_reg_emergency_services-NGAP0(858): none (error -> error) MTC@6a13cb020152: Local verdict of PTC TC_normal_reg_emergency_services-NGAP1(859): none (error -> error) MTC@6a13cb020152: Local verdict of PTC TC_normal_reg_emergency_services0(860): none (error -> error) MTC@6a13cb020152: Test case TC_normal_reg_emergency_services finished. Verdict: error MTC@6a13cb020152: Starting external command `https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs/ws/ttcn3-tcpdump-stop.sh C5G_Tests.TC_normal_reg_emergency_services error'. (09:32:47) load average: 1.04, 4.60, 7.69 [1;31m------ C5G_Tests.TC_normal_reg_emergency_services error ------[0m
Waiting for packet dumper to finish... 0 (prev_count=-1, count=2364) Waiting for packet dumper to finish... 1 (prev_count=2364, count=6860) [32m06/23 09:32:49.146[0m: [[33mdiam[0m] [1;37mDEBUG[0m: pid:ConnTo:pcrf.localdomain in md_hook_cb_tree@dbg_msg_dumps.c:150: CONNECT FAILED to pcrf.localdomain: All connection attempts failed, will retry later ((null):0) MTC@6a13cb020152: External command `https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs/ws/ttcn3-tcpdump-stop.sh C5G_Tests.TC_normal_reg_emergency_services error' was executed successfully (exit status: 0). MTC@6a13cb020152: Starting external command `https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs/ws/ttcn3-tcpdump-start.sh C5G_Tests.TC_normal_reg_emergency_services_fallback'. ------ C5G_Tests.TC_normal_reg_emergency_services_fallback ------ (09:32:49) load average: 0.96, 4.52, 7.64 /usr/bin/dumpcap -q -s 1520 -n -i any -w "https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs/396/artifact/logs/testsuite/C5G_Tests.TC_normal_reg_emergency_services_fallback.pcap" >https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs/396/artifact/logs/testsuite/C5G_Tests.TC_normal_reg_emergency_services_fallback.pcap.stdout 2>/tmp/cmderr & Waiting for packet dumper to start... 0 MTC@6a13cb020152: External command `https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs/ws/ttcn3-tcpdump-start.sh C5G_Tests.TC_normal_reg_emergency_services_fallback' was executed successfully (exit status: 0). MTC@6a13cb020152: Test case TC_normal_reg_emergency_services_fallback started. GTP1U_EM(861)@6a13cb020152: Warning: sizes of 'struct sctp_event_subscribe': compile-time 14, kernel: 14 GTP1U_EM(861)@6a13cb020152: 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)@6a13cb020152: Dynamic test case error: testcase.stop GTP1U_EM(861)@6a13cb020152: setverdict(error): fail -> error GTP1U_EM(861)@6a13cb020152: Final verdict of PTC: error [32m06/23 09:32:50.419[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2 accepted[127.0.0.202]:50000 in ng-path module (../src/amf/ngap-sctp.c:113) [32m06/23 09:32:50.419[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_ACCEPT (../src/amf/amf-sm.c:83) [32m06/23 09:32:50.419[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2 accepted[127.0.0.202] in master_sm module (../src/amf/amf-sm.c:818) TC_normal_reg_emergency_services_fallback-NGAP0(862)@6a13cb020152: Warning: sizes of 'struct sctp_event_subscribe': compile-time 14, kernel: 14 [32m06/23 09:32:50.424[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_initial(): ENTRY (../src/amf/ngap-sm.c:28) [32m06/23 09:32:50.424[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_operational(): ENTRY (../src/amf/ngap-sm.c:55) [32m06/23 09:32:50.424[0m: [[33mamf[0m] [1;32mINFO[0m: [Added] Number of gNBs is now 1 (../src/amf/context.c:1277) [32m06/23 09:32:50.424[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) [32m06/23 09:32:50.424[0m: [[33mamf[0m] [1;37mDEBUG[0m: SCTP_COMM_UP (../src/amf/ngap-sctp.c:161) [32m06/23 09:32:50.424[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_SCTP_COMM_UP (../src/amf/amf-sm.c:83) [32m06/23 09:32:50.424[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)@6a13cb020152: Warning: sizes of 'struct sctp_event_subscribe': compile-time 14, kernel: 14 [32m06/23 09:32:50.425[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2 accepted[127.0.0.202]:50001 in ng-path module (../src/amf/ngap-sctp.c:113) [32m06/23 09:32:50.425[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_ACCEPT (../src/amf/amf-sm.c:83) [32m06/23 09:32:50.425[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2 accepted[127.0.0.202] in master_sm module (../src/amf/amf-sm.c:818) [32m06/23 09:32:50.430[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_initial(): ENTRY (../src/amf/ngap-sm.c:28) [32m06/23 09:32:50.430[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_operational(): ENTRY (../src/amf/ngap-sm.c:55) [32m06/23 09:32:50.430[0m: [[33mamf[0m] [1;32mINFO[0m: [Added] Number of gNBs is now 2 (../src/amf/context.c:1277) [32m06/23 09:32:50.430[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) [32m06/23 09:32:50.430[0m: [[33mamf[0m] [1;37mDEBUG[0m: SCTP_COMM_UP (../src/amf/ngap-sctp.c:161) [32m06/23 09:32:50.430[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_MESSAGE (../src/amf/amf-sm.c:83) [32m06/23 09:32:50.430[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_operational(): AMF_EVENT_NGAP_MESSAGE (../src/amf/ngap-sm.c:55) [32m06/23 09:32:50.430[0m: [[33mamf[0m] [1;37mDEBUG[0m: NGSetupRequest (../src/amf/ngap-handler.c:288) [32m06/23 09:32:50.430[0m: [[33mamf[0m] [1;37mDEBUG[0m: IP[127.0.0.202] GNB_ID[0x0] GNB_ID_LENGTH[22] (../src/amf/ngap-handler.c:386) [32m06/23 09:32:50.430[0m: [[33mamf[0m] [1;37mDEBUG[0m: PLMN_ID[MCC:999 MNC:70] (../src/amf/ngap-handler.c:391) [32m06/23 09:32:50.430[0m: [[33mamf[0m] [1;37mDEBUG[0m: PagingDRX[0] (../src/amf/ngap-handler.c:395) [32m06/23 09:32:50.430[0m: [[33mamf[0m] [1;37mDEBUG[0m: TAC[1] (../src/amf/ngap-handler.c:439) [32m06/23 09:32:50.430[0m: [[33mamf[0m] [1;37mDEBUG[0m: PLMN_ID[MCC:999 MNC:70] (../src/amf/ngap-handler.c:501) [32m06/23 09:32:50.430[0m: [[33mamf[0m] [1;37mDEBUG[0m: S_NSSAI[SST:1 SD:0xffffff] (../src/amf/ngap-handler.c:562) [32m06/23 09:32:50.430[0m: [[33mamf[0m] [1;37mDEBUG[0m: TAC[1] (../src/amf/ngap-handler.c:72) [32m06/23 09:32:50.430[0m: [[33mamf[0m] [1;37mDEBUG[0m: PLMN_ID[MCC:999 MNC:70] (../src/amf/ngap-handler.c:73) [32m06/23 09:32:50.430[0m: [[33mamf[0m] [1;37mDEBUG[0m: SERVED_TAI_INDEX[0] (../src/amf/ngap-handler.c:78) [32m06/23 09:32:50.430[0m: [[33mamf[0m] [1;37mDEBUG[0m: PLMN_ID[MCC:999 MNC:70] (../src/amf/ngap-handler.c:100) [32m06/23 09:32:50.430[0m: [[33mamf[0m] [1;37mDEBUG[0m: S_NSSAI[SST:1 SD:0xffffff] (../src/amf/ngap-handler.c:105) [32m06/23 09:32:50.430[0m: [[33mamf[0m] [1;37mDEBUG[0m: NG-Setup response (../src/amf/ngap-path.c:356) [32m06/23 09:32:50.430[0m: [[33mamf[0m] [1;37mDEBUG[0m: NGSetupResponse (../src/amf/ngap-build.c:91) [32m06/23 09:32:50.430[0m: [[33mamf[0m] [1;37mDEBUG[0m: IP[127.0.0.202] RAN_ID[0] (../src/amf/ngap-path.c:64) [32m06/23 09:32:50.430[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_SCTP_COMM_UP (../src/amf/amf-sm.c:83) [32m06/23 09:32:50.430[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2[127.0.0.202] max_num_of_ostreams : 30 (../src/amf/amf-sm.c:865) MTC@6a13cb020152: setverdict(pass): none -> pass MTC@6a13cb020152: 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@6a13cb020152: setverdict(error): pass -> error TC_normal_reg_emergency_services_fallback0(864)@6a13cb020152: Final verdict of PTC: none [32m06/23 09:32:50.437[0m: [[33mamf[0m] [1;37mDEBUG[0m: SCTP_SHUTDOWN_EVENT:[T:32773, F:0x0, L:12] (../src/amf/ngap-sctp.c:193) [32m06/23 09:32:50.437[0m: [[33mamf[0m] [1;37mDEBUG[0m: SCTP_SHUTDOWN_EVENT:[T:32773, F:0x0, L:12] (../src/amf/ngap-sctp.c:193) [32m06/23 09:32:50.437[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_CONNREFUSED (../src/amf/amf-sm.c:83) [32m06/23 09:32:50.437[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2[127.0.0.202] connection refused!!! (../src/amf/amf-sm.c:878) [32m06/23 09:32:50.437[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_operational(): EXIT (../src/amf/ngap-sm.c:55) [32m06/23 09:32:50.437[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_final(): EXIT (../src/amf/ngap-sm.c:37) [32m06/23 09:32:50.437[0m: [[33mamf[0m] [1;32mINFO[0m: [Removed] Number of gNBs is now 1 (../src/amf/context.c:1305) [32m06/23 09:32:50.437[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_CONNREFUSED (../src/amf/amf-sm.c:83) [32m06/23 09:32:50.437[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2[127.0.0.202] connection refused!!! (../src/amf/amf-sm.c:878) [32m06/23 09:32:50.437[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_operational(): EXIT (../src/amf/ngap-sm.c:55) [32m06/23 09:32:50.437[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_final(): EXIT (../src/amf/ngap-sm.c:37) [32m06/23 09:32:50.437[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)@6a13cb020152: Final verdict of PTC: none TC_normal_reg_emergency_services_fallback-NGAP0(862)@6a13cb020152: Final verdict of PTC: none MTC@6a13cb020152: Setting final verdict of the test case. MTC@6a13cb020152: Local verdict of MTC: error MTC@6a13cb020152: Local verdict of PTC GTP1U_EM(861): error (error -> error) MTC@6a13cb020152: Local verdict of PTC TC_normal_reg_emergency_services_fallback-NGAP0(862): none (error -> error) MTC@6a13cb020152: Local verdict of PTC TC_normal_reg_emergency_services_fallback-NGAP1(863): none (error -> error) MTC@6a13cb020152: Local verdict of PTC TC_normal_reg_emergency_services_fallback0(864): none (error -> error) MTC@6a13cb020152: Test case TC_normal_reg_emergency_services_fallback finished. Verdict: error MTC@6a13cb020152: Starting external command `https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs/ws/ttcn3-tcpdump-stop.sh C5G_Tests.TC_normal_reg_emergency_services_fallback error'. (09:32:50) load average: 0.96, 4.52, 7.64 [1;31m------ C5G_Tests.TC_normal_reg_emergency_services_fallback error ------[0m
Waiting for packet dumper to finish... 0 (prev_count=-1, count=208) Waiting for packet dumper to finish... 1 (prev_count=208, count=4772) MTC@6a13cb020152: External command `https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs/ws/ttcn3-tcpdump-stop.sh C5G_Tests.TC_normal_reg_emergency_services_fallback error' was executed successfully (exit status: 0). MTC@6a13cb020152: Starting external command `https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs/ws/ttcn3-tcpdump-start.sh C5G_Tests.TC_emergency_registration'. ------ C5G_Tests.TC_emergency_registration ------ (09:32:52) load average: 0.96, 4.52, 7.64 /usr/bin/dumpcap -q -s 1520 -n -i any -w "https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs/396/artifact/logs/testsuite/C5G_Tests.TC_emergency_registration.pcap" >https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs/396/artifact/logs/testsuite/C5G_Tests.TC_emergency_registration.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-nightly-20260623-0929-61fdcca8-1'][0m testenv-5gc-osmocom-nightly-20260623-0929-61fdcca8-1 [0;94m[testenv] Skipping clean up scripts, podman container has already stopped[0m [1;34m[testenv] Stopping testsuite (198239)[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-5gc-test-ogs/396/artifact/logs... [0m + RC=1 + [ 1 = 0 ] + uptime + grep --color=always -o load.* [01;31m[Kload average: 0.96, 4.52, 7.64[m[K + exit 1 Error: container has already been removed Error: container has already been removed Build step 'Execute shell' marked build as failure Recording test results [Checks API] No suitable checks publisher found.
jenkins-notifications@lists.osmocom.org
-
jenkins＠lists.osmocom.org