See https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/295/display/redirect?page=changes
Changes:
[Pau Espin Pedrol] stp: m3ua: Fix array size
[Oliver Smith] deps/Makefile: error if repo _commit is missing
[Oliver Smith] sgsn: fix running against current stable osmo-sgsn
------------------------------------------ [...truncated 4.30 MiB...] [32m09/03 13:44:23.478[0m: [[33msbi[0m] [1;37mDEBUG[0m: - addr [127.0.0.12:7777] (../lib/sbi/client.c:192) [32m09/03 13:44:23.478[0m: [[33msbi[0m] [1;37mDEBUG[0m: [UNREF] 5 (../lib/sbi/client.c:200) [32m09/03 13:44:23.478[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [200] (../lib/sbi/nghttp2-server.c:702) [32m09/03 13:44:23.478[0m: [[33msbi[0m] [1;37mDEBUG[0m: SENDING...: 148 (../lib/sbi/nghttp2-server.c:710) [32m09/03 13:44:23.478[0m: [[33msbi[0m] [1;37mDEBUG[0m: {"smfInstanceId":"7ec4ac64-a79d-41f1-9adb-2b09d232eabb","pduSessionId":1,"singleNssai":{"sst":1},"dnn":"internet","plmnId":{"mcc":"999","mnc":"70"}} (../lib/sbi/nghttp2-server.c:711) [32m09/03 13:44:23.478[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [71] (../lib/sbi/nghttp2-server.c:1419) MTC@5c661f82f7cb: 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_pdu_sess_est_always_on fail'. 0000: 00000a01 04000000 4788c8be 0f0d0331 ........G......1 0010: 3438c6 48. 0000: 00009400 01000000 477b2273 6d66496e ........G{"smfIn 0010: 7374616e 63654964 223a2237 65633461 stanceId":"7ec4a 0020: 6336342d 61373964 2d343166 312d3961 c64-a79d-41f1-9a 0030: 64622d32 62303964 32333265 61626222 db-2b09d232eabb" 0040: 2c227064 75536573 73696f6e 4964223a ,"pduSessionId": 0050: 312c2273 696e676c 654e7373 6169223a 1,"singleNssai": 0060: 7b227373 74223a31 7d2c2264 6e6e223a {"sst":1},"dnn": 0070: 22696e74 65726e65 74222c22 706c6d6e "internet","plmn 0080: 4964223a 7b226d63 63223a22 39393922 Id":{"mcc":"999" 0090: 2c226d6e 63223a22 3730227d 7d ,"mnc":"70"}} [32m09/03 13:44:23.479[0m: [[33msbi[0m] [1;37mDEBUG[0m: [200:PUT] http://127.0.0.200:7777/nudm-uecm/v1/imsi-999700000000000/registrations/smf-... (../lib/sbi/client.c:744) [32m09/03 13:44:23.479[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[148] (../lib/sbi/client.c:755) [32m09/03 13:44:23.479[0m: [[33msbi[0m] [1;37mDEBUG[0m: {"smfInstanceId":"7ec4ac64-a79d-41f1-9adb-2b09d232eabb","pduSessionId":1,"singleNssai":{"sst":1},"dnn":"internet","plmnId":{"mcc":"999","mnc":"70"}} (../lib/sbi/client.c:758) [32m09/03 13:44:23.479[0m: [[33msmf[0m] [1;37mDEBUG[0m: smf_state_operational(): OGS_EVENT_NAME_SBI_CLIENT (../src/smf/smf-sm.c:94) [32m09/03 13:44:23.479[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [204] (../lib/sbi/nghttp2-server.c:702) [32m09/03 13:44:23.479[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [35] (../lib/sbi/nghttp2-server.c:1419) 0000: 00000301 05000000 2389cabe ........#... [32m09/03 13:44:23.479[0m: [[33msbi[0m] [1;37mDEBUG[0m: [204:POST] http://127.0.0.4:7777/nsmf-pdusession/v1/sm-contexts/3/modify (../lib/sbi/client.c:744) [32m09/03 13:44:23.479[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755) [32m09/03 13:44:23.479[0m: [[33msbi[0m] [1;37mDEBUG[0m: CLIENT UnRef [3] (../lib/sbi/client.c:186) [32m09/03 13:44:23.479[0m: [[33msbi[0m] [1;37mDEBUG[0m: - addr [127.0.0.4:7777] (../lib/sbi/client.c:192) [32m09/03 13:44:23.479[0m: [[33msbi[0m] [1;37mDEBUG[0m: [UNREF] 3 (../lib/sbi/client.c:200) [32m09/03 13:44:23.479[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [204] (../lib/sbi/nghttp2-server.c:702) [32m09/03 13:44:23.479[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [185] (../lib/sbi/nghttp2-server.c:1419) 0000: 00000301 05000000 b989cfbe ............ [32m09/03 13:44:23.480[0m: [[33msbi[0m] [1;37mDEBUG[0m: [204:POST] http://127.0.0.200:7777/nsmf-pdusession/v1/sm-contexts/3/modify (../lib/sbi/client.c:744) [32m09/03 13:44:23.480[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755) [32m09/03 13:44:23.480[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): OGS_EVENT_NAME_SBI_CLIENT (../src/amf/amf-sm.c:83) [32m09/03 13:44:23.480[0m: [[33mamf[0m] [1;32mINFO[0m: [imsi-999700000000000:1:11][0:0:NULL] /nsmf-pdusession/v1/sm-contexts/{smContextRef}/modify (../src/amf/nsmf-handler.c:983) (13:44:23) load average: 3.77, 2.43, 2.22 [1;31m------ C5G_Tests.TC_pdu_sess_est_always_on fail ------[0m
Waiting for packet dumper to finish... 0 (prev_count=-1, count=4952) [32m09/03 13:44:23.676[0m: [[33mamf[0m] [1;37mDEBUG[0m: SCTP_SHUTDOWN_EVENT:[T:32773, F:0x0, L:12] (../src/amf/ngap-sctp.c:193) [32m09/03 13:44:23.676[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_CONNREFUSED (../src/amf/amf-sm.c:83) [32m09/03 13:44:23.676[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2[127.0.0.202] connection refused!!! (../src/amf/amf-sm.c:878) [32m09/03 13:44:23.676[0m: [[33msbi[0m] [1;37mDEBUG[0m: OGS_SBI_GET_NF_INSTANCE [nf_instance:0x560b35b1c1c8,service_name:nsmf-pdusession] (../lib/sbi/path.c:340) [32m09/03 13:44:23.676[0m: [[33msock[0m] [1;37mDEBUG[0m: addr:127.0.0.4, port:7777 (../lib/core/ogs-sockaddr.c:171) [32m09/03 13:44:23.676[0m: [[33msbi[0m] [1;37mDEBUG[0m: apiroot [http://127.0.0.4:7777] (../lib/sbi/path.c:398) [32m09/03 13:44:23.676[0m: [[33msbi[0m] [1;37mDEBUG[0m: [POST] http://127.0.0.200:7777/nsmf-pdusession/v1/sm-contexts/3/modify (../lib/sbi/client.c:847) [32m09/03 13:44:23.676[0m: [[33msbi[0m] [1;37mDEBUG[0m: [POST] http://127.0.0.200:7777/nsmf-pdusession/v1/sm-contexts/3/modify (../lib/sbi/client.c:797) [32m09/03 13:44:23.676[0m: [[33msbi[0m] [1;37mDEBUG[0m: SENDING...[290] (../lib/sbi/client.c:546) [32m09/03 13:44:23.676[0m: [[33msbi[0m] [1;37mDEBUG[0m: {"ueLocation":{"nrLocation":{"tai":{"plmnId":{"mcc":"999","mnc":"70"},"tac":"000001"},"ncgi":{"plmnId":{"mcc":"999","mnc":"70"},"nrCellId":"000000000"},"ueLocationTimestamp":"2026-09-03T13:44:23.181538Z"}},"ueTimeZone":"+00:00","upCnxState":"DEACTIVATED","ngApCause":{"group":1,"value":24}} (../lib/sbi/client.c:548) [32m09/03 13:44:23.676[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_operational(): EXIT (../src/amf/ngap-sm.c:55) [32m09/03 13:44:23.676[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_final(): EXIT (../src/amf/ngap-sm.c:37) [32m09/03 13:44:23.676[0m: [[33mamf[0m] [1;32mINFO[0m: [Removed] Number of gNBs is now 0 (../src/amf/context.c:1305) [32m09/03 13:44:23.677[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM added [187] (../lib/sbi/nghttp2-server.c:1737) [32m09/03 13:44:23.677[0m: [[33msbi[0m] [1;37mDEBUG[0m: [POST] /nsmf-pdusession/v1/sm-contexts/3/modify (../lib/sbi/nghttp2-server.c:1308) [32m09/03 13:44:23.677[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED: 290 (../lib/sbi/nghttp2-server.c:1312) [32m09/03 13:44:23.677[0m: [[33msbi[0m] [1;37mDEBUG[0m: {"ueLocation":{"nrLocation":{"tai":{"plmnId":{"mcc":"999","mnc":"70"},"tac":"000001"},"ncgi":{"plmnId":{"mcc":"999","mnc":"70"},"nrCellId":"000000000"},"ueLocationTimestamp":"2026-09-03T13:44:23.181538Z"}},"ueTimeZone":"+00:00","upCnxState":"DEACTIVATED","ngApCause":{"group":1,"value":24}} (../lib/sbi/nghttp2-server.c:1314) [32m09/03 13:44:23.677[0m: [[33msock[0m] [1;37mDEBUG[0m: addr:127.0.0.4, port:7777 (../lib/core/ogs-sockaddr.c:171) [32m09/03 13:44:23.677[0m: [[33mscp[0m] [1;37mDEBUG[0m: [REF] 3 (../src/scp/sbi-path.c:527) [32m09/03 13:44:23.677[0m: [[33mscp[0m] [1;37mDEBUG[0m: CLIENT Ref [3] (../src/scp/sbi-path.c:527) [32m09/03 13:44:23.677[0m: [[33mscp[0m] [1;32mINFO[0m: Setup NF EndPoint(addr) [127.0.0.4:7777] (../src/scp/sbi-path.c:527) [32m09/03 13:44:23.677[0m: [[33msbi[0m] [1;37mDEBUG[0m: [POST] http://127.0.0.4:7777/nsmf-pdusession/v1/sm-contexts/3/modify (../lib/sbi/client.c:797) [32m09/03 13:44:23.677[0m: [[33msbi[0m] [1;37mDEBUG[0m: SENDING...[290] (../lib/sbi/client.c:546) [32m09/03 13:44:23.677[0m: [[33msbi[0m] [1;37mDEBUG[0m: {"ueLocation":{"nrLocation":{"tai":{"plmnId":{"mcc":"999","mnc":"70"},"tac":"000001"},"ncgi":{"plmnId":{"mcc":"999","mnc":"70"},"nrCellId":"000000000"},"ueLocationTimestamp":"2026-09-03T13:44:23.181538Z"}},"ueTimeZone":"+00:00","upCnxState":"DEACTIVATED","ngApCause":{"group":1,"value":24}} (../lib/sbi/client.c:548) [32m09/03 13:44:23.677[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM added [37] (../lib/sbi/nghttp2-server.c:1737) [32m09/03 13:44:23.677[0m: [[33msbi[0m] [1;37mDEBUG[0m: [POST] /nsmf-pdusession/v1/sm-contexts/3/modify (../lib/sbi/nghttp2-server.c:1308) [32m09/03 13:44:23.677[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED: 290 (../lib/sbi/nghttp2-server.c:1312) [32m09/03 13:44:23.677[0m: [[33msbi[0m] [1;37mDEBUG[0m: {"ueLocation":{"nrLocation":{"tai":{"plmnId":{"mcc":"999","mnc":"70"},"tac":"000001"},"ncgi":{"plmnId":{"mcc":"999","mnc":"70"},"nrCellId":"000000000"},"ueLocationTimestamp":"2026-09-03T13:44:23.181538Z"}},"ueTimeZone":"+00:00","upCnxState":"DEACTIVATED","ngApCause":{"group":1,"value":24}} (../lib/sbi/nghttp2-server.c:1314) [32m09/03 13:44:23.677[0m: [[33msmf[0m] [1;37mDEBUG[0m: smf_state_operational(): OGS_EVENT_NAME_SBI_SERVER (../src/smf/smf-sm.c:94) [32m09/03 13:44:23.677[0m: [[33msmf[0m] [1;37mDEBUG[0m: smf_gsm_state_operational(): OGS_EVENT_NAME_SBI_SERVER (../src/smf/gsm-sm.c:959) [32m09/03 13:44:23.677[0m: [[33msmf[0m] [1;37mDEBUG[0m: TAI[PLMN_ID:99f907,TAC:1] (../src/smf/nsmf-handler.c:638) [32m09/03 13:44:23.677[0m: [[33msmf[0m] [1;37mDEBUG[0m: NR_CGI[PLMN_ID:99f907,CELL_ID:0x0] (../src/smf/nsmf-handler.c:640) [32m09/03 13:44:23.677[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [16] LOCAL Create peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:130) [32m09/03 13:44:23.677[0m: [[33msmf[0m] [1;37mDEBUG[0m: Session Modification Request (../src/smf/n4-build.c:204) [32m09/03 13:44:23.678[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [16] LOCAL UPD TX-52 peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:229) [32m09/03 13:44:23.678[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [16] LOCAL Commit peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:498) [32m09/03 13:44:23.678[0m: [[33mupf[0m] [1;37mDEBUG[0m: ogs_pfcp_extract_node_id() type [52] pfcp_status [2] node_id [NULL] from [127.0.0.4]:8805 (../src/upf/pfcp-path.c:101) [32m09/03 13:44:23.678[0m: [[33mupf[0m] [1;37mDEBUG[0m: Found PFCP-Node: addr_list [127.0.0.4]:8805 (../src/upf/pfcp-path.c:155) [32m09/03 13:44:23.678[0m: [[33mupf[0m] [1;37mDEBUG[0m: Merged PFCP-Node: addr_list [127.0.0.4]:8805 (../src/upf/pfcp-path.c:161) [32m09/03 13:44:23.678[0m: [[33mupf[0m] [1;37mDEBUG[0m: upf_state_operational(): UPF_EVT_N4_MESSAGE (../src/upf/upf-sm.c:51) [32m09/03 13:44:23.678[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [16] Cannot find new type 52 from PFCP peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:765) [32m09/03 13:44:23.678[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [16] REMOTE Create peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:194) [32m09/03 13:44:23.678[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [16] REMOTE Receive peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:771) [32m09/03 13:44:23.678[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [16] REMOTE UPD RX-52 peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:327) [32m09/03 13:44:23.678[0m: [[33mupf[0m] [1;37mDEBUG[0m: upf_pfcp_state_associated(): UPF_EVT_N4_MESSAGE (../src/upf/pfcp-sm.c:161) [32m09/03 13:44:23.678[0m: [[33mupf[0m] [1;37mDEBUG[0m: Session Modification Request (../src/upf/n4-handler.c:257) [32m09/03 13:44:23.678[0m: [[33mupf[0m] [1;37mDEBUG[0m: Session Modification Response (../src/upf/n4-build.c:91) [32m09/03 13:44:23.678[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [16] REMOTE UPD TX-53 peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:229) [32m09/03 13:44:23.678[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [16] REMOTE Commit peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:498) [32m09/03 13:44:23.678[0m: [[33msmf[0m] [1;37mDEBUG[0m: ogs_pfcp_extract_node_id() type [53] pfcp_status [1] node_id [NULL] from [127.0.0.7]:8805 (../src/smf/pfcp-path.c:138) [32m09/03 13:44:23.678[0m: [[33msmf[0m] [1;37mDEBUG[0m: Found PFCP-Node: addr_list [127.0.0.7]:8805 (../src/smf/pfcp-path.c:192) [32m09/03 13:44:23.678[0m: [[33msmf[0m] [1;37mDEBUG[0m: Merged PFCP-Node: addr_list [127.0.0.7]:8805 (../src/smf/pfcp-path.c:198) [32m09/03 13:44:23.678[0m: [[33msmf[0m] [1;37mDEBUG[0m: smf_state_operational(): SMF_EVT_N4_MESSAGE (../src/smf/smf-sm.c:94) [32m09/03 13:44:23.678[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [16] LOCAL Find peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:756) [32m09/03 13:44:23.678[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [16] LOCAL Receive peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:771) [32m09/03 13:44:23.678[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [16] LOCAL UPD RX-53 peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:327) [32m09/03 13:44:23.678[0m: [[33msmf[0m] [1;37mDEBUG[0m: smf_pfcp_state_associated(): SMF_EVT_N4_MESSAGE (../src/smf/pfcp-sm.c:182) [32m09/03 13:44:23.678[0m: [[33msmf[0m] [1;37mDEBUG[0m: Session Modification Response [5gc] (../src/smf/n4-handler.c:268) [32m09/03 13:44:23.678[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [16] LOCAL Commit peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:498) [32m09/03 13:44:23.678[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [16] LOCAL Delete peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:829) [32m09/03 13:44:23.678[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [200] (../lib/sbi/nghttp2-server.c:702) [32m09/03 13:44:23.678[0m: [[33msbi[0m] [1;37mDEBUG[0m: SENDING...: 28 (../lib/sbi/nghttp2-server.c:710) [32m09/03 13:44:23.678[0m: [[33msbi[0m] [1;37mDEBUG[0m: {"upCnxState":"DEACTIVATED"} (../lib/sbi/nghttp2-server.c:711) [32m09/03 13:44:23.678[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [37] (../lib/sbi/nghttp2-server.c:1419) 0000: 00000901 04000000 2588cabe 0f0d0232 ........%......2 0010: 38c4 8. 0000: 00001c00 01000000 257b2275 70436e78 ........%{"upCnx 0010: 53746174 65223a22 44454143 54495641 State":"DEACTIVA 0020: 54454422 7d TED"} [32m09/03 13:44:23.679[0m: [[33msbi[0m] [1;37mDEBUG[0m: [200:POST] http://127.0.0.4:7777/nsmf-pdusession/v1/sm-contexts/3/modify (../lib/sbi/client.c:744) [32m09/03 13:44:23.679[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[28] (../lib/sbi/client.c:755) [32m09/03 13:44:23.679[0m: [[33msbi[0m] [1;37mDEBUG[0m: {"upCnxState":"DEACTIVATED"} (../lib/sbi/client.c:758) [32m09/03 13:44:23.679[0m: [[33msbi[0m] [1;37mDEBUG[0m: CLIENT UnRef [3] (../lib/sbi/client.c:186) [32m09/03 13:44:23.679[0m: [[33msbi[0m] [1;37mDEBUG[0m: - addr [127.0.0.4:7777] (../lib/sbi/client.c:192) [32m09/03 13:44:23.679[0m: [[33msbi[0m] [1;37mDEBUG[0m: [UNREF] 3 (../lib/sbi/client.c:200) [32m09/03 13:44:23.679[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [200] (../lib/sbi/nghttp2-server.c:702) [32m09/03 13:44:23.679[0m: [[33msbi[0m] [1;37mDEBUG[0m: SENDING...: 28 (../lib/sbi/nghttp2-server.c:710) [32m09/03 13:44:23.679[0m: [[33msbi[0m] [1;37mDEBUG[0m: {"upCnxState":"DEACTIVATED"} (../lib/sbi/nghttp2-server.c:711) [32m09/03 13:44:23.679[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [187] (../lib/sbi/nghttp2-server.c:1419) 0000: 00000901 04000000 bb88cfbe 0f0d0232 ...............2 0010: 38cd 8. [32m09/03 13:44:23.679[0m: [[33msbi[0m] [1;37mDEBUG[0m: [200:POST] http://127.0.0.200:7777/nsmf-pdusession/v1/sm-contexts/3/modify (../lib/sbi/client.c:744) [32m09/03 13:44:23.679[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[28] (../lib/sbi/client.c:755) [32m09/03 13:44:23.679[0m: [[33msbi[0m] [1;37mDEBUG[0m: {"upCnxState":"DEACTIVATED"} (../lib/sbi/client.c:758) [32m09/03 13:44:23.679[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): OGS_EVENT_NAME_SBI_CLIENT (../src/amf/amf-sm.c:83) [32m09/03 13:44:23.679[0m: [[33mamf[0m] [1;37mDEBUG[0m: SUPI[imsi-999700000000000] (../src/amf/nsmf-handler.c:790) [32m09/03 13:44:23.679[0m: [[33mamf[0m] [1;32mINFO[0m: [Removed] Number of gNB-UEs is now 0 (../src/amf/context.c:2939) [32m09/03 13:44:23.679[0m: [[33mamf[0m] [1;32mINFO[0m: [imsi-999700000000000:1:51][0:0:NULL] /nsmf-pdusession/v1/sm-contexts/{smContextRef}/modify (../src/amf/nsmf-handler.c:983) 0000: 00001c00 01000000 bb7b2275 70436e78 .........{"upCnx 0010: 53746174 65223a22 44454143 54495641 State":"DEACTIVA 0020: 54454422 7d TED"} Waiting for packet dumper to finish... 1 (prev_count=4952, count=81708) MTC@5c661f82f7cb: 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_pdu_sess_est_always_on fail' was executed successfully (exit status: 0). MTC@5c661f82f7cb: 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_pdu_sess_est_pco_pcscf'. ------ C5G_Tests.TC_pdu_sess_est_pco_pcscf ------ (13:44:25) load average: 3.47, 2.39, 2.21 /usr/bin/dumpcap -q -s 1520 -n -i any -w "https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/295/artifact/logs/testsuite/C5G_Tests.TC_pdu_sess_est_pco_pcscf.pcap" >https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/295/artifact/logs/testsuite/C5G_Tests.TC_pdu_sess_est_pco_pcscf.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=https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/ws/src', '-e', 'TESTENV_INSTALL_DIR=/', '-e', 'TERM=dumb', '-e', 'PATH=https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/ws/src/osmo-ttcn3-hacks/_testenv/data/scripts:https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/ws/src/osmo-ttcn3-hacks/_testenv/data/scripts/qemu:https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/ws/src/osmo-ttcn3-hacks/5gc:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-e', 'PYTHONUNBUFFERED=1', '-t', 'testenv-5gc-osmocom-latest-20260903-1343-17198665-1', 'sh', '-c', 'echo; gdb --batch /usr/bin/open5gs-seppd https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/295/artifact/logs/sepp2/core -ex bt | tee https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/295/artifact/logs/sepp2/core.backtrace; echo'][0m [32m09/03 13:44:25.681[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [8] REMOTE Holding Timeout for step 2 type 55 peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:670) [32m09/03 13:44:25.681[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [8] REMOTE Delete Transaction for step 2 type 55 peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:682) [32m09/03 13:44:25.681[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [8] REMOTE Delete peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:829)
[32m09/03 13:44:25.892[0m: [[33mupf[0m] [1;37mDEBUG[0m: upf_state_operational(): UPF_EVT_N4_TIMER (../src/upf/upf-sm.c:51) [32m09/03 13:44:25.892[0m: [[33mupf[0m] [1;37mDEBUG[0m: upf_pfcp_state_associated(): UPF_EVT_N4_TIMER (../src/upf/pfcp-sm.c:161) [32m09/03 13:44:25.892[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [4] LOCAL Create peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:130) [32m09/03 13:44:25.892[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: Heartbeat Request (../lib/pfcp/build.c:28) [32m09/03 13:44:25.892[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [4] LOCAL UPD TX-1 peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:229) [32m09/03 13:44:25.892[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [4] LOCAL Commit peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:498) [32m09/03 13:44:25.893[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) [32m09/03 13:44:25.893[0m: [[33msmf[0m] [1;37mDEBUG[0m: Found PFCP-Node: addr_list [127.0.0.7]:8805 (../src/smf/pfcp-path.c:192) [32m09/03 13:44:25.893[0m: [[33msmf[0m] [1;37mDEBUG[0m: Merged PFCP-Node: addr_list [127.0.0.7]:8805 (../src/smf/pfcp-path.c:198) [32m09/03 13:44:25.893[0m: [[33msmf[0m] [1;37mDEBUG[0m: smf_state_operational(): SMF_EVT_N4_MESSAGE (../src/smf/smf-sm.c:94) [32m09/03 13:44:25.893[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [4] Cannot find new type 1 from PFCP peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:765) [32m09/03 13:44:25.893[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [4] REMOTE Create peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:194) [32m09/03 13:44:25.893[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [4] REMOTE Receive peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:771) [32m09/03 13:44:25.893[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [4] REMOTE UPD RX-1 peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:327) [32m09/03 13:44:25.893[0m: [[33msmf[0m] [1;37mDEBUG[0m: smf_pfcp_state_associated(): SMF_EVT_N4_MESSAGE (../src/smf/pfcp-sm.c:182) [32m09/03 13:44:25.893[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: Heartbeat Response (../lib/pfcp/build.c:56) [32m09/03 13:44:25.893[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [4] REMOTE UPD TX-2 peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:229) [32m09/03 13:44:25.893[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [4] REMOTE Commit peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:498) [32m09/03 13:44:25.893[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [4] REMOTE Delete peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:829) [32m09/03 13:44:25.893[0m: [[33msmf[0m] [1;37mDEBUG[0m: smf_state_operational(): SMF_EVT_N4_TIMER (../src/smf/smf-sm.c:94) [32m09/03 13:44:25.893[0m: [[33msmf[0m] [1;37mDEBUG[0m: smf_pfcp_state_associated(): SMF_EVT_N4_TIMER (../src/smf/pfcp-sm.c:182) [32m09/03 13:44:25.893[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [17] LOCAL Create peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:130) [32m09/03 13:44:25.893[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: Heartbeat Request (../lib/pfcp/build.c:28) [32m09/03 13:44:25.893[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [17] LOCAL UPD TX-1 peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:229) [32m09/03 13:44:25.893[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [17] LOCAL Commit peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:498) [32m09/03 13:44:25.893[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) [32m09/03 13:44:25.893[0m: [[33mupf[0m] [1;37mDEBUG[0m: Found PFCP-Node: addr_list [127.0.0.4]:8805 (../src/upf/pfcp-path.c:155) [32m09/03 13:44:25.893[0m: [[33mupf[0m] [1;37mDEBUG[0m: Merged PFCP-Node: addr_list [127.0.0.4]:8805 (../src/upf/pfcp-path.c:161) [32m09/03 13:44:25.893[0m: [[33mupf[0m] [1;37mDEBUG[0m: upf_state_operational(): UPF_EVT_N4_MESSAGE (../src/upf/upf-sm.c:51) [32m09/03 13:44:25.893[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [4] LOCAL Find peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:756) [32m09/03 13:44:25.893[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [4] LOCAL Receive peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:771) [32m09/03 13:44:25.893[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [4] LOCAL UPD RX-2 peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:327) [32m09/03 13:44:25.893[0m: [[33mupf[0m] [1;37mDEBUG[0m: upf_pfcp_state_associated(): UPF_EVT_N4_MESSAGE (../src/upf/pfcp-sm.c:161) [32m09/03 13:44:25.893[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [4] LOCAL Commit peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:498) [32m09/03 13:44:25.893[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [4] LOCAL Delete peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:829) [32m09/03 13:44:25.893[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) [32m09/03 13:44:25.893[0m: [[33mupf[0m] [1;37mDEBUG[0m: Found PFCP-Node: addr_list [127.0.0.4]:8805 (../src/upf/pfcp-path.c:155) [32m09/03 13:44:25.893[0m: [[33mupf[0m] [1;37mDEBUG[0m: Merged PFCP-Node: addr_list [127.0.0.4]:8805 (../src/upf/pfcp-path.c:161) [32m09/03 13:44:25.893[0m: [[33mupf[0m] [1;37mDEBUG[0m: upf_state_operational(): UPF_EVT_N4_MESSAGE (../src/upf/upf-sm.c:51) [32m09/03 13:44:25.893[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [17] Cannot find new type 1 from PFCP peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:765) [32m09/03 13:44:25.893[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [17] REMOTE Create peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:194) [32m09/03 13:44:25.893[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [17] REMOTE Receive peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:771) [32m09/03 13:44:25.893[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [17] REMOTE UPD RX-1 peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:327) [32m09/03 13:44:25.893[0m: [[33mupf[0m] [1;37mDEBUG[0m: upf_pfcp_state_associated(): UPF_EVT_N4_MESSAGE (../src/upf/pfcp-sm.c:161) [32m09/03 13:44:25.893[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: Heartbeat Response (../lib/pfcp/build.c:56) [32m09/03 13:44:25.893[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [17] REMOTE UPD TX-2 peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:229) [32m09/03 13:44:25.893[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [17] REMOTE Commit peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:498) [32m09/03 13:44:25.893[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [17] REMOTE Delete peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:829) [32m09/03 13:44:25.893[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) [32m09/03 13:44:25.893[0m: [[33msmf[0m] [1;37mDEBUG[0m: Found PFCP-Node: addr_list [127.0.0.7]:8805 (../src/smf/pfcp-path.c:192) [32m09/03 13:44:25.893[0m: [[33msmf[0m] [1;37mDEBUG[0m: Merged PFCP-Node: addr_list [127.0.0.7]:8805 (../src/smf/pfcp-path.c:198) [32m09/03 13:44:25.893[0m: [[33msmf[0m] [1;37mDEBUG[0m: smf_state_operational(): SMF_EVT_N4_MESSAGE (../src/smf/smf-sm.c:94) [32m09/03 13:44:25.893[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [17] LOCAL Find peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:756) [32m09/03 13:44:25.893[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [17] LOCAL Receive peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:771) [32m09/03 13:44:25.893[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [17] LOCAL UPD RX-2 peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:327) [32m09/03 13:44:25.893[0m: [[33msmf[0m] [1;37mDEBUG[0m: smf_pfcp_state_associated(): SMF_EVT_N4_MESSAGE (../src/smf/pfcp-sm.c:182) [32m09/03 13:44:25.893[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [17] LOCAL Commit peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:498) [32m09/03 13:44:25.893[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [17] LOCAL Delete peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:829) [32m09/03 13:44:25.949[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [9] REMOTE Holding Timeout for step 2 type 51 peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:670) [32m09/03 13:44:25.949[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [9] REMOTE Delete Transaction for step 2 type 51 peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:682) [32m09/03 13:44:25.949[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [9] REMOTE Delete peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:829) [New LWP 6682] [New LWP 6680] [32m09/03 13:44:25.957[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [10] REMOTE Holding Timeout for step 2 type 53 peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:670) [32m09/03 13:44:25.957[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [10] REMOTE Delete Transaction for step 2 type 53 peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:682) [32m09/03 13:44:25.957[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [10] REMOTE Delete peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:829) [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-sepp2.yaml'. Program terminated with signal SIGABRT, Aborted.
#0 __pthread_kill_implementation (threadid=<optimized out>, signo=signo@entry=6, warning: 44 ./nptl/pthread_kill.c: No such file or directory no_tid=no_tid@entry=0) at ./nptl/pthread_kill.c:44 [Current thread is 1 (Thread 0x7ff6c45076c0 (LWP 6682))] #0 __pthread_kill_implementation (threadid=<optimized out>, signo=signo@entry=6, no_tid=no_tid@entry=0) at ./nptl/pthread_kill.c:44 #1 0x00007ff6c78939ff in __pthread_kill_internal (threadid=<optimized out>, signo=6) at ./nptl/pthread_kill.c:89 #2 0x00007ff6c783ecc2 in __GI_raise (sig=sig@entry=6) at ../sysdeps/posix/raise.c:26 #3 0x00007ff6c78274ac in __GI_abort () at ./stdlib/abort.c:77 #4 0x00007ff6c8348ecd in ogs_abort () at ../lib/core/ogs-abort.c:47 #5 0x000055b89e3523a9 in sepp_state_operational (s=s@entry=0x7ff6c4506c20, e=e@entry=0x7ff6bc005c60) at ../src/sepp/sepp-sm.c:68 #6 0x00007ff6c8359e24 in ogs_fsm_dispatch (fsm=0x7ff6c4506c20, event=0x7ff6bc005c60) at ../lib/core/ogs-fsm.c:127 #7 0x000055b89e34c179 in sepp_main (data=<optimized out>) at ../src/sepp/init.c:141 #8 0x00007ff6c834f005 in thread_worker (arg=0x55b8b562d4c0) at ../lib/core/ogs-thread.c:67 #9 0x00007ff6c7891b7b in start_thread (arg=<optimized out>) at ./nptl/pthread_create.c:448 #10 0x00007ff6c790f7f8 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-latest-20260903-1343-17198665-1'][0m testenv-5gc-osmocom-latest-20260903-1343-17198665-1 https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/295/artifact/logs/sepp2/core: ELF 64-bit LSB core file, x86-64, version 1 (SYSV), SVR4-style, from 'open5gs-seppd -c open5gs-sepp2.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 (2716640)[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/295/artifa... [0m + RC=1 + [ 1 = 0 ] + grep --color=always -o load.* + uptime [01;31m[Kload average: 3.47, 2.39, 2.21[m[K + exit 1 Build step 'Execute shell' marked build as failure Recording test results [Checks API] No suitable checks publisher found.
jenkins-notifications@lists.osmocom.org
-
jenkins＠lists.osmocom.org