See https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/227/display/redirect
Changes:
------------------------------------------ [...truncated 235.05 MiB...] [32m06/28 13:47:35.141[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM added [69] (../lib/sbi/nghttp2-server.c:1737) [32m06/28 13:47:35.141[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] /nnrf-nfm/v1/nf-instances/61e945d0-72f7-41f1-8581-19da6038f912 (../lib/sbi/nghttp2-server.c:1308) [32m06/28 13:47:35.141[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED: 100 (../lib/sbi/nghttp2-server.c:1312) [32m06/28 13:47:35.141[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/28 13:47:35.141[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] http://127.0.0.10:7777/nnrf-nfm/v1/nf-instances/61e945d0-72f7-41f1-8581-19da... (../lib/sbi/client.c:797) [32m06/28 13:47:35.141[0m: [[33msbi[0m] [1;37mDEBUG[0m: SENDING...[100] (../lib/sbi/client.c:546) [32m06/28 13:47:35.141[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":0}] (../lib/sbi/client.c:548) [32m06/28 13:47:35.142[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM added [683] (../lib/sbi/nghttp2-server.c:1737) [32m06/28 13:47:35.142[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] /nnrf-nfm/v1/nf-instances/61e945d0-72f7-41f1-8581-19da6038f912 (../lib/sbi/nghttp2-server.c:1308) [32m06/28 13:47:35.142[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED: 100 (../lib/sbi/nghttp2-server.c:1312) [32m06/28 13:47:35.142[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/28 13:47:35.142[0m: [[33mnrf[0m] [1;37mDEBUG[0m: nrf_state_operational(): OGS_EVENT_NAME_SBI_SERVER (../src/nrf/nrf-sm.c:54) [32m06/28 13:47:35.142[0m: [[33mnrf[0m] [1;37mDEBUG[0m: nrf_nf_state_registered(): OGS_EVENT_NAME_SBI_SERVER (../src/nrf/nf-sm.c:201) [32m06/28 13:47:35.142[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [204] (../lib/sbi/nghttp2-server.c:702) [32m06/28 13:47:35.142[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [683] (../lib/sbi/nghttp2-server.c:1419) 0000: 00001b01 05000002 ab89c861 97dd6d5f ...........a..m_ 0010: 4a09e532 db528200 9c502cdc 69db8cb6 J..2.R...P,.i... 0020: a62d1bff .-.. [32m06/28 13:47:35.142[0m: [[33msbi[0m] [1;37mDEBUG[0m: [204:PATCH] http://127.0.0.10:7777/nnrf-nfm/v1/nf-instances/61e945d0-72f7-41f1-8581-19da... (../lib/sbi/client.c:744) [32m06/28 13:47:35.142[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755) [32m06/28 13:47:35.142[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [204] (../lib/sbi/nghttp2-server.c:702) [32m06/28 13:47:35.142[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [69] (../lib/sbi/nghttp2-server.c:1419) 0000: 00001b01 05000000 4589d761 97dd6d5f ........E..a..m_ 0010: 4a09e532 db528200 9c502cdc 69db8cb6 J..2.R...P,.i... 0020: a62d1bff .-.. [32m06/28 13:47:35.143[0m: [[33msbi[0m] [1;37mDEBUG[0m: [204:PATCH] http://127.0.0.200:7777/nnrf-nfm/v1/nf-instances/61e945d0-72f7-41f1-8581-19d... (../lib/sbi/client.c:744) [32m06/28 13:47:35.143[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755) [32m06/28 13:47:35.143[0m: [[33mudr[0m] [1;37mDEBUG[0m: udr_state_operational(): OGS_EVENT_NAME_SBI_CLIENT (../src/udr/udr-sm.c:53) [32m06/28 13:47:35.143[0m: [[33msbi[0m] [1;37mDEBUG[0m: ogs_sbi_nf_state_registered(): OGS_EVENT_NAME_SBI_CLIENT (../lib/sbi/nf-sm.c:358) [32m06/28 13:47:35.296[0m: [[33mpcf[0m] [1;37mDEBUG[0m: pcf_state_operational(): OGS_EVENT_NAME_SBI_TIMER (../src/pcf/pcf-sm.c:64) [32m06/28 13:47:35.296[0m: [[33msbi[0m] [1;37mDEBUG[0m: ogs_sbi_nf_state_registered(): OGS_EVENT_NAME_SBI_TIMER (../lib/sbi/nf-sm.c:358) [32m06/28 13:47:35.297[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] http://127.0.0.200:7777/nnrf-nfm/v1/nf-instances/620067ec-72f7-41f1-bea9-bda... (../lib/sbi/client.c:797) [32m06/28 13:47:35.297[0m: [[33msbi[0m] [1;37mDEBUG[0m: SENDING...[101] (../lib/sbi/client.c:546) [32m06/28 13:47:35.297[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":25}] (../lib/sbi/client.c:548) [32m06/28 13:47:35.297[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM added [5833] (../lib/sbi/nghttp2-server.c:1737) [32m06/28 13:47:35.297[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] /nnrf-nfm/v1/nf-instances/620067ec-72f7-41f1-bea9-bda2dfadcac5 (../lib/sbi/nghttp2-server.c:1308) [32m06/28 13:47:35.297[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED: 101 (../lib/sbi/nghttp2-server.c:1312) [32m06/28 13:47:35.297[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/28 13:47:35.297[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] http://127.0.0.10:7777/nnrf-nfm/v1/nf-instances/620067ec-72f7-41f1-bea9-bda2... (../lib/sbi/client.c:797) [32m06/28 13:47:35.297[0m: [[33msbi[0m] [1;37mDEBUG[0m: SENDING...[101] (../lib/sbi/client.c:546) [32m06/28 13:47:35.297[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":25}] (../lib/sbi/client.c:548) [32m06/28 13:47:35.297[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM added [685] (../lib/sbi/nghttp2-server.c:1737) [32m06/28 13:47:35.298[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] /nnrf-nfm/v1/nf-instances/620067ec-72f7-41f1-bea9-bda2dfadcac5 (../lib/sbi/nghttp2-server.c:1308) [32m06/28 13:47:35.298[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED: 101 (../lib/sbi/nghttp2-server.c:1312) [32m06/28 13:47:35.298[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/28 13:47:35.298[0m: [[33mnrf[0m] [1;37mDEBUG[0m: nrf_state_operational(): OGS_EVENT_NAME_SBI_SERVER (../src/nrf/nrf-sm.c:54) [32m06/28 13:47:35.298[0m: [[33mnrf[0m] [1;37mDEBUG[0m: nrf_nf_state_registered(): OGS_EVENT_NAME_SBI_SERVER (../src/nrf/nf-sm.c:201) [32m06/28 13:47:35.298[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [204] (../lib/sbi/nghttp2-server.c:702) [32m06/28 13:47:35.298[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [685] (../lib/sbi/nghttp2-server.c:1419) 0000: 00000301 05000002 ad89c9be ............ [32m06/28 13:47:35.298[0m: [[33msbi[0m] [1;37mDEBUG[0m: [204:PATCH] http://127.0.0.10:7777/nnrf-nfm/v1/nf-instances/620067ec-72f7-41f1-bea9-bda2... (../lib/sbi/client.c:744) [32m06/28 13:47:35.298[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755) [32m06/28 13:47:35.298[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [204] (../lib/sbi/nghttp2-server.c:702) [32m06/28 13:47:35.298[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [5833] (../lib/sbi/nghttp2-server.c:1419) [32m06/28 13:47:35.298[0m: [[33msbi[0m] [1;37mDEBUG[0m: [204:PATCH] http://127.0.0.200:7777/nnrf-nfm/v1/nf-instances/620067ec-72f7-41f1-bea9-bda... (../lib/sbi/client.c:744) [32m06/28 13:47:35.298[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755) [32m06/28 13:47:35.298[0m: [[33mpcf[0m] [1;37mDEBUG[0m: pcf_state_operational(): OGS_EVENT_NAME_SBI_CLIENT (../src/pcf/pcf-sm.c:64) [32m06/28 13:47:35.298[0m: [[33msbi[0m] [1;37mDEBUG[0m: ogs_sbi_nf_state_registered(): OGS_EVENT_NAME_SBI_CLIENT (../lib/sbi/nf-sm.c:358) 0000: 00001b01 05000016 c989c861 97dd6d5f ...........a..m_ 0010: 4a09e532 db528200 9c502cdc 69db8cb6 J..2.R...P,.i... 0020: a62d1bff .-.. [32m06/28 13:47:37.740[0m: [[33mupf[0m] [1;37mDEBUG[0m: upf_state_operational(): UPF_EVT_N4_TIMER (../src/upf/upf-sm.c:51) [32m06/28 13:47:37.740[0m: [[33mupf[0m] [1;37mDEBUG[0m: upf_pfcp_state_associated(): UPF_EVT_N4_TIMER (../src/upf/pfcp-sm.c:161) [32m06/28 13:47:37.740[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [23] LOCAL Create peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:130) [32m06/28 13:47:37.740[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: Heartbeat Request (../lib/pfcp/build.c:28) [32m06/28 13:47:37.740[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [23] LOCAL UPD TX-1 peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:229) [32m06/28 13:47:37.740[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [23] LOCAL Commit peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:498) [32m06/28 13:47:37.740[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/28 13:47:37.740[0m: [[33msmf[0m] [1;37mDEBUG[0m: Found PFCP-Node: addr_list [127.0.0.7]:8805 (../src/smf/pfcp-path.c:192) [32m06/28 13:47:37.740[0m: [[33msmf[0m] [1;37mDEBUG[0m: Merged PFCP-Node: addr_list [127.0.0.7]:8805 (../src/smf/pfcp-path.c:198) [32m06/28 13:47:37.740[0m: [[33msmf[0m] [1;37mDEBUG[0m: smf_state_operational(): SMF_EVT_N4_MESSAGE (../src/smf/smf-sm.c:94) [32m06/28 13:47:37.740[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [23] Cannot find new type 1 from PFCP peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:765) [32m06/28 13:47:37.740[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [23] REMOTE Create peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:194) [32m06/28 13:47:37.740[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [23] REMOTE Receive peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:771) [32m06/28 13:47:37.740[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [23] REMOTE UPD RX-1 peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:327) [32m06/28 13:47:37.740[0m: [[33msmf[0m] [1;37mDEBUG[0m: smf_pfcp_state_associated(): SMF_EVT_N4_MESSAGE (../src/smf/pfcp-sm.c:182) [32m06/28 13:47:37.740[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: Heartbeat Response (../lib/pfcp/build.c:56) [32m06/28 13:47:37.740[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [23] REMOTE UPD TX-2 peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:229) [32m06/28 13:47:37.740[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [23] REMOTE Commit peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:498) [32m06/28 13:47:37.740[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [23] REMOTE Delete peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:829) [32m06/28 13:47:37.740[0m: [[33msmf[0m] [1;37mDEBUG[0m: smf_state_operational(): SMF_EVT_N4_TIMER (../src/smf/smf-sm.c:94) [32m06/28 13:47:37.740[0m: [[33msmf[0m] [1;37mDEBUG[0m: smf_pfcp_state_associated(): SMF_EVT_N4_TIMER (../src/smf/pfcp-sm.c:182) [32m06/28 13:47:37.740[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2638] LOCAL Create peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:130) [32m06/28 13:47:37.740[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: Heartbeat Request (../lib/pfcp/build.c:28) [32m06/28 13:47:37.740[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2638] LOCAL UPD TX-1 peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:229) [32m06/28 13:47:37.740[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2638] LOCAL Commit peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:498) [32m06/28 13:47:37.740[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/28 13:47:37.740[0m: [[33mupf[0m] [1;37mDEBUG[0m: Found PFCP-Node: addr_list [127.0.0.4]:8805 (../src/upf/pfcp-path.c:155) [32m06/28 13:47:37.740[0m: [[33mupf[0m] [1;37mDEBUG[0m: Merged PFCP-Node: addr_list [127.0.0.4]:8805 (../src/upf/pfcp-path.c:161) [32m06/28 13:47:37.740[0m: [[33mupf[0m] [1;37mDEBUG[0m: upf_state_operational(): UPF_EVT_N4_MESSAGE (../src/upf/upf-sm.c:51) [32m06/28 13:47:37.740[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [23] LOCAL Find peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:756) [32m06/28 13:47:37.740[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [23] LOCAL Receive peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:771) [32m06/28 13:47:37.741[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [23] LOCAL UPD RX-2 peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:327) [32m06/28 13:47:37.741[0m: [[33mupf[0m] [1;37mDEBUG[0m: upf_pfcp_state_associated(): UPF_EVT_N4_MESSAGE (../src/upf/pfcp-sm.c:161) [32m06/28 13:47:37.741[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [23] LOCAL Commit peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:498) [32m06/28 13:47:37.741[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [23] LOCAL Delete peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:829) [32m06/28 13:47:37.741[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/28 13:47:37.741[0m: [[33mupf[0m] [1;37mDEBUG[0m: Found PFCP-Node: addr_list [127.0.0.4]:8805 (../src/upf/pfcp-path.c:155) [32m06/28 13:47:37.741[0m: [[33mupf[0m] [1;37mDEBUG[0m: Merged PFCP-Node: addr_list [127.0.0.4]:8805 (../src/upf/pfcp-path.c:161) [32m06/28 13:47:37.741[0m: [[33mupf[0m] [1;37mDEBUG[0m: upf_state_operational(): UPF_EVT_N4_MESSAGE (../src/upf/upf-sm.c:51) [32m06/28 13:47:37.741[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2638] Cannot find new type 1 from PFCP peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:765) [32m06/28 13:47:37.741[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2638] REMOTE Create peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:194) [32m06/28 13:47:37.741[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2638] REMOTE Receive peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:771) [32m06/28 13:47:37.741[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2638] REMOTE UPD RX-1 peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:327) [32m06/28 13:47:37.741[0m: [[33mupf[0m] [1;37mDEBUG[0m: upf_pfcp_state_associated(): UPF_EVT_N4_MESSAGE (../src/upf/pfcp-sm.c:161) [32m06/28 13:47:37.741[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: Heartbeat Response (../lib/pfcp/build.c:56) [32m06/28 13:47:37.741[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2638] REMOTE UPD TX-2 peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:229) [32m06/28 13:47:37.741[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2638] REMOTE Commit peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:498) [32m06/28 13:47:37.741[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2638] REMOTE Delete peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:829) [32m06/28 13:47:37.741[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/28 13:47:37.741[0m: [[33msmf[0m] [1;37mDEBUG[0m: Found PFCP-Node: addr_list [127.0.0.7]:8805 (../src/smf/pfcp-path.c:192) [32m06/28 13:47:37.741[0m: [[33msmf[0m] [1;37mDEBUG[0m: Merged PFCP-Node: addr_list [127.0.0.7]:8805 (../src/smf/pfcp-path.c:198) [32m06/28 13:47:37.741[0m: [[33msmf[0m] [1;37mDEBUG[0m: smf_state_operational(): SMF_EVT_N4_MESSAGE (../src/smf/smf-sm.c:94) [32m06/28 13:47:37.741[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2638] LOCAL Find peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:756) [32m06/28 13:47:37.741[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2638] LOCAL Receive peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:771) [32m06/28 13:47:37.741[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2638] LOCAL UPD RX-2 peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:327) [32m06/28 13:47:37.741[0m: [[33msmf[0m] [1;37mDEBUG[0m: smf_pfcp_state_associated(): SMF_EVT_N4_MESSAGE (../src/smf/pfcp-sm.c:182) [32m06/28 13:47:37.741[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2638] LOCAL Commit peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:498) [32m06/28 13:47:37.741[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2638] LOCAL Delete peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:829) 20260628134739929 [1;33mDTUN[0;m [1;33mNOTICE[0;m tun0: No tunnel found for source address fe80::2a6a:846b:e0aa:b750:0 (tun_device.c:273) MTC@15dbd88f23c1: setverdict(fail): pass -> fail reason: ""C5G_Tests.ttcn:253 : Tguard timeout"", new component reason: ""C5G_Tests.ttcn:253 : Tguard timeout"" MTC@15dbd88f23c1: Stopping testcase execution from "C5G_Tests.ttcn":253 TC_handover_inter_ngran_xn-NGAP0(870)@15dbd88f23c1: Final verdict of PTC: none TC_handover_inter_ngran_xn-NGAP1(871)@15dbd88f23c1: Final verdict of PTC: none [38;5;23m20260628134740417 [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 20260628134740417 [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) TC_handover_inter_ngran_xn1(873)@15dbd88f23c1: Final verdict of PTC: none GTP1U_EM(869)@15dbd88f23c1: Final verdict of PTC: none MTC@15dbd88f23c1: Setting final verdict of the test case. MTC@15dbd88f23c1: Local verdict of MTC: fail reason: ""C5G_Tests.ttcn:253 : Tguard timeout"" MTC@15dbd88f23c1: Local verdict of PTC GTP1U_EM(869): none (fail -> fail) MTC@15dbd88f23c1: Local verdict of PTC TC_handover_inter_ngran_xn-NGAP0(870): none (fail -> fail) MTC@15dbd88f23c1: Local verdict of PTC TC_handover_inter_ngran_xn-NGAP1(871): none (fail -> fail) MTC@15dbd88f23c1: Local verdict of PTC TC_handover_inter_ngran_xn0(872): pass (fail -> fail) MTC@15dbd88f23c1: Local verdict of PTC TC_handover_inter_ngran_xn1(873): none (fail -> fail) MTC@15dbd88f23c1: Test case TC_handover_inter_ngran_xn finished. Verdict: fail reason: "C5G_Tests.ttcn:253 : Tguard timeout" MTC@15dbd88f23c1: 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_xn fail'. (13:47:40) load average: 2.93, 2.63, 2.26 [1;31m------ C5G_Tests.TC_handover_inter_ngran_xn fail ------[0m
Waiting for packet dumper to finish... 0 (prev_count=-1, count=134816) Waiting for packet dumper to finish... 1 (prev_count=134816, count=136284) MTC@15dbd88f23c1: External command `https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/ws/ttcn3-tcpdump-stop.sh C5G_Tests.TC_handover_inter_ngran_xn fail' was executed successfully (exit status: 0). MTC@15dbd88f23c1: 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'. ------ C5G_Tests.TC_handover_inter_ngran_n2 ------ (13:47:42) load average: 2.93, 2.63, 2.26 /usr/bin/dumpcap -q -s 1520 -n -i any -w "https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/227/artifact/logs/testsuite/C5G_Tests.TC_handover_inter_ngran_n2.pcap" >https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/227/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] 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-20260628-1343-d31346c7-1', 'sh', '-c', 'echo; gdb --batch /usr/bin/open5gs-amfd https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/227/artifact/logs/amf/core -ex bt | tee https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/227/artifact/logs/amf/core.backtrace; echo'][0m
[New LWP 6608] [New LWP 6606] [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 0x00007f7a45c386bd in NativeInteger_encode_aper (td=0x7f7a4598dba0 <asn_DEF_NGAP_ProtocolIE_ID>, constraints=0x7f7a459d5c20 <asn_PER_memb_NGAP_id_constr_454>, sptr=0x77, po=0x7f79e03a8d00)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 0x7f79e03aa6c0 (LWP 6608))] #0 0x00007f7a45c386bd in NativeInteger_encode_aper (td=0x7f7a4598dba0 <asn_DEF_NGAP_ProtocolIE_ID>, constraints=0x7f7a459d5c20 <asn_PER_memb_NGAP_id_constr_454>, sptr=0x77, po=0x7f79e03a8d00) at ../lib/asn1c/common/NativeInteger_aper.c:57 #1 0x00007f7a45c3af20 in SEQUENCE_encode_aper (td=0x7f7a459b5460 <asn_DEF_NGAP_PathSwitchRequestAcknowledgeIEs>, constraints=<optimized out>, sptr=0x77, po=0x7f79e03a8d00) at ../lib/asn1c/common/constr_SEQUENCE_aper.c:452 #2 0x00007f7a45c3a148 in SEQUENCE_OF_encode_aper (td=0x7f7a45994000 <asn_DEF_NGAP_ProtocolIE_Container_14662P36>, constraints=<optimized out>, sptr=0x7f79d822b3b0, po=0x7f79e03a8d00) at ../lib/asn1c/common/constr_SEQUENCE_OF_aper.c:92 #3 0x00007f7a45c3af20 in SEQUENCE_encode_aper (td=0x7f7a45924d60 <asn_DEF_NGAP_PathSwitchRequestAcknowledge>, constraints=<optimized out>, sptr=0x7f79d82534a0, po=0x7f79e03a8d00) at ../lib/asn1c/common/constr_SEQUENCE_aper.c:452 #4 0x00007f7a45c35d7f in aper_encode (td=0x7f7a45924d60 <asn_DEF_NGAP_PathSwitchRequestAcknowledge>, constraints=<optimized out>, sptr=0x7f79d82534a0, cb=<optimized out>, app_key=app_key@entry=0x7f79e03a8db0) at ../lib/asn1c/common/aper_encoder.c:115 #5 0x00007f7a45c35f19 in aper_encode_to_new_buffer (td=<optimized out>, constraints=<optimized out>, sptr=<optimized out>, buffer_r=buffer_r@entry=0x7f79e03a8df0) at ../lib/asn1c/common/aper_encoder.c:48 #6 0x00007f7a45c368bd in aper_open_type_put (td=<optimized out>, constraints=<optimized out>, sptr=<optimized out>, po=po@entry=0x7f79e03a9010) at ../lib/asn1c/common/aper_opentype.c:99 #7 0x00007f7a45c396e1 in OPEN_TYPE_encode_aper (td=0x7f7a45921260 <asn_DEF_NGAP_value_4>, constraints=<optimized out>, sptr=0x7f79d8381110, po=0x7f79e03a9010) at ../lib/asn1c/common/OPEN_TYPE_aper.c:270 #8 0x00007f7a45c397ab in OPEN_TYPE_aper_put (td=td@entry=0x7f7a459210c0 <asn_DEF_NGAP_SuccessfulOutcome>, sptr=sptr@entry=0x7f79d8381100, elm=0x7f7a45921200 <asn_MBR_NGAP_SuccessfulOutcome_1+160>, po=po@entry=0x7f79e03a9010) at ../lib/asn1c/common/OPEN_TYPE_aper.c:336 #9 0x00007f7a45c3afe3 in SEQUENCE_encode_aper (td=0x7f7a459210c0 <asn_DEF_NGAP_SuccessfulOutcome>, constraints=<optimized out>, sptr=0x7f79d8381100, po=0x7f79e03a9010) at ../lib/asn1c/common/constr_SEQUENCE_aper.c:450 #10 0x00007f7a45c39f35 in CHOICE_encode_aper (td=0x7f7a4591f020 <asn_DEF_NGAP_NGAP_PDU>, constraints=<optimized out>, sptr=0x7f79e03a9150, po=0x7f79e03a9010) at ../lib/asn1c/common/constr_CHOICE_aper.c:195 #11 0x00007f7a45c35d7f in aper_encode (td=td@entry=0x7f7a4591f020 <asn_DEF_NGAP_NGAP_PDU>, constraints=constraints@entry=0x0, sptr=sptr@entry=0x7f79e03a9150, cb=cb@entry=0x7f7a45c35cb0 <encode_to_buffer_cb>, app_key=app_key@entry=0x7f79e03a90a0) at ../lib/asn1c/common/aper_encoder.c:115 #12 0x00007f7a45c35eb2 in aper_encode_to_buffer (td=td@entry=0x7f7a4591f020 <asn_DEF_NGAP_NGAP_PDU>, constraints=constraints@entry=0x0, sptr=sptr@entry=0x7f79e03a9150, buffer=<optimized out>, buffer_size=buffer_size@entry=32768) at ../lib/asn1c/common/aper_encoder.c:36 #13 0x00007f7a45c25807 in ogs_asn_encode (td=td@entry=0x7f7a4591f020 <asn_DEF_NGAP_NGAP_PDU>, sptr=sptr@entry=0x7f79e03a9150) at ../lib/asn1c/util/message.c:95 #14 0x00007f7a45c4bba7 in ogs_ngap_encode (message=message@entry=0x7f79e03a9150) at ../lib/ngap/message.c:33 #15 0x00005576381df769 in ngap_build_path_switch_ack (amf_ue=amf_ue@entry=0x7f79e0f28100) at ../src/amf/ngap-build.c:2514 #16 0x00005576381a9f81 in ngap_send_path_switch_ack (sess=sess@entry=0x7f79e056b780) at ../src/amf/ngap-path.c:611 #17 0x00005576381c948c in amf_nsmf_pdusession_handle_update_sm_context (amf_ue=amf_ue@entry=0x7f79e0f28100, ran_ue=ran_ue@entry=0x557656b37550, sess=sess@entry=0x7f79e056b780, state=state@entry=20, recvmsg=recvmsg@entry=0x7f79e03a9270) at ../src/amf/nsmf-handler.c:485 #18 0x00005576381bf8aa in amf_state_operational (s=<optimized out>, e=<optimized out>) at ../src/amf/amf-sm.c:608 #19 0x00007f7a45c75e24 in ogs_fsm_dispatch (fsm=0x7f79e03a9c20, event=0x7f79d81860c0) at ../lib/core/ogs-fsm.c:127 #20 0x0000557638195e49 in amf_main (data=<optimized out>) at ../src/amf/init.c:163 #21 0x00007f7a45c6b005 in thread_worker (arg=0x557656b608f0) at ../lib/core/ogs-thread.c:67 #22 0x00007f7a44a9eb7b in start_thread (arg=<optimized out>) at ./nptl/pthread_create.c:448 #23 0x00007f7a44b1c7f8 in __GI___clone3 () at ../sysdeps/unix/sysv/linux/x86_64/clone3.S:78
[32m06/28 13:47:43.436[0m: [[33mscp[0m] [1;37mDEBUG[0m: scp_state_operational(): OGS_EVENT_NAME_SBI_TIMER (../src/scp/scp-sm.c:54) [32m06/28 13:47:43.436[0m: [[33msbi[0m] [1;37mDEBUG[0m: ogs_sbi_nf_state_registered(): OGS_EVENT_NAME_SBI_TIMER (../lib/sbi/nf-sm.c:358) [32m06/28 13:47:43.437[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] http://127.0.0.10:7777/nnrf-nfm/v1/nf-instances/60e733d6-72f7-41f1-b3af-c75d... (../lib/sbi/client.c:797) [32m06/28 13:47:43.437[0m: [[33msbi[0m] [1;37mDEBUG[0m: SENDING...[100] (../lib/sbi/client.c:546) [32m06/28 13:47:43.437[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":0}] (../lib/sbi/client.c:548) [32m06/28 13:47:43.437[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM added [687] (../lib/sbi/nghttp2-server.c:1737) [32m06/28 13:47:43.437[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] /nnrf-nfm/v1/nf-instances/60e733d6-72f7-41f1-b3af-c75d1ddbd161 (../lib/sbi/nghttp2-server.c:1308) [32m06/28 13:47:43.437[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED: 100 (../lib/sbi/nghttp2-server.c:1312) [32m06/28 13:47:43.437[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/28 13:47:43.437[0m: [[33mnrf[0m] [1;37mDEBUG[0m: nrf_state_operational(): OGS_EVENT_NAME_SBI_SERVER (../src/nrf/nrf-sm.c:54) [32m06/28 13:47:43.437[0m: [[33mnrf[0m] [1;37mDEBUG[0m: nrf_nf_state_registered(): OGS_EVENT_NAME_SBI_SERVER (../src/nrf/nf-sm.c:201) [32m06/28 13:47:43.437[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [204] (../lib/sbi/nghttp2-server.c:702) [32m06/28 13:47:43.437[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [687] (../lib/sbi/nghttp2-server.c:1419) 0000: 00001b01 05000002 af89c961 97dd6d5f ...........a..m_ 0010: 4a09e532 db528200 9c502cdc 69db8d32 J..2.R...P,.i..2 0020: a62d1bff .-.. [32m06/28 13:47:43.437[0m: [[33msbi[0m] [1;37mDEBUG[0m: [204:PATCH] http://127.0.0.10:7777/nnrf-nfm/v1/nf-instances/60e733d6-72f7-41f1-b3af-c75d... (../lib/sbi/client.c:744) [32m06/28 13:47:43.437[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755) [32m06/28 13:47:43.438[0m: [[33mscp[0m] [1;37mDEBUG[0m: scp_state_operational(): OGS_EVENT_NAME_SBI_CLIENT (../src/scp/scp-sm.c:54) [32m06/28 13:47:43.438[0m: [[33msbi[0m] [1;37mDEBUG[0m: ogs_sbi_nf_state_registered(): OGS_EVENT_NAME_SBI_CLIENT (../lib/sbi/nf-sm.c:358) [1;34m[testenv] Stopping podman container[0m [0;94m[testenv] + ['podman', 'kill', 'testenv-5gc-osmocom-latest-20260628-1343-d31346c7-1'][0m MTC@15dbd88f23c1: 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' was executed successfully (exit status: 0). MTC@15dbd88f23c1: Test case TC_handover_inter_ngran_n2 started. GTP1U_EM(874)@15dbd88f23c1: Warning: sizes of 'struct sctp_event_subscribe': compile-time 14, kernel: 14 [38;5;23m20260628134743531 [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 20260628134743531 [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) 20260628134743532 [1;33mDGT[0;m [1;33mNOTICE[0;m tun0-R00000001-T0000cb97: Destroying (gtp_tunnel.c:136) 20260628134743532 [1;32mDEP[0;m [1;32mINFO[0;m 127.0.0.20:2152: Destroying (gtp_endpoint.c:249) 20260628134743532 [1;32mDTUN[0;m [1;32mINFO[0;m tun0: Releasing (tun_device.c:524) 20260628134743532 [1;32mDTUN[0;m [1;32mINFO[0;m tun0: Destroying (tun_device.c:480) TC_handover_inter_ngran_n2-NGAP0(875)@15dbd88f23c1: Warning: sizes of 'struct sctp_event_subscribe': compile-time 14, kernel: 14 TC_handover_inter_ngran_n2-NGAP0(875)@15dbd88f23c1: 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@15dbd88f23c1: Test Component 875 has requested to stop MTC. Terminating current testcase execution. TC_handover_inter_ngran_n2-NGAP0(875)@15dbd88f23c1: Final verdict of PTC: fail reason: "Could not connect NGAP socket, check your configuration" GTP1U_EM(874)@15dbd88f23c1: Final verdict of PTC: none MTC@15dbd88f23c1: Setting final verdict of the test case. MTC@15dbd88f23c1: Local verdict of MTC: none MTC@15dbd88f23c1: Local verdict of PTC GTP1U_EM(874): none (none -> none) MTC@15dbd88f23c1: Local verdict of PTC TC_handover_inter_ngran_n2-NGAP0(875): fail (none -> fail) reason: "Could not connect NGAP socket, check your configuration" MTC@15dbd88f23c1: Test case TC_handover_inter_ngran_n2 finished. Verdict: fail reason: Could not connect NGAP socket, check your configuration MTC@15dbd88f23c1: 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 fail'. (13:47:43) load average: 2.93, 2.63, 2.26 [1;31m------ C5G_Tests.TC_handover_inter_ngran_n2 fail ------[0m
Waiting for packet dumper to finish... 0 (prev_count=-1, count=208) testenv-5gc-osmocom-latest-20260628-1343-d31346c7-1 [0;94m[testenv] feed_watchdog_loop: podman container has stopped[0m https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/227/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 (642760)[0m [1;34m[testenv] Logs saved to: https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/227/artifa... [0m + RC=1 + [ 1 = 0 ] + + grep --color=always -o load.* uptime [01;31m[Kload average: 2.93, 2.63, 2.26[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.