December 2025 - jenkins-notifications

Build failed in Jenkins: Osmocom_OBS_master #124264
by jenkins＠lists.osmocom.org 22 Dec '25

22 Dec '25

See <https://jenkins.osmocom.org/jenkins/job/Osmocom_OBS_master/124264/display/r…> Changes: ------------------------------------------ Started by upstream project "master-libtelnet" build number 2906 originally caused by: 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 <https://jenkins.osmocom.org/jenkins/job/Osmocom_OBS_master/ws/> The recommended git tool is: NONE No credentials specified Wiping out workspace first. Cloning the remote Git repository Cloning repository https://gerrit.osmocom.org/osmo-ci > git init <https://jenkins.osmocom.org/jenkins/job/Osmocom_OBS_master/ws/> # timeout=10 Fetching upstream changes from https://gerrit.osmocom.org/osmo-ci > git --version # timeout=10 > git --version # 'git version 2.39.5' > git fetch --tags --force --progress -- https://gerrit.osmocom.org/osmo-ci +refs/heads/*:refs/remotes/origin/* # timeout=10 > git config remote.origin.url https://gerrit.osmocom.org/osmo-ci # timeout=10 > git config --add remote.origin.fetch +refs/heads/*:refs/remotes/origin/* # timeout=10 Avoid second fetch > git rev-parse origin/master^{commit} # timeout=10 Checking out Revision bb15a95e3843864b853e6d1747b82b08556a1923 (origin/master) > git config core.sparsecheckout # timeout=10 > git checkout -f bb15a95e3843864b853e6d1747b82b08556a1923 # timeout=10 Commit message: "jobs/ttcn3-testsuites: set RUN_BPFTRACE=1" > git rev-list --no-walk bb15a95e3843864b853e6d1747b82b08556a1923 # timeout=10 [Osmocom_OBS_master] $ /bin/sh -xe /tmp/jenkins12382932782483857171.sh + export PYTHONUNBUFFERED=1 + ./scripts/obs/update_obs_project.py --apiurl https://obs.osmocom.org --conflict-version --docker --feed master --meta osmocom:master docker: building image debian-13-osmocom-obs-build-srcpkg + run_in_docker_and_exit(): ['docker', 'build', '--build-arg', 'DISTRO=debian:13', '--build-arg', 'DISTRO_FROM=debian:trixie', '--build-arg', 'UID=1000', '-t', 'debian-13-osmocom-obs-build-srcpkg', '-f', '<https://jenkins.osmocom.org/jenkins/job/Osmocom_OBS_master/ws/scripts/obs/d…',> '<https://jenkins.osmocom.org/jenkins/job/Osmocom_OBS_master/ws/scripts/obs/d…']> #0 building with "default" instance using docker driver #1 [internal] load build definition from build_srcpkg.Dockerfile #1 transferring dockerfile: 1.45kB done #1 WARN: InvalidDefaultArgInFrom: Default value for ARG ${DISTRO_FROM} results in empty or invalid base image name (line 3) #1 DONE 0.1s #2 [internal] load metadata for docker.io/library/debian:trixie #2 DONE 0.0s #3 [internal] load .dockerignore #3 transferring context: 2B done #3 DONE 0.1s #4 [1/4] FROM docker.io/library/debian:trixie #4 DONE 0.0s #5 [2/4] RUN APT-GET update && apt-get upgrade -y && apt-get install -y --no-install-recommends ca-certificates colordiff debhelper default-libmysqlclient-dev dh-python dh-virtualenv dpkg-dev erlang-nox fakeroot git git-review gnupg2 libxml2-utils lsb-release meson osc pkgconf python3-packaging python3-pip python3-setuptools quilt sed sphinx-common wget && apt-get clean #5 CACHED #6 [3/4] RUN WGET https://github.com/erlang/rebar3/releases/download/3.24.0/rebar3 -O /usr/bin/rebar3 && echo "d2d31cfb98904b8e4917300a75f870de12cb5167cd6214d1043e973a56668a54 /usr/bin/rebar3" | sha256sum -c && chmod +x /usr/bin/rebar3 && rebar3 --version #6 CACHED #7 [4/4] RUN USERADD --uid=1000 -m user #7 CACHED #8 exporting to image #8 exporting layers done #8 writing image sha256:b43e1fe626fd8170bdf303dce63cfb33848878bcba79f8bbf7edf9034c5430aa 0.0s done #8 naming to docker.io/library/debian-13-osmocom-obs-build-srcpkg 0.0s done #8 DONE 0.1s [33m1 warning found (use docker --debug to expand): [0m - InvalidDefaultArgInFrom: Default value for ARG ${DISTRO_FROM} results in empty or invalid base image name (line 3) docker: running: update_obs_project.py inside docker /obs/lib/debian.py:183: SyntaxWarning: invalid escape sequence '\/' lib.run_cmd(["sed", "-i", "s/$(MAKE) -C doc\/manuals//g", f"{debian}/rules"]) OBS: getting packages in osmocom:master ERROR: command failed unexpectedly *** command *** ['osc', '-A', 'https://obs.osmocom.org', 'list', 'osmocom:master'] *** returncode *** 1 *** output *** fatal: not a git repository (or any of the parent directories): .git SSL Error: [SSL: UNEXPECTED_EOF_WHILE_READING] EOF occurred in violation of protocol (_ssl.c:1029) *** python trace *** Traceback (most recent call last): File "/obs/update_obs_project.py", line 265, in <module> main() ~~~~^^ File "/obs/update_obs_project.py", line 257, in main pkgs_remote = lib.osc.get_remote_pkgs() File "/obs/lib/osc.py", line 63, in get_remote_pkgs ret = run_osc(["list", proj]) File "/obs/lib/osc.py", line 58, in run_osc return lib.run_cmd(cmd, *args, **kwargs) ~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^ File "/obs/lib/__init__.py", line 202, in run_cmd exit_error_cmd(p, "command failed unexpectedly") ~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/obs/lib/__init__.py", line 163, in exit_error_cmd raise RuntimeError("shell command related error, find details right above" " this python trace") RuntimeError: shell command related error, find details right above this python trace Build step 'Execute shell' marked build as failure

1 1

Build failed in Jenkins: Osmocom_OBS_check_builders #27685
by jenkins＠lists.osmocom.org 22 Dec '25

22 Dec '25

See <https://jenkins.osmocom.org/jenkins/job/Osmocom_OBS_check_builders/27685/di…> 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/Osmocom_OBS_check_builders The recommended git tool is: NONE No credentials specified Wiping out workspace first. Cloning the remote Git repository Cloning repository https://gerrit.osmocom.org/osmo-ci 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 PluginClassLoader for git-client//org.jenkinsci.plugins.gitclient.RemoteGitImpl$CommandInvocationHandler.execute(RemoteGitImpl.java:153) at jdk.internal.reflect.GeneratedMethodAccessor816.invoke(Unknown Source) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) at java.base/java.lang.reflect.Method.invoke(Unknown Source) at PluginClassLoader for git-client//org.jenkinsci.plugins.gitclient.RemoteGitImpl$CommandInvocationHandler.invoke(RemoteGitImpl.java:138) at PluginClassLoader for git-client/jdk.proxy68/jdk.proxy68.$Proxy186.execute(Unknown Source) at PluginClassLoader for git//hudson.plugins.git.GitSCM.retrieveChanges(GitSCM.java:1220) 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)

1 1

Build failed in Jenkins: ttcn3-cbc-test-asan #564
by jenkins＠lists.osmocom.org 22 Dec '25

22 Dec '25

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-cbc-test-asan/564/display/red…> Changes: [Pau Espin Pedrol] library/NGAP_Templates: PDUSessionResourceSetupRequest: Fix criticality ------------------------------------------ [...truncated 544.49 KiB...] [1;32m20251222165858403 [1;33mDSBcAP[0;m[1;32m [1;33mNOTICE[0;m[1;32m New SBc-AP link connection from 127.0.0.100:16000 (sbcap_link.c:326)[0;m [1;32m20251222165858403 [1;34mDSBcAP[0;m[1;32m [1;34mDEBUG[0;m[1;32m SBcAP-Link(ttcn3-mme-client)[0x5120000213a0]{INIT}: Allocated (fsm.c:456)[0;m [1;32m20251222165858403 [1;34mDSBcAP[0;m[1;32m [1;34mDEBUG[0;m[1;32m SBcAP-Link(ttcn3-mme-client)[0x5120000213a0]{INIT}: Received Event RESET.cmd (sbcap_link.c:382)[0;m [1;32m20251222165858403 [1;34mDSBcAP[0;m[1;32m [1;34mDEBUG[0;m[1;32m SBcAP-Link(ttcn3-mme-client)[0x5120000213a0]{INIT}: State change to IDLE (no timeout) (sbcap_link_fsm.c:56)[0;m MTC@062ebec5dfd3: TX POST CBS: "{\"cbe_name\":\"TTCN-3\",\"category\":\"normal\",\"repetition_period\":5,\"num_of_bcast\":3,\"scope\":{\"scope_plmn\":{}},\"smscb_message\":{\"serial_nr\":{\"serial_nr_encoded\":16752},\"message_id\":48,\"payload\":{\"payload_encoded\":{\"dcs\":1,\"pages\":[\"00\"]}}}}" [1;33m20251222165858405 [1;34mDREST[0;m[1;33m [1;34mDEBUG[0;m[1;33m /message POST: {"cbe_name": "TTCN-3", "category": "normal", "repetition_period": 5, "num_of_bcast": 3, "scope": {"scope_plmn": {}}, "smscb_message": {"serial_nr": {"serial_nr_encoded": 16752}, "message_id": 48, "payload": {"payload_encoded": {"dcs": 1, "pages": ["00"]}}}} (rest_api.c:571)[0;m [1;33m20251222165858405 [1;34mDREST[0;m[1;33m [1;34mDEBUG[0;m[1;33m sending as inter-thread op (rest_api.c:578)[0;m [1;33m20251222165858405 [1;34mDREST[0;m[1;33m [1;34mDEBUG[0;m[1;33m rest_it_op enqueue from 139914702182080 (rest_it_op.c:57)[0;m [1;33m20251222165858405 [1;34mDREST[0;m[1;33m [1;34mDEBUG[0;m[1;33m rest_it_op wait.... (rest_it_op.c:66)[0;m [1;33m20251222165858405 [1;34mDREST[0;m[1;33m [1;34mDEBUG[0;m[1;33m rest2main_read_cb(op=0x51b00002d1e0) from 139914788649536 (rest_it_op.c:93)[0;m [1;35m20251222165858405 [1;32mDSMSCB[0;m[1;35m [1;32mINFO[0;m[1;35m SMSCB(TTCN-3-48)[0x512000021520]{INIT}: Allocated (fsm.c:456)[0;m [1;31m20251222165858405 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-48)[0x5120000216a0]{INIT}: Allocated (fsm.c:456)[0;m [1;31m20251222165858405 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-48)[0x5120000216a0]{INIT}: is child of SMSCB(TTCN-3-48)[0x512000021520] (fsm.c:486)[0;m [1;31m20251222165858405 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-48)[0x512000021820]{INIT}: Allocated (fsm.c:456)[0;m [1;31m20251222165858405 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-48)[0x512000021820]{INIT}: is child of SMSCB(TTCN-3-48)[0x512000021520] (fsm.c:486)[0;m [1;32m20251222165858405 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-48)[0x5120000219a0]{INIT}: Allocated (fsm.c:456)[0;m [1;32m20251222165858405 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-48)[0x5120000219a0]{INIT}: is child of SMSCB(TTCN-3-48)[0x512000021520] (fsm.c:486)[0;m [1;32m20251222165858405 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-48)[0x512000021b20]{INIT}: Allocated (fsm.c:456)[0;m [1;32m20251222165858405 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-48)[0x512000021b20]{INIT}: is child of SMSCB(TTCN-3-48)[0x512000021520] (fsm.c:486)[0;m [1;35m20251222165858405 [1;32mDSMSCB[0;m[1;35m [1;32mINFO[0;m[1;35m SMSCB(TTCN-3-48)[0x512000021520]{INIT}: Received Event CREATE (cbc_message.c:132)[0;m [1;35m20251222165858405 [1;32mDSMSCB[0;m[1;35m [1;32mINFO[0;m[1;35m SMSCB(TTCN-3-48)[0x512000021520]{INIT}: State change to WAIT_WRITE_ACK (T0, 15s) (smscb_message_fsm.c:67)[0;m [1;32m20251222165858405 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-48-ttcn3-mme-server)[0x512000021b20]{INIT}: Received Event CREATE (smscb_message_fsm.c:69)[0;m [1;32m20251222165858405 [1;33mDSBcAP[0;m[1;32m [1;33mNOTICE[0;m[1;32m ttcn3-mme-server: Cannot transmit msg Write-Replace-Warning-Request: reconnecting (sbcap_link.c:402)[0;m [1;32m20251222165858405 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-48-ttcn3-mme-server)[0x512000021b20]{INIT}: State change to WAIT_WRITE_ACK (T0, 10s) (sbcap_smscb_peer_fsm.c:119)[0;m [1;32m20251222165858405 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-48-ttcn3-mme-server)[0x512000021b20]{WAIT_WRITE_ACK}: State change to ACTIVE (no timeout) (sbcap_smscb_peer_fsm.c:230)[0;m [1;35m20251222165858405 [1;32mDSMSCB[0;m[1;35m [1;32mINFO[0;m[1;35m SMSCB(TTCN-3-48)[0x512000021520]{WAIT_WRITE_ACK}: Received Event WRITE_NACK (sbcap_smscb_peer_fsm.c:231)[0;m [1;32m20251222165858405 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-48-ttcn3-mme-client)[0x5120000219a0]{INIT}: Received Event CREATE (smscb_message_fsm.c:69)[0;m [1;32m20251222165858405 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m ttcn3-mme-client: Tx msg Write-Replace-Warning-Request (sbcap_link.c:408)[0;m [1;32m20251222165858405 [1;34mDSBcAP[0;m[1;32m [1;34mDEBUG[0;m[1;32m ttcn3-mme-client: Encoded message Write-Replace-Warning-Request: 00 00 00 80 84 00 00 08 00 05 00 02 00 30 00 0b 00 02 41 70 00 0a 00 02 00 05 00 07 00 02 00 03 00 03 40 01 01 00 10 40 56 00 53 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 01 00 14 00 01 00 00 18 40 01 00 (sbcap_link.c:416)[0;m [1;32m20251222165858405 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-48-ttcn3-mme-client)[0x5120000219a0]{INIT}: State change to WAIT_WRITE_ACK (T0, 10s) (sbcap_smscb_peer_fsm.c:119)[0;m [1;31m20251222165858405 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-48-ttcn3-bsc-server)[0x512000021820]{INIT}: Received Event CREATE (smscb_message_fsm.c:69)[0;m [1;31m20251222165858405 [1;33mDCBSP[0;m[1;31m [1;33mNOTICE[0;m[1;31m [ttcn3-bsc-server] Tx CBSP: not connected (cbc_message.c:64)[0;m [1;31m20251222165858405 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-48-ttcn3-bsc-server)[0x512000021820]{INIT}: State change to WAIT_WRITE_ACK (T0, 10s) (cbsp_smscb_peer_fsm.c:275)[0;m [1;31m20251222165858405 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-48-ttcn3-bsc-server)[0x512000021820]{WAIT_WRITE_ACK}: State change to ACTIVE (no timeout) (cbsp_smscb_peer_fsm.c:439)[0;m [1;35m20251222165858405 [1;32mDSMSCB[0;m[1;35m [1;32mINFO[0;m[1;35m SMSCB(TTCN-3-48)[0x512000021520]{WAIT_WRITE_ACK}: Received Event WRITE_ACK (cbsp_smscb_peer_fsm.c:440)[0;m [1;31m20251222165858405 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-48-ttcn3-bsc-client)[0x5120000216a0]{INIT}: Received Event CREATE (smscb_message_fsm.c:69)[0;m [1;31m20251222165858405 [1;33mDCBSP[0;m[1;31m [1;33mNOTICE[0;m[1;31m [ttcn3-bsc-client] Tx CBSP: not connected (cbc_message.c:64)[0;m [1;31m20251222165858405 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-48-ttcn3-bsc-client)[0x5120000216a0]{INIT}: State change to WAIT_WRITE_ACK (T0, 10s) (cbsp_smscb_peer_fsm.c:275)[0;m [1;31m20251222165858405 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-48-ttcn3-bsc-client)[0x5120000216a0]{WAIT_WRITE_ACK}: State change to ACTIVE (no timeout) (cbsp_smscb_peer_fsm.c:439)[0;m [1;35m20251222165858405 [1;32mDSMSCB[0;m[1;35m [1;32mINFO[0;m[1;35m SMSCB(TTCN-3-48)[0x512000021520]{WAIT_WRITE_ACK}: Received Event WRITE_ACK (cbsp_smscb_peer_fsm.c:440)[0;m TC_cell_failure_restart_active_mme-MME0-MME0(48)@062ebec5dfd3: received expected req:{ connId := 1, remName := "127.0.0.20", remPort := 29168, locName := "127.0.0.100", locPort := 16000, msg := { initiatingMessage := { procedureCode := 0, criticality := reject (0), value_ := { write_Replace_Warning_Request := { protocolIEs := { { id := 5, criticality := reject (0), value_ := { message_Identifier := '0000000000110000'B } }, { id := 11, criticality := reject (0), value_ := { serial_Number := '0100000101110000'B } }, { id := 10, criticality := reject (0), value_ := { repetition_Period := 5 } }, { id := 7, criticality := reject (0), value_ := { number_of_Broadcasts_Requested := 3 } }, { id := 3, criticality := ignore (1), value_ := { data_Coding_Scheme := '00000001'B } }, { id := 16, criticality := ignore (1), value_ := { warning_Message_Content := '010000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000001'O } }, { id := 20, criticality := reject (0), value_ := { concurrent_Warning_Message_Indicator := true_ (0) } }, { id := 24, criticality := ignore (1), value_ := { send_Write_Replace_Warning_Indication := true_ (0) } } }, protocolExtensions := omit } } } } } [1;32m20251222165858407 [1;34mDSBcAP[0;m[1;32m [1;34mDEBUG[0;m[1;32m ttcn3-mme-client: sbcap_cbc_srv_read_cb(): sctp_recvmsg() returned 24 (flags=0x80) (sbcap_link.c:246)[0;m [1;32m20251222165858407 [1;34mDSBcAP[0;m[1;32m [1;34mDEBUG[0;m[1;32m ttcn3-mme-client: Rx SBc-AP 20 00 00 14 00 00 03 00 05 00 02 00 30 00 0b 00 02 41 70 00 01 00 01 00 (sbcap_link.c:281)[0;m [1;32m20251222165858407 [1;34mDSBcAP[0;m[1;32m [1;34mDEBUG[0;m[1;32m Decoded Write-Replace-Warning-Response (sbcap_common.c:118)[0;m [1;32m20251222165858407 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m ttcn3-mme-client: Rx SBc-AP Write-Replace-Warning-Response (sbcap_link.c:286)[0;m [1;32m20251222165858407 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m ttcn3-mme-client: SBcAP SuccessfulOutcome procedure=0 (sbcap_link_fsm.c:318)[0;m [1;32m20251222165858407 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-48-ttcn3-mme-client)[0x5120000219a0]{WAIT_WRITE_ACK}: Received Event SBcAP_WRITE_ACK (sbcap_link_fsm.c:269)[0;m [1;32m20251222165858407 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-48-ttcn3-mme-client)[0x5120000219a0]{WAIT_WRITE_ACK}: State change to ACTIVE (no timeout) (sbcap_smscb_peer_fsm.c:147)[0;m [1;35m20251222165858407 [1;32mDSMSCB[0;m[1;35m [1;32mINFO[0;m[1;35m SMSCB(TTCN-3-48)[0x512000021520]{WAIT_WRITE_ACK}: Received Event WRITE_ACK (sbcap_smscb_peer_fsm.c:149)[0;m [1;33m20251222165858407 [1;34mDREST[0;m[1;33m [1;34mDEBUG[0;m[1;33m rest_it_op_complete(op=0x51b00002d1e0) complete (rest_it_op.c:131)[0;m [1;35m20251222165858407 [1;32mDSMSCB[0;m[1;35m [1;32mINFO[0;m[1;35m SMSCB(TTCN-3-48)[0x512000021520]{WAIT_WRITE_ACK}: State change to ACTIVE (no timeout) (smscb_message_fsm.c:91)[0;m [1;33m20251222165858407 [1;34mDREST[0;m[1;33m [1;34mDEBUG[0;m[1;33m rest_it_op completed with 0 (HTTP 201) (rest_it_op.c:70)[0;m [1;33m20251222165858407 [1;34mDREST[0;m[1;33m [1;34mDEBUG[0;m[1;33m /message POST -> 201 (Created) (rest_api.c:588)[0;m MTC@062ebec5dfd3: setverdict(pass): none -> pass [1;32m20251222165858408 [1;34mDSBcAP[0;m[1;32m [1;34mDEBUG[0;m[1;32m ttcn3-mme-client: sbcap_cbc_srv_read_cb(): sctp_recvmsg() returned 34 (flags=0x80) (sbcap_link.c:246)[0;m [1;32m20251222165858408 [1;34mDSBcAP[0;m[1;32m [1;34mDEBUG[0;m[1;32m ttcn3-mme-client: Rx SBc-AP 00 03 40 1e 00 00 03 00 05 00 02 00 30 00 0b 00 02 41 70 00 17 00 0b 40 00 00 00 09 f1 07 00 00 4d 20 (sbcap_link.c:281)[0;m [1;32m20251222165858408 [1;34mDSBcAP[0;m[1;32m [1;34mDEBUG[0;m[1;32m Decoded Write-Replace-Warning-Indication (sbcap_common.c:118)[0;m [1;32m20251222165858408 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m ttcn3-mme-client: Rx SBc-AP Write-Replace-Warning-Indication (sbcap_link.c:286)[0;m [1;32m20251222165858408 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-48-ttcn3-mme-client)[0x5120000219a0]{ACTIVE}: Received Event SBcAP_WRITE_IND (sbcap_link_fsm.c:366)[0;m [1;32m20251222165858408 [1;34mDSBcAP[0;m[1;32m [1;34mDEBUG[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-48-ttcn3-mme-client)[0x5120000219a0]{ACTIVE}: Appending CellId ECGI 901-70-00004-D2 to Broadcast Completed list (sbcap_smscb_peer_fsm.c:74)[0;m [1;32m20251222165858610 [1;34mDSBcAP[0;m[1;32m [1;34mDEBUG[0;m[1;32m ttcn3-mme-client: sbcap_cbc_srv_read_cb(): sctp_recvmsg() returned 32 (flags=0x80) (sbcap_link.c:246)[0;m [1;32m20251222165858610 [1;34mDSBcAP[0;m[1;32m [1;34mDEBUG[0;m[1;32m ttcn3-mme-client: Rx SBc-AP 00 06 00 1c 00 00 02 00 21 00 09 00 00 09 f1 07 00 00 4d 20 00 1c 00 08 00 09 f1 07 00 00 05 a0 (sbcap_link.c:281)[0;m [1;32m20251222165858611 [1;34mDSBcAP[0;m[1;32m [1;34mDEBUG[0;m[1;32m Decoded PWS-Failure-Indication (sbcap_common.c:118)[0;m [1;32m20251222165858611 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m ttcn3-mme-client: Rx SBc-AP PWS-Failure-Indication (sbcap_link.c:286)[0;m [1;32m20251222165858611 [1;33mDSBcAP[0;m[1;32m [1;33mNOTICE[0;m[1;32m ttcn3-mme-client: Rx PWS-Failure-Indication not implemented yet (sbcap_link_fsm.c:295)[0;m [1;32m20251222165859413 [1;34mDSBcAP[0;m[1;32m [1;34mDEBUG[0;m[1;32m ttcn3-mme-client: sbcap_cbc_srv_read_cb(): sctp_recvmsg() returned 32 (flags=0x80) (sbcap_link.c:246)[0;m [1;32m20251222165859413 [1;34mDSBcAP[0;m[1;32m [1;34mDEBUG[0;m[1;32m ttcn3-mme-client: Rx SBc-AP 00 05 00 1c 00 00 02 00 1e 00 09 00 00 09 f1 07 00 00 4d 20 00 1c 00 08 00 09 f1 07 00 00 05 a0 (sbcap_link.c:281)[0;m [1;32m20251222165859413 [1;34mDSBcAP[0;m[1;32m [1;34mDEBUG[0;m[1;32m Decoded PWS-Restart-Indication (sbcap_common.c:118)[0;m [1;32m20251222165859413 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m ttcn3-mme-client: Rx SBc-AP PWS-Restart-Indication (sbcap_link.c:286)[0;m [1;32m20251222165859413 [1;34mDSBcAP[0;m[1;32m [1;34mDEBUG[0;m[1;32m SBcAP-Link(ttcn3-mme-client)[0x5120000213a0]{IDLE}: Received Event Rx Restart (sbcap_link_fsm.c:291)[0;m [1;32m20251222165859413 [1;33mDSBcAP[0;m[1;32m [1;33mNOTICE[0;m[1;32m ttcn3-mme-client: Rx PWS Restart Indication not implemented yet (sbcap_link_fsm.c:84)[0;m [38;5;23m20251222165900668 [1;31mDLINP[0;m[38;5;23m [1;31mERROR[0;m[38;5;23m CLICONN(,r=127.0.0.100:16001<->l=127.0.0.20:52637){CONNECTING} connect so_error (111) (stream_cli.c:429)[0;m [38;5;23m20251222165900668 [1;32mDLINP[0;m[38;5;23m [1;32mINFO[0;m[38;5;23m CLICONN(,r=127.0.0.100:16001<->l=127.0.0.20:52637){WAIT_RECONNECT} retrying reconnect in 5 seconds... (stream_cli.c:244)[0;m [38;5;23m20251222165905670 [1;31mDLINP[0;m[38;5;23m [1;31mERROR[0;m[38;5;23m CLICONN(,r=127.0.0.100:16001<->l=127.0.0.20:52637){CONNECTING} connect so_error (111) (stream_cli.c:429)[0;m [38;5;23m20251222165905670 [1;32mDLINP[0;m[38;5;23m [1;32mINFO[0;m[38;5;23m CLICONN(,r=127.0.0.100:16001<->l=127.0.0.20:52637){WAIT_RECONNECT} retrying reconnect in 5 seconds... (stream_cli.c:244)[0;m [38;5;23m20251222165910673 [1;31mDLINP[0;m[38;5;23m [1;31mERROR[0;m[38;5;23m CLICONN(,r=127.0.0.100:16001<->l=127.0.0.20:52637){CONNECTING} connect so_error (111) (stream_cli.c:429)[0;m [38;5;23m20251222165910673 [1;32mDLINP[0;m[38;5;23m [1;32mINFO[0;m[38;5;23m CLICONN(,r=127.0.0.100:16001<->l=127.0.0.20:52637){WAIT_RECONNECT} retrying reconnect in 5 seconds... (stream_cli.c:244)[0;m [38;5;23m20251222165915674 [1;31mDLINP[0;m[38;5;23m [1;31mERROR[0;m[38;5;23m CLICONN(,r=127.0.0.100:16001<->l=127.0.0.20:52637){CONNECTING} connect so_error (111) (stream_cli.c:429)[0;m [38;5;23m20251222165915674 [1;32mDLINP[0;m[38;5;23m [1;32mINFO[0;m[38;5;23m CLICONN(,r=127.0.0.100:16001<->l=127.0.0.20:52637){WAIT_RECONNECT} retrying reconnect in 5 seconds... (stream_cli.c:244)[0;m [38;5;23m20251222165920678 [1;31mDLINP[0;m[38;5;23m [1;31mERROR[0;m[38;5;23m CLICONN(,r=127.0.0.100:16001<->l=127.0.0.20:52637){CONNECTING} connect so_error (111) (stream_cli.c:429)[0;m [38;5;23m20251222165920678 [1;32mDLINP[0;m[38;5;23m [1;32mINFO[0;m[38;5;23m CLICONN(,r=127.0.0.100:16001<->l=127.0.0.20:52637){WAIT_RECONNECT} retrying reconnect in 5 seconds... (stream_cli.c:244)[0;m [38;5;23m20251222165925681 [1;31mDLINP[0;m[38;5;23m [1;31mERROR[0;m[38;5;23m CLICONN(,r=127.0.0.100:16001<->l=127.0.0.20:52637){CONNECTING} connect so_error (111) (stream_cli.c:429)[0;m [38;5;23m20251222165925681 [1;32mDLINP[0;m[38;5;23m [1;32mINFO[0;m[38;5;23m CLICONN(,r=127.0.0.100:16001<->l=127.0.0.20:52637){WAIT_RECONNECT} retrying reconnect in 5 seconds... (stream_cli.c:244)[0;m [38;5;23m20251222165930683 [1;31mDLINP[0;m[38;5;23m [1;31mERROR[0;m[38;5;23m CLICONN(,r=127.0.0.100:16001<->l=127.0.0.20:52637){CONNECTING} connect so_error (111) (stream_cli.c:429)[0;m [38;5;23m20251222165930683 [1;32mDLINP[0;m[38;5;23m [1;32mINFO[0;m[38;5;23m CLICONN(,r=127.0.0.100:16001<->l=127.0.0.20:52637){WAIT_RECONNECT} retrying reconnect in 5 seconds... (stream_cli.c:244)[0;m [38;5;23m20251222165935686 [1;31mDLINP[0;m[38;5;23m [1;31mERROR[0;m[38;5;23m CLICONN(,r=127.0.0.100:16001<->l=127.0.0.20:52637){CONNECTING} connect so_error (111) (stream_cli.c:429)[0;m [38;5;23m20251222165935686 [1;32mDLINP[0;m[38;5;23m [1;32mINFO[0;m[38;5;23m CLICONN(,r=127.0.0.100:16001<->l=127.0.0.20:52637){WAIT_RECONNECT} retrying reconnect in 5 seconds... (stream_cli.c:244)[0;m [38;5;23m20251222165940690 [1;31mDLINP[0;m[38;5;23m [1;31mERROR[0;m[38;5;23m CLICONN(,r=127.0.0.100:16001<->l=127.0.0.20:52637){CONNECTING} connect so_error (111) (stream_cli.c:429)[0;m [38;5;23m20251222165940690 [1;32mDLINP[0;m[38;5;23m [1;32mINFO[0;m[38;5;23m CLICONN(,r=127.0.0.100:16001<->l=127.0.0.20:52637){WAIT_RECONNECT} retrying reconnect in 5 seconds... (stream_cli.c:244)[0;m [38;5;23m20251222165945693 [1;31mDLINP[0;m[38;5;23m [1;31mERROR[0;m[38;5;23m CLICONN(,r=127.0.0.100:16001<->l=127.0.0.20:52637){CONNECTING} connect so_error (111) (stream_cli.c:429)[0;m [38;5;23m20251222165945693 [1;32mDLINP[0;m[38;5;23m [1;32mINFO[0;m[38;5;23m CLICONN(,r=127.0.0.100:16001<->l=127.0.0.20:52637){WAIT_RECONNECT} retrying reconnect in 5 seconds... (stream_cli.c:244)[0;m [38;5;23m20251222165950694 [1;31mDLINP[0;m[38;5;23m [1;31mERROR[0;m[38;5;23m CLICONN(,r=127.0.0.100:16001<->l=127.0.0.20:52637){CONNECTING} connect so_error (111) (stream_cli.c:429)[0;m [38;5;23m20251222165950694 [1;32mDLINP[0;m[38;5;23m [1;32mINFO[0;m[38;5;23m CLICONN(,r=127.0.0.100:16001<->l=127.0.0.20:52637){WAIT_RECONNECT} retrying reconnect in 5 seconds... (stream_cli.c:244)[0;m [38;5;23m20251222165955698 [1;31mDLINP[0;m[38;5;23m [1;31mERROR[0;m[38;5;23m CLICONN(,r=127.0.0.100:16001<->l=127.0.0.20:52637){CONNECTING} connect so_error (111) (stream_cli.c:429)[0;m [38;5;23m20251222165955698 [1;32mDLINP[0;m[38;5;23m [1;32mINFO[0;m[38;5;23m CLICONN(,r=127.0.0.100:16001<->l=127.0.0.20:52637){WAIT_RECONNECT} retrying reconnect in 5 seconds... (stream_cli.c:244)[0;m MTC@062ebec5dfd3: setverdict(fail): pass -> fail reason: "Tguard timeout", new component reason: "Tguard timeout" [1;32m20251222165958418 [1;34mDSBcAP[0;m[1;32m [1;34mDEBUG[0;m[1;32m ttcn3-mme-client: sbcap_cbc_srv_read_cb(): sctp_recvmsg() returned 12 (flags=0x8080) (sbcap_link.c:246)[0;m [1;32m20251222165958418 [1;34mDSBcAP[0;m[1;32m [1;34mDEBUG[0;m[1;32m ttcn3-mme-client: Rx sctp notif SHUTDOWN_EVENT (sbcap_link.c:259)[0;m [1;32m20251222165958418 [1;33mDSBcAP[0;m[1;32m [1;33mNOTICE[0;m[1;32m ttcn3-mme-client: connection closed (sbcap_link.c:301)[0;m [1;32m20251222165958418 [1;34mDSBcAP[0;m[1;32m [1;34mDEBUG[0;m[1;32m SBcAP-Link(ttcn3-mme-client)[0x5120000213a0]{IDLE}: Received Event CLOSE.cmd (sbcap_link.c:307)[0;m [1;32m20251222165958418 [1;34mDSBcAP[0;m[1;32m [1;34mDEBUG[0;m[1;32m SBcAP-Link(ttcn3-mme-client)[0x5120000213a0]{IDLE}: Terminating (cause = OSMO_FSM_TERM_REQUEST) (sbcap_link_fsm.c:78)[0;m [1;32m20251222165958418 [1;34mDSBcAP[0;m[1;32m [1;34mDEBUG[0;m[1;32m SBcAP-Link(ttcn3-mme-client)[0x5120000213a0]{IDLE}: Freeing instance (sbcap_link_fsm.c:78)[0;m [1;32m20251222165958418 [1;34mDSBcAP[0;m[1;32m [1;34mDEBUG[0;m[1;32m SBcAP-Link(ttcn3-mme-client)[0x5120000213a0]{IDLE}: Deallocated (fsm.c:568)[0;m TC_cell_failure_restart_active_mme-MME0(47)@062ebec5dfd3: Final verdict of PTC: none TC_cell_failure_restart_active_mme-MME0-MME0(48)@062ebec5dfd3: Final verdict of PTC: none MTC@062ebec5dfd3: Setting final verdict of the test case. MTC@062ebec5dfd3: Local verdict of MTC: fail reason: "Tguard timeout" MTC@062ebec5dfd3: Local verdict of PTC TC_cell_failure_restart_active_mme-MME0(47): none (fail -> fail) MTC@062ebec5dfd3: Local verdict of PTC TC_cell_failure_restart_active_mme-MME0-MME0(48): none (fail -> fail) MTC@062ebec5dfd3: Test case TC_cell_failure_restart_active_mme finished. Verdict: fail reason: Tguard timeout MTC@062ebec5dfd3: Starting external command `/home/osmocom-build/jenkins/workspace/ttcn3-cbc-test-asan/ttcn3-tcpdump-stop.sh CBC_Tests.TC_cell_failure_restart_active_mme fail'. Mon Dec 22 16:59:58 UTC 2025 [1;31m------ CBC_Tests.TC_cell_failure_restart_active_mme fail ------[0m Saving talloc report from 127.0.0.20:4264 to CBC_Tests.TC_cell_failure_restart_active_mme.talloc 20251222165958460 [1;32mDLGLOBAL[0;m [1;32mINFO[0;m Accept()ed new telnet connection r=127.0.0.1:38686<->l=127.0.0.20:4264 (telnet_interface.c:192) 20251222165959664 [1;32mDLGLOBAL[0;m [1;32mINFO[0;m Closing telnet connection r=127.0.0.1:38686<->l=127.0.0.20:4264 (telnet_interface.c:138) Waiting for packet dumper to finish... 0 (prev_count=-1, count=49472) Waiting for packet dumper to finish... 1 (prev_count=49472, count=50012) [38;5;23m20251222170000699 [1;31mDLINP[0;m[38;5;23m [1;31mERROR[0;m[38;5;23m CLICONN(,r=127.0.0.100:16001<->l=127.0.0.20:52637){CONNECTING} connect so_error (111) (stream_cli.c:429)[0;m [38;5;23m20251222170000699 [1;32mDLINP[0;m[38;5;23m [1;32mINFO[0;m[38;5;23m CLICONN(,r=127.0.0.100:16001<->l=127.0.0.20:52637){WAIT_RECONNECT} retrying reconnect in 5 seconds... (stream_cli.c:244)[0;m MTC@062ebec5dfd3: External command `/home/osmocom-build/jenkins/workspace/ttcn3-cbc-test-asan/ttcn3-tcpdump-stop.sh CBC_Tests.TC_cell_failure_restart_active_mme fail' was executed successfully (exit status: 0). MC@062ebec5dfd3: Test execution finished. Execution of [EXECUTE] section finished. emtc MC@062ebec5dfd3: Terminating MTC. MC@062ebec5dfd3: MTC terminated. MC2> exit MC@062ebec5dfd3: Shutting down session. MC@062ebec5dfd3: Shutdown complete. Comparing expected results '/home/osmocom-build/jenkins/workspace/ttcn3-cbc-test-asan/cbc/expected-results.xml' against results in 'junit-xml-6215.log' -------------------- [1;32mpass[0;m CBC_Tests.TC_rx_keepalive [1;32mpass[0;m CBC_Tests.TC_rx_keepalive_timeout [1;32mpass[0;m CBC_Tests.TC_ecbe_create_delete_cgi [1;32mpass[0;m CBC_Tests.TC_ecbe_create_delete_lac_ci [1;32mpass[0;m CBC_Tests.TC_ecbe_create_delete_lac [1;32mpass[0;m CBC_Tests.TC_ecbe_create_delete_ci [1;32mpass[0;m CBC_Tests.TC_ecbe_create_delete_lai [1;32mpass[0;m CBC_Tests.TC_ecbe_create_delete_mme_indication [1;32mpass[0;m CBC_Tests.TC_ecbe_create_delete_mme_unknown_tai [1;32mpass[0;m CBC_Tests.TC_ecbe_create_delete_bsc_and_mme [1;32mpass[0;m CBC_Tests.TC_ecbe_create_delete_bsc_server [1;32mpass[0;m CBC_Tests.TC_ecbe_create_delete_mme_server [1;32mpass[0;m CBC_Tests.TC_concurrent_cbs_msg_mme [1;32mpass[0;m CBC_Tests.TC_ecbe_create_delete_etws_bsc [1;32mpass[0;m CBC_Tests.TC_ecbe_create_delete_etws_mme [1;32mpass[0;m CBC_Tests.TC_create_nack_bsc [1;32mpass[0;m CBC_Tests.TC_create_nack_mme [1;32mpass[0;m CBC_Tests.TC_cell_failure_restart_idle_bsc [1;32mpass[0;m CBC_Tests.TC_cell_failure_restart_idle_mme [1;33mxfail[0;m CBC_Tests.TC_cell_failure_restart_active_bsc [1;33mxfail[0;m CBC_Tests.TC_cell_failure_restart_active_mme Summary: [1;33mxfail[0;m: 2 [1;32mpass[0;m: 19 [0;94m[testenv] Testsuite is done[0m [1;34m[testenv] Stopping testsuite (3431259)[0m [1;34m[testenv] Merging log files[0m [0;94m[testenv] + ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=/home/osmocom-build/jenkins/workspace/ttcn3-cbc-test-asan/_cache', '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TESTENV_INSTALL_DIR=/', '-e', 'TERM=dumb', '-e', 'PATH=/opt/eclipse-titan-11.1.0/bin:/home/osmocom-build/jenkins/workspace/ttcn3-cbc-test-asan/_testenv/data/scripts:/home/osmocom-build/jenkins/workspace/ttcn3-cbc-test-asan/_testenv/data/scripts/qemu:/home/osmocom-build/jenkins/workspace/ttcn3-cbc-test-asan/cbc:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-e', 'PYTHONUNBUFFERED=1', '-e', 'BUILDDIR=/home/osmocom-build/jenkins/workspace/ttcn3-cbc-test-asan/_cache/podman/titan-11.1.0-debian-trixie', '-e', 'TTCN3_DIR=/opt/eclipse-titan-11.1.0', '-t', '-w', '/home/osmocom-build/jenkins/workspace/ttcn3-cbc-test-asan/logs/testsuite', 'testenv-cbc-osmocom-nightly-asan-20251222-1654-dba8558e-0', 'sh', '-c', '/home/osmocom-build/jenkins/workspace/ttcn3-cbc-test-asan/log_merge.sh CBC_Tests --rm >/dev/null'][0m [1;34m[testenv] Formatting log files[0m [0;94m[testenv] + ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=/home/osmocom-build/jenkins/workspace/ttcn3-cbc-test-asan/_cache', '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TESTENV_INSTALL_DIR=/', '-e', 'TERM=dumb', '-e', 'PATH=/opt/eclipse-titan-11.1.0/bin:/home/osmocom-build/jenkins/workspace/ttcn3-cbc-test-asan/_testenv/data/scripts:/home/osmocom-build/jenkins/workspace/ttcn3-cbc-test-asan/_testenv/data/scripts/qemu:/home/osmocom-build/jenkins/workspace/ttcn3-cbc-test-asan/cbc:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-e', 'PYTHONUNBUFFERED=1', '-e', 'BUILDDIR=/home/osmocom-build/jenkins/workspace/ttcn3-cbc-test-asan/_cache/podman/titan-11.1.0-debian-trixie', '-e', 'TTCN3_DIR=/opt/eclipse-titan-11.1.0', '-t', '-w', '/home/osmocom-build/jenkins/workspace/ttcn3-cbc-test-asan/logs/testsuite', 'testenv-cbc-osmocom-nightly-asan-20251222-1654-dba8558e-0', 'sh', '-c', '/home/osmocom-build/jenkins/workspace/ttcn3-cbc-test-asan/_testenv/data/scripts/log_format.sh'][0m [1;34m[testenv] Stopping cbc (3431157)[0m [1;34m[testenv] Showing testsuite/junit-xml-6215.log[0m [0;94m[testenv] + ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=/home/osmocom-build/jenkins/workspace/ttcn3-cbc-test-asan/_cache', '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TESTENV_INSTALL_DIR=/', '-e', 'TERM=dumb', '-e', 'PATH=/home/osmocom-build/jenkins/workspace/ttcn3-cbc-test-asan/_testenv/data/scripts:/home/osmocom-build/jenkins/workspace/ttcn3-cbc-test-asan/_testenv/data/scripts/qemu:/home/osmocom-build/jenkins/workspace/ttcn3-cbc-test-asan/cbc:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-e', 'PYTHONUNBUFFERED=1', '-t', 'testenv-cbc-osmocom-nightly-asan-20251222-1654-dba8558e-0', 'sh', '-c', 'echo && source-highlight -f esc -s xml -i /home/osmocom-build/jenkins/workspace/ttcn3-cbc-test-asan/logs/testsuite/junit-xml-6215.log && echo'][0m [01;34m<?xml[m [32mversion[m[31m=[m[31m"1.0"[m[01;34m?>[m [01;34m<testsuite[m [32mname[m[31m=[m[31m'Titan'[m [32mtests[m[31m=[m[31m'21'[m [32mfailures[m[31m=[m[31m'2'[m [32merrors[m[31m=[m[31m'0'[m [32mskipped[m[31m=[m[31m'0'[m [32minconc[m[31m=[m[31m'0'[m [32mtime[m[31m=[m[31m'343.00'[m[01;34m>[m [01;34m<testcase[m [32mclassname[m[31m=[m[31m'CBC_Tests'[m [32mname[m[31m=[m[31m'TC_rx_keepalive'[m [32mtime[m[31m=[m[31m'30.037193'[m[01;34m/>[m [01;34m<testcase[m [32mclassname[m[31m=[m[31m'CBC_Tests'[m [32mname[m[31m=[m[31m'TC_rx_keepalive_timeout'[m [32mtime[m[31m=[m[31m'65.073633'[m[01;34m/>[m [01;34m<testcase[m [32mclassname[m[31m=[m[31m'CBC_Tests'[m [32mname[m[31m=[m[31m'TC_ecbe_create_delete_cgi'[m [32mtime[m[31m=[m[31m'2.028617'[m[01;34m/>[m [01;34m<testcase[m [32mclassname[m[31m=[m[31m'CBC_Tests'[m [32mname[m[31m=[m[31m'TC_ecbe_create_delete_lac_ci'[m [32mtime[m[31m=[m[31m'2.061880'[m[01;34m/>[m [01;34m<testcase[m [32mclassname[m[31m=[m[31m'CBC_Tests'[m [32mname[m[31m=[m[31m'TC_ecbe_create_delete_lac'[m [32mtime[m[31m=[m[31m'2.065884'[m[01;34m/>[m [01;34m<testcase[m [32mclassname[m[31m=[m[31m'CBC_Tests'[m [32mname[m[31m=[m[31m'TC_ecbe_create_delete_ci'[m [32mtime[m[31m=[m[31m'2.051020'[m[01;34m/>[m [01;34m<testcase[m [32mclassname[m[31m=[m[31m'CBC_Tests'[m [32mname[m[31m=[m[31m'TC_ecbe_create_delete_lai'[m [32mtime[m[31m=[m[31m'2.061682'[m[01;34m/>[m [01;34m<testcase[m [32mclassname[m[31m=[m[31m'CBC_Tests'[m [32mname[m[31m=[m[31m'TC_ecbe_create_delete_mme_indication'[m [32mtime[m[31m=[m[31m'2.067775'[m[01;34m/>[m [01;34m<testcase[m [32mclassname[m[31m=[m[31m'CBC_Tests'[m [32mname[m[31m=[m[31m'TC_ecbe_create_delete_mme_unknown_tai'[m [32mtime[m[31m=[m[31m'2.067364'[m[01;34m/>[m [01;34m<testcase[m [32mclassname[m[31m=[m[31m'CBC_Tests'[m [32mname[m[31m=[m[31m'TC_ecbe_create_delete_bsc_and_mme'[m [32mtime[m[31m=[m[31m'2.066891'[m[01;34m/>[m [01;34m<testcase[m [32mclassname[m[31m=[m[31m'CBC_Tests'[m [32mname[m[31m=[m[31m'TC_ecbe_create_delete_bsc_server'[m [32mtime[m[31m=[m[31m'4.112660'[m[01;34m/>[m [01;34m<testcase[m [32mclassname[m[31m=[m[31m'CBC_Tests'[m [32mname[m[31m=[m[31m'TC_ecbe_create_delete_mme_server'[m [32mtime[m[31m=[m[31m'6.156832'[m[01;34m/>[m [01;34m<testcase[m [32mclassname[m[31m=[m[31m'CBC_Tests'[m [32mname[m[31m=[m[31m'TC_concurrent_cbs_msg_mme'[m [32mtime[m[31m=[m[31m'2.057870'[m[01;34m/>[m [01;34m<testcase[m [32mclassname[m[31m=[m[31m'CBC_Tests'[m [32mname[m[31m=[m[31m'TC_ecbe_create_delete_etws_bsc'[m [32mtime[m[31m=[m[31m'2.050507'[m[01;34m/>[m [01;34m<testcase[m [32mclassname[m[31m=[m[31m'CBC_Tests'[m [32mname[m[31m=[m[31m'TC_ecbe_create_delete_etws_mme'[m [32mtime[m[31m=[m[31m'2.057597'[m[01;34m/>[m [01;34m<testcase[m [32mclassname[m[31m=[m[31m'CBC_Tests'[m [32mname[m[31m=[m[31m'TC_create_nack_bsc'[m [32mtime[m[31m=[m[31m'0.048622'[m[01;34m/>[m [01;34m<testcase[m [32mclassname[m[31m=[m[31m'CBC_Tests'[m [32mname[m[31m=[m[31m'TC_create_nack_mme'[m [32mtime[m[31m=[m[31m'12.052372'[m[01;34m/>[m [01;34m<testcase[m [32mclassname[m[31m=[m[31m'CBC_Tests'[m [32mname[m[31m=[m[31m'TC_cell_failure_restart_idle_bsc'[m [32mtime[m[31m=[m[31m'1.045739'[m[01;34m/>[m [01;34m<testcase[m [32mclassname[m[31m=[m[31m'CBC_Tests'[m [32mname[m[31m=[m[31m'TC_cell_failure_restart_idle_mme'[m [32mtime[m[31m=[m[31m'1.049007'[m[01;34m/>[m [01;34m<testcase[m [32mclassname[m[31m=[m[31m'CBC_Tests'[m [32mname[m[31m=[m[31m'TC_cell_failure_restart_active_bsc'[m [32mtime[m[31m=[m[31m'60.081361'[m[01;34m>[m [01;34m<failure[m [32mtype[m[31m=[m[31m'fail-verdict'[m[01;34m>[mTguard timeout CBC_Tests.ttcn:939 CBC_Tests control part CBC_Tests.ttcn:847 TC_cell_failure_restart_active_bsc testcase [01;34m</failure>[m [01;34m</testcase>[m [01;34m<testcase[m [32mclassname[m[31m=[m[31m'CBC_Tests'[m [32mname[m[31m=[m[31m'TC_cell_failure_restart_active_mme'[m [32mtime[m[31m=[m[31m'60.029042'[m[01;34m>[m [01;34m<failure[m [32mtype[m[31m=[m[31m'fail-verdict'[m[01;34m>[mTguard timeout CBC_Tests.ttcn:940 CBC_Tests control part CBC_Tests.ttcn:912 TC_cell_failure_restart_active_mme testcase [01;34m</failure>[m [01;34m</testcase>[m [01;34m</testsuite>[m [1;34m[testenv] Stopping podman container[0m [0;94m[testenv] + ['podman', 'kill', 'testenv-cbc-osmocom-nightly-asan-20251222-1654-dba8558e-0'][0m testenv-cbc-osmocom-nightly-asan-20251222-1654-dba8558e-0 [0;94m[testenv] feed_watchdog_loop: podman container has stopped[0m [1;34m[testenv] Logs saved to: https://jenkins.osmocom.org/jenkins/job/ttcn3-cbc-test-asan/564/artifact/lo… [0m + [ 0 = 0 ] + rm -rf _cache .linux + + uptimegrep --color=always -o load.* [01;31m[Kload average: 1.64, 1.30, 0.83[m[K + exit 0 FATAL: Unable to delete script file /tmp/jenkins3925988896605104424.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) Agent went offline during the build ERROR: Connection was broken Build step 'Execute shell' marked build as failure ERROR: Step ‘Publish JUnit test result report’ failed: no workspace for ttcn3-cbc-test-asan #564

1 0

Build failed in Jenkins: ttcn3-pcu-test-asan #564
by jenkins＠lists.osmocom.org 22 Dec '25

22 Dec '25

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-pcu-test-asan/564/display/red…> Changes: [Pau Espin Pedrol] library/NGAP_Templates: PDUSessionResourceSetupRequest: Fix criticality ------------------------------------------ [...truncated 16.24 MiB...] [1;34m20251222170210825 [1;34mDTBF[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(UL:TFI-0-0-1:G:TLLI-0x00000009){FLOW} stopping timer T3141 [Contention resolution success (UL-TBF, CCCH)] (tbf.cpp:377)[0;m [0;36m20251222170210825 [1;34mDRLCMACSCHED[0;m[0;36m [1;34mDEBUG[0;m[0;36m PDCH(bts=0,trx=0,ts=7) FN=182 Scheduling control message at RTS for TBF(UL:TFI-0-0-1:G:TLLI-0x00000009){FLOW} (gprs_rlcmac_sched.cpp:202)[0;m [1;32m20251222170210825 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=0,ts=7) FN=182 Sending data request: sapi=5 arfcn=871 cur_fn=177 block=6 data=41 24 02 00 40 00 00 00 00 00 00 00 60 00 00 01 38 01 4b 2b 2b 2b 2b (pcu_l1_if.cpp:189)[0;m [1;32m20251222170210825 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Time indication received: 26 (pcu_l1_if.cpp:1055)[0;m BTS(593)@6ebd5b2d9456: Warning: dec_RlcmacDlCtrlBlock(): Data remained at the end of the stream after successful decoding: '4B2B2B2B2B'O ("K++++") MTC@6ebd5b2d9456: Sending RACH.ind on fn=1337 with RA=122, TA=0 MTC@6ebd5b2d9456: Start timer T: 2 s [1;32m20251222170210826 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m RACH request received: sapi=1 qta=0, ra=0x7a, fn=1337 (rfn=1337), cur_fn=177, is_11bit=0 (pcu_l1_if.cpp:640)[0;m [0;33m20251222170210826 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m MS requests Uplink resource on CCCH/RACH: ra=0x7a (8 bit) Fn=1337 qta=0 (bts.cpp:952)[0;m [0;33m20251222170210826 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m MS requests single TS uplink transmission (one phase packet access) (bts.cpp:970)[0;m [1;34m20251222170210826 [1;32mDMS[0;m[1;34m [1;32mINFO[0;m[1;34m Creating MS object (gprs_ms.c:152)[0;m 20251222170210826 [1;34mDLGLOBAL[0;m [1;34mDEBUG[0;m validating counter group 0x55f469457620(pcu:ms) with 1 counters (rate_ctr.c:86) [1;34m20251222170210826 [1;32mDMS[0;m[1;34m [1;32mINFO[0;m[1;34m MS(TA-220:MSCLS-0-0): + bts_rcv_rach: now used by 1 (bts_rcv_rach) (gprs_ms.c:175)[0;m [0;33m20251222170210826 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m [UL] algo B <single> (suggested TRX: -1): Alloc start (alloc_algo.cpp:763)[0;m [0;33m20251222170210826 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m Found first unallocated TRX=1 TFI=1 (bts.cpp:663)[0;m [0;33m20251222170210826 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m - Skipping TS 0, because not enabled (alloc_algo.cpp:75)[0;m [0;33m20251222170210826 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m - Skipping TS 1, because not enabled (alloc_algo.cpp:75)[0;m [0;33m20251222170210826 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m - Skipping TS 2, because not enabled (alloc_algo.cpp:75)[0;m [0;33m20251222170210826 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m - Skipping TS 3, because not enabled (alloc_algo.cpp:75)[0;m [0;33m20251222170210826 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m - Skipping TS 4, because not enabled (alloc_algo.cpp:75)[0;m [0;33m20251222170210826 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m - Skipping TS 5, because not enabled (alloc_algo.cpp:75)[0;m [0;33m20251222170210826 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m - Skipping TS 6, because not enabled (alloc_algo.cpp:75)[0;m [0;33m20251222170210826 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m - Possible DL/UL slots: (TS=0)".......C"(TS=7) (alloc_algo.cpp:504)[0;m [0;33m20251222170210826 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m Rx=4 Tx=4 Sum Rx+Tx=5, Tta=2 Ttb=1, Tra=2 Trb=1, Type=1 (mslot_class.c:193)[0;m [0;33mSelected UL slots: (TS=0)"u......U"(TS=7), single (alloc_algo.cpp:688)[0;m [0;33m20251222170210826 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m [UL] algo B <single> (suggested TRX: -1): using single slot at TS 7 (alloc_algo.cpp:834)[0;m [0;33m20251222170210826 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m - Available DL/UL slots: (TS=0)"D......C"(TS=7) (alloc_algo.cpp:841)[0;m [1;34m20251222170210826 [1;34mDTBF[0;m[1;34m [1;34mDEBUG[0;m[1;34m MS(TA-220:MSCLS-0-0) ********** UL-TBF starts here ********** (tbf_ul.cpp:114)[0;m [1;34m20251222170210826 [1;32mDTBF[0;m[1;34m [1;32mINFO[0;m[1;34m MS(TA-220:MSCLS-0-0) Allocating UL TBF (tbf_ul.cpp:115)[0;m [1;34m20251222170210826 [1;32mDTBF[0;m[1;34m [1;32mINFO[0;m[1;34m UL_ASS_TBF{NONE}: Allocated (fsm.c:456)[0;m [1;34m20251222170210826 [1;32mDTBF[0;m[1;34m [1;32mINFO[0;m[1;34m DL_ASS_TBF{NONE}: Allocated (fsm.c:456)[0;m 20251222170210826 [1;34mDLGLOBAL[0;m [1;34mDEBUG[0;m validating counter group 0x55f469457a80(pcu:tbf) with 1 counters (rate_ctr.c:86) [1;34m20251222170210826 [1;32mDTBFUL[0;m[1;34m [1;32mINFO[0;m[1;34m UL_TBF{NEW}: Allocated (fsm.c:456)[0;m [1;34m20251222170210826 [1;32mDTBFUL[0;m[1;34m [1;32mINFO[0;m[1;34m UL_ACK_TBF{NONE}: Allocated (fsm.c:456)[0;m 20251222170210826 [1;34mDLGLOBAL[0;m [1;34mDEBUG[0;m validating counter group 0x55f469457bc0(tbf:egprs) with 9 counters (rate_ctr.c:86) 20251222170210826 [1;34mDLGLOBAL[0;m [1;34mDEBUG[0;m validating counter group 0x55f469457c00(tbf:gprs) with 4 counters (rate_ctr.c:86) [1;34m20251222170210826 [1;34mDTBFUL[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(UL:TFI-0-1--1:G){NEW} Assigning TS=7 TFI=1 USF=1 (tbf_ul.cpp:695)[0;m [0;33m20251222170210826 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=1,ts=7) Attaching TBF(UL:TFI-0-1-1:G){NEW}, 2 TBFs, USFs = 03, TFIs = 00000003. (pdch.cpp:1192)[0;m [1;34m20251222170210826 [1;32mDTBF[0;m[1;34m [1;32mINFO[0;m[1;34m TBF(UL:TFI-0-1-1:G){NEW} Setting Control TS PDCH(bts=0,trx=1,ts=7) (tbf.cpp:299)[0;m [1;34m20251222170210826 [1;32mDTBF[0;m[1;34m [1;32mINFO[0;m[1;34m TBF(UL:TFI-0-1-1:G){NEW} Allocated: trx = 1, ul_slots = 80, dl_slots = 00 (tbf_ul.cpp:712)[0;m [1;34m20251222170210826 [1;32mDMS[0;m[1;34m [1;32mINFO[0;m[1;34m MS(TA-220:MSCLS-0-0) Attaching UL TBF: TBF(UL:TFI-0-1-1:G){NEW} (gprs_ms.c:336)[0;m [1;34m20251222170210826 [1;34mDMS[0;m[1;34m [1;34mDEBUG[0;m[1;34m MS(TA-220:MSCLS-0-0:UL): + tbf: now used by 2 (bts_rcv_rach,tbf) (gprs_ms.c:343)[0;m [1;34m20251222170210826 [1;32mDTBFUL[0;m[1;34m [1;32mINFO[0;m[1;34m UL_TBF(UL:TFI-0-1-1:G){NEW}: Received Event ASSIGN_ADD_CCCH (gprs_ms.c:1225)[0;m [1;34m20251222170210826 [1;32mDTBFUL[0;m[1;34m [1;32mINFO[0;m[1;34m TBF(UL:TFI-0-1-1:G){NEW} set ass. type CCCH [prev CCCH:0, PACCH:0] (tbf_ul_fsm.c:65)[0;m [1;34m20251222170210826 [1;32mDTBFUL[0;m[1;34m [1;32mINFO[0;m[1;34m UL_TBF(UL:TFI-0-1-1:G){NEW}: state_chg to ASSIGN (tbf_ul_fsm.c:92)[0;m [1;34m20251222170210826 [1;34mDTBFUL[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(UL:TFI-0-1-1:G){ASSIGN} Starting timer X2002 [assignment (AGCH)] with 0 sec. 200000 microsec (tbf_ul_fsm.c:141)[0;m [1;34m20251222170210826 [1;34mDTBF[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(UL:TFI-0-1-1:G){ASSIGN} Starting timer T3141 [Contention resolution (UL-TBF, CCCH)] with 10 sec. 0 microsec, cur_fn=177 (tbf_ul.cpp:221)[0;m [1;34m20251222170210826 [1;34mDMS[0;m[1;34m [1;34mDEBUG[0;m[1;34m MS(TA-220:MSCLS-0-0:UL): - bts_rcv_rach: now used by 1 (tbf) (bts.cpp:1012)[0;m [1;34m20251222170210826 [1;32mDMS[0;m[1;34m [1;32mINFO[0;m[1;34m Modifying MS object, TLLI = 0xffffffff, TA 220 -> 0 (gprs_ms.c:609)[0;m [0;33m20251222170210826 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m Tx Immediate Assignment on AGCH: TRX=1 (ARFCN 872) TS=7 TA=0 TSC=7 TFI=1 USF=1 (bts.cpp:1026)[0;m [1;32m20251222170210826 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=0,ts=0) FN=0 Sending data request: sapi=9 arfcn=0 cur_fn=177 block=0 data=ff ff ff ff 2d 06 3f 10 0f e3 68 7a 09 6b 00 00 c8 42 30 0b 2b 2b 2b 2b 2b 2b 2b 00 (pcu_l1_if.cpp:189)[0;m BTS(593)@6ebd5b2d9456: Warning: dec_PCUIF_agch(): Data remained at the end of the stream after successful decoding: '00'O MTC@6ebd5b2d9456: Rx Immediate Assignment: { header := { l2_plen := { l2_plen := 11, zero_one := '01'B }, skip_indicator := 0, rr_protocol_discriminator := 6, message_type := IMMEDIATE_ASSIGNMENT (63) }, payload := { imm_ass := { ded_or_tbf := { spare := '0'B, tma := false, downlink := false, tbf := true }, page_mode := PAGE_MODE_NORMAL (0), chan_desc := omit, pkt_chan_desc := { channel_Type_spare := 1, tn := 7, tsc := 7, presence := '0'B, zero := { hopping := '0'B, spare := '0'B, arfcn := 872, indirect := omit }, one := omit }, req_ref := { ra := '01111010'B, t1p := 1, t3 := 11, t2 := 11 }, timing_advance := 0, mobile_allocation := { len := 0, ma := ''B }, rest_octets := { presence := '11'B, ll := omit, lh := omit, hl := omit, hh := { pa_disc := '0'B, pa := { uldl := { ass_disc := '0'B, ass := { ul := { presence := '1'B, dynamic := { tfi_assignment := 1, polling := '0'B, spare := '0'B, usf := 1, usf_granularity := '0'B, p0_present := '0'B, p0 := omit, pr_mode := omit, ch_coding_cmd := CH_CODING_CS2 (1), tlli_block_chan_coding := '1'B, alpha_present := '0'B, alpha := omit, gamma := 0, ta_index_present := '0'B, ta_index := omit, tbf_starting_time_present := '0'B, tbf_starting_time := omit }, single := omit } } } } } } } } } MTC@6ebd5b2d9456: Rx Uplink TBF GPRS assignment: { presence := '1'B, dynamic := { tfi_assignment := 1, polling := '0'B, spare := '0'B, usf := 1, usf_granularity := '0'B, p0_present := '0'B, p0 := omit, pr_mode := omit, ch_coding_cmd := CH_CODING_CS2 (1), tlli_block_chan_coding := '1'B, alpha_present := '0'B, alpha := omit, gamma := 0, ta_index_present := '0'B, ta_index := omit, tbf_starting_time_present := '0'B, tbf_starting_time := omit }, single := omit } [1;32m20251222170210839 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=0,ts=7) FN=182 Rx DATA.ind: sapi=5 arfcn=871 cur_fn=177 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222170210839 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=0,ts=7) FN=182 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [0;33m20251222170210839 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=0,ts=7) Timeout for registered USF (FN=182): TBF(UL:TFI-0-0-1:G:TLLI-0x00000009){FLOW} (pdch_ul_controller.c:309)[0;m [1;34m20251222170210839 [1;34mDTBF[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(UL:TFI-0-0-1:G:TLLI-0x00000009){FLOW} N3101 0 => 1 (< MAX 10) (tbf.cpp:362)[0;m [1;32m20251222170210839 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=1,ts=7) FN=182 Rx DATA.ind: sapi=5 arfcn=872 cur_fn=182 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222170210839 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=1,ts=7) FN=182 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222170210839 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=2,ts=7) FN=182 Rx DATA.ind: sapi=5 arfcn=873 cur_fn=182 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222170210839 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=2,ts=7) FN=182 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222170210839 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=3,ts=7) FN=182 Rx DATA.ind: sapi=5 arfcn=874 cur_fn=182 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222170210839 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=3,ts=7) FN=182 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222170210840 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=4,ts=7) FN=182 Rx DATA.ind: sapi=5 arfcn=875 cur_fn=182 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222170210840 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=4,ts=7) FN=182 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222170210840 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=5,ts=7) FN=182 Rx DATA.ind: sapi=5 arfcn=876 cur_fn=182 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222170210840 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=5,ts=7) FN=182 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222170210840 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=6,ts=7) FN=182 Rx DATA.ind: sapi=5 arfcn=877 cur_fn=182 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222170210840 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=6,ts=7) FN=182 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222170210840 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=7,ts=7) FN=182 Rx DATA.ind: sapi=5 arfcn=878 cur_fn=182 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222170210840 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=7,ts=7) FN=182 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222170210844 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=0,ts=7) FN=186 RX RTS.req: sapi=5 arfcn=871 cur_fn=182 block=7 (pcu_l1_if.cpp:583)[0;m [0;36m20251222170210844 [1;34mDRLCMACSCHED[0;m[0;36m [1;34mDEBUG[0;m[0;36m PDCH(bts=0,trx=0,ts=7) Received RTS for PDCH: FN=186 block_nr=7 scheduling USF=1 for TBF(UL:TFI-0-0-1:G:TLLI-0x00000009){FLOW}, expect answer on UL FN=190 (gprs_rlcmac_sched.cpp:454)[0;m [0;33m20251222170210844 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m PDCH(bts=0,trx=0,ts=7) Reserving FN 190 for type USF (pdch_ul_controller.c:184)[0;m [1;32m20251222170210844 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=0,ts=7) FN=186 Sending data request: sapi=5 arfcn=871 cur_fn=182 block=7 data=41 94 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b (pcu_l1_if.cpp:189)[0;m [1;32m20251222170210844 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Time indication received: 30 (pcu_l1_if.cpp:1055)[0;m BTS(593)@6ebd5b2d9456: Warning: dec_RlcmacDlCtrlBlock(): Data remained at the end of the stream after successful decoding: '2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B'O ("+++++++++++++++++++++") MTC@6ebd5b2d9456: Rx DL block USF 1 vs exp USF 1 [1;32m20251222170210858 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=0,ts=7) FN=186 Rx DATA.ind: sapi=5 arfcn=871 cur_fn=182 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222170210858 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=0,ts=7) FN=186 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [0;33m20251222170210858 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=0,ts=7) Timeout for registered USF (FN=186): TBF(UL:TFI-0-0-1:G:TLLI-0x00000009){FLOW} (pdch_ul_controller.c:309)[0;m [1;34m20251222170210858 [1;34mDTBF[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(UL:TFI-0-0-1:G:TLLI-0x00000009){FLOW} N3101 1 => 2 (< MAX 10) (tbf.cpp:362)[0;m [1;32m20251222170210858 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=1,ts=7) FN=186 Rx DATA.ind: sapi=5 arfcn=872 cur_fn=186 block=7 data=3c 03 01 00 00 00 0a 9d c0 6c de 90 a4 a6 77 bb 40 a0 78 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 00 (pcu_l1_if.cpp:470)[0;m [1;32m20251222170210858 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=1,ts=7) FN=186 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 0 (pcu_l1_if.cpp:485)[0;m [1;36m20251222170210858 [1;34mDRLCMACUL[0;m[1;36m [1;34mDEBUG[0;m[1;36m PDCH(bts=0,trx=1,ts=7) Got RLC block, coding scheme: CS-2, length: 34 (33)) (pdch.cpp:983)[0;m [1;36m20251222170210858 [1;34mDRLCMACUL[0;m[1;36m [1;34mDEBUG[0;m[1;36m PDCH(bts=0,trx=1,ts=7) UL data: 3c 03 01 00 00 00 0a 9d c0 6c de 90 a4 a6 77 bb 40 a0 78 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 00 (pdch.cpp:1021)[0;m [1;36m20251222170210858 [1;32mDRLCMACUL[0;m[1;36m [1;32mINFO[0;m[1;36m PDCH(bts=0,trx=1,ts=7) Got CS-2 RLC block: R=0, SI=0, TFI=1, CPS=0, RSB=0, rc=264 (pdch.cpp:1032)[0;m [1;36m20251222170210858 [1;33mDRLCMACUL[0;m[1;36m [1;33mNOTICE[0;m[1;36m PDCH(bts=0,trx=1,ts=7) FN=186 Rx UL DATA from unexpected TBF(UL:TFI-0-1-1:G){ASSIGN} (pdch.cpp:1072)[0;m [1;34m20251222170210858 [1;34mDTBFUL[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(UL:TFI-0-1-1:G){ASSIGN} UL DATA TFI=1 received (V(Q)=0 .. V(R)=0) (tbf_ul.cpp:257)[0;m [1;31m20251222170210858 [1;32mDRLCMACMEAS[0;m[1;31m [1;32mINFO[0;m[1;31m MS(TA-0:MSCLS-0-0:UL) Link quality 0dB (old 0dB) left window [5, 8], modifying uplink CS level: CS-2 -> CS-1 (gprs_ms.c:816)[0;m [1;34m20251222170210858 [1;34mDTBFUL[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(UL:TFI-0-1-1:G){ASSIGN} Got CS-2 RLC data block: CV=15, BSN=0, SPB=0, PI=0, E=1, TI=1, bitoffs=24 (tbf_ul.cpp:292)[0;m [1;34m20251222170210858 [1;34mDTBFUL[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(UL:TFI-0-1-1:G){ASSIGN} BSN 0 storing in window (0..63) (tbf_ul.cpp:314)[0;m [1;34m20251222170210858 [1;34mDTBFUL[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(UL:TFI-0-1-1:G){ASSIGN} data_length=30, data=00 00 00 0a 9d c0 6c de 90 a4 a6 77 bb 40 a0 78 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b (tbf_ul.cpp:337)[0;m [1;34m20251222170210858 [1;32mDTBFUL[0;m[1;34m [1;32mINFO[0;m[1;34m TBF(UL:TFI-0-1-1:G){ASSIGN} Decoded premier TLLI=0x0000000a of UL DATA TFI=1. (tbf_ul.cpp:361)[0;m [1;34m20251222170210858 [1;32mDMS[0;m[1;34m [1;32mINFO[0;m[1;34m Modifying MS object, UL TLLI: 0xffffffff -> 0x0000000a, not yet confirmed (gprs_ms.c:512)[0;m [1;34m20251222170210858 [1;32mDTBFUL[0;m[1;34m [1;32mINFO[0;m[1;34m UL_TBF(UL:TFI-0-1-1:G){ASSIGN}: Received Event FIRST_UL_DATA_RECVD (tbf_ul.cpp:365)[0;m [1;34m20251222170210858 [1;31mDTBFUL[0;m[1;34m [1;31mERROR[0;m[1;34m UL_TBF(UL:TFI-0-1-1:G){ASSIGN}: Event FIRST_UL_DATA_RECVD not permitted (tbf_ul.cpp:365)[0;m [1;36m20251222170210858 [1;34mDRLCMACUL[0;m[1;36m [1;34mDEBUG[0;m[1;36m - Mark BSN 64 as INVALID (rlc_window_ul.cpp:42)[0;m [1;36m20251222170210858 [1;34mDRLCMACUL[0;m[1;36m [1;34mDEBUG[0;m[1;36m - Raising V(R) to 1 (rlc_window_ul.cpp:50)[0;m [1;36m20251222170210858 [1;34mDRLCMACUL[0;m[1;36m [1;34mDEBUG[0;m[1;36m - Taking block 0 out, raising V(Q) to 1 (rlc_window_ul.cpp:65)[0;m [1;34m20251222170210858 [1;34mDTBFUL[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(UL:TFI-0-1-1:G:TLLI-0x0000000a){ASSIGN} Assembling frames: (len=30) (tbf_ul.cpp:172)[0;m [1;34m20251222170210858 [1;34mDTBFUL[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(UL:TFI-0-1-1:G:TLLI-0x0000000a){ASSIGN} Frame 1 starts at offset 4, length=26, is_complete=0 (tbf_ul.cpp:185)[0;m [1;34m20251222170210858 [1;34mDTBFUL[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(UL:TFI-0-1-1:G:TLLI-0x0000000a){ASSIGN} Scheduling Ack/Nack, because TLLI is included. (tbf_ul.cpp:434)[0;m [1;34m20251222170210858 [1;32mDTBFUL[0;m[1;34m [1;32mINFO[0;m[1;34m UL_ACK_TBF(UL:TFI-0-1-1:G){NONE}: Received Event SCHED_ACK (tbf_ul.cpp:456)[0;m [1;34m20251222170210858 [1;32mDTBFUL[0;m[1;34m [1;32mINFO[0;m[1;34m UL_ACK_TBF(UL:TFI-0-1-1:G){NONE}: state_chg to SCHED_UL_ACK (tbf_ul_ack_fsm.c:102)[0;m [1;32m20251222170210858 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=2,ts=7) FN=186 Rx DATA.ind: sapi=5 arfcn=873 cur_fn=186 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222170210858 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=2,ts=7) FN=186 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222170210858 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=3,ts=7) FN=186 Rx DATA.ind: sapi=5 arfcn=874 cur_fn=186 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222170210858 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=3,ts=7) FN=186 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222170210858 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=4,ts=7) FN=186 Rx DATA.ind: sapi=5 arfcn=875 cur_fn=186 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222170210858 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=4,ts=7) FN=186 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222170210858 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=5,ts=7) FN=186 Rx DATA.ind: sapi=5 arfcn=876 cur_fn=186 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222170210858 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=5,ts=7) FN=186 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222170210859 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=6,ts=7) FN=186 Rx DATA.ind: sapi=5 arfcn=877 cur_fn=186 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222170210859 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=6,ts=7) FN=186 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222170210859 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=7,ts=7) FN=186 Rx DATA.ind: sapi=5 arfcn=878 cur_fn=186 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222170210859 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=7,ts=7) FN=186 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222170210862 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=1,ts=7) FN=190 RX RTS.req: sapi=5 arfcn=872 cur_fn=186 block=8 (pcu_l1_if.cpp:583)[0;m [0;36m20251222170210862 [1;34mDRLCMACSCHED[0;m[0;36m [1;34mDEBUG[0;m[0;36m PDCH(bts=0,trx=1,ts=7) Received RTS for PDCH: FN=190 block_nr=8 scheduling USF=0 for TBF(UL:TFI-0-1-0:G:TLLI-0x00000002){FLOW}, expect answer on UL FN=195 (gprs_rlcmac_sched.cpp:454)[0;m [0;33m20251222170210862 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m PDCH(bts=0,trx=1,ts=7) Reserving FN 195 for type USF (pdch_ul_controller.c:184)[0;m [1;34m20251222170210862 [1;32mDTBFUL[0;m[1;34m [1;32mINFO[0;m[1;34m UL_ACK_TBF(UL:TFI-0-1-1:G){SCHED_UL_ACK}: Received Event CREATE_RLCMAC_MSG (tbf_ul_ack_fsm.c:251)[0;m [1;36m20251222170210862 [1;34mDRLCMACUL[0;m[1;36m [1;34mDEBUG[0;m[1;36m Encoding Ack/Nack for TBF(UL:TFI-0-1-1:G:TLLI-0x0000000a){ASSIGN} (final=0) (encoding.cpp:1116)[0;m [1;36m20251222170210862 [1;34mDRLCMACUL[0;m[1;36m [1;34mDEBUG[0;m[1;36m - V(N): "IIIIIIIIIIIIIIIIIIIIIIIIIIIIIIIIIIIIIIIIIIIIIIIIIIIIIIIIIIIIIIIR" R=Received I=Invalid (encoding.cpp:864)[0;m [1;36m20251222170210862 [1;34mDRLCMACUL[0;m[1;36m [1;34mDEBUG[0;m[1;36m Uplink Ack/Nack bit count 147, max 184, message = 40 24 02 00 40 00 00 00 00 00 00 00 60 00 00 01 58 01 4b 2b 2b 2b 2b (encoding.cpp:1139)[0;m [1;34m20251222170210862 [1;32mDTBFUL[0;m[1;34m [1;32mINFO[0;m[1;34m UL_ACK_TBF(UL:TFI-0-1-1:G){SCHED_UL_ACK}: state_chg to NONE (tbf_ul_ack_fsm.c:131)[0;m [1;34m20251222170210862 [1;32mDTBFUL[0;m[1;34m [1;32mINFO[0;m[1;34m UL_TBF(UL:TFI-0-1-1:G){ASSIGN}: Received Event CONTENTION_RESOLUTION_MS_SUCCESS (tbf_ul_ack_fsm.c:152)[0;m [1;34m20251222170210862 [1;31mDTBFUL[0;m[1;34m [1;31mERROR[0;m[1;34m UL_TBF(UL:TFI-0-1-1:G){ASSIGN}: Event CONTENTION_RESOLUTION_MS_SUCCESS not permitted (tbf_ul_ack_fsm.c:152)[0;m [0;36m20251222170210862 [1;34mDRLCMACSCHED[0;m[0;36m [1;34mDEBUG[0;m[0;36m PDCH(bts=0,trx=1,ts=7) FN=190 Scheduling control message at RTS for TBF(UL:TFI-0-1-1:G:TLLI-0x0000000a){ASSIGN} (gprs_rlcmac_sched.cpp:202)[0;m [1;32m20251222170210862 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=1,ts=7) FN=190 Sending data request: sapi=5 arfcn=872 cur_fn=186 block=8 data=40 24 02 00 40 00 00 00 00 00 00 00 60 00 00 01 58 01 4b 2b 2b 2b 2b (pcu_l1_if.cpp:189)[0;m [1;32m20251222170210862 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Time indication received: 34 (pcu_l1_if.cpp:1055)[0;m BTS(593)@6ebd5b2d9456: Warning: dec_RlcmacDlCtrlBlock(): Data remained at the end of the stream after successful decoding: '4B2B2B2B2B'O ("K++++") MTC@6ebd5b2d9456: Sending RACH.ind on fn=1337 with RA=123, TA=0 MTC@6ebd5b2d9456: Start timer T: 2 s [1;32m20251222170210863 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m RACH request received: sapi=1 qta=0, ra=0x7b, fn=1337 (rfn=1337), cur_fn=186, is_11bit=0 (pcu_l1_if.cpp:640)[0;m [0;33m20251222170210863 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m MS requests Uplink resource on CCCH/RACH: ra=0x7b (8 bit) Fn=1337 qta=0 (bts.cpp:952)[0;m [0;33m20251222170210863 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m MS requests single TS uplink transmission (one phase packet access) (bts.cpp:970)[0;m [1;34m20251222170210863 [1;32mDMS[0;m[1;34m [1;32mINFO[0;m[1;34m Creating MS object (gprs_ms.c:152)[0;m 20251222170210863 [1;34mDLGLOBAL[0;m [1;34mDEBUG[0;m validating counter group 0x55f469457620(pcu:ms) with 1 counters (rate_ctr.c:86) [1;34m20251222170210863 [1;32mDMS[0;m[1;34m [1;32mINFO[0;m[1;34m MS(TA-220:MSCLS-0-0): + bts_rcv_rach: now used by 1 (bts_rcv_rach) (gprs_ms.c:175)[0;m [0;33m20251222170210863 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m [UL] algo B <single> (suggested TRX: -1): Alloc start (alloc_algo.cpp:763)[0;m [0;33m20251222170210863 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m Found first unallocated TRX=2 TFI=1 (bts.cpp:663)[0;m [0;33m20251222170210863 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m - Skipping TS 0, because not enabled (alloc_algo.cpp:75)[0;m [0;33m20251222170210863 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m - Skipping TS 1, because not enabled (alloc_algo.cpp:75)[0;m [0;33m20251222170210863 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m - Skipping TS 2, because not enabled (alloc_algo.cpp:75)[0;m [0;33m20251222170210863 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m - Skipping TS 3, because not enabled (alloc_algo.cpp:75)[0;m [0;33m20251222170210863 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m - Skipping TS 4, because not enabled (alloc_algo.cpp:75)[0;m [0;33m20251222170210863 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m - Skipping TS 5, because not enabled (alloc_algo.cpp:75)[0;m [0;33m20251222170210863 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m - Skipping TS 6, because not enabled (alloc_algo.cpp:75)[0;m [0;33m20251222170210863 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m - Possible DL/UL slots: (TS=0)".......C"(TS=7) (alloc_algo.cpp:504)[0;m [0;33m20251222170210863 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m Rx=4 Tx=4 Sum Rx+Tx=5, Tta=2 Ttb=1, Tra=2 Trb=1, Type=1 (mslot_class.c:193)[0;m [0;33mSelected UL slots: (TS=0)"u......U"(TS=7), single (alloc_algo.cpp:688)[0;m [0;33m20251222170210863 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m [UL] algo B <single> (suggested TRX: -1): using single slot at TS 7 (alloc_algo.cpp:834)[0;m [0;33m20251222170210863 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m - Available DL/UL slots: (TS=0)"D......C"(TS=7) (alloc_algo.cpp:841)[0;m [1;34m20251222170210863 [1;34mDTBF[0;m[1;34m [1;34mDEBUG[0;m[1;34m MS(TA-220:MSCLS-0-0) ********** UL-TBF starts here ********** (tbf_ul.cpp:114)[0;m [1;34m20251222170210863 [1;32mDTBF[0;m[1;34m [1;32mINFO[0;m[1;34m MS(TA-220:MSCLS-0-0) Allocating UL TBF (tbf_ul.cpp:115)[0;m [1;34m20251222170210863 [1;32mDTBF[0;m[1;34m [1;32mINFO[0;m[1;34m UL_ASS_TBF{NONE}: Allocated (fsm.c:456)[0;m [1;34m20251222170210863 [1;32mDTBF[0;m[1;34m [1;32mINFO[0;m[1;34m DL_ASS_TBF{NONE}: Allocated (fsm.c:456)[0;m 20251222170210864 [1;34mDLGLOBAL[0;m [1;34mDEBUG[0;m validating counter group 0x55f469457a80(pcu:tbf) with 1 counters (rate_ctr.c:86) [1;34m20251222170210864 [1;32mDTBFUL[0;m[1;34m [1;32mINFO[0;m[1;34m UL_TBF{NEW}: Allocated (fsm.c:456)[0;m [1;34m20251222170210864 [1;32mDTBFUL[0;m[1;34m [1;32mINFO[0;m[1;34m UL_ACK_TBF{NONE}: Allocated (fsm.c:456)[0;m 20251222170210864 [1;34mDLGLOBAL[0;m [1;34mDEBUG[0;m validating counter group 0x55f469457bc0(tbf:egprs) with 9 counters (rate_ctr.c:86) 20251222170210864 [1;34mDLGLOBAL[0;m [1;34mDEBUG[0;m validating counter group 0x55f469457c00(tbf:gprs) with 4 counters (rate_ctr.c:86) [1;34m20251222170210864 [1;34mDTBFUL[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(UL:TFI-0-2--1:G){NEW} Assigning TS=7 TFI=1 USF=1 (tbf_ul.cpp:695)[0;m [0;33m20251222170210864 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=2,ts=7) Attaching TBF(UL:TFI-0-2-1:G){NEW}, 2 TBFs, USFs = 03, TFIs = 00000003. (pdch.cpp:1192)[0;m [1;34m20251222170210864 [1;32mDTBF[0;m[1;34m [1;32mINFO[0;m[1;34m TBF(UL:TFI-0-2-1:G){NEW} Setting Control TS PDCH(bts=0,trx=2,ts=7) (tbf.cpp:299)[0;m [1;34m20251222170210864 [1;32mDTBF[0;m[1;34m [1;32mINFO[0;m[1;34m TBF(UL:TFI-0-2-1:G){NEW} Allocated: trx = 2, ul_slots = 80, dl_slots = 00 (tbf_ul.cpp:712)[0;m [1;34m20251222170210864 [1;32mDMS[0;m[1;34m [1;32mINFO[0;m[1;34m MS(TA-220:MSCLS-0-0) Attaching UL TBF: TBF(UL:TFI-0-2-1:G){NEW} (gprs_ms.c:336)[0;m [1;34m20251222170210864 [1;34mDMS[0;m[1;34m [1;34mDEBUG[0;m[1;34m MS(TA-220:MSCLS-0-0:UL): + tbf: now used by 2 (bts_rcv_rach,tbf) (gprs_ms.c:343)[0;m [1;34m20251222170210864 [1;32mDTBFUL[0;m[1;34m [1;32mINFO[0;m[1;34m UL_TBF(UL:TFI-0-2-1:G){NEW}: Received Event ASSIGN_ADD_CCCH (gprs_ms.c:1225)[0;m [1;34m20251222170210864 [1;32mDTBFUL[0;m[1;34m [1;32mINFO[0;m[1;34m TBF(UL:TFI-0-2-1:G){NEW} set ass. type CCCH [prev CCCH:0, PACCH:0] (tbf_ul_fsm.c:65)[0;m [1;34m20251222170210864 [1;32mDTBFUL[0;m[1;34m [1;32mINFO[0;m[1;34m UL_TBF(UL:TFI-0-2-1:G){NEW}: state_chg to ASSIGN (tbf_ul_fsm.c:92)[0;m [1;34m20251222170210864 [1;34mDTBFUL[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(UL:TFI-0-2-1:G){ASSIGN} Starting timer X2002 [assignment (AGCH)] with 0 sec. 200000 microsec (tbf_ul_fsm.c:141)[0;m [1;34m20251222170210864 [1;34mDTBF[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(UL:TFI-0-2-1:G){ASSIGN} Starting timer T3141 [Contention resolution (UL-TBF, CCCH)] with 10 sec. 0 microsec, cur_fn=186 (tbf_ul.cpp:221)[0;m [1;34m20251222170210864 [1;34mDMS[0;m[1;34m [1;34mDEBUG[0;m[1;34m MS(TA-220:MSCLS-0-0:UL): - bts_rcv_rach: now used by 1 (tbf) (bts.cpp:1012)[0;m [1;34m20251222170210864 [1;32mDMS[0;m[1;34m [1;32mINFO[0;m[1;34m Modifying MS object, TLLI = 0xffffffff, TA 220 -> 0 (gprs_ms.c:609)[0;m [0;33m20251222170210864 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m Tx Immediate Assignment on AGCH: TRX=2 (ARFCN 873) TS=7 TA=0 TSC=7 TFI=1 USF=1 (bts.cpp:1026)[0;m [1;32m20251222170210864 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=0,ts=0) FN=0 Sending data request: sapi=9 arfcn=0 cur_fn=186 block=0 data=ff ff ff ff 2d 06 3f 10 0f e3 69 7b 09 6b 00 00 c8 42 30 0b 2b 2b 2b 2b 2b 2b 2b 00 (pcu_l1_if.cpp:189)[0;m BTS(593)@6ebd5b2d9456: Warning: dec_PCUIF_agch(): Data remained at the end of the stream after successful decoding: '00'O MTC@6ebd5b2d9456: Rx Immediate Assignment: { header := { l2_plen := { l2_plen := 11, zero_one := '01'B }, skip_indicator := 0, rr_protocol_discriminator := 6, message_type := IMMEDIATE_ASSIGNMENT (63) }, payload := { imm_ass := { ded_or_tbf := { spare := '0'B, tma := false, downlink := false, tbf := true }, page_mode := PAGE_MODE_NORMAL (0), chan_desc := omit, pkt_chan_desc := { channel_Type_spare := 1, tn := 7, tsc := 7, presence := '0'B, zero := { hopping := '0'B, spare := '0'B, arfcn := 873, indirect := omit }, one := omit }, req_ref := { ra := '01111011'B, t1p := 1, t3 := 11, t2 := 11 }, timing_advance := 0, mobile_allocation := { len := 0, ma := ''B }, rest_octets := { presence := '11'B, ll := omit, lh := omit, hl := omit, hh := { pa_disc := '0'B, pa := { uldl := { ass_disc := '0'B, ass := { ul := { presence := '1'B, dynamic := { tfi_assignment := 1, polling := '0'B, spare := '0'B, usf := 1, usf_granularity := '0'B, p0_present := '0'B, p0 := omit, pr_mode := omit, ch_coding_cmd := CH_CODING_CS2 (1), tlli_block_chan_coding := '1'B, alpha_present := '0'B, alpha := omit, gamma := 0, ta_index_present := '0'B, ta_index := omit, tbf_starting_time_present := '0'B, tbf_starting_time := omit }, single := omit } } } } } } } } } MTC@6ebd5b2d9456: Rx Uplink TBF GPRS assignment: { presence := '1'B, dynamic := { tfi_assignment := 1, polling := '0'B, spare := '0'B, usf := 1, usf_granularity := '0'B, p0_present := '0'B, p0 := omit, pr_mode := omit, ch_coding_cmd := CH_CODING_CS2 (1), tlli_block_chan_coding := '1'B, alpha_present := '0'B, alpha := omit, gamma := 0, ta_index_present := '0'B, ta_index := omit, tbf_starting_time_present := '0'B, tbf_starting_time := omit }, single := omit } [1;32m20251222170210876 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=0,ts=7) FN=190 Rx DATA.ind: sapi=5 arfcn=871 cur_fn=186 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222170210876 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=0,ts=7) FN=190 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [0;33m20251222170210876 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=0,ts=7) Timeout for registered USF (FN=190): TBF(UL:TFI-0-0-1:G:TLLI-0x00000009){FLOW} (pdch_ul_controller.c:309)[0;m [1;34m20251222170210876 [1;34mDTBF[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(UL:TFI-0-0-1:G:TLLI-0x00000009){FLOW} N3101 2 => 3 (< MAX 10) (tbf.cpp:362)[0;m [1;32m20251222170210876 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=1,ts=7) FN=190 Rx DATA.ind: sapi=5 arfcn=872 cur_fn=190 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222170210876 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=1,ts=7) FN=190 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[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: 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/jenkins6527127350609205678.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-pcu-test-asan #564

1 0

Build failed in Jenkins: ttcn3-sgsn-test-asan #572
by jenkins＠lists.osmocom.org 22 Dec '25

22 Dec '25

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-asan/572/display/re…> Changes: ------------------------------------------ Started by timer Running as SYSTEM Building remotely on build5-deb12build-ansible (obs ttcn3_with_linux_6.1_or_higher qemu registry-build-amd64 ttcn3 osmo-gsm-tester-build ttcn3-ggsn-test-kernel-git io_uring osmocom-gerrit coverity osmocom-master) in workspace /home/osmocom-build/jenkins/workspace/ttcn3-sgsn-test-asan 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 build5-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.FilePath.act(FilePath.java:1207) at hudson.FilePath.act(FilePath.java:1196) at hudson.FilePath.mkdirs(FilePath.java:1387) at hudson.model.AbstractProject.checkout(AbstractProject.java:1244) 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-sgsn-test-asan #572

1 0

Build failed in Jenkins: ttcn3-mgw-test-asan #565
by jenkins＠lists.osmocom.org 22 Dec '25

22 Dec '25

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test-asan/565/display/red…> Changes: [Pau Espin Pedrol] library/NGAP_Templates: PDUSessionResourceSetupRequest: Fix criticality ------------------------------------------ [...truncated 23.61 KiB...] 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 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_Endpoint_part_1.cc DEP RTP_Endpoint_part_2.cc DEP RTP_Endpoint_part_3.cc DEP RTP_Endpoint_part_4.cc DEP RTP_Endpoint_part_5.cc DEP RTP_Endpoint_part_6.cc DEP RTP_Endpoint_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 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 TCCConversion_Functions_part_7.cc DEP TCCInterface_Functions_part_1.cc DEP TCCInterface_Functions_part_2.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 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 DEP IPA_Emulation_part_6.cc DEP IPA_Emulation_part_7.cc DEP StatsD_Checker_part_1.cc DEP StatsD_Checker_part_2.cc DEP StatsD_Checker_part_3.cc DEP StatsD_Checker_part_4.cc DEP StatsD_Checker_part_5.cc DEP StatsD_Checker_part_6.cc DEP StatsD_Checker_part_7.cc CC AMR_Types.o CC General_Types.o CC IPA_CodecPort.o CC IPA_CodecPort_CtrlFunct.o CC IPA_Types.o CC IPL4asp_Functions.o CC IPL4asp_PortType.o CC IPL4asp_Types.o CC IP_Types.o CC IuUP_Emulation.o CC IuUP_Types.o CC MGCP_CodecPort.o CC MGCP_CodecPort_CtrlFunct.o CC MGCP_Templates.o CC MGCP_Test.o CC MGCP_Types.o CC Misc_Helpers.o CC Native_Functions.o CC OSMUX_CodecPort.o CC OSMUX_CodecPort_CtrlFunct.o CC OSMUX_Emulation.o CC OSMUX_Types.o CC Osmocom_CTRL_Adapter.o CC Osmocom_CTRL_Functions.o CC Osmocom_CTRL_Types.o CC Osmocom_Types.o CC Osmocom_VTY_Functions.o CC RTP_CodecPort.o CC RTP_CodecPort_CtrlFunct.o CC RTP_Emulation.o CC RTP_Endpoint.o CC RTP_Types.o CC SDP_Templates.o CC SDP_Types.o CC Socket_API_Definitions.o CC StatsD_CodecPort.o CC StatsD_CodecPort_CtrlFunct.o CC StatsD_Types.o CC TCCConversion_Functions.o CC TCCInterface_Functions.o CC TELNETasp_PortType.o CC AMR_Types_part_1.o CC AMR_Types_part_2.o CC AMR_Types_part_3.o CC AMR_Types_part_4.o CC AMR_Types_part_5.o CC AMR_Types_part_6.o CC AMR_Types_part_7.o CC General_Types_part_1.o CC General_Types_part_2.o CC General_Types_part_3.o CC General_Types_part_4.o CC General_Types_part_5.o CC General_Types_part_6.o CC General_Types_part_7.o CC IPA_CodecPort_part_1.o CC IPA_CodecPort_part_2.o CC IPA_CodecPort_part_3.o 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/jenkins3339999557681663417.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-mgw-test-asan #565

1 0

Build failed in Jenkins: ttcn3-pcu-test-latest #2516
by jenkins＠lists.osmocom.org 22 Dec '25

22 Dec '25

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-pcu-test-latest/2516/display/…> Changes: [Pau Espin Pedrol] library/NGAP_Templates: PDUSessionResourceSetupRequest: Fix criticality ------------------------------------------ [...truncated 13.66 MiB...] [1;32m20251222125456763 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=7,ts=7) FN=520 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456764 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Time indication received: 4 (pcu_l1_if.cpp:1055)[0;m [1;32m20251222125456777 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=0,ts=7) FN=524 Rx DATA.ind: sapi=5 arfcn=871 cur_fn=520 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456777 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=0,ts=7) FN=524 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456778 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=1,ts=7) FN=524 Rx DATA.ind: sapi=5 arfcn=872 cur_fn=524 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456778 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=1,ts=7) FN=524 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456779 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=2,ts=7) FN=524 Rx DATA.ind: sapi=5 arfcn=873 cur_fn=524 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456779 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=2,ts=7) FN=524 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456780 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=3,ts=7) FN=524 Rx DATA.ind: sapi=5 arfcn=874 cur_fn=524 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456780 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=3,ts=7) FN=524 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456780 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=4,ts=7) FN=524 Rx DATA.ind: sapi=5 arfcn=875 cur_fn=524 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456781 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=4,ts=7) FN=524 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456781 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=5,ts=7) FN=524 Rx DATA.ind: sapi=5 arfcn=876 cur_fn=524 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456781 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=5,ts=7) FN=524 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456782 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=6,ts=7) FN=524 Rx DATA.ind: sapi=5 arfcn=877 cur_fn=524 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456782 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=6,ts=7) FN=524 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456783 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=7,ts=7) FN=524 Rx DATA.ind: sapi=5 arfcn=878 cur_fn=524 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456783 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=7,ts=7) FN=524 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456784 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Time indication received: 8 (pcu_l1_if.cpp:1055)[0;m [1;32m20251222125456795 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=0,ts=7) FN=528 Rx DATA.ind: sapi=5 arfcn=871 cur_fn=524 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456795 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=0,ts=7) FN=528 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456797 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=1,ts=7) FN=528 Rx DATA.ind: sapi=5 arfcn=872 cur_fn=528 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456797 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=1,ts=7) FN=528 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456798 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=2,ts=7) FN=528 Rx DATA.ind: sapi=5 arfcn=873 cur_fn=528 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456798 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=2,ts=7) FN=528 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456799 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=3,ts=7) FN=528 Rx DATA.ind: sapi=5 arfcn=874 cur_fn=528 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456799 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=3,ts=7) FN=528 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;34m20251222125456800 [1;32mDTBFDL[0;m[1;34m [1;32mINFO[0;m[1;34m DL_TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){ASSIGN}: Timeout of X2002 (fsm.c:317)[0;m [1;34m20251222125456800 [1;32mDTBFDL[0;m[1;34m [1;32mINFO[0;m[1;34m DL_TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){ASSIGN}: Received Event ASSIGN_READY_CCCH (tbf_dl_fsm.c:395)[0;m [1;34m20251222125456800 [1;32mDTBFDL[0;m[1;34m [1;32mINFO[0;m[1;34m DL_TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){ASSIGN}: state_chg to FLOW (tbf_dl_fsm.c:199)[0;m [1;32m20251222125456800 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=4,ts=7) FN=528 Rx DATA.ind: sapi=5 arfcn=875 cur_fn=528 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456800 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=4,ts=7) FN=528 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456800 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=5,ts=7) FN=528 Rx DATA.ind: sapi=5 arfcn=876 cur_fn=528 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456800 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=5,ts=7) FN=528 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456801 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=6,ts=7) FN=528 Rx DATA.ind: sapi=5 arfcn=877 cur_fn=528 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456801 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=6,ts=7) FN=528 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456802 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=7,ts=7) FN=528 Rx DATA.ind: sapi=5 arfcn=878 cur_fn=528 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456802 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=7,ts=7) FN=528 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456806 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=0,ts=7) FN=533 RX RTS.req: sapi=5 arfcn=871 cur_fn=528 block=3 (pcu_l1_if.cpp:583)[0;m [0;36m20251222125456806 [1;34mDRLCMACSCHED[0;m[0;36m [1;34mDEBUG[0;m[0;36m PDCH(bts=0,trx=0,ts=7) FN=533 Scheduling data message at RTS for DL TFI=0 prio=3 mcs_mode_restrict=EGPRS (gprs_rlcmac_sched.cpp:329)[0;m [1;34m20251222125456806 [1;34mDTBFDL[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FLOW} downlink (V(A)==0 .. V(S)==0) mcs_mode_restrict=EGPRS (tbf_dl.cpp:401)[0;m [1;34m20251222125456806 [1;34mDTBFDL[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FLOW} Sending new block at BSN 0, CS=CS-3 (tbf_dl.cpp:354)[0;m [1;34m20251222125456806 [1;34mDTBFDL[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FLOW} Dequeue next LLC (len=10) (tbf_dl.cpp:551)[0;m [1;33m20251222125456806 [1;34mDRLCMACDL[0;m[1;33m [1;34mDEBUG[0;m[1;33m -- Chunk with length 10 is less than remaining space (36): add length header to delimit LLC frame (encoding.cpp:1484)[0;m [1;33m20251222125456806 [1;34mDRLCMACDL[0;m[1;33m [1;34mDEBUG[0;m[1;33m -- Final block, so we done. (encoding.cpp:1518)[0;m [1;34m20251222125456806 [1;34mDTBFDL[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FLOW} Complete DL frame, len=10 (tbf_dl.cpp:663)[0;m [1;34m20251222125456806 [1;32mDTBFDL[0;m[1;34m [1;32mINFO[0;m[1;34m DL_TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FLOW}: Received Event LAST_DL_DATA_SENT (tbf_dl.cpp:679)[0;m [1;34m20251222125456806 [1;32mDTBFDL[0;m[1;34m [1;32mINFO[0;m[1;34m DL_TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FLOW}: state_chg to FINISHED (tbf_dl_fsm.c:244)[0;m [1;34m20251222125456806 [1;34mDTBFDL[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FINISHED} data block (BSN 0, CS-3): 29 d9 aa 84 91 42 fd 5f d5 79 1b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b (tbf_dl.cpp:686)[0;m [1;34m20251222125456806 [1;34mDTBFDL[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FINISHED} need_padding 0 spb_status 0 spb 0 (BSN1 0 BSN2 -1) (tbf_dl.cpp:765)[0;m [1;34m20251222125456806 [1;34mDTBFDL[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FINISHED} Copying 1 RLC blocks, 1 BSNs (tbf_dl.cpp:785)[0;m [1;34m20251222125456806 [1;34mDTBFDL[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FINISHED} Copying data unit 0 (BSN 0) (tbf_dl.cpp:847)[0;m [1;34m20251222125456806 [1;34mDTBFDL[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FINISHED} Scheduling Ack/Nack polling, because it was requested explicitly (e.g. first final block sent). (tbf_dl.cpp:868)[0;m [0;33m20251222125456806 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m PDCH(bts=0,trx=0,ts=7) POLL scheduled at FN 533 + 13 = 546 (pdch_ul_controller.c:131)[0;m [0;33m20251222125456806 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m PDCH(bts=0,trx=0,ts=7) Reserving FN 546 for type POLL (pdch_ul_controller.c:184)[0;m [1;34m20251222125456806 [1;34mDTBFDL[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FINISHED} Scheduled DL Acknowledgement polling on PACCH (FN=546, TS=7) (tbf_dl.cpp:883)[0;m [1;34m20251222125456806 [1;34mDTBF[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FINISHED} Starting timer T3191 [final block (DL-TBF)] with 1 sec. 0 microsec, cur_fn=528 (tbf_dl.cpp:890)[0;m [1;34m20251222125456806 [1;32mDTBFDL[0;m[1;34m [1;32mINFO[0;m[1;34m TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FINISHED} Scheduled Ack/Nack polling on FN=546, TS=7 (tbf_dl.cpp:903)[0;m [1;34m20251222125456806 [1;34mDTBFDL[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FINISHED} msg block (BSN 0, CS-3): 0f 01 00 29 d9 aa 84 91 42 fd 5f d5 79 1b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 00 (tbf_dl.cpp:911)[0;m [1;32m20251222125456806 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=0,ts=7) FN=533 Sending data request: sapi=5 arfcn=871 cur_fn=528 block=3 data=0f 01 00 29 d9 aa 84 91 42 fd 5f d5 79 1b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 00 (pcu_l1_if.cpp:189)[0;m [1;32m20251222125456807 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Time indication received: 13 (pcu_l1_if.cpp:1055)[0;m [1;32m20251222125456819 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=0,ts=7) FN=533 Rx DATA.ind: sapi=5 arfcn=871 cur_fn=528 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456819 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=0,ts=7) FN=533 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456821 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=1,ts=7) FN=533 Rx DATA.ind: sapi=5 arfcn=872 cur_fn=533 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456821 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=1,ts=7) FN=533 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456821 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=2,ts=7) FN=533 Rx DATA.ind: sapi=5 arfcn=873 cur_fn=533 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456821 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=2,ts=7) FN=533 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456823 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=3,ts=7) FN=533 Rx DATA.ind: sapi=5 arfcn=874 cur_fn=533 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456823 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=3,ts=7) FN=533 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456824 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=4,ts=7) FN=533 Rx DATA.ind: sapi=5 arfcn=875 cur_fn=533 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456824 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=4,ts=7) FN=533 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456825 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=5,ts=7) FN=533 Rx DATA.ind: sapi=5 arfcn=876 cur_fn=533 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456825 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=5,ts=7) FN=533 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456826 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=6,ts=7) FN=533 Rx DATA.ind: sapi=5 arfcn=877 cur_fn=533 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456826 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=6,ts=7) FN=533 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456827 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=7,ts=7) FN=533 Rx DATA.ind: sapi=5 arfcn=878 cur_fn=533 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456827 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=7,ts=7) FN=533 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456828 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Time indication received: 17 (pcu_l1_if.cpp:1055)[0;m [1;32m20251222125456838 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=0,ts=7) FN=537 Rx DATA.ind: sapi=5 arfcn=871 cur_fn=533 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456838 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=0,ts=7) FN=537 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456840 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=1,ts=7) FN=537 Rx DATA.ind: sapi=5 arfcn=872 cur_fn=537 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456840 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=1,ts=7) FN=537 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456841 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=2,ts=7) FN=537 Rx DATA.ind: sapi=5 arfcn=873 cur_fn=537 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456841 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=2,ts=7) FN=537 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456842 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=3,ts=7) FN=537 Rx DATA.ind: sapi=5 arfcn=874 cur_fn=537 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456842 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=3,ts=7) FN=537 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456843 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=4,ts=7) FN=537 Rx DATA.ind: sapi=5 arfcn=875 cur_fn=537 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456844 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=4,ts=7) FN=537 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456844 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=5,ts=7) FN=537 Rx DATA.ind: sapi=5 arfcn=876 cur_fn=537 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456845 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=5,ts=7) FN=537 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456845 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=6,ts=7) FN=537 Rx DATA.ind: sapi=5 arfcn=877 cur_fn=537 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456845 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=6,ts=7) FN=537 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456846 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=7,ts=7) FN=537 Rx DATA.ind: sapi=5 arfcn=878 cur_fn=537 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456846 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=7,ts=7) FN=537 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456847 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Time indication received: 21 (pcu_l1_if.cpp:1055)[0;m [1;32m20251222125456857 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=0,ts=7) FN=541 Rx DATA.ind: sapi=5 arfcn=871 cur_fn=537 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456857 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=0,ts=7) FN=541 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456859 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=1,ts=7) FN=541 Rx DATA.ind: sapi=5 arfcn=872 cur_fn=541 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456859 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=1,ts=7) FN=541 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456860 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=2,ts=7) FN=541 Rx DATA.ind: sapi=5 arfcn=873 cur_fn=541 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456860 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=2,ts=7) FN=541 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456861 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=3,ts=7) FN=541 Rx DATA.ind: sapi=5 arfcn=874 cur_fn=541 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456861 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=3,ts=7) FN=541 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456862 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=4,ts=7) FN=541 Rx DATA.ind: sapi=5 arfcn=875 cur_fn=541 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456862 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=4,ts=7) FN=541 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456862 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=5,ts=7) FN=541 Rx DATA.ind: sapi=5 arfcn=876 cur_fn=541 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456862 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=5,ts=7) FN=541 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456863 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=6,ts=7) FN=541 Rx DATA.ind: sapi=5 arfcn=877 cur_fn=541 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456863 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=6,ts=7) FN=541 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456864 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=7,ts=7) FN=541 Rx DATA.ind: sapi=5 arfcn=878 cur_fn=541 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456864 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=7,ts=7) FN=541 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456866 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Time indication received: 26 (pcu_l1_if.cpp:1055)[0;m [1;32m20251222125456881 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=0,ts=7) FN=546 Rx DATA.ind: sapi=5 arfcn=871 cur_fn=541 block=6 data=40 08 10 20 00 00 00 00 00 00 00 20 00 00 00 2b 2b 2b 2b 2b 2b 2b 2b (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456881 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=0,ts=7) FN=546 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 0 (pcu_l1_if.cpp:485)[0;m [1;36m20251222125456881 [1;34mDRLCMACUL[0;m[1;36m [1;34mDEBUG[0;m[1;36m PDCH(bts=0,trx=0,ts=7) Got RLC block, coding scheme: CS-1, length: 23 (23)) (pdch.cpp:983)[0;m [0;33m20251222125456881 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m PDCH(bts=0,trx=0,ts=7) FN=546 +++++++++++++++++++++++++ RX : Uplink Control Block +++++++++++++++++++++++++ (pdch.cpp:914)[0;m [1;31m20251222125456881 [1;32mDCSN1[0;m[1;31m [1;32mINFO[0;m[1;31m csnStreamDecoder (type: Pkt DL ACK/NACK (2)): [0;m[1;31mPayloadType = 1 | [0;m[1;31mspare = 0 | [0;m[1;31mR = 0 | [0;m[1;31mMESSAGE_TYPE = 2 | [0;m[1;31mDOWNLINK_TFI = 0 | [0;m[1;31m : Ack_Nack_Description | [0;m[1;31mFINAL_ACK_INDICATION = 1 | [0;m[1;31mSTARTING_SEQUENCE_NUMBER = 1 | [0;m[1;31mRECEIVED_BLOCK_BITMAP[0] = 0 | [0;m[1;31mRECEIVED_BLOCK_BITMAP[1] = 0 | [0;m[1;31mRECEIVED_BLOCK_BITMAP[2] = 0 | [0;m[1;31mRECEIVED_BLOCK_BITMAP[3] = 0 | [0;m[1;31mRECEIVED_BLOCK_BITMAP[4] = 0 | [0;m[1;31mRECEIVED_BLOCK_BITMAP[5] = 0 | [0;m[1;31mRECEIVED_BLOCK_BITMAP[6] = 0 | [0;m[1;31mRECEIVED_BLOCK_BITMAP[7] = 1 | [0;m[1;31m: End Ack_Nack_Description | [0;m[1;31mExist_Channel_Request_Description = 0 | [0;m[1;31m : Channel_Quality_Report | [0;m[1;31mC_VALUE = 0 | [0;m[1;31mRXQUAL = 0 | [0;m[1;31mSIGN_VAR = 0 | [0;m[1;31mSlot[0].Exist = 0 | [0;m[1;31mSlot[1].Exist = 0 | [0;m[1;31mSlot[2].Exist = 0 | [0;m[1;31mSlot[3].Exist = 0 | [0;m[1;31mSlot[4].Exist = 0 | [0;m[1;31mSlot[5].Exist = 0 | [0;m[1;31mSlot[6].Exist = 0 | [0;m[1;31mSlot[7].Exist = 0 | [0;m[1;31m: End Channel_Quality_Report | [0;m[1;31mExist_AdditionsR99 = 0 | [0;m[1;31mPadding = [0;m[1;31m0|[0;m[1;31m43|[0;m[1;31m43|[0;m[1;31m43|[0;m[1;31m43|[0;m[1;31m43|[0;m[1;31m43|[0;m[1;31m43|[0;m[1;31m43|[0;m[1;31m (gsm_rlcmac.c:5472)[0;m [0;33m20251222125456882 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m PDCH(bts=0,trx=0,ts=7) FN=546 ------------------------- RX : Uplink Control Block ------------------------- (pdch.cpp:927)[0;m [1;34m20251222125456882 [1;34mDTBF[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FINISHED} RX: [PCU <- BTS] Packet Downlink Ack/Nack (pdch.cpp:501)[0;m [0;33m20251222125456882 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m PDCH(bts=0,trx=0,ts=7) Got GPRS DL ACK bitmap: SSN: 1, BSN 0 to 1 - 1 (1 blocks), "R" (pdch.cpp:511)[0;m [1;34m20251222125456882 [1;34mDTBFDL[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FINISHED} downlink acknowledge (tbf_dl.cpp:1085)[0;m [1;34m20251222125456882 [1;34mDTBF[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FINISHED} stopping timer T3191 [ACK/NACK received] (tbf.cpp:377)[0;m BTS(202)@b4f2b99cd73c: Shutting down virtual BTS #0... [1;34m20251222125456882 [1;34mDTBFDL[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FINISHED} ack: (BSN=0)"R"(BSN=0) R=ACK I=NACK (tbf_dl.cpp:1034)[0;m [1;34m20251222125456882 [1;34mDTBFDL[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FINISHED} DL analysis, range=0:1, lost=0, recv=1, skipped=0, bsn=0, info='R...............................................................' (tbf_dl.cpp:998)[0;m [1;33m20251222125456882 [1;34mDRLCMACDL[0;m[1;33m [1;34mDEBUG[0;m[1;33m - got ack for BSN=0 (rlc_window_dl.cpp:103)[0;m [1;31m20251222125456882 [1;34mDRLCMACMEAS[0;m[1;31m [1;34mDEBUG[0;m[1;31m TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FINISHED} DL Loss: Received: 1 Lost: 0 Sum: 1 (gprs_rlcmac_meas.cpp:127)[0;m [1;34m20251222125456882 [1;34mDTBFDL[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FINISHED} V(B): (V(A)=1)""(V(S)-1=0) A=Acked N=Nacked U=Unacked X=Resend-Unacked I=Invalid (tbf_dl.cpp:1058)[0;m [1;34m20251222125456882 [1;34mDTBFDL[0;m[1;34m [1;34mDEBUG[0;m[1;34m TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FINISHED} Final ACK received. (tbf_dl.cpp:1105)[0;m [1;34m20251222125456882 [1;32mDTBFDL[0;m[1;34m [1;32mINFO[0;m[1;34m DL_TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FINISHED}: Received Event FINAL_ACK_RECVD (tbf_dl.cpp:1076)[0;m [1;34m20251222125456882 [1;32mDTBFDL[0;m[1;34m [1;32mINFO[0;m[1;34m DL_TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){FINISHED}: state_chg to WAIT_RELEASE (tbf_dl_fsm.c:264)[0;m [1;34m20251222125456882 [1;32mDTBFDL[0;m[1;34m [1;32mINFO[0;m[1;34m TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){WAIT_RELEASE} unset ass. type CCCH [prev CCCH:1, PACCH:0] (tbf_dl_fsm.c:67)[0;m [1;32m20251222125456882 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=1,ts=7) FN=546 Rx DATA.ind: sapi=5 arfcn=872 cur_fn=546 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456882 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=1,ts=7) FN=546 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456883 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=2,ts=7) FN=546 Rx DATA.ind: sapi=5 arfcn=873 cur_fn=546 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456883 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=2,ts=7) FN=546 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456884 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=3,ts=7) FN=546 Rx DATA.ind: sapi=5 arfcn=874 cur_fn=546 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456884 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=3,ts=7) FN=546 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456885 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=4,ts=7) FN=546 Rx DATA.ind: sapi=5 arfcn=875 cur_fn=546 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456885 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=4,ts=7) FN=546 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m 20251222125456885 [1;32mDLGLOBAL[0;m [1;32mINFO[0;m Closing telnet connection r=127.0.0.1:53894<->l=127.0.0.1:4240 (telnet_interface.c:138) [1;32m20251222125456886 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=5,ts=7) FN=546 Rx DATA.ind: sapi=5 arfcn=876 cur_fn=546 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456886 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=5,ts=7) FN=546 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456887 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=6,ts=7) FN=546 Rx DATA.ind: sapi=5 arfcn=877 cur_fn=546 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456887 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=6,ts=7) FN=546 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m [1;32m20251222125456888 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m (bts=0,trx=7,ts=7) FN=546 Rx DATA.ind: sapi=5 arfcn=878 cur_fn=546 block=0 data= (pcu_l1_if.cpp:470)[0;m [1;32m20251222125456888 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m PDCH(bts=0,trx=7,ts=7) FN=546 Rx DATA.ind PDTCH: BER10k = 0, BTO = 0, Q = 10 (pcu_l1_if.cpp:485)[0;m 20251222125456892 [1;32mDLGLOBAL[0;m [1;32mINFO[0;m Closing telnet connection r=127.0.0.1:53880<->l=127.0.0.1:4240 (telnet_interface.c:138) [1;32m20251222125456894 [1;31mDL1IF[0;m[1;32m [1;31mERROR[0;m[1;32m pcu_sock_read: recv() failed with rc=0 errno=9 (pcuif_sock.c:123)[0;m [1;32m20251222125456894 [1;33mDL1IF[0;m[1;32m [1;33mNOTICE[0;m[1;32m PCU socket has LOST connection (pcuif_sock.c:78)[0;m [0;33m20251222125456894 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=0,ts=7) PDCH state: enabled => disabled (pdch.cpp:184)[0;m [1;34m20251222125456894 [1;32mDTBF[0;m[1;34m [1;32mINFO[0;m[1;34m TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){WAIT_RELEASE} free (tbf.cpp:271)[0;m [0;33m20251222125456894 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=0,ts=7) Detaching TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){WAIT_RELEASE}, 1 TBFs, USFs = 00, TFIs = 00000001. (pdch.cpp:1202)[0;m [1;34m20251222125456894 [1;32mDMS[0;m[1;34m [1;32mINFO[0;m[1;34m MS(IMSI-262420000000001:TLLI-0x00000001:TA-220:MSCLS-0-0:DL) Detaching TBF: TBF(DL:G:IMSI-262420000000001:TLLI-0x00000001){WAIT_RELEASE} (gprs_ms.c:381)[0;m [1;34m20251222125456894 [1;32mDMS[0;m[1;34m [1;32mINFO[0;m[1;34m MS(IMSI-262420000000001:TLLI-0x00000001:TA-220:MSCLS-0-0): - tbf: now used by 0 (-) (gprs_ms.c:394)[0;m [1;34m20251222125456894 [1;32mDMS[0;m[1;34m [1;32mINFO[0;m[1;34m MS(IMSI-262420000000001:TLLI-0x00000001:TA-220:MSCLS-0-0) Schedule MS release in 60 secs (gprs_ms.c:246)[0;m [1;34m20251222125456894 [1;34mDTBF[0;m[1;34m [1;34mDEBUG[0;m[1;34m ********** DL-TBF ends here ********** (tbf.cpp:279)[0;m [1;34m20251222125456894 [1;32mDTBFDL[0;m[1;34m [1;32mINFO[0;m[1;34m DL_TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){WAIT_RELEASE}: Deallocated (fsm.c:568)[0;m [1;34m20251222125456894 [1;32mDTBF[0;m[1;34m [1;32mINFO[0;m[1;34m UL_ASS_TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){NONE}: Deallocated (fsm.c:568)[0;m [1;34m20251222125456894 [1;32mDTBF[0;m[1;34m [1;32mINFO[0;m[1;34m DL_ASS_TBF(DL:TFI-0-0-0:G:IMSI-262420000000001:TLLI-0x00000001){NONE}: Deallocated (fsm.c:568)[0;m [0;33m20251222125456894 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=1,ts=7) PDCH state: enabled => disabled (pdch.cpp:184)[0;m [0;33m20251222125456894 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=2,ts=7) PDCH state: enabled => disabled (pdch.cpp:184)[0;m [0;33m20251222125456894 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=3,ts=7) PDCH state: enabled => disabled (pdch.cpp:184)[0;m [0;33m20251222125456894 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=4,ts=7) PDCH state: enabled => disabled (pdch.cpp:184)[0;m [0;33m20251222125456894 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=5,ts=7) PDCH state: enabled => disabled (pdch.cpp:184)[0;m [0;33m20251222125456894 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=6,ts=7) PDCH state: enabled => disabled (pdch.cpp:184)[0;m [0;33m20251222125456894 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=7,ts=7) PDCH state: enabled => disabled (pdch.cpp:184)[0;m [38;5;57m20251222125456894 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(01234) NS-STATUS.ind(bvci=00000): cause=NSE failure, transfer=0, first=0, mtu=65523 (gprs_ns2.c:575)[0;m [1;35m20251222125456894 [1;33mDPCU[0;m[1;35m [1;33mNOTICE[0;m[1;35m NS-NSE 1234 became unavailable (gprs_bssgp_pcu.c:690)[0;m [38;5;57m20251222125456894 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(01234)-NSVC(01234) NS-STATUS.ind(bvci=00000): cause=NSVC failure, transfer=0, first=0, mtu=65523 (gprs_ns2.c:571)[0;m [1;35m20251222125456894 [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;57m20251222125456894 [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;57m20251222125456895 [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;57m20251222125456895 [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 respawn: 21: stopped pid 8220 with status 0 MTC@b4f2b99cd73c: Test case TC_zero_x2031_t3191 finished. Verdict: pass MTC@b4f2b99cd73c: Starting external command `/home/osmocom-build/jenkins/workspace/ttcn3-pcu-test-latest/ttcn3-tcpdump-stop.sh PCU_Tests.TC_zero_x2031_t3191 pass'. respawn: 22: starting: osmo-pcu Mon Dec 22 12:54:56 UTC 2025 [1;32m====== PCU_Tests.TC_zero_x2031_t3191 pass ======[0m Saving talloc report from 127.0.0.1:4240 to PCU_Tests.TC_zero_x2031_t3191.talloc 20251222125456943 [1;33mDLGLOBAL[0;m [1;33mNOTICE[0;m Setting up GSMTAP Um forwarding to '127.0.0.1:4729' (pcu_main.cpp:298) 20251222125456944 [1;33mDLGLOBAL[0;m [1;33mNOTICE[0;m Available via telnet 127.0.0.1 4240 (telnet_interface.c:88) [1;32m20251222125456944 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m Opening OsmoPCU L1 interface v12 to OsmoBTS/OsmoBSC (pcuif_sock.c:202)[0;m [1;32m20251222125456944 [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:210)[0;m 20251222125456973 [1;32mDLGLOBAL[0;m [1;32mINFO[0;m Accept()ed new telnet connection r=127.0.0.1:53900<->l=127.0.0.1:4240 (telnet_interface.c:192) 20251222125457074 [1;32mDLGLOBAL[0;m [1;32mINFO[0;m Closing telnet connection r=127.0.0.1:53900<->l=127.0.0.1:4240 (telnet_interface.c:138) Waiting for packet dumper to finish... 0 (prev_count=-1, count=837076) Waiting for packet dumper to finish... 1 (prev_count=837076, count=851424) 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/jenkins3602645729785274613.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 FATAL: Channel "hudson.remoting.Channel@51452f67: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@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.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

1 0

Build failed in Jenkins: Osmocom_OBS_master #124237
by jenkins＠lists.osmocom.org 22 Dec '25

22 Dec '25

See <https://jenkins.osmocom.org/jenkins/job/Osmocom_OBS_master/124237/display/r…> Changes: ------------------------------------------ Started by upstream project "master-gapk" build number 2769 originally caused by: Started by timer Running as SYSTEM Building remotely on build5-deb12build-ansible (obs ttcn3_with_linux_6.1_or_higher qemu registry-build-amd64 ttcn3 osmo-gsm-tester-build ttcn3-ggsn-test-kernel-git io_uring osmocom-gerrit coverity osmocom-master) in workspace <https://jenkins.osmocom.org/jenkins/job/Osmocom_OBS_master/ws/> The recommended git tool is: NONE No credentials specified Wiping out workspace first. Cloning the remote Git repository Cloning repository https://gerrit.osmocom.org/osmo-ci > git init <https://jenkins.osmocom.org/jenkins/job/Osmocom_OBS_master/ws/> # timeout=10 Fetching upstream changes from https://gerrit.osmocom.org/osmo-ci > git --version # timeout=10 > git --version # 'git version 2.39.5' > git fetch --tags --force --progress -- https://gerrit.osmocom.org/osmo-ci +refs/heads/*:refs/remotes/origin/* # timeout=10 > git config remote.origin.url https://gerrit.osmocom.org/osmo-ci # timeout=10 > git config --add remote.origin.fetch +refs/heads/*:refs/remotes/origin/* # timeout=10 Avoid second fetch > git rev-parse origin/master^{commit} # timeout=10 Checking out Revision bb15a95e3843864b853e6d1747b82b08556a1923 (origin/master) > git config core.sparsecheckout # timeout=10 > git checkout -f bb15a95e3843864b853e6d1747b82b08556a1923 # timeout=10 Commit message: "jobs/ttcn3-testsuites: set RUN_BPFTRACE=1" > git rev-list --no-walk bb15a95e3843864b853e6d1747b82b08556a1923 # timeout=10 [Osmocom_OBS_master] $ /bin/sh -xe /tmp/jenkins9939039595080972234.sh + export PYTHONUNBUFFERED=1 + ./scripts/obs/update_obs_project.py --apiurl https://obs.osmocom.org --conflict-version --docker --feed master --meta osmocom:master docker: building image debian-13-osmocom-obs-build-srcpkg + run_in_docker_and_exit(): ['docker', 'build', '--build-arg', 'DISTRO=debian:13', '--build-arg', 'DISTRO_FROM=debian:trixie', '--build-arg', 'UID=1000', '-t', 'debian-13-osmocom-obs-build-srcpkg', '-f', '<https://jenkins.osmocom.org/jenkins/job/Osmocom_OBS_master/ws/scripts/obs/d…',> '<https://jenkins.osmocom.org/jenkins/job/Osmocom_OBS_master/ws/scripts/obs/d…']> #0 building with "default" instance using docker driver #1 [internal] load build definition from build_srcpkg.Dockerfile #1 transferring dockerfile: 1.45kB done #1 WARN: InvalidDefaultArgInFrom: Default value for ARG ${DISTRO_FROM} results in empty or invalid base image name (line 3) #1 DONE 0.1s #2 [internal] load metadata for docker.io/library/debian:trixie #2 DONE 0.4s #3 [internal] load .dockerignore #3 transferring context: 2B done #3 DONE 0.1s #4 [1/4] FROM docker.io/library/debian:trixie@sha256:0d01188e8dd0ac63bf155900fad49279131a876a1ea7fac917c62e87ccb2732d #4 DONE 0.0s #5 [2/4] RUN APT-GET update && apt-get upgrade -y && apt-get install -y --no-install-recommends ca-certificates colordiff debhelper default-libmysqlclient-dev dh-python dh-virtualenv dpkg-dev erlang-nox fakeroot git git-review gnupg2 libxml2-utils lsb-release meson osc pkgconf python3-packaging python3-pip python3-setuptools quilt sed sphinx-common wget && apt-get clean #5 CACHED #6 [3/4] RUN WGET https://github.com/erlang/rebar3/releases/download/3.24.0/rebar3 -O /usr/bin/rebar3 && echo "d2d31cfb98904b8e4917300a75f870de12cb5167cd6214d1043e973a56668a54 /usr/bin/rebar3" | sha256sum -c && chmod +x /usr/bin/rebar3 && rebar3 --version #6 CACHED #7 [4/4] RUN USERADD --uid=1000 -m user #7 CACHED #8 exporting to image #8 exporting layers done #8 writing image sha256:6c263b1ef9ecdf31472824a0f1d0634380b59e435d64a36c5721304914148c9e done #8 naming to docker.io/library/debian-13-osmocom-obs-build-srcpkg done #8 DONE 0.0s [33m1 warning found (use docker --debug to expand): [0m - InvalidDefaultArgInFrom: Default value for ARG ${DISTRO_FROM} results in empty or invalid base image name (line 3) docker: running: update_obs_project.py inside docker /obs/lib/debian.py:183: SyntaxWarning: invalid escape sequence '\/' lib.run_cmd(["sed", "-i", "s/$(MAKE) -C doc\/manuals//g", f"{debian}/rules"]) OBS: getting packages in osmocom:master ERROR: command failed unexpectedly *** command *** ['osc', '-A', 'https://obs.osmocom.org', 'list', 'osmocom:master'] *** returncode *** 1 *** output *** fatal: not a git repository (or any of the parent directories): .git SSL Error: [SSL: UNEXPECTED_EOF_WHILE_READING] EOF occurred in violation of protocol (_ssl.c:1029) *** python trace *** Traceback (most recent call last): File "/obs/update_obs_project.py", line 265, in <module> main() ~~~~^^ File "/obs/update_obs_project.py", line 257, in main pkgs_remote = lib.osc.get_remote_pkgs() File "/obs/lib/osc.py", line 63, in get_remote_pkgs ret = run_osc(["list", proj]) File "/obs/lib/osc.py", line 58, in run_osc return lib.run_cmd(cmd, *args, **kwargs) ~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^ File "/obs/lib/__init__.py", line 202, in run_cmd exit_error_cmd(p, "command failed unexpectedly") ~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/obs/lib/__init__.py", line 163, in exit_error_cmd raise RuntimeError("shell command related error, find details right above" " this python trace") RuntimeError: shell command related error, find details right above this python trace Build step 'Execute shell' marked build as failure

1 1

Build failed in Jenkins: ttcn3-ggsn-test-kernel-latest-net-next #1771
by jenkins＠lists.osmocom.org 22 Dec '25

22 Dec '25

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-ggsn-test-kernel-latest-net-n…> Changes: [Pau Espin Pedrol] library/NGAP_Templates: PDUSessionResourceSetupRequest: Fix criticality ------------------------------------------ Started by timer Running as SYSTEM Building remotely on build5-deb12build-ansible (obs ttcn3_with_linux_6.1_or_higher qemu registry-build-amd64 ttcn3 osmo-gsm-tester-build ttcn3-ggsn-test-kernel-git io_uring osmocom-gerrit coverity osmocom-master) in workspace /home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-kernel-latest-net-next The recommended git tool is: NONE No credentials specified > git rev-parse --resolve-git-dir /home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-kernel-latest-net-next/.git # timeout=10 Fetching changes from the remote Git repository > git config remote.origin.url https://gerrit.osmocom.org/osmo-ttcn3-hacks # timeout=10 Fetching upstream changes from https://gerrit.osmocom.org/osmo-ttcn3-hacks > git --version # timeout=10 > git --version # 'git version 2.39.5' > git fetch --tags --force --progress -- https://gerrit.osmocom.org/osmo-ttcn3-hacks +refs/heads/*:refs/remotes/origin/* # timeout=10 > git rev-parse origin/master^{commit} # timeout=10 Checking out Revision da2bd77f8ddc55e7e27fdb59b7492c967e7d9a4a (origin/master) > git config core.sparsecheckout # timeout=10 > git checkout -f da2bd77f8ddc55e7e27fdb59b7492c967e7d9a4a # timeout=10 Commit message: "library/NGAP_Templates: PDUSessionResourceSetupRequest: Fix criticality of SetupList IE" > git rev-list --no-walk fc22df8f4e54ed26a7b9878f032eb47830e2e3d4 # timeout=10 FATAL: Failed to copy file:/var/jenkins_home/jobs/build-kernel-net-next/builds/454/archive/output/linux to /home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-kernel-latest-net-next/linux java.io.IOException at hudson.remoting.Channel.close(Channel.java:1621) at hudson.remoting.Channel.close(Channel.java:1574) at hudson.slaves.SlaveComputer.closeChannel(SlaveComputer.java:949) at hudson.slaves.SlaveComputer$2.run(SlaveComputer.java:823) at jenkins.util.ContextResettingExecutorService$1.run(ContextResettingExecutorService.java:28) at jenkins.security.ImpersonatingExecutorService$1.run(ImpersonatingExecutorService.java:68) at jenkins.util.ErrorLoggingExecutorService.lambda$wrap$0(ErrorLoggingExecutorService.java:51) at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Unknown Source) at java.base/java.util.concurrent.FutureTask.run(Unknown Source) at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) at java.base/java.lang.Thread.run(Unknown Source) Also: <cycle to java.io.IOException> Caused: hudson.remoting.ChannelClosedException: Channel "hudson.remoting.Channel@e659bf2:build5-deb12build-ansible": channel is already closed at hudson.remoting.Channel.send(Channel.java:827) at hudson.remoting.ProxyOutputStream.doClose(ProxyOutputStream.java:183) at hudson.remoting.ProxyOutputStream.error(ProxyOutputStream.java:177) at hudson.remoting.ProxyOutputStream.close(ProxyOutputStream.java:167) at hudson.remoting.RemoteOutputStream.close(RemoteOutputStream.java:123) at PluginClassLoader for copyartifact//hudson.plugins.copyartifact.CopyArtifact.copyOne(CopyArtifact.java:756) Caused: hudson.remoting.ChannelClosedException: Channel "hudson.remoting.Channel@e659bf2:build5-deb12build-ansible": channel is already closed at hudson.remoting.Channel.send(Channel.java:827) at hudson.remoting.ProxyOutputStream.write(ProxyOutputStream.java:148) at hudson.remoting.RemoteOutputStream.write(RemoteOutputStream.java:113) at java.base/java.security.DigestOutputStream.write(Unknown Source) at org.apache.commons.io.IOUtils.copyLarge(IOUtils.java:1487) at org.apache.commons.io.IOUtils.copy(IOUtils.java:1111) at org.apache.commons.io.IOUtils.copyLarge(IOUtils.java:1459) at org.apache.commons.io.IOUtils.copy(IOUtils.java:1089) at PluginClassLoader for copyartifact//hudson.plugins.copyartifact.CopyArtifact.copyOne(CopyArtifact.java:757) Caused: java.io.IOException: Failed to copy file:/var/jenkins_home/jobs/build-kernel-net-next/builds/454/archive/output/linux to /home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-kernel-latest-net-next/linux at PluginClassLoader for copyartifact//hudson.plugins.copyartifact.CopyArtifact.copyOne(CopyArtifact.java:779) at PluginClassLoader for copyartifact//hudson.plugins.copyartifact.CopyArtifact.copy(CopyArtifact.java:719) at PluginClassLoader for copyartifact//hudson.plugins.copyartifact.CopyArtifact.perform(CopyArtifact.java:679) at PluginClassLoader for copyartifact//hudson.plugins.copyartifact.CopyArtifact.perform(CopyArtifact.java:563) at jenkins.tasks.SimpleBuildStep.perform(SimpleBuildStep.java:123) at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:80) 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) ERROR: Step ‘Publish JUnit test result report’ failed: no workspace for ttcn3-ggsn-test-kernel-latest-net-next #1771

1 0

Build failed in Jenkins: ttcn3-hlr-test-pyhss-latest #48
by jenkins＠lists.osmocom.org 22 Dec '25

22 Dec '25

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-hlr-test-pyhss-latest/48/disp…> Changes: [Pau Espin Pedrol] library/NGAP_Templates: PDUSessionResourceSetupRequest: Fix criticality ------------------------------------------ [...truncated 695.46 KiB...] HLR_Test-GSUP-IPA(93)@e3639b7046f3: Final verdict of PTC: none EUSE-TC_mo_ussd_euse_continue(92)@e3639b7046f3: Final verdict of PTC: none HLR_Test-GSUP(95)@e3639b7046f3: Final verdict of PTC: fail reason: "GSUP Connection Lost" TC_mo_ussd_euse_continue(96)@e3639b7046f3: Final verdict of PTC: none MTC@e3639b7046f3: Setting final verdict of the test case. MTC@e3639b7046f3: Local verdict of MTC: none MTC@e3639b7046f3: Local verdict of PTC EUSE-TC_mo_ussd_euse_continue(92): none (none -> none) MTC@e3639b7046f3: Local verdict of PTC HLR_Test-GSUP-IPA(93): none (none -> none) MTC@e3639b7046f3: Local verdict of PTC IPA-EUSE-foobar(94): none (none -> none) MTC@e3639b7046f3: Local verdict of PTC HLR_Test-GSUP(95): fail (none -> fail) reason: "GSUP Connection Lost" MTC@e3639b7046f3: Local verdict of PTC TC_mo_ussd_euse_continue(96): none (fail -> fail) MTC@e3639b7046f3: Test case TC_mo_ussd_euse_continue finished. Verdict: fail reason: GSUP Connection Lost MTC@e3639b7046f3: Starting external command `/home/osmocom-build/jenkins/workspace/ttcn3-hlr-test-pyhss-latest/ttcn3-tcpdump-stop.sh HLR_Tests.TC_mo_ussd_euse_continue fail'. Mon Dec 22 12:12:25 UTC 2025 [1;31m------ HLR_Tests.TC_mo_ussd_euse_continue fail ------[0m Waiting for packet dumper to finish... 0 (prev_count=-1, count=208) Waiting for packet dumper to finish... 1 (prev_count=208, count=20828) MTC@e3639b7046f3: External command `/home/osmocom-build/jenkins/workspace/ttcn3-hlr-test-pyhss-latest/ttcn3-tcpdump-stop.sh HLR_Tests.TC_mo_ussd_euse_continue fail' was executed successfully (exit status: 0). MTC@e3639b7046f3: Starting external command `/home/osmocom-build/jenkins/workspace/ttcn3-hlr-test-pyhss-latest/ttcn3-tcpdump-start.sh HLR_Tests.TC_mo_ussd_euse_defaultroute'. ------ HLR_Tests.TC_mo_ussd_euse_defaultroute ------ Mon Dec 22 12:12:27 UTC 2025 /usr/bin/dumpcap -q -s 1520 -n -i any -w "/home/osmocom-build/jenkins/workspace/ttcn3-hlr-test-pyhss-latest/logs/testsuite/HLR_Tests.TC_mo_ussd_euse_defaultroute.pcap" >/home/osmocom-build/jenkins/workspace/ttcn3-hlr-test-pyhss-latest/logs/testsuite/HLR_Tests.TC_mo_ussd_euse_defaultroute.pcap.stdout 2>/tmp/cmderr & Waiting for packet dumper to start... 0 MTC@e3639b7046f3: External command `/home/osmocom-build/jenkins/workspace/ttcn3-hlr-test-pyhss-latest/ttcn3-tcpdump-start.sh HLR_Tests.TC_mo_ussd_euse_defaultroute' was executed successfully (exit status: 0). MTC@e3639b7046f3: Test case TC_mo_ussd_euse_defaultroute started. MTC@e3639b7046f3: legacy= false MTC@e3639b7046f3: in not legacy case 1 MTC@e3639b7046f3: in not legacy case 2 [GSUP] [DEBUG] New connection from 127.0.0.1:41791. IPA-EUSE-foobar(99)@e3639b7046f3: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } [GSUP] [ERROR] Client message received without known identity 127.0.0.1:41791 [GSUP] [DEBUG] New connection from 127.0.0.1:9999. IPA-EUSE-foobar(99)@e3639b7046f3: CCM Rx:{ msg_type := IPAC_MSGT_ID_GET (4), u := { get := { { len := 1, tag := IPAC_IDTAG_UNITID (8) }, { len := 1, tag := IPAC_IDTAG_MACADDR (7) }, { len := 1, tag := IPAC_IDTAG_LOCATION2 (3) }, { len := 1, tag := IPAC_IDTAG_SWVERSION (5) }, { len := 1, tag := IPAC_IDTAG_LOCATION1 (2) }, { len := 1, tag := IPAC_IDTAG_UNITNAME (1) } } } } HLR_Test-GSUP-IPA(98)@e3639b7046f3: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } IPA-EUSE-foobar(99)@e3639b7046f3: CCM Tx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 0, tag := IPAC_IDTAG_UNITID (8), data := '302F312F3200'O }, { len := 0, tag := IPAC_IDTAG_MACADDR (7), data := '00'O }, { len := 0, tag := IPAC_IDTAG_LOCATION2 (3), data := '00'O }, { len := 0, tag := IPAC_IDTAG_SWVERSION (5), data := '00'O }, { len := 0, tag := IPAC_IDTAG_LOCATION1 (2), data := '00'O }, { len := 0, tag := IPAC_IDTAG_UNITNAME (1), data := '4F736D6F636F6D205454434E2D3320455553452053696D756C61746F7200'O } } } } [GSUP] [ERROR] 127.0.0.1:41791: Role not found in tags. 'sgsn' or 'msc' must appear in one of there tags: TYPE, UNIT, UNITNAME. Closing connection. [GSUP] [ERROR] Client message received without known identity 127.0.0.1:9999 MTC@e3639b7046f3: Dynamic test case error: Port VTY has neither connections nor mappings. Message cannot be sent on it. MTC@e3639b7046f3: setverdict(error): none -> error IPA-EUSE-foobar(99)@e3639b7046f3: IPA: Closed HLR_Test-GSUP-IPA(98)@e3639b7046f3: CCM Rx:{ msg_type := IPAC_MSGT_ID_GET (4), u := { get := { { len := 1, tag := IPAC_IDTAG_UNITID (8) }, { len := 1, tag := IPAC_IDTAG_MACADDR (7) }, { len := 1, tag := IPAC_IDTAG_LOCATION2 (3) }, { len := 1, tag := IPAC_IDTAG_SWVERSION (5) }, { len := 1, tag := IPAC_IDTAG_LOCATION1 (2) }, { len := 1, tag := IPAC_IDTAG_UNITNAME (1) } } } } EUSE-TC_mo_ussd_euse_defaultroute(97)@e3639b7046f3: Final verdict of PTC: none HLR_Test-GSUP(100)@e3639b7046f3: Final verdict of PTC: none IPA-EUSE-foobar(99)@e3639b7046f3: Final verdict of PTC: none HLR_Test-GSUP-IPA(98)@e3639b7046f3: CCM Tx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 0, tag := IPAC_IDTAG_UNITID (8), data := '302F312F3200'O }, { len := 0, tag := IPAC_IDTAG_MACADDR (7), data := '00'O }, { len := 0, tag := IPAC_IDTAG_LOCATION2 (3), data := '00'O }, { len := 0, tag := IPAC_IDTAG_SWVERSION (5), data := '00'O }, { len := 0, tag := IPAC_IDTAG_LOCATION1 (2), data := '00'O }, { len := 0, tag := IPAC_IDTAG_UNITNAME (1), data := '4F736D6F636F6D205454434E2D3320475355502053696D756C61746F7220284D53432900'O } } } } [GSUP] [INFO] New peer connected: [127.0.0.1:9999 (MSC)] [GSUP] [INFO] GSUP Client disconnected: 127.0.0.1:9999 HLR_Test-GSUP-IPA(98)@e3639b7046f3: Final verdict of PTC: none MTC@e3639b7046f3: Setting final verdict of the test case. MTC@e3639b7046f3: Local verdict of MTC: error MTC@e3639b7046f3: Local verdict of PTC EUSE-TC_mo_ussd_euse_defaultroute(97): none (error -> error) MTC@e3639b7046f3: Local verdict of PTC HLR_Test-GSUP-IPA(98): none (error -> error) MTC@e3639b7046f3: Local verdict of PTC IPA-EUSE-foobar(99): none (error -> error) MTC@e3639b7046f3: Local verdict of PTC HLR_Test-GSUP(100): none (error -> error) MTC@e3639b7046f3: Test case TC_mo_ussd_euse_defaultroute finished. Verdict: error MTC@e3639b7046f3: Starting external command `/home/osmocom-build/jenkins/workspace/ttcn3-hlr-test-pyhss-latest/ttcn3-tcpdump-stop.sh HLR_Tests.TC_mo_ussd_euse_defaultroute error'. Mon Dec 22 12:12:29 UTC 2025 [1;31m------ HLR_Tests.TC_mo_ussd_euse_defaultroute error ------[0m Waiting for packet dumper to finish... 0 (prev_count=-1, count=208) Waiting for packet dumper to finish... 1 (prev_count=208, count=8004) MTC@e3639b7046f3: External command `/home/osmocom-build/jenkins/workspace/ttcn3-hlr-test-pyhss-latest/ttcn3-tcpdump-stop.sh HLR_Tests.TC_mo_ussd_euse_defaultroute error' was executed successfully (exit status: 0). MTC@e3639b7046f3: Starting external command `/home/osmocom-build/jenkins/workspace/ttcn3-hlr-test-pyhss-latest/ttcn3-tcpdump-start.sh HLR_Tests.TC_mo_sss_reject'. ------ HLR_Tests.TC_mo_sss_reject ------ Mon Dec 22 12:12:31 UTC 2025 /usr/bin/dumpcap -q -s 1520 -n -i any -w "/home/osmocom-build/jenkins/workspace/ttcn3-hlr-test-pyhss-latest/logs/testsuite/HLR_Tests.TC_mo_sss_reject.pcap" >/home/osmocom-build/jenkins/workspace/ttcn3-hlr-test-pyhss-latest/logs/testsuite/HLR_Tests.TC_mo_sss_reject.pcap.stdout 2>/tmp/cmderr & Waiting for packet dumper to start... 0 MTC@e3639b7046f3: External command `/home/osmocom-build/jenkins/workspace/ttcn3-hlr-test-pyhss-latest/ttcn3-tcpdump-start.sh HLR_Tests.TC_mo_sss_reject' was executed successfully (exit status: 0). MTC@e3639b7046f3: Test case TC_mo_sss_reject started. MTC@e3639b7046f3: legacy= false MTC@e3639b7046f3: in not legacy case 1 MTC@e3639b7046f3: in not legacy case 2 [GSUP] [DEBUG] New connection from 127.0.0.1:9999. HLR_Test-GSUP-IPA(101)@e3639b7046f3: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } [GSUP] [ERROR] Client message received without known identity 127.0.0.1:9999 MTC@e3639b7046f3: Executing: "pyhss_api_helper.py add_subscr --imsi 262422500419881 --msisdn 491618048216 --auc-id 1 --algo 1 --ki 000102030405060708090A0B0C0D0E0F --opc 22222222222222222222222222222222" HLR_Test-GSUP-IPA(101)@e3639b7046f3: CCM Rx:{ msg_type := IPAC_MSGT_ID_GET (4), u := { get := { { len := 1, tag := IPAC_IDTAG_UNITID (8) }, { len := 1, tag := IPAC_IDTAG_MACADDR (7) }, { len := 1, tag := IPAC_IDTAG_LOCATION2 (3) }, { len := 1, tag := IPAC_IDTAG_SWVERSION (5) }, { len := 1, tag := IPAC_IDTAG_LOCATION1 (2) }, { len := 1, tag := IPAC_IDTAG_UNITNAME (1) } } } } HLR_Test-GSUP-IPA(101)@e3639b7046f3: CCM Tx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 0, tag := IPAC_IDTAG_UNITID (8), data := '302F312F3200'O }, { len := 0, tag := IPAC_IDTAG_MACADDR (7), data := '00'O }, { len := 0, tag := IPAC_IDTAG_LOCATION2 (3), data := '00'O }, { len := 0, tag := IPAC_IDTAG_SWVERSION (5), data := '00'O }, { len := 0, tag := IPAC_IDTAG_LOCATION1 (2), data := '00'O }, { len := 0, tag := IPAC_IDTAG_UNITNAME (1), data := '4F736D6F636F6D205454434E2D3320475355502053696D756C61746F7220284D53432900'O } } } } [GSUP] [INFO] New peer connected: [127.0.0.1:9999 (MSC)] HLR_Test-GSUP-IPA(101)@e3639b7046f3: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } [Database] [DEBUG] Called DeleteObj for type <class 'database.AUC'> with id 1 [Database] [DEBUG] Called GetObj for type <class 'database.AUC'> 127.0.0.1 - - [22/Dec/2025 12:12:32] "DELETE /auc/1 HTTP/1.1" 200 - JSON Data sent: {'auc_id': '1', 'ki': '000102030405060708090A0B0C0D0E0F', 'opc': '22222222222222222222222222222222', 'amf': '8000', 'sqn': '0', 'imsi': '262422500419881', 'algo': '1'} [Database] [DEBUG] Called CreateObj to create <class 'database.AUC'> with value: {'auc_id': '1', 'ki': '000102030405060708090A0B0C0D0E0F', 'opc': '22222222222222222222222222222222', 'amf': '8000', 'sqn': '0', 'imsi': '262422500419881', 'algo': '1'} 127.0.0.1 - - [22/Dec/2025 12:12:32] "PUT /auc/ HTTP/1.1" 200 - JSON Data sent: {'auc_id': '1', 'default_apn': 'internet', 'apn_list': '1,2', 'imsi': '262422500419881', 'msisdn': '491618048216'} [Database] [DEBUG] Called CreateObj to create <class 'database.SUBSCRIBER'> with value: {'auc_id': '1', 'default_apn': 'internet', 'apn_list': '1,2', 'imsi': '262422500419881', 'msisdn': '491618048216'} 127.0.0.1 - - [22/Dec/2025 12:12:32] "PUT /subscriber/ HTTP/1.1" 200 - MTC@e3639b7046f3: Result: { stdout := "DELETE http://127.0.0.1:8080/auc/1\nPUT http://127.0.0.1:8080/auc/\nPUT http://127.0.0.1:8080/subscriber/", stderr := "", code := 0 } HLR_Test-GSUP(102)@e3639b7046f3: Created GsupExpect[0] for "262422500419881" to be handled at TC_mo_sss_reject(103) [GSUP] [WARN] Unhandled GSUP message 32 from [127.0.0.1:9999 (MSC)]. Responding with error. [GSUP] [ERROR] Error handling connection: 'GsupMessageBuilder' object has no attribute 'to_bytes' trace: Traceback (most recent call last): File "/opt/venvs/pyhss/lib/python3.13/site-packages/pyhss/lib/gsup/server.py", line 107, in __handle_connection await self.__handle_gsup(peer, payload_length) File "/opt/venvs/pyhss/lib/python3.13/site-packages/pyhss/lib/gsup/server.py", line 218, in __handle_gsup await self.gsup_handler.dispatch(peer, request) File "/opt/venvs/pyhss/lib/python3.13/site-packages/pyhss/lib/gsup/request_dispatcher.py", line 71, in dispatch await self.__handle_gsup_unhandled_request(peer, request) File "/opt/venvs/pyhss/lib/python3.13/site-packages/pyhss/lib/gsup/request_dispatcher.py", line 98, in __handle_gsup_unhandled_request await self.__send_gsup_response(peer, builder) File "/opt/venvs/pyhss/lib/python3.13/site-packages/pyhss/lib/gsup/request_dispatcher.py", line 74, in __send_gsup_response data = response.to_bytes() ^^^^^^^^^^^^^^^^^ AttributeError: 'GsupMessageBuilder' object has no attribute 'to_bytes' HLR_Test-GSUP-IPA(101)@e3639b7046f3: IPA: Closed HLR_Test-GSUP(102)@e3639b7046f3: setverdict(fail): none -> fail reason: "GSUP Connection Lost", new component reason: "GSUP Connection Lost" MC@e3639b7046f3: Test Component 102 has requested to stop MTC. Terminating current testcase execution. HLR_Test-GSUP-IPA(101)@e3639b7046f3: Final verdict of PTC: none HLR_Test-GSUP(102)@e3639b7046f3: Final verdict of PTC: fail reason: "GSUP Connection Lost" TC_mo_sss_reject(103)@e3639b7046f3: Final verdict of PTC: none MTC@e3639b7046f3: Setting final verdict of the test case. MTC@e3639b7046f3: Local verdict of MTC: none MTC@e3639b7046f3: Local verdict of PTC HLR_Test-GSUP-IPA(101): none (none -> none) MTC@e3639b7046f3: Local verdict of PTC HLR_Test-GSUP(102): fail (none -> fail) reason: "GSUP Connection Lost" MTC@e3639b7046f3: Local verdict of PTC TC_mo_sss_reject(103): none (fail -> fail) MTC@e3639b7046f3: Test case TC_mo_sss_reject finished. Verdict: fail reason: GSUP Connection Lost MTC@e3639b7046f3: Starting external command `/home/osmocom-build/jenkins/workspace/ttcn3-hlr-test-pyhss-latest/ttcn3-tcpdump-stop.sh HLR_Tests.TC_mo_sss_reject fail'. Mon Dec 22 12:12:32 UTC 2025 [1;31m------ HLR_Tests.TC_mo_sss_reject fail ------[0m Waiting for packet dumper to finish... 0 (prev_count=-1, count=208) Waiting for packet dumper to finish... 1 (prev_count=208, count=17548) MTC@e3639b7046f3: External command `/home/osmocom-build/jenkins/workspace/ttcn3-hlr-test-pyhss-latest/ttcn3-tcpdump-stop.sh HLR_Tests.TC_mo_sss_reject fail' was executed successfully (exit status: 0). MC@e3639b7046f3: Test execution finished. Execution of [EXECUTE] section finished. emtc MC@e3639b7046f3: Terminating MTC. MC@e3639b7046f3: MTC terminated. MC2> exit MC@e3639b7046f3: Shutting down session. MC@e3639b7046f3: Shutdown complete. Comparing expected results '/home/osmocom-build/jenkins/workspace/ttcn3-hlr-test-pyhss-latest/hlr/expected-results.xml' against results in 'junit-xml-8810.log' -------------------- [1;32mpass[0;m HLR_Tests.TC_gsup_sai_err_invalid_imsi [1;32mpass[0;m HLR_Tests.TC_gsup_sai [1;32mpass[0;m HLR_Tests.TC_gsup_sai_num_auth_vectors [1;32mpass[0;m HLR_Tests.TC_gsup_ul_unknown_imsi [1;32mpass[0;m HLR_Tests.TC_gsup_sai_err_unknown_imsi [1;32mpass[0;m HLR_Tests.TC_gsup_ul [1;31mpass->FAIL[0;m HLR_Tests.TC_gsup_ul_subscriber_data [1;32mpass[0;m HLR_Tests.TC_gsup_purge_cs [1;32mpass[0;m HLR_Tests.TC_gsup_purge_ps [1;32mpass[0;m HLR_Tests.TC_gsup_purge_unknown [1;31mpass->FAIL[0;m HLR_Tests.TC_mo_ussd_unknown [1;31mpass->FAIL[0;m HLR_Tests.TC_mo_ussd_euse_disc [1;31mpass->FAIL[0;m HLR_Tests.TC_mo_ussd_iuse_imsi [1;31mpass->FAIL[0;m HLR_Tests.TC_mo_ussd_iuse_msisdn [1;31mpass->FAIL[0;m HLR_Tests.TC_mo_ussd_euse [1;31mpass->FAIL[0;m HLR_Tests.TC_mo_ussd_euse_continue [1;31mpass->FAIL[0;m HLR_Tests.TC_mo_ussd_euse_defaultroute [1;31mpass->FAIL[0;m HLR_Tests.TC_mo_sss_reject Summary: [1;31mpass->FAIL[0;m: 9 [1;32mpass[0;m: 9 [1;34mskip[0;m: 20 [0;94m[testenv][pyhss] Testsuite is done[0m [1;34m[testenv][pyhss] Stopping testsuite (1562865)[0m [1;34m[testenv][pyhss] Merging log files[0m [0;94m[testenv][pyhss] + ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=/home/osmocom-build/jenkins/workspace/ttcn3-hlr-test-pyhss-latest/_cache', '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TESTENV_INSTALL_DIR=/', '-e', 'TERM=dumb', '-e', 'PATH=/opt/eclipse-titan-11.1.0/bin:/home/osmocom-build/jenkins/workspace/ttcn3-hlr-test-pyhss-latest/_testenv/data/scripts:/home/osmocom-build/jenkins/workspace/ttcn3-hlr-test-pyhss-latest/_testenv/data/scripts/qemu:/home/osmocom-build/jenkins/workspace/ttcn3-hlr-test-pyhss-latest/hlr:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-e', 'PYTHONUNBUFFERED=1', '-e', 'BUILDDIR=/home/osmocom-build/jenkins/workspace/ttcn3-hlr-test-pyhss-latest/_cache/podman/titan-11.1.0-debian-trixie', '-e', 'TTCN3_DIR=/opt/eclipse-titan-11.1.0', '-t', '-w', '/home/osmocom-build/jenkins/workspace/ttcn3-hlr-test-pyhss-latest/logs/testsuite', 'testenv-hlr-pyhss-osmocom-latest-20251222-1210-f6bd7dd7-0', 'sh', '-c', '/home/osmocom-build/jenkins/workspace/ttcn3-hlr-test-pyhss-latest/log_merge.sh HLR_Tests --rm >/dev/null'][0m [1;34m[testenv][pyhss] Formatting log files[0m [0;94m[testenv][pyhss] + ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=/home/osmocom-build/jenkins/workspace/ttcn3-hlr-test-pyhss-latest/_cache', '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TESTENV_INSTALL_DIR=/', '-e', 'TERM=dumb', '-e', 'PATH=/opt/eclipse-titan-11.1.0/bin:/home/osmocom-build/jenkins/workspace/ttcn3-hlr-test-pyhss-latest/_testenv/data/scripts:/home/osmocom-build/jenkins/workspace/ttcn3-hlr-test-pyhss-latest/_testenv/data/scripts/qemu:/home/osmocom-build/jenkins/workspace/ttcn3-hlr-test-pyhss-latest/hlr:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-e', 'PYTHONUNBUFFERED=1', '-e', 'BUILDDIR=/home/osmocom-build/jenkins/workspace/ttcn3-hlr-test-pyhss-latest/_cache/podman/titan-11.1.0-debian-trixie', '-e', 'TTCN3_DIR=/opt/eclipse-titan-11.1.0', '-t', '-w', '/home/osmocom-build/jenkins/workspace/ttcn3-hlr-test-pyhss-latest/logs/testsuite', 'testenv-hlr-pyhss-osmocom-latest-20251222-1210-f6bd7dd7-0', 'sh', '-c', '/home/osmocom-build/jenkins/workspace/ttcn3-hlr-test-pyhss-latest/_testenv/data/scripts/log_format.sh'][0m [1;34m[testenv][pyhss] Stopping pyhss_api (1562736)[0m [1;34m[testenv][pyhss] Stopping pyhss_gsup (1562667)[0m [1;34m[testenv][pyhss] Stopping redis (1562636)[0m [1;34m[testenv] Showing testsuite/junit-xml-8810.log[0m [0;94m[testenv] + ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=/home/osmocom-build/jenkins/workspace/ttcn3-hlr-test-pyhss-latest/_cache', '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TESTENV_INSTALL_DIR=/', '-e', 'TERM=dumb', '-e', 'PATH=/home/osmocom-build/jenkins/workspace/ttcn3-hlr-test-pyhss-latest/_testenv/data/scripts:/home/osmocom-build/jenkins/workspace/ttcn3-hlr-test-pyhss-latest/_testenv/data/scripts/qemu:/home/osmocom-build/jenkins/workspace/ttcn3-hlr-test-pyhss-latest/hlr:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-e', 'PYTHONUNBUFFERED=1', '-t', 'testenv-hlr-pyhss-osmocom-latest-20251222-1210-f6bd7dd7-0', 'sh', '-c', 'echo && source-highlight -f esc -s xml -i /home/osmocom-build/jenkins/workspace/ttcn3-hlr-test-pyhss-latest/logs/testsuite/junit-xml-8810.log && echo'][0m [01;34m<?xml[m [32mversion[m[31m=[m[31m"1.0"[m[01;34m?>[m [01;34m<testsuite[m [32mname[m[31m=[m[31m'Titan'[m [32mtests[m[31m=[m[31m'18'[m [32mfailures[m[31m=[m[31m'8'[m [32merrors[m[31m=[m[31m'1'[m [32mskipped[m[31m=[m[31m'0'[m [32minconc[m[31m=[m[31m'0'[m [32mtime[m[31m=[m[31m'79.00'[m[01;34m>[m [01;34m<testcase[m [32mclassname[m[31m=[m[31m'HLR_Tests'[m [32mname[m[31m=[m[31m'TC_gsup_sai_err_invalid_imsi'[m [32mtime[m[31m=[m[31m'0.081837'[m[01;34m/>[m [01;34m<testcase[m [32mclassname[m[31m=[m[31m'HLR_Tests'[m [32mname[m[31m=[m[31m'TC_gsup_sai'[m [32mtime[m[31m=[m[31m'3.924549'[m[01;34m/>[m [01;34m<testcase[m [32mclassname[m[31m=[m[31m'HLR_Tests'[m [32mname[m[31m=[m[31m'TC_gsup_sai_num_auth_vectors'[m [32mtime[m[31m=[m[31m'5.871764'[m[01;34m/>[m [01;34m<testcase[m [32mclassname[m[31m=[m[31m'HLR_Tests'[m [32mname[m[31m=[m[31m'TC_gsup_ul_unknown_imsi'[m [32mtime[m[31m=[m[31m'0.082960'[m[01;34m/>[m [01;34m<testcase[m [32mclassname[m[31m=[m[31m'HLR_Tests'[m [32mname[m[31m=[m[31m'TC_gsup_sai_err_unknown_imsi'[m [32mtime[m[31m=[m[31m'0.065864'[m[01;34m/>[m [01;34m<testcase[m [32mclassname[m[31m=[m[31m'HLR_Tests'[m [32mname[m[31m=[m[31m'TC_gsup_ul'[m [32mtime[m[31m=[m[31m'2.752023'[m[01;34m/>[m [01;34m<testcase[m [32mclassname[m[31m=[m[31m'HLR_Tests'[m [32mname[m[31m=[m[31m'TC_gsup_ul_subscriber_data'[m [32mtime[m[31m=[m[31m'2.950738'[m[01;34m>[m [01;34m<failure[m [32mtype[m[31m=[m[31m'fail-verdict'[m[01;34m>[mMultiple APNs: Coulnd't find first PDP Info IE in: { msg_type := OSMO_GSUP_MSGT_INSERT_DATA_REQUEST (16), ies := { { tag := OSMO_GSUP_IMSI_IE (1), len := 8, val := { imsi := '262424675222314'H } }, { tag := OSMO_GSUP_MSISDN_IE (8), len := 7, val := { msisdn := { len := 6, digits := '491618516568'H } } }, { tag := OSMO_GSUP_DESTINATION_NAME_IE (97), len := 0, val := { destination_name := ''O } } } } HLR_Tests.ttcn:2217 HLR_Tests control part HLR_Tests.ttcn:1229 TC_gsup_ul_subscriber_data testcase [01;34m</failure>[m [01;34m</testcase>[m [01;34m<testcase[m [32mclassname[m[31m=[m[31m'HLR_Tests'[m [32mname[m[31m=[m[31m'TC_gsup_purge_cs'[m [32mtime[m[31m=[m[31m'3.026564'[m[01;34m/>[m [01;34m<testcase[m [32mclassname[m[31m=[m[31m'HLR_Tests'[m [32mname[m[31m=[m[31m'TC_gsup_purge_ps'[m [32mtime[m[31m=[m[31m'2.999889'[m[01;34m/>[m [01;34m<testcase[m [32mclassname[m[31m=[m[31m'HLR_Tests'[m [32mname[m[31m=[m[31m'TC_gsup_purge_unknown'[m [32mtime[m[31m=[m[31m'0.049418'[m[01;34m/>[m [01;34m<testcase[m [32mclassname[m[31m=[m[31m'HLR_Tests'[m [32mname[m[31m=[m[31m'TC_mo_ussd_unknown'[m [32mtime[m[31m=[m[31m'0.242961'[m[01;34m>[m [01;34m<failure[m [32mtype[m[31m=[m[31m'fail-verdict'[m[01;34m>[mGSUP Connection Lost HLR_Tests.ttcn:2222 HLR_Tests control part HLR_Tests.ttcn:1384 TC_mo_ussd_unknown testcase [01;34m</failure>[m [01;34m</testcase>[m [01;34m<testcase[m [32mclassname[m[31m=[m[31m'HLR_Tests'[m [32mname[m[31m=[m[31m'TC_mo_ussd_euse_disc'[m [32mtime[m[31m=[m[31m'0.247952'[m[01;34m>[m [01;34m<failure[m [32mtype[m[31m=[m[31m'fail-verdict'[m[01;34m>[mGSUP Connection Lost HLR_Tests.ttcn:2223 HLR_Tests control part HLR_Tests.ttcn:1411 TC_mo_ussd_euse_disc testcase [01;34m</failure>[m [01;34m</testcase>[m [01;34m<testcase[m [32mclassname[m[31m=[m[31m'HLR_Tests'[m [32mname[m[31m=[m[31m'TC_mo_ussd_iuse_imsi'[m [32mtime[m[31m=[m[31m'0.285401'[m[01;34m>[m [01;34m<failure[m [32mtype[m[31m=[m[31m'fail-verdict'[m[01;34m>[mGSUP Connection Lost HLR_Tests.ttcn:2224 HLR_Tests control part HLR_Tests.ttcn:1443 TC_mo_ussd_iuse_imsi testcase [01;34m</failure>[m [01;34m</testcase>[m [01;34m<testcase[m [32mclassname[m[31m=[m[31m'HLR_Tests'[m [32mname[m[31m=[m[31m'TC_mo_ussd_iuse_msisdn'[m [32mtime[m[31m=[m[31m'0.266052'[m[01;34m>[m [01;34m<failure[m [32mtype[m[31m=[m[31m'fail-verdict'[m[01;34m>[mGSUP Connection Lost HLR_Tests.ttcn:2225 HLR_Tests control part HLR_Tests.ttcn:1489 TC_mo_ussd_iuse_msisdn testcase [01;34m</failure>[m [01;34m</testcase>[m [01;34m<testcase[m [32mclassname[m[31m=[m[31m'HLR_Tests'[m [32mname[m[31m=[m[31m'TC_mo_ussd_euse'[m [32mtime[m[31m=[m[31m'0.261293'[m[01;34m>[m [01;34m<failure[m [32mtype[m[31m=[m[31m'fail-verdict'[m[01;34m>[mGSUP Connection Lost HLR_Tests.ttcn:2226 HLR_Tests control part HLR_Tests.ttcn:1536 TC_mo_ussd_euse testcase [01;34m</failure>[m [01;34m</testcase>[m [01;34m<testcase[m [32mclassname[m[31m=[m[31m'HLR_Tests'[m [32mname[m[31m=[m[31m'TC_mo_ussd_euse_continue'[m [32mtime[m[31m=[m[31m'0.276865'[m[01;34m>[m [01;34m<failure[m [32mtype[m[31m=[m[31m'fail-verdict'[m[01;34m>[mGSUP Connection Lost HLR_Tests.ttcn:2227 HLR_Tests control part HLR_Tests.ttcn:1583 TC_mo_ussd_euse_continue testcase [01;34m</failure>[m [01;34m</testcase>[m [01;34m<testcase[m [32mclassname[m[31m=[m[31m'HLR_Tests'[m [32mname[m[31m=[m[31m'TC_mo_ussd_euse_defaultroute'[m [32mtime[m[31m=[m[31m'0.020502'[m[01;34m>[m [01;34m<error[m [32mtype[m[31m=[m[31m'DTE'[m[01;34m>[mDynamic test case error: Port VTY has neither connections nor mappings. Message cannot be sent on it.[01;34m</error>[m [01;34m</testcase>[m [01;34m<testcase[m [32mclassname[m[31m=[m[31m'HLR_Tests'[m [32mname[m[31m=[m[31m'TC_mo_sss_reject'[m [32mtime[m[31m=[m[31m'0.262208'[m[01;34m>[m [01;34m<failure[m [32mtype[m[31m=[m[31m'fail-verdict'[m[01;34m>[mGSUP Connection Lost HLR_Tests.ttcn:2231 HLR_Tests control part HLR_Tests.ttcn:1670 TC_mo_sss_reject testcase [01;34m</failure>[m [01;34m</testcase>[m [01;34m</testsuite>[m [1;34m[testenv] Stopping podman container[0m [0;94m[testenv] + ['podman', 'kill', 'testenv-hlr-pyhss-osmocom-latest-20251222-1210-f6bd7dd7-0'][0m testenv-hlr-pyhss-osmocom-latest-20251222-1210-f6bd7dd7-0 [0;94m[testenv] feed_watchdog_loop: podman container has stopped[0m [1;34m[testenv] Logs saved to: https://jenkins.osmocom.org/jenkins/job/ttcn3-hlr-test-pyhss-latest/48/arti… [0m + [ 0 = 0 ] + rm -rf _cache .linux + + grep --color=always -o load.* uptime [01;31m[Kload average: 1.25, 0.63, 0.28[m[K + exit 0

1 0

2026

2025

2024

2023

2022

2021

2020

2019

jenkins-notifications December 2025