18 Jan
2025
18 Jan
'25
3:58 p.m.
See
<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/323/display/redirect>
Changes:
------------------------------------------
Started by timer
Running as SYSTEM
Building remotely on build4-deb12build-ansible (ttcn3 obs osmo-gsm-tester-build
ttcn3_with_linux_6.1_or_higher qemu io_uring osmocom-gerrit coverity osmocom-master) in
workspace <https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/ws/>
The recommended git tool is: NONE
No credentials specified
git rev-parse --resolve-git-dir
<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/ws/.git> #
timeout=10
Fetching changes from the remote Git repository
Also: hudson.remoting.Channel$CallSiteStackTrace: Remote call to
build4-deb12build-ansible
at hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1826)
at hudson.remoting.UserRequest$ExceptionResponse.retrieve(UserRequest.java:356)
at hudson.remoting.Channel.call(Channel.java:1042)
at hudson.remoting.RemoteInvocationHandler.invoke(RemoteInvocationHandler.java:285)
at PluginClassLoader for
git-client/jdk.proxy11/jdk.proxy11.$Proxy63.withRepository(Unknown Source)
at PluginClassLoader for
git-client//org.jenkinsci.plugins.gitclient.RemoteGitImpl.withRepository(RemoteGitImpl.java:261)
at PluginClassLoader for
git//jenkins.plugins.git.GitHooksConfiguration.configure(GitHooksConfiguration.java:111)
at PluginClassLoader for
git//jenkins.plugins.git.GitHooksConfiguration.configure(GitHooksConfiguration.java:101)
at PluginClassLoader for
git//jenkins.plugins.git.GitHooksConfiguration.configure(GitHooksConfiguration.java:90)
at PluginClassLoader for
git//hudson.plugins.git.GitSCM.retrieveChanges(GitSCM.java:1231)
at PluginClassLoader for git//hudson.plugins.git.GitSCM.checkout(GitSCM.java:1303)
at hudson.scm.SCM.checkout(SCM.java:540)
at hudson.model.AbstractProject.checkout(AbstractProject.java:1247)
at
hudson.model.AbstractBuild$AbstractBuildExecution.defaultCheckout(AbstractBuild.java:649)
at jenkins.scm.SCMCheckoutStrategy.checkout(SCMCheckoutStrategy.java:85)
at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:521)
at hudson.model.Run.execute(Run.java:1894)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:44)
at hudson.model.ResourceController.execute(ResourceController.java:101)
at hudson.model.Executor.run(Executor.java:446)
java.io.IOException: No space left on device
at java.base/java.io.FileOutputStream.writeBytes(Native Method)
at java.base/java.io.FileOutputStream.write(FileOutputStream.java:336)
at PluginClassLoader for
git-client//org.eclipse.jgit.internal.storage.file.LockFile.write(LockFile.java:292)
at PluginClassLoader for
git-client//org.eclipse.jgit.storage.file.FileBasedConfig.save(FileBasedConfig.java:205)
at PluginClassLoader for
git//jenkins.plugins.git.GitHooksConfiguration.disable(GitHooksConfiguration.java:138)
at PluginClassLoader for
git//jenkins.plugins.git.GitHooksConfiguration.lambda$configure$2f1f6402$1(GitHooksConfiguration.java:112)
at PluginClassLoader for
git-client//org.jenkinsci.plugins.gitclient.AbstractGitAPIImpl.withRepository(AbstractGitAPIImpl.java:28)
at PluginClassLoader for
git-client//org.jenkinsci.plugins.gitclient.CliGitAPIImpl.withRepository(CliGitAPIImpl.java:83)
at jdk.internal.reflect.GeneratedMethodAccessor21.invoke(Unknown Source)
at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:568)
at
hudson.remoting.RemoteInvocationHandler$RPCRequest.perform(RemoteInvocationHandler.java:924)
at
hudson.remoting.RemoteInvocationHandler$RPCRequest.call(RemoteInvocationHandler.java:902)
at
hudson.remoting.RemoteInvocationHandler$RPCRequest.call(RemoteInvocationHandler.java:853)
at hudson.remoting.UserRequest.perform(UserRequest.java:211)
at hudson.remoting.UserRequest.perform(UserRequest.java:54)
at hudson.remoting.Request$2.run(Request.java:377)
at
hudson.remoting.InterceptingExecutorService.lambda$wrap$0(InterceptingExecutorService.java:78)
at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
at
java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136)
at
java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
at java.base/java.lang.Thread.run(Thread.java:840)
Recording test results
[Checks API] No suitable checks publisher found.
19 Jan
19 Jan
4:09 p.m.
New subject: Build failed in Jenkins: ttcn3-sgsn-test-io_uring #324
See
<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/324/display/redirect>
Changes:
------------------------------------------
[...truncated 2.96 MiB...]
[38;5;63m20250119150921015 [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;57m20250119150921015 [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)[0x562163c972c0]{BLOCKED}:
Received Event RX-ALIVE (gprs_ns2_vc_fsm.c:957)[0;m
[38;5;63m20250119150921015 [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;63m20250119150921015 [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;57m20250119150921015 [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)[0x562163c972c0]{BLOCKED}:
Received Event RX-UNBLOCK (gprs_ns2_vc_fsm.c:951)[0;m
[38;5;63m20250119150921015 [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;57m20250119150921015 [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)[0x562163c972c0]{BLOCKED}:
state_chg to UNBLOCKED (gprs_ns2_vc_fsm.c:414)[0;m
[38;5;57m20250119150921015 [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
20250119150921015 [1;33mDGPRS[0;m [1;33mNOTICE[0;m NS-E 98 became available
(gprs_ns.c:44)
[38;5;57m20250119150921015 [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
20250119150921015 [1;33mDGPRS[0;m [1;33mNOTICE[0;m NS: indication Unknown prim 2 from
NS (gprs_ns.c:59)
[38;5;63m20250119150921015 [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;57m20250119150921015 [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)[0x562163c972c0]{UNBLOCKED}:
Received Event RX-ALIVE_ACK (gprs_ns2_vc_fsm.c:960)[0;m
-NSVCI99(1177)@ba9e6d5e5358: Rx NS-ALIVE-ACK: stopping Tns-alive; starting Tns-test
-NSVCI99(1177)@ba9e6d5e5358: NSVC 99 State Transition: NSVC_S_ALIVE_BLOCKED (3) ->
NSVC_S_ALIVE_UNBLOCKED (4)
[38;5;57m20250119150921017 [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)[0x562163c972c0]{UNBLOCKED}:
Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
SGSN_Test-Gb2-BSSGP2(1174)@ba9e6d5e5358: BVCI(0) Rx BVC-RESET-ACK
SGSN_Test-Gb2-BSSGP2(1174)@ba9e6d5e5358: BVCI(0) State Transition:
BVC_S_WAIT_NS_ALIVE_UNBLOCKED (1) -> BVC_S_UNBLOCKED (3)
[38;5;57m20250119150921022 [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)[0x562163c972c0]{UNBLOCKED}:
Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
[38;5;59m20250119150921022 [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_suspend_resume-BVCI220(1176)@ba9e6d5e5358: BVCI(220) State Transition: BVC_S_BLOCKED
(2) -> BVC_S_UNBLOCKED (3)
[38;5;39m20250119150921371 [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:77)[0;m
[38;5;23m20250119150921371 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m
127.0.0.103:4222 connection done (ipa.c:144)[0;m
[38;5;39m20250119150921371 [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:154)[0;m
[38;5;39m20250119150921371 [1;34mDLGSUP[0;m[38;5;39m [1;34mDEBUG[0;m[38;5;39m GSUP
sending PING (gsup_client.c:291)[0;m
SGSN_Test-GSUP-IPA(1178)@ba9e6d5e5358: Established a new IPA connection (conn_id=2)
SGSN_Test-GSUP-IPA(1178)@ba9e6d5e5358: 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;23m20250119150921375 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m
received ID_GET for unit ID 0/0/0 (ipa.c:617)[0;m
SGSN_Test-GSUP-IPA(1178)@ba9e6d5e5358: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit
}
SGSN_Test-GSUP-IPA(1178)@ba9e6d5e5358: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit
}
[38;5;39m20250119150921376 [1;34mDLGSUP[0;m[38;5;39m [1;34mDEBUG[0;m[38;5;39m GSUP
receiving PONG (gsup_client.c:228)[0;m
SGSN_Test-GSUP-IPA(1178)@ba9e6d5e5358: CCM Rx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := {
resp := { { len := 6, tag := IPAC_IDTAG_UNITNAME (1), data := '5347534E00'O }, {
len := 7, tag := IPAC_IDTAG_UNITID (8), data := '302F302F3000'O } } } }
SGSN_Test-GSUP-IPA(1178)@ba9e6d5e5358: IPA ID RESP: { { len := 6, tag :=
IPAC_IDTAG_UNITNAME (1), data := '5347534E00'O }, { len := 7, tag :=
IPAC_IDTAG_UNITID (8), data := '302F302F3000'O } }
SGSN_Test-GSUP-IPA(1178)@ba9e6d5e5358: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u :=
omit }
SGSN_Test-GSUP-IPA(1178)@ba9e6d5e5358: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u :=
omit }
TC_suspend_resume-BVCI196(1166)@ba9e6d5e5358: Adding Client=TC_suspend_resume(1181),
IMSI='262420000000049'H, TLLI='E703FAD9'O, index=0
SGSN_Test-GSUP(1179)@ba9e6d5e5358: Created GsupExpect[0] for "262420000000049"
to be handled at TC_suspend_resume(1181)
TC_suspend_resume(1181)@ba9e6d5e5358: 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 := 'C96BCDD60EC2B63CBF3D3802FC22791B'O } }, { tag :=
OSMO_GSUP_SRES_IE (33), len := 0, val := { sres := '886BEBD6'O } }, { tag :=
OSMO_GSUP_KC_IE (34), len := 0, val := { kc := '998FEFB348642577'O } } } } }
[38;5;57m20250119150922435 [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)[0x562163c938a0]{UNBLOCKED}:
Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20250119150922435 [1;33mDLLC[0;m [1;33mNOTICE[0;m LLME(ffffffff/e703fad9){UNASSIGNED}
LLC RX: unknown TLLI 0xe703fad9, creating LLME on the fly (gprs_llc.c:552)
20250119150922435 [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;33m20250119150922435 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(---/ffffffff)
-> GMM ATTACH REQUEST [0;m[1;33mMI(IMSI-262420000000049) type="GPRS attach"
[0;m[1;33m (gprs_gmm.c:1248)[0;m
[1;33m20250119150922435 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
GMM(gmm_fsm)[0x562163c98da0]{Deregistered}: Allocated (fsm.c:456)[0;m
[1;33m20250119150922435 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x562163c99130]{Init}: Allocated (fsm.c:456)[0;m
[1;33m20250119150922435 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
MM_STATE_Gb[0x562163c99000]{Idle}: Allocated (fsm.c:456)[0;m
[1;33m20250119150922435 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
MM_STATE_Iu[0x562163c98ed0]{Detached}: Allocated (fsm.c:456)[0;m
[1;33m20250119150922435 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
GMM(gmm_fsm)[0x562163c98da0]{Deregistered}: Received Event E_GMM_COMMON_PROC_INIT_REQ
(gprs_gmm.c:1126)[0;m
[1;33m20250119150922435 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
GMM(gmm_fsm)[0x562163c98da0]{Deregistered}: state_chg to CommonProcedureInitiated
(gprs_gmm_fsm.c:49)[0;m
20250119150922435 [1;33mDLLC[0;m [1;33mNOTICE[0;m LLME(ffffffff/e703fad9){UNASSIGNED}
LLGM Assign pre (e703fad9 => ca4e85af) (gprs_llc.c:1079)
20250119150922435 [1;33mDLLC[0;m [1;33mNOTICE[0;m LLME(e703fad9/ca4e85af){ASSIGNED}
LLGM Assign post (e703fad9 => ca4e85af) (gprs_llc.c:1125)
[1;33m20250119150922435 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x562163c99130]{Init}: Received Event E_ATTACH_REQ_RECV
(gprs_gmm.c:1352)[0;m
[1;33m20250119150922435 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x562163c99130]{Init}: state_chg to CheckIdentity
(gprs_gmm_attach.c:52)[0;m
[1;33m20250119150922435 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
MM(262420000000049/ca4e85af) <- GMM IDENTITY REQUEST: mi_type=IMEI
(gprs_gmm.c:413)[0;m
[38;5;57m20250119150922453 [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)[0x562163c938a0]{UNBLOCKED}:
Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20250119150922453 [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;33m20250119150922453 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
MM_STATE_Gb(0)[0x562163c99000]{Idle}: Received Event E_MM_PDU_RECEPTION
(gprs_gmm.c:2301)[0;m
[1;33m20250119150922454 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
MM(262420000000049/ca4e85af) -> GMM IDENTITY RESPONSE: MI=IMEI-499990000000490
(gprs_gmm.c:1066)[0;m
[1;33m20250119150922454 [1;33mDMM[0;m[1;33m [1;33mNOTICE[0;m[1;33m
MM(262420000000049/ca4e85af) Stopping *inactive* MM timer 3370 (gprs_gmm.c:97)[0;m
[1;33m20250119150922454 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x562163c99130]{CheckIdentity}: Received Event
E_IDEN_RESP_RECV (gprs_gmm.c:1110)[0;m
[1;33m20250119150922454 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x562163c99130]{CheckIdentity}: state_chg to Authenticate
(gprs_gmm_attach.c:115)[0;m
[1;33m20250119150922454 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x562163c99130]{Authenticate}: state_chg to AskVLR
(gprs_gmm_attach.c:143)[0;m
[1;33m20250119150922454 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
MM(262420000000049/ca4e85af) Requesting authorization (sgsn_auth.c:160)[0;m
[1;33m20250119150922454 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m
MM(262420000000049/ca4e85af) Requesting authentication tuples (sgsn_auth.c:184)[0;m
[1;33m20250119150922454 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
MM(262420000000049/ca4e85af) Requesting subscriber authentication info
(gprs_subscriber.c:921)[0;m
20250119150922454 [1;32mDGPRS[0;m [1;32mINFO[0;m SUBSCR(262420000000049) requesting
auth info (gprs_subscriber.c:805)
20250119150922454 [1;32mDGPRS[0;m [1;32mINFO[0;m SUBSCR(262420000000049) Sending GSUP,
will send: 08 01 08 62 42 02 00 00 00 40 f9 28 01 01 (gprs_subscriber.c:210)
SGSN_Test-GSUP(1179)@ba9e6d5e5358: Found GsupExpect[0] for "262420000000049"
handled at TC_suspend_resume(1181)
SGSN_Test-GSUP(1179)@ba9e6d5e5358: Added IMSI table entry
0TC_suspend_resume(1181)"262420000000049"
20250119150922459 [1;32mDGPRS[0;m [1;32mINFO[0;m SUBSCR(262420000000049) Received GSUP
message OSMO_GSUP_MSGT_SEND_AUTH_INFO_RESULT (gprs_subscriber.c:723)
20250119150922459 [1;32mDGPRS[0;m [1;32mINFO[0;m SUBSCR(262420000000049) Got
SendAuthenticationInfoResult, num_auth_vectors = 1 (gprs_subscriber.c:242)
20250119150922459 [1;34mDGPRS[0;m [1;34mDEBUG[0;m SUBSCR(262420000000049) Adding auth
tuple, cksn = 0 (gprs_subscriber.c:255)
20250119150922459 [1;34mDGPRS[0;m [1;34mDEBUG[0;m SUBSCR(262420000000049) Updating
subscriber authentication info (gprs_subscriber.c:850)
[1;33m20250119150922459 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m
MM(262420000000049/ca4e85af) Subscriber data update (mmctx.c:445)[0;m
[1;33m20250119150922459 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
MM(262420000000049/ca4e85af) Updating authorization (unknown -> authenticate)
(sgsn_auth.c:217)[0;m
[1;33m20250119150922459 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m
MM(262420000000049/ca4e85af) Got authorization update: state unknown -> authenticate
(sgsn_auth.c:246)[0;m
[1;33m20250119150922459 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x562163c99130]{AskVLR}: Received Event E_VLR_ANSWERED
(gprs_gmm.c:973)[0;m
[1;33m20250119150922459 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x562163c99130]{AskVLR}: state_chg to Authenticate
(gprs_gmm_attach.c:253)[0;m
[1;33m20250119150922459 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m
MM(262420000000049/ca4e85af) <- GMM AUTH AND CIPHERING REQ (rand = c9 6b cd d6 0e c2 b6
3c bf 3d 38 02 fc 22 79 1b , mmctx_is_r99=1, vec->auth_types=0x1[0;m[1;33m)
(gprs_gmm.c:468)[0;m
[38;5;57m20250119150922475 [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)[0x562163c938a0]{UNBLOCKED}:
Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20250119150922475 [1;34mDLLC[0;m [1;34mDEBUG[0;m LLC SAPI=1 C U GEA0 IOV-UI=0x000000
FCS=0xe62da8 CMD=UI DATA (gprs_llc_parse.c:89)
[1;33m20250119150922475 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
MM_STATE_Gb(0)[0x562163c99000]{Idle}: Received Event E_MM_PDU_RECEPTION
(gprs_gmm.c:2301)[0;m
[1;33m20250119150922475 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m
MM(262420000000049/ca4e85af) -> GMM AUTH AND CIPH RESPONSE (gprs_gmm.c:602)[0;m
[1;33m20250119150922475 [1;33mDMM[0;m[1;33m [1;33mNOTICE[0;m[1;33m
MM(262420000000049/ca4e85af) Stopping *inactive* MM timer 3360 (gprs_gmm.c:97)[0;m
[1;33m20250119150922475 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
MM(262420000000049/ca4e85af) checking auth: received GSM SRES = 88 6b eb d6
(gprs_gmm.c:648)[0;m
[1;33m20250119150922475 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x562163c99130]{Authenticate}: Received Event
E_AUTH_RESP_RECV_SUCCESS (gprs_gmm.c:663)[0;m
[1;33m20250119150922475 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
MM(262420000000049/ca4e85af) Requesting authorization (sgsn_auth.c:160)[0;m
[1;33m20250119150922475 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m
MM(262420000000049/ca4e85af) Missing information, requesting subscriber data
(sgsn_auth.c:195)[0;m
[1;33m20250119150922475 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
MM(262420000000049/ca4e85af) Requesting subscriber data update
(gprs_subscriber.c:894)[0;m
20250119150922476 [1;32mDGPRS[0;m [1;32mINFO[0;m SUBSCR(262420000000049) subscriber
data is not available (gprs_subscriber.c:818)
20250119150922476 [1;32mDGPRS[0;m [1;32mINFO[0;m SUBSCR(262420000000049) Sending GSUP,
will send: 04 01 08 62 42 02 00 00 00 40 f9 28 01 01 2a 01 01 (gprs_subscriber.c:210)
[1;33m20250119150922476 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x562163c99130]{Authenticate}: state_chg to
WaitAttachComplete (gprs_gmm_attach.c:181)[0;m
[1;33m20250119150922476 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m
MM(262420000000049/ca4e85af) <- GMM ATTACH ACCEPT (new P-TMSI=0xca4e85af)
(gprs_gmm.c:282)[0;m
20250119150922478 [1;32mDGPRS[0;m [1;32mINFO[0;m SUBSCR(262420000000049) Received GSUP
message OSMO_GSUP_MSGT_INSERT_DATA_REQUEST (gprs_subscriber.c:723)
20250119150922478 [1;32mDGPRS[0;m [1;32mINFO[0;m SUBSCR(262420000000049) Will set PDP
info, context id = 0, APN = 2a (gprs_subscriber.c:367)
20250119150922478 [1;34mDGPRS[0;m [1;34mDEBUG[0;m SUBSCR(262420000000049) Updating
subscriber data (gprs_subscriber.c:839)
[1;33m20250119150922478 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m
MM(262420000000049/ca4e85af) Subscriber data update (mmctx.c:445)[0;m
[1;33m20250119150922478 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
MM(262420000000049/ca4e85af) Updating authorization (authenticate -> accepted)
(sgsn_auth.c:217)[0;m
[1;33m20250119150922478 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m
MM(262420000000049/ca4e85af) Got authorization update: state authenticate -> accepted
(sgsn_auth.c:246)[0;m
[1;33m20250119150922478 [1;33mDMM[0;m[1;33m [1;33mNOTICE[0;m[1;33m
MM(262420000000049/ca4e85af) Authorized, continuing procedure, IMSI=262420000000049
(gprs_gmm.c:982)[0;m
[1;33m20250119150922478 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x562163c99130]{WaitAttachComplete}: Received Event
E_VLR_ANSWERED (gprs_gmm.c:987)[0;m
[1;33m20250119150922478 [1;33mDMM[0;m[1;33m [1;33mNOTICE[0;m[1;33m
MM(262420000000049/ca4e85af) Unusual event: if MS got no data connection, check that it
has APN configured. (gprs_gmm_attach.c:217)[0;m
20250119150922478 [1;32mDGPRS[0;m [1;32mINFO[0;m SUBSCR(262420000000049) Sending GSUP,
will send: 12 01 08 62 42 02 00 00 00 40 f9 28 01 01 (gprs_subscriber.c:210)
20250119150922480 [1;32mDGPRS[0;m [1;32mINFO[0;m SUBSCR(262420000000049) Received GSUP
message OSMO_GSUP_MSGT_UPDATE_LOCATION_RESULT (gprs_subscriber.c:723)
20250119150922480 [1;34mDGPRS[0;m [1;34mDEBUG[0;m SUBSCR(262420000000049) Updating
subscriber data (gprs_subscriber.c:839)
[1;33m20250119150922480 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m
MM(262420000000049/ca4e85af) Subscriber data update (mmctx.c:445)[0;m
[1;33m20250119150922480 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
MM(262420000000049/ca4e85af) Updating authorization (accepted -> accepted)
(sgsn_auth.c:217)[0;m
[38;5;57m20250119150922489 [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)[0x562163c938a0]{UNBLOCKED}:
Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20250119150922489 [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;33m20250119150922489 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
MM_STATE_Gb(0)[0x562163c99000]{Idle}: Received Event E_MM_PDU_RECEPTION
(gprs_gmm.c:2301)[0;m
[1;33m20250119150922489 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m
MM(262420000000049/ca4e85af) -> GMM ATTACH COMPLETE (gprs_gmm.c:1379)[0;m
[1;33m20250119150922489 [1;33mDMM[0;m[1;33m [1;33mNOTICE[0;m[1;33m
MM(262420000000049/ca4e85af) Stopping *inactive* MM timer 3350 (gprs_gmm.c:97)[0;m
[1;33m20250119150922489 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
GMM(gmm_fsm)[0x562163c98da0]{CommonProcedureInitiated}: Received Event
E_GMM_ATTACH_SUCCESS (gprs_gmm.c:1391)[0;m
[1;33m20250119150922489 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
GMM(gmm_fsm)[0x562163c98da0]{CommonProcedureInitiated}: state_chg to Registered.NORMAL
(gprs_gmm_fsm.c:72)[0;m
20250119150922489 [1;33mDLLC[0;m [1;33mNOTICE[0;m LLME(e703fad9/ca4e85af){ASSIGNED}
LLGM Assign pre (ffffffff => ca4e85af) (gprs_llc.c:1079)
20250119150922489 [1;33mDLLC[0;m [1;33mNOTICE[0;m LLME(ffffffff/ca4e85af){ASSIGNED}
LLGM Assign post (ffffffff => ca4e85af) (gprs_llc.c:1125)
[1;33m20250119150922489 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
MM_STATE_Gb(0)[0x562163c99000]{Idle}: Received Event E_MM_GPRS_ATTACH
(gprs_gmm.c:1402)[0;m
[1;33m20250119150922489 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
MM_STATE_Gb(0)[0x562163c99000]{Idle}: state_chg to Ready (gprs_mm_state_gb_fsm.c:60)[0;m
[1;33m20250119150922489 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x562163c99130]{WaitAttachComplete}: Received Event
E_ATTACH_COMPLETE_RECV (gprs_gmm.c:1406)[0;m
[1;33m20250119150922489 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x562163c99130]{WaitAttachComplete}: state_chg to Init
(gprs_gmm_attach.c:212)[0;m
TC_suspend_resume(1181)@ba9e6d5e5358: setverdict(pass): none -> pass
[38;5;57m20250119150922544 [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)[0x562163c938a0]{UNBLOCKED}:
Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20250119150922544 [1;34mDLLC[0;m [1;34mDEBUG[0;m LLC SAPI=1 C U GEA0 IOV-UI=0x000000
FCS=0x540e4a CMD=UI DATA (gprs_llc_parse.c:89)
[1;33m20250119150922544 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
MM_STATE_Gb(0)[0x562163c99000]{Ready}: Received Event E_MM_PDU_RECEPTION
(gprs_gmm.c:2301)[0;m
[1;33m20250119150922544 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m
MM(262420000000049/ca4e85af) -> ACTIVATE PDP CONTEXT REQ: SAPI=3 NSAPI=5
[0;m[1;33mIETF [0;m[1;33mIPv4 [0;m[1;33m (gprs_sm.c:473)[0;m
[1;33m20250119150922544 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m
MM(262420000000049/ca4e85af) Found GGSN 0 for APN '' (requested '')
(mmctx.c:593)[0;m
[1;33m20250119150922544 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
MM(262420000000049/ca4e85af) Using GGSN 0 (gprs_sm.c:317)[0;m
20250119150922544 [1;31mDLGLOBAL[0;m [1;31mERROR[0;m counter group
'sgsn:pdpctx' already exists for index 5, instead using index 10. This is a
software bug that needs fixing. (rate_ctr.c:221)
[38;5;35m20250119150922544 [1;34mDLGTP[0;m[38;5;35m [1;34mDEBUG[0;m[38;5;35m Begin
pdp_tidset tid = 5940000000024262 (pdp.c:279)[0;m
[38;5;35m20250119150922544 [1;34mDLGTP[0;m[38;5;35m [1;34mDEBUG[0;m[38;5;35m End
pdp_tidset (pdp.c:288)[0;m
20250119150922544 [1;33mDGPRS[0;m [1;33mNOTICE[0;m PDP(262420000000049/0) Create PDP
Context (sgsn_libgtp.c:186)
[38;5;35m20250119150922544 [1;34mDLGTP[0;m[38;5;35m [1;34mDEBUG[0;m[38;5;35m
Registering seq=1064 in restransmit req queue (gtp.c:402)[0;m
[38;5;35m20250119150922568 [1;34mDLGTP[0;m[38;5;35m [1;34mDEBUG[0;m[38;5;35m Packet
from 127.0.0.103:2123, length: 67 content: 32 11 00 3b 00 00 00 06 04 28 00 00 01 80 08 00
10 d7 bd 34 c5 11 8c 18 21 b6 14 05 7f 7b ff b7 66 85 00 04 7f 00 00 67 85 00 04 7f 00 00
67 87 00 11 00 0b 92 1f 92 96 fe fe 54 05 fe fe 00 5b 5b 6e 6e : Freeing seq=1064 from
retransmit req queue (gtp.c:443)[0;m
20250119150922568 [1;34mDGPRS[0;m [1;34mDEBUG[0;m libgtp cb_conf(type=16, cause=128,
pdp=0x7fc731a26310, cbp=0x562163c93c40) (sgsn_libgtp.c:633)
20250119150922568 [1;32mDGPRS[0;m [1;32mINFO[0;m PDP(262420000000049/0) Received
CREATE PDP CTX CONF, cause=128(Request accepted) (sgsn_libgtp.c:408)
20250119150922568 [1;32mDSNDCP[0;m [1;32mINFO[0;m SNSM-ACTIVATE.ind
(lle=0x562163ca06b8 TLLI=ca4e85af, SAPI=3, NSAPI=5) (gprs_sndcp.c:526)
20250119150922568 [1;32mDGPRS[0;m [1;32mINFO[0;m PDP(262420000000049/0) <- ACTIVATE
PDP CONTEXT ACK (gprs_sm.c:195)
20250119150922568 [1;33mDLLC[0;m [1;33mNOTICE[0;m Sending XID type NULL (8 bytes)
request to MS... (gprs_llc.c:364)
TC_suspend_resume(1181)@ba9e6d5e5358: setverdict(pass): pass -> pass, component reason
not changed
TC_suspend_resume(1181)@ba9e6d5e5358: setverdict(pass): pass -> pass, component reason
not changed
20250119150922575 [1;34mDGPRS[0;m [1;34mDEBUG[0;m GTP DATA IND from GGSN for
262420000000049, length=100 (sgsn_libgtp.c:826)
[38;5;57m20250119150922579 [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)[0x562163c938a0]{UNBLOCKED}:
Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
[1;33m20250119150922579 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
GMM(gmm_fsm)[0x562163c98da0]{Registered.NORMAL}: Received Event E_GMM_SUSPEND
(gprs_gmm.c:2266)[0;m
[1;33m20250119150922579 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
GMM(gmm_fsm)[0x562163c98da0]{Registered.NORMAL}: state_chg to Registered.SUSPENDED
(gprs_gmm_fsm.c:95)[0;m
20250119150922581 [1;34mDGPRS[0;m [1;34mDEBUG[0;m GTP DATA IND from GGSN for
262420000000049, length=100 (sgsn_libgtp.c:826)
[1;33m20250119150922581 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m
MM(262420000000049/ca4e85af) Dropping DL packet for MS in GMM state Registered.SUSPENDED
(sgsn_libgtp.c:853)[0;m
[38;5;57m20250119150927590 [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)[0x562163c938a0]{UNBLOCKED}:
Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
[1;33m20250119150927590 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
GMM(gmm_fsm)[0x562163c98da0]{Registered.SUSPENDED}: Received Event unknown 0x4
(gprs_gmm.c:2292)[0;m
[1;33m20250119150927590 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m
GMM(gmm_fsm)[0x562163c98da0]{Registered.SUSPENDED}: state_chg to Registered.NORMAL
(gprs_gmm_fsm.c:104)[0;m
20250119150927597 [1;34mDGPRS[0;m [1;34mDEBUG[0;m GTP DATA IND from GGSN for
262420000000049, length=100 (sgsn_libgtp.c:826)
TC_suspend_resume-BVCI196(1166)@ba9e6d5e5358: Removing Client
IMSI='262420000000049'H, index=0
TC_suspend_resume(1181)@ba9e6d5e5358: Final verdict of PTC: pass
20250119150927614 [1;32mDLGLOBAL[0;m [1;32mINFO[0;m Closing telnet connection
r=127.0.0.1:42142<->l=127.0.0.10:4245 (telnet_interface.c:138)
SGSN_Test-GSUP(1179)@ba9e6d5e5358: Final verdict of PTC: none
[38;5;23m20250119150927618 [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:69)[0;m
[38;5;39m20250119150927618 [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:154)[0;m
SGSN_Test-GSUP-IPA(1178)@ba9e6d5e5358: Final verdict of PTC: none
TC_suspend_resume-BVCI196(1166)@ba9e6d5e5358: Final verdict of PTC: none
-NSVCI99(1177)@ba9e6d5e5358: Final verdict of PTC: none
TC_suspend_resume-BVCI210(1171)@ba9e6d5e5358: Final verdict of PTC: none
SGSN_Test-Gb0-NS0(1163)@ba9e6d5e5358: Final verdict of PTC: none
-NSVCI98(1172)@ba9e6d5e5358: Final verdict of PTC: none
-NSVCI97(1167)@ba9e6d5e5358: Final verdict of PTC: none
SGSN_Test-Gb1-BSSGP1(1169)@ba9e6d5e5358: Final verdict of PTC: none
SGSN_Test-Gb2-BSSGP2(1174)@ba9e6d5e5358: Final verdict of PTC: none
SGSN_Test-Gb2-NS2(1173)@ba9e6d5e5358: Final verdict of PTC: none
SGSN_Test-Gb0-BSSGP0(1164)@ba9e6d5e5358: Final verdict of PTC: none
SGSN_Test-Gb1-NS1(1168)@ba9e6d5e5358: Final verdict of PTC: none
TC_suspend_resume-BVCI220(1176)@ba9e6d5e5358: Final verdict of PTC: none
SGSN_Test-GGSN_GTP(1180)@ba9e6d5e5358: Final verdict of PTC: none
-NSVCI99-provIP(1175)@ba9e6d5e5358: Final verdict of PTC: none
-NSVCI98-provIP(1170)@ba9e6d5e5358: Final verdict of PTC: none
-NSVCI97-provIP(1165)@ba9e6d5e5358: Final verdict of PTC: none
MTC@ba9e6d5e5358: Setting final verdict of the test case.
MTC@ba9e6d5e5358: Local verdict of MTC: none
MTC@ba9e6d5e5358: Local verdict of PTC SGSN_Test-Gb0-NS0(1163): none (none -> none)
MTC@ba9e6d5e5358: Local verdict of PTC SGSN_Test-Gb0-BSSGP0(1164): none (none -> none)
MTC@ba9e6d5e5358: Local verdict of PTC -NSVCI97-provIP(1165): none (none -> none)
MTC@ba9e6d5e5358: Local verdict of PTC TC_suspend_resume-BVCI196(1166): none (none ->
none)
MTC@ba9e6d5e5358: Local verdict of PTC -NSVCI97(1167): none (none -> none)
MTC@ba9e6d5e5358: Local verdict of PTC SGSN_Test-Gb1-NS1(1168): none (none -> none)
MTC@ba9e6d5e5358: Local verdict of PTC SGSN_Test-Gb1-BSSGP1(1169): none (none -> none)
MTC@ba9e6d5e5358: Local verdict of PTC -NSVCI98-provIP(1170): none (none -> none)
MTC@ba9e6d5e5358: Local verdict of PTC TC_suspend_resume-BVCI210(1171): none (none ->
none)
MTC@ba9e6d5e5358: Local verdict of PTC -NSVCI98(1172): none (none -> none)
MTC@ba9e6d5e5358: Local verdict of PTC SGSN_Test-Gb2-NS2(1173): none (none -> none)
MTC@ba9e6d5e5358: Local verdict of PTC SGSN_Test-Gb2-BSSGP2(1174): none (none -> none)
MTC@ba9e6d5e5358: Local verdict of PTC -NSVCI99-provIP(1175): none (none -> none)
MTC@ba9e6d5e5358: Local verdict of PTC TC_suspend_resume-BVCI220(1176): none (none ->
none)
MTC@ba9e6d5e5358: Local verdict of PTC -NSVCI99(1177): none (none -> none)
MTC@ba9e6d5e5358: Local verdict of PTC SGSN_Test-GSUP-IPA(1178): none (none -> none)
MTC@ba9e6d5e5358: Local verdict of PTC SGSN_Test-GSUP(1179): none (none -> none)
MTC@ba9e6d5e5358: Local verdict of PTC SGSN_Test-GGSN_GTP(1180): none (none -> none)
MTC@ba9e6d5e5358: Local verdict of PTC TC_suspend_resume(1181): pass (none -> pass)
MTC@ba9e6d5e5358: Test case TC_suspend_resume finished. Verdict: pass
MTC@ba9e6d5e5358: 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_suspend_resume pass'.
Sun Jan 19 15:09:27 UTC 2025
[1;32m====== SGSN_Tests.TC_suspend_resume pass ======[0m
Saving talloc report from 127.0.0.10:4245 to SGSN_Tests.TC_suspend_resume.talloc
20250119150927739 [1;32mDLGLOBAL[0;m [1;32mINFO[0;m Accept()ed new telnet connection
r=127.0.0.1:39806<->l=127.0.0.10:4245 (telnet_interface.c:192)
20250119150928141 [1;32mDLGLOBAL[0;m [1;32mINFO[0;m Closing telnet connection
r=127.0.0.1:39806<->l=127.0.0.10:4245 (telnet_interface.c:138)
Waiting for packet dumper to finish... 0 (prev_count=-1, count=96948)
[38;5;39m20250119150928620 [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:77)[0;m
[38;5;39m20250119150928620 [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:154)[0;m
[0;94m[testenv] feed_watchdog_loop: podman container has stopped[0m
Waiting for packet dumper to finish... 1 (prev_count=96948, count=105120)
[38;5;39m20250119150929621 [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:77)[0;m
[38;5;39m20250119150929621 [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:154)[0;m
gzip:
<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/324/artifact/logs/testsuite/SGSN_Tests.TC_suspend_resume.pcap.gz>:
No space left on device
MTC@ba9e6d5e5358: 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_suspend_resume pass' was executed successfully (exit status: 0).
Fatal error during logging: Writing to log file failed. (No space left on device)
Exiting.
Error: Unexpected end of MTC connection from 10.0.2.100 [10.0.2.100].
MC@ba9e6d5e5358: The control connection to MTC is lost. Destroying all PTC connections.
MC@ba9e6d5e5358: MTC terminated.
ttcn3_start: error: the MTC terminated unexpectedly
exit
MC@ba9e6d5e5358: Shutting down session.
MC@ba9e6d5e5358: Shutdown complete.
Fatal error during logging: Writing to log file failed. (No space left on device)
Exiting.
Comparing expected results
'<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/ws/_cache/podman/osmo-ttcn3-hacks/sgsn/expected-results.xml'>
against results in 'junit-xml-7959.log'
--------------------
Summary:
[1;34mskip[0;m: 92
[38;5;39m20250119150930622 [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:77)[0;m
[38;5;39m20250119150930622 [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:154)[0;m
[38;5;39m20250119150931624 [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:77)[0;m
[38;5;39m20250119150931624 [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:154)[0;m
[38;5;39m20250119150932626 [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:77)[0;m
[38;5;39m20250119150932626 [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:154)[0;m
[38;5;39m20250119150933627 [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:77)[0;m
[38;5;39m20250119150933627 [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:154)[0;m
[38;5;39m20250119150934629 [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:77)[0;m
[38;5;39m20250119150934629 [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:154)[0;m
[38;5;39m20250119150935630 [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:77)[0;m
[38;5;39m20250119150935630 [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:154)[0;m
Error: timed out waiting for file
/home/osmocom-build/.local/share/containers/storage/overlay-containers/ba9e6d5e5358ce6de089664eb1ec7f11e2784a58485b660acfee13f5191d3422/userdata/0258ac04fb6b1a23ac0bfb841f88081cb49a0eb8f6612181f8a07c7b2d4382a3/exit/ba9e6d5e5358ce6de089664eb1ec7f11e2784a58485b660acfee13f5191d3422:
internal libpod error
[1;91m[testenv][generic] podman container crashed![0m
[1;34m[testenv][generic] Stopping testsuite (1133770)[0m
[1;34m[testenv][generic] Logs saved to:
https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/324/artifa… [0m
[38;5;39m20250119150936632 [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:77)[0;m
[38;5;39m20250119150936632 [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:154)[0;m
Build step 'Execute shell' marked build as failure
Recording test results
[Checks API] No suitable checks publisher found.
166
days inactive
167
days old
jenkins-notifications@lists.osmocom.org
1 comments
1 participants
participants (1)
-
jenkins＠lists.osmocom.org