See https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/159/display/redirect
Changes:
------------------------------------------ [...truncated 1.23 MB...] [38;5;57m20240811120318920 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00098-NSVC00099-127_0_0_10:23000-127_0_0_103:21012)[0x55bd9e871d00]{BLOCKED}: Starting Tns-alive of 3 seconds (gprs_ns2_vc_fsm.c:177)[0;m -NSVCI99(398)@b1482cb78400: NSVC 99 State Transition: NSVC_S_WAIT_RESET (2) -> NSVC_S_ALIVE_BLOCKED (3) [38;5;63m20240811120318921 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00098)-NSVC(00099) Rx NS-ALIVE (gprs_ns2.c:1309)[0;m [38;5;57m20240811120318921 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00098-NSVC00099-127_0_0_10:23000-127_0_0_103:21012)[0x55bd9e871d00]{BLOCKED}: Received Event RX-ALIVE (gprs_ns2_vc_fsm.c:957)[0;m [38;5;63m20240811120318921 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00098)-NSVC(00099) Tx NS-ALIVE-ACK (gprs_ns2_message.c:193)[0;m [38;5;63m20240811120318921 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00098)-NSVC(00099) Rx NS-UNBLOCK (gprs_ns2.c:1309)[0;m [38;5;57m20240811120318921 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00098-NSVC00099-127_0_0_10:23000-127_0_0_103:21012)[0x55bd9e871d00]{BLOCKED}: Received Event RX-UNBLOCK (gprs_ns2_vc_fsm.c:951)[0;m [38;5;63m20240811120318921 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00098)-NSVC(00099) Tx NS-UNBLOCK-ACK (gprs_ns2_message.c:193)[0;m [38;5;57m20240811120318921 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00098-NSVC00099-127_0_0_10:23000-127_0_0_103:21012)[0x55bd9e871d00]{BLOCKED}: state_chg to UNBLOCKED (gprs_ns2_vc_fsm.c:414)[0;m [38;5;57m20240811120318921 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(00098) NS-STATUS.ind(bvci=00000): cause=NSE recovery, transfer=100, first=0, mtu=65523 (gprs_ns2.c:575)[0;m 20240811120318921 [1;33mDGPRS[0;m [1;33mNOTICE[0;m NS-E 98 became available (gprs_ns.c:43) [38;5;57m20240811120318921 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(00098)-NSVC(00099) NS-STATUS.ind(bvci=00000): cause=NSVC recovery, transfer=100, first=0, mtu=65523 (gprs_ns2.c:571)[0;m 20240811120318921 [1;33mDGPRS[0;m [1;33mNOTICE[0;m NS: indication Unknown prim 2 from NS (gprs_ns.c:57) [38;5;63m20240811120318921 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00098)-NSVC(00099) Rx NS-ALIVE-ACK (gprs_ns2.c:1309)[0;m [38;5;57m20240811120318921 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00098-NSVC00099-127_0_0_10:23000-127_0_0_103:21012)[0x55bd9e871d00]{UNBLOCKED}: Received Event RX-ALIVE_ACK (gprs_ns2_vc_fsm.c:960)[0;m -NSVCI99(398)@b1482cb78400: Rx NS-ALIVE-ACK: stopping Tns-alive; starting Tns-test -NSVCI99(398)@b1482cb78400: NSVC 99 State Transition: NSVC_S_ALIVE_BLOCKED (3) -> NSVC_S_ALIVE_UNBLOCKED (4) [38;5;57m20240811120318922 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00098-NSVC00099-127_0_0_10:23000-127_0_0_103:21012)[0x55bd9e871d00]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m SGSN_Test-Gb2-BSSGP2(395)@b1482cb78400: BVCI(0) Rx BVC-RESET-ACK SGSN_Test-Gb2-BSSGP2(395)@b1482cb78400: BVCI(0) State Transition: BVC_S_WAIT_NS_ALIVE_UNBLOCKED (1) -> BVC_S_UNBLOCKED (3) [38;5;57m20240811120318927 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00098-NSVC00099-127_0_0_10:23000-127_0_0_103:21012)[0x55bd9e871d00]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m [38;5;59m20240811120318927 [1;33mDLBSSGP[0;m[38;5;59m [1;33mNOTICE[0;m[38;5;59m Cell 262-42-13200-0 CI 20962 on BVCI 220 (gprs_bssgp.c:400)[0;m TC_hlr_location_cancel_request_withdraw-BVCI220(397)@b1482cb78400: BVCI(220) State Transition: BVC_S_BLOCKED (2) -> BVC_S_UNBLOCKED (3) [38;5;39m20240811120319729 [1;33mDLGSUP[0;m[38;5;39m [1;33mNOTICE[0;m[38;5;39m GSUP connecting to 127.0.0.103:4222 (gsup_client.c:74)[0;m [38;5;23m20240811120319729 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m 127.0.0.103:4222 connection done (ipa.c:143)[0;m [38;5;39m20240811120319729 [1;32mDLGSUP[0;m[38;5;39m [1;32mINFO[0;m[38;5;39m GSUP link to 127.0.0.103:4222 UP (gsup_client.c:151)[0;m [38;5;39m20240811120319729 [1;34mDLGSUP[0;m[38;5;39m [1;34mDEBUG[0;m[38;5;39m GSUP sending PING (gsup_client.c:288)[0;m SGSN_Test-GSUP-IPA(399)@b1482cb78400: Established a new IPA connection (conn_id=2) SGSN_Test-GSUP-IPA(399)@b1482cb78400: CCM Tx:{ msg_type := IPAC_MSGT_ID_GET (4), u := { get := { { len := 1, tag := IPAC_IDTAG_UNITNAME (1) }, { len := 1, tag := IPAC_IDTAG_UNITID (8) } } } } [38;5;23m20240811120319730 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m received ID_GET for unit ID 0/0/0 (ipaccess.c:897)[0;m SGSN_Test-GSUP-IPA(399)@b1482cb78400: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } SGSN_Test-GSUP-IPA(399)@b1482cb78400: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } [38;5;39m20240811120319730 [1;34mDLGSUP[0;m[38;5;39m [1;34mDEBUG[0;m[38;5;39m GSUP receiving PONG (gsup_client.c:225)[0;m SGSN_Test-GSUP-IPA(399)@b1482cb78400: CCM Rx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 24, tag := IPAC_IDTAG_UNITNAME (1), data := '5347534E2D30302D30302D30302D30302D30302D303000'O }, { len := 7, tag := IPAC_IDTAG_UNITID (8), data := '302F302F3000'O } } } } SGSN_Test-GSUP-IPA(399)@b1482cb78400: IPA ID RESP: { { len := 24, tag := IPAC_IDTAG_UNITNAME (1), data := '5347534E2D30302D30302D30302D30302D30302D303000'O }, { len := 7, tag := IPAC_IDTAG_UNITID (8), data := '302F302F3000'O } } SGSN_Test-GSUP-IPA(399)@b1482cb78400: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } SGSN_Test-GSUP-IPA(399)@b1482cb78400: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } TC_hlr_location_cancel_request_withdraw-BVCI196(387)@b1482cb78400: Adding Client=TC_hlr_location_cancel_request_withdraw(402), IMSI='262420000000029'H, TLLI='F7466F9E'O, index=0 SGSN_Test-GSUP(400)@b1482cb78400: Created GsupExpect[0] for "262420000000029" to be handled at TC_hlr_location_cancel_request_withdraw(402) TC_hlr_location_cancel_request_withdraw(402)@b1482cb78400: GSUP sends only 2G auth tuple{ tag := OSMO_GSUP_AUTH_TUPLE_IE (3), len := 0, val := { auth_tuple := { { tag := OSMO_GSUP_RAND_IE (32), len := 0, val := { rand := 'FBCA7884F00ADFB76B196DC7D6D01009'O } }, { tag := OSMO_GSUP_SRES_IE (33), len := 0, val := { sres := '9D06C7D4'O } }, { tag := OSMO_GSUP_KC_IE (34), len := 0, val := { kc := '239667D64039A7AF'O } } } } } [38;5;57m20240811120320777 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00096-NSVC00097-127_0_0_10:23000-127_0_0_103:21010)[0x55bd9e86de10]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m 20240811120320777 [1;33mDLLC[0;m [1;33mNOTICE[0;m LLME(ffffffff/f7466f9e){UNASSIGNED} LLC RX: unknown TLLI 0xf7466f9e, creating LLME on the fly (gprs_llc.c:552) 20240811120320777 [1;34mDLLC[0;m [1;34mDEBUG[0;m LLC SAPI=1 C U GEA0 IOV-UI=0x000000 FCS=0xf218e2 CMD=UI DATA (gprs_llc_parse.c:89) [1;33m20240811120320777 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(---/ffffffff) -> GMM ATTACH REQUEST [0;m[1;33mMI(IMSI-262420000000029) type="GPRS attach" [0;m[1;33m (gprs_gmm.c:1266)[0;m [1;33m20240811120320777 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM(gmm_fsm)[0x55bd9e873450]{Deregistered}: Allocated (fsm.c:456)[0;m [1;33m20240811120320777 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x55bd9e873580]{Init}: Allocated (fsm.c:456)[0;m [1;33m20240811120320777 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM_STATE_Gb[0x55bd9e8736b0]{Idle}: Allocated (fsm.c:456)[0;m [1;33m20240811120320777 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM_STATE_Iu[0x55bd9e8737e0]{Detached}: Allocated (fsm.c:456)[0;m [1;33m20240811120320777 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM(gmm_fsm)[0x55bd9e873450]{Deregistered}: Received Event E_GMM_COMMON_PROC_INIT_REQ (gprs_gmm.c:1144)[0;m [1;33m20240811120320777 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM(gmm_fsm)[0x55bd9e873450]{Deregistered}: state_chg to CommonProcedureInitiated (gprs_gmm_fsm.c:49)[0;m 20240811120320777 [1;33mDLLC[0;m [1;33mNOTICE[0;m LLME(ffffffff/f7466f9e){UNASSIGNED} LLGM Assign pre (f7466f9e => d6096639) (gprs_llc.c:1079) 20240811120320777 [1;33mDLLC[0;m [1;33mNOTICE[0;m LLME(f7466f9e/d6096639){ASSIGNED} LLGM Assign post (f7466f9e => d6096639) (gprs_llc.c:1125) [1;33m20240811120320778 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x55bd9e873580]{Init}: Received Event E_ATTACH_REQ_RECV (gprs_gmm.c:1370)[0;m [1;33m20240811120320778 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x55bd9e873580]{Init}: state_chg to CheckIdentity (gprs_gmm_attach.c:52)[0;m [1;33m20240811120320778 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM(262420000000029/d6096639) <- GMM IDENTITY REQUEST: mi_type=IMEI (gprs_gmm.c:431)[0;m [38;5;57m20240811120320787 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00096-NSVC00097-127_0_0_10:23000-127_0_0_103:21010)[0x55bd9e86de10]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m 20240811120320787 [1;34mDLLC[0;m [1;34mDEBUG[0;m LLC SAPI=1 C U GEA0 IOV-UI=0x000000 FCS=0x612fca CMD=UI DATA (gprs_llc_parse.c:89) [1;33m20240811120320787 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM_STATE_Gb(0)[0x55bd9e8736b0]{Idle}: Received Event E_MM_PDU_RECEPTION (gprs_gmm.c:2333)[0;m [1;33m20240811120320787 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM(262420000000029/d6096639) -> GMM IDENTITY RESPONSE: MI=IMEI-499990000000290 (gprs_gmm.c:1084)[0;m [1;33m20240811120320787 [1;33mDMM[0;m[1;33m [1;33mNOTICE[0;m[1;33m MM(262420000000029/d6096639) Stopping *inactive* MM timer 3370 (gprs_gmm.c:115)[0;m [1;33m20240811120320787 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x55bd9e873580]{CheckIdentity}: Received Event E_IDEN_RESP_RECV (gprs_gmm.c:1128)[0;m [1;33m20240811120320787 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x55bd9e873580]{CheckIdentity}: state_chg to Authenticate (gprs_gmm_attach.c:115)[0;m [1;33m20240811120320787 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x55bd9e873580]{Authenticate}: state_chg to AskVLR (gprs_gmm_attach.c:143)[0;m [1;33m20240811120320787 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM(262420000000029/d6096639) Requesting authorization (sgsn_auth.c:160)[0;m [1;33m20240811120320787 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(262420000000029/d6096639) Requesting authentication tuples (sgsn_auth.c:184)[0;m [1;33m20240811120320787 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM(262420000000029/d6096639) Requesting subscriber authentication info (gprs_subscriber.c:921)[0;m 20240811120320787 [1;32mDGPRS[0;m [1;32mINFO[0;m SUBSCR(262420000000029) requesting auth info (gprs_subscriber.c:805) 20240811120320787 [1;32mDGPRS[0;m [1;32mINFO[0;m SUBSCR(262420000000029) Sending GSUP, will send: 08 01 08 62 42 02 00 00 00 20 f9 28 01 01 (gprs_subscriber.c:210) SGSN_Test-GSUP(400)@b1482cb78400: Found GsupExpect[0] for "262420000000029" handled at TC_hlr_location_cancel_request_withdraw(402) SGSN_Test-GSUP(400)@b1482cb78400: Added IMSI table entry 0TC_hlr_location_cancel_request_withdraw(402)"262420000000029" 20240811120320790 [1;32mDGPRS[0;m [1;32mINFO[0;m SUBSCR(262420000000029) Received GSUP message OSMO_GSUP_MSGT_SEND_AUTH_INFO_RESULT (gprs_subscriber.c:723) 20240811120320790 [1;32mDGPRS[0;m [1;32mINFO[0;m SUBSCR(262420000000029) Got SendAuthenticationInfoResult, num_auth_vectors = 1 (gprs_subscriber.c:242) 20240811120320790 [1;34mDGPRS[0;m [1;34mDEBUG[0;m SUBSCR(262420000000029) Adding auth tuple, cksn = 0 (gprs_subscriber.c:255) 20240811120320790 [1;34mDGPRS[0;m [1;34mDEBUG[0;m SUBSCR(262420000000029) Updating subscriber authentication info (gprs_subscriber.c:850) [1;33m20240811120320790 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(262420000000029/d6096639) Subscriber data update (mmctx.c:430)[0;m [1;33m20240811120320790 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM(262420000000029/d6096639) Updating authorization (unknown -> authenticate) (sgsn_auth.c:217)[0;m [1;33m20240811120320790 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(262420000000029/d6096639) Got authorization update: state unknown -> authenticate (sgsn_auth.c:246)[0;m [1;33m20240811120320790 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x55bd9e873580]{AskVLR}: Received Event E_VLR_ANSWERED (gprs_gmm.c:991)[0;m [1;33m20240811120320790 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x55bd9e873580]{AskVLR}: state_chg to Authenticate (gprs_gmm_attach.c:253)[0;m [1;33m20240811120320790 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(262420000000029/d6096639) <- GMM AUTH AND CIPHERING REQ (rand = fb ca 78 84 f0 0a df b7 6b 19 6d c7 d6 d0 10 09 , mmctx_is_r99=1, vec->auth_types=0x1[0;m[1;33m) (gprs_gmm.c:486)[0;m [38;5;57m20240811120320797 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00096-NSVC00097-127_0_0_10:23000-127_0_0_103:21010)[0x55bd9e86de10]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m 20240811120320797 [1;34mDLLC[0;m [1;34mDEBUG[0;m LLC SAPI=1 C U GEA0 IOV-UI=0x000000 FCS=0xedcfd8 CMD=UI DATA (gprs_llc_parse.c:89) [1;33m20240811120320797 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM_STATE_Gb(0)[0x55bd9e8736b0]{Idle}: Received Event E_MM_PDU_RECEPTION (gprs_gmm.c:2333)[0;m [1;33m20240811120320797 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(262420000000029/d6096639) -> GMM AUTH AND CIPH RESPONSE (gprs_gmm.c:620)[0;m [1;33m20240811120320797 [1;33mDMM[0;m[1;33m [1;33mNOTICE[0;m[1;33m MM(262420000000029/d6096639) Stopping *inactive* MM timer 3360 (gprs_gmm.c:115)[0;m [1;33m20240811120320797 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM(262420000000029/d6096639) checking auth: received GSM SRES = 9d 06 c7 d4 (gprs_gmm.c:666)[0;m [1;33m20240811120320797 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x55bd9e873580]{Authenticate}: Received Event E_AUTH_RESP_RECV_SUCCESS (gprs_gmm.c:681)[0;m [1;33m20240811120320797 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM(262420000000029/d6096639) Requesting authorization (sgsn_auth.c:160)[0;m [1;33m20240811120320797 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(262420000000029/d6096639) Missing information, requesting subscriber data (sgsn_auth.c:195)[0;m [1;33m20240811120320797 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM(262420000000029/d6096639) Requesting subscriber data update (gprs_subscriber.c:894)[0;m 20240811120320797 [1;32mDGPRS[0;m [1;32mINFO[0;m SUBSCR(262420000000029) subscriber data is not available (gprs_subscriber.c:818) 20240811120320797 [1;32mDGPRS[0;m [1;32mINFO[0;m SUBSCR(262420000000029) Sending GSUP, will send: 04 01 08 62 42 02 00 00 00 20 f9 28 01 01 2a 01 01 (gprs_subscriber.c:210) [1;33m20240811120320797 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x55bd9e873580]{Authenticate}: state_chg to WaitAttachComplete (gprs_gmm_attach.c:181)[0;m [1;33m20240811120320797 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(262420000000029/d6096639) <- GMM ATTACH ACCEPT (new P-TMSI=0xd6096639) (gprs_gmm.c:300)[0;m 20240811120320799 [1;32mDGPRS[0;m [1;32mINFO[0;m SUBSCR(262420000000029) Received GSUP message OSMO_GSUP_MSGT_INSERT_DATA_REQUEST (gprs_subscriber.c:723) 20240811120320799 [1;32mDGPRS[0;m [1;32mINFO[0;m SUBSCR(262420000000029) Will set PDP info, context id = 0, APN = 2a (gprs_subscriber.c:367) 20240811120320799 [1;34mDGPRS[0;m [1;34mDEBUG[0;m SUBSCR(262420000000029) Updating subscriber data (gprs_subscriber.c:839) [1;33m20240811120320799 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(262420000000029/d6096639) Subscriber data update (mmctx.c:430)[0;m [1;33m20240811120320799 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM(262420000000029/d6096639) Updating authorization (authenticate -> accepted) (sgsn_auth.c:217)[0;m [1;33m20240811120320799 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(262420000000029/d6096639) Got authorization update: state authenticate -> accepted (sgsn_auth.c:246)[0;m [1;33m20240811120320799 [1;33mDMM[0;m[1;33m [1;33mNOTICE[0;m[1;33m MM(262420000000029/d6096639) Authorized, continuing procedure, IMSI=262420000000029 (gprs_gmm.c:1000)[0;m [1;33m20240811120320799 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x55bd9e873580]{WaitAttachComplete}: Received Event E_VLR_ANSWERED (gprs_gmm.c:1005)[0;m [1;33m20240811120320799 [1;33mDMM[0;m[1;33m [1;33mNOTICE[0;m[1;33m MM(262420000000029/d6096639) Unusual event: if MS got no data connection, check that it has APN configured. (gprs_gmm_attach.c:217)[0;m 20240811120320799 [1;32mDGPRS[0;m [1;32mINFO[0;m SUBSCR(262420000000029) Sending GSUP, will send: 12 01 08 62 42 02 00 00 00 20 f9 28 01 01 (gprs_subscriber.c:210) 20240811120320800 [1;32mDGPRS[0;m [1;32mINFO[0;m SUBSCR(262420000000029) Received GSUP message OSMO_GSUP_MSGT_UPDATE_LOCATION_RESULT (gprs_subscriber.c:723) 20240811120320800 [1;34mDGPRS[0;m [1;34mDEBUG[0;m SUBSCR(262420000000029) Updating subscriber data (gprs_subscriber.c:839) [1;33m20240811120320800 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(262420000000029/d6096639) Subscriber data update (mmctx.c:430)[0;m [1;33m20240811120320800 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM(262420000000029/d6096639) Updating authorization (accepted -> accepted) (sgsn_auth.c:217)[0;m [38;5;57m20240811120320806 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00096-NSVC00097-127_0_0_10:23000-127_0_0_103:21010)[0x55bd9e86de10]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m 20240811120320806 [1;34mDLLC[0;m [1;34mDEBUG[0;m LLC SAPI=1 C U GEA0 IOV-UI=0x000000 FCS=0xffae8e CMD=UI DATA (gprs_llc_parse.c:89) [1;33m20240811120320806 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM_STATE_Gb(0)[0x55bd9e8736b0]{Idle}: Received Event E_MM_PDU_RECEPTION (gprs_gmm.c:2333)[0;m [1;33m20240811120320806 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(262420000000029/d6096639) -> GMM ATTACH COMPLETE (gprs_gmm.c:1397)[0;m [1;33m20240811120320806 [1;33mDMM[0;m[1;33m [1;33mNOTICE[0;m[1;33m MM(262420000000029/d6096639) Stopping *inactive* MM timer 3350 (gprs_gmm.c:115)[0;m [1;33m20240811120320806 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM(gmm_fsm)[0x55bd9e873450]{CommonProcedureInitiated}: Received Event E_GMM_ATTACH_SUCCESS (gprs_gmm.c:1409)[0;m [1;33m20240811120320806 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM(gmm_fsm)[0x55bd9e873450]{CommonProcedureInitiated}: state_chg to Registered.NORMAL (gprs_gmm_fsm.c:72)[0;m 20240811120320806 [1;33mDLLC[0;m [1;33mNOTICE[0;m LLME(f7466f9e/d6096639){ASSIGNED} LLGM Assign pre (ffffffff => d6096639) (gprs_llc.c:1079) 20240811120320806 [1;33mDLLC[0;m [1;33mNOTICE[0;m LLME(ffffffff/d6096639){ASSIGNED} LLGM Assign post (ffffffff => d6096639) (gprs_llc.c:1125) [1;33m20240811120320806 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM_STATE_Gb(0)[0x55bd9e8736b0]{Idle}: Received Event E_MM_GPRS_ATTACH (gprs_gmm.c:1420)[0;m [1;33m20240811120320806 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM_STATE_Gb(0)[0x55bd9e8736b0]{Idle}: state_chg to Ready (gprs_mm_state_gb_fsm.c:60)[0;m [1;33m20240811120320806 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x55bd9e873580]{WaitAttachComplete}: Received Event E_ATTACH_COMPLETE_RECV (gprs_gmm.c:1424)[0;m [1;33m20240811120320806 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x55bd9e873580]{WaitAttachComplete}: state_chg to Init (gprs_gmm_attach.c:212)[0;m TC_hlr_location_cancel_request_withdraw(402)@b1482cb78400: setverdict(pass): none -> pass 20240811120320855 [1;32mDGPRS[0;m [1;32mINFO[0;m SUBSCR(262420000000029) Received GSUP message OSMO_GSUP_MSGT_LOCATION_CANCEL_REQUEST (gprs_subscriber.c:723) 20240811120320855 [1;32mDGPRS[0;m [1;32mINFO[0;m SUBSCR(262420000000029) Cancelling MS subscriber (subscription withdraw) (gprs_subscriber.c:635) 20240811120320855 [1;32mDGPRS[0;m [1;32mINFO[0;m SUBSCR(262420000000029) Sending GSUP, will send: 1e 01 08 62 42 02 00 00 00 20 f9 28 01 01 (gprs_subscriber.c:210) 20240811120320855 [1;34mDGPRS[0;m [1;34mDEBUG[0;m SUBSCR(262420000000029) Updating subscriber data (gprs_subscriber.c:839) [1;33m20240811120320855 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(262420000000029/d6096639) Subscriber data update (mmctx.c:430)[0;m [1;33m20240811120320855 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM(262420000000029/d6096639) Updating authorization (accepted -> rejected) (sgsn_auth.c:217)[0;m [1;33m20240811120320855 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(262420000000029/d6096639) Got authorization update: state accepted -> rejected (sgsn_auth.c:246)[0;m [1;33m20240811120320855 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(262420000000029/d6096639) Cancelled with cause 'Implicitly detached' (10), deleting context (gprs_gmm.c:1053)[0;m [1;33m20240811120320855 [1;33mDMM[0;m[1;33m [1;33mNOTICE[0;m[1;33m MM(262420000000029/d6096639) Authorization lost, detaching with cause 'Implicitly detached' (10) (gprs_gmm.c:1031)[0;m [1;33m20240811120320855 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m <- GMM DETACH REQ (type: IMSI detach (after VLR failure), cause: Implicitly detached) (gprs_gmm.c:251)[0;m [1;33m20240811120320855 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(262420000000029/d6096639) Cleaning MM context due to auth lost (gprs_gmm.c:200)[0;m [1;33m20240811120320855 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM(gmm_fsm)[0x55bd9e873450]{Registered.NORMAL}: Received Event E_GMM_CLEANUP (gprs_gmm.c:203)[0;m [1;33m20240811120320855 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM(gmm_fsm)[0x55bd9e873450]{Registered.NORMAL}: state_chg to Deregistered (gprs_gmm_fsm.c:223)[0;m [1;33m20240811120320855 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM_STATE_Gb(0)[0x55bd9e8736b0]{Ready}: Received Event E_MM_GPRS_DETACH (gprs_gmm.c:210)[0;m [1;33m20240811120320855 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM_STATE_Gb(0)[0x55bd9e8736b0]{Ready}: state_chg to Idle (gprs_mm_state_gb_fsm.c:76)[0;m 20240811120320856 [1;33mDLLC[0;m [1;33mNOTICE[0;m LLME(ffffffff/d6096639){ASSIGNED} LLGM Assign pre (d6096639 => ffffffff) (gprs_llc.c:1079) 20240811120320856 [1;33mDLLC[0;m [1;33mNOTICE[0;m LLME(00000000/00000000){UNASSIGNED} LLGM Assign post (d6096639 => ffffffff) (gprs_llc.c:1125) [1;33m20240811120320856 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x55bd9e873580]{Init}: Deallocated (fsm.c:568)[0;m [1;33m20240811120320856 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM_STATE_Gb(0)[0x55bd9e8736b0]{Idle}: Deallocated (fsm.c:568)[0;m [1;33m20240811120320856 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM_STATE_Iu[0x55bd9e8737e0]{Detached}: Deallocated (fsm.c:568)[0;m [1;33m20240811120320856 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM(gmm_fsm)[0x55bd9e873450]{Deregistered}: Deallocated (fsm.c:568)[0;m 20240811120320857 [1;33mDGPRS[0;m [1;33mNOTICE[0;m Unknown IMSI 262420000000029, discarding GSUP response of type 0x06 (gprs_subscriber.c:673) TC_hlr_location_cancel_request_withdraw(402)@b1482cb78400: setverdict(pass): pass -> pass, component reason not changed TC_hlr_location_cancel_request_withdraw-BVCI196(387)@b1482cb78400: Removing Client IMSI='262420000000029'H, index=0 [38;5;57m20240811120320868 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00096-NSVC00097-127_0_0_10:23000-127_0_0_103:21010)[0x55bd9e86de10]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m 20240811120320868 [1;33mDLLC[0;m [1;33mNOTICE[0;m LLME(ffffffff/d6096639){UNASSIGNED} LLC RX: unknown TLLI 0xd6096639, creating LLME on the fly (gprs_llc.c:552) 20240811120320868 [1;34mDLLC[0;m [1;34mDEBUG[0;m LLC SAPI=1 C U GEA0 IOV-UI=0x000000 FCS=0x65f352 CMD=UI TC_hlr_location_cancel_request_withdraw(402)@b1482cb78400: Final verdict of PTC: pass DATA (gprs_llc_parse.c:89) [1;33m20240811120320868 [1;33mDMM[0;m[1;33m [1;33mNOTICE[0;m[1;33m LLME(ffffffff/d6096639){UNASSIGNED} Cannot handle GMM for unknown MM CTX (gprs_gmm.c:2035)[0;m 20240811120320868 [1;33mDLLC[0;m [1;33mNOTICE[0;m LLME(ffffffff/d6096639){UNASSIGNED} LLGM Assign pre (d6096639 => ffffffff) (gprs_llc.c:1079) 20240811120320868 [1;33mDLLC[0;m [1;33mNOTICE[0;m LLME(00000000/00000000){UNASSIGNED} LLGM Assign post (d6096639 => ffffffff) (gprs_llc.c:1125) 20240811120320869 [1;32mDLGLOBAL[0;m [1;32mINFO[0;m Closing telnet connection r=127.0.0.1:58042<->l=127.0.0.10:4245 (telnet_interface.c:138) SGSN_Test-Gb0-NS0(384)@b1482cb78400: Final verdict of PTC: none TC_hlr_location_cancel_request_withdraw-BVCI196(387)@b1482cb78400: Final verdict of PTC: none SGSN_Test-GSUP(400)@b1482cb78400: Final verdict of PTC: none SGSN_Test-Gb1-BSSGP1(390)@b1482cb78400: Final verdict of PTC: none -NSVCI99(398)@b1482cb78400: Final verdict of PTC: none [38;5;23m20240811120320872 [1;31mDLINP[0;m[38;5;23m [1;31mERROR[0;m[38;5;23m 127.0.0.103:4222 connection closed with server (ipa.c:68)[0;m -NSVCI97(388)@b1482cb78400: Final verdict of PTC: none SGSN_Test-Gb2-NS2(394)@b1482cb78400: Final verdict of PTC: none -NSVCI98(393)@b1482cb78400: Final verdict of PTC: none SGSN_Test-Gb2-BSSGP2(395)@b1482cb78400: Final verdict of PTC: none SGSN_Test-Gb1-NS1(389)@b1482cb78400: Final verdict of PTC: none SGSN_Test-Gb0-BSSGP0(385)@b1482cb78400: Final verdict of PTC: none SGSN_Test-GSUP-IPA(399)@b1482cb78400: Final verdict of PTC: none SGSN_Test-GGSN_GTP(401)@b1482cb78400: Final verdict of PTC: none TC_hlr_location_cancel_request_withdraw-BVCI220(397)@b1482cb78400: Final verdict of PTC: none -NSVCI97-provIP(386)@b1482cb78400: Final verdict of PTC: none -NSVCI98-provIP(391)@b1482cb78400: Final verdict of PTC: none -NSVCI99-provIP(396)@b1482cb78400: Final verdict of PTC: none TC_hlr_location_cancel_request_withdraw-BVCI210(392)@b1482cb78400: Final verdict of PTC: none [38;5;39m20240811120320872 [1;32mDLGSUP[0;m[38;5;39m [1;32mINFO[0;m[38;5;39m GSUP link to 127.0.0.103:4222 DOWN (gsup_client.c:151)[0;m MTC@b1482cb78400: Setting final verdict of the test case. MTC@b1482cb78400: Local verdict of MTC: none MTC@b1482cb78400: Local verdict of PTC SGSN_Test-Gb0-NS0(384): none (none -> none) MTC@b1482cb78400: Local verdict of PTC SGSN_Test-Gb0-BSSGP0(385): none (none -> none) MTC@b1482cb78400: Local verdict of PTC -NSVCI97-provIP(386): none (none -> none) MTC@b1482cb78400: Local verdict of PTC TC_hlr_location_cancel_request_withdraw-BVCI196(387): none (none -> none) MTC@b1482cb78400: Local verdict of PTC -NSVCI97(388): none (none -> none) MTC@b1482cb78400: Local verdict of PTC SGSN_Test-Gb1-NS1(389): none (none -> none) MTC@b1482cb78400: Local verdict of PTC SGSN_Test-Gb1-BSSGP1(390): none (none -> none) MTC@b1482cb78400: Local verdict of PTC -NSVCI98-provIP(391): none (none -> none) MTC@b1482cb78400: Local verdict of PTC TC_hlr_location_cancel_request_withdraw-BVCI210(392): none (none -> none) MTC@b1482cb78400: Local verdict of PTC -NSVCI98(393): none (none -> none) MTC@b1482cb78400: Local verdict of PTC SGSN_Test-Gb2-NS2(394): none (none -> none) MTC@b1482cb78400: Local verdict of PTC SGSN_Test-Gb2-BSSGP2(395): none (none -> none) MTC@b1482cb78400: Local verdict of PTC -NSVCI99-provIP(396): none (none -> none) MTC@b1482cb78400: Local verdict of PTC TC_hlr_location_cancel_request_withdraw-BVCI220(397): none (none -> none) MTC@b1482cb78400: Local verdict of PTC -NSVCI99(398): none (none -> none) MTC@b1482cb78400: Local verdict of PTC SGSN_Test-GSUP-IPA(399): none (none -> none) MTC@b1482cb78400: Local verdict of PTC SGSN_Test-GSUP(400): none (none -> none) MTC@b1482cb78400: Local verdict of PTC SGSN_Test-GGSN_GTP(401): none (none -> none) MTC@b1482cb78400: Local verdict of PTC TC_hlr_location_cancel_request_withdraw(402): pass (none -> pass) MTC@b1482cb78400: Test case TC_hlr_location_cancel_request_withdraw finished. Verdict: pass MTC@b1482cb78400: Starting external command `https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/ws/_cache/podman/osmo-ttcn3-hacks/ttcn3-tcpdump-stop.sh SGSN_Tests.TC_hlr_location_cancel_request_withdraw pass'. Sun Aug 11 12:03:20 UTC 2024 [1;32m====== SGSN_Tests.TC_hlr_location_cancel_request_withdraw pass ======[0m
Waiting for packet dumper to finish... 0 (prev_count=-1, count=44200) [38;5;39m20240811120321873 [1;33mDLGSUP[0;m[38;5;39m [1;33mNOTICE[0;m[38;5;39m GSUP connecting to 127.0.0.103:4222 (gsup_client.c:74)[0;m [38;5;39m20240811120321873 [1;32mDLGSUP[0;m[38;5;39m [1;32mINFO[0;m[38;5;39m GSUP link to 127.0.0.103:4222 DOWN (gsup_client.c:151)[0;m Waiting for packet dumper to finish... 1 (prev_count=44200, count=93300) [38;5;39m20240811120322874 [1;33mDLGSUP[0;m[38;5;39m [1;33mNOTICE[0;m[38;5;39m GSUP connecting to 127.0.0.103:4222 (gsup_client.c:74)[0;m [38;5;39m20240811120322874 [1;32mDLGSUP[0;m[38;5;39m [1;32mINFO[0;m[38;5;39m GSUP link to 127.0.0.103:4222 DOWN (gsup_client.c:151)[0;m [38;5;39m20240811120323875 [1;33mDLGSUP[0;m[38;5;39m [1;33mNOTICE[0;m[38;5;39m GSUP connecting to 127.0.0.103:4222 (gsup_client.c:74)[0;m [38;5;39m20240811120323875 [1;32mDLGSUP[0;m[38;5;39m [1;32mINFO[0;m[38;5;39m GSUP link to 127.0.0.103:4222 DOWN (gsup_client.c:151)[0;m MTC@b1482cb78400: External command `https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/ws/_cache/podman/osmo-ttcn3-hacks/ttcn3-tcpdump-stop.sh SGSN_Tests.TC_hlr_location_cancel_request_withdraw pass' was executed successfully (exit status: 0). MTC@b1482cb78400: Starting external command `https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/ws/_cache/podman/osmo-ttcn3-hacks/ttcn3-tcpdump-start.sh SGSN_Tests.TC_hlr_location_cancel_request_unknown_subscriber_withdraw'. ------ SGSN_Tests.TC_hlr_location_cancel_request_unknown_subscriber_withdraw ------ Sun Aug 11 12:03:24 UTC 2024 /usr/bin/dumpcap -q -s 1520 -n -i any -w "https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/159/artifact/logs/testsuite/SGSN_Tests.TC_hlr_location_cancel_request_unknown_subscriber_withdraw.pcap" >https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/159/artifact/logs/testsuite/SGSN_Tests.TC_hlr_location_cancel_request_unknown_subscriber_withdraw.pcap.stdout 2>/tmp/cmderr & Waiting for packet dumper to start... 0 [0;94m[testenv][generic] Testsuite is done[0m [1;34m[testenv][generic] Stopping testsuite (687893)[0m [1;34m[testenv][generic] Merging log files[0m [0;94m[testenv][generic] + ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/ws/_cache', '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TERM=dumb', '-e', 'TESTENV_GIT_DIR=https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/ws/_cache/git', '-e', 'PATH=https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/ws/_testenv/data/scripts:https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/ws/_cache/podman/osmo-ttcn3-hacks/sgsn:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-t', '-w', 'https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/159/artifact/logs/testsuite', 'testenv-sgsn-generic-osmocom-nightly-20240811-1158-43a63e57', 'sh', '-c', 'https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/ws/_cache/podman/osmo-ttcn3-hacks/log_merge.sh SGSN_Tests --rm >/dev/null'][0m Error: container b1482cb78400155330c6c81ab503dd45017a654768fbedf771e74eb95969f5ff does not exist in database: no such container [1;91m[testenv] podman container crashed![0m Error: no container with name or ID "testenv-sgsn-generic-osmocom-nightly-20240811-1158-43a63e57" found: no such container [1;91m[testenv][generic] Command failed unexpectedly[0m [0;94m[testenv][generic] Command: ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/ws/_cache', '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TERM=dumb', '-e', 'TESTENV_GIT_DIR=https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/ws/_cache/git', '-e', 'PATH=https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/ws/_testenv/data/scripts:https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/ws/_cache/podman/osmo-ttcn3-hacks/sgsn:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-t', '-w', 'https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/159/artifact/logs/testsuite', 'testenv-sgsn-generic-osmocom-nightly-20240811-1158-43a63e57', 'sh', '-c', 'https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/ws/_cache/podman/osmo-ttcn3-hacks/log_merge.sh SGSN_Tests --rm >/dev/null'][0m [0;94m[testenv][generic] Returncode: 125[0m Traceback (most recent call last): File "https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/ws/./testenv.py", line 115, in <module> main() File "https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/ws/./testenv.py", line 104, in main run() File "https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/ws/./testenv.py", line 63, in run testenv.testsuite.run(cfg) File "https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/ws/_testenv/testenv/testsuite.py", line 199, in run merge_log_files(cfg) File "https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/ws/_testenv/testenv/testsuite.py", line 131, in merge_log_files testenv.cmd.run(cmd, cwd=cwd) File "https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/ws/_testenv/testenv/cmd.py", line 86, in run return testenv.podman.exec_cmd(cmd, check=check, env=env, *args, **kwargs) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/ws/_testenv/testenv/podman.py", line 133, in exec_cmd testenv.cmd.run( File "https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/ws/_testenv/testenv/cmd.py", line 103, in run exit_error_cmd(p, "Command failed unexpectedly") File "https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/ws/_testenv/testenv/cmd.py", line 59, in exit_error_cmd raise RuntimeError("shell command related error, find details right above this python trace") RuntimeError: shell command related error, find details right above this python trace [1;34m[testenv][generic] Logs saved to: https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/159/artifac... [0m 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