See https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/247/display/redirect?page=changes
Changes:
[Pau Espin Pedrol] hnbgw: Introduce PfcpParameters in TestHdlrParameters
[Oliver Smith] bsc: testenv: set MTU=1500 for lo
[Pau Espin Pedrol] hnbgw: Merge TC_ps_rab_assignment_* testcases
[Pau Espin Pedrol] hnbgw: Move normal condition Iu Release procedure to helper function
[Pau Espin Pedrol] hnbgw: Tear down SCCP conn from CN under normal conditions
------------------------------------------ [...truncated 768.67 KiB...] -NSVCI97(159)@cf08b375ff3a: Provider Link came up: sending NS-RESET [38;5;63m20241104150046291 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00096)-NSVC(00097) Rx NS-RESET (gprs_ns2.c:1309)[0;m [38;5;57m20241104150046291 [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)[0x564a0d099f00]{UNBLOCKED}: Received Event RX-RESET (gprs_ns2_vc_fsm.c:934)[0;m [38;5;57m20241104150046291 [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)[0x564a0d099f00]{UNBLOCKED}: state_chg to RESET (gprs_ns2_vc_fsm.c:657)[0;m [38;5;57m20241104150046291 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(00096) NS-STATUS.ind(bvci=00000): cause=NSE failure, transfer=0, first=0, mtu=65523 (gprs_ns2.c:575)[0;m 20241104150046291 [1;33mDGPRS[0;m [1;33mNOTICE[0;m NS-E 96 became unavailable (gprs_ns.c:55) [38;5;63m20241104150046291 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00096)-NSVC(00097) Tx NS-RESET-ACK (gprs_ns2_message.c:331)[0;m [38;5;57m20241104150046291 [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)[0x564a0d099f00]{RESET}: state_chg to BLOCKED (gprs_ns2_vc_fsm.c:329)[0;m [38;5;63m20241104150046291 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00096)-NSVC(00097) Tx NS-ALIVE (gprs_ns2_message.c:193)[0;m [38;5;57m20241104150046291 [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)[0x564a0d099f00]{BLOCKED}: Starting Tns-alive of 3 seconds (gprs_ns2_vc_fsm.c:177)[0;m -NSVCI97(159)@cf08b375ff3a: NSVC 97 State Transition: NSVC_S_WAIT_RESET (2) -> NSVC_S_ALIVE_BLOCKED (3) [38;5;63m20241104150046292 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00096)-NSVC(00097) Rx NS-ALIVE (gprs_ns2.c:1309)[0;m [38;5;57m20241104150046292 [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)[0x564a0d099f00]{BLOCKED}: Received Event RX-ALIVE (gprs_ns2_vc_fsm.c:957)[0;m [38;5;63m20241104150046292 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00096)-NSVC(00097) Tx NS-ALIVE-ACK (gprs_ns2_message.c:193)[0;m [38;5;63m20241104150046292 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00096)-NSVC(00097) Rx NS-UNBLOCK (gprs_ns2.c:1309)[0;m [38;5;57m20241104150046292 [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)[0x564a0d099f00]{BLOCKED}: Received Event RX-UNBLOCK (gprs_ns2_vc_fsm.c:951)[0;m [38;5;63m20241104150046292 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00096)-NSVC(00097) Tx NS-UNBLOCK-ACK (gprs_ns2_message.c:193)[0;m [38;5;57m20241104150046292 [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)[0x564a0d099f00]{BLOCKED}: state_chg to UNBLOCKED (gprs_ns2_vc_fsm.c:414)[0;m [38;5;57m20241104150046292 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(00096) NS-STATUS.ind(bvci=00000): cause=NSE recovery, transfer=100, first=0, mtu=65523 (gprs_ns2.c:575)[0;m 20241104150046292 [1;33mDGPRS[0;m [1;33mNOTICE[0;m NS-E 96 became available (gprs_ns.c:44) [38;5;57m20241104150046292 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(00096)-NSVC(00097) NS-STATUS.ind(bvci=00000): cause=NSVC recovery, transfer=100, first=0, mtu=65523 (gprs_ns2.c:571)[0;m 20241104150046292 [1;33mDGPRS[0;m [1;33mNOTICE[0;m NS: indication Unknown prim 2 from NS (gprs_ns.c:59) [38;5;63m20241104150046293 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00096)-NSVC(00097) Rx NS-ALIVE-ACK (gprs_ns2.c:1309)[0;m [38;5;57m20241104150046293 [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)[0x564a0d099f00]{UNBLOCKED}: Received Event RX-ALIVE_ACK (gprs_ns2_vc_fsm.c:960)[0;m -NSVCI97(159)@cf08b375ff3a: Rx NS-ALIVE-ACK: stopping Tns-alive; starting Tns-test -NSVCI97(159)@cf08b375ff3a: NSVC 97 State Transition: NSVC_S_ALIVE_BLOCKED (3) -> NSVC_S_ALIVE_UNBLOCKED (4) [38;5;57m20241104150046296 [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)[0x564a0d099f00]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m SGSN_Test-Gb1-NS1(160)@cf08b375ff3a: Creating NSIP provider for "127.0.0.103":21011 SGSN_Test-Gb0-BSSGP0(156)@cf08b375ff3a: BVCI(0) Rx BVC-RESET-ACK SGSN_Test-Gb0-BSSGP0(156)@cf08b375ff3a: BVCI(0) State Transition: BVC_S_WAIT_NS_ALIVE_UNBLOCKED (1) -> BVC_S_UNBLOCKED (3) SGSN_Test-Gb1-BSSGP1(161)@cf08b375ff3a: BVCI(0) State Transition: BVC_S_WAIT_RESET (0) -> BVC_S_WAIT_NS_ALIVE_UNBLOCKED (1) [38;5;57m20241104150046303 [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)[0x564a0d099f00]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m [38;5;59m20241104150046303 [1;33mDLBSSGP[0;m[38;5;59m [1;33mNOTICE[0;m[38;5;59m Cell 262-42-13135-0 CI 20960 on BVCI 196 (gprs_bssgp.c:435)[0;m TC_attach_gsup_lu_timeout-BVCI196(158)@cf08b375ff3a: BVCI(196) State Transition: BVC_S_BLOCKED (2) -> BVC_S_UNBLOCKED (3) SGSN_Test-Gb1-NS1(160)@cf08b375ff3a: Starting NSVC component for { provider := { ip := { address_family := AF_INET (2), local_udp_port := 21011, local_ip := "127.0.0.103", remote_udp_port := 23000, remote_ip := "127.0.0.10", data_weight := 1, signalling_weight := 1 } }, nsvci := 98 } -NSVCI98(165)@cf08b375ff3a: NSVC 98 State Transition: NSVC_S_DEAD_BLOCKED (1) -> NSVC_S_DEAD_BLOCKED (1) -NSVCI98(165)@cf08b375ff3a: Provider Link came up: sending NS-RESET SGSN_Test-Gb2-NS2(164)@cf08b375ff3a: Creating NSIP provider for "127.0.0.103":21012 [38;5;63m20241104150046313 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00097)-NSVC(00098) Rx NS-RESET (gprs_ns2.c:1309)[0;m [38;5;57m20241104150046313 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00097-NSVC00098-127_0_0_10:23000-127_0_0_103:21011)[0x564a0d09bec0]{UNBLOCKED}: Received Event RX-RESET (gprs_ns2_vc_fsm.c:934)[0;m [38;5;57m20241104150046313 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00097-NSVC00098-127_0_0_10:23000-127_0_0_103:21011)[0x564a0d09bec0]{UNBLOCKED}: state_chg to RESET (gprs_ns2_vc_fsm.c:657)[0;m [38;5;57m20241104150046313 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(00097) NS-STATUS.ind(bvci=00000): cause=NSE failure, transfer=0, first=0, mtu=65523 (gprs_ns2.c:575)[0;m 20241104150046313 [1;33mDGPRS[0;m [1;33mNOTICE[0;m NS-E 97 became unavailable (gprs_ns.c:55) [38;5;63m20241104150046313 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00097)-NSVC(00098) Tx NS-RESET-ACK (gprs_ns2_message.c:331)[0;m [38;5;57m20241104150046313 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00097-NSVC00098-127_0_0_10:23000-127_0_0_103:21011)[0x564a0d09bec0]{RESET}: state_chg to BLOCKED (gprs_ns2_vc_fsm.c:329)[0;m [38;5;63m20241104150046313 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00097)-NSVC(00098) Tx NS-ALIVE (gprs_ns2_message.c:193)[0;m [38;5;57m20241104150046313 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00097-NSVC00098-127_0_0_10:23000-127_0_0_103:21011)[0x564a0d09bec0]{BLOCKED}: Starting Tns-alive of 3 seconds (gprs_ns2_vc_fsm.c:177)[0;m -NSVCI98(165)@cf08b375ff3a: NSVC 98 State Transition: NSVC_S_WAIT_RESET (2) -> NSVC_S_ALIVE_BLOCKED (3) [38;5;63m20241104150046314 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00097)-NSVC(00098) Rx NS-ALIVE (gprs_ns2.c:1309)[0;m [38;5;57m20241104150046314 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00097-NSVC00098-127_0_0_10:23000-127_0_0_103:21011)[0x564a0d09bec0]{BLOCKED}: Received Event RX-ALIVE (gprs_ns2_vc_fsm.c:957)[0;m [38;5;63m20241104150046314 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00097)-NSVC(00098) Tx NS-ALIVE-ACK (gprs_ns2_message.c:193)[0;m [38;5;63m20241104150046314 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00097)-NSVC(00098) Rx NS-UNBLOCK (gprs_ns2.c:1309)[0;m [38;5;57m20241104150046314 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00097-NSVC00098-127_0_0_10:23000-127_0_0_103:21011)[0x564a0d09bec0]{BLOCKED}: Received Event RX-UNBLOCK (gprs_ns2_vc_fsm.c:951)[0;m [38;5;63m20241104150046314 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00097)-NSVC(00098) Tx NS-UNBLOCK-ACK (gprs_ns2_message.c:193)[0;m [38;5;57m20241104150046314 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00097-NSVC00098-127_0_0_10:23000-127_0_0_103:21011)[0x564a0d09bec0]{BLOCKED}: state_chg to UNBLOCKED (gprs_ns2_vc_fsm.c:414)[0;m [38;5;57m20241104150046314 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(00097) NS-STATUS.ind(bvci=00000): cause=NSE recovery, transfer=100, first=0, mtu=65523 (gprs_ns2.c:575)[0;m 20241104150046314 [1;33mDGPRS[0;m [1;33mNOTICE[0;m NS-E 97 became available (gprs_ns.c:44) [38;5;57m20241104150046314 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(00097)-NSVC(00098) NS-STATUS.ind(bvci=00000): cause=NSVC recovery, transfer=100, first=0, mtu=65523 (gprs_ns2.c:571)[0;m 20241104150046314 [1;33mDGPRS[0;m [1;33mNOTICE[0;m NS: indication Unknown prim 2 from NS (gprs_ns.c:59) [38;5;63m20241104150046314 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00097)-NSVC(00098) Rx NS-ALIVE-ACK (gprs_ns2.c:1309)[0;m [38;5;57m20241104150046314 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00097-NSVC00098-127_0_0_10:23000-127_0_0_103:21011)[0x564a0d09bec0]{UNBLOCKED}: Received Event RX-ALIVE_ACK (gprs_ns2_vc_fsm.c:960)[0;m -NSVCI98(165)@cf08b375ff3a: Rx NS-ALIVE-ACK: stopping Tns-alive; starting Tns-test -NSVCI98(165)@cf08b375ff3a: NSVC 98 State Transition: NSVC_S_ALIVE_BLOCKED (3) -> NSVC_S_ALIVE_UNBLOCKED (4) [38;5;57m20241104150046316 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00097-NSVC00098-127_0_0_10:23000-127_0_0_103:21011)[0x564a0d09bec0]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m SGSN_Test-Gb1-BSSGP1(161)@cf08b375ff3a: BVCI(0) Rx BVC-RESET-ACK SGSN_Test-Gb1-BSSGP1(161)@cf08b375ff3a: BVCI(0) State Transition: BVC_S_WAIT_NS_ALIVE_UNBLOCKED (1) -> BVC_S_UNBLOCKED (3) SGSN_Test-Gb2-BSSGP2(166)@cf08b375ff3a: BVCI(0) State Transition: BVC_S_WAIT_RESET (0) -> BVC_S_WAIT_NS_ALIVE_UNBLOCKED (1) [38;5;57m20241104150046320 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00097-NSVC00098-127_0_0_10:23000-127_0_0_103:21011)[0x564a0d09bec0]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m [38;5;59m20241104150046320 [1;33mDLBSSGP[0;m[38;5;59m [1;33mNOTICE[0;m[38;5;59m Cell 262-42-13200-0 CI 20961 on BVCI 210 (gprs_bssgp.c:435)[0;m TC_attach_gsup_lu_timeout-BVCI210(162)@cf08b375ff3a: BVCI(210) State Transition: BVC_S_BLOCKED (2) -> BVC_S_UNBLOCKED (3) SGSN_Test-Gb2-NS2(164)@cf08b375ff3a: Starting NSVC component for { provider := { ip := { address_family := AF_INET (2), local_udp_port := 21012, local_ip := "127.0.0.103", remote_udp_port := 23000, remote_ip := "127.0.0.10", data_weight := 1, signalling_weight := 1 } }, nsvci := 99 } -NSVCI99(169)@cf08b375ff3a: NSVC 99 State Transition: NSVC_S_DEAD_BLOCKED (1) -> NSVC_S_DEAD_BLOCKED (1) -NSVCI99(169)@cf08b375ff3a: Provider Link came up: sending NS-RESET [38;5;63m20241104150046324 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00098)-NSVC(00099) Rx NS-RESET (gprs_ns2.c:1309)[0;m [38;5;57m20241104150046324 [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)[0x564a0d09d920]{UNBLOCKED}: Received Event RX-RESET (gprs_ns2_vc_fsm.c:934)[0;m [38;5;57m20241104150046324 [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)[0x564a0d09d920]{UNBLOCKED}: state_chg to RESET (gprs_ns2_vc_fsm.c:657)[0;m [38;5;57m20241104150046324 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(00098) NS-STATUS.ind(bvci=00000): cause=NSE failure, transfer=0, first=0, mtu=65523 (gprs_ns2.c:575)[0;m 20241104150046324 [1;33mDGPRS[0;m [1;33mNOTICE[0;m NS-E 98 became unavailable (gprs_ns.c:55) [38;5;63m20241104150046324 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00098)-NSVC(00099) Tx NS-RESET-ACK (gprs_ns2_message.c:331)[0;m [38;5;57m20241104150046324 [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)[0x564a0d09d920]{RESET}: state_chg to BLOCKED (gprs_ns2_vc_fsm.c:329)[0;m [38;5;63m20241104150046324 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00098)-NSVC(00099) Tx NS-ALIVE (gprs_ns2_message.c:193)[0;m [38;5;57m20241104150046324 [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)[0x564a0d09d920]{BLOCKED}: Starting Tns-alive of 3 seconds (gprs_ns2_vc_fsm.c:177)[0;m -NSVCI99(169)@cf08b375ff3a: NSVC 99 State Transition: NSVC_S_WAIT_RESET (2) -> NSVC_S_ALIVE_BLOCKED (3) [38;5;63m20241104150046325 [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;57m20241104150046325 [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)[0x564a0d09d920]{BLOCKED}: Received Event RX-ALIVE (gprs_ns2_vc_fsm.c:957)[0;m [38;5;63m20241104150046325 [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;63m20241104150046325 [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;57m20241104150046325 [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)[0x564a0d09d920]{BLOCKED}: Received Event RX-UNBLOCK (gprs_ns2_vc_fsm.c:951)[0;m [38;5;63m20241104150046325 [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;57m20241104150046325 [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)[0x564a0d09d920]{BLOCKED}: state_chg to UNBLOCKED (gprs_ns2_vc_fsm.c:414)[0;m [38;5;57m20241104150046325 [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 20241104150046325 [1;33mDGPRS[0;m [1;33mNOTICE[0;m NS-E 98 became available (gprs_ns.c:44) [38;5;57m20241104150046325 [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 20241104150046325 [1;33mDGPRS[0;m [1;33mNOTICE[0;m NS: indication Unknown prim 2 from NS (gprs_ns.c:59) [38;5;63m20241104150046326 [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;57m20241104150046326 [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)[0x564a0d09d920]{UNBLOCKED}: Received Event RX-ALIVE_ACK (gprs_ns2_vc_fsm.c:960)[0;m -NSVCI99(169)@cf08b375ff3a: Rx NS-ALIVE-ACK: stopping Tns-alive; starting Tns-test -NSVCI99(169)@cf08b375ff3a: NSVC 99 State Transition: NSVC_S_ALIVE_BLOCKED (3) -> NSVC_S_ALIVE_UNBLOCKED (4) [38;5;57m20241104150046328 [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)[0x564a0d09d920]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m SGSN_Test-Gb2-BSSGP2(166)@cf08b375ff3a: BVCI(0) Rx BVC-RESET-ACK SGSN_Test-Gb2-BSSGP2(166)@cf08b375ff3a: BVCI(0) State Transition: BVC_S_WAIT_NS_ALIVE_UNBLOCKED (1) -> BVC_S_UNBLOCKED (3) [38;5;57m20241104150046332 [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)[0x564a0d09d920]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m [38;5;59m20241104150046332 [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:435)[0;m TC_attach_gsup_lu_timeout-BVCI220(168)@cf08b375ff3a: BVCI(220) State Transition: BVC_S_BLOCKED (2) -> BVC_S_UNBLOCKED (3) [38;5;39m20241104150046743 [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;23m20241104150046743 [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;39m20241104150046743 [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;39m20241104150046743 [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(170)@cf08b375ff3a: Established a new IPA connection (conn_id=2) SGSN_Test-GSUP-IPA(170)@cf08b375ff3a: 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;23m20241104150046747 [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(170)@cf08b375ff3a: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } SGSN_Test-GSUP-IPA(170)@cf08b375ff3a: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } [38;5;39m20241104150046748 [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(170)@cf08b375ff3a: 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(170)@cf08b375ff3a: 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(170)@cf08b375ff3a: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } SGSN_Test-GSUP-IPA(170)@cf08b375ff3a: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } TC_attach_gsup_lu_timeout-BVCI196(158)@cf08b375ff3a: Adding Client=TC_attach_gsup_lu_timeout(173), IMSI='262420000000005'H, TLLI='D183B9F0'O, index=0 SGSN_Test-GSUP(171)@cf08b375ff3a: Created GsupExpect[0] for "262420000000005" to be handled at TC_attach_gsup_lu_timeout(173) TC_attach_gsup_lu_timeout(173)@cf08b375ff3a: 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 := '4288CF8B19FDA0CC4618DBDB1E09B8F5'O } }, { tag := OSMO_GSUP_SRES_IE (33), len := 0, val := { sres := '4ACB3274'O } }, { tag := OSMO_GSUP_KC_IE (34), len := 0, val := { kc := 'C6E73EAC472F4164'O } } } } } [38;5;57m20241104150047832 [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)[0x564a0d099f00]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m 20241104150047832 [1;33mDLLC[0;m [1;33mNOTICE[0;m LLME(ffffffff/d183b9f0){UNASSIGNED} LLC RX: unknown TLLI 0xd183b9f0, creating LLME on the fly (gprs_llc.c:552) 20241104150047832 [1;34mDLLC[0;m [1;34mDEBUG[0;m LLC SAPI=1 C U GEA0 IOV-UI=0x000000 FCS=0x24bf94 CMD=UI DATA (gprs_llc_parse.c:89) [1;33m20241104150047832 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(---/ffffffff) -> GMM ATTACH REQUEST [0;m[1;33mMI(IMSI-262420000000005) type="GPRS attach" [0;m[1;33m (gprs_gmm.c:1266)[0;m [1;33m20241104150047832 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM(gmm_fsm)[0x564a0d09ef30]{Deregistered}: Allocated (fsm.c:456)[0;m [1;33m20241104150047832 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x564a0d09f060]{Init}: Allocated (fsm.c:456)[0;m [1;33m20241104150047832 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM_STATE_Gb[0x564a0d09f190]{Idle}: Allocated (fsm.c:456)[0;m [1;33m20241104150047832 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM_STATE_Iu[0x564a0d09f2c0]{Detached}: Allocated (fsm.c:456)[0;m [1;33m20241104150047833 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM(gmm_fsm)[0x564a0d09ef30]{Deregistered}: Received Event E_GMM_COMMON_PROC_INIT_REQ (gprs_gmm.c:1144)[0;m [1;33m20241104150047833 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM(gmm_fsm)[0x564a0d09ef30]{Deregistered}: state_chg to CommonProcedureInitiated (gprs_gmm_fsm.c:49)[0;m 20241104150047833 [1;33mDLLC[0;m [1;33mNOTICE[0;m LLME(ffffffff/d183b9f0){UNASSIGNED} LLGM Assign pre (d183b9f0 => d88d157e) (gprs_llc.c:1079) 20241104150047833 [1;33mDLLC[0;m [1;33mNOTICE[0;m LLME(d183b9f0/d88d157e){ASSIGNED} LLGM Assign post (d183b9f0 => d88d157e) (gprs_llc.c:1125) [1;33m20241104150047833 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x564a0d09f060]{Init}: Received Event E_ATTACH_REQ_RECV (gprs_gmm.c:1370)[0;m [1;33m20241104150047833 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x564a0d09f060]{Init}: state_chg to CheckIdentity (gprs_gmm_attach.c:52)[0;m [1;33m20241104150047833 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM(262420000000005/d88d157e) <- GMM IDENTITY REQUEST: mi_type=IMEI (gprs_gmm.c:431)[0;m [38;5;57m20241104150047861 [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)[0x564a0d099f00]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m 20241104150047861 [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;33m20241104150047861 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM_STATE_Gb(0)[0x564a0d09f190]{Idle}: Received Event E_MM_PDU_RECEPTION (gprs_gmm.c:2341)[0;m [1;33m20241104150047861 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM(262420000000005/d88d157e) -> GMM IDENTITY RESPONSE: MI=IMEI-499990000000050 (gprs_gmm.c:1084)[0;m [1;33m20241104150047861 [1;33mDMM[0;m[1;33m [1;33mNOTICE[0;m[1;33m MM(262420000000005/d88d157e) Stopping *inactive* MM timer 3370 (gprs_gmm.c:115)[0;m [1;33m20241104150047861 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x564a0d09f060]{CheckIdentity}: Received Event E_IDEN_RESP_RECV (gprs_gmm.c:1128)[0;m [1;33m20241104150047861 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x564a0d09f060]{CheckIdentity}: state_chg to Authenticate (gprs_gmm_attach.c:115)[0;m [1;33m20241104150047861 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x564a0d09f060]{Authenticate}: state_chg to AskVLR (gprs_gmm_attach.c:143)[0;m [1;33m20241104150047861 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM(262420000000005/d88d157e) Requesting authorization (sgsn_auth.c:160)[0;m [1;33m20241104150047861 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(262420000000005/d88d157e) Requesting authentication tuples (sgsn_auth.c:184)[0;m [1;33m20241104150047861 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM(262420000000005/d88d157e) Requesting subscriber authentication info (gprs_subscriber.c:921)[0;m 20241104150047861 [1;32mDGPRS[0;m [1;32mINFO[0;m SUBSCR(262420000000005) requesting auth info (gprs_subscriber.c:805) 20241104150047861 [1;32mDGPRS[0;m [1;32mINFO[0;m SUBSCR(262420000000005) Sending GSUP, will send: 08 01 08 62 42 02 00 00 00 00 f5 28 01 01 (gprs_subscriber.c:210) SGSN_Test-GSUP(171)@cf08b375ff3a: Found GsupExpect[0] for "262420000000005" handled at TC_attach_gsup_lu_timeout(173) SGSN_Test-GSUP(171)@cf08b375ff3a: Added IMSI table entry 0TC_attach_gsup_lu_timeout(173)"262420000000005" 20241104150047867 [1;32mDGPRS[0;m [1;32mINFO[0;m SUBSCR(262420000000005) Received GSUP message OSMO_GSUP_MSGT_SEND_AUTH_INFO_RESULT (gprs_subscriber.c:723) 20241104150047867 [1;32mDGPRS[0;m [1;32mINFO[0;m SUBSCR(262420000000005) Got SendAuthenticationInfoResult, num_auth_vectors = 1 (gprs_subscriber.c:242) 20241104150047867 [1;34mDGPRS[0;m [1;34mDEBUG[0;m SUBSCR(262420000000005) Adding auth tuple, cksn = 0 (gprs_subscriber.c:255) 20241104150047867 [1;34mDGPRS[0;m [1;34mDEBUG[0;m SUBSCR(262420000000005) Updating subscriber authentication info (gprs_subscriber.c:850) [1;33m20241104150047867 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(262420000000005/d88d157e) Subscriber data update (mmctx.c:445)[0;m [1;33m20241104150047867 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM(262420000000005/d88d157e) Updating authorization (unknown -> authenticate) (sgsn_auth.c:217)[0;m [1;33m20241104150047867 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(262420000000005/d88d157e) Got authorization update: state unknown -> authenticate (sgsn_auth.c:246)[0;m [1;33m20241104150047867 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x564a0d09f060]{AskVLR}: Received Event E_VLR_ANSWERED (gprs_gmm.c:991)[0;m [1;33m20241104150047867 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x564a0d09f060]{AskVLR}: state_chg to Authenticate (gprs_gmm_attach.c:253)[0;m [1;33m20241104150047867 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(262420000000005/d88d157e) <- GMM AUTH AND CIPHERING REQ (rand = 42 88 cf 8b 19 fd a0 cc 46 18 db db 1e 09 b8 f5 , mmctx_is_r99=0, vec->auth_types=0x1[0;m[1;33m) (gprs_gmm.c:486)[0;m [38;5;57m20241104150047885 [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)[0x564a0d099f00]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m 20241104150047885 [1;34mDLLC[0;m [1;34mDEBUG[0;m LLC SAPI=1 C U GEA0 IOV-UI=0x000000 FCS=0xfcaae8 CMD=UI DATA (gprs_llc_parse.c:89) [1;33m20241104150047885 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM_STATE_Gb(0)[0x564a0d09f190]{Idle}: Received Event E_MM_PDU_RECEPTION (gprs_gmm.c:2341)[0;m [1;33m20241104150047885 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(262420000000005/d88d157e) -> GMM AUTH AND CIPH RESPONSE (gprs_gmm.c:620)[0;m [1;33m20241104150047885 [1;33mDMM[0;m[1;33m [1;33mNOTICE[0;m[1;33m MM(262420000000005/d88d157e) Stopping *inactive* MM timer 3360 (gprs_gmm.c:115)[0;m [1;33m20241104150047885 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM(262420000000005/d88d157e) checking auth: received GSM SRES = 4a cb 32 74 (gprs_gmm.c:666)[0;m [1;33m20241104150047885 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x564a0d09f060]{Authenticate}: Received Event E_AUTH_RESP_RECV_SUCCESS (gprs_gmm.c:681)[0;m [1;33m20241104150047885 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM(262420000000005/d88d157e) Requesting authorization (sgsn_auth.c:160)[0;m [1;33m20241104150047885 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(262420000000005/d88d157e) Missing information, requesting subscriber data (sgsn_auth.c:195)[0;m [1;33m20241104150047885 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM(262420000000005/d88d157e) Requesting subscriber data update (gprs_subscriber.c:894)[0;m 20241104150047885 [1;32mDGPRS[0;m [1;32mINFO[0;m SUBSCR(262420000000005) subscriber data is not available (gprs_subscriber.c:818) 20241104150047885 [1;32mDGPRS[0;m [1;32mINFO[0;m SUBSCR(262420000000005) Sending GSUP, will send: 04 01 08 62 42 02 00 00 00 00 f5 28 01 01 2a 01 01 (gprs_subscriber.c:210) [1;33m20241104150047885 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x564a0d09f060]{Authenticate}: state_chg to WaitAttachComplete (gprs_gmm_attach.c:181)[0;m [1;33m20241104150047885 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(262420000000005/d88d157e) <- GMM ATTACH ACCEPT (new P-TMSI=0xd88d157e) (gprs_gmm.c:300)[0;m TC_attach_gsup_lu_timeout(173)@cf08b375ff3a: setverdict(fail): none -> fail MC@cf08b375ff3a: Test Component 173 has requested to stop MTC. Terminating current testcase execution. 20241104150047903 [1;32mDLGLOBAL[0;m [1;32mINFO[0;m Closing telnet connection r=127.0.0.1:60654<->l=127.0.0.10:4245 (telnet_interface.c:138) [38;5;23m20241104150047904 [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 [38;5;39m20241104150047904 [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 SGSN_Test-GSUP(171)@cf08b375ff3a: Final verdict of PTC: none SGSN_Test-GSUP-IPA(170)@cf08b375ff3a: Final verdict of PTC: none SGSN_Test-Gb1-NS1(160)@cf08b375ff3a: Final verdict of PTC: none SGSN_Test-Gb2-NS2(164)@cf08b375ff3a: Final verdict of PTC: none -NSVCI98(165)@cf08b375ff3a: Final verdict of PTC: none -NSVCI99(169)@cf08b375ff3a: Final verdict of PTC: none SGSN_Test-Gb2-BSSGP2(166)@cf08b375ff3a: Final verdict of PTC: none SGSN_Test-Gb0-NS0(155)@cf08b375ff3a: Final verdict of PTC: none -NSVCI97(159)@cf08b375ff3a: Final verdict of PTC: none SGSN_Test-Gb1-BSSGP1(161)@cf08b375ff3a: Final verdict of PTC: none TC_attach_gsup_lu_timeout-BVCI220(168)@cf08b375ff3a: Final verdict of PTC: none SGSN_Test-Gb0-BSSGP0(156)@cf08b375ff3a: Final verdict of PTC: none TC_attach_gsup_lu_timeout-BVCI210(162)@cf08b375ff3a: Final verdict of PTC: none TC_attach_gsup_lu_timeout-BVCI196(158)@cf08b375ff3a: Final verdict of PTC: none -NSVCI98-provIP(163)@cf08b375ff3a: Final verdict of PTC: none -NSVCI99-provIP(167)@cf08b375ff3a: Final verdict of PTC: none TC_attach_gsup_lu_timeout(173)@cf08b375ff3a: Final verdict of PTC: fail SGSN_Test-GGSN_GTP(172)@cf08b375ff3a: Final verdict of PTC: none -NSVCI97-provIP(157)@cf08b375ff3a: Final verdict of PTC: none MTC@cf08b375ff3a: Setting final verdict of the test case. MTC@cf08b375ff3a: Local verdict of MTC: none MTC@cf08b375ff3a: Local verdict of PTC SGSN_Test-Gb0-NS0(155): none (none -> none) MTC@cf08b375ff3a: Local verdict of PTC SGSN_Test-Gb0-BSSGP0(156): none (none -> none) MTC@cf08b375ff3a: Local verdict of PTC -NSVCI97-provIP(157): none (none -> none) MTC@cf08b375ff3a: Local verdict of PTC TC_attach_gsup_lu_timeout-BVCI196(158): none (none -> none) MTC@cf08b375ff3a: Local verdict of PTC -NSVCI97(159): none (none -> none) MTC@cf08b375ff3a: Local verdict of PTC SGSN_Test-Gb1-NS1(160): none (none -> none) MTC@cf08b375ff3a: Local verdict of PTC SGSN_Test-Gb1-BSSGP1(161): none (none -> none) MTC@cf08b375ff3a: Local verdict of PTC TC_attach_gsup_lu_timeout-BVCI210(162): none (none -> none) MTC@cf08b375ff3a: Local verdict of PTC -NSVCI98-provIP(163): none (none -> none) MTC@cf08b375ff3a: Local verdict of PTC SGSN_Test-Gb2-NS2(164): none (none -> none) MTC@cf08b375ff3a: Local verdict of PTC -NSVCI98(165): none (none -> none) MTC@cf08b375ff3a: Local verdict of PTC SGSN_Test-Gb2-BSSGP2(166): none (none -> none) MTC@cf08b375ff3a: Local verdict of PTC -NSVCI99-provIP(167): none (none -> none) MTC@cf08b375ff3a: Local verdict of PTC TC_attach_gsup_lu_timeout-BVCI220(168): none (none -> none) MTC@cf08b375ff3a: Local verdict of PTC -NSVCI99(169): none (none -> none) MTC@cf08b375ff3a: Local verdict of PTC SGSN_Test-GSUP-IPA(170): none (none -> none) MTC@cf08b375ff3a: Local verdict of PTC SGSN_Test-GSUP(171): none (none -> none) MTC@cf08b375ff3a: Local verdict of PTC SGSN_Test-GGSN_GTP(172): none (none -> none) MTC@cf08b375ff3a: Local verdict of PTC TC_attach_gsup_lu_timeout(173): fail (none -> fail) MTC@cf08b375ff3a: Test case TC_attach_gsup_lu_timeout finished. Verdict: fail MTC@cf08b375ff3a: 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_attach_gsup_lu_timeout fail'. Mon Nov 4 15:00:47 UTC 2024 [1;31m------ SGSN_Tests.TC_attach_gsup_lu_timeout fail ------[0m
Saving talloc report from 127.0.0.10:4245 to SGSN_Tests.TC_attach_gsup_lu_timeout.talloc 20241104150047997 [1;32mDLGLOBAL[0;m [1;32mINFO[0;m Accept()ed new telnet connection r=127.0.0.1:60660<->l=127.0.0.10:4245 (telnet_interface.c:192) 20241104150048788 [1;32mDLGLOBAL[0;m [1;32mINFO[0;m Closing telnet connection r=127.0.0.1:60660<->l=127.0.0.10:4245 (telnet_interface.c:138) Waiting for packet dumper to finish... 0 (prev_count=-1, count=44540) [38;5;39m20241104150048905 [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;39m20241104150048905 [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=44540, count=74796) [38;5;39m20241104150049906 [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;39m20241104150049906 [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@cf08b375ff3a: 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_attach_gsup_lu_timeout fail' was executed successfully (exit status: 0). [38;5;39m20241104150050907 [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;39m20241104150050907 [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;39m20241104150051908 [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 ERROR: /tmp/watchdog was not created, exiting Error: OCI runtime error: crun: the container `cf08b375ff3a17bf44f8f98bc9209bea332dc3accc84c984325b9171a0508b67` is not running [1;91m[testenv] podman container crashed![0m [1;34m[testenv][generic] Looking for a coredump[0m [0;94m[testenv][generic] coredumpctl is not available, won't try to get coredump[0m [1;91m[testenv][generic] stp crashed![0m [1;34m[testenv][generic] Stopping testsuite (2565960)[0m [1;34m[testenv][generic] Logs saved to: https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/247/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