See https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/228/display/redirect?page=changes
Changes:
[Vadim Yanitskiy] Revert "bts: limit stderr logging to ERROR to avoid long write() to ext4
[Vadim Yanitskiy] Revert "bts: limit stderr logging to NOTICE to avoid long write() to
------------------------------------------ [...truncated 235.12 MiB...] [32m06/29 13:47:47.034[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [204] (../lib/sbi/nghttp2-server.c:702) [32m06/29 13:47:47.034[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [13719] (../lib/sbi/nghttp2-server.c:1419) 0000: 00001b01 05000002 bb89cb61 97d07abe ...........a..z. 0010: 9413ea65 b6a50401 38a059b8 d3b71a75 ...e....8.Y....u 0020: 4c5a37ff LZ7. 0000: 00001b01 05000035 9789ce61 97d07abe .......5...a..z. 0010: 9413ea65 b6a50401 38a059b8 d3b71a75 ...e....8.Y....u 0020: 4c5a37ff LZ7. [32m06/29 13:47:47.034[0m: [[33msbi[0m] [1;37mDEBUG[0m: [204:PATCH] http://127.0.0.200:7777/nnrf-nfm/v1/nf-instances/8d72a948-73c0-41f1-90a9-292... (../lib/sbi/client.c:744) [32m06/29 13:47:47.034[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755) [32m06/29 13:47:47.034[0m: [[33mudm[0m] [1;37mDEBUG[0m: udm_state_operational(): OGS_EVENT_NAME_SBI_CLIENT (../src/udm/udm-sm.c:61) [32m06/29 13:47:47.034[0m: [[33msbi[0m] [1;37mDEBUG[0m: ogs_sbi_nf_state_registered(): OGS_EVENT_NAME_SBI_CLIENT (../lib/sbi/nf-sm.c:358) [32m06/29 13:47:47.144[0m: [[33mudr[0m] [1;37mDEBUG[0m: udr_state_operational(): OGS_EVENT_NAME_SBI_TIMER (../src/udr/udr-sm.c:53) [32m06/29 13:47:47.145[0m: [[33msbi[0m] [1;37mDEBUG[0m: ogs_sbi_nf_state_registered(): OGS_EVENT_NAME_SBI_TIMER (../lib/sbi/nf-sm.c:358) [32m06/29 13:47:47.145[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] http://127.0.0.200:7777/nnrf-nfm/v1/nf-instances/8d833272-73c0-41f1-ac7b-13f... (../lib/sbi/client.c:797) [32m06/29 13:47:47.145[0m: [[33msbi[0m] [1;37mDEBUG[0m: SENDING...[100] (../lib/sbi/client.c:546) [32m06/29 13:47:47.145[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":0}] (../lib/sbi/client.c:548) [32m06/29 13:47:47.145[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM added [71] (../lib/sbi/nghttp2-server.c:1737) [32m06/29 13:47:47.145[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] /nnrf-nfm/v1/nf-instances/8d833272-73c0-41f1-ac7b-13f155d25ac6 (../lib/sbi/nghttp2-server.c:1308) [32m06/29 13:47:47.145[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED: 100 (../lib/sbi/nghttp2-server.c:1312) [32m06/29 13:47:47.145[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/29 13:47:47.145[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] http://127.0.0.10:7777/nnrf-nfm/v1/nf-instances/8d833272-73c0-41f1-ac7b-13f1... (../lib/sbi/client.c:797) [32m06/29 13:47:47.145[0m: [[33msbi[0m] [1;37mDEBUG[0m: SENDING...[100] (../lib/sbi/client.c:546) [32m06/29 13:47:47.145[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":0}] (../lib/sbi/client.c:548) [32m06/29 13:47:47.146[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM added [701] (../lib/sbi/nghttp2-server.c:1737) [32m06/29 13:47:47.146[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] /nnrf-nfm/v1/nf-instances/8d833272-73c0-41f1-ac7b-13f155d25ac6 (../lib/sbi/nghttp2-server.c:1308) [32m06/29 13:47:47.146[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED: 100 (../lib/sbi/nghttp2-server.c:1312) [32m06/29 13:47:47.146[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/29 13:47:47.146[0m: [[33mnrf[0m] [1;37mDEBUG[0m: nrf_state_operational(): OGS_EVENT_NAME_SBI_SERVER (../src/nrf/nrf-sm.c:54) [32m06/29 13:47:47.146[0m: [[33mnrf[0m] [1;37mDEBUG[0m: nrf_nf_state_registered(): OGS_EVENT_NAME_SBI_SERVER (../src/nrf/nf-sm.c:201) [32m06/29 13:47:47.146[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [204] (../lib/sbi/nghttp2-server.c:702) [32m06/29 13:47:47.146[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [701] (../lib/sbi/nghttp2-server.c:1419) 0000: 00000301 05000002 bd89ccbe ............ [32m06/29 13:47:47.146[0m: [[33msbi[0m] [1;37mDEBUG[0m: [204:PATCH] http://127.0.0.10:7777/nnrf-nfm/v1/nf-instances/8d833272-73c0-41f1-ac7b-13f1... (../lib/sbi/client.c:744) [32m06/29 13:47:47.146[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755) [32m06/29 13:47:47.146[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [204] (../lib/sbi/nghttp2-server.c:702) [32m06/29 13:47:47.146[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [71] (../lib/sbi/nghttp2-server.c:1419) 0000: 00001b01 05000000 4789d861 97d07abe ........G..a..z. 0010: 9413ea65 b6a50401 38a059b8 d3b71a75 ...e....8.Y....u 0020: 4c5a37ff LZ7. [32m06/29 13:47:47.146[0m: [[33msbi[0m] [1;37mDEBUG[0m: [204:PATCH] http://127.0.0.200:7777/nnrf-nfm/v1/nf-instances/8d833272-73c0-41f1-ac7b-13f... (../lib/sbi/client.c:744) [32m06/29 13:47:47.146[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755) [32m06/29 13:47:47.146[0m: [[33mudr[0m] [1;37mDEBUG[0m: udr_state_operational(): OGS_EVENT_NAME_SBI_CLIENT (../src/udr/udr-sm.c:53) [32m06/29 13:47:47.146[0m: [[33msbi[0m] [1;37mDEBUG[0m: ogs_sbi_nf_state_registered(): OGS_EVENT_NAME_SBI_CLIENT (../lib/sbi/nf-sm.c:358) [32m06/29 13:47:47.388[0m: [[33mpcf[0m] [1;37mDEBUG[0m: pcf_state_operational(): OGS_EVENT_NAME_SBI_TIMER (../src/pcf/pcf-sm.c:64) [32m06/29 13:47:47.388[0m: [[33msbi[0m] [1;37mDEBUG[0m: ogs_sbi_nf_state_registered(): OGS_EVENT_NAME_SBI_TIMER (../lib/sbi/nf-sm.c:358) [32m06/29 13:47:47.389[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] http://127.0.0.200:7777/nnrf-nfm/v1/nf-instances/8da8e59e-73c0-41f1-a846-7d4... (../lib/sbi/client.c:797) [32m06/29 13:47:47.389[0m: [[33msbi[0m] [1;37mDEBUG[0m: SENDING...[101] (../lib/sbi/client.c:546) [32m06/29 13:47:47.389[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":25}] (../lib/sbi/client.c:548) [32m06/29 13:47:47.389[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM added [5835] (../lib/sbi/nghttp2-server.c:1737) [32m06/29 13:47:47.389[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] /nnrf-nfm/v1/nf-instances/8da8e59e-73c0-41f1-a846-7d46568d1c6e (../lib/sbi/nghttp2-server.c:1308) [32m06/29 13:47:47.389[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED: 101 (../lib/sbi/nghttp2-server.c:1312) [32m06/29 13:47:47.389[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/29 13:47:47.389[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] http://127.0.0.10:7777/nnrf-nfm/v1/nf-instances/8da8e59e-73c0-41f1-a846-7d46... (../lib/sbi/client.c:797) [32m06/29 13:47:47.389[0m: [[33msbi[0m] [1;37mDEBUG[0m: SENDING...[101] (../lib/sbi/client.c:546) [32m06/29 13:47:47.389[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":25}] (../lib/sbi/client.c:548) [32m06/29 13:47:47.389[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM added [703] (../lib/sbi/nghttp2-server.c:1737) [32m06/29 13:47:47.389[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] /nnrf-nfm/v1/nf-instances/8da8e59e-73c0-41f1-a846-7d46568d1c6e (../lib/sbi/nghttp2-server.c:1308) [32m06/29 13:47:47.389[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED: 101 (../lib/sbi/nghttp2-server.c:1312) [32m06/29 13:47:47.389[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/29 13:47:47.389[0m: [[33mnrf[0m] [1;37mDEBUG[0m: nrf_state_operational(): OGS_EVENT_NAME_SBI_SERVER (../src/nrf/nrf-sm.c:54) [32m06/29 13:47:47.389[0m: [[33mnrf[0m] [1;37mDEBUG[0m: nrf_nf_state_registered(): OGS_EVENT_NAME_SBI_SERVER (../src/nrf/nf-sm.c:201) [32m06/29 13:47:47.389[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [204] (../lib/sbi/nghttp2-server.c:702) [32m06/29 13:47:47.389[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [703] (../lib/sbi/nghttp2-server.c:1419) [32m06/29 13:47:47.389[0m: [[33msbi[0m] [1;37mDEBUG[0m: [204:PATCH] http://127.0.0.10:7777/nnrf-nfm/v1/nf-instances/8da8e59e-73c0-41f1-a846-7d46... (../lib/sbi/client.c:744) [32m06/29 13:47:47.389[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755) [32m06/29 13:47:47.389[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [204] (../lib/sbi/nghttp2-server.c:702) [32m06/29 13:47:47.389[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [5835] (../lib/sbi/nghttp2-server.c:1419) 0000: 00000301 05000002 bf89ccbe ............ 0000: 00001b01 05000016 cb89ca61 97d07abe ...........a..z. 0010: 9413ea65 b6a50401 38a059b8 d3b71a75 ...e....8.Y....u 0020: 4c5a37ff LZ7. [32m06/29 13:47:47.389[0m: [[33msbi[0m] [1;37mDEBUG[0m: [204:PATCH] http://127.0.0.200:7777/nnrf-nfm/v1/nf-instances/8da8e59e-73c0-41f1-a846-7d4... (../lib/sbi/client.c:744) [32m06/29 13:47:47.389[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755) [32m06/29 13:47:47.389[0m: [[33mpcf[0m] [1;37mDEBUG[0m: pcf_state_operational(): OGS_EVENT_NAME_SBI_CLIENT (../src/pcf/pcf-sm.c:64) [32m06/29 13:47:47.389[0m: [[33msbi[0m] [1;37mDEBUG[0m: ogs_sbi_nf_state_registered(): OGS_EVENT_NAME_SBI_CLIENT (../lib/sbi/nf-sm.c:358) MTC@bc23c8737c76: External command `https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/ws/ttcn3-tcpdump-stop.sh C5G_Tests.TC_handover_inter_ngran_n2 fail' was executed successfully (exit status: 0). MTC@bc23c8737c76: Starting external command `https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/ws/ttcn3-tcpdump-start.sh C5G_Tests.TC_handover_inter_ngran_n2_cancel'. ------ C5G_Tests.TC_handover_inter_ngran_n2_cancel ------ (13:47:47) load average: 1.80, 3.21, 2.87 /usr/bin/dumpcap -q -s 1520 -n -i any -w "https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/228/artifact/logs/testsuite/C5G_Tests.TC_handover_inter_ngran_n2_cancel.pcap" >https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/228/artifact/logs/testsuite/C5G_Tests.TC_handover_inter_ngran_n2_cancel.pcap.stdout 2>/tmp/cmderr & Waiting for packet dumper to start... 0 MTC@bc23c8737c76: External command `https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/ws/ttcn3-tcpdump-start.sh C5G_Tests.TC_handover_inter_ngran_n2_cancel' was executed successfully (exit status: 0). MTC@bc23c8737c76: Test case TC_handover_inter_ngran_n2_cancel started. GTP1U_EM(876)@bc23c8737c76: Warning: sizes of 'struct sctp_event_subscribe': compile-time 14, kernel: 14 [38;5;23m20260629134748731 [1;32mDLINP[0;m[38;5;23m [1;32mINFO[0;m[38;5;23m SRV(,127.0.0.20:4268) accept()ed new link from 127.0.0.1:9999 (stream_srv.c:143)[0;m 20260629134748731 [1;32mDUECUPS[0;m [1;32mINFO[0;m r=127.0.0.1:9999<->l=127.0.0.20:4268: Accepted new UECUPS connection (cups_client.c:673) TC_handover_inter_ngran_n2_cancel-NGAP0(877)@bc23c8737c76: Warning: sizes of 'struct sctp_event_subscribe': compile-time 14, kernel: 14 TC_handover_inter_ngran_n2_cancel-NGAP0(877)@bc23c8737c76: setverdict(fail): none -> fail reason: "Could not connect NGAP socket, check your configuration", new component reason: "Could not connect NGAP socket, check your configuration" MC@bc23c8737c76: Test Component 877 has requested to stop MTC. Terminating current testcase execution. TC_handover_inter_ngran_n2_cancel-NGAP0(877)@bc23c8737c76: Final verdict of PTC: fail reason: "Could not connect NGAP socket, check your configuration" [38;5;23m20260629134748749 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m r=127.0.0.1:9999<->l=127.0.0.20:4268 NOTIFICATION SHUTDOWN_EVENT flags=0x0 (stream.c:348)[0;m 20260629134748749 [1;33mDUECUPS[0;m [1;33mNOTICE[0;m r=127.0.0.1:9999<->l=127.0.0.20:4268: UECUPS connection lost (cups_client.c:599) GTP1U_EM(876)@bc23c8737c76: Final verdict of PTC: none MTC@bc23c8737c76: Setting final verdict of the test case. MTC@bc23c8737c76: Local verdict of MTC: none MTC@bc23c8737c76: Local verdict of PTC GTP1U_EM(876): none (none -> none) MTC@bc23c8737c76: Local verdict of PTC TC_handover_inter_ngran_n2_cancel-NGAP0(877): fail (none -> fail) reason: "Could not connect NGAP socket, check your configuration" MTC@bc23c8737c76: Test case TC_handover_inter_ngran_n2_cancel finished. Verdict: fail reason: Could not connect NGAP socket, check your configuration MTC@bc23c8737c76: Starting external command `https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/ws/ttcn3-tcpdump-stop.sh C5G_Tests.TC_handover_inter_ngran_n2_cancel fail'. (13:47:48) load average: 1.65, 3.16, 2.86 [1;31m------ C5G_Tests.TC_handover_inter_ngran_n2_cancel fail ------[0m
Waiting for packet dumper to finish... 0 (prev_count=-1, count=208) Waiting for packet dumper to finish... 1 (prev_count=208, count=6468) MTC@bc23c8737c76: External command `https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/ws/ttcn3-tcpdump-stop.sh C5G_Tests.TC_handover_inter_ngran_n2_cancel fail' was executed successfully (exit status: 0). MTC@bc23c8737c76: Starting external command `https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/ws/ttcn3-tcpdump-start.sh C5G_Tests.TC_ran_initiated_qos_flow_mobility'. ------ C5G_Tests.TC_ran_initiated_qos_flow_mobility ------ (13:47:50) load average: 1.65, 3.16, 2.86 [32m06/29 13:47:50.828[0m: [[33mupf[0m] [1;37mDEBUG[0m: upf_state_operational(): UPF_EVT_N4_TIMER (../src/upf/upf-sm.c:51) [32m06/29 13:47:50.828[0m: [[33mupf[0m] [1;37mDEBUG[0m: upf_pfcp_state_associated(): UPF_EVT_N4_TIMER (../src/upf/pfcp-sm.c:161) [32m06/29 13:47:50.828[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [24] LOCAL Create peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:130) [32m06/29 13:47:50.828[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: Heartbeat Request (../lib/pfcp/build.c:28) [32m06/29 13:47:50.828[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [24] LOCAL UPD TX-1 peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:229) [32m06/29 13:47:50.829[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [24] LOCAL Commit peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:498) [32m06/29 13:47:50.829[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) [32m06/29 13:47:50.829[0m: [[33msmf[0m] [1;37mDEBUG[0m: Found PFCP-Node: addr_list [127.0.0.7]:8805 (../src/smf/pfcp-path.c:192) [32m06/29 13:47:50.829[0m: [[33msmf[0m] [1;37mDEBUG[0m: Merged PFCP-Node: addr_list [127.0.0.7]:8805 (../src/smf/pfcp-path.c:198) [32m06/29 13:47:50.829[0m: [[33msmf[0m] [1;37mDEBUG[0m: smf_state_operational(): SMF_EVT_N4_MESSAGE (../src/smf/smf-sm.c:94) [32m06/29 13:47:50.829[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [24] Cannot find new type 1 from PFCP peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:765) [32m06/29 13:47:50.829[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [24] REMOTE Create peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:194) [32m06/29 13:47:50.829[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [24] REMOTE Receive peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:771) [32m06/29 13:47:50.829[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [24] REMOTE UPD RX-1 peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:327) [32m06/29 13:47:50.829[0m: [[33msmf[0m] [1;37mDEBUG[0m: smf_pfcp_state_associated(): SMF_EVT_N4_MESSAGE (../src/smf/pfcp-sm.c:182) [32m06/29 13:47:50.829[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: Heartbeat Response (../lib/pfcp/build.c:56) [32m06/29 13:47:50.829[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [24] REMOTE UPD TX-2 peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:229) [32m06/29 13:47:50.829[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [24] REMOTE Commit peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:498) [32m06/29 13:47:50.829[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [24] REMOTE Delete peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:829) [32m06/29 13:47:50.829[0m: [[33msmf[0m] [1;37mDEBUG[0m: smf_state_operational(): SMF_EVT_N4_TIMER (../src/smf/smf-sm.c:94) [32m06/29 13:47:50.829[0m: [[33msmf[0m] [1;37mDEBUG[0m: smf_pfcp_state_associated(): SMF_EVT_N4_TIMER (../src/smf/pfcp-sm.c:182) [32m06/29 13:47:50.829[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2639] LOCAL Create peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:130) [32m06/29 13:47:50.829[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: Heartbeat Request (../lib/pfcp/build.c:28) [32m06/29 13:47:50.829[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2639] LOCAL UPD TX-1 peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:229) [32m06/29 13:47:50.829[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2639] LOCAL Commit peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:498) [32m06/29 13:47:50.829[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) [32m06/29 13:47:50.829[0m: [[33mupf[0m] [1;37mDEBUG[0m: Found PFCP-Node: addr_list [127.0.0.4]:8805 (../src/upf/pfcp-path.c:155) [32m06/29 13:47:50.829[0m: [[33mupf[0m] [1;37mDEBUG[0m: Merged PFCP-Node: addr_list [127.0.0.4]:8805 (../src/upf/pfcp-path.c:161) [32m06/29 13:47:50.829[0m: [[33mupf[0m] [1;37mDEBUG[0m: upf_state_operational(): UPF_EVT_N4_MESSAGE (../src/upf/upf-sm.c:51) [32m06/29 13:47:50.829[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [24] LOCAL Find peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:756) [32m06/29 13:47:50.829[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [24] LOCAL Receive peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:771) [32m06/29 13:47:50.829[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [24] LOCAL UPD RX-2 peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:327) [32m06/29 13:47:50.829[0m: [[33mupf[0m] [1;37mDEBUG[0m: upf_pfcp_state_associated(): UPF_EVT_N4_MESSAGE (../src/upf/pfcp-sm.c:161) [32m06/29 13:47:50.829[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [24] LOCAL Commit peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:498) [32m06/29 13:47:50.829[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [24] LOCAL Delete peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:829) [32m06/29 13:47:50.829[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) [32m06/29 13:47:50.829[0m: [[33mupf[0m] [1;37mDEBUG[0m: Found PFCP-Node: addr_list [127.0.0.4]:8805 (../src/upf/pfcp-path.c:155) [32m06/29 13:47:50.829[0m: [[33mupf[0m] [1;37mDEBUG[0m: Merged PFCP-Node: addr_list [127.0.0.4]:8805 (../src/upf/pfcp-path.c:161) [32m06/29 13:47:50.829[0m: [[33mupf[0m] [1;37mDEBUG[0m: upf_state_operational(): UPF_EVT_N4_MESSAGE (../src/upf/upf-sm.c:51) [32m06/29 13:47:50.829[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2639] Cannot find new type 1 from PFCP peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:765) [32m06/29 13:47:50.829[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2639] REMOTE Create peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:194) [32m06/29 13:47:50.829[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2639] REMOTE Receive peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:771) [32m06/29 13:47:50.829[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2639] REMOTE UPD RX-1 peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:327) [32m06/29 13:47:50.829[0m: [[33mupf[0m] [1;37mDEBUG[0m: upf_pfcp_state_associated(): UPF_EVT_N4_MESSAGE (../src/upf/pfcp-sm.c:161) [32m06/29 13:47:50.829[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: Heartbeat Response (../lib/pfcp/build.c:56) [32m06/29 13:47:50.829[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2639] REMOTE UPD TX-2 peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:229) [32m06/29 13:47:50.829[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2639] REMOTE Commit peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:498) [32m06/29 13:47:50.829[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2639] REMOTE Delete peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:829) [32m06/29 13:47:50.829[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) [32m06/29 13:47:50.829[0m: [[33msmf[0m] [1;37mDEBUG[0m: Found PFCP-Node: addr_list [127.0.0.7]:8805 (../src/smf/pfcp-path.c:192) [32m06/29 13:47:50.829[0m: [[33msmf[0m] [1;37mDEBUG[0m: Merged PFCP-Node: addr_list [127.0.0.7]:8805 (../src/smf/pfcp-path.c:198) [32m06/29 13:47:50.829[0m: [[33msmf[0m] [1;37mDEBUG[0m: smf_state_operational(): SMF_EVT_N4_MESSAGE (../src/smf/smf-sm.c:94) [32m06/29 13:47:50.829[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2639] LOCAL Find peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:756) [32m06/29 13:47:50.829[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2639] LOCAL Receive peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:771) [32m06/29 13:47:50.829[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2639] LOCAL UPD RX-2 peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:327) [32m06/29 13:47:50.829[0m: [[33msmf[0m] [1;37mDEBUG[0m: smf_pfcp_state_associated(): SMF_EVT_N4_MESSAGE (../src/smf/pfcp-sm.c:182) [32m06/29 13:47:50.829[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2639] LOCAL Commit peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:498) [32m06/29 13:47:50.829[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2639] LOCAL Delete peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:829) /usr/bin/dumpcap -q -s 1520 -n -i any -w "https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/228/artifact/logs/testsuite/C5G_Tests.TC_ran_initiated_qos_flow_mobility.pcap" >https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/228/artifact/logs/testsuite/C5G_Tests.TC_ran_initiated_qos_flow_mobility.pcap.stdout 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-5gc-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-5gc-test-ogs-latest/ws/_testenv/data/scripts:https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/ws/_testenv/data/scripts/qemu:https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/ws/5gc:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-e', 'PYTHONUNBUFFERED=1', '-t', 'testenv-5gc-osmocom-latest-20260629-1343-3955abf2-1', 'sh', '-c', 'echo; gdb --batch /usr/bin/open5gs-amfd https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/228/artifact/logs/amf/core -ex bt | tee https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/228/artifact/logs/amf/core.backtrace; echo'][0m
[New LWP 6608] [New LWP 6606] [32m06/29 13:47:51.453[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) [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-amfd -c open5gs-amf.yaml'. Program terminated with signal SIGSEGV, Segmentation fault. #0 0x00007f5c41e7c6bd in NativeInteger_encode_aper (td=0x7f5c41d8dba0 <asn_DEF_NGAP_ProtocolIE_ID>, constraints=0x7f5c41dd5c20 <asn_PER_memb_NGAP_id_constr_454>, sptr=0x77, po=0x7f5bdc5ebd00) warning: 57 ../lib/asn1c/common/NativeInteger_aper.c: No such file or directory at ../lib/asn1c/common/NativeInteger_aper.c:57 [Current thread is 1 (Thread 0x7f5bdc5ed6c0 (LWP 6608))] #0 0x00007f5c41e7c6bd in NativeInteger_encode_aper (td=0x7f5c41d8dba0 <asn_DEF_NGAP_ProtocolIE_ID>, constraints=0x7f5c41dd5c20 <asn_PER_memb_NGAP_id_constr_454>, sptr=0x77, po=0x7f5bdc5ebd00) at ../lib/asn1c/common/NativeInteger_aper.c:57 #1 0x00007f5c41e7ef20 in SEQUENCE_encode_aper (td=0x7f5c41db5460 <asn_DEF_NGAP_PathSwitchRequestAcknowledgeIEs>, constraints=<optimized out>, sptr=0x77, po=0x7f5bdc5ebd00) at ../lib/asn1c/common/constr_SEQUENCE_aper.c:452 #2 0x00007f5c41e7e148 in SEQUENCE_OF_encode_aper (td=0x7f5c41d94000 <asn_DEF_NGAP_ProtocolIE_Container_14662P36>, constraints=<optimized out>, sptr=0x7f5bd410fc10, po=0x7f5bdc5ebd00) at ../lib/asn1c/common/constr_SEQUENCE_OF_aper.c:92 #3 0x00007f5c41e7ef20 in SEQUENCE_encode_aper (td=0x7f5c41d24d60 <asn_DEF_NGAP_PathSwitchRequestAcknowledge>, constraints=<optimized out>, sptr=0x7f5bd46b9480, po=0x7f5bdc5ebd00) at ../lib/asn1c/common/constr_SEQUENCE_aper.c:452 #4 0x00007f5c41e79d7f in aper_encode (td=0x7f5c41d24d60 <asn_DEF_NGAP_PathSwitchRequestAcknowledge>, constraints=<optimized out>, sptr=0x7f5bd46b9480, cb=<optimized out>, app_key=app_key@entry=0x7f5bdc5ebdb0) at ../lib/asn1c/common/aper_encoder.c:115 #5 0x00007f5c41e79f19 in aper_encode_to_new_buffer (td=<optimized out>, constraints=<optimized out>, sptr=<optimized out>, buffer_r=buffer_r@entry=0x7f5bdc5ebdf0) at ../lib/asn1c/common/aper_encoder.c:48 #6 0x00007f5c41e7a8bd in aper_open_type_put (td=<optimized out>, constraints=<optimized out>, sptr=<optimized out>, po=po@entry=0x7f5bdc5ec010) at ../lib/asn1c/common/aper_opentype.c:99 #7 0x00007f5c41e7d6e1 in OPEN_TYPE_encode_aper (td=0x7f5c41d21260 <asn_DEF_NGAP_value_4>, constraints=<optimized out>, sptr=0x7f5bd407ea60, po=0x7f5bdc5ec010) at ../lib/asn1c/common/OPEN_TYPE_aper.c:270 #8 0x00007f5c41e7d7ab in OPEN_TYPE_aper_put (td=td@entry=0x7f5c41d210c0 <asn_DEF_NGAP_SuccessfulOutcome>, sptr=sptr@entry=0x7f5bd407ea50, elm=0x7f5c41d21200 <asn_MBR_NGAP_SuccessfulOutcome_1+160>, po=po@entry=0x7f5bdc5ec010) at ../lib/asn1c/common/OPEN_TYPE_aper.c:336 #9 0x00007f5c41e7efe3 in SEQUENCE_encode_aper (td=0x7f5c41d210c0 <asn_DEF_NGAP_SuccessfulOutcome>, constraints=<optimized out>, sptr=0x7f5bd407ea50, po=0x7f5bdc5ec010) at ../lib/asn1c/common/constr_SEQUENCE_aper.c:450 #10 0x00007f5c41e7df35 in CHOICE_encode_aper (td=0x7f5c41d1f020 <asn_DEF_NGAP_NGAP_PDU>, constraints=<optimized out>, sptr=0x7f5bdc5ec150, po=0x7f5bdc5ec010) at ../lib/asn1c/common/constr_CHOICE_aper.c:195 #11 0x00007f5c41e79d7f in aper_encode (td=td@entry=0x7f5c41d1f020 <asn_DEF_NGAP_NGAP_PDU>, constraints=constraints@entry=0x0, sptr=sptr@entry=0x7f5bdc5ec150, cb=cb@entry=0x7f5c41e79cb0 <encode_to_buffer_cb>, app_key=app_key@entry=0x7f5bdc5ec0a0) at ../lib/asn1c/common/aper_encoder.c:115 #12 0x00007f5c41e79eb2 in aper_encode_to_buffer (td=td@entry=0x7f5c41d1f020 <asn_DEF_NGAP_NGAP_PDU>, constraints=constraints@entry=0x0, sptr=sptr@entry=0x7f5bdc5ec150, buffer=<optimized out>, buffer_size=buffer_size@entry=32768) at ../lib/asn1c/common/aper_encoder.c:36 #13 0x00007f5c419fc807 in ogs_asn_encode (td=td@entry=0x7f5c41d1f020 <asn_DEF_NGAP_NGAP_PDU>, sptr=sptr@entry=0x7f5bdc5ec150) at ../lib/asn1c/util/message.c:95 #14 0x00007f5c41e8fba7 in ogs_ngap_encode (message=message@entry=0x7f5bdc5ec150) at ../lib/ngap/message.c:33 #15 0x000055c5ed193769 in ngap_build_path_switch_ack (amf_ue=amf_ue@entry=0x7f5bdd16b100) at ../src/amf/ngap-build.c:2514 #16 0x000055c5ed15df81 in ngap_send_path_switch_ack (sess=sess@entry=0x7f5bdc7ae780) at ../src/amf/ngap-path.c:611 #17 0x000055c5ed17d48c in amf_nsmf_pdusession_handle_update_sm_context (amf_ue=amf_ue@entry=0x7f5bdd16b100, ran_ue=ran_ue@entry=0x55c5f406a550, sess=sess@entry=0x7f5bdc7ae780, state=state@entry=20, recvmsg=recvmsg@entry=0x7f5bdc5ec270) at ../src/amf/nsmf-handler.c:485 #18 0x000055c5ed1738aa in amf_state_operational (s=<optimized out>, e=<optimized out>) at ../src/amf/amf-sm.c:608 #19 0x00007f5c41eb9e24 in ogs_fsm_dispatch (fsm=0x7f5bdc5ecc20, event=0x7f5bd41465e0) at ../lib/core/ogs-fsm.c:127 #20 0x000055c5ed149e49 in amf_main (data=<optimized out>) at ../src/amf/init.c:163 #21 0x00007f5c41eaf005 in thread_worker (arg=0x55c5f40938f0) at ../lib/core/ogs-thread.c:67 #22 0x00007f5c40e9eb7b in start_thread (arg=<optimized out>) at ./nptl/pthread_create.c:448 #23 0x00007f5c40f1c7f8 in __GI___clone3 () at ../sysdeps/unix/sysv/linux/x86_64/clone3.S:78
MTC@bc23c8737c76: External command `https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/ws/ttcn3-tcpdump-start.sh C5G_Tests.TC_ran_initiated_qos_flow_mobility' was executed successfully (exit status: 0). MTC@bc23c8737c76: Test case TC_ran_initiated_qos_flow_mobility started. GTP1U_EM(878)@bc23c8737c76: Warning: sizes of 'struct sctp_event_subscribe': compile-time 14, kernel: 14 [38;5;23m20260629134751845 [1;32mDLINP[0;m[38;5;23m [1;32mINFO[0;m[38;5;23m SRV(,127.0.0.20:4268) accept()ed new link from 127.0.0.1:9999 (stream_srv.c:143)[0;m 20260629134751846 [1;32mDUECUPS[0;m [1;32mINFO[0;m r=127.0.0.1:9999<->l=127.0.0.20:4268: Accepted new UECUPS connection (cups_client.c:673) TC_ran_initiated_qos_flow_mobility-NGAP0(879)@bc23c8737c76: Warning: sizes of 'struct sctp_event_subscribe': compile-time 14, kernel: 14 TC_ran_initiated_qos_flow_mobility-NGAP0(879)@bc23c8737c76: setverdict(fail): none -> fail reason: "Could not connect NGAP socket, check your configuration", new component reason: "Could not connect NGAP socket, check your configuration" MC@bc23c8737c76: Test Component 879 has requested to stop MTC. Terminating current testcase execution. TC_ran_initiated_qos_flow_mobility-NGAP0(879)@bc23c8737c76: Final verdict of PTC: fail reason: "Could not connect NGAP socket, check your configuration" [38;5;23m20260629134751851 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m r=127.0.0.1:9999<->l=127.0.0.20:4268 NOTIFICATION SHUTDOWN_EVENT flags=0x0 (stream.c:348)[0;m 20260629134751851 [1;33mDUECUPS[0;m [1;33mNOTICE[0;m r=127.0.0.1:9999<->l=127.0.0.20:4268: UECUPS connection lost (cups_client.c:599) GTP1U_EM(878)@bc23c8737c76: Final verdict of PTC: none MTC@bc23c8737c76: Setting final verdict of the test case. MTC@bc23c8737c76: Local verdict of MTC: none MTC@bc23c8737c76: Local verdict of PTC GTP1U_EM(878): none (none -> none) MTC@bc23c8737c76: Local verdict of PTC TC_ran_initiated_qos_flow_mobility-NGAP0(879): fail (none -> fail) reason: "Could not connect NGAP socket, check your configuration" MTC@bc23c8737c76: Test case TC_ran_initiated_qos_flow_mobility finished. Verdict: fail reason: Could not connect NGAP socket, check your configuration MTC@bc23c8737c76: Starting external command `https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/ws/ttcn3-tcpdump-stop.sh C5G_Tests.TC_ran_initiated_qos_flow_mobility fail'. (13:47:51) load average: 1.65, 3.16, 2.86 [1;31m------ C5G_Tests.TC_ran_initiated_qos_flow_mobility fail ------[0m
Waiting for packet dumper to finish... 0 (prev_count=-1, count=208) [1;34m[testenv] Stopping podman container[0m [0;94m[testenv] + ['podman', 'kill', 'testenv-5gc-osmocom-latest-20260629-1343-3955abf2-1'][0m testenv-5gc-osmocom-latest-20260629-1343-3955abf2-1 https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/228/artifact/logs/amf/core: ELF 64-bit LSB core file, x86-64, version 1 (SYSV), SVR4-style, from 'open5gs-amfd -c open5gs-amf.yaml', real uid: 0, effective uid: 0, real gid: 0, effective gid: 0, execfn: '/usr/bin/open5gs-amfd', platform: 'x86_64' [0;94m[testenv] Skipping clean up scripts, podman container has already stopped[0m [1;34m[testenv] Stopping testsuite (2082291)[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-latest/228/artifa... [0m + RC=1 + [ 1 = 0 ] + uptime + grep --color=always -o load.* [01;31m[Kload average: 1.52, 3.11, 2.84[m[K + exit 1 Build step 'Execute shell' marked build as failure Recording test results [Checks API] No suitable checks publisher found.