See https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/334/display/redirect?page=changes
Changes:
[Oliver Smith] testenv: use proper envtype for testsuite (no -b)
[Oliver Smith] testenv: use proper envtype for --shell
[Pau Espin Pedrol] Document future removal of unneeded osmo-stp.cfg AS dynamic routing-key
------------------------------------------ [...truncated 251.36 MiB...] 0010: 940894d4 44a82009 c502cdc6 9db82029 ....D. ....... ) 0020: 8b46ff .F. [32m10/12 13:47:20.298[0m: [[33msbi[0m] [1;37mDEBUG[0m: [204:PATCH] http://127.0.0.200:7777/nnrf-nfm/v1/nf-instances/f4c5a562-c642-41f1-85f6-61d... (../lib/sbi/client.c:744) [32m10/12 13:47:20.298[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755) [32m10/12 13:47:20.298[0m: [[33mpcf[0m] [1;37mDEBUG[0m: pcf_state_operational(): OGS_EVENT_NAME_SBI_CLIENT (../src/pcf/pcf-sm.c:64) [32m10/12 13:47:20.298[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=4000, count=12776) [32m10/12 13:47:20.560[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): OGS_EVENT_NAME_SBI_TIMER (../src/amf/amf-sm.c:83) [32m10/12 13:47:20.560[0m: [[33msbi[0m] [1;37mDEBUG[0m: ogs_sbi_nf_state_registered(): OGS_EVENT_NAME_SBI_TIMER (../lib/sbi/nf-sm.c:358) [32m10/12 13:47:20.560[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] http://127.0.0.200:7777/nnrf-nfm/v1/nf-instances/f4f4b226-c642-41f1-8dce-695... (../lib/sbi/client.c:797) [32m10/12 13:47:20.560[0m: [[33msbi[0m] [1;37mDEBUG[0m: SENDING...[100] (../lib/sbi/client.c:546) [32m10/12 13:47:20.560[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":0}] (../lib/sbi/client.c:548) [32m10/12 13:47:20.560[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM added [23951] (../lib/sbi/nghttp2-server.c:1737) [32m10/12 13:47:20.560[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] /nnrf-nfm/v1/nf-instances/f4f4b226-c642-41f1-8dce-6950887f2181 (../lib/sbi/nghttp2-server.c:1308) [32m10/12 13:47:20.560[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED: 100 (../lib/sbi/nghttp2-server.c:1312) [32m10/12 13:47:20.560[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":0}] (../lib/sbi/nghttp2-server.c:1314) [32m10/12 13:47:20.560[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] http://127.0.0.10:7777/nnrf-nfm/v1/nf-instances/f4f4b226-c642-41f1-8dce-6950... (../lib/sbi/client.c:797) [32m10/12 13:47:20.560[0m: [[33msbi[0m] [1;37mDEBUG[0m: SENDING...[100] (../lib/sbi/client.c:546) [32m10/12 13:47:20.561[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":0}] (../lib/sbi/client.c:548) [32m10/12 13:47:20.561[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM added [649] (../lib/sbi/nghttp2-server.c:1737) [32m10/12 13:47:20.561[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] /nnrf-nfm/v1/nf-instances/f4f4b226-c642-41f1-8dce-6950887f2181 (../lib/sbi/nghttp2-server.c:1308) [32m10/12 13:47:20.561[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED: 100 (../lib/sbi/nghttp2-server.c:1312) [32m10/12 13:47:20.561[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":0}] (../lib/sbi/nghttp2-server.c:1314) [32m10/12 13:47:20.561[0m: [[33mnrf[0m] [1;37mDEBUG[0m: nrf_state_operational(): OGS_EVENT_NAME_SBI_SERVER (../src/nrf/nrf-sm.c:54) [32m10/12 13:47:20.561[0m: [[33mnrf[0m] [1;37mDEBUG[0m: nrf_nf_state_registered(): OGS_EVENT_NAME_SBI_SERVER (../src/nrf/nf-sm.c:201) [32m10/12 13:47:20.561[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [204] (../lib/sbi/nghttp2-server.c:702) [32m10/12 13:47:20.561[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [649] (../lib/sbi/nghttp2-server.c:1419) 0000: 00000301 05000002 8989c3be ............ [32m10/12 13:47:20.562[0m: [[33msbi[0m] [1;37mDEBUG[0m: [204:PATCH] http://127.0.0.10:7777/nnrf-nfm/v1/nf-instances/f4f4b226-c642-41f1-8dce-6950... (../lib/sbi/client.c:744) [32m10/12 13:47:20.562[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755) [32m10/12 13:47:20.562[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [204] (../lib/sbi/nghttp2-server.c:702) [32m10/12 13:47:20.562[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [23951] (../lib/sbi/nghttp2-server.c:1419) 0000: 00001a01 0500005d 8f89cf61 96d07abe .......]...a..z. 0010: 940894d4 44a82009 c502cdc6 9db82029 ....D. ....... ) 0020: 8b46ff .F. [32m10/12 13:47:20.562[0m: [[33msbi[0m] [1;37mDEBUG[0m: [204:PATCH] http://127.0.0.200:7777/nnrf-nfm/v1/nf-instances/f4f4b226-c642-41f1-8dce-695... (../lib/sbi/client.c:744) [32m10/12 13:47:20.562[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755) [32m10/12 13:47:20.562[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): OGS_EVENT_NAME_SBI_CLIENT (../src/amf/amf-sm.c:83) [32m10/12 13:47:20.562[0m: [[33msbi[0m] [1;37mDEBUG[0m: ogs_sbi_nf_state_registered(): OGS_EVENT_NAME_SBI_CLIENT (../lib/sbi/nf-sm.c:358) [32m10/12 13:47:20.774[0m: [[33msmf[0m] [1;37mDEBUG[0m: smf_state_operational(): SMF_EVT_N4_TIMER (../src/smf/smf-sm.c:94) [32m10/12 13:47:20.774[0m: [[33msmf[0m] [1;37mDEBUG[0m: smf_pfcp_state_associated(): SMF_EVT_N4_TIMER (../src/smf/pfcp-sm.c:182) [32m10/12 13:47:20.774[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2356] LOCAL Create peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:130) [32m10/12 13:47:20.774[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: Heartbeat Request (../lib/pfcp/build.c:28) [32m10/12 13:47:20.775[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2356] LOCAL UPD TX-1 peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:229) [32m10/12 13:47:20.775[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2356] LOCAL Commit peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:498) [32m10/12 13:47:20.774[0m: [[33mupf[0m] [1;37mDEBUG[0m: upf_state_operational(): UPF_EVT_N4_TIMER (../src/upf/upf-sm.c:51) [32m10/12 13:47:20.774[0m: [[33mupf[0m] [1;37mDEBUG[0m: upf_pfcp_state_associated(): UPF_EVT_N4_TIMER (../src/upf/pfcp-sm.c:161) [32m10/12 13:47:20.774[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [245] LOCAL Create peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:130) [32m10/12 13:47:20.774[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: Heartbeat Request (../lib/pfcp/build.c:28) [32m10/12 13:47:20.775[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [245] LOCAL UPD TX-1 peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:229) [32m10/12 13:47:20.775[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [245] LOCAL Commit peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:498) [32m10/12 13:47:20.775[0m: [[33msmf[0m] [1;37mDEBUG[0m: ogs_pfcp_extract_node_id() type [1] pfcp_status [1] node_id [NULL] from [127.0.0.7]:8805 (../src/smf/pfcp-path.c:138) [32m10/12 13:47:20.775[0m: [[33msmf[0m] [1;37mDEBUG[0m: Found PFCP-Node: addr_list [127.0.0.7]:8805 (../src/smf/pfcp-path.c:192) [32m10/12 13:47:20.775[0m: [[33msmf[0m] [1;37mDEBUG[0m: Merged PFCP-Node: addr_list [127.0.0.7]:8805 (../src/smf/pfcp-path.c:198) [32m10/12 13:47:20.775[0m: [[33msmf[0m] [1;37mDEBUG[0m: smf_state_operational(): SMF_EVT_N4_MESSAGE (../src/smf/smf-sm.c:94) [32m10/12 13:47:20.775[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [245] Cannot find new type 1 from PFCP peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:765) [32m10/12 13:47:20.775[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [245] REMOTE Create peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:194) [32m10/12 13:47:20.775[0m: [[33mupf[0m] [1;37mDEBUG[0m: ogs_pfcp_extract_node_id() type [1] pfcp_status [1] node_id [NULL] from [127.0.0.4]:8805 (../src/upf/pfcp-path.c:101) [32m10/12 13:47:20.775[0m: [[33mupf[0m] [1;37mDEBUG[0m: Found PFCP-Node: addr_list [127.0.0.4]:8805 (../src/upf/pfcp-path.c:155) [32m10/12 13:47:20.775[0m: [[33mupf[0m] [1;37mDEBUG[0m: Merged PFCP-Node: addr_list [127.0.0.4]:8805 (../src/upf/pfcp-path.c:161) [32m10/12 13:47:20.775[0m: [[33mupf[0m] [1;37mDEBUG[0m: upf_state_operational(): UPF_EVT_N4_MESSAGE (../src/upf/upf-sm.c:51) [32m10/12 13:47:20.775[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2356] Cannot find new type 1 from PFCP peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:765) [32m10/12 13:47:20.775[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2356] REMOTE Create peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:194) [32m10/12 13:47:20.775[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2356] REMOTE Receive peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:771) [32m10/12 13:47:20.775[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2356] REMOTE UPD RX-1 peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:327) [32m10/12 13:47:20.775[0m: [[33mupf[0m] [1;37mDEBUG[0m: upf_pfcp_state_associated(): UPF_EVT_N4_MESSAGE (../src/upf/pfcp-sm.c:161) [32m10/12 13:47:20.775[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: Heartbeat Response (../lib/pfcp/build.c:56) [32m10/12 13:47:20.775[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [245] REMOTE Receive peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:771) [32m10/12 13:47:20.775[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [245] REMOTE UPD RX-1 peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:327) [32m10/12 13:47:20.775[0m: [[33msmf[0m] [1;37mDEBUG[0m: smf_pfcp_state_associated(): SMF_EVT_N4_MESSAGE (../src/smf/pfcp-sm.c:182) [32m10/12 13:47:20.775[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: Heartbeat Response (../lib/pfcp/build.c:56) [32m10/12 13:47:20.775[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [245] REMOTE UPD TX-2 peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:229) [32m10/12 13:47:20.775[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [245] REMOTE Commit peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:498) [32m10/12 13:47:20.775[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [245] REMOTE Delete peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:829) [32m10/12 13:47:20.775[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2356] REMOTE UPD TX-2 peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:229) [32m10/12 13:47:20.775[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2356] REMOTE Commit peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:498) [32m10/12 13:47:20.775[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2356] REMOTE Delete peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:829) [32m10/12 13:47:20.775[0m: [[33msmf[0m] [1;37mDEBUG[0m: ogs_pfcp_extract_node_id() type [2] pfcp_status [1] node_id [NULL] from [127.0.0.7]:8805 (../src/smf/pfcp-path.c:138) [32m10/12 13:47:20.775[0m: [[33msmf[0m] [1;37mDEBUG[0m: Found PFCP-Node: addr_list [127.0.0.7]:8805 (../src/smf/pfcp-path.c:192) [32m10/12 13:47:20.775[0m: [[33msmf[0m] [1;37mDEBUG[0m: Merged PFCP-Node: addr_list [127.0.0.7]:8805 (../src/smf/pfcp-path.c:198) [32m10/12 13:47:20.775[0m: [[33msmf[0m] [1;37mDEBUG[0m: smf_state_operational(): SMF_EVT_N4_MESSAGE (../src/smf/smf-sm.c:94) [32m10/12 13:47:20.775[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2356] LOCAL Find peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:756) [32m10/12 13:47:20.775[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2356] LOCAL Receive peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:771) [32m10/12 13:47:20.775[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2356] LOCAL UPD RX-2 peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:327) [32m10/12 13:47:20.775[0m: [[33msmf[0m] [1;37mDEBUG[0m: smf_pfcp_state_associated(): SMF_EVT_N4_MESSAGE (../src/smf/pfcp-sm.c:182) [32m10/12 13:47:20.775[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2356] LOCAL Commit peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:498) [32m10/12 13:47:20.775[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2356] LOCAL Delete peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:829) [32m10/12 13:47:20.775[0m: [[33mupf[0m] [1;37mDEBUG[0m: ogs_pfcp_extract_node_id() type [2] pfcp_status [1] node_id [NULL] from [127.0.0.4]:8805 (../src/upf/pfcp-path.c:101) [32m10/12 13:47:20.775[0m: [[33mupf[0m] [1;37mDEBUG[0m: Found PFCP-Node: addr_list [127.0.0.4]:8805 (../src/upf/pfcp-path.c:155) [32m10/12 13:47:20.775[0m: [[33mupf[0m] [1;37mDEBUG[0m: Merged PFCP-Node: addr_list [127.0.0.4]:8805 (../src/upf/pfcp-path.c:161) [32m10/12 13:47:20.775[0m: [[33mupf[0m] [1;37mDEBUG[0m: upf_state_operational(): UPF_EVT_N4_MESSAGE (../src/upf/upf-sm.c:51) [32m10/12 13:47:20.775[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [245] LOCAL Find peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:756) [32m10/12 13:47:20.775[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [245] LOCAL Receive peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:771) [32m10/12 13:47:20.775[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [245] LOCAL UPD RX-2 peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:327) [32m10/12 13:47:20.775[0m: [[33mupf[0m] [1;37mDEBUG[0m: upf_pfcp_state_associated(): UPF_EVT_N4_MESSAGE (../src/upf/pfcp-sm.c:161) [32m10/12 13:47:20.775[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [245] LOCAL Commit peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:498) [32m10/12 13:47:20.775[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [245] LOCAL Delete peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:829) MTC@312ad0d01202: 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_ran_initiated_qos_flow_mobility error' was executed successfully (exit status: 0). MTC@312ad0d01202: 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_secondary_rat_data_usage_report'. ------ C5G_Tests.TC_secondary_rat_data_usage_report ------ (13:47:21) load average: 3.55, 3.48, 2.95 /usr/bin/dumpcap -q -s 1520 -n -i any -w "https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/334/artifact/logs/testsuite/C5G_Tests.TC_secondary_rat_data_usage_report.pcap" >https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/334/artifact/logs/testsuite/C5G_Tests.TC_secondary_rat_data_usage_report.pcap.stdout 2>/tmp/cmderr & Waiting for packet dumper to start... 0 MTC@312ad0d01202: 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_secondary_rat_data_usage_report' was executed successfully (exit status: 0). MTC@312ad0d01202: Test case TC_secondary_rat_data_usage_report started. GTP1U_EM(885)@312ad0d01202: Warning: sizes of 'struct sctp_event_subscribe': compile-time 14, kernel: 14 GTP1U_EM(885)@312ad0d01202: 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(885)@312ad0d01202: Dynamic test case error: testcase.stop GTP1U_EM(885)@312ad0d01202: setverdict(error): fail -> error GTP1U_EM(885)@312ad0d01202: Final verdict of PTC: error TC_secondary_rat_data_usage_report-NGAP0(886)@312ad0d01202: Warning: sizes of 'struct sctp_event_subscribe': compile-time 14, kernel: 14 [32m10/12 13:47:22.584[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2 accepted[127.0.0.202]:50000 in ng-path module (../src/amf/ngap-sctp.c:113) [32m10/12 13:47:22.584[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_ACCEPT (../src/amf/amf-sm.c:83) [32m10/12 13:47:22.584[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2 accepted[127.0.0.202] in master_sm module (../src/amf/amf-sm.c:818) [32m10/12 13:47:22.588[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_initial(): ENTRY (../src/amf/ngap-sm.c:28) [32m10/12 13:47:22.588[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_operational(): ENTRY (../src/amf/ngap-sm.c:55) [32m10/12 13:47:22.588[0m: [[33mamf[0m] [1;32mINFO[0m: [Added] Number of gNBs is now 1 (../src/amf/context.c:1277) [32m10/12 13:47:22.588[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) [32m10/12 13:47:22.588[0m: [[33mamf[0m] [1;37mDEBUG[0m: SCTP_COMM_UP (../src/amf/ngap-sctp.c:161) [32m10/12 13:47:22.588[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_SCTP_COMM_UP (../src/amf/amf-sm.c:83) [32m10/12 13:47:22.588[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2[127.0.0.202] max_num_of_ostreams : 30 (../src/amf/amf-sm.c:865) TC_secondary_rat_data_usage_report-NGAP1(887)@312ad0d01202: Warning: sizes of 'struct sctp_event_subscribe': compile-time 14, kernel: 14 [32m10/12 13:47:22.591[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2 accepted[127.0.0.202]:50001 in ng-path module (../src/amf/ngap-sctp.c:113) [32m10/12 13:47:22.591[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_ACCEPT (../src/amf/amf-sm.c:83) [32m10/12 13:47:22.591[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2 accepted[127.0.0.202] in master_sm module (../src/amf/amf-sm.c:818) [32m10/12 13:47:22.595[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_initial(): ENTRY (../src/amf/ngap-sm.c:28) [32m10/12 13:47:22.595[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_operational(): ENTRY (../src/amf/ngap-sm.c:55) [32m10/12 13:47:22.595[0m: [[33mamf[0m] [1;32mINFO[0m: [Added] Number of gNBs is now 2 (../src/amf/context.c:1277) [32m10/12 13:47:22.595[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) [32m10/12 13:47:22.595[0m: [[33mamf[0m] [1;37mDEBUG[0m: SCTP_COMM_UP (../src/amf/ngap-sctp.c:161) [32m10/12 13:47:22.595[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_SCTP_COMM_UP (../src/amf/amf-sm.c:83) [32m10/12 13:47:22.595[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2[127.0.0.202] max_num_of_ostreams : 30 (../src/amf/amf-sm.c:865) [32m10/12 13:47:22.597[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_MESSAGE (../src/amf/amf-sm.c:83) [32m10/12 13:47:22.597[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_operational(): AMF_EVENT_NGAP_MESSAGE (../src/amf/ngap-sm.c:55) [32m10/12 13:47:22.597[0m: [[33mamf[0m] [1;37mDEBUG[0m: NGSetupRequest (../src/amf/ngap-handler.c:288) [32m10/12 13:47:22.597[0m: [[33mamf[0m] [1;37mDEBUG[0m: IP[127.0.0.202] GNB_ID[0x0] GNB_ID_LENGTH[22] (../src/amf/ngap-handler.c:386) [32m10/12 13:47:22.597[0m: [[33mamf[0m] [1;37mDEBUG[0m: PLMN_ID[MCC:999 MNC:70] (../src/amf/ngap-handler.c:391) [32m10/12 13:47:22.597[0m: [[33mamf[0m] [1;37mDEBUG[0m: PagingDRX[0] (../src/amf/ngap-handler.c:395) [32m10/12 13:47:22.597[0m: [[33mamf[0m] [1;37mDEBUG[0m: TAC[1] (../src/amf/ngap-handler.c:439) [32m10/12 13:47:22.597[0m: [[33mamf[0m] [1;37mDEBUG[0m: PLMN_ID[MCC:999 MNC:70] (../src/amf/ngap-handler.c:501) [32m10/12 13:47:22.597[0m: [[33mamf[0m] [1;37mDEBUG[0m: S_NSSAI[SST:1 SD:0xffffff] (../src/amf/ngap-handler.c:562) [32m10/12 13:47:22.597[0m: [[33mamf[0m] [1;37mDEBUG[0m: TAC[1] (../src/amf/ngap-handler.c:72) [32m10/12 13:47:22.597[0m: [[33mamf[0m] [1;37mDEBUG[0m: PLMN_ID[MCC:999 MNC:70] (../src/amf/ngap-handler.c:73) [32m10/12 13:47:22.597[0m: [[33mamf[0m] [1;37mDEBUG[0m: SERVED_TAI_INDEX[0] (../src/amf/ngap-handler.c:78) [32m10/12 13:47:22.597[0m: [[33mamf[0m] [1;37mDEBUG[0m: PLMN_ID[MCC:999 MNC:70] (../src/amf/ngap-handler.c:100) [32m10/12 13:47:22.597[0m: [[33mamf[0m] [1;37mDEBUG[0m: S_NSSAI[SST:1 SD:0xffffff] (../src/amf/ngap-handler.c:105) [32m10/12 13:47:22.597[0m: [[33mamf[0m] [1;37mDEBUG[0m: NG-Setup response (../src/amf/ngap-path.c:356) [32m10/12 13:47:22.597[0m: [[33mamf[0m] [1;37mDEBUG[0m: NGSetupResponse (../src/amf/ngap-build.c:91) [32m10/12 13:47:22.597[0m: [[33mamf[0m] [1;37mDEBUG[0m: IP[127.0.0.202] RAN_ID[0] (../src/amf/ngap-path.c:64) MTC@312ad0d01202: setverdict(pass): none -> pass MTC@312ad0d01202: Dynamic test case error: Error message was received from MC: The connect operation refers to test component with component reference 885, which has already terminated. MTC@312ad0d01202: setverdict(error): pass -> error TC_secondary_rat_data_usage_report0(888)@312ad0d01202: Final verdict of PTC: none [32m10/12 13:47:22.607[0m: [[33mamf[0m] [1;37mDEBUG[0m: SCTP_SHUTDOWN_EVENT:[T:32773, F:0x0, L:12] (../src/amf/ngap-sctp.c:193) [32m10/12 13:47:22.608[0m: [[33mamf[0m] [1;37mDEBUG[0m: SCTP_SHUTDOWN_EVENT:[T:32773, F:0x0, L:12] (../src/amf/ngap-sctp.c:193) [32m10/12 13:47:22.608[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_CONNREFUSED (../src/amf/amf-sm.c:83) [32m10/12 13:47:22.608[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2[127.0.0.202] connection refused!!! (../src/amf/amf-sm.c:878) [32m10/12 13:47:22.608[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_operational(): EXIT (../src/amf/ngap-sm.c:55) [32m10/12 13:47:22.608[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_final(): EXIT (../src/amf/ngap-sm.c:37) [32m10/12 13:47:22.608[0m: [[33mamf[0m] [1;32mINFO[0m: [Removed] Number of gNBs is now 1 (../src/amf/context.c:1305) [32m10/12 13:47:22.608[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_CONNREFUSED (../src/amf/amf-sm.c:83) [32m10/12 13:47:22.608[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2[127.0.0.202] connection refused!!! (../src/amf/amf-sm.c:878) [32m10/12 13:47:22.608[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_operational(): EXIT (../src/amf/ngap-sm.c:55) [32m10/12 13:47:22.608[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_final(): EXIT (../src/amf/ngap-sm.c:37) [32m10/12 13:47:22.608[0m: [[33mamf[0m] [1;32mINFO[0m: [Removed] Number of gNBs is now 0 (../src/amf/context.c:1305) TC_secondary_rat_data_usage_report-NGAP0(886)@312ad0d01202: Final verdict of PTC: none TC_secondary_rat_data_usage_report-NGAP1(887)@312ad0d01202: Final verdict of PTC: none MTC@312ad0d01202: Setting final verdict of the test case. MTC@312ad0d01202: Local verdict of MTC: error MTC@312ad0d01202: Local verdict of PTC GTP1U_EM(885): error (error -> error) MTC@312ad0d01202: Local verdict of PTC TC_secondary_rat_data_usage_report-NGAP0(886): none (error -> error) MTC@312ad0d01202: Local verdict of PTC TC_secondary_rat_data_usage_report-NGAP1(887): none (error -> error) MTC@312ad0d01202: Local verdict of PTC TC_secondary_rat_data_usage_report0(888): none (error -> error) MTC@312ad0d01202: Test case TC_secondary_rat_data_usage_report finished. Verdict: error MTC@312ad0d01202: 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_secondary_rat_data_usage_report error'. (13:47:22) load average: 3.55, 3.48, 2.95 [1;31m------ C5G_Tests.TC_secondary_rat_data_usage_report 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=4732) [32m10/12 13:47:24.048[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@312ad0d01202: 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_secondary_rat_data_usage_report error' was executed successfully (exit status: 0). MC@312ad0d01202: Test execution finished. Execution of [EXECUTE] section finished. emtc MC@312ad0d01202: Terminating MTC. MC@312ad0d01202: MTC terminated. MC2> exit MC@312ad0d01202: Shutting down session. MC@312ad0d01202: Shutdown complete.
Comparing expected results 'https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/ws/src/osmo-ttcn3-hacks/5gc/expected-results.xml' against results in 'junit-xml-6863.log' -------------------- [1;32mpass[0;m C5G_Tests.TC_ng_setup_unknown_global_gnb_id_plmn [1;32mpass[0;m C5G_Tests.TC_ng_setup_wrong_tac [1;32mpass[0;m C5G_Tests.TC_ng_setup [1;32mpass[0;m C5G_Tests.TC_ng_register [1;32mpass[0;m C5G_Tests.TC_periodic_registration_updating [1;32mpass[0;m C5G_Tests.TC_ue_context_release_no_pdu_session [1;32mpass[0;m C5G_Tests.TC_ue_context_release_with_pdu_session [1;32mpass[0;m C5G_Tests.TC_pdu_sess_modification [1;33mxfail[0;m C5G_Tests.TC_pdu_sess_est_always_on [1;32mpass[0;m C5G_Tests.TC_pdu_sess_est_pco_pcscf [1;32mpass[0;m C5G_Tests.TC_ng_register_ping4 [1;32mpass[0;m C5G_Tests.TC_ng_register_ping4_256 [1;32mpass[0;m C5G_Tests.TC_ng_register_ping6 [1;32mpass[0;m C5G_Tests.TC_ng_register_ping6_256 [1;32mpass[0;m C5G_Tests.TC_ng_register_ping46 [1;31mpass->FAIL[0;m C5G_Tests.TC_ng_register_ping46_256 [1;32mpass[0;m C5G_Tests.TC_ue_service_request_cm_idle_ul_data [1;31mpass->FAIL[0;m C5G_Tests.TC_ue_service_request_cm_idle_inact_sess [1;31mpass->FAIL[0;m C5G_Tests.TC_ue_service_request_cm_idle_unknown_sess_active [1;31mpass->FAIL[0;m C5G_Tests.TC_ue_service_request_cm_connected [1;31mpass->FAIL[0;m C5G_Tests.TC_net_triggered_service_req [1;33mxfail[0;m C5G_Tests.TC_connection_inactive [1;33mxfail[0;m C5G_Tests.TC_connection_suspend [1;33mxfail[0;m C5G_Tests.TC_normal_reg_emergency_services [1;33mxfail[0;m C5G_Tests.TC_normal_reg_emergency_services_fallback [1;33mxfail[0;m C5G_Tests.TC_emergency_registration [1;31mpass->FAIL[0;m C5G_Tests.TC_handover_inter_ngran_n2 [1;31mpass->FAIL[0;m C5G_Tests.TC_handover_inter_ngran_n2_cancel [1;31mpass->FAIL[0;m C5G_Tests.TC_handover_inter_ngran_n2_dataforwardingpossible_change [1;33mxfail[0;m C5G_Tests.TC_ran_initiated_qos_flow_mobility [1;31mpass->FAIL[0;m C5G_Tests.TC_secondary_rat_data_usage_report
Summary: [1;31mpass->FAIL[0;m: 9 [1;33mxfail[0;m: 7 [1;32mpass[0;m: 15 [1;34mskip[0;m: 1
[1;34m[testenv] Stopping podman container[0m [0;94m[testenv] + ['podman', 'kill', 'testenv-5gc-osmocom-latest-20261012-1343-86f5a798-1'][0m testenv-5gc-osmocom-latest-20261012-1343-86f5a798-1 [0;94m[testenv] Skipping clean up scripts, podman container has already stopped[0m [1;34m[testenv] Stopping testsuite (754473)[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-latest/334/artifa... [0m + RC=1 + [ 1 = 0 ] + uptime + grep --color=always -o load.* [01;31m[Kload average: 3.08, 3.38, 2.93[m[K + exit 1 Build step 'Execute shell' marked build as failure Recording test results [Checks API] No suitable checks publisher found.