jenkins-notifications
December 2025
jenkins-notifications@lists.osmocom.org
- 1 participants
- 113 discussions
23 Dec '25
See <https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-sccplite-asan/173/di…>
Changes:
[Pau Espin Pedrol] library/NGAP_Templates: PDUSessionResourceSetupRequest: Fix criticality
------------------------------------------
[...truncated 47.56 KiB...]
DEP Osmocom_CTRL_Types_part_5.cc
DEP Osmocom_CTRL_Types_part_6.cc
DEP Osmocom_CTRL_Types_part_7.cc
DEP Osmocom_Types_part_1.cc
DEP Osmocom_Types_part_2.cc
DEP Osmocom_Types_part_3.cc
DEP Osmocom_Types_part_4.cc
DEP Osmocom_Types_part_5.cc
DEP Osmocom_Types_part_6.cc
DEP Osmocom_Types_part_7.cc
DEP Osmocom_VTY_Functions_part_1.cc
DEP Osmocom_VTY_Functions_part_2.cc
DEP Osmocom_VTY_Functions_part_3.cc
DEP Osmocom_VTY_Functions_part_4.cc
DEP Osmocom_VTY_Functions_part_5.cc
DEP Osmocom_VTY_Functions_part_6.cc
DEP Osmocom_VTY_Functions_part_7.cc
DEP RLCMAC_CSN1_Templates_part_1.cc
DEP RLCMAC_CSN1_Templates_part_2.cc
DEP RLCMAC_CSN1_Templates_part_3.cc
DEP RLCMAC_CSN1_Templates_part_4.cc
DEP RLCMAC_CSN1_Templates_part_5.cc
DEP RLCMAC_CSN1_Templates_part_6.cc
DEP RLCMAC_CSN1_Templates_part_7.cc
DEP RLCMAC_CSN1_Types_part_1.cc
DEP RLCMAC_CSN1_Types_part_2.cc
DEP RLCMAC_CSN1_Types_part_3.cc
DEP RLCMAC_CSN1_Types_part_4.cc
DEP RLCMAC_CSN1_Types_part_5.cc
DEP RLCMAC_CSN1_Types_part_6.cc
DEP RLCMAC_CSN1_Types_part_7.cc
DEP RSL_Emulation_part_1.cc
DEP RSL_Emulation_part_2.cc
DEP RSL_Emulation_part_3.cc
DEP RSL_Emulation_part_4.cc
DEP RSL_Emulation_part_5.cc
DEP RSL_Emulation_part_6.cc
DEP RSL_Emulation_part_7.cc
DEP RSL_Types_part_1.cc
DEP RSL_Types_part_2.cc
DEP RSL_Types_part_3.cc
DEP RSL_Types_part_4.cc
DEP RSL_Types_part_5.cc
DEP RSL_Types_part_6.cc
DEP RSL_Types_part_7.cc
DEP RTP_CodecPort_part_1.cc
DEP RTP_CodecPort_part_2.cc
DEP RTP_CodecPort_part_3.cc
DEP RTP_CodecPort_part_4.cc
DEP RTP_CodecPort_part_5.cc
DEP RTP_CodecPort_part_6.cc
DEP RTP_CodecPort_part_7.cc
DEP RTP_CodecPort_CtrlFunct_part_1.cc
DEP RTP_CodecPort_CtrlFunct_part_2.cc
DEP RTP_CodecPort_CtrlFunct_part_3.cc
DEP RTP_CodecPort_CtrlFunct_part_4.cc
DEP RTP_CodecPort_CtrlFunct_part_5.cc
DEP RTP_CodecPort_CtrlFunct_part_6.cc
DEP RTP_CodecPort_CtrlFunct_part_7.cc
DEP RTP_Emulation_part_1.cc
DEP RTP_Emulation_part_2.cc
DEP RTP_Emulation_part_3.cc
DEP RTP_Emulation_part_4.cc
DEP RTP_Emulation_part_5.cc
DEP RTP_Emulation_part_6.cc
DEP RTP_Emulation_part_7.cc
DEP RTP_Types_part_1.cc
DEP RTP_Types_part_2.cc
DEP RTP_Types_part_3.cc
DEP RTP_Types_part_4.cc
DEP RTP_Types_part_5.cc
DEP RTP_Types_part_6.cc
DEP RTP_Types_part_7.cc
DEP SCCP_Emulation_part_1.cc
DEP SCCP_Emulation_part_2.cc
DEP SCCP_Emulation_part_3.cc
DEP SCCP_Emulation_part_4.cc
DEP SCCP_Emulation_part_5.cc
DEP SCCP_Emulation_part_6.cc
DEP SCCP_Emulation_part_7.cc
DEP SCCP_Templates_part_1.cc
DEP SCCP_Templates_part_2.cc
DEP SCCP_Templates_part_3.cc
DEP SCCP_Templates_part_4.cc
DEP SCCP_Templates_part_5.cc
DEP SCCP_Templates_part_6.cc
DEP SCCP_Templates_part_7.cc
DEP SCCP_Types_part_1.cc
DEP SCCP_Types_part_2.cc
DEP SCCP_Types_part_3.cc
DEP SCCP_Types_part_4.cc
DEP SCCP_Types_part_5.cc
DEP SCCP_Types_part_6.cc
DEP SCCP_Types_part_7.cc
DEP SCCPasp_Types_part_1.cc
DEP SCCPasp_Types_part_2.cc
DEP SCCPasp_Types_part_3.cc
DEP SCCPasp_Types_part_4.cc
DEP SCCPasp_Types_part_5.cc
DEP SCCPasp_Types_part_6.cc
DEP SCCPasp_Types_part_7.cc
DEP SCTPasp_PortType_part_1.cc
DEP SCTPasp_PortType_part_2.cc
DEP SCTPasp_PortType_part_3.cc
DEP SCTPasp_PortType_part_4.cc
DEP SCTPasp_PortType_part_5.cc
DEP SCTPasp_PortType_part_6.cc
DEP SCTPasp_PortType_part_7.cc
DEP SCTPasp_Types_part_1.cc
DEP SCTPasp_Types_part_2.cc
DEP SCTPasp_Types_part_3.cc
DEP SCTPasp_Types_part_4.cc
DEP SCTPasp_Types_part_5.cc
DEP SCTPasp_Types_part_6.cc
DEP SCTPasp_Types_part_7.cc
DEP SDP_Templates_part_1.cc
DEP SDP_Templates_part_2.cc
DEP SDP_Templates_part_3.cc
DEP SDP_Templates_part_4.cc
DEP SDP_Templates_part_5.cc
DEP SDP_Templates_part_6.cc
DEP SDP_Templates_part_7.cc
DEP SDP_Types_part_1.cc
DEP SDP_Types_part_2.cc
DEP SDP_Types_part_3.cc
DEP SDP_Types_part_4.cc
DEP SDP_Types_part_5.cc
DEP SDP_Types_part_6.cc
DEP SDP_Types_part_7.cc
DEP Socket_API_Definitions_part_1.cc
DEP Socket_API_Definitions_part_2.cc
DEP Socket_API_Definitions_part_3.cc
DEP Socket_API_Definitions_part_4.cc
DEP Socket_API_Definitions_part_5.cc
DEP Socket_API_Definitions_part_6.cc
DEP Socket_API_Definitions_part_7.cc
DEP StatsD_CodecPort_part_1.cc
DEP StatsD_CodecPort_part_2.cc
DEP StatsD_CodecPort_part_3.cc
DEP StatsD_CodecPort_part_4.cc
DEP StatsD_CodecPort_part_5.cc
DEP StatsD_CodecPort_part_6.cc
DEP StatsD_CodecPort_part_7.cc
DEP StatsD_CodecPort_CtrlFunct_part_1.cc
DEP StatsD_CodecPort_CtrlFunct_part_2.cc
DEP StatsD_CodecPort_CtrlFunct_part_3.cc
DEP StatsD_CodecPort_CtrlFunct_part_4.cc
DEP StatsD_CodecPort_CtrlFunct_part_5.cc
DEP StatsD_CodecPort_CtrlFunct_part_6.cc
DEP StatsD_CodecPort_CtrlFunct_part_7.cc
DEP StatsD_Types_part_1.cc
DEP StatsD_Types_part_2.cc
DEP StatsD_Types_part_3.cc
DEP StatsD_Types_part_4.cc
DEP StatsD_Types_part_5.cc
DEP StatsD_Types_part_6.cc
DEP StatsD_Types_part_7.cc
DEP TCCConversion_Functions_part_1.cc
DEP TCCConversion_Functions_part_2.cc
DEP TCCConversion_Functions_part_3.cc
DEP TCCConversion_Functions_part_4.cc
DEP TCCConversion_Functions_part_5.cc
DEP TCCConversion_Functions_part_6.cc
DEP TCCInterface_Functions_part_1.cc
DEP TCCInterface_Functions_part_2.cc
DEP TCCConversion_Functions_part_7.cc
DEP TCCInterface_Functions_part_3.cc
DEP TCCInterface_Functions_part_4.cc
DEP TCCInterface_Functions_part_5.cc
DEP TCCInterface_Functions_part_6.cc
DEP TCCInterface_Functions_part_7.cc
DEP TELNETasp_PortType_part_1.cc
DEP TELNETasp_PortType_part_2.cc
DEP TELNETasp_PortType_part_3.cc
DEP TELNETasp_PortType_part_4.cc
DEP TELNETasp_PortType_part_5.cc
DEP TELNETasp_PortType_part_6.cc
DEP TELNETasp_PortType_part_7.cc
DEP IPA_Emulation.cc
DEP RAN_Adapter.cc
DEP RAN_Emulation.cc
DEP SCCP_Adapter.cc
DEP SCCP_Mapping.cc
DEP StatsD_Checker.cc
DEP IPA_Emulation_part_1.cc
DEP IPA_Emulation_part_2.cc
DEP IPA_Emulation_part_3.cc
DEP IPA_Emulation_part_4.cc
DEP IPA_Emulation_part_5.cc
FATAL: command execution failed
java.lang.OutOfMemoryError: Java heap space
Caused: java.io.IOException: Unexpected reader termination
at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:98)
Caused: java.io.IOException: Backing channel 'build4-deb12build-ansible' is disconnected.
at hudson.remoting.RemoteInvocationHandler.channelOrFail(RemoteInvocationHandler.java:227)
at hudson.remoting.RemoteInvocationHandler.invoke(RemoteInvocationHandler.java:306)
at jdk.proxy2/jdk.proxy2.$Proxy156.isAlive(Unknown Source)
at hudson.Launcher$RemoteLauncher$ProcImpl.isAlive(Launcher.java:1212)
at hudson.Launcher$RemoteLauncher$ProcImpl.join(Launcher.java:1204)
at hudson.tasks.CommandInterpreter.join(CommandInterpreter.java:195)
at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:145)
at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:92)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:818)
at hudson.model.Build$BuildExecution.build(Build.java:199)
at hudson.model.Build$BuildExecution.doRun(Build.java:164)
at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:527)
at hudson.model.Run.execute(Run.java:1833)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:44)
at hudson.model.ResourceController.execute(ResourceController.java:101)
at hudson.model.Executor.run(Executor.java:446)
FATAL: Unable to delete script file /tmp/jenkins5153914970880604229.sh
java.lang.OutOfMemoryError: Java heap space
Caused: java.io.IOException: Unexpected reader termination
at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:98)
Caused: hudson.remoting.ChannelClosedException: Channel "hudson.remoting.Channel@2dbf888b:build4-deb12build-ansible": Remote call on build4-deb12build-ansible failed. The channel is closing down or has closed down
at hudson.remoting.Channel.call(Channel.java:1105)
at hudson.FilePath.act(FilePath.java:1207)
at hudson.FilePath.act(FilePath.java:1196)
at hudson.FilePath.delete(FilePath.java:1743)
at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:163)
at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:92)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:818)
at hudson.model.Build$BuildExecution.build(Build.java:199)
at hudson.model.Build$BuildExecution.doRun(Build.java:164)
at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:527)
at hudson.model.Run.execute(Run.java:1833)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:44)
at hudson.model.ResourceController.execute(ResourceController.java:101)
at hudson.model.Executor.run(Executor.java:446)
Build step 'Execute shell' marked build as failure
FATAL: Channel "hudson.remoting.Channel@2dbf888b:build4-deb12build-ansible": Remote call on build4-deb12build-ansible failed. The channel is closing down or has closed down
java.lang.OutOfMemoryError: Java heap space
Caused: java.io.IOException: Unexpected reader termination
at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:98)
Caused: hudson.remoting.ChannelClosedException: Channel "hudson.remoting.Channel@2dbf888b:build4-deb12build-ansible": Remote call on build4-deb12build-ansible failed. The channel is closing down or has closed down
at hudson.remoting.Channel.call(Channel.java:1105)
at hudson.Launcher$RemoteLauncher.kill(Launcher.java:1147)
at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:539)
at hudson.model.Run.execute(Run.java:1833)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:44)
at hudson.model.ResourceController.execute(ResourceController.java:101)
at hudson.model.Executor.run(Executor.java:446)
Recording test results
Agent went offline during the build
ERROR: Connection was broken
Build step 'Publish JUnit test result report' marked build as failure
DEP IPA_Emulation_part_6.cc
1
1
23 Dec '25
See <https://jenkins.osmocom.org/jenkins/job/ttcn3-gbproxy-test-asan/563/display…>
Changes:
------------------------------------------
Started by timer
Running as SYSTEM
Building remotely on build4-deb12build-ansible (registry-build-amd64 ttcn3 obs osmo-gsm-tester-build ttcn3_with_linux_6.1_or_higher qemu io_uring osmocom-gerrit coverity osmocom-master) in workspace /home/osmocom-build/jenkins/workspace/ttcn3-gbproxy-test-asan
The recommended git tool is: NONE
No credentials specified
FATAL: java.io.IOException: Unexpected termination of the channel
java.io.EOFException
at java.base/java.io.ObjectInputStream$PeekInputStream.readFully(Unknown Source)
at java.base/java.io.ObjectInputStream$BlockDataInputStream.readShort(Unknown Source)
at java.base/java.io.ObjectInputStream.readStreamHeader(Unknown Source)
at java.base/java.io.ObjectInputStream.<init>(Unknown Source)
at hudson.remoting.ObjectInputStreamEx.<init>(ObjectInputStreamEx.java:50)
at hudson.remoting.Command.readFrom(Command.java:141)
at hudson.remoting.Command.readFrom(Command.java:127)
at hudson.remoting.AbstractSynchronousByteArrayCommandTransport.read(AbstractSynchronousByteArrayCommandTransport.java:35)
at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:62)
Caused: java.io.IOException: Unexpected termination of the channel
at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:80)
Also: hudson.remoting.Channel$CallSiteStackTrace: Remote call to build4-deb12build-ansible
at hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1923)
at hudson.remoting.Request.call(Request.java:204)
at hudson.remoting.Channel.call(Channel.java:1111)
at hudson.remoting.RemoteInvocationHandler.invoke(RemoteInvocationHandler.java:306)
at PluginClassLoader for git-client/jdk.proxy68/jdk.proxy68.$Proxy152.hasGitRepo(Unknown Source)
at PluginClassLoader for git-client//org.jenkinsci.plugins.gitclient.RemoteGitImpl.hasGitRepo(RemoteGitImpl.java:330)
at PluginClassLoader for git//hudson.plugins.git.GitSCM.retrieveChanges(GitSCM.java:1205)
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:1250)
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:522)
at hudson.model.Run.execute(Run.java:1833)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:44)
at hudson.model.ResourceController.execute(ResourceController.java:101)
at hudson.model.Executor.run(Executor.java:446)
Caused: hudson.remoting.RequestAbortedException
at hudson.remoting.Request.abort(Request.java:358)
at hudson.remoting.Channel.terminate(Channel.java:1196)
at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:95)
ERROR: Step ‘Publish JUnit test result report’ failed: no workspace for ttcn3-gbproxy-test-asan #563
1
1
23 Dec '25
See <https://jenkins.osmocom.org/jenkins/job/ttcn3-ggsn-test-asan/565/display/re…>
Changes:
[Pau Espin Pedrol] library/NGAP_Templates: PDUSessionResourceSetupRequest: Fix criticality
------------------------------------------
[...truncated 212.37 KiB...]
MTC@6e07f179bda6: "TC_pdp46_act_deact_gtpu_access_wrong_ll_saddr_ipv6() start"
20251222170130260 [1;33mDLGLOBAL[0;m [1;33mNOTICE[0;m TTCN3 f_logp(): TC_pdp46_act_deact_gtpu_access_wrong_ll_saddr_ipv6() start (logging_vty.c:1209)
MTC@6e07f179bda6: sending CreatePDP
20251222170130264 [1;32mDGGSN[0;m [1;32mINFO[0;m SGSN(172.18.3.1): Discovered (sgsn.c:83)
20251222170130264 [1;32mDGGSN[0;m [1;32mINFO[0;m PDP(262427287298525:10): Successful PDP Context Creation: APN=inet46(inet46), TEIC=1, IPv4=176.16.32.9, IPv6=2001:780:44:2109:: (ggsn.c:619)
MTC@6e07f179bda6: setverdict(pass): none -> pass
20251222170130266 [1;31mDGGSN[0;m [1;31mERROR[0;m PDP(262427287298525:10): Packet from MS using unassigned src IPv6: fe80::3110:1691:1155:3018 (ggsn.c:785)
MTC@6e07f179bda6: Warning: Stopping inactive timer T_default.
20251222170133271 [1;32mDGGSN[0;m [1;32mINFO[0;m PDP(262427287298525:10): Deleting PDP context (ggsn.c:403)
20251222170133271 [1;32mDGGSN[0;m [1;32mINFO[0;m SGSN(172.18.3.1): Deleting SGSN (sgsn.c:21)
MTC@6e07f179bda6: setverdict(pass): pass -> pass, component reason not changed
MTC@6e07f179bda6: setverdict(pass): pass -> pass, component reason not changed
MTC@6e07f179bda6: Setting final verdict of the test case.
MTC@6e07f179bda6: Local verdict of MTC: pass
MTC@6e07f179bda6: No PTCs were created.
MTC@6e07f179bda6: Test case TC_pdp46_act_deact_gtpu_access_wrong_ll_saddr_ipv6 finished. Verdict: pass
MTC@6e07f179bda6: Starting external command `/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/ttcn3-tcpdump-stop.sh GGSN_Tests.TC_pdp46_act_deact_gtpu_access_wrong_ll_saddr_ipv6 pass'.
Mon Dec 22 17:01:35 UTC 2025
[1;32m====== GGSN_Tests.TC_pdp46_act_deact_gtpu_access_wrong_ll_saddr_ipv6 pass ======[0m
Saving talloc report from 172.18.3.2:4260 to GGSN_Tests.TC_pdp46_act_deact_gtpu_access_wrong_ll_saddr_ipv6.talloc
Waiting for packet dumper to finish... 0 (prev_count=-1, count=10988)
Waiting for packet dumper to finish... 1 (prev_count=10988, count=15576)
MTC@6e07f179bda6: External command `/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/ttcn3-tcpdump-stop.sh GGSN_Tests.TC_pdp46_act_deact_gtpu_access_wrong_ll_saddr_ipv6 pass' was executed successfully (exit status: 0).
MTC@6e07f179bda6: Starting external command `/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/ttcn3-tcpdump-start.sh GGSN_Tests.TC_pdp46_act_deact_gtpu_access_wrong_global_saddr_ipv6'.
------ GGSN_Tests.TC_pdp46_act_deact_gtpu_access_wrong_global_saddr_ipv6 ------
Mon Dec 22 17:01:37 UTC 2025
/usr/bin/dumpcap -q -s 1520 -n -i any -w "/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/logs/osmo_all/testsuite/GGSN_Tests.TC_pdp46_act_deact_gtpu_access_wrong_global_saddr_ipv6.pcap" >/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/logs/osmo_all/testsuite/GGSN_Tests.TC_pdp46_act_deact_gtpu_access_wrong_global_saddr_ipv6.pcap.stdout 2>/tmp/cmderr &
Waiting for packet dumper to start... 0
MTC@6e07f179bda6: External command `/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/ttcn3-tcpdump-start.sh GGSN_Tests.TC_pdp46_act_deact_gtpu_access_wrong_global_saddr_ipv6' was executed successfully (exit status: 0).
MTC@6e07f179bda6: Test case TC_pdp46_act_deact_gtpu_access_wrong_global_saddr_ipv6 started.
MTC@6e07f179bda6: GTP1C ConnectionID: 1
MTC@6e07f179bda6: "TC_pdp46_act_deact_gtpu_access_wrong_global_saddr_ipv6() start"
20251222170138397 [1;33mDLGLOBAL[0;m [1;33mNOTICE[0;m TTCN3 f_logp(): TC_pdp46_act_deact_gtpu_access_wrong_global_saddr_ipv6() start (logging_vty.c:1209)
MTC@6e07f179bda6: sending CreatePDP
20251222170138398 [1;32mDGGSN[0;m [1;32mINFO[0;m SGSN(172.18.3.1): Discovered (sgsn.c:83)
20251222170138398 [1;32mDGGSN[0;m [1;32mINFO[0;m PDP(262420478234213:2): Successful PDP Context Creation: APN=inet46(inet46), TEIC=1, IPv4=176.16.32.10, IPv6=2001:780:44:210a:: (ggsn.c:619)
MTC@6e07f179bda6: setverdict(pass): none -> pass
MTC@6e07f179bda6: RA with /64 prefix '200107800044210A0000000000000000'O
20251222170138399 [1;34mDICMP6[0;m [1;34mDEBUG[0;m Ignoring Rx ICMPv6 Neighbor Soliciation: 60 00 00 00 00 18 3a ff fe 80 00 00 00 00 00 00 20 01 07 80 00 44 21 0a ff 02 00 00 00 00 00 00 00 00 00 01 ff 44 21 0a 87 00 ca b9 00 00 00 00 fe 80 00 00 00 00 00 00 20 01 07 80 00 44 21 0a (icmpv6.c:328)
20251222170138399 [1;31mDGGSN[0;m [1;31mERROR[0;m PDP(262420478234213:2): Packet from MS using unassigned src IPv6: 3110:1691:1155:301b:325:325:325:325 (ggsn.c:785)
MTC@6e07f179bda6: Warning: Stopping inactive timer T_default.
20251222170141404 [1;32mDGGSN[0;m [1;32mINFO[0;m PDP(262420478234213:2): Deleting PDP context (ggsn.c:403)
20251222170141405 [1;32mDGGSN[0;m [1;32mINFO[0;m SGSN(172.18.3.1): Deleting SGSN (sgsn.c:21)
MTC@6e07f179bda6: setverdict(pass): pass -> pass, component reason not changed
MTC@6e07f179bda6: setverdict(pass): pass -> pass, component reason not changed
MTC@6e07f179bda6: Setting final verdict of the test case.
MTC@6e07f179bda6: Local verdict of MTC: pass
MTC@6e07f179bda6: No PTCs were created.
MTC@6e07f179bda6: Test case TC_pdp46_act_deact_gtpu_access_wrong_global_saddr_ipv6 finished. Verdict: pass
MTC@6e07f179bda6: Starting external command `/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/ttcn3-tcpdump-stop.sh GGSN_Tests.TC_pdp46_act_deact_gtpu_access_wrong_global_saddr_ipv6 pass'.
Mon Dec 22 17:01:43 UTC 2025
[1;32m====== GGSN_Tests.TC_pdp46_act_deact_gtpu_access_wrong_global_saddr_ipv6 pass ======[0m
Saving talloc report from 172.18.3.2:4260 to GGSN_Tests.TC_pdp46_act_deact_gtpu_access_wrong_global_saddr_ipv6.talloc
Waiting for packet dumper to finish... 0 (prev_count=-1, count=12408)
Waiting for packet dumper to finish... 1 (prev_count=12408, count=16996)
MTC@6e07f179bda6: External command `/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/ttcn3-tcpdump-stop.sh GGSN_Tests.TC_pdp46_act_deact_gtpu_access_wrong_global_saddr_ipv6 pass' was executed successfully (exit status: 0).
MTC@6e07f179bda6: Starting external command `/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/ttcn3-tcpdump-start.sh GGSN_Tests.TC_pdp46_clients_interact'.
------ GGSN_Tests.TC_pdp46_clients_interact ------
Mon Dec 22 17:01:45 UTC 2025
/usr/bin/dumpcap -q -s 1520 -n -i any -w "/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/logs/osmo_all/testsuite/GGSN_Tests.TC_pdp46_clients_interact.pcap" >/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/logs/osmo_all/testsuite/GGSN_Tests.TC_pdp46_clients_interact.pcap.stdout 2>/tmp/cmderr &
Waiting for packet dumper to start... 0
MTC@6e07f179bda6: External command `/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/ttcn3-tcpdump-start.sh GGSN_Tests.TC_pdp46_clients_interact' was executed successfully (exit status: 0).
MTC@6e07f179bda6: Test case TC_pdp46_clients_interact started.
MTC@6e07f179bda6: GTP1C ConnectionID: 1
MTC@6e07f179bda6: "TC_pdp46_clients_interact() start"
20251222170146549 [1;33mDLGLOBAL[0;m [1;33mNOTICE[0;m TTCN3 f_logp(): TC_pdp46_clients_interact() start (logging_vty.c:1209)
MTC@6e07f179bda6: sending CreatePDP
20251222170146554 [1;32mDGGSN[0;m [1;32mINFO[0;m SGSN(172.18.3.1): Discovered (sgsn.c:83)
20251222170146554 [1;32mDGGSN[0;m [1;32mINFO[0;m PDP(262423560336054:7): Successful PDP Context Creation: APN=inet46(inet46), TEIC=1, IPv4=176.16.32.11, IPv6=2001:780:44:210b:: (ggsn.c:619)
MTC@6e07f179bda6: setverdict(pass): none -> pass
MTC@6e07f179bda6: RA with /64 prefix '200107800044210B0000000000000000'O
20251222170146557 [1;34mDICMP6[0;m [1;34mDEBUG[0;m Ignoring Rx ICMPv6 Neighbor Soliciation: 60 00 00 00 00 18 3a ff fe 80 00 00 00 00 00 00 20 01 07 80 00 44 21 0b ff 02 00 00 00 00 00 00 00 00 00 01 ff 44 21 0b 87 00 ca b6 00 00 00 00 fe 80 00 00 00 00 00 00 20 01 07 80 00 44 21 0b (icmpv6.c:328)
MTC@6e07f179bda6: sending CreatePDP
20251222170146559 [1;32mDGGSN[0;m [1;32mINFO[0;m PDP(262422258479106:11): Successful PDP Context Creation: APN=inet46(inet46), TEIC=2, IPv4=176.16.32.12, IPv6=2001:780:44:210c:: (ggsn.c:619)
MTC@6e07f179bda6: setverdict(pass): pass -> pass, component reason not changed
MTC@6e07f179bda6: RA with /64 prefix '200107800044210C0000000000000000'O
20251222170146562 [1;34mDICMP6[0;m [1;34mDEBUG[0;m Ignoring Rx ICMPv6 Neighbor Soliciation: 60 00 00 00 00 18 3a ff fe 80 00 00 00 00 00 00 20 01 07 80 00 44 21 0c ff 02 00 00 00 00 00 00 00 00 00 01 ff 44 21 0c 87 00 ca b3 00 00 00 00 fe 80 00 00 00 00 00 00 20 01 07 80 00 44 21 0c (icmpv6.c:328)
MTC@6e07f179bda6: Warning: Stopping inactive timer T_default.
MTC@6e07f179bda6: Warning: Stopping inactive timer T_default.
20251222170152569 [1;32mDGGSN[0;m [1;32mINFO[0;m PDP(262423560336054:7): Deleting PDP context (ggsn.c:403)
MTC@6e07f179bda6: setverdict(pass): pass -> pass, component reason not changed
20251222170152571 [1;32mDGGSN[0;m [1;32mINFO[0;m PDP(262422258479106:11): Deleting PDP context (ggsn.c:403)
20251222170152571 [1;32mDGGSN[0;m [1;32mINFO[0;m SGSN(172.18.3.1): Deleting SGSN (sgsn.c:21)
MTC@6e07f179bda6: setverdict(pass): pass -> pass, component reason not changed
MTC@6e07f179bda6: setverdict(pass): pass -> pass, component reason not changed
MTC@6e07f179bda6: Setting final verdict of the test case.
MTC@6e07f179bda6: Local verdict of MTC: pass
MTC@6e07f179bda6: No PTCs were created.
MTC@6e07f179bda6: Test case TC_pdp46_clients_interact finished. Verdict: pass
MTC@6e07f179bda6: Starting external command `/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/ttcn3-tcpdump-stop.sh GGSN_Tests.TC_pdp46_clients_interact pass'.
Mon Dec 22 17:01:54 UTC 2025
[1;32m====== GGSN_Tests.TC_pdp46_clients_interact pass ======[0m
Saving talloc report from 172.18.3.2:4260 to GGSN_Tests.TC_pdp46_clients_interact.talloc
Waiting for packet dumper to finish... 0 (prev_count=-1, count=17016)
Waiting for packet dumper to finish... 1 (prev_count=17016, count=21836)
MTC@6e07f179bda6: External command `/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/ttcn3-tcpdump-stop.sh GGSN_Tests.TC_pdp46_clients_interact pass' was executed successfully (exit status: 0).
MTC@6e07f179bda6: Starting external command `/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/ttcn3-tcpdump-start.sh GGSN_Tests.TC_pdp46_act_deact_apn4'.
------ GGSN_Tests.TC_pdp46_act_deact_apn4 ------
Mon Dec 22 17:01:56 UTC 2025
/usr/bin/dumpcap -q -s 1520 -n -i any -w "/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/logs/osmo_all/testsuite/GGSN_Tests.TC_pdp46_act_deact_apn4.pcap" >/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/logs/osmo_all/testsuite/GGSN_Tests.TC_pdp46_act_deact_apn4.pcap.stdout 2>/tmp/cmderr &
Waiting for packet dumper to start... 0
MTC@6e07f179bda6: External command `/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/ttcn3-tcpdump-start.sh GGSN_Tests.TC_pdp46_act_deact_apn4' was executed successfully (exit status: 0).
MTC@6e07f179bda6: Test case TC_pdp46_act_deact_apn4 started.
MTC@6e07f179bda6: GTP1C ConnectionID: 1
MTC@6e07f179bda6: "TC_pdp46_act_deact_apn4() start"
20251222170157719 [1;33mDLGLOBAL[0;m [1;33mNOTICE[0;m TTCN3 f_logp(): TC_pdp46_act_deact_apn4() start (logging_vty.c:1209)
MTC@6e07f179bda6: sending CreatePDP
20251222170157723 [1;31mDGGSN[0;m [1;31mERROR[0;m PDP(262420144199212:15): APN doesn't support requested EUA / AF type (ggsn.c:632)
20251222170157724 [1;32mDGGSN[0;m [1;32mINFO[0;m PDP(262420144199212:15): Deleting PDP context (ggsn.c:403)
20251222170157724 [1;33mDGGSN[0;m [1;33mNOTICE[0;m PDP(262420144199212:15): Deleting PDP context: without private structure! (ggsn.c:416)
20251222170157724 [1;33mDGGSN[0;m [1;33mNOTICE[0;m GGSN(ggsn0): Received Recovery IE for unknown SGSN (no PDP contexts active) (ggsn.c:889)
MTC@6e07f179bda6: setverdict(pass): none -> pass
MTC@6e07f179bda6: sending CreatePDP
20251222170157727 [1;32mDGGSN[0;m [1;32mINFO[0;m SGSN(172.18.3.1): Discovered (sgsn.c:83)
20251222170157727 [1;32mDGGSN[0;m [1;32mINFO[0;m PDP(262425584634134:1): Successful PDP Context Creation: APN=internet(internet), TEIC=1, IPv4=176.16.16.19, IPv6=none (ggsn.c:619)
MTC@6e07f179bda6: setverdict(pass): pass -> pass, component reason not changed
MTC@6e07f179bda6: sending CreatePDP
20251222170157731 [1;31mDGGSN[0;m [1;31mERROR[0;m PDP(262426558151889:6): APN doesn't support requested EUA / AF type (ggsn.c:632)
20251222170157731 [1;32mDGGSN[0;m [1;32mINFO[0;m PDP(262426558151889:6): Deleting PDP context (ggsn.c:403)
20251222170157731 [1;31mDGGSN[0;m [1;31mERROR[0;m PDP(262426558151889:6): Cannot find/free IP Pool member (ggsn.c:411)
20251222170157731 [1;33mDGGSN[0;m [1;33mNOTICE[0;m PDP(262426558151889:6): Deleting PDP context: without private structure! (ggsn.c:416)
MTC@6e07f179bda6: setverdict(pass): pass -> pass, component reason not changed
20251222170157732 [1;32mDGGSN[0;m [1;32mINFO[0;m PDP(262425584634134:1): Deleting PDP context (ggsn.c:403)
20251222170157732 [1;32mDGGSN[0;m [1;32mINFO[0;m SGSN(172.18.3.1): Deleting SGSN (sgsn.c:21)
MTC@6e07f179bda6: setverdict(pass): pass -> pass, component reason not changed
MTC@6e07f179bda6: setverdict(pass): pass -> pass, component reason not changed
MTC@6e07f179bda6: Setting final verdict of the test case.
MTC@6e07f179bda6: Local verdict of MTC: pass
MTC@6e07f179bda6: No PTCs were created.
MTC@6e07f179bda6: Test case TC_pdp46_act_deact_apn4 finished. Verdict: pass
MTC@6e07f179bda6: Starting external command `/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/ttcn3-tcpdump-stop.sh GGSN_Tests.TC_pdp46_act_deact_apn4 pass'.
Mon Dec 22 17:01:59 UTC 2025
[1;32m====== GGSN_Tests.TC_pdp46_act_deact_apn4 pass ======[0m
Saving talloc report from 172.18.3.2:4260 to GGSN_Tests.TC_pdp46_act_deact_apn4.talloc
Waiting for packet dumper to finish... 0 (prev_count=-1, count=15712)
Waiting for packet dumper to finish... 1 (prev_count=15712, count=21020)
MTC@6e07f179bda6: External command `/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/ttcn3-tcpdump-stop.sh GGSN_Tests.TC_pdp46_act_deact_apn4 pass' was executed successfully (exit status: 0).
MTC@6e07f179bda6: Starting external command `/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/ttcn3-tcpdump-start.sh GGSN_Tests.TC_echo_req_resp'.
------ GGSN_Tests.TC_echo_req_resp ------
Mon Dec 22 17:02:01 UTC 2025
/usr/bin/dumpcap -q -s 1520 -n -i any -w "/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/logs/osmo_all/testsuite/GGSN_Tests.TC_echo_req_resp.pcap" >/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/logs/osmo_all/testsuite/GGSN_Tests.TC_echo_req_resp.pcap.stdout 2>/tmp/cmderr &
Waiting for packet dumper to start... 0
MTC@6e07f179bda6: External command `/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/ttcn3-tcpdump-start.sh GGSN_Tests.TC_echo_req_resp' was executed successfully (exit status: 0).
MTC@6e07f179bda6: Test case TC_echo_req_resp started.
MTC@6e07f179bda6: GTP1C ConnectionID: 1
MTC@6e07f179bda6: "TC_echo_req_resp() start"
20251222170202878 [1;33mDLGLOBAL[0;m [1;33mNOTICE[0;m TTCN3 f_logp(): TC_echo_req_resp() start (logging_vty.c:1209)
MTC@6e07f179bda6: setverdict(pass): none -> pass
MTC@6e07f179bda6: setverdict(pass): pass -> pass, component reason not changed
MTC@6e07f179bda6: Setting final verdict of the test case.
MTC@6e07f179bda6: Local verdict of MTC: pass
MTC@6e07f179bda6: No PTCs were created.
MTC@6e07f179bda6: Test case TC_echo_req_resp finished. Verdict: pass
MTC@6e07f179bda6: Starting external command `/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/ttcn3-tcpdump-stop.sh GGSN_Tests.TC_echo_req_resp pass'.
Mon Dec 22 17:02:04 UTC 2025
[1;32m====== GGSN_Tests.TC_echo_req_resp pass ======[0m
Saving talloc report from 172.18.3.2:4260 to GGSN_Tests.TC_echo_req_resp.talloc
Waiting for packet dumper to finish... 0 (prev_count=-1, count=6684)
Waiting for packet dumper to finish... 1 (prev_count=6684, count=11272)
MTC@6e07f179bda6: External command `/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/ttcn3-tcpdump-stop.sh GGSN_Tests.TC_echo_req_resp pass' was executed successfully (exit status: 0).
MTC@6e07f179bda6: Starting external command `/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/ttcn3-tcpdump-start.sh GGSN_Tests.TC_echo_req_resp_gtpu'.
------ GGSN_Tests.TC_echo_req_resp_gtpu ------
Mon Dec 22 17:02:06 UTC 2025
/usr/bin/dumpcap -q -s 1520 -n -i any -w "/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/logs/osmo_all/testsuite/GGSN_Tests.TC_echo_req_resp_gtpu.pcap" >/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/logs/osmo_all/testsuite/GGSN_Tests.TC_echo_req_resp_gtpu.pcap.stdout 2>/tmp/cmderr &
Waiting for packet dumper to start... 0
MTC@6e07f179bda6: External command `/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/ttcn3-tcpdump-start.sh GGSN_Tests.TC_echo_req_resp_gtpu' was executed successfully (exit status: 0).
MTC@6e07f179bda6: Test case TC_echo_req_resp_gtpu started.
MTC@6e07f179bda6: GTP1C ConnectionID: 1
MTC@6e07f179bda6: "TC_echo_req_resp_gtpu() start"
20251222170208045 [1;33mDLGLOBAL[0;m [1;33mNOTICE[0;m TTCN3 f_logp(): TC_echo_req_resp_gtpu() start (logging_vty.c:1209)
MTC@6e07f179bda6: setverdict(pass): none -> pass
MTC@6e07f179bda6: setverdict(pass): pass -> pass, component reason not changed
MTC@6e07f179bda6: Setting final verdict of the test case.
MTC@6e07f179bda6: Local verdict of MTC: pass
MTC@6e07f179bda6: No PTCs were created.
MTC@6e07f179bda6: Test case TC_echo_req_resp_gtpu finished. Verdict: pass
MTC@6e07f179bda6: Starting external command `/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/ttcn3-tcpdump-stop.sh GGSN_Tests.TC_echo_req_resp_gtpu pass'.
Mon Dec 22 17:02:10 UTC 2025
[1;32m====== GGSN_Tests.TC_echo_req_resp_gtpu pass ======[0m
Saving talloc report from 172.18.3.2:4260 to GGSN_Tests.TC_echo_req_resp_gtpu.talloc
Waiting for packet dumper to finish... 0 (prev_count=-1, count=6700)
FATAL: command execution failed
java.io.EOFException
at java.base/java.io.ObjectInputStream$PeekInputStream.readFully(Unknown Source)
at java.base/java.io.ObjectInputStream$BlockDataInputStream.readShort(Unknown Source)
at java.base/java.io.ObjectInputStream.readStreamHeader(Unknown Source)
at java.base/java.io.ObjectInputStream.<init>(Unknown Source)
at hudson.remoting.ObjectInputStreamEx.<init>(ObjectInputStreamEx.java:50)
at hudson.remoting.Command.readFrom(Command.java:141)
at hudson.remoting.Command.readFrom(Command.java:127)
at hudson.remoting.AbstractSynchronousByteArrayCommandTransport.read(AbstractSynchronousByteArrayCommandTransport.java:35)
at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:62)
Caused: java.io.IOException: Unexpected termination of the channel
at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:80)
Caused: java.io.IOException: Backing channel 'build4-deb12build-ansible' is disconnected.
at hudson.remoting.RemoteInvocationHandler.channelOrFail(RemoteInvocationHandler.java:227)
at hudson.remoting.RemoteInvocationHandler.invoke(RemoteInvocationHandler.java:306)
at jdk.proxy2/jdk.proxy2.$Proxy156.isAlive(Unknown Source)
at hudson.Launcher$RemoteLauncher$ProcImpl.isAlive(Launcher.java:1212)
at hudson.Launcher$RemoteLauncher$ProcImpl.join(Launcher.java:1204)
at hudson.tasks.CommandInterpreter.join(CommandInterpreter.java:195)
at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:145)
at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:92)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:818)
at hudson.model.Build$BuildExecution.build(Build.java:199)
at hudson.model.Build$BuildExecution.doRun(Build.java:164)
at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:527)
at hudson.model.Run.execute(Run.java:1833)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:44)
at hudson.model.ResourceController.execute(ResourceController.java:101)
at hudson.model.Executor.run(Executor.java:446)
FATAL: Unable to delete script file /tmp/jenkins12905233980473776275.sh
java.io.EOFException
at java.base/java.io.ObjectInputStream$PeekInputStream.readFully(Unknown Source)
at java.base/java.io.ObjectInputStream$BlockDataInputStream.readShort(Unknown Source)
at java.base/java.io.ObjectInputStream.readStreamHeader(Unknown Source)
at java.base/java.io.ObjectInputStream.<init>(Unknown Source)
at hudson.remoting.ObjectInputStreamEx.<init>(ObjectInputStreamEx.java:50)
at hudson.remoting.Command.readFrom(Command.java:141)
at hudson.remoting.Command.readFrom(Command.java:127)
at hudson.remoting.AbstractSynchronousByteArrayCommandTransport.read(AbstractSynchronousByteArrayCommandTransport.java:35)
at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:62)
Caused: java.io.IOException: Unexpected termination of the channel
at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:80)
Caused: hudson.remoting.ChannelClosedException: Channel "hudson.remoting.Channel@3f9ba8e:build4-deb12build-ansible": Remote call on build4-deb12build-ansible failed. The channel is closing down or has closed down
at hudson.remoting.Channel.call(Channel.java:1105)
at hudson.FilePath.act(FilePath.java:1207)
at hudson.FilePath.act(FilePath.java:1196)
at hudson.FilePath.delete(FilePath.java:1743)
at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:163)
at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:92)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:818)
at hudson.model.Build$BuildExecution.build(Build.java:199)
at hudson.model.Build$BuildExecution.doRun(Build.java:164)
at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:527)
at hudson.model.Run.execute(Run.java:1833)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:44)
at hudson.model.ResourceController.execute(ResourceController.java:101)
at hudson.model.Executor.run(Executor.java:446)
Build step 'Execute shell' marked build as failure
ERROR: Step ‘Publish JUnit test result report’ failed: no workspace for ttcn3-ggsn-test-asan #565
1
1
23 Dec '25
See <https://jenkins.osmocom.org/jenkins/job/ttcn3-pcu-test-sns-asan/425/display…>
Changes:
[Pau Espin Pedrol] library/NGAP_Templates: PDUSessionResourceSetupRequest: Fix criticality
------------------------------------------
[...truncated 459.75 KiB...]
[1;35m20251222164034039 [1;34mDPCU[0;m[1;35m [1;34mDEBUG[0;m[1;35m NS: indication Unknown affecting cause NSVC failure / 0 from NS (gprs_bssgp_pcu.c:702)[0;m
[38;5;57m20251222164034039 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23000){UNBLOCKED}: Terminating (cause = OSMO_FSM_TERM_REQUEST) (gprs_ns2.c:656)[0;m
[38;5;57m20251222164034039 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23000){UNBLOCKED}: Freeing instance (gprs_ns2.c:656)[0;m
[38;5;57m20251222164034039 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23000){UNBLOCKED}: Deallocated (fsm.c:568)[0;m
[38;5;57m20251222164034039 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(01234)-NSVC(none) NS-STATUS.ind(bvci=00000): cause=NSVC failure, transfer=0, first=0, mtu=65523 (gprs_ns2.c:571)[0;m
[1;35m20251222164034039 [1;34mDPCU[0;m[1;35m [1;34mDEBUG[0;m[1;35m NS: indication Unknown affecting cause NSVC failure / 0 from NS (gprs_bssgp_pcu.c:702)[0;m
[38;5;57m20251222164034039 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23001){UNBLOCKED}: Terminating (cause = OSMO_FSM_TERM_REQUEST) (gprs_ns2.c:656)[0;m
[38;5;57m20251222164034039 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23001){UNBLOCKED}: Freeing instance (gprs_ns2.c:656)[0;m
[38;5;57m20251222164034040 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23001){UNBLOCKED}: Deallocated (fsm.c:568)[0;m
[38;5;57m20251222164034040 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(01234)-NSVC(none) NS-STATUS.ind(bvci=00000): cause=NSVC failure, transfer=0, first=0, mtu=65523 (gprs_ns2.c:571)[0;m
[1;35m20251222164034040 [1;34mDPCU[0;m[1;35m [1;34mDEBUG[0;m[1;35m NS: indication Unknown affecting cause NSVC failure / 0 from NS (gprs_bssgp_pcu.c:702)[0;m
[38;5;57m20251222164034040 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23002){UNBLOCKED}: Terminating (cause = OSMO_FSM_TERM_REQUEST) (gprs_ns2.c:656)[0;m
[38;5;57m20251222164034040 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23002){UNBLOCKED}: Freeing instance (gprs_ns2.c:656)[0;m
[38;5;57m20251222164034040 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23002){UNBLOCKED}: Deallocated (fsm.c:568)[0;m
MTC@613b72e27ae4: Test case TC_sns_add finished. Verdict: pass
MTC@613b72e27ae4: Starting external command `/home/osmocom-build/jenkins/workspace/ttcn3-pcu-test-sns-asan/ttcn3-tcpdump-stop.sh PCU_Tests_SNS.TC_sns_add pass'.
Mon Dec 22 16:40:34 UTC 2025
[1;32m====== PCU_Tests_SNS.TC_sns_add pass ======[0m
Saving talloc report from 127.0.0.1:4240 to PCU_Tests_SNS.TC_sns_add.talloc
respawn: 11: stopped pid 7579 with status 0
respawn: 12: starting: osmo-pcu -c osmo-pcu-sns.cfg
% 'alpha <0-10>' is now deprecated: use osmo-bsc's 'gprs power-control alpha <0-10>' instead
20251222164034075 [1;33mDLGLOBAL[0;m [1;33mNOTICE[0;m Available via telnet 127.0.0.1 4240 (telnet_interface.c:88)
[1;32m20251222164034075 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m Opening OsmoPCU L1 interface v12 to OsmoBTS/OsmoBSC (pcuif_sock.c:204)[0;m
[1;32m20251222164034075 [1;31mDL1IF[0;m[1;32m [1;31mERROR[0;m[1;32m Failed to connect to the BTS (/tmp/pcu_bts). Retrying... (pcuif_sock.c:212)[0;m
20251222164034076 [1;32mDLGLOBAL[0;m [1;32mINFO[0;m Accept()ed new telnet connection r=127.0.0.1:54800<->l=127.0.0.1:4240 (telnet_interface.c:192)
20251222164034077 [1;32mDLGLOBAL[0;m [1;32mINFO[0;m Closing telnet connection r=127.0.0.1:54800<->l=127.0.0.1:4240 (telnet_interface.c:138)
Waiting for packet dumper to finish... 0 (prev_count=-1, count=36008)
Waiting for packet dumper to finish... 1 (prev_count=36008, count=63100)
MTC@613b72e27ae4: External command `/home/osmocom-build/jenkins/workspace/ttcn3-pcu-test-sns-asan/ttcn3-tcpdump-stop.sh PCU_Tests_SNS.TC_sns_add pass' was executed successfully (exit status: 0).
MTC@613b72e27ae4: Starting external command `/home/osmocom-build/jenkins/workspace/ttcn3-pcu-test-sns-asan/ttcn3-tcpdump-start.sh PCU_Tests_SNS.TC_sns_add_nack'.
------ PCU_Tests_SNS.TC_sns_add_nack ------
Mon Dec 22 16:40:36 UTC 2025
/usr/bin/dumpcap -q -s 1520 -n -i any -w "/home/osmocom-build/jenkins/workspace/ttcn3-pcu-test-sns-asan/logs/testsuite/PCU_Tests_SNS.TC_sns_add_nack.pcap" >/home/osmocom-build/jenkins/workspace/ttcn3-pcu-test-sns-asan/logs/testsuite/PCU_Tests_SNS.TC_sns_add_nack.pcap.stdout 2>/tmp/cmderr &
Waiting for packet dumper to start... 0
MTC@613b72e27ae4: External command `/home/osmocom-build/jenkins/workspace/ttcn3-pcu-test-sns-asan/ttcn3-tcpdump-start.sh PCU_Tests_SNS.TC_sns_add_nack' was executed successfully (exit status: 0).
MTC@613b72e27ae4: Test case TC_sns_add_nack started.
MTC@613b72e27ae4: Start timer g_T_guard: 60 s
MTC@613b72e27ae4: Start timer T: 5 s
[1;32m20251222164039076 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m Opening OsmoPCU L1 interface v12 to OsmoBTS/OsmoBSC (pcuif_sock.c:204)[0;m
[1;32m20251222164039077 [1;33mDL1IF[0;m[1;32m [1;33mNOTICE[0;m[1;32m osmo-bts PCU socket /tmp/pcu_bts has been connected (pcuif_sock.c:222)[0;m
[1;32m20251222164039077 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m Sending 1.5.2.1-c4d5d.202512212029~asan TXT as PCU_VERSION to BTS (pcu_l1_if.cpp:152)[0;m
[1;32m20251222164039083 [1;33mDL1IF[0;m[1;32m [1;33mNOTICE[0;m[1;32m Received message for new BTS0 (pcu_l1_if.cpp:1246)[0;m
20251222164039084 [1;34mDLGLOBAL[0;m [1;34mDEBUG[0;m validating counter group 0x55b0c60be1a0(bts) with 102 counters (rate_ctr.c:86)
[1;32m20251222164039084 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Info indication received: (pcu_l1_if.cpp:775)[0;m
[1;32m20251222164039084 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m BTS available (pcu_l1_if.cpp:801)[0;m
[1;32m20251222164039084 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m mcc=262 (pcu_l1_if.cpp:802)[0;m
[1;32m20251222164039084 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m mnc=42 (pcu_l1_if.cpp:803)[0;m
[1;32m20251222164039084 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m lac=13135 (pcu_l1_if.cpp:804)[0;m
[1;32m20251222164039084 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m rac=0 (pcu_l1_if.cpp:805)[0;m
MTC@613b72e27ae4: f_incoming_sns_size(idx=0)
MTC@613b72e27ae4: f_ns_exp() expecting { pDU_SNS_Size := { nsPduType := '12'O, nSEI_NS := { iEI := '04'O, ext := '1'B, lengthIndicator := { length1 := 2 }, nSEI := '04D2'O }, resetFlag := { iEI := '0A'O ("\n"), resetBIT := '1'B, spare := '0000000'B }, maxNumberOfNSVCs := { iEI := '07'O ("\a"), maxNumberOfNSVCs := ? }, numberOfIP4_Endpoints := ?, numberOfIP6_Endpoints := omit } }
[1;32m20251222164039084 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m cell_id=20960 (pcu_l1_if.cpp:806)[0;m
[1;32m20251222164039084 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m bsic=7 (pcu_l1_if.cpp:807)[0;m
[1;32m20251222164039084 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m nsei=1234 (pcu_l1_if.cpp:808)[0;m
[1;32m20251222164039084 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m nse_timer=3 3 3 3 30 3 10 (pcu_l1_if.cpp:809)[0;m
[1;32m20251222164039084 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m cell_timer=3 3 3 3 3 10 3 10 3 10 3 (pcu_l1_if.cpp:814)[0;m
[1;32m20251222164039084 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m repeat_time=250 (pcu_l1_if.cpp:822)[0;m
[1;32m20251222164039084 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m repeat_count=3 (pcu_l1_if.cpp:823)[0;m
[1;32m20251222164039084 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m bvci=1234 (pcu_l1_if.cpp:824)[0;m
[1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m t3142=20 (pcu_l1_if.cpp:825)[0;m
[1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m t3169=5 (pcu_l1_if.cpp:826)[0;m
[1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m t3191=5 (pcu_l1_if.cpp:827)[0;m
[1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m t3193=1600 (ms) (pcu_l1_if.cpp:828)[0;m
[1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m t3195=5 (pcu_l1_if.cpp:829)[0;m
[1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m n3101=10 (pcu_l1_if.cpp:830)[0;m
[1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m n3103=4 (pcu_l1_if.cpp:831)[0;m
[1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m n3105=8 (pcu_l1_if.cpp:832)[0;m
[1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m cv_countdown=15 (pcu_l1_if.cpp:833)[0;m
[1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m dl_tbf_ext=2500 (pcu_l1_if.cpp:834)[0;m
[1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m ul_tbf_ext=2500 (pcu_l1_if.cpp:835)[0;m
[1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Use CS1 (pcu_l1_if.cpp:849)[0;m
[1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Use CS2 (pcu_l1_if.cpp:849)[0;m
[1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Use CS3 (pcu_l1_if.cpp:849)[0;m
[1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Use CS4 (pcu_l1_if.cpp:849)[0;m
[0;33m20251222164039085 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m New max CS: DL=4 UL=4 (bts.cpp:1422)[0;m
[1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Use MCS1 (pcu_l1_if.cpp:858)[0;m
[1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Use MCS2 (pcu_l1_if.cpp:858)[0;m
[1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Use MCS3 (pcu_l1_if.cpp:858)[0;m
[1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Use MCS4 (pcu_l1_if.cpp:858)[0;m
[1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Use MCS5 (pcu_l1_if.cpp:858)[0;m
[1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Use MCS6 (pcu_l1_if.cpp:858)[0;m
[1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Use MCS7 (pcu_l1_if.cpp:858)[0;m
[1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Use MCS8 (pcu_l1_if.cpp:858)[0;m
[1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Use MCS9 (pcu_l1_if.cpp:858)[0;m
[0;33m20251222164039085 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m New max MCS: DL=9 UL=9 (bts.cpp:1449)[0;m
[1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m initial_cs=2 (VTY forced, ignoring) (pcu_l1_if.cpp:863)[0;m
[1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m initial_mcs=1 (pcu_l1_if.cpp:868)[0;m
20251222164039085 [1;34mDLGLOBAL[0;m [1;34mDEBUG[0;m validating counter group 0x7f21e74de0a0(bssgp:bss_ctx) with 7 counters (rate_ctr.c:86)
20251222164039085 [1;34mDLGLOBAL[0;m [1;34mDEBUG[0;m validating counter group 0x55b0c60bd5a0(pcu:sgsn) with 2 counters (rate_ctr.c:86)
[1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m NS0 nsvci=1234 (pcu_l1_if.cpp:715)[0;m
[1;32m20251222164039086 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m NS0 address: r=127.0.0.1:23000<->l=NULL:22000 (pcu_l1_if.cpp:719)[0;m
[38;5;57m20251222164039086 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){UNCONFIGURED}: Allocated (fsm.c:456)[0;m
20251222164039086 [1;34mDLGLOBAL[0;m [1;34mDEBUG[0;m validating counter group 0x7f21e74dee00(ns:nse) with 15 counters (rate_ctr.c:86)
[38;5;57m20251222164039086 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){UNCONFIGURED}: Received Event REQ_ADD_BIND (gprs_ns2_sns.c:2680)[0;m
[38;5;57m20251222164039086 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){UNCONFIGURED}: Received Event REQ_SELECT_ENDPOINT (gprs_ns2_sns.c:2028)[0;m
[38;5;57m20251222164039086 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(01234) NS-STATUS.ind(bvci=00000): cause=NSE SNS no endpoints, transfer=0, first=1, mtu=0 (gprs_ns2.c:575)[0;m
[1;35m20251222164039086 [1;34mDPCU[0;m[1;35m [1;34mDEBUG[0;m[1;35m NS: indication Unknown affecting cause NSE SNS no endpoints / 6 from NS (gprs_bssgp_pcu.c:702)[0;m
[38;5;57m20251222164039086 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){UNCONFIGURED}: state_chg to UNCONFIGURED (gprs_ns2_sns.c:2240)[0;m
[38;5;57m20251222164039086 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){UNCONFIGURED}: Received Event REQ_SELECT_ENDPOINT (gprs_ns2_sns.c:2513)[0;m
[38;5;57m20251222164039086 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){UNCONFIGURED}: state_chg to BSS_SIZE (gprs_ns2_sns.c:2255)[0;m
20251222164039086 [1;34mDLGLOBAL[0;m [1;34mDEBUG[0;m validating counter group 0x7f21e74dedc0(ns:nsvc) with 15 counters (rate_ctr.c:86)
[38;5;57m20251222164039086 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23000){UNCONFIGURED}: Allocated (fsm.c:456)[0;m
[38;5;63m20251222164039086 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Tx SNS-SIZE (reset=1, max_nr_nsvc=8192, num_ip4=1, num_ip6=-1) (gprs_ns2_message.c:807)[0;m
[1;32m20251222164039086 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH(bts=0,trx=0,ts=7) Sending activate request (pcu_l1_if.cpp:165)[0;m
[0;33m20251222164039086 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=0,ts=7) PDCH state: disabled => enabled (pdch.cpp:171)[0;m
[1;32m20251222164039086 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH (trx=0, ts=7): tsc=7, hopping=no (pcu_l1_if.cpp:959)[0;m
[1;32m20251222164039086 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH(bts=0,trx=1,ts=7) Sending activate request (pcu_l1_if.cpp:165)[0;m
[0;33m20251222164039087 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=1,ts=7) PDCH state: disabled => enabled (pdch.cpp:171)[0;m
[1;32m20251222164039087 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH (trx=1, ts=7): tsc=7, hopping=no (pcu_l1_if.cpp:959)[0;m
[1;32m20251222164039087 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH(bts=0,trx=2,ts=7) Sending activate request (pcu_l1_if.cpp:165)[0;m
[0;33m20251222164039087 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=2,ts=7) PDCH state: disabled => enabled (pdch.cpp:171)[0;m
[1;32m20251222164039087 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH (trx=2, ts=7): tsc=7, hopping=no (pcu_l1_if.cpp:959)[0;m
[1;32m20251222164039087 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH(bts=0,trx=3,ts=7) Sending activate request (pcu_l1_if.cpp:165)[0;m
[0;33m20251222164039087 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=3,ts=7) PDCH state: disabled => enabled (pdch.cpp:171)[0;m
[1;32m20251222164039087 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH (trx=3, ts=7): tsc=7, hopping=no (pcu_l1_if.cpp:959)[0;m
[1;32m20251222164039087 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH(bts=0,trx=4,ts=7) Sending activate request (pcu_l1_if.cpp:165)[0;m
[0;33m20251222164039087 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=4,ts=7) PDCH state: disabled => enabled (pdch.cpp:171)[0;m
[1;32m20251222164039087 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH (trx=4, ts=7): tsc=7, hopping=no (pcu_l1_if.cpp:959)[0;m
[1;32m20251222164039087 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH(bts=0,trx=5,ts=7) Sending activate request (pcu_l1_if.cpp:165)[0;m
[0;33m20251222164039087 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=5,ts=7) PDCH state: disabled => enabled (pdch.cpp:171)[0;m
[1;32m20251222164039087 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH (trx=5, ts=7): tsc=7, hopping=no (pcu_l1_if.cpp:959)[0;m
[1;32m20251222164039087 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH(bts=0,trx=6,ts=7) Sending activate request (pcu_l1_if.cpp:165)[0;m
[0;33m20251222164039087 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=6,ts=7) PDCH state: disabled => enabled (pdch.cpp:171)[0;m
[1;32m20251222164039087 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH (trx=6, ts=7): tsc=7, hopping=no (pcu_l1_if.cpp:959)[0;m
[1;32m20251222164039087 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH(bts=0,trx=7,ts=7) Sending activate request (pcu_l1_if.cpp:165)[0;m
[0;33m20251222164039087 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=7,ts=7) PDCH state: disabled => enabled (pdch.cpp:171)[0;m
[1;32m20251222164039087 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH (trx=7, ts=7): tsc=7, hopping=no (pcu_l1_if.cpp:959)[0;m
[1;32m20251222164039087 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m BTS model: osmo-bts-trx (pcu_l1_if.cpp:974)[0;m
MTC@613b72e27ae4: f_incoming_sns_config(idx=0)
MTC@613b72e27ae4: f_ns_exp() expecting { pDU_SNS_Config := { nsPduType := '0F'O, endFlag := { eBIT := '1'B, spare := '0000000'B }, nSEI_NS := { iEI := '04'O, ext := '1'B, lengthIndicator := { length1 := 2 }, nSEI := '04D2'O }, listofIP4Elements := { iEI := '05'O, ext := '1'B, lengthIndicator := { length1 := ? }, iP4_Elements := { { ipAddress := '7F000001'O, uDP_Port := '55F0'O, signallingWeight := ?, dataWeight := ? } } }, listofIP6Elements := omit } }
[38;5;63m20251222164039093 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Rx SNS-SIZE-ACK (gprs_ns2.c:1309)[0;m
[38;5;57m20251222164039093 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){BSS_SIZE}: NSEI=1234 Rx SNS PDU type SNS-SIZE-ACK (gprs_ns2_sns.c:2341)[0;m
[38;5;57m20251222164039094 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){BSS_SIZE}: Received Event RX_SIZE_ACK (gprs_ns2_sns.c:2349)[0;m
[38;5;57m20251222164039094 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){BSS_SIZE}: state_chg to BSS_CONFIG_BSS (gprs_ns2_sns.c:827)[0;m
[38;5;63m20251222164039094 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Tx SNS-CONFIG (end_flag=1, num_ip4=1, num_ip6=0) (gprs_ns2_message.c:713)[0;m
MTC@613b72e27ae4: f_outgoing_sns_config_1c1u(idx=0)
MTC@613b72e27ae4: f_ns_exp() expecting { pDU_SNS_Config_Ack := { nsPduType := '10'O, nSEI_NS := { iEI := '04'O, ext := '1'B, lengthIndicator := { length1 := 2 }, nSEI := '04D2'O }, causeNS := omit } }
[38;5;63m20251222164039096 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Rx SNS-CONFIG-ACK (gprs_ns2.c:1309)[0;m
[38;5;57m20251222164039096 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){BSS_CONFIG_BSS}: NSEI=1234 Rx SNS PDU type SNS-CONFIG-ACK (gprs_ns2_sns.c:2341)[0;m
[38;5;57m20251222164039096 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){BSS_CONFIG_BSS}: Received Event RX_CONFIG_ACK (gprs_ns2_sns.c:2358)[0;m
[38;5;57m20251222164039096 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){BSS_CONFIG_BSS}: state_chg to BSS_CONFIG_SGSN (gprs_ns2_sns.c:1059)[0;m
[38;5;63m20251222164039096 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Rx SNS-CONFIG (gprs_ns2.c:1309)[0;m
[38;5;57m20251222164039096 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){BSS_CONFIG_SGSN}: NSEI=1234 Rx SNS PDU type SNS-CONFIG (gprs_ns2_sns.c:2341)[0;m
[38;5;57m20251222164039096 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){BSS_CONFIG_SGSN}: Received Event RX_CONFIG_END (gprs_ns2_sns.c:2353)[0;m
[38;5;57m20251222164039097 [1;32mDLNS[0;m[38;5;57m [1;32mINFO[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){BSS_CONFIG_SGSN}: Rx SNS-CONFIG: Remote IPv4 list now 2 entries (gprs_ns2_sns.c:1126)[0;m
20251222164039097 [1;34mDLGLOBAL[0;m [1;34mDEBUG[0;m validating counter group 0x7f21e74dedc0(ns:nsvc) with 15 counters (rate_ctr.c:86)
[38;5;57m20251222164039097 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23001){UNCONFIGURED}: Allocated (fsm.c:456)[0;m
[38;5;57m20251222164039097 [1;32mDLNS[0;m[38;5;57m [1;32mINFO[0;m[38;5;57m NSE(01234)-NSVC(none) Tx SNS-CONFIG-ACK (cause=NULL) (gprs_ns2_message.c:755)[0;m
[38;5;63m20251222164039097 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Tx SNS-CONFIG-ACK (gprs_ns2_message.c:757)[0;m
[38;5;57m20251222164039097 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23000){UNCONFIGURED}: Received Event REQ-START (gprs_ns2_vc_fsm.c:817)[0;m
[38;5;57m20251222164039097 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23000){UNCONFIGURED}: state_chg to UNBLOCKED (gprs_ns2_vc_fsm.c:278)[0;m
[38;5;57m20251222164039097 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(01234)-NSVC(none) NS-STATUS.ind(bvci=00000): cause=NSVC recovery, transfer=0, first=1, mtu=65523 (gprs_ns2.c:571)[0;m
[1;35m20251222164039097 [1;34mDPCU[0;m[1;35m [1;34mDEBUG[0;m[1;35m NS: indication Unknown affecting cause NSVC recovery / 1 from NS (gprs_bssgp_pcu.c:702)[0;m
[38;5;63m20251222164039097 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Tx NS-ALIVE (gprs_ns2_message.c:193)[0;m
[38;5;57m20251222164039097 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23000){UNBLOCKED}: Starting Tns-alive of 3 seconds (gprs_ns2_vc_fsm.c:177)[0;m
[38;5;57m20251222164039097 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23001){UNCONFIGURED}: Received Event REQ-START (gprs_ns2_vc_fsm.c:817)[0;m
[38;5;57m20251222164039097 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23001){UNCONFIGURED}: state_chg to UNBLOCKED (gprs_ns2_vc_fsm.c:278)[0;m
[38;5;57m20251222164039097 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(01234) NS-STATUS.ind(bvci=00000): cause=NSE recovery, transfer=100, first=1, mtu=65523 (gprs_ns2.c:575)[0;m
[1;35m20251222164039097 [1;33mDPCU[0;m[1;35m [1;33mNOTICE[0;m[1;35m NS-NSE 1234 became available (gprs_bssgp_pcu.c:681)[0;m
[38;5;59m20251222164039097 [1;32mDLBSSGP[0;m[38;5;59m [1;32mINFO[0;m[38;5;59m Sending reset on BVCI 0 (gprs_bssgp_pcu.c:1045)[0;m
[38;5;59m20251222164039097 [1;33mDLBSSGP[0;m[38;5;59m [1;33mNOTICE[0;m[38;5;59m BSSGP (BVCI=0) Tx BVC-RESET CAUSE=O&M intervention (gprs_bssgp.c:124)[0;m
[38;5;61m20251222164039097 [1;32mDLNSDATA[0;m[38;5;61m [1;32mINFO[0;m[38;5;61m NSE(01234)-NSVC(none) Tx NS-UNITDATA (gprs_ns2_message.c:412)[0;m
[38;5;57m20251222164039097 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(01234)-NSVC(none) NS-STATUS.ind(bvci=00000): cause=NSVC recovery, transfer=100, first=0, mtu=65523 (gprs_ns2.c:571)[0;m
[1;35m20251222164039097 [1;34mDPCU[0;m[1;35m [1;34mDEBUG[0;m[1;35m NS: indication Unknown affecting cause NSVC recovery / 1 from NS (gprs_bssgp_pcu.c:702)[0;m
[38;5;63m20251222164039097 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Tx NS-ALIVE (gprs_ns2_message.c:193)[0;m
[38;5;57m20251222164039097 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23001){UNBLOCKED}: Starting Tns-alive of 3 seconds (gprs_ns2_vc_fsm.c:177)[0;m
[38;5;57m20251222164039098 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){BSS_CONFIG_SGSN}: state_chg to CONFIGURED (gprs_ns2_sns.c:1195)[0;m
[38;5;57m20251222164039098 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(01234) NS-STATUS.ind(bvci=00000): cause=NSE SNS configured, transfer=100, first=0, mtu=65523 (gprs_ns2.c:575)[0;m
[1;35m20251222164039098 [1;33mDPCU[0;m[1;35m [1;33mNOTICE[0;m[1;35m NS-NSE 1234 SNS configured. (gprs_bssgp_pcu.c:678)[0;m
[38;5;63m20251222164039099 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Rx NS-ALIVE-ACK (gprs_ns2.c:1309)[0;m
[38;5;57m20251222164039100 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23000){UNBLOCKED}: Received Event RX-ALIVE_ACK (gprs_ns2_vc_fsm.c:960)[0;m
[38;5;63m20251222164039101 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Rx NS-ALIVE (gprs_ns2.c:1309)[0;m
[38;5;57m20251222164039101 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23000){UNBLOCKED}: Received Event RX-ALIVE (gprs_ns2_vc_fsm.c:957)[0;m
[38;5;63m20251222164039101 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Tx NS-ALIVE-ACK (gprs_ns2_message.c:193)[0;m
[38;5;63m20251222164039101 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Rx NS-ALIVE-ACK (gprs_ns2.c:1309)[0;m
[38;5;57m20251222164039101 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23001){UNBLOCKED}: Received Event RX-ALIVE_ACK (gprs_ns2_vc_fsm.c:960)[0;m
[38;5;63m20251222164039102 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Rx NS-ALIVE (gprs_ns2.c:1309)[0;m
[38;5;57m20251222164039102 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23001){UNBLOCKED}: Received Event RX-ALIVE (gprs_ns2_vc_fsm.c:957)[0;m
[38;5;63m20251222164039102 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Tx NS-ALIVE-ACK (gprs_ns2_message.c:193)[0;m
[38;5;63m20251222164049100 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Tx NS-ALIVE (gprs_ns2_message.c:193)[0;m
[38;5;63m20251222164049102 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Tx NS-ALIVE (gprs_ns2_message.c:193)[0;m
[38;5;63m20251222164049102 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Rx NS-ALIVE-ACK (gprs_ns2.c:1309)[0;m
[38;5;57m20251222164049102 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23000){UNBLOCKED}: Received Event RX-ALIVE_ACK (gprs_ns2_vc_fsm.c:960)[0;m
[38;5;63m20251222164049102 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Rx NS-ALIVE-ACK (gprs_ns2.c:1309)[0;m
[38;5;57m20251222164049102 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23001){UNBLOCKED}: Received Event RX-ALIVE_ACK (gprs_ns2_vc_fsm.c:960)[0;m
FATAL: command execution failed
java.io.EOFException
at java.base/java.io.ObjectInputStream$PeekInputStream.readFully(Unknown Source)
at java.base/java.io.ObjectInputStream$BlockDataInputStream.readShort(Unknown Source)
at java.base/java.io.ObjectInputStream.readStreamHeader(Unknown Source)
at java.base/java.io.ObjectInputStream.<init>(Unknown Source)
at hudson.remoting.ObjectInputStreamEx.<init>(ObjectInputStreamEx.java:50)
at hudson.remoting.Command.readFrom(Command.java:141)
at hudson.remoting.Command.readFrom(Command.java:127)
at hudson.remoting.AbstractSynchronousByteArrayCommandTransport.read(AbstractSynchronousByteArrayCommandTransport.java:35)
at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:62)
Caused: java.io.IOException: Unexpected termination of the channel
at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:80)
Caused: hudson.remoting.ChannelClosedException: Channel "hudson.remoting.Channel@187572d1:build5-deb12build-ansible": Remote call on build5-deb12build-ansible failed. The channel is closing down or has closed down
at hudson.remoting.Channel.call(Channel.java:1105)
at hudson.remoting.RemoteInvocationHandler.invoke(RemoteInvocationHandler.java:306)
at jdk.proxy2/jdk.proxy2.$Proxy156.isAlive(Unknown Source)
at hudson.Launcher$RemoteLauncher$ProcImpl.isAlive(Launcher.java:1212)
at hudson.Launcher$RemoteLauncher$ProcImpl.join(Launcher.java:1204)
at hudson.tasks.CommandInterpreter.join(CommandInterpreter.java:195)
at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:145)
at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:92)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:818)
at hudson.model.Build$BuildExecution.build(Build.java:199)
at hudson.model.Build$BuildExecution.doRun(Build.java:164)
at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:527)
at hudson.model.Run.execute(Run.java:1833)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:44)
at hudson.model.ResourceController.execute(ResourceController.java:101)
at hudson.model.Executor.run(Executor.java:446)
FATAL: Unable to delete script file /tmp/jenkins14745648370965389386.sh
java.io.EOFException
at java.base/java.io.ObjectInputStream$PeekInputStream.readFully(Unknown Source)
at java.base/java.io.ObjectInputStream$BlockDataInputStream.readShort(Unknown Source)
at java.base/java.io.ObjectInputStream.readStreamHeader(Unknown Source)
at java.base/java.io.ObjectInputStream.<init>(Unknown Source)
at hudson.remoting.ObjectInputStreamEx.<init>(ObjectInputStreamEx.java:50)
at hudson.remoting.Command.readFrom(Command.java:141)
at hudson.remoting.Command.readFrom(Command.java:127)
at hudson.remoting.AbstractSynchronousByteArrayCommandTransport.read(AbstractSynchronousByteArrayCommandTransport.java:35)
at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:62)
Caused: java.io.IOException: Unexpected termination of the channel
at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:80)
Caused: hudson.remoting.ChannelClosedException: Channel "hudson.remoting.Channel@187572d1:build5-deb12build-ansible": Remote call on build5-deb12build-ansible failed. The channel is closing down or has closed down
at hudson.remoting.Channel.call(Channel.java:1105)
at hudson.FilePath.act(FilePath.java:1207)
at hudson.FilePath.act(FilePath.java:1196)
at hudson.FilePath.delete(FilePath.java:1743)
at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:163)
at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:92)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:818)
at hudson.model.Build$BuildExecution.build(Build.java:199)
at hudson.model.Build$BuildExecution.doRun(Build.java:164)
at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:527)
at hudson.model.Run.execute(Run.java:1833)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:44)
at hudson.model.ResourceController.execute(ResourceController.java:101)
at hudson.model.Executor.run(Executor.java:446)
Build step 'Execute shell' marked build as failure
ERROR: Step ‘Publish JUnit test result report’ failed: no workspace for ttcn3-pcu-test-sns-asan #425
1
1
Build failed in Jenkins: ttcn3-bsc-test-sccplite-latest #2536
by jenkins＠lists.osmocom.org 23 Dec '25
by jenkins＠lists.osmocom.org 23 Dec '25
23 Dec '25
See <https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-sccplite-latest/2536…>
Changes:
[Pau Espin Pedrol] library/NGAP_Templates: PDUSessionResourceSetupRequest: Fix criticality
------------------------------------------
[...truncated 95.63 MiB...]
MTC@e0eada7afaad: Matching on port BSCVTY succeeded: "OsmoBSC(config)# " with pattern "[\w-]+\(*\)\# " matched
MTC@e0eada7afaad: Receive operation on port BSCVTY succeeded, message from system(): charstring : "OsmoBSC(config)# " id 8
MTC@e0eada7afaad: Message with id 8 was extracted from the queue of BSCVTY.
MTC@e0eada7afaad: Stop timer T: 2 s
MTC@e0eada7afaad: Sent on BSCVTY to system charstring : "msc 0"
MTC@e0eada7afaad: Start timer T: 2 s
MTC@e0eada7afaad: Message enqueued on BSCVTY from system charstring : "OsmoBSC(config-msc)# " id 9
MTC@e0eada7afaad: Matching on port BSCVTY "OsmoBSC(config-msc)# " with pattern "[\w-]+> " unmatched: First message in the queue does not match the template:
MTC@e0eada7afaad: Matching on port BSCVTY "OsmoBSC(config-msc)# " with pattern "[\w-]+\# " unmatched: First message in the queue does not match the template:
MTC@e0eada7afaad: Matching on port BSCVTY succeeded: "OsmoBSC(config-msc)# " with pattern "[\w-]+\(*\)\# " matched
MTC@e0eada7afaad: Receive operation on port BSCVTY succeeded, message from system(): charstring : "OsmoBSC(config-msc)# " id 9
MTC@e0eada7afaad: Message with id 9 was extracted from the queue of BSCVTY.
MTC@e0eada7afaad: Stop timer T: 2 s
MTC@e0eada7afaad: Sent on BSCVTY to system charstring : "osmux off"
MTC@e0eada7afaad: Start timer T: 2 s
MTC@e0eada7afaad: Message enqueued on BSCVTY from system charstring : "OsmoBSC(config-msc)# " id 10
MTC@e0eada7afaad: Matching on port BSCVTY "OsmoBSC(config-msc)# " with pattern "[\w-]+> " unmatched: First message in the queue does not match the template:
MTC@e0eada7afaad: Matching on port BSCVTY "OsmoBSC(config-msc)# " with pattern "[\w-]+\# " unmatched: First message in the queue does not match the template:
MTC@e0eada7afaad: Matching on port BSCVTY succeeded: "OsmoBSC(config-msc)# " with pattern "[\w-]+\(*\)\# " matched
MTC@e0eada7afaad: Receive operation on port BSCVTY succeeded, message from system(): charstring : "OsmoBSC(config-msc)# " id 10
MTC@e0eada7afaad: Message with id 10 was extracted from the queue of BSCVTY.
MTC@e0eada7afaad: Stop timer T: 2 s
MTC@e0eada7afaad: Sent on BSCVTY to system charstring : "exit"
MTC@e0eada7afaad: Start timer T: 2 s
MTC@e0eada7afaad: Message enqueued on BSCVTY from system charstring : "OsmoBSC(config)# " id 11
MTC@e0eada7afaad: Matching on port BSCVTY "OsmoBSC(config)# " with pattern "[\w-]+> " unmatched: First message in the queue does not match the template:
MTC@e0eada7afaad: Matching on port BSCVTY "OsmoBSC(config)# " with pattern "[\w-]+\# " unmatched: First message in the queue does not match the template:
MTC@e0eada7afaad: Matching on port BSCVTY succeeded: "OsmoBSC(config)# " with pattern "[\w-]+\(*\)\# " matched
MTC@e0eada7afaad: Receive operation on port BSCVTY succeeded, message from system(): charstring : "OsmoBSC(config)# " id 11
MTC@e0eada7afaad: Message with id 11 was extracted from the queue of BSCVTY.
MTC@e0eada7afaad: Stop timer T: 2 s
MTC@e0eada7afaad: Sent on BSCVTY to system charstring : "exit"
MTC@e0eada7afaad: Start timer T: 2 s
MTC@e0eada7afaad: Message enqueued on BSCVTY from system charstring : "OsmoBSC# " id 12
MTC@e0eada7afaad: Matching on port BSCVTY "OsmoBSC# " with pattern "[\w-]+> " unmatched: First message in the queue does not match the template:
MTC@e0eada7afaad: Matching on port BSCVTY succeeded: "OsmoBSC# " with pattern "[\w-]+\# " matched
MTC@e0eada7afaad: Receive operation on port BSCVTY succeeded, message from system(): charstring : "OsmoBSC# " id 12
MTC@e0eada7afaad: Message with id 12 was extracted from the queue of BSCVTY.
MTC@e0eada7afaad: Stop timer T: 2 s
MTC@e0eada7afaad: Creating new PTC with component type StatsD_Checker.StatsD_Checker_CT, component name: VirtMSC-STATS.
HC@e0eada7afaad: PTC VirtMSC-STATS(1164) finished. Process statistics: { process id: 19177, terminated normally, exit status: 0, user time: 0.038806 s, system time: 0.017247 s, maximum resident set size: 102808, integral resident set size: 0, page faults not requiring physical I/O: 4615, page faults requiring physical I/O: 3, swaps: 0, block input operations: 0, block output operations: 32, messages sent: 0, messages received: 0, signals received: 0, voluntary context switches: 81, involuntary context switches: 5 }
HC@e0eada7afaad: PTC VirtMSC-SCCP(1165) finished. Process statistics: { process id: 19178, terminated normally, exit status: 0, user time: 0.041452 s, system time: 0.018423 s, maximum resident set size: 102792, integral resident set size: 0, page faults not requiring physical I/O: 4612, page faults requiring physical I/O: 2, swaps: 0, block input operations: 0, block output operations: 96, messages sent: 0, messages received: 0, signals received: 0, voluntary context switches: 235, involuntary context switches: 18 }
HC@e0eada7afaad: PTC VirtMSC-IPA(1166) finished. Process statistics: { process id: 19179, terminated normally, exit status: 0, user time: 0.050993 s, system time: 0.027458 s, maximum resident set size: 103044, integral resident set size: 0, page faults not requiring physical I/O: 4585, page faults requiring physical I/O: 5, swaps: 0, block input operations: 0, block output operations: 88, messages sent: 0, messages received: 0, signals received: 0, voluntary context switches: 239, involuntary context switches: 8 }
HC@e0eada7afaad: PTC VirtMSC-IPA-WAIT(1167) finished. Process statistics: { process id: 19180, terminated normally, exit status: 0, user time: 0.039210 s, system time: 0.007129 s, maximum resident set size: 100492, integral resident set size: 0, page faults not requiring physical I/O: 4506, page faults requiring physical I/O: 0, swaps: 0, block input operations: 0, block output operations: 16, messages sent: 0, messages received: 0, signals received: 0, voluntary context switches: 39, involuntary context switches: 9 }
HC@e0eada7afaad: PTC VirtMSC-RAN(1168) finished. Process statistics: { process id: 19182, terminated normally, exit status: 0, user time: 0.053956 s, system time: 0.020233 s, maximum resident set size: 106964, integral resident set size: 0, page faults not requiring physical I/O: 4682, page faults requiring physical I/O: 2, swaps: 0, block input operations: 0, block output operations: 120, messages sent: 0, messages received: 0, signals received: 0, voluntary context switches: 196, involuntary context switches: 15 }
HC@e0eada7afaad: PTC IPA-CTRL-CLI-IPA(1169) finished. Process statistics: { process id: 19183, terminated normally, exit status: 0, user time: 0.100597 s, system time: 0.048111 s, maximum resident set size: 101696, integral resident set size: 0, page faults not requiring physical I/O: 4597, page faults requiring physical I/O: 0, swaps: 0, block input operations: 0, block output operations: 2240, messages sent: 0, messages received: 0, signals received: 0, voluntary context switches: 5321, involuntary context switches: 52 }
HC@e0eada7afaad: PTC VirtMGW-MGCP-0(1170) finished. Process statistics: { process id: 19184, terminated normally, exit status: 0, user time: 0.043743 s, system time: 0.035790 s, maximum resident set size: 102948, integral resident set size: 0, page faults not requiring physical I/O: 4595, page faults requiring physical I/O: 7, swaps: 0, block input operations: 0, block output operations: 104, messages sent: 0, messages received: 0, signals received: 0, voluntary context switches: 152, involuntary context switches: 10 }
HC@e0eada7afaad: PTC IPA-BTS0-TRX0-RSL-IPA(1171) finished. Process statistics: { process id: 19186, terminated normally, exit status: 0, user time: 0.063228 s, system time: 0.012645 s, maximum resident set size: 102592, integral resident set size: 0, page faults not requiring physical I/O: 4587, page faults requiring physical I/O: 4, swaps: 0, block input operations: 0, block output operations: 168, messages sent: 0, messages received: 0, signals received: 0, voluntary context switches: 342, involuntary context switches: 9 }
HC@e0eada7afaad: PTC IPA-BTS0-TRX0-RSL-RSL(1172) finished. Process statistics: { process id: 19187, terminated normally, exit status: 0, user time: 0.055844 s, system time: 0.042957 s, maximum resident set size: 104032, integral resident set size: 0, page faults not requiring physical I/O: 4610, page faults requiring physical I/O: 0, swaps: 0, block input operations: 0, block output operations: 304, messages sent: 0, messages received: 0, signals received: 0, voluntary context switches: 498, involuntary context switches: 15 }
HC@e0eada7afaad: PTC IPA-BTS1-TRX0-RSL-IPA(1173) finished. Process statistics: { process id: 19189, terminated normally, exit status: 0, user time: 0.072395 s, system time: 0.025551 s, maximum resident set size: 102348, integral resident set size: 0, page faults not requiring physical I/O: 4582, page faults requiring physical I/O: 13, swaps: 0, block input operations: 0, block output operations: 128, messages sent: 0, messages received: 0, signals received: 0, voluntary context switches: 264, involuntary context switches: 10 }
HC@e0eada7afaad: PTC IPA-BTS1-TRX0-RSL-RSL(1174) finished. Process statistics: { process id: 19190, terminated normally, exit status: 0, user time: 0.042339 s, system time: 0.034641 s, maximum resident set size: 102968, integral resident set size: 0, page faults not requiring physical I/O: 4617, page faults requiring physical I/O: 1, swaps: 0, block input operations: 0, block output operations: 232, messages sent: 0, messages received: 0, signals received: 0, voluntary context switches: 384, involuntary context switches: 10 }
HC@e0eada7afaad: PTC TC_ho_int_a5_0(1175) finished. Process statistics: { process id: 19192, terminated normally, exit status: 0, user time: 0.108794 s, system time: 0.027198 s, maximum resident set size: 110188, integral resident set size: 0, page faults not requiring physical I/O: 4780, page faults requiring physical I/O: 0, swaps: 0, block input operations: 0, block output operations: 256, messages sent: 0, messages received: 0, signals received: 0, voluntary context switches: 538, involuntary context switches: 14 }
HC@e0eada7afaad: PTC was created. Component reference: 1176, component type: StatsD_Checker.StatsD_Checker_CT, component name: VirtMSC-STATS, testcase name: TC_ho_int_a5_1, process id: 19255.
VirtMSC-STATS(1176)@e0eada7afaad: Initializing variables, timers and ports of component type StatsD_Checker.StatsD_Checker_CT inside testcase TC_ho_int_a5_1.
MTC@e0eada7afaad: PTC was created. Component reference: 1176, alive: no, type: StatsD_Checker.StatsD_Checker_CT, component name: VirtMSC-STATS.
MTC@e0eada7afaad: Starting function main("127.0.0.1", 8125, 5.000000) on component VirtMSC-STATS(1176).
MTC@e0eada7afaad: Function was started.
MTC@e0eada7afaad: Sent on BSCVTY to system charstring : "msc 0 bssmap reset"
20251222142558384 [1;33mDMSC[0;m [1;33mNOTICE[0;m (msc=0) VTY requests BSSMAP RESET (bsc_vty.c:3616)
MTC@e0eada7afaad: Start timer T: 2 s
MTC@e0eada7afaad: Message enqueued on BSCVTY from system charstring : "OsmoBSC# " id 13
MTC@e0eada7afaad: Matching on port BSCVTY "OsmoBSC# " with pattern "[\w-]+> " unmatched: First message in the queue does not match the template:
MTC@e0eada7afaad: Matching on port BSCVTY succeeded: "OsmoBSC# " with pattern "[\w-]+\# " matched
MTC@e0eada7afaad: Receive operation on port BSCVTY succeeded, message from system(): charstring : "OsmoBSC# " id 13
MTC@e0eada7afaad: Message with id 13 was extracted from the queue of BSCVTY.
MTC@e0eada7afaad: Stop timer T: 2 s
MTC@e0eada7afaad: Sent on BSCVTY to system charstring : "msc 1 bssmap reset"
MTC@e0eada7afaad: Start timer T: 2 s
MTC@e0eada7afaad: Message enqueued on BSCVTY from system charstring : "% No such MSC: nr 1" id 14
MTC@e0eada7afaad: Message enqueued on BSCVTY from system charstring : "OsmoBSC# " id 15
MTC@e0eada7afaad: Matching on port BSCVTY "% No such MSC: nr 1" with pattern "[\w-]+> " unmatched: First message in the queue does not match the template:
MTC@e0eada7afaad: Matching on port BSCVTY "% No such MSC: nr 1" with pattern "[\w-]+\# " unmatched: First message in the queue does not match the template:
MTC@e0eada7afaad: Matching on port BSCVTY "% No such MSC: nr 1" with pattern "[\w-]+\(*\)\# " unmatched: First message in the queue does not match the template:
MTC@e0eada7afaad: Matching on port BSCVTY "% No such MSC: nr 1" with pattern "*% Unknown command." unmatched: First message in the queue does not match the template:
MTC@e0eada7afaad: Matching on port BSCVTY succeeded: "% No such MSC: nr 1" with ? matched
MTC@e0eada7afaad: Receive operation on port BSCVTY succeeded, message from system(): charstring : "% No such MSC: nr 1" id 14
MTC@e0eada7afaad: Message with id 14 was extracted from the queue of BSCVTY.
MTC@e0eada7afaad: Matching on port BSCVTY "OsmoBSC# " with pattern "[\w-]+> " unmatched: First message in the queue does not match the template:
MTC@e0eada7afaad: Matching on port BSCVTY succeeded: "OsmoBSC# " with pattern "[\w-]+\# " matched
MTC@e0eada7afaad: Receive operation on port BSCVTY succeeded, message from system(): charstring : "OsmoBSC# " id 15
MTC@e0eada7afaad: Message with id 15 was extracted from the queue of BSCVTY.
[38;5;45m20251222142558387 [1;31mDLSCCP[0;m[38;5;45m [1;31mERROR[0;m[38;5;45m 0: SCCP(rctx=0) MTP-TRANSFER.req from SCCP for OPC=187=0.23.3,DPC=185=0.23.1,SLS=2: no route! (sccp_scrc.c:177)[0;m
MTC@e0eada7afaad: Stop timer T: 2 s
MTC@e0eada7afaad: Sent on BSCVTY to system charstring : "msc 2 bssmap reset"
[38;5;45m20251222142558387 [1;33mDLSCCP[0;m[38;5;45m [1;33mNOTICE[0;m[38;5;45m 0: SCCP(rctx=0) SCRC Routing Failure (MTP failure) for CL message CL:CLDT (sccp_sclc.c:359)[0;m
MTC@e0eada7afaad: Start timer T: 2 s
20251222142558387 [1;33mDMSC[0;m [1;33mNOTICE[0;m (msc=0) (calling_addr=RI=2,PC=185,SSN=254) N-NOTICE.ind cause=5='MTP failure' importance=0 (osmo_bsc_sigtran.c:168)
MTC@e0eada7afaad: Message enqueued on BSCVTY from system charstring : "% No such MSC: nr 2" id 16
MTC@e0eada7afaad: Message enqueued on BSCVTY from system charstring : "OsmoBSC# " id 17
MTC@e0eada7afaad: Matching on port BSCVTY "% No such MSC: nr 2" with pattern "[\w-]+> " unmatched: First message in the queue does not match the template:
MTC@e0eada7afaad: Matching on port BSCVTY "% No such MSC: nr 2" with pattern "[\w-]+\# " unmatched: First message in the queue does not match the template:
MTC@e0eada7afaad: Matching on port BSCVTY "% No such MSC: nr 2" with pattern "[\w-]+\(*\)\# " unmatched: First message in the queue does not match the template:
MTC@e0eada7afaad: Matching on port BSCVTY "% No such MSC: nr 2" with pattern "*% Unknown command." unmatched: First message in the queue does not match the template:
MTC@e0eada7afaad: Matching on port BSCVTY succeeded: "% No such MSC: nr 2" with ? matched
MTC@e0eada7afaad: Receive operation on port BSCVTY succeeded, message from system(): charstring : "% No such MSC: nr 2" id 16
MTC@e0eada7afaad: Message with id 16 was extracted from the queue of BSCVTY.
MTC@e0eada7afaad: Matching on port BSCVTY "OsmoBSC# " with pattern "[\w-]+> " unmatched: First message in the queue does not match the template:
MTC@e0eada7afaad: Matching on port BSCVTY succeeded: "OsmoBSC# " with pattern "[\w-]+\# " matched
MTC@e0eada7afaad: Receive operation on port BSCVTY succeeded, message from system(): charstring : "OsmoBSC# " id 17
MTC@e0eada7afaad: Message with id 17 was extracted from the queue of BSCVTY.
MTC@e0eada7afaad: Stop timer T: 2 s
MTC@e0eada7afaad: Creating new alive PTC with component type SCCP_Emulation.SCCP_CT, component name: VirtMSC-SCCP.
VirtMSC-STATS(1176)@e0eada7afaad: Port STATSVTY was started.
VirtMSC-STATS(1176)@e0eada7afaad: Port STATSD_PROC was started.
VirtMSC-STATS(1176)@e0eada7afaad: Port STATS was started.
VirtMSC-STATS(1176)@e0eada7afaad: Component type StatsD_Checker.StatsD_Checker_CT was initialized.
VirtMSC-STATS(1176)@e0eada7afaad: Starting function main("127.0.0.1", 8125, 5.000000).
VirtMSC-STATS(1176)@e0eada7afaad: Mapping port VirtMSC-STATS(1176):STATS to system:STATS.
VirtMSC-STATS(1176)@e0eada7afaad: Port STATS was mapped to system:STATS.
VirtMSC-STATS(1176)@e0eada7afaad: Map operation of VirtMSC-STATS(1176):STATS to system:STATS finished.
VirtMSC-STATS(1176)@e0eada7afaad: entering f__IPL4__PROVIDER__listen: 127.0.0.1:8125 / UDP
VirtMSC-STATS(1176)@e0eada7afaad: Mapping port VirtMSC-STATS(1176):STATSVTY to system:STATSVTY.
VirtMSC-STATS(1176)@e0eada7afaad: Message enqueued on STATSVTY from system integer : 2 id 1
VirtMSC-STATS(1176)@e0eada7afaad: Port STATSVTY was mapped to system:STATSVTY.
VirtMSC-STATS(1176)@e0eada7afaad: Map operation of VirtMSC-STATS(1176):STATSVTY to system:STATSVTY finished.
HC@e0eada7afaad: PTC was created. Component reference: 1177, component type: SCCP_Emulation.SCCP_CT, component name: VirtMSC-SCCP, testcase name: TC_ho_int_a5_1, process id: 19256.
VirtMSC-STATS(1176)@e0eada7afaad: Sent on STATSVTY to system @TELNETasp_PortType.ASP_TelnetDynamicConfig : { prompt := { id := 1, prompt := "OsmoBSC> ", has_wildcards := false } }
VirtMSC-STATS(1176)@e0eada7afaad: Sent on STATSVTY to system @TELNETasp_PortType.ASP_TelnetDynamicConfig : { prompt := { id := 2, prompt := "OsmoBSC# ", has_wildcards := false } }
VirtMSC-SCCP(1177)@e0eada7afaad: Initializing variables, timers and ports of component type SCCP_Emulation.SCCP_CT inside testcase TC_ho_int_a5_1.
MTC@e0eada7afaad: PTC was created. Component reference: 1177, alive: yes, type: SCCP_Emulation.SCCP_CT, component name: VirtMSC-SCCP.
MTC@e0eada7afaad: Creating new alive PTC with component type IPA_Emulation.IPA_Emulation_CT, component name: VirtMSC-IPA.
VirtMSC-STATS(1176)@e0eada7afaad: Sent on STATSVTY to system @TELNETasp_PortType.ASP_TelnetDynamicConfig : { prompt := { id := 3, prompt := "OsmoBSC(*)", has_wildcards := true } }
VirtMSC-STATS(1176)@e0eada7afaad: Sent on STATSVTY to system charstring : "enable"
VirtMSC-STATS(1176)@e0eada7afaad: Start timer T: 2 s
VirtMSC-STATS(1176)@e0eada7afaad: Matching on port STATSVTY failed: Type of the first message in the queue is not charstring.
VirtMSC-STATS(1176)@e0eada7afaad: Matching on port STATSVTY failed: Type of the first message in the queue is not charstring.
VirtMSC-STATS(1176)@e0eada7afaad: Matching on port STATSVTY failed: Type of the first message in the queue is not charstring.
VirtMSC-STATS(1176)@e0eada7afaad: Matching on port STATSVTY failed: Type of the first message in the queue is not charstring.
VirtMSC-STATS(1176)@e0eada7afaad: Matching on port STATSVTY failed: Type of the first message in the queue is not charstring.
VirtMSC-STATS(1176)@e0eada7afaad: Matching on port STATSVTY succeeded: 2 with ? matched
VirtMSC-STATS(1176)@e0eada7afaad: Receive operation on port STATSVTY succeeded, message from system(): integer : 2 id 1
VirtMSC-STATS(1176)@e0eada7afaad: Message with id 1 was extracted from the queue of STATSVTY.
VirtMSC-SCCP(1177)@e0eada7afaad: Port SCCP_SP_PORT was started.
VirtMSC-SCCP(1177)@e0eada7afaad: Port MTP3_SCCP_PORT was started.
VirtMSC-STATS(1176)@e0eada7afaad: Message enqueued on STATSVTY from system charstring : "OsmoBSC# " id 2
VirtMSC-SCCP(1177)@e0eada7afaad: Component type SCCP_Emulation.SCCP_CT was initialized.
VirtMSC-STATS(1176)@e0eada7afaad: Matching on port STATSVTY "OsmoBSC# " with pattern "[\w-]+> " unmatched: First message in the queue does not match the template:
VirtMSC-STATS(1176)@e0eada7afaad: Matching on port STATSVTY succeeded: "OsmoBSC# " with pattern "[\w-]+\# " matched
VirtMSC-STATS(1176)@e0eada7afaad: Receive operation on port STATSVTY succeeded, message from system(): charstring : "OsmoBSC# " id 2
VirtMSC-STATS(1176)@e0eada7afaad: Message with id 2 was extracted from the queue of STATSVTY.
VirtMSC-STATS(1176)@e0eada7afaad: Stop timer T: 2 s
VirtMSC-STATS(1176)@e0eada7afaad: Sent on STATSVTY to system charstring : "stats reset"
VirtMSC-STATS(1176)@e0eada7afaad: Start timer T: 2 s
VirtMSC-STATS(1176)@e0eada7afaad: Message enqueued on STATSVTY from system charstring : "OsmoBSC# " id 3
VirtMSC-STATS(1176)@e0eada7afaad: Matching on port STATSVTY "OsmoBSC# " with pattern "[\w-]+> " unmatched: First message in the queue does not match the template:
VirtMSC-STATS(1176)@e0eada7afaad: Matching on port STATSVTY succeeded: "OsmoBSC# " with pattern "[\w-]+\# " matched
VirtMSC-STATS(1176)@e0eada7afaad: Receive operation on port STATSVTY succeeded, message from system(): charstring : "OsmoBSC# " id 3
VirtMSC-STATS(1176)@e0eada7afaad: Message with id 3 was extracted from the queue of STATSVTY.
VirtMSC-STATS(1176)@e0eada7afaad: Stop timer T: 2 s
HC@e0eada7afaad: PTC was created. Component reference: 1178, component type: IPA_Emulation.IPA_Emulation_CT, component name: VirtMSC-IPA, testcase name: TC_ho_int_a5_1, process id: 19257.
VirtMSC-IPA(1178)@e0eada7afaad: Initializing variables, timers and ports of component type IPA_Emulation.IPA_Emulation_CT inside testcase TC_ho_int_a5_1.
MTC@e0eada7afaad: PTC was created. Component reference: 1178, alive: yes, type: IPA_Emulation.IPA_Emulation_CT, component name: VirtMSC-IPA.
MTC@e0eada7afaad: Mapping port VirtMSC-IPA(1178):IPA_PORT to system:IPA_CODEC_PT.
VirtMSC-IPA(1178)@e0eada7afaad: Port IPA_PORT was started.
VirtMSC-IPA(1178)@e0eada7afaad: Port CFG_PORT was started.
VirtMSC-IPA(1178)@e0eada7afaad: Port MTP3_SP_PORT was started.
VirtMSC-IPA(1178)@e0eada7afaad: Port IPA_MGCP_PORT was started.
VirtMSC-IPA(1178)@e0eada7afaad: Port IPA_RSL_PORT was started.
VirtMSC-IPA(1178)@e0eada7afaad: Port IPA_OML_PORT was started.
VirtMSC-IPA(1178)@e0eada7afaad: Port IPA_CTRL_PORT was started.
VirtMSC-IPA(1178)@e0eada7afaad: Port IPA_SP_PORT was started.
VirtMSC-IPA(1178)@e0eada7afaad: Component type IPA_Emulation.IPA_Emulation_CT was initialized.
VirtMSC-IPA(1178)@e0eada7afaad: Port IPA_PORT was mapped to system:IPA_CODEC_PT.
MTC@e0eada7afaad: Map operation of VirtMSC-IPA(1178):IPA_PORT to system:IPA_CODEC_PT finished.
MTC@e0eada7afaad: Connecting ports VirtMSC-IPA(1178):MTP3_SP_PORT and VirtMSC-SCCP(1177):MTP3_SCCP_PORT.
VirtMSC-SCCP(1177)@e0eada7afaad: Port MTP3_SCCP_PORT is waiting for connection from VirtMSC-IPA(1178):MTP3_SP_PORT on UNIX pathname /tmp/ttcn3-portconn-7511f0e4.
VirtMSC-IPA(1178)@e0eada7afaad: Port MTP3_SP_PORT has established the connection with VirtMSC-SCCP(1177):MTP3_SCCP_PORT using transport type UNIX.
VirtMSC-SCCP(1177)@e0eada7afaad: Port MTP3_SCCP_PORT has accepted the connection from VirtMSC-IPA(1178):MTP3_SP_PORT.
MTC@e0eada7afaad: Connect operation on VirtMSC-IPA(1178):MTP3_SP_PORT and VirtMSC-SCCP(1177):MTP3_SCCP_PORT finished.
MTC@e0eada7afaad: Creating new alive PTC with component type IPA_Emulation.IPA_EventWaiter_CT, component name: VirtMSC-IPA-WAIT.
HC@e0eada7afaad: PTC was created. Component reference: 1179, component type: IPA_Emulation.IPA_EventWaiter_CT, component name: VirtMSC-IPA-WAIT, testcase name: TC_ho_int_a5_1, process id: 19258.
VirtMSC-IPA-WAIT(1179)@e0eada7afaad: Initializing variables, timers and ports of component type IPA_Emulation.IPA_EventWaiter_CT inside testcase TC_ho_int_a5_1.
MTC@e0eada7afaad: PTC was created. Component reference: 1179, alive: yes, type: IPA_Emulation.IPA_EventWaiter_CT, component name: VirtMSC-IPA-WAIT.
MTC@e0eada7afaad: Connecting ports VirtMSC-IPA(1178):IPA_SP_PORT and VirtMSC-IPA-WAIT(1179):IPA_SP_PORT.
VirtMSC-IPA(1178)@e0eada7afaad: Port IPA_SP_PORT is waiting for connection from VirtMSC-IPA-WAIT(1179):IPA_SP_PORT on UNIX pathname /tmp/ttcn3-portconn-5202b695.
VirtMSC-IPA-WAIT(1179)@e0eada7afaad: Port IPA_SP_PORT was started.
VirtMSC-IPA-WAIT(1179)@e0eada7afaad: Component type IPA_Emulation.IPA_EventWaiter_CT was initialized.
VirtMSC-IPA-WAIT(1179)@e0eada7afaad: Port IPA_SP_PORT has established the connection with VirtMSC-IPA(1178):IPA_SP_PORT using transport type UNIX.
VirtMSC-IPA(1178)@e0eada7afaad: Port IPA_SP_PORT has accepted the connection from VirtMSC-IPA-WAIT(1179):IPA_SP_PORT.
MTC@e0eada7afaad: Connect operation on VirtMSC-IPA(1178):IPA_SP_PORT and VirtMSC-IPA-WAIT(1179):IPA_SP_PORT finished.
MTC@e0eada7afaad: Starting function waiter_main({ ev_type := ASP_IPA_EVENT_ID_ACK (3), conn_id := ?, id_resp := * }) on component VirtMSC-IPA-WAIT(1179).
MTC@e0eada7afaad: Function was started.
MTC@e0eada7afaad: Starting function main_server("127.0.0.1", 5000, true, IPA_INIT_SEND_IPA_ID_ACK (1), true) on component VirtMSC-IPA(1178).
MTC@e0eada7afaad: Function was started.
VirtMSC-IPA-WAIT(1179)@e0eada7afaad: Starting function waiter_main({ ev_type := ASP_IPA_EVENT_ID_ACK (3), conn_id := ?, id_resp := * }).
VirtMSC-IPA(1178)@e0eada7afaad: Starting function main_server("127.0.0.1", 5000, true, IPA_INIT_SEND_IPA_ID_ACK (1), true).
VirtMSC-IPA(1178)@e0eada7afaad: entering f__IPL4__PROVIDER__listen: 127.0.0.1:5000 / TCP
FATAL: command execution failed
java.io.EOFException
at java.base/java.io.ObjectInputStream$PeekInputStream.readFully(Unknown Source)
at java.base/java.io.ObjectInputStream$BlockDataInputStream.readShort(Unknown Source)
at java.base/java.io.ObjectInputStream.readStreamHeader(Unknown Source)
at java.base/java.io.ObjectInputStream.<init>(Unknown Source)
at hudson.remoting.ObjectInputStreamEx.<init>(ObjectInputStreamEx.java:50)
at hudson.remoting.Command.readFrom(Command.java:141)
at hudson.remoting.Command.readFrom(Command.java:127)
at hudson.remoting.AbstractSynchronousByteArrayCommandTransport.read(AbstractSynchronousByteArrayCommandTransport.java:35)
at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:62)
Caused: java.io.IOException: Unexpected termination of the channel
at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:80)
Caused: java.io.IOException: Backing channel 'build5-deb12build-ansible' is disconnected.
at hudson.remoting.RemoteInvocationHandler.channelOrFail(RemoteInvocationHandler.java:227)
at hudson.remoting.RemoteInvocationHandler.invoke(RemoteInvocationHandler.java:306)
at jdk.proxy2/jdk.proxy2.$Proxy156.isAlive(Unknown Source)
at hudson.Launcher$RemoteLauncher$ProcImpl.isAlive(Launcher.java:1212)
at hudson.Launcher$RemoteLauncher$ProcImpl.join(Launcher.java:1204)
at hudson.tasks.CommandInterpreter.join(CommandInterpreter.java:195)
at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:145)
at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:92)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:818)
at hudson.model.Build$BuildExecution.build(Build.java:199)
at hudson.model.Build$BuildExecution.doRun(Build.java:164)
at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:527)
at hudson.model.Run.execute(Run.java:1833)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:44)
at hudson.model.ResourceController.execute(ResourceController.java:101)
at hudson.model.Executor.run(Executor.java:446)
FATAL: Unable to delete script file /tmp/jenkins2152644542038171758.sh
java.io.EOFException
at java.base/java.io.ObjectInputStream$PeekInputStream.readFully(Unknown Source)
at java.base/java.io.ObjectInputStream$BlockDataInputStream.readShort(Unknown Source)
at java.base/java.io.ObjectInputStream.readStreamHeader(Unknown Source)
at java.base/java.io.ObjectInputStream.<init>(Unknown Source)
at hudson.remoting.ObjectInputStreamEx.<init>(ObjectInputStreamEx.java:50)
at hudson.remoting.Command.readFrom(Command.java:141)
at hudson.remoting.Command.readFrom(Command.java:127)
at hudson.remoting.AbstractSynchronousByteArrayCommandTransport.read(AbstractSynchronousByteArrayCommandTransport.java:35)
at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:62)
Caused: java.io.IOException: Unexpected termination of the channel
at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:80)
Caused: hudson.remoting.ChannelClosedException: Channel "hudson.remoting.Channel@3d67a435:build5-deb12build-ansible": Remote call on build5-deb12build-ansible failed. The channel is closing down or has closed down
at hudson.remoting.Channel.call(Channel.java:1105)
at hudson.FilePath.act(FilePath.java:1207)
at hudson.FilePath.act(FilePath.java:1196)
at hudson.FilePath.delete(FilePath.java:1743)
at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:163)
at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:92)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:818)
at hudson.model.Build$BuildExecution.build(Build.java:199)
at hudson.model.Build$BuildExecution.doRun(Build.java:164)
at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:527)
at hudson.model.Run.execute(Run.java:1833)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:44)
at hudson.model.ResourceController.execute(ResourceController.java:101)
at hudson.model.Executor.run(Executor.java:446)
Build step 'Execute shell' marked build as failure
ERROR: Step ‘Publish JUnit test result report’ failed: no workspace for ttcn3-bsc-test-sccplite-latest #2536
1
1
23 Dec '25
See <https://jenkins.osmocom.org/jenkins/job/ttcn3-gbproxy-test-io_uring/653/dis…>
Changes:
[Pau Espin Pedrol] library/NGAP_Templates: PDUSessionResourceSetupRequest: Fix criticality
------------------------------------------
[...truncated 2.92 MiB...]
MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed
[38;5;57m20251222142558391 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE02001-NSVC-UDP-127_0_0_1:24000-127_0_0_10:21010)[0x55ec6dd63420]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20251222142558391 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) Rx RESUME (gb_proxy.c:1053)
20251222142558391 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) proxying to NSE(00101/SGSN) (gb_proxy.c:204)
MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed
[38;5;57m20251222142558394 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE00101-NSVC-UDP-127_0_0_1:23000-127_0_0_10:7777)[0x55ec6dd5d030]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20251222142558394 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00101/SGSN)-BVC(00000/??) Rx RESUME-NACK (gb_proxy.c:1425)
20251222142558394 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS) Rx RESUME-NACK: forwarding (gb_proxy.c:1618)
20251222142558394 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00101/SGSN)-BVC(00000/??) proxying to NSE(02001/BSS) (gb_proxy.c:204)
MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed
MTC@114173dc6707: f_gen_tmsi(suffix:=6, nri_v:=3, nri_bitlen:=5, base_tmsi:='42000023'O) -> prefix:='01000010'B, suffix:='0000000000000101001'B, total_bits:='01000010000110000000000000101001'B == '42180029'O
[38;5;57m20251222142558397 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE02001-NSVC-UDP-127_0_0_1:24000-127_0_0_10:21010)[0x55ec6dd63420]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20251222142558397 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) Rx RESUME (gb_proxy.c:1053)
20251222142558397 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) proxying to NSE(00101/SGSN) (gb_proxy.c:204)
MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed
[38;5;57m20251222142558399 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE00101-NSVC-UDP-127_0_0_1:23000-127_0_0_10:7777)[0x55ec6dd5d030]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20251222142558399 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00101/SGSN)-BVC(00000/??) Rx RESUME-ACK (gb_proxy.c:1425)
20251222142558399 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS) Rx RESUME-ACK: forwarding (gb_proxy.c:1618)
20251222142558399 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00101/SGSN)-BVC(00000/??) proxying to NSE(02001/BSS) (gb_proxy.c:204)
MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed
[38;5;57m20251222142558401 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE02001-NSVC-UDP-127_0_0_1:24000-127_0_0_10:21010)[0x55ec6dd63420]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20251222142558401 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) Rx RESUME (gb_proxy.c:1053)
20251222142558401 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) proxying to NSE(00101/SGSN) (gb_proxy.c:204)
MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed
[38;5;57m20251222142558402 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE00101-NSVC-UDP-127_0_0_1:23000-127_0_0_10:7777)[0x55ec6dd5d030]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20251222142558402 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00101/SGSN)-BVC(00000/??) Rx RESUME-NACK (gb_proxy.c:1425)
20251222142558402 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS) Rx RESUME-NACK: forwarding (gb_proxy.c:1618)
20251222142558402 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00101/SGSN)-BVC(00000/??) proxying to NSE(02001/BSS) (gb_proxy.c:204)
MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed
MTC@114173dc6707: f_gen_tmsi(suffix:=7, nri_v:=3, nri_bitlen:=5, base_tmsi:='42000023'O) -> prefix:='01000010'B, suffix:='0000000000000101010'B, total_bits:='01000010000110000000000000101010'B == '4218002A'O
[38;5;57m20251222142558404 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE02001-NSVC-UDP-127_0_0_1:24000-127_0_0_10:21010)[0x55ec6dd63420]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20251222142558404 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) Rx RESUME (gb_proxy.c:1053)
20251222142558404 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) proxying to NSE(00101/SGSN) (gb_proxy.c:204)
MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed
[38;5;57m20251222142558405 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE00101-NSVC-UDP-127_0_0_1:23000-127_0_0_10:7777)[0x55ec6dd5d030]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20251222142558405 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00101/SGSN)-BVC(00000/??) Rx RESUME-ACK (gb_proxy.c:1425)
20251222142558405 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS) Rx RESUME-ACK: forwarding (gb_proxy.c:1618)
20251222142558405 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00101/SGSN)-BVC(00000/??) proxying to NSE(02001/BSS) (gb_proxy.c:204)
MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed
[38;5;57m20251222142558406 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE02001-NSVC-UDP-127_0_0_1:24000-127_0_0_10:21010)[0x55ec6dd63420]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20251222142558406 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) Rx RESUME (gb_proxy.c:1053)
20251222142558406 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) proxying to NSE(00101/SGSN) (gb_proxy.c:204)
MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed
[38;5;57m20251222142558408 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE00101-NSVC-UDP-127_0_0_1:23000-127_0_0_10:7777)[0x55ec6dd5d030]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20251222142558408 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00101/SGSN)-BVC(00000/??) Rx RESUME-NACK (gb_proxy.c:1425)
20251222142558408 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS) Rx RESUME-NACK: forwarding (gb_proxy.c:1618)
20251222142558408 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00101/SGSN)-BVC(00000/??) proxying to NSE(02001/BSS) (gb_proxy.c:204)
MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed
MTC@114173dc6707: f_gen_tmsi(suffix:=8, nri_v:=3, nri_bitlen:=5, base_tmsi:='42000023'O) -> prefix:='01000010'B, suffix:='0000000000000101011'B, total_bits:='01000010000110000000000000101011'B == '4218002B'O
[38;5;57m20251222142558410 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE02001-NSVC-UDP-127_0_0_1:24000-127_0_0_10:21010)[0x55ec6dd63420]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20251222142558410 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) Rx RESUME (gb_proxy.c:1053)
20251222142558410 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) proxying to NSE(00101/SGSN) (gb_proxy.c:204)
MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed
[38;5;57m20251222142558411 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE00101-NSVC-UDP-127_0_0_1:23000-127_0_0_10:7777)[0x55ec6dd5d030]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20251222142558411 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00101/SGSN)-BVC(00000/??) Rx RESUME-ACK (gb_proxy.c:1425)
20251222142558411 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS) Rx RESUME-ACK: forwarding (gb_proxy.c:1618)
20251222142558411 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00101/SGSN)-BVC(00000/??) proxying to NSE(02001/BSS) (gb_proxy.c:204)
MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed
[38;5;57m20251222142558414 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE02001-NSVC-UDP-127_0_0_1:24000-127_0_0_10:21010)[0x55ec6dd63420]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20251222142558414 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) Rx RESUME (gb_proxy.c:1053)
20251222142558414 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) proxying to NSE(00101/SGSN) (gb_proxy.c:204)
MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed
[38;5;57m20251222142558416 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE00101-NSVC-UDP-127_0_0_1:23000-127_0_0_10:7777)[0x55ec6dd5d030]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20251222142558416 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00101/SGSN)-BVC(00000/??) Rx RESUME-NACK (gb_proxy.c:1425)
20251222142558417 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS) Rx RESUME-NACK: forwarding (gb_proxy.c:1618)
20251222142558417 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00101/SGSN)-BVC(00000/??) proxying to NSE(02001/BSS) (gb_proxy.c:204)
MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed
MTC@114173dc6707: f_gen_tmsi(suffix:=9, nri_v:=3, nri_bitlen:=5, base_tmsi:='42000023'O) -> prefix:='01000010'B, suffix:='0000000000000101100'B, total_bits:='01000010000110000000000000101100'B == '4218002C'O
[38;5;57m20251222142558421 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE02001-NSVC-UDP-127_0_0_1:24000-127_0_0_10:21010)[0x55ec6dd63420]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20251222142558421 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) Rx RESUME (gb_proxy.c:1053)
20251222142558421 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) proxying to NSE(00101/SGSN) (gb_proxy.c:204)
MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed
[38;5;57m20251222142558425 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE00101-NSVC-UDP-127_0_0_1:23000-127_0_0_10:7777)[0x55ec6dd5d030]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20251222142558425 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00101/SGSN)-BVC(00000/??) Rx RESUME-ACK (gb_proxy.c:1425)
20251222142558425 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS) Rx RESUME-ACK: forwarding (gb_proxy.c:1618)
20251222142558425 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00101/SGSN)-BVC(00000/??) proxying to NSE(02001/BSS) (gb_proxy.c:204)
MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed
[38;5;57m20251222142558431 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE02001-NSVC-UDP-127_0_0_1:24000-127_0_0_10:21010)[0x55ec6dd63420]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20251222142558431 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) Rx RESUME (gb_proxy.c:1053)
20251222142558431 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) proxying to NSE(00101/SGSN) (gb_proxy.c:204)
MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed
[38;5;57m20251222142558436 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE00101-NSVC-UDP-127_0_0_1:23000-127_0_0_10:7777)[0x55ec6dd5d030]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20251222142558436 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00101/SGSN)-BVC(00000/??) Rx RESUME-NACK (gb_proxy.c:1425)
20251222142558436 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS) Rx RESUME-NACK: forwarding (gb_proxy.c:1618)
20251222142558436 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00101/SGSN)-BVC(00000/??) proxying to NSE(02001/BSS) (gb_proxy.c:204)
MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed
MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed
MTC@114173dc6707: f_gen_tmsi(suffix:=0, nri_v:=4, nri_bitlen:=5, base_tmsi:='42000023'O) -> prefix:='01000010'B, suffix:='0000000000000100011'B, total_bits:='01000010001000000000000000100011'B == '42200023'O
[38;5;57m20251222142558443 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE02001-NSVC-UDP-127_0_0_1:24000-127_0_0_10:21010)[0x55ec6dd63420]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20251222142558443 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) Rx RESUME (gb_proxy.c:1053)
20251222142558443 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) proxying to NSE(00102/SGSN) (gb_proxy.c:204)
MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed
[38;5;57m20251222142558456 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE00102-NSVC-UDP-127_0_0_1:23000-127_0_0_10:8888)[0x55ec6dd67d90]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20251222142558456 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00102/SGSN)-BVC(00000/??) Rx RESUME-ACK (gb_proxy.c:1425)
20251222142558456 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS) Rx RESUME-ACK: forwarding (gb_proxy.c:1618)
20251222142558456 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00102/SGSN)-BVC(00000/??) proxying to NSE(02001/BSS) (gb_proxy.c:204)
MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed
[38;5;57m20251222142558462 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE02001-NSVC-UDP-127_0_0_1:24000-127_0_0_10:21010)[0x55ec6dd63420]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20251222142558462 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) Rx RESUME (gb_proxy.c:1053)
20251222142558462 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) proxying to NSE(00102/SGSN) (gb_proxy.c:204)
MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed
[38;5;57m20251222142558470 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE00102-NSVC-UDP-127_0_0_1:23000-127_0_0_10:8888)[0x55ec6dd67d90]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20251222142558470 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00102/SGSN)-BVC(00000/??) Rx RESUME-NACK (gb_proxy.c:1425)
20251222142558470 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS) Rx RESUME-NACK: forwarding (gb_proxy.c:1618)
20251222142558470 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00102/SGSN)-BVC(00000/??) proxying to NSE(02001/BSS) (gb_proxy.c:204)
MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed
MTC@114173dc6707: f_gen_tmsi(suffix:=1, nri_v:=4, nri_bitlen:=5, base_tmsi:='42000023'O) -> prefix:='01000010'B, suffix:='0000000000000100100'B, total_bits:='01000010001000000000000000100100'B == '42200024'O
[38;5;57m20251222142558477 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE02001-NSVC-UDP-127_0_0_1:24000-127_0_0_10:21010)[0x55ec6dd63420]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20251222142558477 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) Rx RESUME (gb_proxy.c:1053)
20251222142558477 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) proxying to NSE(00102/SGSN) (gb_proxy.c:204)
MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed
[38;5;57m20251222142558484 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE00102-NSVC-UDP-127_0_0_1:23000-127_0_0_10:8888)[0x55ec6dd67d90]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20251222142558484 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00102/SGSN)-BVC(00000/??) Rx RESUME-ACK (gb_proxy.c:1425)
20251222142558484 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS) Rx RESUME-ACK: forwarding (gb_proxy.c:1618)
20251222142558484 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00102/SGSN)-BVC(00000/??) proxying to NSE(02001/BSS) (gb_proxy.c:204)
MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed
[38;5;57m20251222142558490 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE02001-NSVC-UDP-127_0_0_1:24000-127_0_0_10:21010)[0x55ec6dd63420]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20251222142558490 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) Rx RESUME (gb_proxy.c:1053)
20251222142558490 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) proxying to NSE(00102/SGSN) (gb_proxy.c:204)
MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed
[38;5;57m20251222142558496 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE00102-NSVC-UDP-127_0_0_1:23000-127_0_0_10:8888)[0x55ec6dd67d90]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20251222142558496 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00102/SGSN)-BVC(00000/??) Rx RESUME-NACK (gb_proxy.c:1425)
20251222142558496 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS) Rx RESUME-NACK: forwarding (gb_proxy.c:1618)
20251222142558496 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00102/SGSN)-BVC(00000/??) proxying to NSE(02001/BSS) (gb_proxy.c:204)
MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed
MTC@114173dc6707: f_gen_tmsi(suffix:=2, nri_v:=4, nri_bitlen:=5, base_tmsi:='42000023'O) -> prefix:='01000010'B, suffix:='0000000000000100101'B, total_bits:='01000010001000000000000000100101'B == '42200025'O
[38;5;57m20251222142558503 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE02001-NSVC-UDP-127_0_0_1:24000-127_0_0_10:21010)[0x55ec6dd63420]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20251222142558503 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) Rx RESUME (gb_proxy.c:1053)
20251222142558503 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) proxying to NSE(00102/SGSN) (gb_proxy.c:204)
MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed
[38;5;57m20251222142558510 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE00102-NSVC-UDP-127_0_0_1:23000-127_0_0_10:8888)[0x55ec6dd67d90]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20251222142558510 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00102/SGSN)-BVC(00000/??) Rx RESUME-ACK (gb_proxy.c:1425)
20251222142558510 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS) Rx RESUME-ACK: forwarding (gb_proxy.c:1618)
20251222142558510 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00102/SGSN)-BVC(00000/??) proxying to NSE(02001/BSS) (gb_proxy.c:204)
MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed
[38;5;57m20251222142558517 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE02001-NSVC-UDP-127_0_0_1:24000-127_0_0_10:21010)[0x55ec6dd63420]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20251222142558518 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) Rx RESUME (gb_proxy.c:1053)
20251222142558518 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) proxying to NSE(00102/SGSN) (gb_proxy.c:204)
MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed
[38;5;57m20251222142558524 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE00102-NSVC-UDP-127_0_0_1:23000-127_0_0_10:8888)[0x55ec6dd67d90]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20251222142558524 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00102/SGSN)-BVC(00000/??) Rx RESUME-NACK (gb_proxy.c:1425)
20251222142558524 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS) Rx RESUME-NACK: forwarding (gb_proxy.c:1618)
20251222142558524 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00102/SGSN)-BVC(00000/??) proxying to NSE(02001/BSS) (gb_proxy.c:204)
MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed
MTC@114173dc6707: f_gen_tmsi(suffix:=3, nri_v:=4, nri_bitlen:=5, base_tmsi:='42000023'O) -> prefix:='01000010'B, suffix:='0000000000000100110'B, total_bits:='01000010001000000000000000100110'B == '42200026'O
[38;5;57m20251222142558533 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE02001-NSVC-UDP-127_0_0_1:24000-127_0_0_10:21010)[0x55ec6dd63420]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20251222142558533 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) Rx RESUME (gb_proxy.c:1053)
20251222142558533 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) proxying to NSE(00102/SGSN) (gb_proxy.c:204)
MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed
[38;5;57m20251222142558539 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE00102-NSVC-UDP-127_0_0_1:23000-127_0_0_10:8888)[0x55ec6dd67d90]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20251222142558539 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00102/SGSN)-BVC(00000/??) Rx RESUME-ACK (gb_proxy.c:1425)
20251222142558539 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS) Rx RESUME-ACK: forwarding (gb_proxy.c:1618)
20251222142558539 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00102/SGSN)-BVC(00000/??) proxying to NSE(02001/BSS) (gb_proxy.c:204)
MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed
[38;5;57m20251222142558546 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE02001-NSVC-UDP-127_0_0_1:24000-127_0_0_10:21010)[0x55ec6dd63420]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20251222142558546 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) Rx RESUME (gb_proxy.c:1053)
20251222142558546 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) proxying to NSE(00102/SGSN) (gb_proxy.c:204)
MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed
[38;5;57m20251222142558552 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE00102-NSVC-UDP-127_0_0_1:23000-127_0_0_10:8888)[0x55ec6dd67d90]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20251222142558552 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00102/SGSN)-BVC(00000/??) Rx RESUME-NACK (gb_proxy.c:1425)
20251222142558552 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS) Rx RESUME-NACK: forwarding (gb_proxy.c:1618)
20251222142558552 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00102/SGSN)-BVC(00000/??) proxying to NSE(02001/BSS) (gb_proxy.c:204)
MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed
MTC@114173dc6707: f_gen_tmsi(suffix:=4, nri_v:=4, nri_bitlen:=5, base_tmsi:='42000023'O) -> prefix:='01000010'B, suffix:='0000000000000100111'B, total_bits:='01000010001000000000000000100111'B == '42200027'O
[38;5;57m20251222142558560 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE02001-NSVC-UDP-127_0_0_1:24000-127_0_0_10:21010)[0x55ec6dd63420]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20251222142558560 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) Rx RESUME (gb_proxy.c:1053)
20251222142558560 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) proxying to NSE(00102/SGSN) (gb_proxy.c:204)
MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed
[38;5;57m20251222142558566 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE00102-NSVC-UDP-127_0_0_1:23000-127_0_0_10:8888)[0x55ec6dd67d90]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20251222142558566 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00102/SGSN)-BVC(00000/??) Rx RESUME-ACK (gb_proxy.c:1425)
20251222142558566 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS) Rx RESUME-ACK: forwarding (gb_proxy.c:1618)
20251222142558566 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00102/SGSN)-BVC(00000/??) proxying to NSE(02001/BSS) (gb_proxy.c:204)
MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed
[38;5;57m20251222142558573 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE02001-NSVC-UDP-127_0_0_1:24000-127_0_0_10:21010)[0x55ec6dd63420]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20251222142558573 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) Rx RESUME (gb_proxy.c:1053)
20251222142558573 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) proxying to NSE(00102/SGSN) (gb_proxy.c:204)
MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed
[38;5;57m20251222142558579 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE00102-NSVC-UDP-127_0_0_1:23000-127_0_0_10:8888)[0x55ec6dd67d90]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20251222142558579 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00102/SGSN)-BVC(00000/??) Rx RESUME-NACK (gb_proxy.c:1425)
20251222142558579 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS) Rx RESUME-NACK: forwarding (gb_proxy.c:1618)
20251222142558579 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(00102/SGSN)-BVC(00000/??) proxying to NSE(02001/BSS) (gb_proxy.c:204)
MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed
MTC@114173dc6707: f_gen_tmsi(suffix:=5, nri_v:=4, nri_bitlen:=5, base_tmsi:='42000023'O) -> prefix:='01000010'B, suffix:='0000000000000101000'B, total_bits:='01000010001000000000000000101000'B == '42200028'O
[38;5;57m20251222142558585 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE02001-NSVC-UDP-127_0_0_1:24000-127_0_0_10:21010)[0x55ec6dd63420]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20251222142558585 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) Rx RESUME (gb_proxy.c:1053)
20251222142558585 [1;34mDGPRS[0;m [1;34mDEBUG[0;m NSE(02001/BSS)-BVC(00000/??) proxying to NSE(00102/SGSN) (gb_proxy.c:204)
MTC@114173dc6707: setverdict(pass): pass -> pass, component reason not changed
FATAL: command execution failed
java.io.EOFException
at java.base/java.io.ObjectInputStream$PeekInputStream.readFully(Unknown Source)
at java.base/java.io.ObjectInputStream$BlockDataInputStream.readShort(Unknown Source)
at java.base/java.io.ObjectInputStream.readStreamHeader(Unknown Source)
at java.base/java.io.ObjectInputStream.<init>(Unknown Source)
at hudson.remoting.ObjectInputStreamEx.<init>(ObjectInputStreamEx.java:50)
at hudson.remoting.Command.readFrom(Command.java:141)
at hudson.remoting.Command.readFrom(Command.java:127)
at hudson.remoting.AbstractSynchronousByteArrayCommandTransport.read(AbstractSynchronousByteArrayCommandTransport.java:35)
at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:62)
Caused: java.io.IOException: Unexpected termination of the channel
at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:80)
Caused: hudson.remoting.ChannelClosedException: Channel "hudson.remoting.Channel@3d67a435:build5-deb12build-ansible": Remote call on build5-deb12build-ansible failed. The channel is closing down or has closed down
at hudson.remoting.Channel.call(Channel.java:1105)
at hudson.remoting.RemoteInvocationHandler.invoke(RemoteInvocationHandler.java:306)
at jdk.proxy2/jdk.proxy2.$Proxy156.isAlive(Unknown Source)
at hudson.Launcher$RemoteLauncher$ProcImpl.isAlive(Launcher.java:1212)
at hudson.Launcher$RemoteLauncher$ProcImpl.join(Launcher.java:1204)
at hudson.tasks.CommandInterpreter.join(CommandInterpreter.java:195)
at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:145)
at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:92)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:818)
at hudson.model.Build$BuildExecution.build(Build.java:199)
at hudson.model.Build$BuildExecution.doRun(Build.java:164)
at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:527)
at hudson.model.Run.execute(Run.java:1833)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:44)
at hudson.model.ResourceController.execute(ResourceController.java:101)
at hudson.model.Executor.run(Executor.java:446)
FATAL: Unable to delete script file /tmp/jenkins6032169796691918818.sh
java.io.EOFException
at java.base/java.io.ObjectInputStream$PeekInputStream.readFully(Unknown Source)
at java.base/java.io.ObjectInputStream$BlockDataInputStream.readShort(Unknown Source)
at java.base/java.io.ObjectInputStream.readStreamHeader(Unknown Source)
at java.base/java.io.ObjectInputStream.<init>(Unknown Source)
at hudson.remoting.ObjectInputStreamEx.<init>(ObjectInputStreamEx.java:50)
at hudson.remoting.Command.readFrom(Command.java:141)
at hudson.remoting.Command.readFrom(Command.java:127)
at hudson.remoting.AbstractSynchronousByteArrayCommandTransport.read(AbstractSynchronousByteArrayCommandTransport.java:35)
at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:62)
Caused: java.io.IOException: Unexpected termination of the channel
at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:80)
Caused: hudson.remoting.ChannelClosedException: Channel "hudson.remoting.Channel@3d67a435:build5-deb12build-ansible": Remote call on build5-deb12build-ansible failed. The channel is closing down or has closed down
at hudson.remoting.Channel.call(Channel.java:1105)
at hudson.FilePath.act(FilePath.java:1207)
at hudson.FilePath.act(FilePath.java:1196)
at hudson.FilePath.delete(FilePath.java:1743)
at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:163)
at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:92)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:818)
at hudson.model.Build$BuildExecution.build(Build.java:199)
at hudson.model.Build$BuildExecution.doRun(Build.java:164)
at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:527)
at hudson.model.Run.execute(Run.java:1833)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:44)
at hudson.model.ResourceController.execute(ResourceController.java:101)
at hudson.model.Executor.run(Executor.java:446)
Build step 'Execute shell' marked build as failure
ERROR: Step ‘Publish JUnit test result report’ failed: no workspace for ttcn3-gbproxy-test-io_uring #653
1
1
Jenkins build became unstable: ttcn3-hnbgw-test-io_uring #656
by jenkins＠lists.osmocom.org 23 Dec '25
by jenkins＠lists.osmocom.org 23 Dec '25
23 Dec '25
23 Dec '25
23 Dec '25
See <https://jenkins.osmocom.org/jenkins/job/ttcn3-hnbgw-test-latest/1288/displa…>
Changes:
[Pau Espin Pedrol] library/NGAP_Templates: PDUSessionResourceSetupRequest: Fix criticality
------------------------------------------
[...truncated 30.43 MiB...]
[38;5;43m20251222125458107 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: RT(dpc=185=0.23.1,mask=0x3fff=7.255.7,prio=5,via=virt-msc0,st=available) changed to status 'unavailable' (ss7_route.c:409)[0;m
[38;5;43m20251222125458107 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: RT(dpc=185=0.23.1,mask=0x3fff=7.255.7,prio=5,via=virt-msc0,st=available) changed to status 'unavailable' (ss7_route.c:409)[0;m
[38;5;43m20251222125458107 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(hnbgw0-0) Tx 16 bytes: [L2]> 01 00 02 01 00 00 00 10 00 12 00 08 00 00 00 b9 (ss7_asp.c:1400)[0;m
[38;5;43m20251222125458107 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(virt-msc1-0) Tx 24 bytes: [L2]> 01 00 02 01 00 00 00 18 00 06 00 08 00 00 00 03 00 12 00 08 00 00 00 b9 (ss7_asp.c:1400)[0;m
[38;5;43m20251222125458107 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(virt-sgsn0-0) Tx 24 bytes: [L2]> 01 00 02 01 00 00 00 18 00 06 00 08 00 00 00 02 00 12 00 08 00 00 00 b9 (ss7_asp.c:1400)[0;m
[38;5;43m20251222125458107 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(virt-msc0-0){ASP_DOWN}: No Layer Manager, dropping M-ASP_DOWN.indication (xua_asp_fsm.c:129)[0;m
[38;5;43m20251222125458107 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(virt-msc0-0){ASP_DOWN}: No Layer Manager, dropping M-SCTP_RELEASE.indication (xua_asp_fsm.c:129)[0;m
[38;5;23m20251222125458107 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m SRVCONN(virt-msc1-0,r=::ffff:127.0.0.1:23907<->l=::ffff:127.0.0.1:2905) connection closed with client (stream_srv.c:822)[0;m
[38;5;43m20251222125458107 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(virt-msc1-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1107)[0;m
[38;5;43m20251222125458107 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m virt-msc1-0: connection closed (ss7_asp.c:1349)[0;m
[38;5;43m20251222125458107 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(virt-msc1-0){ASP_ACTIVE}: Received Event SCTP-COMM_DOWN.ind (ss7_asp.c:1355)[0;m
[38;5;43m20251222125458107 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(virt-msc1-0){ASP_ACTIVE}: state_chg to ASP_DOWN (xua_asp_fsm.c:859)[0;m
[38;5;43m20251222125458107 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(virt-msc1-0){ASP_DOWN}: T(beat) stopped (xua_asp_fsm.c:429)[0;m
[38;5;43m20251222125458107 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(virt-msc1){AS_ACTIVE}: Received Event ASPAS-ASP_DOWN.ind (xua_asp_fsm.c:405)[0;m
[38;5;43m20251222125458107 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(virt-msc1){AS_ACTIVE}: state_chg to AS_PENDING (xua_as_fsm.c:592)[0;m
[38;5;43m20251222125458107 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(virt-msc1){AS_PENDING}: Broadcasting NOTIFY Type State Change:AS Pending () to all non-DOWN ASPs (xua_as_fsm.c:116)[0;m
[38;5;43m20251222125458107 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: RT(dpc=2=0.0.2,mask=0x3fff=7.255.7,prio=5,via=virt-msc1,st=available) changed to status 'unavailable' (ss7_route.c:409)[0;m
[38;5;43m20251222125458107 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: RT(dpc=2=0.0.2,mask=0x3fff=7.255.7,prio=5,via=virt-msc1,st=available) changed to status 'unavailable' (ss7_route.c:409)[0;m
[38;5;43m20251222125458107 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(hnbgw0-0) Tx 16 bytes: [L2]> 01 00 02 01 00 00 00 10 00 12 00 08 00 00 00 02 (ss7_asp.c:1400)[0;m
[38;5;43m20251222125458107 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(virt-sgsn0-0) Tx 24 bytes: [L2]> 01 00 02 01 00 00 00 18 00 06 00 08 00 00 00 02 00 12 00 08 00 00 00 02 (ss7_asp.c:1400)[0;m
[38;5;43m20251222125458107 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(virt-msc1-0){ASP_DOWN}: No Layer Manager, dropping M-ASP_DOWN.indication (xua_asp_fsm.c:129)[0;m
[38;5;43m20251222125458107 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(virt-msc1-0){ASP_DOWN}: No Layer Manager, dropping M-SCTP_RELEASE.indication (xua_asp_fsm.c:129)[0;m
[38;5;23m20251222125458108 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m virt-sgsn0-0 NOTIFICATION SHUTDOWN_EVENT flags=0x0 (stream.c:348)[0;m
[38;5;43m20251222125458108 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(virt-sgsn0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x80) (ss7_asp.c:1107)[0;m
[38;5;43m20251222125458108 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0: ASP(virt-sgsn0-0) xUA SRV SCTP NOTIFICATION 32773 flags=0x0 (ss7_asp.c:998)[0;m
[38;5;43m20251222125458108 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: ASP(virt-sgsn0-0) xUA SRV SHUTDOWN_EVENT (ss7_asp.c:1021)[0;m
[38;5;23m20251222125458108 [1;31mDLINP[0;m[38;5;23m [1;31mERROR[0;m[38;5;23m SRVCONN(virt-sgsn0-0,r=::ffff:127.0.0.1:23906<->l=::ffff:127.0.0.1:2905) error to send: Broken pipe (stream_srv.c:796)[0;m
[38;5;23m20251222125458108 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m virt-sgsn0-0 NOTIFICATION ASSOC_CHANGE flags=0x0 SHUTDOWN_COMP (stream.c:348)[0;m
[38;5;43m20251222125458108 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(virt-sgsn0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x80) (ss7_asp.c:1107)[0;m
[38;5;43m20251222125458108 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0: ASP(virt-sgsn0-0) xUA SRV SCTP NOTIFICATION 32769 flags=0x0 (ss7_asp.c:998)[0;m
[38;5;43m20251222125458108 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: ASP(virt-sgsn0-0) xUA SRV SCTP_ASSOC_CHANGE: SHUTDOWN_COMP (ss7_asp.c:1006)[0;m
[38;5;23m20251222125458108 [1;31mDLINP[0;m[38;5;23m [1;31mERROR[0;m[38;5;23m SRVCONN(virt-sgsn0-0,r=::ffff:127.0.0.1:23906<->l=::ffff:127.0.0.1:2905) error to send: Broken pipe (stream_srv.c:796)[0;m
[38;5;43m20251222125458108 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(hnbgw0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1107)[0;m
[38;5;49m20251222125458108 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(hnbgw0-0) Received M3UA Message (XFER:DATA) (m3ua.c:811)[0;m
[38;5;49m20251222125458109 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(hnbgw0-0) m3ua_rx_xfer (m3ua.c:601)[0;m
[38;5;49m20251222125458109 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(hnbgw0-0) m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=10=0.1.2 sls=1 (m3ua.c:632)[0;m
[38;5;43m20251222125458109 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: mtp3_hmdc_rx_from_l2(): dpc=10=0.1.2 not local, message is for routing (mtp3_hmdc.c:49)[0;m
[38;5;43m20251222125458109 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0: CombinedLinkset(dpc=10=0.1.2,mask=0x3fff=7.255.7,prio=5) RT Lookup: OPC=189=0.23.5,DPC=10=0.1.2,SLS=1 -> eSLS=17: Normal Route via 'virt-sgsn1' unavailable, all Alternative Routes unavailable (ss7_combined_linkset.c:322)[0;m
[38;5;43m20251222125458109 [1;31mDLSS7[0;m[38;5;43m [1;31mERROR[0;m[38;5;43m 0: MTP-TRANSFER.req for dpc=10=0.1.2: no route! (mtp3_hmrt.c:113)[0;m
[38;5;43m20251222125458109 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: T8: SP 10=0.1.2: Start (ss7_instance.c:879)[0;m
[38;5;43m20251222125458109 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: CombinedLinkset(dpc=189=0.23.5,mask=0x3fff=7.255.7,prio=5) RT lookup: OPC=10=0.1.2,DPC=189=0.23.5,SLS=1 -> eSLS=17: use Normal Route via 'hnbgw0' (ss7_combined_linkset.c:275)[0;m
[38;5;43m20251222125458109 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0: Message received for inaccessible SP 10=0.1.2. Tx TFP (DUNA) to concerned SP 189=0.23.5 (mtp3_rtpc.c:120)[0;m
[38;5;43m20251222125458109 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(hnbgw0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:563)[0;m
[38;5;43m20251222125458109 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(hnbgw0-0) Tx 60 bytes: [L2]> 01 00 02 01 00 00 00 3c 00 12 00 08 00 00 00 0a 00 04 00 2a 74 72 61 6e 73 66 65 72 20 70 72 6f 68 69 62 69 74 65 64 20 28 69 6e 61 63 63 65 73 73 69 62 6c 65 20 53 50 29 00 00 00 (ss7_asp.c:1400)[0;m
[38;5;23m20251222125458109 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m SRVCONN(virt-sgsn0-0,r=::ffff:127.0.0.1:23906<->l=::ffff:127.0.0.1:2905) connection closed with client (stream_srv.c:822)[0;m
[38;5;43m20251222125458109 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(virt-sgsn0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1107)[0;m
[38;5;43m20251222125458109 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m virt-sgsn0-0: connection closed (ss7_asp.c:1349)[0;m
[38;5;43m20251222125458109 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(virt-sgsn0-0){ASP_ACTIVE}: Received Event SCTP-COMM_DOWN.ind (ss7_asp.c:1355)[0;m
[38;5;43m20251222125458109 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(virt-sgsn0-0){ASP_ACTIVE}: state_chg to ASP_DOWN (xua_asp_fsm.c:859)[0;m
[38;5;43m20251222125458109 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(virt-sgsn0-0){ASP_DOWN}: T(beat) stopped (xua_asp_fsm.c:429)[0;m
[38;5;43m20251222125458109 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(virt-sgsn0){AS_ACTIVE}: Received Event ASPAS-ASP_DOWN.ind (xua_asp_fsm.c:405)[0;m
[38;5;43m20251222125458109 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(virt-sgsn0){AS_ACTIVE}: state_chg to AS_PENDING (xua_as_fsm.c:592)[0;m
[38;5;43m20251222125458109 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(virt-sgsn0){AS_PENDING}: Broadcasting NOTIFY Type State Change:AS Pending () to all non-DOWN ASPs (xua_as_fsm.c:116)[0;m
[38;5;43m20251222125458109 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: RT(dpc=188=0.23.4,mask=0x3fff=7.255.7,prio=5,via=virt-sgsn0,st=available) changed to status 'unavailable' (ss7_route.c:409)[0;m
[38;5;43m20251222125458109 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: RT(dpc=188=0.23.4,mask=0x3fff=7.255.7,prio=5,via=virt-sgsn0,st=available) changed to status 'unavailable' (ss7_route.c:409)[0;m
[38;5;43m20251222125458109 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(hnbgw0-0) Tx 16 bytes: [L2]> 01 00 02 01 00 00 00 10 00 12 00 08 00 00 00 bc (ss7_asp.c:1400)[0;m
[38;5;43m20251222125458110 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(virt-sgsn0-0){ASP_DOWN}: No Layer Manager, dropping M-ASP_DOWN.indication (xua_asp_fsm.c:129)[0;m
[38;5;43m20251222125458110 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(virt-sgsn0-0){ASP_DOWN}: No Layer Manager, dropping M-SCTP_RELEASE.indication (xua_asp_fsm.c:129)[0;m
20251222125458110 [1;33mDRANAP[0;m [1;33mNOTICE[0;m cnlink(msc-1)[0x556305a6aa50]{DISCONNECTED}: link lost (cnlink_fsm.c:73)[0;m
[38;5;23m20251222125458110 [1;34mDLINP[0;m[38;5;23m [1;34mDEBUG[0;m[38;5;23m SRVCONN(,r=127.0.0.1:9999<->l=127.0.0.1:29169) message received (res=12) (stream_srv.c:812)[0;m
[38;5;23m20251222125458110 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m r=127.0.0.1:9999<->l=127.0.0.1:29169 NOTIFICATION SHUTDOWN_EVENT flags=0x0 (stream.c:348)[0;m
20251222125458110 [1;34mDMAIN[0;m [1;34mDEBUG[0;m (127.0.0.1:9999 TTCN3-HNB-0) hnb_read_cb(): sctp_recvmsg() returned -11 (flags=0x80) (hnb.c:238)[0;m
20251222125458110 [1;33mDMAIN[0;m [1;33mNOTICE[0;m (127.0.0.1:9999 TTCN3-HNB-0) sctp_recvmsg(r=NULL<->l=127.0.0.1:29169) = SCTP_SHUTDOWN_EVENT, closing conn (hnb.c:269)[0;m
20251222125458110 [1;32mDMAIN[0;m [1;32mINFO[0;m (? TTCN3-HNB-0) connection closed (hnb.c:335)[0;m
20251222125458110 [1;32mDMAIN[0;m [1;32mINFO[0;m (disconnected TTCN3-HNB-0) Releasing HNB context (hnb.c:166)[0;m
[1;36m20251222125458110 [1;34mDHNB[0;m[1;36m [1;34mDEBUG[0;m[1;36m map_rua(127-0-0-1:9999-TTCN3-HNB-0-PS-RUA-4460376)[0x556305ab0cf0]{connected}: Received Event MAP_RUA_EV_HNB_LINK_LOST (context_map.c:185)[0;m
[1;36m20251222125458110 [1;34mDHNB[0;m[1;36m [1;34mDEBUG[0;m[1;36m map_rua(127-0-0-1:9999-TTCN3-HNB-0-PS-RUA-4460376)[0x556305ab0cf0]{connected}: State change to disrupted (X31, 5s) (context_map_rua.c:246)[0;m
[33m20251222125458110 [1;34mDCN[0;m[33m [1;34mDEBUG[0;m[33m map_sccp(127-0-0-1:9999-TTCN3-HNB-0-sgsn-0-SCCP-117)[0x556305a85490]{connected}: Received Event MAP_SCCP_EV_RAN_LINK_LOST (context_map_rua.c:267)[0;m
[38;5;45m20251222125458110 [1;34mDLSCCP[0;m[38;5;45m [1;34mDEBUG[0;m[38;5;45m 0: SCCP(rctx=0) SCU(cs7-0-sccp-OsmoHNBGW-RANAP:RI=SSN_PC,PC=0.23.5,SSN=RANAP) Received SCCP User Primitive (N-DISCONNECT.request) (sccp_sap.c:222)[0;m
[38;5;45m20251222125458110 [1;34mDLSCCP[0;m[38;5;45m [1;34mDEBUG[0;m[38;5;45m SCCP-SCOC(117)[0x556305a88ed0]{ACTIVE}: Received Event N-DISCONNECT.req (sccp_sap.c:263)[0;m
[38;5;45m20251222125458110 [1;34mDLSCCP[0;m[38;5;45m [1;34mDEBUG[0;m[38;5;45m 0: SCCP(rctx=0) sccp_scrc_rx_scoc_conn_msg: HDR=(CO:RELRE,V=0,LEN=0), PART(T=Routing Context,L=4,D=00000000), PART(T=Destination Reference,L=4,D=007b8ca7), PART(T=Source Reference,L=4,D=00000075), PART(T=SCCP Cause,L=4,D=00000300) (sccp_scrc.c:452)[0;m
[38;5;45m20251222125458110 [1;34mDLSCCP[0;m[38;5;45m [1;34mDEBUG[0;m[38;5;45m 0: SCCP(rctx=0) dpc_accessible(DPC=188=0.23.4): true (sccp_scrc.c:85)[0;m
[38;5;43m20251222125458110 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: CombinedLinkset(dpc=188=0.23.4,mask=0x3fff=7.255.7,prio=5) RT lookup: OPC=0=0.0.0,DPC=188=0.23.4,SLS=2 -> eSLS=50: use Normal Route via 'as-clnt-CN' (ss7_combined_linkset.c:275)[0;m
[38;5;43m20251222125458110 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: mtp3_hmdc_rx_from_l2(): dpc=188=0.23.4 not local, message is for routing (mtp3_hmdc.c:49)[0;m
[38;5;43m20251222125458110 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: CombinedLinkset(dpc=188=0.23.4,mask=0x3fff=7.255.7,prio=5) RT lookup: OPC=189=0.23.5,DPC=188=0.23.4,SLS=2 -> eSLS=18: use Normal Route via 'as-clnt-CN' (ss7_combined_linkset.c:275)[0;m
[38;5;43m20251222125458110 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: Found route for dpc=188=0.23.4: pc=188=0.23.4 mask=0x3fff=7.255.7 prio=5 dyn via AS as-clnt-CN proto=m3ua (mtp3_hmrt.c:80)[0;m
[38;5;43m20251222125458111 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: rt->dest.as proto is M3UA for dpc=188=0.23.4 (mtp3_hmrt.c:90)[0;m
[38;5;43m20251222125458111 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(as-clnt-CN)[0x556305a5ff00]{AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:563)[0;m
[38;5;43m20251222125458111 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(asp-clnt-CN-0) Tx 36 bytes: [L2]> 01 00 01 01 00 00 00 24 02 10 00 19 00 00 00 bd 00 00 00 bc 03 00 00 02 04 7b 8c a7 00 00 75 00 00 00 00 00 (ss7_asp.c:1400)[0;m
[38;5;45m20251222125458111 [1;34mDLSCCP[0;m[38;5;45m [1;34mDEBUG[0;m[38;5;45m SCCP-SCOC(117)[0x556305a88ed0]{ACTIVE}: State change to DISCONN_PEND (no timeout) (sccp_scoc_fsm.c:373)[0;m
[33m20251222125458111 [1;34mDCN[0;m[33m [1;34mDEBUG[0;m[33m map_sccp(127-0-0-1:9999-TTCN3-HNB-0-sgsn-0-SCCP-117)[0x556305a85490]{connected}: State change to disconnected (X31, 5s) (context_map_sccp.c:351)[0;m
[1;36m20251222125458111 [1;34mDHNB[0;m[1;36m [1;34mDEBUG[0;m[1;36m map_rua(127-0-0-1:9999-TTCN3-HNB-0-PS-RUA-4460376)[0x556305ab0cf0]{disrupted}: Terminating (cause = OSMO_FSM_TERM_REGULAR) (context_map.c:203)[0;m
[1;36m20251222125458111 [1;34mDHNB[0;m[1;36m [1;34mDEBUG[0;m[1;36m map_rua(127-0-0-1:9999-TTCN3-HNB-0-PS-RUA-4460376)[0x556305ab0cf0]{disrupted}: Freeing instance (context_map.c:203)[0;m
[1;36m20251222125458111 [1;34mDHNB[0;m[1;36m [1;34mDEBUG[0;m[1;36m map_rua(127-0-0-1:9999-TTCN3-HNB-0-PS-RUA-4460376)[0x556305ab0cf0]{disrupted}: Deallocated (fsm.c:568)[0;m
[33m20251222125458111 [1;34mDCN[0;m[33m [1;34mDEBUG[0;m[33m map_sccp(127-0-0-1:9999-TTCN3-HNB-0-sgsn-0-SCCP-117)[0x556305a85490]{disconnected}: Terminating (cause = OSMO_FSM_TERM_REGULAR) (context_map.c:207)[0;m
[33m20251222125458111 [1;34mDCN[0;m[33m [1;34mDEBUG[0;m[33m map_sccp(127-0-0-1:9999-TTCN3-HNB-0-sgsn-0-SCCP-117)[0x556305a85490]{disconnected}: Freeing instance (context_map.c:207)[0;m
[33m20251222125458111 [1;34mDCN[0;m[33m [1;34mDEBUG[0;m[33m map_sccp(127-0-0-1:9999-TTCN3-HNB-0-sgsn-0-SCCP-117)[0x556305a85490]{disconnected}: Deallocated (fsm.c:568)[0;m
20251222125458111 [1;32mDMAIN[0;m [1;32mINFO[0;m (disconnected TTCN3-HNB-0) RUA-4460376 SCCP-117 sgsn-0 MI=IMSI-262420000000003: Deallocating (context_map.c:226)[0;m
[1;36m20251222125458111 [1;34mDHNB[0;m[1;36m [1;34mDEBUG[0;m[1;36m map_rua(127-0-0-1:9999-TTCN3-HNB-0-PS-RUA-15062496)[0x556305a841e0]{connected}: Received Event MAP_RUA_EV_HNB_LINK_LOST (context_map.c:185)[0;m
[1;36m20251222125458111 [1;34mDHNB[0;m[1;36m [1;34mDEBUG[0;m[1;36m map_rua(127-0-0-1:9999-TTCN3-HNB-0-PS-RUA-15062496)[0x556305a841e0]{connected}: State change to disrupted (X31, 5s) (context_map_rua.c:246)[0;m
[33m20251222125458111 [1;34mDCN[0;m[33m [1;34mDEBUG[0;m[33m map_sccp(127-0-0-1:9999-TTCN3-HNB-0-sgsn-0-SCCP-119)[0x556305a89840]{connected}: Received Event MAP_SCCP_EV_RAN_LINK_LOST (context_map_rua.c:267)[0;m
[38;5;45m20251222125458111 [1;34mDLSCCP[0;m[38;5;45m [1;34mDEBUG[0;m[38;5;45m 0: SCCP(rctx=0) SCU(cs7-0-sccp-OsmoHNBGW-RANAP:RI=SSN_PC,PC=0.23.5,SSN=RANAP) Received SCCP User Primitive (N-DISCONNECT.request) (sccp_sap.c:222)[0;m
[38;5;45m20251222125458111 [1;34mDLSCCP[0;m[38;5;45m [1;34mDEBUG[0;m[38;5;45m SCCP-SCOC(119)[0x5563059717e0]{ACTIVE}: Received Event N-DISCONNECT.req (sccp_sap.c:263)[0;m
[38;5;45m20251222125458111 [1;34mDLSCCP[0;m[38;5;45m [1;34mDEBUG[0;m[38;5;45m 0: SCCP(rctx=0) sccp_scrc_rx_scoc_conn_msg: HDR=(CO:RELRE,V=0,LEN=0), PART(T=Routing Context,L=4,D=00000000), PART(T=Destination Reference,L=4,D=003dad6b), PART(T=Source Reference,L=4,D=00000077), PART(T=SCCP Cause,L=4,D=00000300) (sccp_scrc.c:452)[0;m
[38;5;45m20251222125458111 [1;34mDLSCCP[0;m[38;5;45m [1;34mDEBUG[0;m[38;5;45m 0: SCCP(rctx=0) dpc_accessible(DPC=188=0.23.4): true (sccp_scrc.c:85)[0;m
[38;5;43m20251222125458111 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: CombinedLinkset(dpc=188=0.23.4,mask=0x3fff=7.255.7,prio=5) RT lookup: OPC=0=0.0.0,DPC=188=0.23.4,SLS=0 -> eSLS=48: use Normal Route via 'as-clnt-CN' (ss7_combined_linkset.c:275)[0;m
[38;5;43m20251222125458111 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: mtp3_hmdc_rx_from_l2(): dpc=188=0.23.4 not local, message is for routing (mtp3_hmdc.c:49)[0;m
[38;5;43m20251222125458111 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: CombinedLinkset(dpc=188=0.23.4,mask=0x3fff=7.255.7,prio=5) RT lookup: OPC=189=0.23.5,DPC=188=0.23.4,SLS=0 -> eSLS=16: use Normal Route via 'as-clnt-CN' (ss7_combined_linkset.c:275)[0;m
[38;5;43m20251222125458111 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: Found route for dpc=188=0.23.4: pc=188=0.23.4 mask=0x3fff=7.255.7 prio=5 dyn via AS as-clnt-CN proto=m3ua (mtp3_hmrt.c:80)[0;m
[38;5;43m20251222125458111 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: rt->dest.as proto is M3UA for dpc=188=0.23.4 (mtp3_hmrt.c:90)[0;m
[38;5;43m20251222125458111 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(as-clnt-CN)[0x556305a5ff00]{AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:563)[0;m
[38;5;43m20251222125458111 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(asp-clnt-CN-0) Tx 36 bytes: [L2]> 01 00 01 01 00 00 00 24 02 10 00 19 00 00 00 bd 00 00 00 bc 03 00 00 00 04 3d ad 6b 00 00 77 00 00 00 00 00 (ss7_asp.c:1400)[0;m
[38;5;45m20251222125458111 [1;34mDLSCCP[0;m[38;5;45m [1;34mDEBUG[0;m[38;5;45m SCCP-SCOC(119)[0x5563059717e0]{ACTIVE}: State change to DISCONN_PEND (no timeout) (sccp_scoc_fsm.c:373)[0;m
[33m20251222125458111 [1;34mDCN[0;m[33m [1;34mDEBUG[0;m[33m map_sccp(127-0-0-1:9999-TTCN3-HNB-0-sgsn-0-SCCP-119)[0x556305a89840]{connected}: State change to disconnected (X31, 5s) (context_map_sccp.c:351)[0;m
[1;36m20251222125458111 [1;34mDHNB[0;m[1;36m [1;34mDEBUG[0;m[1;36m map_rua(127-0-0-1:9999-TTCN3-HNB-0-PS-RUA-15062496)[0x556305a841e0]{disrupted}: Terminating (cause = OSMO_FSM_TERM_REGULAR) (context_map.c:203)[0;m
[1;36m20251222125458111 [1;34mDHNB[0;m[1;36m [1;34mDEBUG[0;m[1;36m map_rua(127-0-0-1:9999-TTCN3-HNB-0-PS-RUA-15062496)[0x556305a841e0]{disrupted}: Freeing instance (context_map.c:203)[0;m
[1;36m20251222125458111 [1;34mDHNB[0;m[1;36m [1;34mDEBUG[0;m[1;36m map_rua(127-0-0-1:9999-TTCN3-HNB-0-PS-RUA-15062496)[0x556305a841e0]{disrupted}: Deallocated (fsm.c:568)[0;m
[33m20251222125458111 [1;34mDCN[0;m[33m [1;34mDEBUG[0;m[33m map_sccp(127-0-0-1:9999-TTCN3-HNB-0-sgsn-0-SCCP-119)[0x556305a89840]{disconnected}: Terminating (cause = OSMO_FSM_TERM_REGULAR) (context_map.c:207)[0;m
[33m20251222125458111 [1;34mDCN[0;m[33m [1;34mDEBUG[0;m[33m map_sccp(127-0-0-1:9999-TTCN3-HNB-0-sgsn-0-SCCP-119)[0x556305a89840]{disconnected}: Freeing instance (context_map.c:207)[0;m
[33m20251222125458111 [1;34mDCN[0;m[33m [1;34mDEBUG[0;m[33m map_sccp(127-0-0-1:9999-TTCN3-HNB-0-sgsn-0-SCCP-119)[0x556305a89840]{disconnected}: Deallocated (fsm.c:568)[0;m
20251222125458111 [1;32mDMAIN[0;m [1;32mINFO[0;m (disconnected TTCN3-HNB-0) RUA-15062496 SCCP-119 sgsn-0 MI=NONE: Deallocating (context_map.c:226)[0;m
[38;5;43m20251222125458112 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(asp-clnt-CN-0) xua_cli_read_cb(): sctp_recvmsg() returned 60 (flags=0x0) (ss7_asp.c:1308)[0;m
[38;5;49m20251222125458112 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(asp-clnt-CN-0) Received M3UA Message (SNM:DUNA) (m3ua.c:811)[0;m
[38;5;49m20251222125458112 [1;33mDLM3UA[0;m[38;5;49m [1;33mNOTICE[0;m[38;5;49m 0: ASP(asp-clnt-CN-0) Rx DUNA(transfer prohibited (inaccessible SP)) for 0.1.2/0 (xua_snm.c:501)[0;m
[38;5;43m20251222125458112 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: RT(dpc=10=0.1.2,mask=0x3fff=7.255.7,prio=5,via=as-clnt-CN,st=unavailable) changed to status 'unavailable' (ss7_route.c:409)[0;m
[38;5;43m20251222125458112 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ss7_user(SCCP) Delivering to MTP User: MTP-PAUSE.indication (mtp_sap.c:204)[0;m
[38;5;45m20251222125458112 [1;34mDLSCCP[0;m[38;5;45m [1;34mDEBUG[0;m[38;5;45m 0: SCCP(rctx=0) SCU(SCCP Management:RI=SSN_PC,PC=(no PC),SSN=MGMT) Delivering to SCCP User: N-PCSTATE.indication (sccp_user.c:121)[0;m
[38;5;45m20251222125458112 [1;34mDLSCCP[0;m[38;5;45m [1;34mDEBUG[0;m[38;5;45m 0: SCCP(rctx=0) SCU(SCCP Management:RI=SSN_PC,PC=(no PC),SSN=MGMT) Ignoring SCCP user primitive N-PCSTATE.indication (sccp_scmg.c:318)[0;m
[38;5;45m20251222125458112 [1;34mDLSCCP[0;m[38;5;45m [1;34mDEBUG[0;m[38;5;45m 0: SCCP(rctx=0) SCU(cs7-0-sccp-OsmoHNBGW-RANAP:RI=SSN_PC,PC=0.23.5,SSN=RANAP) Delivering to SCCP User: N-PCSTATE.indication (sccp_user.c:121)[0;m
[33m20251222125458112 [1;34mDCN[0;m[33m [1;34mDEBUG[0;m[33m sccp_sap_up(N-PCSTATE.indication) (hnbgw_sccp.c:331)[0;m
[33m20251222125458112 [1;34mDCN[0;m[33m [1;34mDEBUG[0;m[33m N-PCSTATE ind: affected_pc=10=0.1.2 sp_status=INACCESSIBLE remote_sccp_status=UNAVAILABLE_UNKNOWN (hnbgw_sccp.c:237)[0;m
[38;5;43m20251222125458112 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(asp-clnt-CN-0) xua_cli_read_cb(): sctp_recvmsg() returned 16 (flags=0x0) (ss7_asp.c:1308)[0;m
[38;5;49m20251222125458112 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(asp-clnt-CN-0) Received M3UA Message (SNM:DUNA) (m3ua.c:811)[0;m
[38;5;49m20251222125458112 [1;33mDLM3UA[0;m[38;5;49m [1;33mNOTICE[0;m[38;5;49m 0: ASP(asp-clnt-CN-0) Rx DUNA() for 0.23.4/0 (xua_snm.c:501)[0;m
[38;5;43m20251222125458112 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: RT(dpc=188=0.23.4,mask=0x3fff=7.255.7,prio=5,via=as-clnt-CN,st=available) changed to status 'unavailable' (ss7_route.c:409)[0;m
[38;5;43m20251222125458112 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ss7_user(SCCP) Delivering to MTP User: MTP-PAUSE.indication (mtp_sap.c:204)[0;m
[38;5;45m20251222125458112 [1;34mDLSCCP[0;m[38;5;45m [1;34mDEBUG[0;m[38;5;45m 0: SCCP(rctx=0) SCU(SCCP Management:RI=SSN_PC,PC=(no PC),SSN=MGMT) Delivering to SCCP User: N-PCSTATE.indication (sccp_user.c:121)[0;m
[38;5;45m20251222125458112 [1;34mDLSCCP[0;m[38;5;45m [1;34mDEBUG[0;m[38;5;45m 0: SCCP(rctx=0) SCU(SCCP Management:RI=SSN_PC,PC=(no PC),SSN=MGMT) Ignoring SCCP user primitive N-PCSTATE.indication (sccp_scmg.c:318)[0;m
[38;5;45m20251222125458112 [1;34mDLSCCP[0;m[38;5;45m [1;34mDEBUG[0;m[38;5;45m 0: SCCP(rctx=0) SCU(cs7-0-sccp-OsmoHNBGW-RANAP:RI=SSN_PC,PC=0.23.5,SSN=RANAP) Delivering to SCCP User: N-PCSTATE.indication (sccp_user.c:121)[0;m
[33m20251222125458112 [1;34mDCN[0;m[33m [1;34mDEBUG[0;m[33m sccp_sap_up(N-PCSTATE.indication) (hnbgw_sccp.c:331)[0;m
[33m20251222125458112 [1;34mDCN[0;m[33m [1;34mDEBUG[0;m[33m N-PCSTATE ind: affected_pc=188=0.23.4 sp_status=INACCESSIBLE remote_sccp_status=UNAVAILABLE_UNKNOWN (hnbgw_sccp.c:237)[0;m
[33m20251222125458112 [1;33mDCN[0;m[33m [1;33mNOTICE[0;m[33m (sgsn-0) now unreachable: N-PCSTATE ind: pc=188=0.23.4 sp_status=INACCESSIBLE remote_sccp_status=UNAVAILABLE_UNKNOWN (hnbgw_sccp.c:303)[0;m
20251222125458112 [1;34mDRANAP[0;m [1;34mDEBUG[0;m cnlink(sgsn-0)[0x556305a6d450]{CONNECTED}: State change to DISCONNECTED (T4, 60s) (cnlink_fsm.c:203)[0;m
20251222125458112 [1;33mDRANAP[0;m [1;33mNOTICE[0;m cnlink(sgsn-0)[0x556305a6d450]{DISCONNECTED}: link lost (cnlink_fsm.c:73)[0;m
20251222125458112 [1;32mDLGLOBAL[0;m [1;32mINFO[0;m Closing telnet connection r=127.0.0.1:49132<->l=127.0.0.1:4261 (telnet_interface.c:138)
TC_sgsnpool_L3Complete_no_nri_round_robin0(994)@50b239c1fc8c: Final verdict of PTC: pass
[38;5;43m20251222125458115 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(hnbgw0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1107)[0;m
[38;5;49m20251222125458115 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(hnbgw0-0) Received M3UA Message (XFER:DATA) (m3ua.c:811)[0;m
[38;5;49m20251222125458115 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(hnbgw0-0) m3ua_rx_xfer (m3ua.c:601)[0;m
[38;5;49m20251222125458115 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(hnbgw0-0) m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=188=0.23.4 sls=2 (m3ua.c:632)[0;m
[38;5;43m20251222125458115 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: mtp3_hmdc_rx_from_l2(): dpc=188=0.23.4 not local, message is for routing (mtp3_hmdc.c:49)[0;m
[38;5;43m20251222125458115 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0: CombinedLinkset(dpc=188=0.23.4,mask=0x3fff=7.255.7,prio=5) RT Lookup: OPC=189=0.23.5,DPC=188=0.23.4,SLS=2 -> eSLS=18: Normal Route via 'virt-sgsn0' unavailable, all Alternative Routes unavailable (ss7_combined_linkset.c:322)[0;m
[38;5;43m20251222125458115 [1;31mDLSS7[0;m[38;5;43m [1;31mERROR[0;m[38;5;43m 0: MTP-TRANSFER.req for dpc=188=0.23.4: no route! (mtp3_hmrt.c:113)[0;m
[38;5;43m20251222125458115 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: T8: SP 188=0.23.4: Start (ss7_instance.c:879)[0;m
20251222125458116 [1;32mDLGLOBAL[0;m [1;32mINFO[0;m Closing telnet connection r=127.0.0.1:49124<->l=127.0.0.1:4261 (telnet_interface.c:138)
HNBGW-MGCP(992)@50b239c1fc8c: Final verdict of PTC: none
[38;5;43m20251222125458116 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: CombinedLinkset(dpc=189=0.23.5,mask=0x3fff=7.255.7,prio=5) RT lookup: OPC=188=0.23.4,DPC=189=0.23.5,SLS=2 -> eSLS=18: use Normal Route via 'hnbgw0' (ss7_combined_linkset.c:275)[0;m
[38;5;43m20251222125458116 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0: Message received for inaccessible SP 188=0.23.4. Tx TFP (DUNA) to concerned SP 189=0.23.5 (mtp3_rtpc.c:120)[0;m
[38;5;43m20251222125458116 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(hnbgw0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:563)[0;m
[38;5;43m20251222125458118 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(asp-clnt-CN-0) xua_cli_read_cb(): sctp_recvmsg() returned 60 (flags=0x0) (ss7_asp.c:1308)[0;m
[38;5;49m20251222125458118 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(asp-clnt-CN-0) Received M3UA Message (SNM:DUNA) (m3ua.c:811)[0;m
[38;5;49m20251222125458118 [1;33mDLM3UA[0;m[38;5;49m [1;33mNOTICE[0;m[38;5;49m 0: ASP(asp-clnt-CN-0) Rx DUNA(transfer prohibited (inaccessible SP)) for 0.23.4/0 (xua_snm.c:501)[0;m
[38;5;43m20251222125458118 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: RT(dpc=188=0.23.4,mask=0x3fff=7.255.7,prio=5,via=as-clnt-CN,st=unavailable) changed to status 'unavailable' (ss7_route.c:409)[0;m
[38;5;43m20251222125458118 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ss7_user(SCCP) Delivering to MTP User: MTP-PAUSE.indication (mtp_sap.c:204)[0;m
[38;5;45m20251222125458118 [1;34mDLSCCP[0;m[38;5;45m [1;34mDEBUG[0;m[38;5;45m 0: SCCP(rctx=0) SCU(SCCP Management:RI=SSN_PC,PC=(no PC),SSN=MGMT) Delivering to SCCP User: N-PCSTATE.indication (sccp_user.c:121)[0;m
[38;5;45m20251222125458118 [1;34mDLSCCP[0;m[38;5;45m [1;34mDEBUG[0;m[38;5;45m 0: SCCP(rctx=0) SCU(SCCP Management:RI=SSN_PC,PC=(no PC),SSN=MGMT) Ignoring SCCP user primitive N-PCSTATE.indication (sccp_scmg.c:318)[0;m
[38;5;45m20251222125458118 [1;34mDLSCCP[0;m[38;5;45m [1;34mDEBUG[0;m[38;5;45m 0: SCCP(rctx=0) SCU(cs7-0-sccp-OsmoHNBGW-RANAP:RI=SSN_PC,PC=0.23.5,SSN=RANAP) Delivering to SCCP User: N-PCSTATE.indication (sccp_user.c:121)[0;m
[33m20251222125458118 [1;34mDCN[0;m[33m [1;34mDEBUG[0;m[33m sccp_sap_up(N-PCSTATE.indication) (hnbgw_sccp.c:331)[0;m
[33m20251222125458118 [1;34mDCN[0;m[33m [1;34mDEBUG[0;m[33m N-PCSTATE ind: affected_pc=188=0.23.4 sp_status=INACCESSIBLE remote_sccp_status=UNAVAILABLE_UNKNOWN (hnbgw_sccp.c:237)[0;m
TC_sgsnpool_L3Complete_no_nri_round_robin0(993)@50b239c1fc8c: Final verdict of PTC: pass
[38;5;43m20251222125458116 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(hnbgw0-0) Tx 60 bytes: [L2]> 01 00 02 01 00 00 00 3c 00 12 00 08 00 00 00 bc 00 04 00 2a 74 72 61 6e 73 66 65 72 20 70 72 6f 68 69 62 69 74 65 64 20 28 69 6e 61 63 63 65 73 73 69 62 6c 65 20 53 50 29 00 00 00 (ss7_asp.c:1400)[0;m
[38;5;43m20251222125458116 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(hnbgw0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1107)[0;m
[38;5;49m20251222125458117 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(hnbgw0-0) Received M3UA Message (XFER:DATA) (m3ua.c:811)[0;m
[38;5;49m20251222125458117 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(hnbgw0-0) m3ua_rx_xfer (m3ua.c:601)[0;m
[38;5;49m20251222125458117 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(hnbgw0-0) m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=188=0.23.4 sls=0 (m3ua.c:632)[0;m
[38;5;43m20251222125458117 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: mtp3_hmdc_rx_from_l2(): dpc=188=0.23.4 not local, message is for routing (mtp3_hmdc.c:49)[0;m
[38;5;43m20251222125458117 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0: CombinedLinkset(dpc=188=0.23.4,mask=0x3fff=7.255.7,prio=5) RT Lookup: OPC=189=0.23.5,DPC=188=0.23.4,SLS=0 -> eSLS=16: Normal Route via 'virt-sgsn0' unavailable, all Alternative Routes unavailable (ss7_combined_linkset.c:322)[0;m
[38;5;43m20251222125458117 [1;31mDLSS7[0;m[38;5;43m [1;31mERROR[0;m[38;5;43m 0: MTP-TRANSFER.req for dpc=188=0.23.4: no route! (mtp3_hmrt.c:113)[0;m
[38;5;43m20251222125458117 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: Tx TFP (DUNA) inaccessible SP 188=0.23.4 to concerned SP 189=0.23.5: inhibit due to T8 (mtp3_rtpc.c:74)[0;m
HNBGW_Test.msc0-M3UA(981)@50b239c1fc8c: Final verdict of PTC: none
20251222125458124 [1;32mDLGLOBAL[0;m [1;32mINFO[0;m Closing telnet connection r=127.0.0.1:49142<->l=127.0.0.1:4261 (telnet_interface.c:138)
TC_sgsnpool_L3Complete_no_nri_round_robin0(995)@50b239c1fc8c: Final verdict of PTC: pass
MTC@50b239c1fc8c: Setting final verdict of the test case.
MTC@50b239c1fc8c: Local verdict of MTC: pass
MTC@50b239c1fc8c: Local verdict of PTC MutexDispCT-TC_sgsnpool_L3Complete_no_nri_round_robin(972): none (pass -> pass)
MTC@50b239c1fc8c: Local verdict of PTC VirtHNBGW-STATS(973): none (pass -> pass)
MTC@50b239c1fc8c: Local verdict of PTC IPA-CTRL-CLI-IPA(974): none (pass -> pass)
MTC@50b239c1fc8c: Local verdict of PTC HNBGW-PFCP(975): none (pass -> pass)
MTC@50b239c1fc8c: Local verdict of PTC TC_sgsnpool_L3Complete_no_nri_round_robin-Iuh0(976): none (pass -> pass)
MTC@50b239c1fc8c: Local verdict of PTC TC_sgsnpool_L3Complete_no_nri_round_robin-Iuh0-RUA(977): none (pass -> pass)
MTC@50b239c1fc8c: Local verdict of PTC TC_sgsnpool_L3Complete_no_nri_round_robin-Iuh1(978): none (pass -> pass)
MTC@50b239c1fc8c: Local verdict of PTC TC_sgsnpool_L3Complete_no_nri_round_robin-Iuh1-RUA(979): none (pass -> pass)
MTC@50b239c1fc8c: Local verdict of PTC HNBGW_Test.msc0-SCCP(980): none (pass -> pass)
MTC@50b239c1fc8c: Local verdict of PTC HNBGW_Test.msc0-M3UA(981): none (pass -> pass)
MTC@50b239c1fc8c: Local verdict of PTC HNBGW_Test.msc0-RAN(982): none (pass -> pass)
MTC@50b239c1fc8c: Local verdict of PTC HNBGW_Test.msc1-SCCP(983): none (pass -> pass)
MTC@50b239c1fc8c: Local verdict of PTC HNBGW_Test.msc1-M3UA(984): none (pass -> pass)
MTC@50b239c1fc8c: Local verdict of PTC HNBGW_Test.msc1-RAN(985): none (pass -> pass)
MTC@50b239c1fc8c: Local verdict of PTC HNBGW_Test.sgsn0-SCCP(986): none (pass -> pass)
MTC@50b239c1fc8c: Local verdict of PTC HNBGW_Test.sgsn0-M3UA(987): none (pass -> pass)
MTC@50b239c1fc8c: Local verdict of PTC HNBGW_Test.sgsn0-RAN(988): none (pass -> pass)
MTC@50b239c1fc8c: Local verdict of PTC HNBGW_Test.sgsn1-SCCP(989): none (pass -> pass)
MTC@50b239c1fc8c: Local verdict of PTC HNBGW_Test.sgsn1-M3UA(990): none (pass -> pass)
MTC@50b239c1fc8c: Local verdict of PTC HNBGW_Test.sgsn1-RAN(991): none (pass -> pass)
MTC@50b239c1fc8c: Local verdict of PTC HNBGW-MGCP(992): none (pass -> pass)
MTC@50b239c1fc8c: Local verdict of PTC TC_sgsnpool_L3Complete_no_nri_round_robin0(993): pass (pass -> pass)
MTC@50b239c1fc8c: Local verdict of PTC TC_sgsnpool_L3Complete_no_nri_round_robin0(994): pass (pass -> pass)
MTC@50b239c1fc8c: Local verdict of PTC TC_sgsnpool_L3Complete_no_nri_round_robin0(995): pass (pass -> pass)
MTC@50b239c1fc8c: Test case TC_sgsnpool_L3Complete_no_nri_round_robin finished. Verdict: pass
MTC@50b239c1fc8c: Starting external command `/home/osmocom-build/jenkins/workspace/ttcn3-hnbgw-test-latest/ttcn3-tcpdump-stop.sh HNBGW_Tests.TC_sgsnpool_L3Complete_no_nri_round_robin pass'.
Mon Dec 22 12:54:58 UTC 2025
[1;32m====== HNBGW_Tests.TC_sgsnpool_L3Complete_no_nri_round_robin pass ======[0m
Saving talloc report from 127.0.0.1:4261 to HNBGW_Tests.TC_sgsnpool_L3Complete_no_nri_round_robin.talloc
20251222125458229 [1;32mDLGLOBAL[0;m [1;32mINFO[0;m Accept()ed new telnet connection r=127.0.0.1:49156<->l=127.0.0.1:4261 (telnet_interface.c:192)
FATAL: command execution failed
java.lang.OutOfMemoryError: Java heap space
Caused: java.io.IOException: Unexpected reader termination
at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:98)
Caused: java.io.IOException: Backing channel 'build4-deb12build-ansible' is disconnected.
at hudson.remoting.RemoteInvocationHandler.channelOrFail(RemoteInvocationHandler.java:227)
at hudson.remoting.RemoteInvocationHandler.invoke(RemoteInvocationHandler.java:306)
at jdk.proxy2/jdk.proxy2.$Proxy156.isAlive(Unknown Source)
at hudson.Launcher$RemoteLauncher$ProcImpl.isAlive(Launcher.java:1212)
at hudson.Launcher$RemoteLauncher$ProcImpl.join(Launcher.java:1204)
at hudson.tasks.CommandInterpreter.join(CommandInterpreter.java:195)
at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:145)
at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:92)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:818)
at hudson.model.Build$BuildExecution.build(Build.java:199)
at hudson.model.Build$BuildExecution.doRun(Build.java:164)
at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:527)
at hudson.model.Run.execute(Run.java:1833)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:44)
at hudson.model.ResourceController.execute(ResourceController.java:101)
at hudson.model.Executor.run(Executor.java:446)
FATAL: Unable to delete script file /tmp/jenkins8870542166860956989.sh
java.lang.OutOfMemoryError: Java heap space
Caused: java.io.IOException: Unexpected reader termination
at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:98)
Caused: hudson.remoting.ChannelClosedException: Channel "hudson.remoting.Channel@51452f67:build4-deb12build-ansible": Remote call on build4-deb12build-ansible failed. The channel is closing down or has closed down
at hudson.remoting.Channel.call(Channel.java:1105)
at hudson.FilePath.act(FilePath.java:1207)
at hudson.FilePath.act(FilePath.java:1196)
at hudson.FilePath.delete(FilePath.java:1743)
at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:163)
at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:92)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:818)
at hudson.model.Build$BuildExecution.build(Build.java:199)
at hudson.model.Build$BuildExecution.doRun(Build.java:164)
at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:527)
at hudson.model.Run.execute(Run.java:1833)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:44)
at hudson.model.ResourceController.execute(ResourceController.java:101)
at hudson.model.Executor.run(Executor.java:446)
Build step 'Execute shell' marked build as failure
ERROR: Step ‘Publish JUnit test result report’ failed: no workspace for ttcn3-hnbgw-test-latest #1288
1
1
Build failed in Jenkins: update-osmo-ci-on-slaves » rpi4-raspbian12build-ansible-1 #3197
by jenkins＠lists.osmocom.org 23 Dec '25
by jenkins＠lists.osmocom.org 23 Dec '25
23 Dec '25
See <https://jenkins.osmocom.org/jenkins/job/update-osmo-ci-on-slaves/label=rpi4…>
Changes:
------------------------------------------
[...truncated 12.55 KiB...]
---> Using cache
---> 76e5951902fd
Step 12/16 : RUN set -x && git clone --depth=1 https://gerrit.osmocom.org/python/osmo-python-tests osmo-python-tests && cd osmo-python-tests && python3 setup.py clean build install && cd .. && rm -rf osmo-python-tests
---> Using cache
---> d3a5c7d32d3b
Step 13/16 : ADD https://gerrit.osmocom.org/plugins/gitiles/osmo-ci/+/master?format=TEXT /tmp/osmo-ci-commit
---> Using cache
---> b6591f1db2f0
Step 14/16 : RUN set -x && git clone --depth=1 https://gerrit.osmocom.org/osmo-ci osmo-ci && su build -c "cd osmo-ci/scripts && cp -v *.sh *.py /usr/local/bin" && rm -rf osmo-ci
---> Using cache
---> 6be571e37d1a
Step 15/16 : ADD $OSMOCOM_REPO/Release /tmp/Release
---> e7be9f2b88d3
Step 16/16 : RUN set -x && echo "deb [signed-by=/etc/apt/trusted.gpg.d/obs.osmocom.org.asc] $OSMOCOM_REPO ./" > /etc/apt/sources.list.d/osmocom-nightly.list && apt-get update && apt-get install -y --no-install-recommends liblimesuite-dev libuhd-dev && apt-get clean
---> Running in d1fbf6a053b0
[91m+ echo deb [signed-by=/etc/apt/trusted.gpg.d/obs.osmocom.org.asc] https://downloads.osmocom.org/packages/osmocom:/nightly/Debian_12/ ./
[0m[91m+ apt-get update
[0mHit:1 http://deb.debian.org/debian bookworm InRelease
Get:2 http://deb.debian.org/debian bookworm-updates InRelease [55.4 kB]
Get:3 http://deb.debian.org/debian-security bookworm-security InRelease [48.0 kB]
Get:4 https://downloads.osmocom.org/packages/osmocom:/nightly/Debian_12 ./ InRelease [1620 B]
Get:5 http://deb.debian.org/debian-security bookworm-security/main armhf Packages [270 kB]
Get:6 https://downloads.osmocom.org/packages/osmocom:/nightly/Debian_12 ./ Packages [207 kB]
Fetched 582 kB in 2s (296 kB/s)
Reading package lists...
[91m+ apt-get install -y --no-install-recommends liblimesuite-dev libuhd-dev
[0mReading package lists...
Building dependency tree...
Reading state information...
The following additional packages will be installed:
liblimesuite22.09-1 libpython3.11 libuhd4.3.0
Suggested packages:
liblimesuite-doc uhd-doc uhd-host
Recommended packages:
limesuite-udev gnuradio-dev
The following NEW packages will be installed:
liblimesuite-dev liblimesuite22.09-1 libpython3.11 libuhd-dev libuhd4.3.0
0 upgraded, 5 newly installed, 0 to remove and 0 not upgraded.
Need to get 5117 kB of archives.
After this operation, 15.8 MB of additional disk space will be used.
Get:1 http://deb.debian.org/debian bookworm/main armhf liblimesuite22.09-1 armhf 22.09.1+dfsg-1+b2 [222 kB]
Get:2 http://deb.debian.org/debian bookworm/main armhf liblimesuite-dev armhf 22.09.1+dfsg-1+b2 [55.8 kB]
Get:3 http://deb.debian.org/debian bookworm/main armhf libpython3.11 armhf 3.11.2-6+deb12u6 [1709 kB]
Get:4 http://deb.debian.org/debian bookworm/main armhf libuhd4.3.0 armhf 4.3.0.0+ds1-5 [2912 kB]
Get:5 http://deb.debian.org/debian bookworm/main armhf libuhd-dev armhf 4.3.0.0+ds1-5 [219 kB]
[91mdebconf: delaying package configuration, since apt-utils is not installed
[0mFetched 5117 kB in 3s (1506 kB/s)
Selecting previously unselected package liblimesuite22.09-1:armhf.
(Reading database ... (Reading database ... 5%(Reading database ... 10%(Reading database ... 15%(Reading database ... 20%(Reading database ... 25%(Reading database ... 30%(Reading database ... 35%(Reading database ... 40%(Reading database ... 45%(Reading database ... 50%(Reading database ... 55%(Reading database ... 60%(Reading database ... 65%(Reading database ... 70%(Reading database ... 75%(Reading database ... 80%(Reading database ... 85%(Reading database ... 90%(Reading database ... 95%(Reading database ... 100%(Reading database ... 32158 files and directories currently installed.)
Preparing to unpack .../liblimesuite22.09-1_22.09.1+dfsg-1+b2_armhf.deb ...
Unpacking liblimesuite22.09-1:armhf (22.09.1+dfsg-1+b2) ...
Selecting previously unselected package liblimesuite-dev:armhf.
Preparing to unpack .../liblimesuite-dev_22.09.1+dfsg-1+b2_armhf.deb ...
Unpacking liblimesuite-dev:armhf (22.09.1+dfsg-1+b2) ...
Selecting previously unselected package libpython3.11:armhf.
Preparing to unpack .../libpython3.11_3.11.2-6+deb12u6_armhf.deb ...
Unpacking libpython3.11:armhf (3.11.2-6+deb12u6) ...
Selecting previously unselected package libuhd4.3.0:armhf.
Preparing to unpack .../libuhd4.3.0_4.3.0.0+ds1-5_armhf.deb ...
Unpacking libuhd4.3.0:armhf (4.3.0.0+ds1-5) ...
Selecting previously unselected package libuhd-dev:armhf.
Preparing to unpack .../libuhd-dev_4.3.0.0+ds1-5_armhf.deb ...
Unpacking libuhd-dev:armhf (4.3.0.0+ds1-5) ...
Setting up libpython3.11:armhf (3.11.2-6+deb12u6) ...
Setting up libuhd4.3.0:armhf (4.3.0.0+ds1-5) ...
Setting up liblimesuite22.09-1:armhf (22.09.1+dfsg-1+b2) ...
Setting up libuhd-dev:armhf (4.3.0.0+ds1-5) ...
Setting up liblimesuite-dev:armhf (22.09.1+dfsg-1+b2) ...
Processing triggers for libc-bin (2.36-9+deb12u13) ...
[91m+ apt-get clean
[0mRemoving intermediate container d1fbf6a053b0
---> 800ab78d7125
[Warning] One or more build-args [OSMO_SIP_BRANCH USER OPEN5GS_BRANCH OSMO_GGSN_BRANCH OSMO_HNBGW_BRANCH OSMO_IUH_BRANCH OSMO_PCU_BRANCH OSMO_SGSN_BRANCH OSMO_STP_BRANCH OSMOCOM_REPO_TESTSUITE_MIRROR OSMO_HNODEB_BRANCH OSMO_NITB_BRANCH PJPROJECT_BRANCH M3UA_TESTTOOL_BRANCH OSMOCOM_REPO_VERSION OSMO_BB_BRANCH OSMO_S1GW_BRANCH OSMO_UECUPS_BRANCH OSMO_BSC_BRANCH OSMO_BTS_BRANCH OSMO_MSC_BRANCH OSMO_REMSIM_BRANCH OSMO_TTCN3_BRANCH ASTERISK_BRANCH LIBOSMOCORE_BRANCH OSMO_CBC_BRANCH OSMO_DIA2GSUP_BRANCH OSMO_GSM_TESTER_BRANCH OSMO_HLR_BRANCH DISTRO OSMO_GBPROXY_BRANCH OSMO_MGW_BRANCH OSMO_EPDG_BRANCH] were not consumed
Successfully built 800ab78d7125
Successfully tagged osmocom-build/debian-bookworm-build-arm:latest
rm -rf .common
make: Leaving directory '<https://jenkins.osmocom.org/jenkins/job/update-osmo-ci-on-slaves/label=rpi4…'>
+ docker_image_exists debian-bookworm-build-arm
+ docker images -q osmocom-build/debian-bookworm-build-arm
+ test -n 800ab78d7125
+ list_osmo_packages debian-bookworm debian-bookworm-build-arm
+ local distro=debian-bookworm
+ local image=debian-bookworm-build-arm
+ local docker_run_sh=docker run --rm --entrypoint=/bin/sh osmocom-build/debian-bookworm-build-arm -c
+ [ -n ]
+ return
+ [ = registry.osmocom.org ]
+ docker_depends debian-trixie-build-arm
+ local feed
+ depends=
+ [ -n ]
+ docker_distro_from_image_name debian-trixie-build-arm
+ echo debian-trixie
+ distro_arg=debian-trixie
+ [ -z ]
+ docker_upstream_distro_from_image_name debian-trixie-build-arm
+ echo debian:trixie
+ upstream_distro_arg=debian:trixie
+ docker_dir_from_image_name debian-trixie-build-arm
+ echo debian-trixie-build-arm
+ dir=debian-trixie-build-arm
+ [ -z 1 ]
+ set +x
Building image: debian-trixie-build-arm (export NO_DOCKER_IMAGE_BUILD=1 to prevent this)
+ docker_osmo_ttcn3_branch
+ [ -n ]
+ echo master
+ make -C ../debian-trixie-build-arm BUILD_ARGS= UPSTREAM_DISTRO=debian:trixie DISTRO=debian-trixie IMAGE=osmocom-build/debian-trixie-build-arm OSMO_TTCN3_BRANCH=master
make: Entering directory '<https://jenkins.osmocom.org/jenkins/job/update-osmo-ci-on-slaves/label=rpi4…'>
awk: cannot open .release (No such file or directory)
awk: cannot open .release (No such file or directory)
awk: cannot open .release (No such file or directory)
rm -rf .common
cp -r <https://jenkins.osmocom.org/jenkins/job/update-osmo-ci-on-slaves/label=rpi4…> .common
INFO: .release created
release=0.0.0
docker build \
--build-arg USER=osmocom-build \
--build-arg UID=1000 \
--build-arg REGISTRY=docker.io \
--build-arg OSMO_TTCN3_BRANCH=master \
--build-arg UPSTREAM_DISTRO=debian:trixie \
--build-arg DISTRO=debian-trixie \
--build-arg OSMOCOM_REPO_MIRROR=https://downloads.osmocom.org \
--build-arg OSMOCOM_REPO_PATH=packages/osmocom: \
--build-arg OSMOCOM_REPO_VERSION=latest \
--build-arg OSMOCOM_REPO_TESTSUITE_MIRROR=https://downloads.osmocom.org \
--build-arg ASTERISK_BRANCH=sysmocom/20.7.0 \
--build-arg LIBOSMOCORE_BRANCH=master \
--build-arg M3UA_TESTTOOL_BRANCH=osmocom/master \
--build-arg OSMO_BB_BRANCH=master \
--build-arg OSMO_BSC_BRANCH=master \
--build-arg OSMO_BTS_BRANCH=master \
--build-arg OSMO_CBC_BRANCH=master \
--build-arg OSMO_DIA2GSUP_BRANCH=master \
--build-arg OSMO_EPDG_BRANCH=master \
--build-arg OSMO_GBPROXY_BRANCH=master \
--build-arg OSMO_GGSN_BRANCH=master \
--build-arg OSMO_GSM_TESTER_BRANCH=master \
--build-arg OSMO_HLR_BRANCH=master \
--build-arg OSMO_HNBGW_BRANCH=master \
--build-arg OSMO_HNODEB_BRANCH=master \
--build-arg OSMO_IUH_BRANCH=master \
--build-arg OSMO_MGW_BRANCH=master \
--build-arg OSMO_MSC_BRANCH=master \
--build-arg OSMO_NITB_BRANCH=master \
--build-arg OSMO_PCU_BRANCH=master \
--build-arg OSMO_REMSIM_BRANCH=master \
--build-arg OSMO_S1GW_BRANCH=master \
--build-arg OSMO_SGSN_BRANCH=master \
--build-arg OSMO_SIP_BRANCH=master \
--build-arg OSMO_STP_BRANCH=master \
--build-arg OSMO_UECUPS_BRANCH=master \
--build-arg OPEN5GS_BRANCH=main \
--build-arg PJPROJECT_BRANCH=sysmocom/2.14 \
-t osmocom-build/debian-trixie-build-arm:latest .
Sending build context to Docker daemon 37.89kB
Step 1/16 : ARG REGISTRY=docker.io
Step 2/16 : ARG UPSTREAM_DISTRO=debian:trixie
Step 3/16 : FROM ${REGISTRY}/arm32v7/${UPSTREAM_DISTRO}
---> 458df24d5ca1
Step 4/16 : ARG OSMOCOM_REPO_MIRROR="https://downloads.osmocom.org"
---> Using cache
---> e645b9979391
Step 5/16 : ARG OSMOCOM_REPO_PATH="packages/osmocom:"
---> Using cache
---> 97b305b7e08f
Step 6/16 : ARG OSMOCOM_REPO="${OSMOCOM_REPO_MIRROR}/${OSMOCOM_REPO_PATH}/nightly/Debian_13/"
---> Using cache
---> 8d86f5443406
Step 7/16 : ARG UID
---> Using cache
---> c3bef72dc2da
Step 8/16 : COPY .common/Release.key /etc/apt/trusted.gpg.d/obs.osmocom.org.asc
---> Using cache
---> b5bc42f7462e
Step 9/16 : RUN set -x && useradd --uid=${UID} -d /build -m build && chown -R build:build /usr/local && echo "path-exclude=/usr/share/man/*" > /etc/dpkg/dpkg.cfg.d/exclude-man-pages && rm -rf /usr/share/man/
---> Using cache
---> 962bea47dce4
Step 10/16 : RUN set -x && apt-get update && apt-get install -y --no-install-recommends autoconf automake bzip2 ca-certificates ccache g++ gcc git libbladerf-dev libboost-dev libboost-filesystem-dev libboost-program-options-dev libboost-thread-dev libfftw3-dev libgnutls28-dev liblimesuite-dev libmnl-dev libortp-dev libpcsclite-dev libsctp-dev libtalloc-dev libtool libuhd-dev liburing-dev libusb-1.0-0-dev make pkg-config python3-minimal python3-setuptools stow && apt-get clean
---> Using cache
---> f15cd082d96d
Step 11/16 : ADD https://gerrit.osmocom.org/plugins/gitiles/python/osmo-python-tests/+/maste… /tmp/osmo-python-tests-commit
---> Using cache
---> c6b8a3d2af5b
Step 12/16 : RUN set -x && git clone --depth=1 https://gerrit.osmocom.org/python/osmo-python-tests osmo-python-tests && cd osmo-python-tests && python3 setup.py clean build install && cd .. && rm -rf osmo-python-tests
---> Using cache
---> 4c0c5105eb1e
Step 13/16 : ADD https://gerrit.osmocom.org/plugins/gitiles/osmo-ci/+/master?format=TEXT /tmp/osmo-ci-commit
---> Using cache
---> 959887850414
Step 14/16 : RUN set -x && git clone --depth=1 https://gerrit.osmocom.org/osmo-ci osmo-ci && su build -c "cd osmo-ci/scripts && cp -v *.sh *.py /usr/local/bin" && rm -rf osmo-ci
---> Using cache
---> 30210545af9e
Step 15/16 : ADD $OSMOCOM_REPO/Release /tmp/Release
---> c01779409743
Step 16/16 : RUN set -x && echo "deb [signed-by=/etc/apt/trusted.gpg.d/obs.osmocom.org.asc] $OSMOCOM_REPO ./" > /etc/apt/sources.list.d/osmocom-nightly.list && apt-get update && apt-get install -y --no-install-recommends -t'o=obs://osmocom/osmocom:nightly/Debian_13,n=Debian_13,l=osmocom:nightly,c=' sdcc && apt-get clean
---> Running in 0721498e0bbc
[91m+ echo deb [signed-by=/etc/apt/trusted.gpg.d/obs.osmocom.org.asc] https://downloads.osmocom.org/packages/osmocom:/nightly/Debian_13/ ./
+ apt-get update
[0mHit:1 http://deb.debian.org/debian trixie InRelease
Get:2 http://deb.debian.org/debian trixie-updates InRelease [47.3 kB]
Get:3 http://deb.debian.org/debian-security trixie-security InRelease [43.4 kB]
Ign:4 https://downloads.osmocom.org/packages/osmocom:/nightly/Debian_13 ./ InRelease
Get:5 http://deb.debian.org/debian-security trixie-security/main armhf Packages [87.9 kB]
Get:4 https://downloads.osmocom.org/packages/osmocom:/nightly/Debian_13 ./ InRelease [1620 B]
Get:6 https://downloads.osmocom.org/packages/osmocom:/nightly/Debian_13 ./ Packages [204 kB]
Fetched 385 kB in 2s (228 kB/s)
Reading package lists...
[91m+ apt-get install -y --no-install-recommends -to=obs://osmocom/osmocom:nightly/Debian_13,n=Debian_13,l=osmocom:nightly,c= sdcc
[0mReading package lists...
Building dependency tree...
Reading state information...
The following additional packages will be installed:
sdcc-libraries
Suggested packages:
python sdcc-ucsim
Recommended packages:
gputils
The following NEW packages will be installed:
sdcc sdcc-libraries
0 upgraded, 2 newly installed, 0 to remove and 0 not upgraded.
Need to get 3622 kB of archives.
After this operation, 26.2 MB of additional disk space will be used.
Ign:1 https://downloads.osmocom.org/packages/osmocom:/nightly/Debian_13 ./ sdcc-libraries 4.2.0~osmocom3.113.9edd
Ign:2 https://downloads.osmocom.org/packages/osmocom:/nightly/Debian_13 ./ sdcc 4.2.0~osmocom3.113.9edd
Ign:2 https://downloads.osmocom.org/packages/osmocom:/nightly/Debian_13 ./ sdcc 4.2.0~osmocom3.113.9edd
Ign:1 https://downloads.osmocom.org/packages/osmocom:/nightly/Debian_13 ./ sdcc-libraries 4.2.0~osmocom3.113.9edd
Ign:1 https://downloads.osmocom.org/packages/osmocom:/nightly/Debian_13 ./ sdcc-libraries 4.2.0~osmocom3.113.9edd
Ign:2 https://downloads.osmocom.org/packages/osmocom:/nightly/Debian_13 ./ sdcc 4.2.0~osmocom3.113.9edd
Err:2 https://downloads.osmocom.org/packages/osmocom:/nightly/Debian_13 ./ sdcc 4.2.0~osmocom3.113.9edd
SSL connection failed: error:0A000126:SSL routines::unexpected eof while reading / Success [IP: 148.251.236.141 443]
Get:1 https://downloads.osmocom.org/packages/osmocom:/nightly/Debian_13 ./ sdcc-libraries 4.2.0~osmocom3.113.9edd [1584 kB]
Fetched 1584 kB in 8s (195 kB/s)
[91mE: Failed to fetch https://downloads.osmocom.org/packages/osmocom:/nightly/Debian_13/./armhf/s… SSL connection failed: error:0A000126:SSL routines::unexpected eof while reading / Success [IP: 148.251.236.141 443]
E: Unable to fetch some archives, maybe run apt-get update or try with --fix-missing?
[0mThe command '/bin/sh -c set -x && echo "deb [signed-by=/etc/apt/trusted.gpg.d/obs.osmocom.org.asc] $OSMOCOM_REPO ./" > /etc/apt/sources.list.d/osmocom-nightly.list && apt-get update && apt-get install -y --no-install-recommends -t'o=obs://osmocom/osmocom:nightly/Debian_13,n=Debian_13,l=osmocom:nightly,c=' sdcc && apt-get clean' returned a non-zero code: 100
make: *** [../make/Makefile:97: docker-build] Error 100
make: Leaving directory '<https://jenkins.osmocom.org/jenkins/job/update-osmo-ci-on-slaves/label=rpi4…'>
+ exit 1
Build step 'Execute shell' marked build as failure
1
1