Build failed in Jenkins: ttcn3-sgsn-test-io_uring #169 - jenkins-notifications

20 Aug 2024

See
<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/169/display/redirect?page=changes>

Changes:

[Pau Espin Pedrol] ggsn: Split test and relax expectations testing gtpu access over ipv6

[Pau Espin Pedrol] sgsn: Fix running with testenv.py podman setup

[Pau Espin Pedrol] sgsn: Introduce test TC_attach_rau_a_b_wrong_old_ra


------------------------------------------
[...truncated 985.96 KB...]
[1;33m20240820120147445 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x558fdf4b4580]{CheckIdentity}: Timeout of T3370
(fsm.c:317)[0;m
[1;33m20240820120147445 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
MM(262420000000032/f6935c18) <- GMM IDENTITY REQUEST: mi_type=IMEI
(gprs_gmm.c:431)[0;m
[38;5;63m20240820120151520 [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;63m20240820120151521 [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;57m20240820120151521 [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)[0x558fdf4aee10]{UNBLOCKED}:
Received Event RX-ALIVE_ACK (gprs_ns2_vc_fsm.c:960)[0;m
[38;5;63m20240820120151538 [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;63m20240820120151539 [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;57m20240820120151539 [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)[0x558fdf4b12a0]{UNBLOCKED}:
Received Event RX-ALIVE_ACK (gprs_ns2_vc_fsm.c:960)[0;m
[38;5;63m20240820120151554 [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;63m20240820120151556 [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;57m20240820120151556 [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)[0x558fdf4b2d00]{UNBLOCKED}:
Received Event RX-ALIVE_ACK (gprs_ns2_vc_fsm.c:960)[0;m
[1;33m20240820120153446 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x558fdf4b4580]{CheckIdentity}: Timeout of T3370
(fsm.c:317)[0;m
[1;33m20240820120153446 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x558fdf4b4580]{CheckIdentity}: Received Event E_REJECT
(gprs_gmm_attach.c:422)[0;m
[1;33m20240820120153446 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x558fdf4b4580]{CheckIdentity}: state_chg to Reject
(gprs_gmm_attach.c:397)[0;m
[1;33m20240820120153446 [1;33mDMM[0;m[1;33m [1;33mNOTICE[0;m[1;33m
MM(262420000000032/f6935c18) <- GMM ATTACH REJECT: MS identity cannot be derived by the
network (gprs_gmm.c:364)[0;m
[1;33m20240820120153446 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x558fdf4b4580]{Reject}: Deallocated (fsm.c:568)[0;m
[1;33m20240820120153446 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
MM_STATE_Gb(0)[0x558fdf4b46b0]{Idle}: Deallocated (fsm.c:568)[0;m
[1;33m20240820120153446 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
MM_STATE_Iu[0x558fdf4b47e0]{Detached}: Deallocated (fsm.c:568)[0;m
[1;33m20240820120153446 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
GMM(gmm_fsm)[0x558fdf4b4450]{CommonProcedureInitiated}: Deallocated (fsm.c:568)[0;m
20240820120153446 [1;33mDLLC[0;m [1;33mNOTICE[0;m LLME(f5445ec1/f6935c18){ASSIGNED}
LLGM Assign pre (f6935c18 => ffffffff) (gprs_llc.c:1079)
20240820120153446 [1;33mDLLC[0;m [1;33mNOTICE[0;m LLME(00000000/00000000){UNASSIGNED}
LLGM Assign post (f6935c18 => ffffffff) (gprs_llc.c:1125)
TC_attach_no_imei_response(269)@1c739be12bc2: setverdict(pass): none -> pass
TC_attach_no_imei_response-BVCI196(254)@1c739be12bc2: Removing Client
IMSI='262420000000032'H, index=0
TC_attach_no_imei_response(269)@1c739be12bc2: Final verdict of PTC: pass
20240820120153453 [1;32mDLGLOBAL[0;m [1;32mINFO[0;m Closing telnet connection
r=127.0.0.1:59770<->l=127.0.0.10:4245 (telnet_interface.c:138)
SGSN_Test-Gb2-NS2(261)@1c739be12bc2: Final verdict of PTC: none
SGSN_Test-Gb0-NS0(251)@1c739be12bc2: Final verdict of PTC: none
TC_attach_no_imei_response-BVCI220(264)@1c739be12bc2: Final verdict of PTC: none
-NSVCI98(260)@1c739be12bc2: Final verdict of PTC: none
SGSN_Test-Gb1-BSSGP1(257)@1c739be12bc2: Final verdict of PTC: none
SGSN_Test-Gb0-BSSGP0(252)@1c739be12bc2: Final verdict of PTC: none
-NSVCI99(265)@1c739be12bc2: Final verdict of PTC: none
SGSN_Test-GSUP(267)@1c739be12bc2: Final verdict of PTC: none
[38;5;23m20240820120153456 [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;39m20240820120153456 [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-Gb1-NS1(256)@1c739be12bc2: Final verdict of PTC: none
-NSVCI98-provIP(258)@1c739be12bc2: Final verdict of PTC: none
-NSVCI97-provIP(253)@1c739be12bc2: Final verdict of PTC: none
TC_attach_no_imei_response-BVCI210(259)@1c739be12bc2: Final verdict of PTC: none
TC_attach_no_imei_response-BVCI196(254)@1c739be12bc2: Final verdict of PTC: none
-NSVCI99-provIP(263)@1c739be12bc2: Final verdict of PTC: none
-NSVCI97(255)@1c739be12bc2: Final verdict of PTC: none
SGSN_Test-GGSN_GTP(268)@1c739be12bc2: Final verdict of PTC: none
SGSN_Test-GSUP-IPA(266)@1c739be12bc2: Final verdict of PTC: none
SGSN_Test-Gb2-BSSGP2(262)@1c739be12bc2: Final verdict of PTC: none
MTC@1c739be12bc2: Setting final verdict of the test case.
MTC@1c739be12bc2: Local verdict of MTC: none
MTC@1c739be12bc2: Local verdict of PTC SGSN_Test-Gb0-NS0(251): none (none -> none)
MTC@1c739be12bc2: Local verdict of PTC SGSN_Test-Gb0-BSSGP0(252): none (none -> none)
MTC@1c739be12bc2: Local verdict of PTC -NSVCI97-provIP(253): none (none -> none)
MTC@1c739be12bc2: Local verdict of PTC TC_attach_no_imei_response-BVCI196(254): none (none
-> none)
MTC@1c739be12bc2: Local verdict of PTC -NSVCI97(255): none (none -> none)
MTC@1c739be12bc2: Local verdict of PTC SGSN_Test-Gb1-NS1(256): none (none -> none)
MTC@1c739be12bc2: Local verdict of PTC SGSN_Test-Gb1-BSSGP1(257): none (none -> none)
MTC@1c739be12bc2: Local verdict of PTC -NSVCI98-provIP(258): none (none -> none)
MTC@1c739be12bc2: Local verdict of PTC TC_attach_no_imei_response-BVCI210(259): none (none
-> none)
MTC@1c739be12bc2: Local verdict of PTC -NSVCI98(260): none (none -> none)
MTC@1c739be12bc2: Local verdict of PTC SGSN_Test-Gb2-NS2(261): none (none -> none)
MTC@1c739be12bc2: Local verdict of PTC SGSN_Test-Gb2-BSSGP2(262): none (none -> none)
MTC@1c739be12bc2: Local verdict of PTC -NSVCI99-provIP(263): none (none -> none)
MTC@1c739be12bc2: Local verdict of PTC TC_attach_no_imei_response-BVCI220(264): none (none
-> none)
MTC@1c739be12bc2: Local verdict of PTC -NSVCI99(265): none (none -> none)
MTC@1c739be12bc2: Local verdict of PTC SGSN_Test-GSUP-IPA(266): none (none -> none)
MTC@1c739be12bc2: Local verdict of PTC SGSN_Test-GSUP(267): none (none -> none)
MTC@1c739be12bc2: Local verdict of PTC SGSN_Test-GGSN_GTP(268): none (none -> none)
MTC@1c739be12bc2: Local verdict of PTC TC_attach_no_imei_response(269): pass (none ->
pass)
MTC@1c739be12bc2: Test case TC_attach_no_imei_response finished. Verdict: pass
MTC@1c739be12bc2: 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_no_imei_response pass'.
Tue Aug 20 12:01:53 UTC 2024
[1;32m====== SGSN_Tests.TC_attach_no_imei_response pass ======[0m

Waiting for packet dumper to finish... 0 (prev_count=-1, count=62632)
[38;5;39m20240820120154457 [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;39m20240820120154457 [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=62632, count=67152)
[38;5;39m20240820120155458 [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;39m20240820120155458 [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@1c739be12bc2: 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_no_imei_response pass' was executed successfully (exit status:
0).
MTC@1c739be12bc2: 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_attach_no_imsi_response'.
------ SGSN_Tests.TC_attach_no_imsi_response ------
Tue Aug 20 12:01:55 UTC 2024
/usr/bin/dumpcap -q -s 1520 -n -i any -w
"<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/169/artifact/logs/testsuite/SGSN_Tests.TC_attach_no_imsi_response.pcap">
><https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/169/artifact/logs/testsuite/SGSN_Tests.TC_attach_no_imsi_response.pcap.stdout>
2>/tmp/cmderr &
Waiting for packet dumper to start... 0
[38;5;39m20240820120156459 [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;39m20240820120156459 [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@1c739be12bc2: 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_attach_no_imsi_response' was executed successfully (exit status: 0).
MTC@1c739be12bc2: Test case TC_attach_no_imsi_response started.
20240820120156667 [1;32mDLGLOBAL[0;m [1;32mINFO[0;m Accept()ed new telnet connection
r=127.0.0.1:49296<->l=127.0.0.10:4245 (telnet_interface.c:192)
[38;5;35m20240820120156668 [1;32mDLGTP[0;m[38;5;35m [1;32mINFO[0;m[38;5;35m
Clearing req & resp retransmit queues (gsn.c:365)[0;m
SGSN_Test-Gb0-NS0(270)@1c739be12bc2: Creating NSIP provider for
"127.0.0.103":21010
SGSN_Test-Gb0-BSSGP0(271)@1c739be12bc2: BVCI(0) State Transition: BVC_S_WAIT_RESET (0)
-> BVC_S_WAIT_NS_ALIVE_UNBLOCKED (1)
SGSN_Test-Gb0-NS0(270)@1c739be12bc2: Starting NSVC component for { provider := { ip := {
address_family := AF_INET (2), local_udp_port := 21010, local_ip :=
"127.0.0.103", remote_udp_port := 23000, remote_ip := "127.0.0.10",
data_weight := 1, signalling_weight := 1 } }, nsvci := 97 }
-NSVCI97(275)@1c739be12bc2: NSVC 97 State Transition: NSVC_S_DEAD_BLOCKED (1) ->
NSVC_S_DEAD_BLOCKED (1)
-NSVCI97(275)@1c739be12bc2: Provider Link came up: sending NS-RESET
SGSN_Test-Gb1-NS1(274)@1c739be12bc2: Creating NSIP provider for
"127.0.0.103":21011
[38;5;63m20240820120156699 [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;57m20240820120156699 [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)[0x558fdf4aee10]{UNBLOCKED}:
Received Event RX-RESET (gprs_ns2_vc_fsm.c:934)[0;m
[38;5;57m20240820120156699 [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)[0x558fdf4aee10]{UNBLOCKED}:
state_chg to RESET (gprs_ns2_vc_fsm.c:657)[0;m
[38;5;57m20240820120156699 [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
20240820120156699 [1;33mDGPRS[0;m [1;33mNOTICE[0;m NS-E 96 became unavailable
(gprs_ns.c:54)
[38;5;63m20240820120156699 [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;57m20240820120156699 [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)[0x558fdf4aee10]{RESET}:
state_chg to BLOCKED (gprs_ns2_vc_fsm.c:329)[0;m
[38;5;63m20240820120156699 [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;57m20240820120156699 [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)[0x558fdf4aee10]{BLOCKED}:
Starting Tns-alive of 3 seconds (gprs_ns2_vc_fsm.c:177)[0;m
-NSVCI97(275)@1c739be12bc2: NSVC 97 State Transition: NSVC_S_WAIT_RESET (2) ->
NSVC_S_ALIVE_BLOCKED (3)
[38;5;63m20240820120156701 [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;57m20240820120156701 [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)[0x558fdf4aee10]{BLOCKED}:
Received Event RX-ALIVE (gprs_ns2_vc_fsm.c:957)[0;m
[38;5;63m20240820120156701 [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;63m20240820120156701 [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;57m20240820120156701 [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)[0x558fdf4aee10]{BLOCKED}:
Received Event RX-UNBLOCK (gprs_ns2_vc_fsm.c:951)[0;m
[38;5;63m20240820120156701 [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;57m20240820120156701 [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)[0x558fdf4aee10]{BLOCKED}:
state_chg to UNBLOCKED (gprs_ns2_vc_fsm.c:414)[0;m
[38;5;57m20240820120156701 [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
20240820120156701 [1;33mDGPRS[0;m [1;33mNOTICE[0;m NS-E 96 became available
(gprs_ns.c:43)
[38;5;57m20240820120156701 [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
20240820120156701 [1;33mDGPRS[0;m [1;33mNOTICE[0;m NS: indication Unknown prim 2 from
NS (gprs_ns.c:57)
-NSVCI97(275)@1c739be12bc2: Rx NS-ALIVE-ACK: stopping Tns-alive; starting Tns-test
[38;5;63m20240820120156701 [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;57m20240820120156701 [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)[0x558fdf4aee10]{UNBLOCKED}:
Received Event RX-ALIVE_ACK (gprs_ns2_vc_fsm.c:960)[0;m
-NSVCI97(275)@1c739be12bc2: NSVC 97 State Transition: NSVC_S_ALIVE_BLOCKED (3) ->
NSVC_S_ALIVE_UNBLOCKED (4)
[38;5;57m20240820120156704 [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)[0x558fdf4aee10]{UNBLOCKED}:
Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
SGSN_Test-Gb0-BSSGP0(271)@1c739be12bc2: BVCI(0) Rx BVC-RESET-ACK
SGSN_Test-Gb0-BSSGP0(271)@1c739be12bc2: BVCI(0) State Transition:
BVC_S_WAIT_NS_ALIVE_UNBLOCKED (1) -> BVC_S_UNBLOCKED (3)
SGSN_Test-Gb1-BSSGP1(276)@1c739be12bc2: BVCI(0) State Transition: BVC_S_WAIT_RESET (0)
-> BVC_S_WAIT_NS_ALIVE_UNBLOCKED (1)
SGSN_Test-Gb1-NS1(274)@1c739be12bc2: 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 }
[38;5;57m20240820120156712 [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)[0x558fdf4aee10]{UNBLOCKED}:
Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
[38;5;59m20240820120156712 [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:400)[0;m
-NSVCI98(279)@1c739be12bc2: NSVC 98 State Transition: NSVC_S_DEAD_BLOCKED (1) ->
NSVC_S_DEAD_BLOCKED (1)
TC_attach_no_imsi_response-BVCI196(272)@1c739be12bc2: BVCI(196) State Transition:
BVC_S_BLOCKED (2) -> BVC_S_UNBLOCKED (3)
-NSVCI98(279)@1c739be12bc2: Provider Link came up: sending NS-RESET
[38;5;63m20240820120156716 [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;57m20240820120156716 [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)[0x558fdf4b12a0]{UNBLOCKED}:
Received Event RX-RESET (gprs_ns2_vc_fsm.c:934)[0;m
[38;5;57m20240820120156716 [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)[0x558fdf4b12a0]{UNBLOCKED}:
state_chg to RESET (gprs_ns2_vc_fsm.c:657)[0;m
[38;5;57m20240820120156716 [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
20240820120156716 [1;33mDGPRS[0;m [1;33mNOTICE[0;m NS-E 97 became unavailable
(gprs_ns.c:54)
[38;5;63m20240820120156716 [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;57m20240820120156716 [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)[0x558fdf4b12a0]{RESET}:
state_chg to BLOCKED (gprs_ns2_vc_fsm.c:329)[0;m
[38;5;63m20240820120156716 [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;57m20240820120156716 [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)[0x558fdf4b12a0]{BLOCKED}:
Starting Tns-alive of 3 seconds (gprs_ns2_vc_fsm.c:177)[0;m
-NSVCI98(279)@1c739be12bc2: NSVC 98 State Transition: NSVC_S_WAIT_RESET (2) ->
NSVC_S_ALIVE_BLOCKED (3)
[38;5;63m20240820120156717 [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;57m20240820120156717 [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)[0x558fdf4b12a0]{BLOCKED}:
Received Event RX-ALIVE (gprs_ns2_vc_fsm.c:957)[0;m
[38;5;63m20240820120156718 [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;63m20240820120156718 [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;57m20240820120156718 [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)[0x558fdf4b12a0]{BLOCKED}:
Received Event RX-UNBLOCK (gprs_ns2_vc_fsm.c:951)[0;m
[38;5;63m20240820120156718 [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;57m20240820120156718 [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)[0x558fdf4b12a0]{BLOCKED}:
state_chg to UNBLOCKED (gprs_ns2_vc_fsm.c:414)[0;m
[38;5;57m20240820120156718 [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
20240820120156718 [1;33mDGPRS[0;m [1;33mNOTICE[0;m NS-E 97 became available
(gprs_ns.c:43)
[38;5;57m20240820120156718 [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
20240820120156718 [1;33mDGPRS[0;m [1;33mNOTICE[0;m NS: indication Unknown prim 2 from
NS (gprs_ns.c:57)
[38;5;63m20240820120156718 [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;57m20240820120156718 [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)[0x558fdf4b12a0]{UNBLOCKED}:
Received Event RX-ALIVE_ACK (gprs_ns2_vc_fsm.c:960)[0;m
-NSVCI98(279)@1c739be12bc2: Rx NS-ALIVE-ACK: stopping Tns-alive; starting Tns-test
-NSVCI98(279)@1c739be12bc2: NSVC 98 State Transition: NSVC_S_ALIVE_BLOCKED (3) ->
NSVC_S_ALIVE_UNBLOCKED (4)
[38;5;57m20240820120156721 [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)[0x558fdf4b12a0]{UNBLOCKED}:
Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
SGSN_Test-Gb2-NS2(280)@1c739be12bc2: Creating NSIP provider for
"127.0.0.103":21012
SGSN_Test-Gb1-BSSGP1(276)@1c739be12bc2: BVCI(0) Rx BVC-RESET-ACK
SGSN_Test-Gb1-BSSGP1(276)@1c739be12bc2: BVCI(0) State Transition:
BVC_S_WAIT_NS_ALIVE_UNBLOCKED (1) -> BVC_S_UNBLOCKED (3)
[38;5;57m20240820120156729 [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)[0x558fdf4b12a0]{UNBLOCKED}:
Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
[38;5;59m20240820120156729 [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:400)[0;m
TC_attach_no_imsi_response-BVCI210(278)@1c739be12bc2: BVCI(210) State Transition:
BVC_S_BLOCKED (2) -> BVC_S_UNBLOCKED (3)
SGSN_Test-Gb2-BSSGP2(281)@1c739be12bc2: BVCI(0) State Transition: BVC_S_WAIT_RESET (0)
-> BVC_S_WAIT_NS_ALIVE_UNBLOCKED (1)
SGSN_Test-Gb2-NS2(280)@1c739be12bc2: 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(284)@1c739be12bc2: NSVC 99 State Transition: NSVC_S_DEAD_BLOCKED (1) ->
NSVC_S_DEAD_BLOCKED (1)
-NSVCI99(284)@1c739be12bc2: Provider Link came up: sending NS-RESET
[38;5;63m20240820120156738 [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;57m20240820120156738 [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)[0x558fdf4b2d00]{UNBLOCKED}:
Received Event RX-RESET (gprs_ns2_vc_fsm.c:934)[0;m
[38;5;57m20240820120156738 [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)[0x558fdf4b2d00]{UNBLOCKED}:
state_chg to RESET (gprs_ns2_vc_fsm.c:657)[0;m
[38;5;57m20240820120156738 [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
20240820120156738 [1;33mDGPRS[0;m [1;33mNOTICE[0;m NS-E 98 became unavailable
(gprs_ns.c:54)
[38;5;63m20240820120156738 [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;57m20240820120156738 [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)[0x558fdf4b2d00]{RESET}:
state_chg to BLOCKED (gprs_ns2_vc_fsm.c:329)[0;m
[38;5;63m20240820120156738 [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;57m20240820120156738 [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)[0x558fdf4b2d00]{BLOCKED}:
Starting Tns-alive of 3 seconds (gprs_ns2_vc_fsm.c:177)[0;m
-NSVCI99(284)@1c739be12bc2: NSVC 99 State Transition: NSVC_S_WAIT_RESET (2) ->
NSVC_S_ALIVE_BLOCKED (3)
[38;5;63m20240820120156739 [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;57m20240820120156739 [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)[0x558fdf4b2d00]{BLOCKED}:
Received Event RX-ALIVE (gprs_ns2_vc_fsm.c:957)[0;m
[38;5;63m20240820120156739 [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;63m20240820120156739 [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;57m20240820120156739 [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)[0x558fdf4b2d00]{BLOCKED}:
Received Event RX-UNBLOCK (gprs_ns2_vc_fsm.c:951)[0;m
[38;5;63m20240820120156739 [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;57m20240820120156740 [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)[0x558fdf4b2d00]{BLOCKED}:
state_chg to UNBLOCKED (gprs_ns2_vc_fsm.c:414)[0;m
[38;5;57m20240820120156740 [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
20240820120156740 [1;33mDGPRS[0;m [1;33mNOTICE[0;m NS-E 98 became available
(gprs_ns.c:43)
[38;5;57m20240820120156740 [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
20240820120156740 [1;33mDGPRS[0;m [1;33mNOTICE[0;m NS: indication Unknown prim 2 from
NS (gprs_ns.c:57)
[38;5;63m20240820120156740 [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;57m20240820120156740 [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)[0x558fdf4b2d00]{UNBLOCKED}:
Received Event RX-ALIVE_ACK (gprs_ns2_vc_fsm.c:960)[0;m
-NSVCI99(284)@1c739be12bc2: Rx NS-ALIVE-ACK: stopping Tns-alive; starting Tns-test
-NSVCI99(284)@1c739be12bc2: NSVC 99 State Transition: NSVC_S_ALIVE_BLOCKED (3) ->
NSVC_S_ALIVE_UNBLOCKED (4)
[38;5;57m20240820120156743 [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)[0x558fdf4b2d00]{UNBLOCKED}:
Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
SGSN_Test-Gb2-BSSGP2(281)@1c739be12bc2: BVCI(0) Rx BVC-RESET-ACK
SGSN_Test-Gb2-BSSGP2(281)@1c739be12bc2: BVCI(0) State Transition:
BVC_S_WAIT_NS_ALIVE_UNBLOCKED (1) -> BVC_S_UNBLOCKED (3)
[38;5;57m20240820120156749 [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)[0x558fdf4b2d00]{UNBLOCKED}:
Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
[38;5;59m20240820120156749 [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_attach_no_imsi_response-BVCI220(283)@1c739be12bc2: BVCI(220) State Transition:
BVC_S_BLOCKED (2) -> BVC_S_UNBLOCKED (3)
[38;5;39m20240820120157459 [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;23m20240820120157459 [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;39m20240820120157459 [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;39m20240820120157459 [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(285)@1c739be12bc2: Established a new IPA connection (conn_id=2)
SGSN_Test-GSUP-IPA(285)@1c739be12bc2: 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;23m20240820120157460 [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
[38;5;39m20240820120157461 [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(285)@1c739be12bc2: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit
}
SGSN_Test-GSUP-IPA(285)@1c739be12bc2: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit
}
SGSN_Test-GSUP-IPA(285)@1c739be12bc2: 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(285)@1c739be12bc2: 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(285)@1c739be12bc2: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit
}
SGSN_Test-GSUP-IPA(285)@1c739be12bc2: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit
}
TC_attach_no_imsi_response-BVCI196(272)@1c739be12bc2: Adding
Client=TC_attach_no_imsi_response(288), IMSI='262420000000035'H,
TLLI='D923B82A'O, index=0
SGSN_Test-GSUP(286)@1c739be12bc2: Created GsupExpect[0] for "262420000000035" to
be handled at TC_attach_no_imsi_response(288)
[38;5;57m20240820120158482 [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)[0x558fdf4aee10]{UNBLOCKED}:
Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20240820120158482 [1;33mDLLC[0;m [1;33mNOTICE[0;m LLME(ffffffff/d923b82a){UNASSIGNED}
LLC RX: unknown TLLI 0xd923b82a, creating LLME on the fly (gprs_llc.c:552)
20240820120158482 [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;33m20240820120158482 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(---/ffffffff)
-> GMM ATTACH REQUEST [0;m[1;33mMI(TMSI-0xC0000035) type="Combined GPRS/IMSI
attach" [0;m[1;33m (gprs_gmm.c:1266)[0;m
[1;33m20240820120158482 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
GMM(gmm_fsm)[0x558fdf4b4450]{Deregistered}: Allocated (fsm.c:456)[0;m
[1;33m20240820120158482 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x558fdf4b47e0]{Init}: Allocated (fsm.c:456)[0;m
[1;33m20240820120158482 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
MM_STATE_Gb[0x558fdf4b46b0]{Idle}: Allocated (fsm.c:456)[0;m
[1;33m20240820120158482 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
MM_STATE_Iu[0x558fdf4b4580]{Detached}: Allocated (fsm.c:456)[0;m
[1;33m20240820120158482 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
GMM(gmm_fsm)[0x558fdf4b4450]{Deregistered}: Received Event E_GMM_COMMON_PROC_INIT_REQ
(gprs_gmm.c:1144)[0;m
[1;33m20240820120158482 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
GMM(gmm_fsm)[0x558fdf4b4450]{Deregistered}: state_chg to CommonProcedureInitiated
(gprs_gmm_fsm.c:49)[0;m
20240820120158482 [1;33mDLLC[0;m [1;33mNOTICE[0;m LLME(ffffffff/d923b82a){UNASSIGNED}
LLGM Assign pre (d923b82a => d64c93cc) (gprs_llc.c:1079)
20240820120158482 [1;33mDLLC[0;m [1;33mNOTICE[0;m LLME(d923b82a/d64c93cc){ASSIGNED}
LLGM Assign post (d923b82a => d64c93cc) (gprs_llc.c:1125)
[1;33m20240820120158482 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x558fdf4b47e0]{Init}: Received Event E_ATTACH_REQ_RECV
(gprs_gmm.c:1370)[0;m
[1;33m20240820120158482 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x558fdf4b47e0]{Init}: state_chg to CheckIdentity
(gprs_gmm_attach.c:52)[0;m
[1;33m20240820120158482 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM(/d64c93cc)
<- GMM IDENTITY REQUEST: mi_type=IMEI (gprs_gmm.c:431)[0;m
[38;5;57m20240820120158490 [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)[0x558fdf4aee10]{UNBLOCKED}:
Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20240820120158490 [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;33m20240820120158490 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
MM_STATE_Gb(3642996778)[0x558fdf4b46b0]{Idle}: Received Event E_MM_PDU_RECEPTION
(gprs_gmm.c:2333)[0;m
[1;33m20240820120158490 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM(/d64c93cc)
-> GMM IDENTITY RESPONSE: MI=IMEI-499990000000350 (gprs_gmm.c:1084)[0;m
[1;33m20240820120158490 [1;33mDMM[0;m[1;33m [1;33mNOTICE[0;m[1;33m MM(/d64c93cc)
Stopping *inactive* MM timer 3370 (gprs_gmm.c:115)[0;m
[1;33m20240820120158490 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x558fdf4b47e0]{CheckIdentity}: Received Event
E_IDEN_RESP_RECV (gprs_gmm.c:1128)[0;m
[1;33m20240820120158490 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x558fdf4b47e0]{CheckIdentity}: state_chg to CheckIdentity
(gprs_gmm_attach.c:113)[0;m
[1;33m20240820120158491 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM(/d64c93cc)
<- GMM IDENTITY REQUEST: mi_type=IMSI (gprs_gmm.c:431)[0;m
20240820120202437 [1;34mDGPRS[0;m [1;34mDEBUG[0;m Checking for inactive LLMEs, time =
26683521 (sgsn.c:131)
[1;33m20240820120204491 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x558fdf4b47e0]{CheckIdentity}: Timeout of T3370
(fsm.c:317)[0;m
[1;33m20240820120204491 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM(/d64c93cc)
<- GMM IDENTITY REQUEST: mi_type=IMSI (gprs_gmm.c:431)[0;m
[1;33m20240820120210492 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x558fdf4b47e0]{CheckIdentity}: Timeout of T3370
(fsm.c:317)[0;m
[1;33m20240820120210492 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM(/d64c93cc)
<- GMM IDENTITY REQUEST: mi_type=IMSI (gprs_gmm.c:431)[0;m
[1;91m[testenv][generic] stp crashed![0m
Error: container 1c739be12bc2f8a482dd66c05d277611429f392a20a4bb4a23b7f966ba5b93d9 does not
exist in database: no such container
[1;91m[testenv] podman container crashed![0m
[1;34m[testenv][generic] Stopping testsuite (590140)[0m
[1;34m[testenv][generic] Logs saved to:
https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/169/artifa… [0m
Build step 'Execute shell' marked build as failure
Recording test results
[Checks API] No suitable checks publisher found.