See https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs/210/display/redirect
Changes:
------------------------------------------ [...truncated 2.98 MiB...] [32m12/18 09:30:23.290[0m: [[33msbi[0m] [1;37mDEBUG[0m: [DELETE] http://127.0.0.200:7777/nudr-dr/v1/subscription-data/imsi-999700000000000/au... (../lib/sbi/client.c:797) [32m12/18 09:30:23.290[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM added [35] (../lib/sbi/nghttp2-server.c:1600) [32m12/18 09:30:23.290[0m: [[33msbi[0m] [1;37mDEBUG[0m: [DELETE] /nudr-dr/v1/subscription-data/imsi-999700000000000/authentication-data/authentication-status (../lib/sbi/nghttp2-server.c:1215) [32m12/18 09:30:23.290[0m: [[33msock[0m] [1;37mDEBUG[0m: addr:127.0.0.20, port:7777 (../lib/core/ogs-sockaddr.c:171) [32m12/18 09:30:23.290[0m: [[33mscp[0m] [1;37mDEBUG[0m: [REF] 3 (../src/scp/sbi-path.c:463) [32m12/18 09:30:23.290[0m: [[33mscp[0m] [1;37mDEBUG[0m: CLIENT Ref [3] (../src/scp/sbi-path.c:463) [32m12/18 09:30:23.290[0m: [[33mscp[0m] [1;32mINFO[0m: Setup NF EndPoint(addr) [127.0.0.20:7777] (../src/scp/sbi-path.c:463) [32m12/18 09:30:23.290[0m: [[33msbi[0m] [1;37mDEBUG[0m: [DELETE] http://127.0.0.20:7777/nudr-dr/v1/subscription-data/imsi-999700000000000/aut... (../lib/sbi/client.c:797) [32m12/18 09:30:23.290[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM added [19] (../lib/sbi/nghttp2-server.c:1600) [32m12/18 09:30:23.290[0m: [[33msbi[0m] [1;37mDEBUG[0m: [DELETE] /nudr-dr/v1/subscription-data/imsi-999700000000000/authentication-data/authentication-status (../lib/sbi/nghttp2-server.c:1215) [32m12/18 09:30:23.290[0m: [[33mudr[0m] [1;37mDEBUG[0m: udr_state_operational(): OGS_EVENT_NAME_SBI_SERVER (../src/udr/udr-sm.c:52) [32m12/18 09:30:23.291[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [204] (../lib/sbi/nghttp2-server.c:687) [32m12/18 09:30:23.291[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [19] (../lib/sbi/nghttp2-server.c:1326) 0000: 00000301 05000000 1389c1bf ............ [32m12/18 09:30:23.291[0m: [[33msbi[0m] [1;37mDEBUG[0m: [204:DELETE] http://127.0.0.20:7777/nudr-dr/v1/subscription-data/imsi-999700000000000/aut... (../lib/sbi/client.c:744) [32m12/18 09:30:23.291[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755) [32m12/18 09:30:23.291[0m: [[33msbi[0m] [1;37mDEBUG[0m: CLIENT UnRef [3] (../lib/sbi/client.c:186) [32m12/18 09:30:23.291[0m: [[33msbi[0m] [1;37mDEBUG[0m: - addr [127.0.0.20:7777] (../lib/sbi/client.c:192) [32m12/18 09:30:23.291[0m: [[33msbi[0m] [1;37mDEBUG[0m: [UNREF] 3 (../lib/sbi/client.c:200) [32m12/18 09:30:23.291[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [204] (../lib/sbi/nghttp2-server.c:687) [32m12/18 09:30:23.291[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [35] (../lib/sbi/nghttp2-server.c:1326) 0000: 00000301 05000000 2389c3be ........#... [32m12/18 09:30:23.291[0m: [[33msbi[0m] [1;37mDEBUG[0m: [204:DELETE] http://127.0.0.200:7777/nudr-dr/v1/subscription-data/imsi-999700000000000/au... (../lib/sbi/client.c:744) [32m12/18 09:30:23.291[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755) [32m12/18 09:30:23.291[0m: [[33mudm[0m] [1;37mDEBUG[0m: udm_state_operational(): OGS_EVENT_NAME_SBI_CLIENT (../src/udm/udm-sm.c:59) [32m12/18 09:30:23.291[0m: [[33mudm[0m] [1;37mDEBUG[0m: udm_ue_state_operational(): OGS_EVENT_NAME_SBI_CLIENT (../src/udm/ue-sm.c:48) [32m12/18 09:30:23.291[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [204] (../lib/sbi/nghttp2-server.c:687) [32m12/18 09:30:23.291[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [23] (../lib/sbi/nghttp2-server.c:1326) 0000: 00000301 05000000 1789c2c0 ............ [32m12/18 09:30:23.291[0m: [[33msbi[0m] [1;37mDEBUG[0m: [204:PUT] http://127.0.0.12:7777/nudm-ueau/v1/imsi-999700000000000/auth-events (../lib/sbi/client.c:744) [32m12/18 09:30:23.291[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755) [32m12/18 09:30:23.291[0m: [[33msbi[0m] [1;37mDEBUG[0m: CLIENT UnRef [5] (../lib/sbi/client.c:186) [32m12/18 09:30:23.291[0m: [[33msbi[0m] [1;37mDEBUG[0m: - addr [127.0.0.12:7777] (../lib/sbi/client.c:192) [32m12/18 09:30:23.291[0m: [[33msbi[0m] [1;37mDEBUG[0m: [UNREF] 5 (../lib/sbi/client.c:200) [32m12/18 09:30:23.291[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [204] (../lib/sbi/nghttp2-server.c:687) [32m12/18 09:30:23.291[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [23] (../lib/sbi/nghttp2-server.c:1326) 0000: 00000301 05000000 1789c3be ............ [32m12/18 09:30:23.291[0m: [[33msbi[0m] [1;37mDEBUG[0m: [204:PUT] http://127.0.0.200:7777/nudm-ueau/v1/imsi-999700000000000/auth-events (../lib/sbi/client.c:744) [32m12/18 09:30:23.291[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755) [32m12/18 09:30:23.291[0m: [[33mausf[0m] [1;37mDEBUG[0m: ausf_state_operational(): OGS_EVENT_NAME_SBI_CLIENT (../src/ausf/ausf-sm.c:55) [32m12/18 09:30:23.291[0m: [[33mausf[0m] [1;37mDEBUG[0m: ausf_ue_state_operational(): OGS_EVENT_NAME_SBI_CLIENT (../src/ausf/ue-sm.c:65) [32m12/18 09:30:23.291[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [204] (../lib/sbi/nghttp2-server.c:687) [32m12/18 09:30:23.291[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [11] (../lib/sbi/nghttp2-server.c:1326) [32m12/18 09:30:23.291[0m: [[33mausf[0m] [1;37mDEBUG[0m: ausf_ue_state_operational(): EXIT (../src/ausf/ue-sm.c:65) [32m12/18 09:30:23.291[0m: [[33mausf[0m] [1;37mDEBUG[0m: ausf_ue_state_deleted(): ENTRY (../src/ausf/ue-sm.c:245) [32m12/18 09:30:23.291[0m: [[33mausf[0m] [1;32mINFO[0m: [imsi-999700000000000] AUSF-UE removed (../src/ausf/ausf-sm.c:378) [32m12/18 09:30:23.291[0m: [[33mausf[0m] [1;37mDEBUG[0m: ausf_ue_state_deleted(): EXIT (../src/ausf/ue-sm.c:245) [32m12/18 09:30:23.291[0m: [[33mausf[0m] [1;37mDEBUG[0m: ausf_ue_state_final(): EXIT (../src/ausf/ue-sm.c:47) [32m12/18 09:30:23.291[0m: [[33msbi[0m] [1;37mDEBUG[0m: CLIENT UnRef [3] (../lib/sbi/client.c:186) [32m12/18 09:30:23.291[0m: [[33msbi[0m] [1;37mDEBUG[0m: - addr [127.0.0.12:7777] (../lib/sbi/client.c:192) [32m12/18 09:30:23.291[0m: [[33msbi[0m] [1;37mDEBUG[0m: [UNREF] 3 (../lib/sbi/client.c:200) [32m12/18 09:30:23.291[0m: [[33msbi[0m] [1;37mDEBUG[0m: [204:DELETE] http://127.0.0.11:7777/nausf-auth/v1/ue-authentications/1/5g-aka-confirmatio... (../lib/sbi/client.c:744) [32m12/18 09:30:23.291[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755) [32m12/18 09:30:23.291[0m: [[33msbi[0m] [1;37mDEBUG[0m: CLIENT UnRef [3] (../lib/sbi/client.c:186) [32m12/18 09:30:23.291[0m: [[33msbi[0m] [1;37mDEBUG[0m: - addr [127.0.0.11:7777] (../lib/sbi/client.c:192) [32m12/18 09:30:23.291[0m: [[33msbi[0m] [1;37mDEBUG[0m: [UNREF] 3 (../lib/sbi/client.c:200) [32m12/18 09:30:23.291[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [204] (../lib/sbi/nghttp2-server.c:687) 0000: 00000301 05000000 0b89c4c0 ............ [32m12/18 09:30:23.291[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [57] (../lib/sbi/nghttp2-server.c:1326) 0000: 00000301 05000000 3989c3be ........9... [32m12/18 09:30:23.291[0m: [[33msbi[0m] [1;37mDEBUG[0m: [204:DELETE] http://127.0.0.200:7777/nausf-auth/v1/ue-authentications/1/5g-aka-confirmati... (../lib/sbi/client.c:744) [32m12/18 09:30:23.291[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755) [32m12/18 09:30:23.291[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): OGS_EVENT_NAME_SBI_CLIENT (../src/amf/amf-sm.c:86) [32m12/18 09:30:23.291[0m: [[33mgmm[0m] [1;37mDEBUG[0m: gmm_state_de_registered(): OGS_EVENT_NAME_SBI_CLIENT (../src/amf/gmm-sm.c:94) [32m12/18 09:30:23.291[0m: [[33msbi[0m] [1;37mDEBUG[0m: OGS_SBI_GET_NF_INSTANCE [nf_instance:0x561d5a972720,service_name:npcf-am-policy-control] (../lib/sbi/path.c:298) [32m12/18 09:30:23.291[0m: [[33msock[0m] [1;37mDEBUG[0m: addr:127.0.0.13, port:7777 (../lib/core/ogs-sockaddr.c:171) [32m12/18 09:30:23.291[0m: [[33msbi[0m] [1;37mDEBUG[0m: apiroot [http://127.0.0.13:7777] (../lib/sbi/path.c:356) [32m12/18 09:30:23.291[0m: [[33msbi[0m] [1;37mDEBUG[0m: [DELETE] http://127.0.0.200:7777/npcf-am-policy-control/v1/policies/1 (../lib/sbi/client.c:842) [32m12/18 09:30:23.291[0m: [[33msbi[0m] [1;37mDEBUG[0m: [DELETE] http://127.0.0.200:7777/npcf-am-policy-control/v1/policies/1 (../lib/sbi/client.c:797) [32m12/18 09:30:23.291[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM added [59] (../lib/sbi/nghttp2-server.c:1600) [32m12/18 09:30:23.291[0m: [[33msbi[0m] [1;37mDEBUG[0m: [DELETE] /npcf-am-policy-control/v1/policies/1 (../lib/sbi/nghttp2-server.c:1215) [32m12/18 09:30:23.291[0m: [[33msock[0m] [1;37mDEBUG[0m: addr:127.0.0.13, port:7777 (../lib/core/ogs-sockaddr.c:171) [32m12/18 09:30:23.291[0m: [[33mscp[0m] [1;37mDEBUG[0m: [REF] 5 (../src/scp/sbi-path.c:463) [32m12/18 09:30:23.291[0m: [[33mscp[0m] [1;37mDEBUG[0m: CLIENT Ref [5] (../src/scp/sbi-path.c:463) [32m12/18 09:30:23.291[0m: [[33mscp[0m] [1;32mINFO[0m: Setup NF EndPoint(addr) [127.0.0.13:7777] (../src/scp/sbi-path.c:463) [32m12/18 09:30:23.291[0m: [[33msbi[0m] [1;37mDEBUG[0m: [DELETE] http://127.0.0.13:7777/npcf-am-policy-control/v1/policies/1 (../lib/sbi/client.c:797) [32m12/18 09:30:23.292[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM added [7] (../lib/sbi/nghttp2-server.c:1600) [32m12/18 09:30:23.292[0m: [[33msbi[0m] [1;37mDEBUG[0m: [DELETE] /npcf-am-policy-control/v1/policies/1 (../lib/sbi/nghttp2-server.c:1215) [32m12/18 09:30:23.292[0m: [[33mpcf[0m] [1;37mDEBUG[0m: pcf_state_operational(): OGS_EVENT_NAME_SBI_SERVER (../src/pcf/pcf-sm.c:63) [32m12/18 09:30:23.292[0m: [[33mpcf[0m] [1;37mDEBUG[0m: pcf_am_state_operational(): OGS_EVENT_NAME_SBI_SERVER (../src/pcf/am-sm.c:49) [32m12/18 09:30:23.292[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [204] (../lib/sbi/nghttp2-server.c:687) [32m12/18 09:30:23.292[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [7] (../lib/sbi/nghttp2-server.c:1326) [32m12/18 09:30:23.292[0m: [[33mpcf[0m] [1;37mDEBUG[0m: pcf_am_state_operational(): EXIT (../src/pcf/am-sm.c:49) [32m12/18 09:30:23.292[0m: [[33mpcf[0m] [1;37mDEBUG[0m: pcf_am_state_deleted(): ENTRY (../src/pcf/am-sm.c:180) [32m12/18 09:30:23.292[0m: [[33mpcf[0m] [1;37mDEBUG[0m: [imsi-999700000000000] PCF-AM removed (../src/pcf/pcf-sm.c:182) [32m12/18 09:30:23.292[0m: [[33mpcf[0m] [1;37mDEBUG[0m: pcf_am_state_deleted(): EXIT (../src/pcf/am-sm.c:180) [32m12/18 09:30:23.292[0m: [[33msbi[0m] [1;37mDEBUG[0m: CLIENT UnRef [2] (../lib/sbi/client.c:186) [32m12/18 09:30:23.292[0m: [[33msbi[0m] [1;37mDEBUG[0m: - addr [127.0.0.5:7777] (../lib/sbi/client.c:192) [32m12/18 09:30:23.292[0m: [[33msbi[0m] [1;37mDEBUG[0m: [UNREF] 2 (../lib/sbi/client.c:200) 0000: 00000301 05000000 0789c2bf ............ [32m12/18 09:30:23.292[0m: [[33msbi[0m] [1;37mDEBUG[0m: [204:DELETE] http://127.0.0.13:7777/npcf-am-policy-control/v1/policies/1 (../lib/sbi/client.c:744) [32m12/18 09:30:23.292[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755) [32m12/18 09:30:23.292[0m: [[33msbi[0m] [1;37mDEBUG[0m: CLIENT UnRef [5] (../lib/sbi/client.c:186) [32m12/18 09:30:23.292[0m: [[33msbi[0m] [1;37mDEBUG[0m: - addr [127.0.0.13:7777] (../lib/sbi/client.c:192) [32m12/18 09:30:23.292[0m: [[33msbi[0m] [1;37mDEBUG[0m: [UNREF] 5 (../lib/sbi/client.c:200) [32m12/18 09:30:23.292[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [204] (../lib/sbi/nghttp2-server.c:687) [32m12/18 09:30:23.292[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [59] (../lib/sbi/nghttp2-server.c:1326) 0000: 00000301 05000000 3b89c3be ........;... [32m12/18 09:30:23.292[0m: [[33msbi[0m] [1;37mDEBUG[0m: [204:DELETE] http://127.0.0.200:7777/npcf-am-policy-control/v1/policies/1 (../lib/sbi/client.c:744) [32m12/18 09:30:23.292[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755) [32m12/18 09:30:23.292[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): OGS_EVENT_NAME_SBI_CLIENT (../src/amf/amf-sm.c:86) [32m12/18 09:30:23.292[0m: [[33mgmm[0m] [1;37mDEBUG[0m: gmm_state_de_registered(): OGS_EVENT_NAME_SBI_CLIENT (../src/amf/gmm-sm.c:94) [32m12/18 09:30:23.292[0m: [[33mamf[0m] [1;37mDEBUG[0m: [imsi-999700000000000] De-registration accept (../src/amf/nas-path.c:349) [32m12/18 09:30:23.292[0m: [[33mamf[0m] [1;37mDEBUG[0m: DownlinkNASTransport (../src/amf/ngap-build.c:385) [32m12/18 09:30:23.292[0m: [[33mamf[0m] [1;37mDEBUG[0m: RAN_UE_NGAP_ID[0] AMF_UE_NGAP_ID[1] (../src/amf/ngap-build.c:428) [32m12/18 09:30:23.292[0m: [[33mamf[0m] [1;37mDEBUG[0m: IP[127.0.0.202] RAN_ID[0] (../src/amf/ngap-path.c:64) [32m12/18 09:30:23.292[0m: [[33mamf[0m] [1;37mDEBUG[0m: UEContextReleaseCommand (../src/amf/ngap-path.c:431) [32m12/18 09:30:23.292[0m: [[33mamf[0m] [1;37mDEBUG[0m: RAN_UE_NGAP_ID[0] AMF_UE_NGAP_ID[1] (../src/amf/ngap-path.c:432) [32m12/18 09:30:23.292[0m: [[33mamf[0m] [1;37mDEBUG[0m: Group[3] Cause[2] Action[2] Duration[0] (../src/amf/ngap-path.c:439) [32m12/18 09:30:23.292[0m: [[33mamf[0m] [1;37mDEBUG[0m: UEContextReleaseCommand (../src/amf/ngap-build.c:1292) [32m12/18 09:30:23.292[0m: [[33mamf[0m] [1;37mDEBUG[0m: IP[127.0.0.202] RAN_ID[0] (../src/amf/ngap-path.c:64) TC_ng_register0(11)@1aa284f88586: Final verdict of PTC: none [32m12/18 09:30:23.295[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_MESSAGE (../src/amf/amf-sm.c:86) [32m12/18 09:30:23.295[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_operational(): AMF_EVENT_NGAP_MESSAGE (../src/amf/ngap-sm.c:55) [32m12/18 09:30:23.295[0m: [[33mamf[0m] [1;37mDEBUG[0m: UEContextReleaseComplete (../src/amf/ngap-handler.c:1695) [32m12/18 09:30:23.295[0m: [[33mamf[0m] [1;37mDEBUG[0m: IP[127.0.0.202] RAN_ID[0] (../src/amf/ngap-handler.c:1711) [32m12/18 09:30:23.295[0m: [[33mamf[0m] [1;32mINFO[0m: UE Context Release [Action:2] (../src/amf/ngap-handler.c:1757) [32m12/18 09:30:23.295[0m: [[33mamf[0m] [1;32mINFO[0m: RAN_UE_NGAP_ID[0] AMF_UE_NGAP_ID[1] (../src/amf/ngap-handler.c:1758) [32m12/18 09:30:23.295[0m: [[33mamf[0m] [1;32mINFO[0m: SUCI[suci-0-999-70-1234-0-1-0000000000] (../src/amf/ngap-handler.c:1762) [32m12/18 09:30:23.295[0m: [[33mamf[0m] [1;37mDEBUG[0m: Action: NG normal release (../src/amf/ngap-handler.c:1781) [32m12/18 09:30:23.295[0m: [[33mamf[0m] [1;32mINFO[0m: [Removed] Number of gNB-UEs is now 0 (../src/amf/context.c:2784) TC_ng_register-NGAP0(10)@1aa284f88586: Final verdict of PTC: none [38;5;23m20251218093023296 [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:360)[0;m 20251218093023296 [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(9)@1aa284f88586: Final verdict of PTC: none MTC@1aa284f88586: Setting final verdict of the test case. MTC@1aa284f88586: Local verdict of MTC: pass MTC@1aa284f88586: Local verdict of PTC GTP1U_EM(9): none (pass -> pass) MTC@1aa284f88586: Local verdict of PTC TC_ng_register-NGAP0(10): none (pass -> pass) MTC@1aa284f88586: Local verdict of PTC TC_ng_register0(11): none (pass -> pass) MTC@1aa284f88586: Test case TC_ng_register finished. Verdict: pass MTC@1aa284f88586: Starting external command `https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs/ws/ttcn3-tcpdump-stop.sh C5G_Tests.TC_ng_register pass'. Thu Dec 18 09:30:23 UTC 2025 [1;32m====== C5G_Tests.TC_ng_register pass ======[0m
Waiting for packet dumper to finish... 0 (prev_count=-1, count=50664) [32m12/18 09:30:23.496[0m: [[33mamf[0m] [1;37mDEBUG[0m: SCTP_SHUTDOWN_EVENT:[T:32773, F:0x0, L:12] (../src/amf/ngap-sctp.c:193) [32m12/18 09:30:23.496[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_CONNREFUSED (../src/amf/amf-sm.c:86) [32m12/18 09:30:23.496[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2[127.0.0.202] connection refused!!! (../src/amf/amf-sm.c:997) [32m12/18 09:30:23.496[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_operational(): EXIT (../src/amf/ngap-sm.c:55) [32m12/18 09:30:23.496[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_final(): EXIT (../src/amf/ngap-sm.c:37) [32m12/18 09:30:23.496[0m: [[33mamf[0m] [1;32mINFO[0m: [Removed] Number of gNBs is now 0 (../src/amf/context.c:1305) Waiting for packet dumper to finish... 1 (prev_count=50664, count=116716) [32m12/18 09:30:24.735[0m: [[33mupf[0m] [1;37mDEBUG[0m: upf_state_operational(): UPF_EVT_N4_TIMER (../src/upf/upf-sm.c:51) [32m12/18 09:30:24.735[0m: [[33mupf[0m] [1;37mDEBUG[0m: upf_pfcp_state_associated(): UPF_EVT_N4_TIMER (../src/upf/pfcp-sm.c:161) [32m12/18 09:30:24.735[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2] LOCAL Create peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:111) [32m12/18 09:30:24.735[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: Heartbeat Request (../lib/pfcp/build.c:28) [32m12/18 09:30:24.735[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2] LOCAL UPD TX-1 peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:191) [32m12/18 09:30:24.735[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2] LOCAL Commit peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:460) [32m12/18 09:30:24.736[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) [32m12/18 09:30:24.736[0m: [[33msmf[0m] [1;37mDEBUG[0m: Found PFCP-Node: addr_list [127.0.0.7]:8805 (../src/smf/pfcp-path.c:192) [32m12/18 09:30:24.736[0m: [[33msmf[0m] [1;37mDEBUG[0m: Merged PFCP-Node: addr_list [127.0.0.7]:8805 (../src/smf/pfcp-path.c:198) [32m12/18 09:30:24.736[0m: [[33msmf[0m] [1;37mDEBUG[0m: smf_state_operational(): SMF_EVT_N4_MESSAGE (../src/smf/smf-sm.c:93) [32m12/18 09:30:24.736[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2] Cannot find new type 1 from PFCP peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:727) [32m12/18 09:30:24.736[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2] REMOTE Create peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:156) [32m12/18 09:30:24.736[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2] REMOTE Receive peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:733) [32m12/18 09:30:24.736[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2] REMOTE UPD RX-1 peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:289) [32m12/18 09:30:24.736[0m: [[33msmf[0m] [1;37mDEBUG[0m: smf_pfcp_state_associated(): SMF_EVT_N4_MESSAGE (../src/smf/pfcp-sm.c:182) [32m12/18 09:30:24.736[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: Heartbeat Response (../lib/pfcp/build.c:56) [32m12/18 09:30:24.736[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2] REMOTE UPD TX-2 peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:191) [32m12/18 09:30:24.736[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2] REMOTE Commit peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:460) [32m12/18 09:30:24.736[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2] REMOTE Delete peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:791) [32m12/18 09:30:24.736[0m: [[33msmf[0m] [1;37mDEBUG[0m: smf_state_operational(): SMF_EVT_N4_TIMER (../src/smf/smf-sm.c:93) [32m12/18 09:30:24.736[0m: [[33msmf[0m] [1;37mDEBUG[0m: smf_pfcp_state_associated(): SMF_EVT_N4_TIMER (../src/smf/pfcp-sm.c:182) [32m12/18 09:30:24.736[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [3] LOCAL Create peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:111) [32m12/18 09:30:24.736[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: Heartbeat Request (../lib/pfcp/build.c:28) [32m12/18 09:30:24.736[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [3] LOCAL UPD TX-1 peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:191) [32m12/18 09:30:24.736[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [3] LOCAL Commit peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:460) [32m12/18 09:30:24.736[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) [32m12/18 09:30:24.736[0m: [[33mupf[0m] [1;37mDEBUG[0m: Found PFCP-Node: addr_list [127.0.0.4]:8805 (../src/upf/pfcp-path.c:155) [32m12/18 09:30:24.736[0m: [[33mupf[0m] [1;37mDEBUG[0m: Merged PFCP-Node: addr_list [127.0.0.4]:8805 (../src/upf/pfcp-path.c:161) [32m12/18 09:30:24.736[0m: [[33mupf[0m] [1;37mDEBUG[0m: upf_state_operational(): UPF_EVT_N4_MESSAGE (../src/upf/upf-sm.c:51) [32m12/18 09:30:24.736[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2] LOCAL Find peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:718) [32m12/18 09:30:24.736[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2] LOCAL Receive peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:733) [32m12/18 09:30:24.736[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2] LOCAL UPD RX-2 peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:289) [32m12/18 09:30:24.736[0m: [[33mupf[0m] [1;37mDEBUG[0m: upf_pfcp_state_associated(): UPF_EVT_N4_MESSAGE (../src/upf/pfcp-sm.c:161) [32m12/18 09:30:24.736[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2] LOCAL Commit peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:460) [32m12/18 09:30:24.736[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [2] LOCAL Delete peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:791) [32m12/18 09:30:24.736[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) [32m12/18 09:30:24.736[0m: [[33mupf[0m] [1;37mDEBUG[0m: Found PFCP-Node: addr_list [127.0.0.4]:8805 (../src/upf/pfcp-path.c:155) [32m12/18 09:30:24.736[0m: [[33mupf[0m] [1;37mDEBUG[0m: Merged PFCP-Node: addr_list [127.0.0.4]:8805 (../src/upf/pfcp-path.c:161) [32m12/18 09:30:24.736[0m: [[33mupf[0m] [1;37mDEBUG[0m: upf_state_operational(): UPF_EVT_N4_MESSAGE (../src/upf/upf-sm.c:51) [32m12/18 09:30:24.736[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [3] Cannot find new type 1 from PFCP peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:727) [32m12/18 09:30:24.736[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [3] REMOTE Create peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:156) [32m12/18 09:30:24.736[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [3] REMOTE Receive peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:733) [32m12/18 09:30:24.736[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [3] REMOTE UPD RX-1 peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:289) [32m12/18 09:30:24.736[0m: [[33mupf[0m] [1;37mDEBUG[0m: upf_pfcp_state_associated(): UPF_EVT_N4_MESSAGE (../src/upf/pfcp-sm.c:161) [32m12/18 09:30:24.736[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: Heartbeat Response (../lib/pfcp/build.c:56) [32m12/18 09:30:24.736[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [3] REMOTE UPD TX-2 peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:191) [32m12/18 09:30:24.736[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [3] REMOTE Commit peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:460) [32m12/18 09:30:24.736[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [3] REMOTE Delete peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:791) [32m12/18 09:30:24.736[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) [32m12/18 09:30:24.736[0m: [[33msmf[0m] [1;37mDEBUG[0m: Found PFCP-Node: addr_list [127.0.0.7]:8805 (../src/smf/pfcp-path.c:192) [32m12/18 09:30:24.736[0m: [[33msmf[0m] [1;37mDEBUG[0m: Merged PFCP-Node: addr_list [127.0.0.7]:8805 (../src/smf/pfcp-path.c:198) [32m12/18 09:30:24.736[0m: [[33msmf[0m] [1;37mDEBUG[0m: smf_state_operational(): SMF_EVT_N4_MESSAGE (../src/smf/smf-sm.c:93) [32m12/18 09:30:24.736[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [3] LOCAL Find peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:718) [32m12/18 09:30:24.736[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [3] LOCAL Receive peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:733) [32m12/18 09:30:24.736[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [3] LOCAL UPD RX-2 peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:289) [32m12/18 09:30:24.736[0m: [[33msmf[0m] [1;37mDEBUG[0m: smf_pfcp_state_associated(): SMF_EVT_N4_MESSAGE (../src/smf/pfcp-sm.c:182) [32m12/18 09:30:24.736[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [3] LOCAL Commit peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:460) [32m12/18 09:30:24.736[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [3] LOCAL Delete peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:791) MTC@1aa284f88586: External command `https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs/ws/ttcn3-tcpdump-stop.sh C5G_Tests.TC_ng_register pass' was executed successfully (exit status: 0). MTC@1aa284f88586: Starting external command `https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs/ws/ttcn3-tcpdump-start.sh C5G_Tests.TC_periodic_registration_updating'. ------ C5G_Tests.TC_periodic_registration_updating ------ Thu Dec 18 09:30:25 UTC 2025 /usr/bin/dumpcap -q -s 1520 -n -i any -w "https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs/210/artifact/logs/testsuite/C5G_Tests.TC_periodic_registration_updating.pcap" >https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs/210/artifact/logs/testsuite/C5G_Tests.TC_periodic_registration_updating.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/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/ws/_testenv/data/scripts:https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs/ws/_testenv/data/scripts/qemu:https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs/ws/5gc:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-e', 'PYTHONUNBUFFERED=1', '-t', 'testenv-5gc-osmocom-nightly-20251218-0929-7f673bde-0', 'sh', '-c', 'echo; gdb --batch /usr/bin/open5gs-seppd https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs/210/artifact/logs/sepp1/core -ex bt | tee https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs/210/artifact/logs/sepp1/core.backtrace; echo'][0m
[New LWP 13732] [New LWP 13730] [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-seppd -c open5gs-sepp1.yaml'. Program terminated with signal SIGABRT, Aborted.
#0 __pthread_kill_implementation (threadid=<optimized out>, signo=signo@entry=6, no_tid=no_tid@entry=0) at ./nptl/pthread_kill.c:44 warning: 44 ./nptl/pthread_kill.c: No such file or directory [Current thread is 1 (Thread 0x7fab346306c0 (LWP 13732))] #0 __pthread_kill_implementation (threadid=<optimized out>, signo=signo@entry=6, no_tid=no_tid@entry=0) at ./nptl/pthread_kill.c:44 #1 0x00007fab3789e9ff in __pthread_kill_internal (threadid=<optimized out>, signo=6) at ./nptl/pthread_kill.c:89 #2 0x00007fab37849cc2 in __GI_raise (sig=sig@entry=6) at ../sysdeps/posix/raise.c:26 #3 0x00007fab378324ac in __GI_abort () at ./stdlib/abort.c:73 #4 0x00007fab38105ecd in ogs_abort () at ../lib/core/ogs-abort.c:47 #5 0x0000562812efb547 in sepp_state_operational (s=s@entry=0x7fab3462fc20, e=e@entry=0x7fab2c00ce60) at ../src/sepp/sepp-sm.c:67 #6 0x00007fab38116ca4 in ogs_fsm_dispatch (fsm=0x7fab3462fc20, event=0x7fab2c00ce60) at ../lib/core/ogs-fsm.c:127 #7 0x0000562812ef5179 in sepp_main (data=<optimized out>) at ../src/sepp/init.c:141 #8 0x00007fab3810bf45 in thread_worker (arg=0x562819eea300) at ../lib/core/ogs-thread.c:67 #9 0x00007fab3789cb7b in start_thread (arg=<optimized out>) at ./nptl/pthread_create.c:448 #10 0x00007fab3791a7b8 in __GI___clone3 () at ../sysdeps/unix/sysv/linux/x86_64/clone3.S:78
[1;34m[testenv] Stopping podman container[0m [0;94m[testenv] + ['podman', 'kill', 'testenv-5gc-osmocom-nightly-20251218-0929-7f673bde-0'][0m testenv-5gc-osmocom-nightly-20251218-0929-7f673bde-0 https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs/210/artifact/logs/sepp1/core: ELF 64-bit LSB core file, x86-64, version 1 (SYSV), SVR4-style, from 'open5gs-seppd -c open5gs-sepp1.yaml', real uid: 0, effective uid: 0, real gid: 0, effective gid: 0, execfn: '/usr/bin/open5gs-seppd', platform: 'x86_64' [0;94m[testenv] Skipping clean up scripts, podman container has already stopped[0m [1;34m[testenv] Stopping testsuite (759636)[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/210/artifact/logs... [0m + RC=1 + [ 1 = 0 ] + grep --color=always -o load.* + uptime [01;31m[Kload average: 12.83, 11.86, 9.79[m[K + exit 1 Build step 'Execute shell' marked build as failure Recording test results [Checks API] No suitable checks publisher found.