- jenkins-notifications - lists.osmocom.org

Build failed in Jenkins: ttcn3-hss-test-pyhss #47
by jenkins＠lists.osmocom.org 29 Dec '25

29 Dec '25

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-hss-test-pyhss/47/display/red…> Changes: ------------------------------------------ [...truncated 62.03 KiB...] 2885:C 28 Dec 2025 09:17:15.500 * Redis version=8.0.2, bits=64, commit=00000000, modified=0, pid=2885, just started 2885:C 28 Dec 2025 09:17:15.500 * Configuration loaded 2885:M 28 Dec 2025 09:17:15.501 * monotonic clock: POSIX clock_gettime 2885:M 28 Dec 2025 09:17:15.501 * Running mode=standalone, port=6379. 2885:M 28 Dec 2025 09:17:15.502 * Server initialized 2885:M 28 Dec 2025 09:17:15.502 * Ready to accept connections tcp [1;34m[testenv][pyhss] Running pyhss_hss[0m [0;94m[testenv][pyhss] + ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-hss-test-pyhss/ws/_cache',> '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TESTENV_INSTALL_DIR=/', '-e', 'TERM=dumb', '-e', 'PATH=<https://jenkins.osmocom.org/jenkins/job/ttcn3-hss-test-pyhss/ws/_testenv/da…>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-hss-test-pyhss/ws/_testenv/da…>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-hss-test-pyhss/ws/hss>:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-e', 'PYTHONUNBUFFERED=1', '-w', '<https://jenkins.osmocom.org/jenkins/job/ttcn3-hss-test-pyhss/47/artifact/lo…',> 'testenv-hss-pyhss-osmocom-nightly-20251228-0917-4d65f3c9-0', 'sh', '-c', 'ulimit -c unlimited; ./run_in_venv.sh pyhss_hss 2>&1 | tee <https://jenkins.osmocom.org/jenkins/job/ttcn3-hss-test-pyhss/47/artifact/lo…'][0m> [1;34m[testenv][pyhss] Running pyhss_diameter[0m [0;94m[testenv][pyhss] + ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-hss-test-pyhss/ws/_cache',> '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TESTENV_INSTALL_DIR=/', '-e', 'TERM=dumb', '-e', 'PATH=<https://jenkins.osmocom.org/jenkins/job/ttcn3-hss-test-pyhss/ws/_testenv/da…>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-hss-test-pyhss/ws/_testenv/da…>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-hss-test-pyhss/ws/hss>:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-e', 'PYTHONUNBUFFERED=1', '-w', '<https://jenkins.osmocom.org/jenkins/job/ttcn3-hss-test-pyhss/47/artifact/lo…',> 'testenv-hss-pyhss-osmocom-nightly-20251228-0917-4d65f3c9-0', 'sh', '-c', 'ulimit -c unlimited; cd ../pyhss_hss && ./run_in_venv.sh pyhss_diameter 2>&1 | tee <https://jenkins.osmocom.org/jenkins/job/ttcn3-hss-test-pyhss/47/artifact/lo…'][0m> [1;34m[testenv][pyhss] Running pyhss_diameter setup script[0m [0;94m[testenv][pyhss] + ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-hss-test-pyhss/ws/_cache',> '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TESTENV_INSTALL_DIR=/', '-e', 'TERM=dumb', '-e', 'PATH=<https://jenkins.osmocom.org/jenkins/job/ttcn3-hss-test-pyhss/ws/_testenv/da…>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-hss-test-pyhss/ws/_testenv/da…>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-hss-test-pyhss/ws/hss>:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-e', 'PYTHONUNBUFFERED=1', '-t', '-w', '<https://jenkins.osmocom.org/jenkins/job/ttcn3-hss-test-pyhss/47/artifact/lo…',> 'testenv-hss-pyhss-osmocom-nightly-20251228-0917-4d65f3c9-0', 'sh', '-c', './run_in_venv.sh wait_for_port.py -p 3868 -H 127.0.0.201 -P sctp'][0m [Diameter] [DEBUG] [Diameter] [startServer] SCTP Parameters: {'initialRto': 1000, 'rtoMin': 500, 'rtoMax': 5000} [Diameter] [INFO] [Diameter] [logProcessedMessages] Processed 0 inbound diameter messages in the last 3600 second(s) [Diameter] [INFO] ###### ## ## ##### ##### ## ## ## ## ## ## ## ## ## ## ## ## ## ## ## ## ###### ## ## ####### ##### ##### ## ## ## ## ## ## ## ## ## ## ## ## ## ## ## ## ## ##### ## ## ##### ##### ## #### Diameter Service [Diameter] Serving on ('127.0.0.201', 3868) [Diameter] [INFO] [Diameter] [logProcessedMessages] Processed 0 outbound in the last 3600 second(s) [Diameter] [INFO] [Diameter] New Connection from: 127.0.0.1 on port 37308 [Diameter] [INFO] [Diameter] [logActivePeers] 1 Active Peers {'127.0.0.1-37308': Peer(IpAddress='127.0.0.1', Port='37308', Hostname='', Connected=True, TransportProtocol='', PeerType='', LastConnectTimestamp='2025-12-28T09:17:16.120218+00:00', LastDisconnectTimestamp='', ReconnectionCount=0, Metadata='')} [Diameter] [DEBUG] [Diameter] [readInboundData] [a432a406-09bc-4dc7-a18d-6419428b08c3] New connection from 127.0.0.1 on port 37308 [Diameter] [DEBUG] [Diameter] [writeOutboundData] [a432a406-09bc-4dc7-a18d-6419428b08c3] writeOutboundData with host 127.0.0.1 on port 37308 [Diameter] [DEBUG] [Diameter] [writeOutboundData] [a432a406-09bc-4dc7-a18d-6419428b08c3] Waiting for messages for host 127.0.0.1 on port 37308 [HSS] [INFO] ###### ## ## ##### ##### ## ## ## ## ## ## ## ## ## ## ## ## ## ## ## ## ###### ## ## ####### ##### ##### ## ## ## ## ## ## ## ## ## ## ## ## ## ## ## ## ## ##### ## ## ##### ##### ## #### HSS Service [1;34m[testenv][pyhss] Running pyhss_api[0m [0;94m[testenv][pyhss] + ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-hss-test-pyhss/ws/_cache',> '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TESTENV_INSTALL_DIR=/', '-e', 'TERM=dumb', '-e', 'PATH=<https://jenkins.osmocom.org/jenkins/job/ttcn3-hss-test-pyhss/ws/_testenv/da…>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-hss-test-pyhss/ws/_testenv/da…>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-hss-test-pyhss/ws/hss>:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-e', 'PYTHONUNBUFFERED=1', '-w', '<https://jenkins.osmocom.org/jenkins/job/ttcn3-hss-test-pyhss/47/artifact/lo…',> 'testenv-hss-pyhss-osmocom-nightly-20251228-0917-4d65f3c9-0', 'sh', '-c', 'ulimit -c unlimited; cd ../pyhss_hss && ./run_in_venv.sh pyhss_api 2>&1 | tee <https://jenkins.osmocom.org/jenkins/job/ttcn3-hss-test-pyhss/47/artifact/lo…'][0m> [Database] [DEBUG] Creating database [Diameter] [INFO] [Diameter] [handleConnection] [a432a406-09bc-4dc7-a18d-6419428b08c3] Connection closed for 127.0.0.1 on port 37308. [Diameter] [INFO] [Diameter] [logActivePeers] 1 Active Peers {'127.0.0.1-37308': Peer(IpAddress='127.0.0.1', Port='37308', Hostname='', Connected=False, TransportProtocol='', PeerType='', LastConnectTimestamp='2025-12-28T09:17:16.120218+00:00', LastDisconnectTimestamp='2025-12-28T09:17:16.221128+00:00', ReconnectionCount=0, Metadata='')} [1;34m[testenv][pyhss] Running pyhss_api setup script[0m [0;94m[testenv][pyhss] + ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-hss-test-pyhss/ws/_cache',> '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TESTENV_INSTALL_DIR=/', '-e', 'TERM=dumb', '-e', 'PATH=<https://jenkins.osmocom.org/jenkins/job/ttcn3-hss-test-pyhss/ws/_testenv/da…>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-hss-test-pyhss/ws/_testenv/da…>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-hss-test-pyhss/ws/hss>:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-e', 'PYTHONUNBUFFERED=1', '-t', '-w', '<https://jenkins.osmocom.org/jenkins/job/ttcn3-hss-test-pyhss/47/artifact/lo…',> 'testenv-hss-pyhss-osmocom-nightly-20251228-0917-4d65f3c9-0', 'sh', '-c', './setup_db.sh'][0m + wait_for_port.py -p 8080 [Database] [DEBUG] Database already created [Database] [INFO] Not loading EIR IMEI TAC Database as Redis not enabled or TAC CSV Database not set in config [Database] [DEBUG] Table apn already exists [Database] [DEBUG] Table auc already exists [Database] [DEBUG] Table subscriber already exists [Database] [DEBUG] Table subscriber_routing already exists [Database] [DEBUG] Table serving_apn already exists [Database] [DEBUG] Table ims_subscriber already exists [Database] [DEBUG] Table roaming_network already exists [Database] [DEBUG] Table emergency_subscriber already exists [Database] [DEBUG] Table roaming_rule already exists [Database] [DEBUG] Table charging_rule already exists [Database] [DEBUG] Table tft already exists [Database] [DEBUG] Table eir already exists [Database] [DEBUG] Table eir_history already exists [Database] [DEBUG] Table subscriber_attributes already exists [Database] [DEBUG] Creating table operation_log [Database] [INFO] Not loading EIR IMEI TAC Database as Redis not enabled or TAC CSV Database not set in config [Database] [DEBUG] Table apn already exists [Database] [DEBUG] Table auc already exists [Database] [DEBUG] Table subscriber already exists [Database] [DEBUG] Table subscriber_routing already exists [Database] [DEBUG] Table serving_apn already exists [Database] [DEBUG] Table ims_subscriber already exists [Database] [DEBUG] Table roaming_network already exists [Database] [DEBUG] Table emergency_subscriber already exists [Database] [DEBUG] Table roaming_rule already exists [Database] [DEBUG] Table charging_rule already exists [Database] [DEBUG] Table tft already exists [Database] [DEBUG] Table eir already exists [Database] [DEBUG] Table eir_history already exists [Database] [DEBUG] Table subscriber_attributes already exists [Database] [DEBUG] Table operation_log already exists [HSS] [INFO] Initialized Diameter Library [HSS] [INFO] Origin Host: hss01 [HSS] [INFO] Realm: epc.mnc001.mcc001.3gppnetwork.org [HSS] [INFO] Product Name: pyHSS [HSS] [INFO] PLMN: 001/01 Traceback (most recent call last): File "/opt/venvs/pyhss/lib/python3.13/site-packages/sqlalchemy/engine/base.py", line 1967, in _exec_single_context self.dialect.do_execute( ~~~~~~~~~~~~~~~~~~~~~~~^ cursor, str_statement, effective_parameters, context ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ ) ^ File "/opt/venvs/pyhss/lib/python3.13/site-packages/sqlalchemy/engine/default.py", line 951, in do_execute cursor.execute(statement, parameters) ~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^ sqlite3.OperationalError: table operation_log already exists The above exception was the direct cause of the following exception: Traceback (most recent call last): File "/opt/venvs/pyhss/bin/pyhss_api", line 5, in <module> from pyhss.services.apiService import main File "/opt/venvs/pyhss/lib/python3.13/site-packages/pyhss/services/apiService.py", line 44, in <module> diameterClient = Diameter( redisMessaging=redisMessaging, ...<5 lines>... productName='PyHSS-client-API' ) File "/opt/venvs/pyhss/lib/python3.13/site-packages/pyhss/lib/diameter.py", line 48, in __init__ self.database = Database(logTool=logTool) ~~~~~~~~^^^^^^^^^^^^^^^^^ File "/opt/venvs/pyhss/lib/python3.13/site-packages/pyhss/lib/database.py", line 413, in __init__ Base.metadata.tables[table_name].create(bind=self.engine) ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^ File "/opt/venvs/pyhss/lib/python3.13/site-packages/sqlalchemy/sql/schema.py", line 1288, in create bind._run_ddl_visitor(ddl.SchemaGenerator, self, checkfirst=checkfirst) ~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/opt/venvs/pyhss/lib/python3.13/site-packages/sqlalchemy/engine/base.py", line 3252, in _run_ddl_visitor conn._run_ddl_visitor(visitorcallable, element, **kwargs) ~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/opt/venvs/pyhss/lib/python3.13/site-packages/sqlalchemy/engine/base.py", line 2459, in _run_ddl_visitor ).traverse_single(element) ~~~~~~~~~~~~~~~^^^^^^^^^ File "/opt/venvs/pyhss/lib/python3.13/site-packages/sqlalchemy/sql/visitors.py", line 661, in traverse_single return meth(obj, **kw) File "/opt/venvs/pyhss/lib/python3.13/site-packages/sqlalchemy/sql/ddl.py", line 1022, in visit_table )._invoke_with(self.connection) ~~~~~~~~~~~~^^^^^^^^^^^^^^^^^ File "/opt/venvs/pyhss/lib/python3.13/site-packages/sqlalchemy/sql/ddl.py", line 321, in _invoke_with return bind.execute(self) ~~~~~~~~~~~~^^^^^^ File "/opt/venvs/pyhss/lib/python3.13/site-packages/sqlalchemy/engine/base.py", line 1419, in execute return meth( self, distilled_parameters, execution_options or NO_OPTIONS, ) File "/opt/venvs/pyhss/lib/python3.13/site-packages/sqlalchemy/sql/ddl.py", line 187, in _execute_on_connection return connection._execute_ddl( ~~~~~~~~~~~~~~~~~~~~~~~^ self, distilled_params, execution_options ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ ) ^ File "/opt/venvs/pyhss/lib/python3.13/site-packages/sqlalchemy/engine/base.py", line 1530, in _execute_ddl ret = self._execute_context( dialect, ...<4 lines>... compiled, ) File "/opt/venvs/pyhss/lib/python3.13/site-packages/sqlalchemy/engine/base.py", line 1846, in _execute_context return self._exec_single_context( ~~~~~~~~~~~~~~~~~~~~~~~~~^ dialect, context, statement, parameters ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ ) ^ File "/opt/venvs/pyhss/lib/python3.13/site-packages/sqlalchemy/engine/base.py", line 1986, in _exec_single_context self._handle_dbapi_exception( ~~~~~~~~~~~~~~~~~~~~~~~~~~~~^ e, str_statement, effective_parameters, cursor, context ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ ) ^ File "/opt/venvs/pyhss/lib/python3.13/site-packages/sqlalchemy/engine/base.py", line 2355, in _handle_dbapi_exception raise sqlalchemy_exception.with_traceback(exc_info[2]) from e File "/opt/venvs/pyhss/lib/python3.13/site-packages/sqlalchemy/engine/base.py", line 1967, in _exec_single_context self.dialect.do_execute( ~~~~~~~~~~~~~~~~~~~~~~~^ cursor, str_statement, effective_parameters, context ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ ) ^ File "/opt/venvs/pyhss/lib/python3.13/site-packages/sqlalchemy/engine/default.py", line 951, in do_execute cursor.execute(statement, parameters) ~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^ sqlalchemy.exc.OperationalError: (sqlite3.OperationalError) table operation_log already exists [SQL: CREATE TABLE operation_log ( id INTEGER NOT NULL, item_id INTEGER NOT NULL, operation_id VARCHAR(36) NOT NULL, operation VARCHAR(10), changes TEXT, last_modified VARCHAR(100), timestamp DATETIME, table_name VARCHAR(255), apn_id INTEGER, subscriber_routing_id INTEGER, serving_apn_id INTEGER, auc_id INTEGER, subscriber_id INTEGER, ims_subscriber_id INTEGER, roaming_rule_id INTEGER, roaming_network_id INTEGER, emergency_subscriber_id INTEGER, charging_rule_id INTEGER, tft_id INTEGER, eir_id INTEGER, imsi_imei_history_id INTEGER, subscriber_attributes_id INTEGER, PRIMARY KEY (id), FOREIGN KEY(apn_id) REFERENCES apn (apn_id), FOREIGN KEY(subscriber_routing_id) REFERENCES subscriber_routing (subscriber_routing_id), FOREIGN KEY(serving_apn_id) REFERENCES serving_apn (serving_apn_id), FOREIGN KEY(auc_id) REFERENCES auc (auc_id), FOREIGN KEY(subscriber_id) REFERENCES subscriber (subscriber_id), FOREIGN KEY(ims_subscriber_id) REFERENCES ims_subscriber (ims_subscriber_id), FOREIGN KEY(roaming_rule_id) REFERENCES roaming_rule (roaming_rule_id), FOREIGN KEY(roaming_network_id) REFERENCES roaming_network (roaming_network_id), FOREIGN KEY(emergency_subscriber_id) REFERENCES emergency_subscriber (emergency_subscriber_id), FOREIGN KEY(charging_rule_id) REFERENCES charging_rule (charging_rule_id), FOREIGN KEY(tft_id) REFERENCES tft (tft_id), FOREIGN KEY(eir_id) REFERENCES eir (eir_id), FOREIGN KEY(imsi_imei_history_id) REFERENCES eir_history (imsi_imei_history_id), FOREIGN KEY(subscriber_attributes_id) REFERENCES subscriber_attributes (subscriber_attributes_id) ) ] (Background on this error at: https://sqlalche.me/e/20/e3q8) ERROR: 127.0.0.1:8080 did not become available within 5s! [1;91m[testenv][pyhss] pyhss_api: setup script failed[0m [1;34m[testenv][pyhss] Stopping podman container[0m [0;94m[testenv][pyhss] + ['podman', 'kill', 'testenv-hss-pyhss-osmocom-nightly-20251228-0917-4d65f3c9-0'][0m testenv-hss-pyhss-osmocom-nightly-20251228-0917-4d65f3c9-0 [0;94m[testenv] feed_watchdog_loop: podman container has stopped[0m [1;34m[testenv][pyhss] Logs saved to: https://jenkins.osmocom.org/jenkins/job/ttcn3-hss-test-pyhss/47/artifact/lo… [0m + RC=1 + [ 1 = 0 ] + + uptime grep --color=always -o load.* [01;31m[Kload average: 0.59, 0.51, 0.52[m[K + exit 1 Build step 'Execute shell' marked build as failure Recording test results ERROR: Step ‘Publish JUnit test result report’ failed: No test report files were found. Configuration error?

1 1

Jenkins build became unstable: ttcn3-bsc-test-asan #571
by jenkins＠lists.osmocom.org 28 Dec '25

28 Dec '25

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-asan/571/display/red…>

1 1

Jenkins build is back to stable : ttcn3-bsc-test #3082
by jenkins＠lists.osmocom.org 25 Dec '25

25 Dec '25

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test/3082/display/redirect>

1 0

Build failed in Jenkins: ttcn3-bsc-test-io_uring #441
by jenkins＠lists.osmocom.org 24 Dec '25

24 Dec '25

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-io_uring/441/display…> Changes: ------------------------------------------ [...truncated 22.38 MiB...] [38;5;49m20251223153146441 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(virt-msc0-0) Received M3UA Message (XFER:DATA) (m3ua.c:811)[0;m [38;5;49m20251223153146441 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(virt-msc0-0) m3ua_rx_xfer (m3ua.c:601)[0;m [38;5;49m20251223153146441 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(virt-msc0-0) m3ua_rx_xfer(): M3UA data header: opc=185=0.23.1 dpc=187=0.23.3 sls=0 (m3ua.c:632)[0;m [38;5;43m20251223153146441 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: mtp3_hmdc_rx_from_l2(): dpc=187=0.23.3 not local, message is for routing (mtp3_hmdc.c:49)[0;m [38;5;43m20251223153146441 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: CombinedLinkset(dpc=187=0.23.3,mask=0x3fff=7.255.7,prio=5) RT lookup: OPC=185=0.23.1,DPC=187=0.23.3,SLS=0 -> eSLS=32: use Normal Route via 'bsc0' (ss7_combined_linkset.c:275)[0;m [38;5;43m20251223153146441 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: Found route for dpc=187=0.23.3: pc=187=0.23.3 mask=0x3fff=7.255.7 prio=5 dyn via AS bsc0 proto=m3ua (mtp3_hmrt.c:80)[0;m [38;5;43m20251223153146441 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: rt->dest.as proto is M3UA for dpc=187=0.23.3 (mtp3_hmrt.c:90)[0;m [38;5;43m20251223153146442 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(bsc0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:563)[0;m [38;5;43m20251223153146442 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(bsc0-0) Tx 36 bytes: [L2]> 01 00 01 01 00 00 00 24 02 10 00 19 00 00 00 b9 00 00 00 bb 03 00 00 00 04 00 00 85 b1 18 9f 00 00 00 00 00 (ss7_asp.c:1400)[0;m [38;5;43m20251223153146442 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(bsc0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1107)[0;m [38;5;49m20251223153146442 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(bsc0-0) Received M3UA Message (XFER:DATA) (m3ua.c:811)[0;m [38;5;49m20251223153146442 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(bsc0-0) m3ua_rx_xfer (m3ua.c:601)[0;m [38;5;49m20251223153146443 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(bsc0-0) m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3 dpc=185=0.23.1 sls=13 (m3ua.c:632)[0;m [38;5;43m20251223153146443 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: mtp3_hmdc_rx_from_l2(): dpc=185=0.23.1 not local, message is for routing (mtp3_hmdc.c:49)[0;m [38;5;43m20251223153146443 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: CombinedLinkset(dpc=185=0.23.1,mask=0x3fff=7.255.7,prio=5) RT lookup: OPC=187=0.23.3,DPC=185=0.23.1,SLS=13 -> eSLS=45: use Normal Route via 'virt-msc0' (ss7_combined_linkset.c:275)[0;m [38;5;43m20251223153146443 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: Found route for dpc=185=0.23.1: pc=185=0.23.1 mask=0x3fff=7.255.7 prio=5 via AS virt-msc0 proto=m3ua (mtp3_hmrt.c:80)[0;m [38;5;43m20251223153146443 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: rt->dest.as proto is M3UA for dpc=185=0.23.1 (mtp3_hmrt.c:90)[0;m [38;5;43m20251223153146443 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(virt-msc0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:563)[0;m [38;5;43m20251223153146443 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(virt-msc0-0) Tx 40 bytes: [L2]> 01 00 01 01 00 00 00 28 02 10 00 17 00 00 00 bb 00 00 00 b9 03 00 00 0d 05 b1 18 9f 00 00 85 00 00 06 00 08 00 00 00 01 (ss7_asp.c:1400)[0;m [38;5;43m20251223153146443 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(virt-msc0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1107)[0;m [38;5;49m20251223153146443 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(virt-msc0-0) Received M3UA Message (XFER:DATA) (m3ua.c:811)[0;m [38;5;49m20251223153146443 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(virt-msc0-0) m3ua_rx_xfer (m3ua.c:601)[0;m [38;5;49m20251223153146443 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(virt-msc0-0) m3ua_rx_xfer(): M3UA data header: opc=185=0.23.1 dpc=187=0.23.3 sls=0 (m3ua.c:632)[0;m [38;5;43m20251223153146443 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: mtp3_hmdc_rx_from_l2(): dpc=187=0.23.3 not local, message is for routing (mtp3_hmdc.c:49)[0;m [38;5;43m20251223153146443 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: CombinedLinkset(dpc=187=0.23.3,mask=0x3fff=7.255.7,prio=5) RT lookup: OPC=185=0.23.1,DPC=187=0.23.3,SLS=0 -> eSLS=32: use Normal Route via 'bsc0' (ss7_combined_linkset.c:275)[0;m [38;5;43m20251223153146443 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: Found route for dpc=187=0.23.3: pc=187=0.23.3 mask=0x3fff=7.255.7 prio=5 dyn via AS bsc0 proto=m3ua (mtp3_hmrt.c:80)[0;m [38;5;43m20251223153146443 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: rt->dest.as proto is M3UA for dpc=187=0.23.3 (mtp3_hmrt.c:90)[0;m [38;5;43m20251223153146443 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(bsc0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:563)[0;m [38;5;43m20251223153146443 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(bsc0-0) Tx 40 bytes: [L2]> 01 00 01 01 00 00 00 28 02 10 00 1d 00 00 00 b9 00 00 00 bb 03 00 00 00 06 00 00 86 00 01 06 00 04 20 04 01 00 00 00 00 (ss7_asp.c:1400)[0;m VirtMSC-SCCP(2258)@a27f6ca23576: Session index based on local reference:1 VirtMSC-SCCP(2258)@a27f6ca23576: RLC received in state:disconnect_pending [38;5;43m20251223153146444 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(bsc0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1107)[0;m [38;5;49m20251223153146444 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(bsc0-0) Received M3UA Message (XFER:DATA) (m3ua.c:811)[0;m [38;5;49m20251223153146444 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(bsc0-0) m3ua_rx_xfer (m3ua.c:601)[0;m [38;5;49m20251223153146444 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(bsc0-0) m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3 dpc=185=0.23.1 sls=14 (m3ua.c:632)[0;m [38;5;43m20251223153146444 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: mtp3_hmdc_rx_from_l2(): dpc=185=0.23.1 not local, message is for routing (mtp3_hmdc.c:49)[0;m [38;5;43m20251223153146444 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: CombinedLinkset(dpc=185=0.23.1,mask=0x3fff=7.255.7,prio=5) RT lookup: OPC=187=0.23.3,DPC=185=0.23.1,SLS=14 -> eSLS=46: use Normal Route via 'virt-msc0' (ss7_combined_linkset.c:275)[0;m [38;5;43m20251223153146444 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: Found route for dpc=185=0.23.1: pc=185=0.23.1 mask=0x3fff=7.255.7 prio=5 via AS virt-msc0 proto=m3ua (mtp3_hmrt.c:80)[0;m [38;5;43m20251223153146444 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: rt->dest.as proto is M3UA for dpc=185=0.23.1 (mtp3_hmrt.c:90)[0;m [38;5;43m20251223153146444 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(virt-msc0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:563)[0;m [38;5;43m20251223153146444 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(virt-msc0-0) Tx 44 bytes: [L2]> 01 00 01 01 00 00 00 2c 02 10 00 1a 00 00 00 bb 00 00 00 b9 03 00 00 0e 06 8a a3 76 00 01 03 00 01 21 00 00 00 06 00 08 00 00 00 01 (ss7_asp.c:1400)[0;m VirtMSC-SCCP(2258)@a27f6ca23576: Session index based on local reference:2 VirtMSC-SCCP(2258)@a27f6ca23576: DT1 will be put to the reassembly buffer VirtMSC-SCCP(2258)@a27f6ca23576: DT1 data has been put to the reassembly buffer VirtMSC-SCCP(2258)@a27f6ca23576: DT1/segmentingReassembl/more==0 received=> send ASP_SCCP_N_DATA comes [38;5;43m20251223153146894 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(bsc0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1107)[0;m [38;5;49m20251223153146894 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(bsc0-0) Received M3UA Message (XFER:DATA) (m3ua.c:811)[0;m [38;5;49m20251223153146894 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(bsc0-0) m3ua_rx_xfer (m3ua.c:601)[0;m [38;5;49m20251223153146894 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(bsc0-0) m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3 dpc=2=0.0.2 sls=8 (m3ua.c:632)[0;m [38;5;43m20251223153146894 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: mtp3_hmdc_rx_from_l2(): dpc=2=0.0.2 not local, message is for routing (mtp3_hmdc.c:49)[0;m [38;5;43m20251223153146894 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0: CombinedLinkset(dpc=2=0.0.2,mask=0x3fff=7.255.7,prio=5) RT Lookup: OPC=187=0.23.3,DPC=2=0.0.2,SLS=8 -> eSLS=104: Normal Route via 'virt-msc1' unavailable, all Alternative Routes unavailable (ss7_combined_linkset.c:322)[0;m [38;5;43m20251223153146894 [1;31mDLSS7[0;m[38;5;43m [1;31mERROR[0;m[38;5;43m 0: MTP-TRANSFER.req for dpc=2=0.0.2: no route! (mtp3_hmrt.c:113)[0;m [38;5;43m20251223153146894 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: T8: SP 2=0.0.2: Start (ss7_instance.c:879)[0;m [38;5;43m20251223153146894 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: CombinedLinkset(dpc=187=0.23.3,mask=0x3fff=7.255.7,prio=5) RT lookup: OPC=2=0.0.2,DPC=187=0.23.3,SLS=8 -> eSLS=104: use Normal Route via 'bsc0' (ss7_combined_linkset.c:275)[0;m [38;5;43m20251223153146894 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0: Message received for inaccessible SP 2=0.0.2. Tx TFP (DUNA) to concerned SP 187=0.23.3 (mtp3_rtpc.c:120)[0;m [38;5;43m20251223153146894 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(bsc0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:563)[0;m [38;5;43m20251223153146894 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(bsc0-0) Tx 60 bytes: [L2]> 01 00 02 01 00 00 00 3c 00 12 00 08 00 00 00 02 00 04 00 2a 74 72 61 6e 73 66 65 72 20 70 72 6f 68 69 62 69 74 65 64 20 28 69 6e 61 63 63 65 73 73 69 62 6c 65 20 53 50 29 00 00 00 (ss7_asp.c:1400)[0;m [38;5;49m20251223153146894 [1;33mDLM3UA[0;m[38;5;49m [1;33mNOTICE[0;m[38;5;49m 0: ASP(asp-clnt-msc-0) Rx DUNA(transfer prohibited (inaccessible SP)) for 0.0.2/0 (xua_snm.c:501)[0;m [38;5;43m20251223153146894 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: RT(dpc=2=0.0.2,mask=0x3fff=7.255.7,prio=5,via=as-clnt-A-0-m3ua,st=unavailable) changed to status 'unavailable' (ss7_route.c:409)[0;m 20251223153146895 [1;31mDLCS[0;m [1;31mERROR[0;m Unhandled SIGTRAN primitive N-PCSTATE.indication (lb.c:212) [38;5;43m20251223153146896 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(bsc0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1107)[0;m [38;5;49m20251223153146896 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(bsc0-0) Received M3UA Message (XFER:DATA) (m3ua.c:811)[0;m [38;5;49m20251223153146897 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(bsc0-0) m3ua_rx_xfer (m3ua.c:601)[0;m [38;5;49m20251223153146897 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(bsc0-0) m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3 dpc=3=0.0.3 sls=6 (m3ua.c:632)[0;m [38;5;43m20251223153146897 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: mtp3_hmdc_rx_from_l2(): dpc=3=0.0.3 not local, message is for routing (mtp3_hmdc.c:49)[0;m [38;5;43m20251223153146897 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0: CombinedLinkset(dpc=3=0.0.3,mask=0x3fff=7.255.7,prio=5) RT Lookup: OPC=187=0.23.3,DPC=3=0.0.3,SLS=6 -> eSLS=118: Normal Route via 'virt-msc2' unavailable, all Alternative Routes unavailable (ss7_combined_linkset.c:322)[0;m [38;5;43m20251223153146897 [1;31mDLSS7[0;m[38;5;43m [1;31mERROR[0;m[38;5;43m 0: MTP-TRANSFER.req for dpc=3=0.0.3: no route! (mtp3_hmrt.c:113)[0;m [38;5;43m20251223153146897 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: T8: SP 3=0.0.3: Start (ss7_instance.c:879)[0;m [38;5;43m20251223153146897 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: CombinedLinkset(dpc=187=0.23.3,mask=0x3fff=7.255.7,prio=5) RT lookup: OPC=3=0.0.3,DPC=187=0.23.3,SLS=6 -> eSLS=118: use Normal Route via 'bsc0' (ss7_combined_linkset.c:275)[0;m [38;5;43m20251223153146897 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0: Message received for inaccessible SP 3=0.0.3. Tx TFP (DUNA) to concerned SP 187=0.23.3 (mtp3_rtpc.c:120)[0;m [38;5;43m20251223153146897 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(bsc0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:563)[0;m [38;5;43m20251223153146897 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(bsc0-0) Tx 60 bytes: [L2]> 01 00 02 01 00 00 00 3c 00 12 00 08 00 00 00 03 00 04 00 2a 74 72 61 6e 73 66 65 72 20 70 72 6f 68 69 62 69 74 65 64 20 28 69 6e 61 63 63 65 73 73 69 62 6c 65 20 53 50 29 00 00 00 (ss7_asp.c:1400)[0;m [38;5;49m20251223153146897 [1;33mDLM3UA[0;m[38;5;49m [1;33mNOTICE[0;m[38;5;49m 0: ASP(asp-clnt-msc-0) Rx DUNA(transfer prohibited (inaccessible SP)) for 0.0.3/0 (xua_snm.c:501)[0;m [38;5;43m20251223153146897 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: RT(dpc=3=0.0.3,mask=0x3fff=7.255.7,prio=5,via=as-clnt-A-0-m3ua,st=unavailable) changed to status 'unavailable' (ss7_route.c:409)[0;m 20251223153146897 [1;31mDLCS[0;m [1;31mERROR[0;m Unhandled SIGTRAN primitive N-PCSTATE.indication (lb.c:212) [38;5;43m20251223153147895 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: T8: SP 2=0.0.2: Timeout (ss7_instance.c:858)[0;m [38;5;43m20251223153147897 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: T8: SP 3=0.0.3: Timeout (ss7_instance.c:858)[0;m VirtMSC-SCCP(2258)@a27f6ca23576: Session index based on connection ID:2 [38;5;43m20251223153148452 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(virt-msc0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1107)[0;m [38;5;49m20251223153148452 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(virt-msc0-0) Received M3UA Message (XFER:DATA) (m3ua.c:811)[0;m [38;5;49m20251223153148452 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(virt-msc0-0) m3ua_rx_xfer (m3ua.c:601)[0;m [38;5;49m20251223153148452 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(virt-msc0-0) m3ua_rx_xfer(): M3UA data header: opc=185=0.23.1 dpc=187=0.23.3 sls=0 (m3ua.c:632)[0;m [38;5;43m20251223153148452 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: mtp3_hmdc_rx_from_l2(): dpc=187=0.23.3 not local, message is for routing (mtp3_hmdc.c:49)[0;m [38;5;43m20251223153148452 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: CombinedLinkset(dpc=187=0.23.3,mask=0x3fff=7.255.7,prio=5) RT lookup: OPC=185=0.23.1,DPC=187=0.23.3,SLS=0 -> eSLS=32: use Normal Route via 'bsc0' (ss7_combined_linkset.c:275)[0;m [38;5;43m20251223153148452 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: Found route for dpc=187=0.23.3: pc=187=0.23.3 mask=0x3fff=7.255.7 prio=5 dyn via AS bsc0 proto=m3ua (mtp3_hmrt.c:80)[0;m [38;5;43m20251223153148452 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: rt->dest.as proto is M3UA for dpc=187=0.23.3 (mtp3_hmrt.c:90)[0;m [38;5;43m20251223153148452 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(bsc0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:563)[0;m [38;5;43m20251223153148452 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(bsc0-0) Tx 36 bytes: [L2]> 01 00 01 01 00 00 00 24 02 10 00 19 00 00 00 b9 00 00 00 bb 03 00 00 00 04 00 00 86 8a a3 76 00 00 00 00 00 (ss7_asp.c:1400)[0;m [38;5;43m20251223153148453 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(bsc0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1107)[0;m [38;5;49m20251223153148453 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(bsc0-0) Received M3UA Message (XFER:DATA) (m3ua.c:811)[0;m [38;5;49m20251223153148453 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(bsc0-0) m3ua_rx_xfer (m3ua.c:601)[0;m [38;5;49m20251223153148453 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(bsc0-0) m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3 dpc=185=0.23.1 sls=14 (m3ua.c:632)[0;m [38;5;43m20251223153148453 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: mtp3_hmdc_rx_from_l2(): dpc=185=0.23.1 not local, message is for routing (mtp3_hmdc.c:49)[0;m [38;5;43m20251223153148453 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: CombinedLinkset(dpc=185=0.23.1,mask=0x3fff=7.255.7,prio=5) RT lookup: OPC=187=0.23.3,DPC=185=0.23.1,SLS=14 -> eSLS=46: use Normal Route via 'virt-msc0' (ss7_combined_linkset.c:275)[0;m [38;5;43m20251223153148453 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: Found route for dpc=185=0.23.1: pc=185=0.23.1 mask=0x3fff=7.255.7 prio=5 via AS virt-msc0 proto=m3ua (mtp3_hmrt.c:80)[0;m [38;5;43m20251223153148453 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: rt->dest.as proto is M3UA for dpc=185=0.23.1 (mtp3_hmrt.c:90)[0;m [38;5;43m20251223153148453 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(virt-msc0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:563)[0;m [38;5;43m20251223153148453 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(virt-msc0-0) Tx 40 bytes: [L2]> 01 00 01 01 00 00 00 28 02 10 00 17 00 00 00 bb 00 00 00 b9 03 00 00 0e 05 8a a3 76 00 00 86 00 00 06 00 08 00 00 00 01 (ss7_asp.c:1400)[0;m VirtMSC-SCCP(2258)@a27f6ca23576: Session index based on local reference:2 VirtMSC-SCCP(2258)@a27f6ca23576: RLC received in state:disconnect_pending MTC@a27f6ca23576: talloc reports "struct bsc_subscr" x 0, expecting 0 MTC@a27f6ca23576: talloc reports "struct gsm_subscriber_connection" x 0, expecting 0 VirtMSC-STATS(2257)@a27f6ca23576: Final verdict of PTC: none IPA-CTRL-CLI-IPA(2262)@a27f6ca23576: Final verdict of PTC: none MTC@a27f6ca23576: setverdict(pass): none -> pass [38;5;23m20251223153148469 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m virt-msc0-0 NOTIFICATION SHUTDOWN_EVENT flags=0x0 (stream.c:360)[0;m [38;5;43m20251223153148469 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(virt-msc0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x80) (ss7_asp.c:1107)[0;m [38;5;43m20251223153148469 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0: ASP(virt-msc0-0) xUA SRV SCTP NOTIFICATION 32773 flags=0x0 (ss7_asp.c:998)[0;m [38;5;43m20251223153148469 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: ASP(virt-msc0-0) xUA SRV SHUTDOWN_EVENT (ss7_asp.c:1021)[0;m [38;5;23m20251223153148469 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m virt-msc0-0 NOTIFICATION ASSOC_CHANGE flags=0x0 SHUTDOWN_COMP (stream.c:360)[0;m [38;5;43m20251223153148469 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(virt-msc0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x80) (ss7_asp.c:1107)[0;m [38;5;43m20251223153148469 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0: ASP(virt-msc0-0) xUA SRV SCTP NOTIFICATION 32769 flags=0x0 (ss7_asp.c:998)[0;m [38;5;43m20251223153148469 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: ASP(virt-msc0-0) xUA SRV SCTP_ASSOC_CHANGE: SHUTDOWN_COMP (ss7_asp.c:1006)[0;m [38;5;23m20251223153148469 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m SRVCONN(virt-msc0-0,r=127.0.0.1:23905<->l=127.0.0.1:2905) connection closed with client (stream_srv.c:829)[0;m [38;5;43m20251223153148469 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(virt-msc0-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1107)[0;m [38;5;43m20251223153148469 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m virt-msc0-0: connection closed (ss7_asp.c:1349)[0;m [38;5;43m20251223153148469 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(virt-msc0-0){ASP_ACTIVE}: Received Event SCTP-COMM_DOWN.ind (ss7_asp.c:1355)[0;m [38;5;43m20251223153148469 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(virt-msc0-0){ASP_ACTIVE}: state_chg to ASP_DOWN (xua_asp_fsm.c:859)[0;m [38;5;43m20251223153148469 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(virt-msc0-0){ASP_DOWN}: T(beat) stopped (xua_asp_fsm.c:429)[0;m [38;5;43m20251223153148469 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(virt-msc0){AS_ACTIVE}: Received Event ASPAS-ASP_DOWN.ind (xua_asp_fsm.c:405)[0;m [38;5;43m20251223153148469 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(virt-msc0){AS_ACTIVE}: state_chg to AS_PENDING (xua_as_fsm.c:592)[0;m [38;5;43m20251223153148469 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(virt-msc0){AS_PENDING}: Broadcasting NOTIFY Type State Change:AS Pending () to all non-DOWN ASPs (xua_as_fsm.c:116)[0;m [38;5;43m20251223153148469 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: RT(dpc=185=0.23.1,mask=0x3fff=7.255.7,prio=5,via=virt-msc0,st=available) changed to status 'unavailable' (ss7_route.c:409)[0;m [38;5;43m20251223153148469 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: RT(dpc=185=0.23.1,mask=0x3fff=7.255.7,prio=5,via=virt-msc0,st=available) changed to status 'unavailable' (ss7_route.c:409)[0;m [38;5;43m20251223153148470 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(bsc0-0) Tx 16 bytes: [L2]> 01 00 02 01 00 00 00 10 00 12 00 08 00 00 00 b9 (ss7_asp.c:1400)[0;m VirtSMLC-SCCP(2260)@a27f6ca23576: Final verdict of PTC: none VirtMSC-M3UA(2259)@a27f6ca23576: Final verdict of PTC: none VirtMSC-SCCP(2258)@a27f6ca23576: Final verdict of PTC: none [38;5;43m20251223153148470 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(virt-smlc-0) Tx 24 bytes: [L2]> 01 00 02 01 00 00 00 18 00 06 00 08 00 00 00 06 00 12 00 08 00 00 00 b9 (ss7_asp.c:1400)[0;m [38;5;43m20251223153148470 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(virt-msc0-0){ASP_DOWN}: No Layer Manager, dropping M-ASP_DOWN.indication (xua_asp_fsm.c:129)[0;m [38;5;43m20251223153148470 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(virt-msc0-0){ASP_DOWN}: No Layer Manager, dropping M-SCTP_RELEASE.indication (xua_asp_fsm.c:129)[0;m [38;5;23m20251223153148470 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m virt-smlc-0 NOTIFICATION SHUTDOWN_EVENT flags=0x0 (stream.c:360)[0;m [38;5;43m20251223153148470 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(virt-smlc-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x80) (ss7_asp.c:1107)[0;m [38;5;43m20251223153148470 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0: ASP(virt-smlc-0) xUA SRV SCTP NOTIFICATION 32773 flags=0x0 (ss7_asp.c:998)[0;m [38;5;43m20251223153148470 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: ASP(virt-smlc-0) xUA SRV SHUTDOWN_EVENT (ss7_asp.c:1021)[0;m [38;5;23m20251223153148470 [1;31mDLINP[0;m[38;5;23m [1;31mERROR[0;m[38;5;23m SRVCONN(virt-smlc-0,r=127.0.0.1:23908<->l=127.0.0.1:2905) error to send: Bad file descriptor (stream_srv.c:803)[0;m [38;5;23m20251223153148470 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m SRVCONN(ts-0-2-rsl,r=127.0.0.1:10000<->l=127.0.0.1:3003) connection closed with client (stream_srv.c:777)[0;m [38;5;23m20251223153148470 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m E1TS(0:2) failed reading from socket: 0 (ipaccess.c:554)[0;m [38;5;23m20251223153148470 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m E1L(0) Signalling link down (bts_ipaccess_nanobts.c:778)[0;m [38;5;23m20251223153148470 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m (bts=0) Dropping OML link: link down (bts_ipaccess_nanobts.c:543)[0;m [38;5;27m20251223153148470 [1;31mDLMI[0;m[38;5;27m [1;31mERROR[0;m[38;5;27m (bts=0,trx=0) Lost E1 OML link (osmo_bsc_main.c:487)[0;m [38;5;23m20251223153148470 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m virt-smlc-0 NOTIFICATION ASSOC_CHANGE flags=0x0 SHUTDOWN_COMP (stream.c:360)[0;m [38;5;43m20251223153148470 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(virt-smlc-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x80) (ss7_asp.c:1107)[0;m [38;5;43m20251223153148470 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0: ASP(virt-smlc-0) xUA SRV SCTP NOTIFICATION 32769 flags=0x0 (ss7_asp.c:998)[0;m [38;5;43m20251223153148470 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: ASP(virt-smlc-0) xUA SRV SCTP_ASSOC_CHANGE: SHUTDOWN_COMP (ss7_asp.c:1006)[0;m [38;5;23m20251223153148470 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m SRVCONN(virt-smlc-0,r=127.0.0.1:23908<->l=127.0.0.1:2905) connection closed with client (stream_srv.c:829)[0;m [38;5;43m20251223153148470 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(virt-smlc-0) ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1107)[0;m [38;5;43m20251223153148470 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m virt-smlc-0: connection closed (ss7_asp.c:1349)[0;m [38;5;43m20251223153148470 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(virt-smlc-0){ASP_ACTIVE}: Received Event SCTP-COMM_DOWN.ind (ss7_asp.c:1355)[0;m [38;5;43m20251223153148470 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(virt-smlc-0){ASP_ACTIVE}: state_chg to ASP_DOWN (xua_asp_fsm.c:859)[0;m [38;5;43m20251223153148470 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(virt-smlc-0){ASP_DOWN}: T(beat) stopped (xua_asp_fsm.c:429)[0;m [38;5;43m20251223153148470 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(virt-smlc){AS_ACTIVE}: Received Event ASPAS-ASP_DOWN.ind (xua_asp_fsm.c:405)[0;m [38;5;43m20251223153148470 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(virt-smlc){AS_ACTIVE}: state_chg to AS_PENDING (xua_as_fsm.c:592)[0;m [38;5;43m20251223153148470 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(virt-smlc){AS_PENDING}: Broadcasting NOTIFY Type State Change:AS Pending () to all non-DOWN ASPs (xua_as_fsm.c:116)[0;m [38;5;43m20251223153148470 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: RT(dpc=190=0.23.6,mask=0x3fff=7.255.7,prio=5,via=virt-smlc,st=available) changed to status 'unavailable' (ss7_route.c:409)[0;m [38;5;43m20251223153148470 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: RT(dpc=190=0.23.6,mask=0x3fff=7.255.7,prio=5,via=virt-smlc,st=available) changed to status 'unavailable' (ss7_route.c:409)[0;m [38;5;43m20251223153148470 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(bsc0-0) Tx 16 bytes: [L2]> 01 00 02 01 00 00 00 10 00 12 00 08 00 00 00 be (ss7_asp.c:1400)[0;m [38;5;43m20251223153148470 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(virt-smlc-0){ASP_DOWN}: No Layer Manager, dropping M-ASP_DOWN.indication (xua_asp_fsm.c:129)[0;m [38;5;43m20251223153148470 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(virt-smlc-0){ASP_DOWN}: No Layer Manager, dropping M-SCTP_RELEASE.indication (xua_asp_fsm.c:129)[0;m VirtSMLC-M3UA(2261)@a27f6ca23576: Final verdict of PTC: none VirtMGW-MGCP-0(2263)@a27f6ca23576: Final verdict of PTC: none [38;5;23m20251223153148472 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m E1L(0) Signalling link down (bts_ipaccess_nanobts.c:778)[0;m [38;5;27m20251223153148472 [1;31mDLMI[0;m[38;5;27m [1;31mERROR[0;m[38;5;27m (bts=0,trx=0) Lost E1 OSMO link (osmo_bsc_main.c:487)[0;m [38;5;23m20251223153148472 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m (bts=0,trx=0) Dropping RSL link: OML link drop (bts_ipaccess_nanobts.c:514)[0;m [38;5;27m20251223153148472 [1;31mDLMI[0;m[38;5;27m [1;31mERROR[0;m[38;5;27m (bts=0,trx=0) Lost E1 RSL link (osmo_bsc_main.c:487)[0;m [38;5;23m[1;33mDLINP[0;m[38;5;23m stream_cli.c:548 CLICONN(ts-0-1-oml,r=127.0.0.1:3002<->l=127.0.0.1:45913){CONNECTED} connection closed with srv[0;m [1;31mDABIS[0;m abis.c:429 E1L(0) Signalling link down [1;36m[1;33mDOML[0;m[1;36m bts_shutdown_fsm.c:268 BTS_SHUTDOWN(bts0)[0x55f392214990]{NONE}: Shutting down BTS, exit 1, reason: Abis close[0;m [38;5;23m[1;33mDLINP[0;m[38;5;23m input/ipaccess.c:926 E1TS(0:1) failed reading from socket: 0[0;m IPA-BTS0-TRX0-RSL-IPA(2264)@a27f6ca23576: Final verdict of PTC: none MTC@a27f6ca23576: Setting final verdict of the test case. [38;5;49m20251223153148473 [1;33mDLM3UA[0;m[38;5;49m [1;33mNOTICE[0;m[38;5;49m 0: ASP(asp-clnt-msc-0) Rx DUNA() for 0.23.1/0 (xua_snm.c:501)[0;m [38;5;43m20251223153148473 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: RT(dpc=185=0.23.1,mask=0x3fff=7.255.7,prio=5,via=as-clnt-A-0-m3ua,st=available) changed to status 'unavailable' (ss7_route.c:409)[0;m 20251223153148474 [1;33mDMSC[0;m [1;33mNOTICE[0;m (msc=0) now unreachable: N-PCSTATE ind: pc=185=0.23.1 sp_status=INACCESSIBLE remote_sccp_status=UNAVAILABLE_UNKNOWN (osmo_bsc_sigtran.c:311) 20251223153148474 [1;33mDRESET[0;m [1;33mNOTICE[0;m bssmap_reset(msc-0)[0x557077126dd0]{DISCONNECTED}: link lost (bssmap_reset.c:91) 20251223153148474 [1;33mDMSC[0;m [1;33mNOTICE[0;m (msc=0) BSSMAP association is down (a_reset.c:54) 20251223153148474 [1;31mDLCS[0;m [1;31mERROR[0;m Unhandled SIGTRAN primitive N-PCSTATE.indication (lb.c:212) [38;5;49m20251223153148474 [1;33mDLM3UA[0;m[38;5;49m [1;33mNOTICE[0;m[38;5;49m 0: ASP(asp-clnt-msc-0) Rx DUNA() for 0.23.6/0 (xua_snm.c:501)[0;m [38;5;43m20251223153148474 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: RT(dpc=190=0.23.6,mask=0x3fff=7.255.7,prio=5,via=as-clnt-A-0-m3ua,st=available) changed to status 'unavailable' (ss7_route.c:409)[0;m 20251223153148474 [1;31mDLCS[0;m [1;31mERROR[0;m Unhandled SIGTRAN primitive N-PCSTATE.indication (lb.c:212) MTC@a27f6ca23576: Local verdict of MTC: pass MTC@a27f6ca23576: Local verdict of PTC VirtMSC-STATS(2257): none (pass -> pass) MTC@a27f6ca23576: Local verdict of PTC VirtMSC-SCCP(2258): none (pass -> pass) MTC@a27f6ca23576: Local verdict of PTC VirtMSC-M3UA(2259): none (pass -> pass) MTC@a27f6ca23576: Local verdict of PTC VirtSMLC-SCCP(2260): none (pass -> pass) MTC@a27f6ca23576: Local verdict of PTC VirtSMLC-M3UA(2261): none (pass -> pass) MTC@a27f6ca23576: Local verdict of PTC IPA-CTRL-CLI-IPA(2262): none (pass -> pass) MTC@a27f6ca23576: Local verdict of PTC VirtMGW-MGCP-0(2263): none (pass -> pass) MTC@a27f6ca23576: Local verdict of PTC IPA-BTS0-TRX0-RSL-IPA(2264): none (pass -> pass) MTC@a27f6ca23576: Test case TC_dyn_ts_sdcch8_act_deact finished. Verdict: pass MTC@a27f6ca23576: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-io_uring/ws/ttcn3-tc…> BSC_Tests.TC_dyn_ts_sdcch8_act_deact pass'. Tue Dec 23 15:31:48 UTC 2025 [1;32m====== BSC_Tests.TC_dyn_ts_sdcch8_act_deact pass ======[0m Saving talloc report from 127.0.0.1:4242 to BSC_Tests.TC_dyn_ts_sdcch8_act_deact.talloc [1;91m[testenv][generic] Timeout in wait_until_test_stopped()[0m [38;5;43m20251223153150471 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(virt-smlc){AS_PENDING}: Received Event AS-T_REC_EXPD.ind (xua_as_fsm.c:349)[0;m [38;5;43m20251223153150471 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(virt-smlc){AS_PENDING}: T(r) expired; dropping queued messages (xua_as_fsm.c:665)[0;m [38;5;43m20251223153150471 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(virt-smlc){AS_PENDING}: state_chg to AS_DOWN (xua_as_fsm.c:675)[0;m [38;5;43m20251223153150471 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(virt-msc0){AS_PENDING}: Received Event AS-T_REC_EXPD.ind (xua_as_fsm.c:349)[0;m [38;5;43m20251223153150471 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(virt-msc0){AS_PENDING}: T(r) expired; dropping queued messages (xua_as_fsm.c:665)[0;m [38;5;43m20251223153150471 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(virt-msc0){AS_PENDING}: state_chg to AS_DOWN (xua_as_fsm.c:675)[0;m [1;34m[testenv][generic] Running gdb to get a backtrace[0m [0;94m[testenv][generic] + ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-io_uring/ws/_cache',> '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TESTENV_INSTALL_DIR=/', '-e', 'TERM=dumb', '-e', 'PATH=<https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-io_uring/ws/_testenv…>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-io_uring/ws/_testenv…>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-io_uring/ws/bsc>:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-e', 'PYTHONUNBUFFERED=1', '-t', 'testenv-bsc-generic-osmocom-nightly-20251223-1438-1450a117-0', 'sh', '-c', 'echo; gdb --batch /usr/bin/osmo-bts-omldummy <https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-io_uring/441/artifac…> -ex bt | tee <https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-io_uring/441/artifac…;> echo'][0m [New LWP 23711] [Thread debugging using libthread_db enabled] Using host libthread_db library "/lib/x86_64-linux-gnu/libthread_db.so.1". Core was generated by `/usr/bin/osmo-bts-omldummy -fCCN,EGPRS,GPRS,IPv6_NSVC,PAGING_COORDINATION,OSMUX 127.0.0.1 1235 1'. Program terminated with signal SIGABRT, Aborted. warning: 44 ./nptl/pthread_kill.c: No such file or directory #0 __pthread_kill_implementation (threadid=<optimized out>, signo=signo@entry=6, no_tid=no_tid@entry=0) at ./nptl/pthread_kill.c:44 #0 __pthread_kill_implementation (threadid=<optimized out>, signo=signo@entry=6, no_tid=no_tid@entry=0) at ./nptl/pthread_kill.c:44 #1 0x00007efe64e259ff in __pthread_kill_internal (threadid=<optimized out>, signo=6) at ./nptl/pthread_kill.c:89 #2 0x00007efe64dd0cc2 in __GI_raise (sig=sig@entry=6) at ../sysdeps/posix/raise.c:26 #3 0x00007efe64db94ac in __GI_abort () at ./stdlib/abort.c:73 #4 0x00007efe64fbd7b6 in osmo_panic_default (fmt=0x7efe64fdb630 "failure during io_uring_queue_init(): %s\n", args=0x7ffd4bcaf000) at ./src/core/panic.c:45 #5 osmo_panic (fmt=fmt@entry=0x7efe64fdb630 "failure during io_uring_queue_init(): %s\n") at ./src/core/panic.c:80 #6 0x00007efe64fd4666 in osmo_iofd_uring_init () at ./src/core/osmo_io_uring.c:138 #7 0x00007efe65126fae in call_init (l=<optimized out>, argc=5, argv=0x7ffd4bcaf188, env=0x7ffd4bcaf1b8) at ./elf/dl-init.c:74 #8 call_init (l=<optimized out>, argc=5, argv=0x7ffd4bcaf188, env=0x7ffd4bcaf1b8) at ./elf/dl-init.c:26 #9 0x00007efe6512707c in _dl_init (main_map=0x7efe65159310, argc=5, argv=0x7ffd4bcaf188, env=0x7ffd4bcaf1b8) at ./elf/dl-init.c:121 #10 0x00007efe6513e470 in _dl_start_user () from /lib64/ld-linux-x86-64.so.2 #11 0x0000000000000005 in ?? () #12 0x00007ffd4bcb0c35 in ?? () #13 0x00007ffd4bcb0c47 in ?? () #14 0x00007ffd4bcb0c7c in ?? () #15 0x00007ffd4bcb0c86 in ?? () #16 0x00007ffd4bcb0c8b in ?? () #17 0x0000000000000000 in ?? () [1;34m[testenv][generic] Stopping podman container[0m [0;94m[testenv][generic] + ['podman', 'kill', 'testenv-bsc-generic-osmocom-nightly-20251223-1438-1450a117-0'][0m testenv-bsc-generic-osmocom-nightly-20251223-1438-1450a117-0 <https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-io_uring/441/artifac…>: ELF 64-bit LSB core file, x86-64, version 1 (SYSV), SVR4-style, from 'osmo-bts-omldummy -fCCN,EGPRS,GPRS,IPv6_NSVC,PAGING_COORDINATION,OSMUX 127.0.0.', real uid: 0, effective uid: 0, real gid: 0, effective gid: 0, execfn: '/usr/bin/osmo-bts-omldummy', platform: 'x86_64' [1;34m[testenv][generic] Stopping testsuite (3027447)[0m Error: container has already been removed [0;94m[testenv] feed_watchdog_loop: podman container has stopped[0m [1;34m[testenv][generic] Logs saved to: https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-io_uring/441/artifac… [0m + RC=1 + [ 1 = 0 ] + grep --color=always -o load.* + uptime [01;31m[Kload average: 1.68, 4.28, 3.79[m[K + exit 1 Build step 'Execute shell' marked build as failure Recording test results [Checks API] No suitable checks publisher found.

1 1

Build failed in Jenkins: ttcn3-bsc-test-asan #566
by jenkins＠lists.osmocom.org 23 Dec '25

23 Dec '25

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-asan/566/display/red…> Changes: ------------------------------------------ Started by timer Running as SYSTEM Building remotely on build4-deb12build-ansible (registry-build-amd64 ttcn3 obs osmo-gsm-tester-build ttcn3_with_linux_6.1_or_higher qemu io_uring osmocom-gerrit coverity osmocom-master) in workspace /home/osmocom-build/jenkins/workspace/ttcn3-bsc-test-asan FATAL: java.io.IOException: Unexpected reader termination java.lang.OutOfMemoryError: Java heap space Caused: java.io.IOException: Unexpected reader termination Also: hudson.remoting.Channel$CallSiteStackTrace: Remote call to build4-deb12build-ansible at hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1923) at hudson.remoting.Request.call(Request.java:204) at hudson.remoting.Channel.call(Channel.java:1111) at hudson.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:98) 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 1

Build failed in Jenkins: ttcn3-bsc-test-sccplite-asan #173
by jenkins＠lists.osmocom.org 23 Dec '25

23 Dec '25

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-sccplite-asan/173/di…> Changes: [Pau Espin Pedrol] library/NGAP_Templates: PDUSessionResourceSetupRequest: Fix criticality ------------------------------------------ [...truncated 47.56 KiB...] DEP Osmocom_CTRL_Types_part_5.cc DEP Osmocom_CTRL_Types_part_6.cc DEP Osmocom_CTRL_Types_part_7.cc DEP Osmocom_Types_part_1.cc DEP Osmocom_Types_part_2.cc DEP Osmocom_Types_part_3.cc DEP Osmocom_Types_part_4.cc DEP Osmocom_Types_part_5.cc DEP Osmocom_Types_part_6.cc DEP Osmocom_Types_part_7.cc DEP Osmocom_VTY_Functions_part_1.cc DEP Osmocom_VTY_Functions_part_2.cc DEP Osmocom_VTY_Functions_part_3.cc DEP Osmocom_VTY_Functions_part_4.cc DEP Osmocom_VTY_Functions_part_5.cc DEP Osmocom_VTY_Functions_part_6.cc DEP Osmocom_VTY_Functions_part_7.cc DEP RLCMAC_CSN1_Templates_part_1.cc DEP RLCMAC_CSN1_Templates_part_2.cc DEP RLCMAC_CSN1_Templates_part_3.cc DEP RLCMAC_CSN1_Templates_part_4.cc DEP RLCMAC_CSN1_Templates_part_5.cc DEP RLCMAC_CSN1_Templates_part_6.cc DEP RLCMAC_CSN1_Templates_part_7.cc DEP RLCMAC_CSN1_Types_part_1.cc DEP RLCMAC_CSN1_Types_part_2.cc DEP RLCMAC_CSN1_Types_part_3.cc DEP RLCMAC_CSN1_Types_part_4.cc DEP RLCMAC_CSN1_Types_part_5.cc DEP RLCMAC_CSN1_Types_part_6.cc DEP RLCMAC_CSN1_Types_part_7.cc DEP RSL_Emulation_part_1.cc DEP RSL_Emulation_part_2.cc DEP RSL_Emulation_part_3.cc DEP RSL_Emulation_part_4.cc DEP RSL_Emulation_part_5.cc DEP RSL_Emulation_part_6.cc DEP RSL_Emulation_part_7.cc DEP RSL_Types_part_1.cc DEP RSL_Types_part_2.cc DEP RSL_Types_part_3.cc DEP RSL_Types_part_4.cc DEP RSL_Types_part_5.cc DEP RSL_Types_part_6.cc DEP RSL_Types_part_7.cc DEP RTP_CodecPort_part_1.cc DEP RTP_CodecPort_part_2.cc DEP RTP_CodecPort_part_3.cc DEP RTP_CodecPort_part_4.cc DEP RTP_CodecPort_part_5.cc DEP RTP_CodecPort_part_6.cc DEP RTP_CodecPort_part_7.cc DEP RTP_CodecPort_CtrlFunct_part_1.cc DEP RTP_CodecPort_CtrlFunct_part_2.cc DEP RTP_CodecPort_CtrlFunct_part_3.cc DEP RTP_CodecPort_CtrlFunct_part_4.cc DEP RTP_CodecPort_CtrlFunct_part_5.cc DEP RTP_CodecPort_CtrlFunct_part_6.cc DEP RTP_CodecPort_CtrlFunct_part_7.cc DEP RTP_Emulation_part_1.cc DEP RTP_Emulation_part_2.cc DEP RTP_Emulation_part_3.cc DEP RTP_Emulation_part_4.cc DEP RTP_Emulation_part_5.cc DEP RTP_Emulation_part_6.cc DEP RTP_Emulation_part_7.cc DEP RTP_Types_part_1.cc DEP RTP_Types_part_2.cc DEP RTP_Types_part_3.cc DEP RTP_Types_part_4.cc DEP RTP_Types_part_5.cc DEP RTP_Types_part_6.cc DEP RTP_Types_part_7.cc DEP SCCP_Emulation_part_1.cc DEP SCCP_Emulation_part_2.cc DEP SCCP_Emulation_part_3.cc DEP SCCP_Emulation_part_4.cc DEP SCCP_Emulation_part_5.cc DEP SCCP_Emulation_part_6.cc DEP SCCP_Emulation_part_7.cc DEP SCCP_Templates_part_1.cc DEP SCCP_Templates_part_2.cc DEP SCCP_Templates_part_3.cc DEP SCCP_Templates_part_4.cc DEP SCCP_Templates_part_5.cc DEP SCCP_Templates_part_6.cc DEP SCCP_Templates_part_7.cc DEP SCCP_Types_part_1.cc DEP SCCP_Types_part_2.cc DEP SCCP_Types_part_3.cc DEP SCCP_Types_part_4.cc DEP SCCP_Types_part_5.cc DEP SCCP_Types_part_6.cc DEP SCCP_Types_part_7.cc DEP SCCPasp_Types_part_1.cc DEP SCCPasp_Types_part_2.cc DEP SCCPasp_Types_part_3.cc DEP SCCPasp_Types_part_4.cc DEP SCCPasp_Types_part_5.cc DEP SCCPasp_Types_part_6.cc DEP SCCPasp_Types_part_7.cc DEP SCTPasp_PortType_part_1.cc DEP SCTPasp_PortType_part_2.cc DEP SCTPasp_PortType_part_3.cc DEP SCTPasp_PortType_part_4.cc DEP SCTPasp_PortType_part_5.cc DEP SCTPasp_PortType_part_6.cc DEP SCTPasp_PortType_part_7.cc DEP SCTPasp_Types_part_1.cc DEP SCTPasp_Types_part_2.cc DEP SCTPasp_Types_part_3.cc DEP SCTPasp_Types_part_4.cc DEP SCTPasp_Types_part_5.cc DEP SCTPasp_Types_part_6.cc DEP SCTPasp_Types_part_7.cc DEP SDP_Templates_part_1.cc DEP SDP_Templates_part_2.cc DEP SDP_Templates_part_3.cc DEP SDP_Templates_part_4.cc DEP SDP_Templates_part_5.cc DEP SDP_Templates_part_6.cc DEP SDP_Templates_part_7.cc DEP SDP_Types_part_1.cc DEP SDP_Types_part_2.cc DEP SDP_Types_part_3.cc DEP SDP_Types_part_4.cc DEP SDP_Types_part_5.cc DEP SDP_Types_part_6.cc DEP SDP_Types_part_7.cc DEP Socket_API_Definitions_part_1.cc DEP Socket_API_Definitions_part_2.cc DEP Socket_API_Definitions_part_3.cc DEP Socket_API_Definitions_part_4.cc DEP Socket_API_Definitions_part_5.cc DEP Socket_API_Definitions_part_6.cc DEP Socket_API_Definitions_part_7.cc DEP StatsD_CodecPort_part_1.cc DEP StatsD_CodecPort_part_2.cc DEP StatsD_CodecPort_part_3.cc DEP StatsD_CodecPort_part_4.cc DEP StatsD_CodecPort_part_5.cc DEP StatsD_CodecPort_part_6.cc DEP StatsD_CodecPort_part_7.cc DEP StatsD_CodecPort_CtrlFunct_part_1.cc DEP StatsD_CodecPort_CtrlFunct_part_2.cc DEP StatsD_CodecPort_CtrlFunct_part_3.cc DEP StatsD_CodecPort_CtrlFunct_part_4.cc DEP StatsD_CodecPort_CtrlFunct_part_5.cc DEP StatsD_CodecPort_CtrlFunct_part_6.cc DEP StatsD_CodecPort_CtrlFunct_part_7.cc DEP StatsD_Types_part_1.cc DEP StatsD_Types_part_2.cc DEP StatsD_Types_part_3.cc DEP StatsD_Types_part_4.cc DEP StatsD_Types_part_5.cc DEP StatsD_Types_part_6.cc DEP StatsD_Types_part_7.cc DEP TCCConversion_Functions_part_1.cc DEP TCCConversion_Functions_part_2.cc DEP TCCConversion_Functions_part_3.cc DEP TCCConversion_Functions_part_4.cc DEP TCCConversion_Functions_part_5.cc DEP TCCConversion_Functions_part_6.cc DEP TCCInterface_Functions_part_1.cc DEP TCCInterface_Functions_part_2.cc DEP TCCConversion_Functions_part_7.cc DEP TCCInterface_Functions_part_3.cc DEP TCCInterface_Functions_part_4.cc DEP TCCInterface_Functions_part_5.cc DEP TCCInterface_Functions_part_6.cc DEP TCCInterface_Functions_part_7.cc DEP TELNETasp_PortType_part_1.cc DEP TELNETasp_PortType_part_2.cc DEP TELNETasp_PortType_part_3.cc DEP TELNETasp_PortType_part_4.cc DEP TELNETasp_PortType_part_5.cc DEP TELNETasp_PortType_part_6.cc DEP TELNETasp_PortType_part_7.cc DEP IPA_Emulation.cc DEP RAN_Adapter.cc DEP RAN_Emulation.cc DEP SCCP_Adapter.cc DEP SCCP_Mapping.cc DEP StatsD_Checker.cc DEP IPA_Emulation_part_1.cc DEP IPA_Emulation_part_2.cc DEP IPA_Emulation_part_3.cc DEP IPA_Emulation_part_4.cc DEP IPA_Emulation_part_5.cc FATAL: command execution failed java.lang.OutOfMemoryError: Java heap space Caused: java.io.IOException: Unexpected reader termination at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:98) Caused: java.io.IOException: Backing channel 'build4-deb12build-ansible' is disconnected. at hudson.remoting.RemoteInvocationHandler.channelOrFail(RemoteInvocationHandler.java:227) at hudson.remoting.RemoteInvocationHandler.invoke(RemoteInvocationHandler.java:306) at jdk.proxy2/jdk.proxy2.$Proxy156.isAlive(Unknown Source) at hudson.Launcher$RemoteLauncher$ProcImpl.isAlive(Launcher.java:1212) at hudson.Launcher$RemoteLauncher$ProcImpl.join(Launcher.java:1204) at hudson.tasks.CommandInterpreter.join(CommandInterpreter.java:195) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:145) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:92) at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:818) at hudson.model.Build$BuildExecution.build(Build.java:199) at hudson.model.Build$BuildExecution.doRun(Build.java:164) at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:527) at hudson.model.Run.execute(Run.java:1833) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:44) at hudson.model.ResourceController.execute(ResourceController.java:101) at hudson.model.Executor.run(Executor.java:446) FATAL: Unable to delete script file /tmp/jenkins5153914970880604229.sh java.lang.OutOfMemoryError: Java heap space Caused: java.io.IOException: Unexpected reader termination at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:98) Caused: hudson.remoting.ChannelClosedException: Channel "hudson.remoting.Channel@2dbf888b:build4-deb12build-ansible": Remote call on build4-deb12build-ansible failed. The channel is closing down or has closed down at hudson.remoting.Channel.call(Channel.java:1105) at hudson.FilePath.act(FilePath.java:1207) at hudson.FilePath.act(FilePath.java:1196) at hudson.FilePath.delete(FilePath.java:1743) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:163) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:92) at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:818) at hudson.model.Build$BuildExecution.build(Build.java:199) at hudson.model.Build$BuildExecution.doRun(Build.java:164) at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:527) at hudson.model.Run.execute(Run.java:1833) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:44) at hudson.model.ResourceController.execute(ResourceController.java:101) at hudson.model.Executor.run(Executor.java:446) Build step 'Execute shell' marked build as failure FATAL: Channel "hudson.remoting.Channel@2dbf888b:build4-deb12build-ansible": Remote call on build4-deb12build-ansible failed. The channel is closing down or has closed down java.lang.OutOfMemoryError: Java heap space Caused: java.io.IOException: Unexpected reader termination at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:98) Caused: hudson.remoting.ChannelClosedException: Channel "hudson.remoting.Channel@2dbf888b:build4-deb12build-ansible": Remote call on build4-deb12build-ansible failed. The channel is closing down or has closed down at hudson.remoting.Channel.call(Channel.java:1105) at hudson.Launcher$RemoteLauncher.kill(Launcher.java:1147) at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:539) at hudson.model.Run.execute(Run.java:1833) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:44) at hudson.model.ResourceController.execute(ResourceController.java:101) at hudson.model.Executor.run(Executor.java:446) Recording test results Agent went offline during the build ERROR: Connection was broken Build step 'Publish JUnit test result report' marked build as failure DEP IPA_Emulation_part_6.cc

1 1

Build failed in Jenkins: ttcn3-gbproxy-test-asan #563
by jenkins＠lists.osmocom.org 23 Dec '25

23 Dec '25

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-gbproxy-test-asan/563/display…> Changes: ------------------------------------------ Started by timer Running as SYSTEM Building remotely on build4-deb12build-ansible (registry-build-amd64 ttcn3 obs osmo-gsm-tester-build ttcn3_with_linux_6.1_or_higher qemu io_uring osmocom-gerrit coverity osmocom-master) in workspace /home/osmocom-build/jenkins/workspace/ttcn3-gbproxy-test-asan The recommended git tool is: NONE No credentials specified FATAL: java.io.IOException: Unexpected termination of the channel java.io.EOFException at java.base/java.io.ObjectInputStream$PeekInputStream.readFully(Unknown Source) at java.base/java.io.ObjectInputStream$BlockDataInputStream.readShort(Unknown Source) at java.base/java.io.ObjectInputStream.readStreamHeader(Unknown Source) at java.base/java.io.ObjectInputStream.<init>(Unknown Source) at hudson.remoting.ObjectInputStreamEx.<init>(ObjectInputStreamEx.java:50) at hudson.remoting.Command.readFrom(Command.java:141) at hudson.remoting.Command.readFrom(Command.java:127) at hudson.remoting.AbstractSynchronousByteArrayCommandTransport.read(AbstractSynchronousByteArrayCommandTransport.java:35) at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:62) Caused: java.io.IOException: Unexpected termination of the channel at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:80) Also: hudson.remoting.Channel$CallSiteStackTrace: Remote call to build4-deb12build-ansible at hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1923) at hudson.remoting.Request.call(Request.java:204) at hudson.remoting.Channel.call(Channel.java:1111) at hudson.remoting.RemoteInvocationHandler.invoke(RemoteInvocationHandler.java:306) at PluginClassLoader for git-client/jdk.proxy68/jdk.proxy68.$Proxy152.hasGitRepo(Unknown Source) at PluginClassLoader for git-client//org.jenkinsci.plugins.gitclient.RemoteGitImpl.hasGitRepo(RemoteGitImpl.java:330) at PluginClassLoader for git//hudson.plugins.git.GitSCM.retrieveChanges(GitSCM.java:1205) at PluginClassLoader for git//hudson.plugins.git.GitSCM.checkout(GitSCM.java:1303) at hudson.scm.SCM.checkout(SCM.java:540) at hudson.model.AbstractProject.checkout(AbstractProject.java:1250) at hudson.model.AbstractBuild$AbstractBuildExecution.defaultCheckout(AbstractBuild.java:649) at jenkins.scm.SCMCheckoutStrategy.checkout(SCMCheckoutStrategy.java:85) at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:522) at hudson.model.Run.execute(Run.java:1833) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:44) at hudson.model.ResourceController.execute(ResourceController.java:101) at hudson.model.Executor.run(Executor.java:446) Caused: hudson.remoting.RequestAbortedException at hudson.remoting.Request.abort(Request.java:358) at hudson.remoting.Channel.terminate(Channel.java:1196) at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:95) ERROR: Step ‘Publish JUnit test result report’ failed: no workspace for ttcn3-gbproxy-test-asan #563

1 1

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

23 Dec '25

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-ggsn-test-asan/565/display/re…> Changes: [Pau Espin Pedrol] library/NGAP_Templates: PDUSessionResourceSetupRequest: Fix criticality ------------------------------------------ [...truncated 212.37 KiB...] MTC@6e07f179bda6: "TC_pdp46_act_deact_gtpu_access_wrong_ll_saddr_ipv6() start" 20251222170130260 [1;33mDLGLOBAL[0;m [1;33mNOTICE[0;m TTCN3 f_logp(): TC_pdp46_act_deact_gtpu_access_wrong_ll_saddr_ipv6() start (logging_vty.c:1209) MTC@6e07f179bda6: sending CreatePDP 20251222170130264 [1;32mDGGSN[0;m [1;32mINFO[0;m SGSN(172.18.3.1): Discovered (sgsn.c:83) 20251222170130264 [1;32mDGGSN[0;m [1;32mINFO[0;m PDP(262427287298525:10): Successful PDP Context Creation: APN=inet46(inet46), TEIC=1, IPv4=176.16.32.9, IPv6=2001:780:44:2109:: (ggsn.c:619) MTC@6e07f179bda6: setverdict(pass): none -> pass 20251222170130266 [1;31mDGGSN[0;m [1;31mERROR[0;m PDP(262427287298525:10): Packet from MS using unassigned src IPv6: fe80::3110:1691:1155:3018 (ggsn.c:785) MTC@6e07f179bda6: Warning: Stopping inactive timer T_default. 20251222170133271 [1;32mDGGSN[0;m [1;32mINFO[0;m PDP(262427287298525:10): Deleting PDP context (ggsn.c:403) 20251222170133271 [1;32mDGGSN[0;m [1;32mINFO[0;m SGSN(172.18.3.1): Deleting SGSN (sgsn.c:21) MTC@6e07f179bda6: setverdict(pass): pass -> pass, component reason not changed MTC@6e07f179bda6: setverdict(pass): pass -> pass, component reason not changed MTC@6e07f179bda6: Setting final verdict of the test case. MTC@6e07f179bda6: Local verdict of MTC: pass MTC@6e07f179bda6: No PTCs were created. MTC@6e07f179bda6: Test case TC_pdp46_act_deact_gtpu_access_wrong_ll_saddr_ipv6 finished. Verdict: pass MTC@6e07f179bda6: Starting external command `/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/ttcn3-tcpdump-stop.sh GGSN_Tests.TC_pdp46_act_deact_gtpu_access_wrong_ll_saddr_ipv6 pass'. Mon Dec 22 17:01:35 UTC 2025 [1;32m====== GGSN_Tests.TC_pdp46_act_deact_gtpu_access_wrong_ll_saddr_ipv6 pass ======[0m Saving talloc report from 172.18.3.2:4260 to GGSN_Tests.TC_pdp46_act_deact_gtpu_access_wrong_ll_saddr_ipv6.talloc Waiting for packet dumper to finish... 0 (prev_count=-1, count=10988) Waiting for packet dumper to finish... 1 (prev_count=10988, count=15576) MTC@6e07f179bda6: External command `/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/ttcn3-tcpdump-stop.sh GGSN_Tests.TC_pdp46_act_deact_gtpu_access_wrong_ll_saddr_ipv6 pass' was executed successfully (exit status: 0). MTC@6e07f179bda6: Starting external command `/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/ttcn3-tcpdump-start.sh GGSN_Tests.TC_pdp46_act_deact_gtpu_access_wrong_global_saddr_ipv6'. ------ GGSN_Tests.TC_pdp46_act_deact_gtpu_access_wrong_global_saddr_ipv6 ------ Mon Dec 22 17:01:37 UTC 2025 /usr/bin/dumpcap -q -s 1520 -n -i any -w "/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/logs/osmo_all/testsuite/GGSN_Tests.TC_pdp46_act_deact_gtpu_access_wrong_global_saddr_ipv6.pcap" >/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/logs/osmo_all/testsuite/GGSN_Tests.TC_pdp46_act_deact_gtpu_access_wrong_global_saddr_ipv6.pcap.stdout 2>/tmp/cmderr & Waiting for packet dumper to start... 0 MTC@6e07f179bda6: External command `/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/ttcn3-tcpdump-start.sh GGSN_Tests.TC_pdp46_act_deact_gtpu_access_wrong_global_saddr_ipv6' was executed successfully (exit status: 0). MTC@6e07f179bda6: Test case TC_pdp46_act_deact_gtpu_access_wrong_global_saddr_ipv6 started. MTC@6e07f179bda6: GTP1C ConnectionID: 1 MTC@6e07f179bda6: "TC_pdp46_act_deact_gtpu_access_wrong_global_saddr_ipv6() start" 20251222170138397 [1;33mDLGLOBAL[0;m [1;33mNOTICE[0;m TTCN3 f_logp(): TC_pdp46_act_deact_gtpu_access_wrong_global_saddr_ipv6() start (logging_vty.c:1209) MTC@6e07f179bda6: sending CreatePDP 20251222170138398 [1;32mDGGSN[0;m [1;32mINFO[0;m SGSN(172.18.3.1): Discovered (sgsn.c:83) 20251222170138398 [1;32mDGGSN[0;m [1;32mINFO[0;m PDP(262420478234213:2): Successful PDP Context Creation: APN=inet46(inet46), TEIC=1, IPv4=176.16.32.10, IPv6=2001:780:44:210a:: (ggsn.c:619) MTC@6e07f179bda6: setverdict(pass): none -> pass MTC@6e07f179bda6: RA with /64 prefix '200107800044210A0000000000000000'O 20251222170138399 [1;34mDICMP6[0;m [1;34mDEBUG[0;m Ignoring Rx ICMPv6 Neighbor Soliciation: 60 00 00 00 00 18 3a ff fe 80 00 00 00 00 00 00 20 01 07 80 00 44 21 0a ff 02 00 00 00 00 00 00 00 00 00 01 ff 44 21 0a 87 00 ca b9 00 00 00 00 fe 80 00 00 00 00 00 00 20 01 07 80 00 44 21 0a (icmpv6.c:328) 20251222170138399 [1;31mDGGSN[0;m [1;31mERROR[0;m PDP(262420478234213:2): Packet from MS using unassigned src IPv6: 3110:1691:1155:301b:325:325:325:325 (ggsn.c:785) MTC@6e07f179bda6: Warning: Stopping inactive timer T_default. 20251222170141404 [1;32mDGGSN[0;m [1;32mINFO[0;m PDP(262420478234213:2): Deleting PDP context (ggsn.c:403) 20251222170141405 [1;32mDGGSN[0;m [1;32mINFO[0;m SGSN(172.18.3.1): Deleting SGSN (sgsn.c:21) MTC@6e07f179bda6: setverdict(pass): pass -> pass, component reason not changed MTC@6e07f179bda6: setverdict(pass): pass -> pass, component reason not changed MTC@6e07f179bda6: Setting final verdict of the test case. MTC@6e07f179bda6: Local verdict of MTC: pass MTC@6e07f179bda6: No PTCs were created. MTC@6e07f179bda6: Test case TC_pdp46_act_deact_gtpu_access_wrong_global_saddr_ipv6 finished. Verdict: pass MTC@6e07f179bda6: Starting external command `/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/ttcn3-tcpdump-stop.sh GGSN_Tests.TC_pdp46_act_deact_gtpu_access_wrong_global_saddr_ipv6 pass'. Mon Dec 22 17:01:43 UTC 2025 [1;32m====== GGSN_Tests.TC_pdp46_act_deact_gtpu_access_wrong_global_saddr_ipv6 pass ======[0m Saving talloc report from 172.18.3.2:4260 to GGSN_Tests.TC_pdp46_act_deact_gtpu_access_wrong_global_saddr_ipv6.talloc Waiting for packet dumper to finish... 0 (prev_count=-1, count=12408) Waiting for packet dumper to finish... 1 (prev_count=12408, count=16996) MTC@6e07f179bda6: External command `/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/ttcn3-tcpdump-stop.sh GGSN_Tests.TC_pdp46_act_deact_gtpu_access_wrong_global_saddr_ipv6 pass' was executed successfully (exit status: 0). MTC@6e07f179bda6: Starting external command `/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/ttcn3-tcpdump-start.sh GGSN_Tests.TC_pdp46_clients_interact'. ------ GGSN_Tests.TC_pdp46_clients_interact ------ Mon Dec 22 17:01:45 UTC 2025 /usr/bin/dumpcap -q -s 1520 -n -i any -w "/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/logs/osmo_all/testsuite/GGSN_Tests.TC_pdp46_clients_interact.pcap" >/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/logs/osmo_all/testsuite/GGSN_Tests.TC_pdp46_clients_interact.pcap.stdout 2>/tmp/cmderr & Waiting for packet dumper to start... 0 MTC@6e07f179bda6: External command `/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/ttcn3-tcpdump-start.sh GGSN_Tests.TC_pdp46_clients_interact' was executed successfully (exit status: 0). MTC@6e07f179bda6: Test case TC_pdp46_clients_interact started. MTC@6e07f179bda6: GTP1C ConnectionID: 1 MTC@6e07f179bda6: "TC_pdp46_clients_interact() start" 20251222170146549 [1;33mDLGLOBAL[0;m [1;33mNOTICE[0;m TTCN3 f_logp(): TC_pdp46_clients_interact() start (logging_vty.c:1209) MTC@6e07f179bda6: sending CreatePDP 20251222170146554 [1;32mDGGSN[0;m [1;32mINFO[0;m SGSN(172.18.3.1): Discovered (sgsn.c:83) 20251222170146554 [1;32mDGGSN[0;m [1;32mINFO[0;m PDP(262423560336054:7): Successful PDP Context Creation: APN=inet46(inet46), TEIC=1, IPv4=176.16.32.11, IPv6=2001:780:44:210b:: (ggsn.c:619) MTC@6e07f179bda6: setverdict(pass): none -> pass MTC@6e07f179bda6: RA with /64 prefix '200107800044210B0000000000000000'O 20251222170146557 [1;34mDICMP6[0;m [1;34mDEBUG[0;m Ignoring Rx ICMPv6 Neighbor Soliciation: 60 00 00 00 00 18 3a ff fe 80 00 00 00 00 00 00 20 01 07 80 00 44 21 0b ff 02 00 00 00 00 00 00 00 00 00 01 ff 44 21 0b 87 00 ca b6 00 00 00 00 fe 80 00 00 00 00 00 00 20 01 07 80 00 44 21 0b (icmpv6.c:328) MTC@6e07f179bda6: sending CreatePDP 20251222170146559 [1;32mDGGSN[0;m [1;32mINFO[0;m PDP(262422258479106:11): Successful PDP Context Creation: APN=inet46(inet46), TEIC=2, IPv4=176.16.32.12, IPv6=2001:780:44:210c:: (ggsn.c:619) MTC@6e07f179bda6: setverdict(pass): pass -> pass, component reason not changed MTC@6e07f179bda6: RA with /64 prefix '200107800044210C0000000000000000'O 20251222170146562 [1;34mDICMP6[0;m [1;34mDEBUG[0;m Ignoring Rx ICMPv6 Neighbor Soliciation: 60 00 00 00 00 18 3a ff fe 80 00 00 00 00 00 00 20 01 07 80 00 44 21 0c ff 02 00 00 00 00 00 00 00 00 00 01 ff 44 21 0c 87 00 ca b3 00 00 00 00 fe 80 00 00 00 00 00 00 20 01 07 80 00 44 21 0c (icmpv6.c:328) MTC@6e07f179bda6: Warning: Stopping inactive timer T_default. MTC@6e07f179bda6: Warning: Stopping inactive timer T_default. 20251222170152569 [1;32mDGGSN[0;m [1;32mINFO[0;m PDP(262423560336054:7): Deleting PDP context (ggsn.c:403) MTC@6e07f179bda6: setverdict(pass): pass -> pass, component reason not changed 20251222170152571 [1;32mDGGSN[0;m [1;32mINFO[0;m PDP(262422258479106:11): Deleting PDP context (ggsn.c:403) 20251222170152571 [1;32mDGGSN[0;m [1;32mINFO[0;m SGSN(172.18.3.1): Deleting SGSN (sgsn.c:21) MTC@6e07f179bda6: setverdict(pass): pass -> pass, component reason not changed MTC@6e07f179bda6: setverdict(pass): pass -> pass, component reason not changed MTC@6e07f179bda6: Setting final verdict of the test case. MTC@6e07f179bda6: Local verdict of MTC: pass MTC@6e07f179bda6: No PTCs were created. MTC@6e07f179bda6: Test case TC_pdp46_clients_interact finished. Verdict: pass MTC@6e07f179bda6: Starting external command `/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/ttcn3-tcpdump-stop.sh GGSN_Tests.TC_pdp46_clients_interact pass'. Mon Dec 22 17:01:54 UTC 2025 [1;32m====== GGSN_Tests.TC_pdp46_clients_interact pass ======[0m Saving talloc report from 172.18.3.2:4260 to GGSN_Tests.TC_pdp46_clients_interact.talloc Waiting for packet dumper to finish... 0 (prev_count=-1, count=17016) Waiting for packet dumper to finish... 1 (prev_count=17016, count=21836) MTC@6e07f179bda6: External command `/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/ttcn3-tcpdump-stop.sh GGSN_Tests.TC_pdp46_clients_interact pass' was executed successfully (exit status: 0). MTC@6e07f179bda6: Starting external command `/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/ttcn3-tcpdump-start.sh GGSN_Tests.TC_pdp46_act_deact_apn4'. ------ GGSN_Tests.TC_pdp46_act_deact_apn4 ------ Mon Dec 22 17:01:56 UTC 2025 /usr/bin/dumpcap -q -s 1520 -n -i any -w "/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/logs/osmo_all/testsuite/GGSN_Tests.TC_pdp46_act_deact_apn4.pcap" >/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/logs/osmo_all/testsuite/GGSN_Tests.TC_pdp46_act_deact_apn4.pcap.stdout 2>/tmp/cmderr & Waiting for packet dumper to start... 0 MTC@6e07f179bda6: External command `/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/ttcn3-tcpdump-start.sh GGSN_Tests.TC_pdp46_act_deact_apn4' was executed successfully (exit status: 0). MTC@6e07f179bda6: Test case TC_pdp46_act_deact_apn4 started. MTC@6e07f179bda6: GTP1C ConnectionID: 1 MTC@6e07f179bda6: "TC_pdp46_act_deact_apn4() start" 20251222170157719 [1;33mDLGLOBAL[0;m [1;33mNOTICE[0;m TTCN3 f_logp(): TC_pdp46_act_deact_apn4() start (logging_vty.c:1209) MTC@6e07f179bda6: sending CreatePDP 20251222170157723 [1;31mDGGSN[0;m [1;31mERROR[0;m PDP(262420144199212:15): APN doesn't support requested EUA / AF type (ggsn.c:632) 20251222170157724 [1;32mDGGSN[0;m [1;32mINFO[0;m PDP(262420144199212:15): Deleting PDP context (ggsn.c:403) 20251222170157724 [1;33mDGGSN[0;m [1;33mNOTICE[0;m PDP(262420144199212:15): Deleting PDP context: without private structure! (ggsn.c:416) 20251222170157724 [1;33mDGGSN[0;m [1;33mNOTICE[0;m GGSN(ggsn0): Received Recovery IE for unknown SGSN (no PDP contexts active) (ggsn.c:889) MTC@6e07f179bda6: setverdict(pass): none -> pass MTC@6e07f179bda6: sending CreatePDP 20251222170157727 [1;32mDGGSN[0;m [1;32mINFO[0;m SGSN(172.18.3.1): Discovered (sgsn.c:83) 20251222170157727 [1;32mDGGSN[0;m [1;32mINFO[0;m PDP(262425584634134:1): Successful PDP Context Creation: APN=internet(internet), TEIC=1, IPv4=176.16.16.19, IPv6=none (ggsn.c:619) MTC@6e07f179bda6: setverdict(pass): pass -> pass, component reason not changed MTC@6e07f179bda6: sending CreatePDP 20251222170157731 [1;31mDGGSN[0;m [1;31mERROR[0;m PDP(262426558151889:6): APN doesn't support requested EUA / AF type (ggsn.c:632) 20251222170157731 [1;32mDGGSN[0;m [1;32mINFO[0;m PDP(262426558151889:6): Deleting PDP context (ggsn.c:403) 20251222170157731 [1;31mDGGSN[0;m [1;31mERROR[0;m PDP(262426558151889:6): Cannot find/free IP Pool member (ggsn.c:411) 20251222170157731 [1;33mDGGSN[0;m [1;33mNOTICE[0;m PDP(262426558151889:6): Deleting PDP context: without private structure! (ggsn.c:416) MTC@6e07f179bda6: setverdict(pass): pass -> pass, component reason not changed 20251222170157732 [1;32mDGGSN[0;m [1;32mINFO[0;m PDP(262425584634134:1): Deleting PDP context (ggsn.c:403) 20251222170157732 [1;32mDGGSN[0;m [1;32mINFO[0;m SGSN(172.18.3.1): Deleting SGSN (sgsn.c:21) MTC@6e07f179bda6: setverdict(pass): pass -> pass, component reason not changed MTC@6e07f179bda6: setverdict(pass): pass -> pass, component reason not changed MTC@6e07f179bda6: Setting final verdict of the test case. MTC@6e07f179bda6: Local verdict of MTC: pass MTC@6e07f179bda6: No PTCs were created. MTC@6e07f179bda6: Test case TC_pdp46_act_deact_apn4 finished. Verdict: pass MTC@6e07f179bda6: Starting external command `/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/ttcn3-tcpdump-stop.sh GGSN_Tests.TC_pdp46_act_deact_apn4 pass'. Mon Dec 22 17:01:59 UTC 2025 [1;32m====== GGSN_Tests.TC_pdp46_act_deact_apn4 pass ======[0m Saving talloc report from 172.18.3.2:4260 to GGSN_Tests.TC_pdp46_act_deact_apn4.talloc Waiting for packet dumper to finish... 0 (prev_count=-1, count=15712) Waiting for packet dumper to finish... 1 (prev_count=15712, count=21020) MTC@6e07f179bda6: External command `/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/ttcn3-tcpdump-stop.sh GGSN_Tests.TC_pdp46_act_deact_apn4 pass' was executed successfully (exit status: 0). MTC@6e07f179bda6: Starting external command `/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/ttcn3-tcpdump-start.sh GGSN_Tests.TC_echo_req_resp'. ------ GGSN_Tests.TC_echo_req_resp ------ Mon Dec 22 17:02:01 UTC 2025 /usr/bin/dumpcap -q -s 1520 -n -i any -w "/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/logs/osmo_all/testsuite/GGSN_Tests.TC_echo_req_resp.pcap" >/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/logs/osmo_all/testsuite/GGSN_Tests.TC_echo_req_resp.pcap.stdout 2>/tmp/cmderr & Waiting for packet dumper to start... 0 MTC@6e07f179bda6: External command `/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/ttcn3-tcpdump-start.sh GGSN_Tests.TC_echo_req_resp' was executed successfully (exit status: 0). MTC@6e07f179bda6: Test case TC_echo_req_resp started. MTC@6e07f179bda6: GTP1C ConnectionID: 1 MTC@6e07f179bda6: "TC_echo_req_resp() start" 20251222170202878 [1;33mDLGLOBAL[0;m [1;33mNOTICE[0;m TTCN3 f_logp(): TC_echo_req_resp() start (logging_vty.c:1209) MTC@6e07f179bda6: setverdict(pass): none -> pass MTC@6e07f179bda6: setverdict(pass): pass -> pass, component reason not changed MTC@6e07f179bda6: Setting final verdict of the test case. MTC@6e07f179bda6: Local verdict of MTC: pass MTC@6e07f179bda6: No PTCs were created. MTC@6e07f179bda6: Test case TC_echo_req_resp finished. Verdict: pass MTC@6e07f179bda6: Starting external command `/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/ttcn3-tcpdump-stop.sh GGSN_Tests.TC_echo_req_resp pass'. Mon Dec 22 17:02:04 UTC 2025 [1;32m====== GGSN_Tests.TC_echo_req_resp pass ======[0m Saving talloc report from 172.18.3.2:4260 to GGSN_Tests.TC_echo_req_resp.talloc Waiting for packet dumper to finish... 0 (prev_count=-1, count=6684) Waiting for packet dumper to finish... 1 (prev_count=6684, count=11272) MTC@6e07f179bda6: External command `/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/ttcn3-tcpdump-stop.sh GGSN_Tests.TC_echo_req_resp pass' was executed successfully (exit status: 0). MTC@6e07f179bda6: Starting external command `/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/ttcn3-tcpdump-start.sh GGSN_Tests.TC_echo_req_resp_gtpu'. ------ GGSN_Tests.TC_echo_req_resp_gtpu ------ Mon Dec 22 17:02:06 UTC 2025 /usr/bin/dumpcap -q -s 1520 -n -i any -w "/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/logs/osmo_all/testsuite/GGSN_Tests.TC_echo_req_resp_gtpu.pcap" >/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/logs/osmo_all/testsuite/GGSN_Tests.TC_echo_req_resp_gtpu.pcap.stdout 2>/tmp/cmderr & Waiting for packet dumper to start... 0 MTC@6e07f179bda6: External command `/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/ttcn3-tcpdump-start.sh GGSN_Tests.TC_echo_req_resp_gtpu' was executed successfully (exit status: 0). MTC@6e07f179bda6: Test case TC_echo_req_resp_gtpu started. MTC@6e07f179bda6: GTP1C ConnectionID: 1 MTC@6e07f179bda6: "TC_echo_req_resp_gtpu() start" 20251222170208045 [1;33mDLGLOBAL[0;m [1;33mNOTICE[0;m TTCN3 f_logp(): TC_echo_req_resp_gtpu() start (logging_vty.c:1209) MTC@6e07f179bda6: setverdict(pass): none -> pass MTC@6e07f179bda6: setverdict(pass): pass -> pass, component reason not changed MTC@6e07f179bda6: Setting final verdict of the test case. MTC@6e07f179bda6: Local verdict of MTC: pass MTC@6e07f179bda6: No PTCs were created. MTC@6e07f179bda6: Test case TC_echo_req_resp_gtpu finished. Verdict: pass MTC@6e07f179bda6: Starting external command `/home/osmocom-build/jenkins/workspace/ttcn3-ggsn-test-asan/ttcn3-tcpdump-stop.sh GGSN_Tests.TC_echo_req_resp_gtpu pass'. Mon Dec 22 17:02:10 UTC 2025 [1;32m====== GGSN_Tests.TC_echo_req_resp_gtpu pass ======[0m Saving talloc report from 172.18.3.2:4260 to GGSN_Tests.TC_echo_req_resp_gtpu.talloc Waiting for packet dumper to finish... 0 (prev_count=-1, count=6700) FATAL: command execution failed java.io.EOFException at java.base/java.io.ObjectInputStream$PeekInputStream.readFully(Unknown Source) at java.base/java.io.ObjectInputStream$BlockDataInputStream.readShort(Unknown Source) at java.base/java.io.ObjectInputStream.readStreamHeader(Unknown Source) at java.base/java.io.ObjectInputStream.<init>(Unknown Source) at hudson.remoting.ObjectInputStreamEx.<init>(ObjectInputStreamEx.java:50) at hudson.remoting.Command.readFrom(Command.java:141) at hudson.remoting.Command.readFrom(Command.java:127) at hudson.remoting.AbstractSynchronousByteArrayCommandTransport.read(AbstractSynchronousByteArrayCommandTransport.java:35) at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:62) Caused: java.io.IOException: Unexpected termination of the channel at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:80) Caused: java.io.IOException: Backing channel 'build4-deb12build-ansible' is disconnected. at hudson.remoting.RemoteInvocationHandler.channelOrFail(RemoteInvocationHandler.java:227) at hudson.remoting.RemoteInvocationHandler.invoke(RemoteInvocationHandler.java:306) at jdk.proxy2/jdk.proxy2.$Proxy156.isAlive(Unknown Source) at hudson.Launcher$RemoteLauncher$ProcImpl.isAlive(Launcher.java:1212) at hudson.Launcher$RemoteLauncher$ProcImpl.join(Launcher.java:1204) at hudson.tasks.CommandInterpreter.join(CommandInterpreter.java:195) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:145) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:92) at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:818) at hudson.model.Build$BuildExecution.build(Build.java:199) at hudson.model.Build$BuildExecution.doRun(Build.java:164) at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:527) at hudson.model.Run.execute(Run.java:1833) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:44) at hudson.model.ResourceController.execute(ResourceController.java:101) at hudson.model.Executor.run(Executor.java:446) FATAL: Unable to delete script file /tmp/jenkins12905233980473776275.sh java.io.EOFException at java.base/java.io.ObjectInputStream$PeekInputStream.readFully(Unknown Source) at java.base/java.io.ObjectInputStream$BlockDataInputStream.readShort(Unknown Source) at java.base/java.io.ObjectInputStream.readStreamHeader(Unknown Source) at java.base/java.io.ObjectInputStream.<init>(Unknown Source) at hudson.remoting.ObjectInputStreamEx.<init>(ObjectInputStreamEx.java:50) at hudson.remoting.Command.readFrom(Command.java:141) at hudson.remoting.Command.readFrom(Command.java:127) at hudson.remoting.AbstractSynchronousByteArrayCommandTransport.read(AbstractSynchronousByteArrayCommandTransport.java:35) at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:62) Caused: java.io.IOException: Unexpected termination of the channel at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:80) Caused: hudson.remoting.ChannelClosedException: Channel "hudson.remoting.Channel@3f9ba8e:build4-deb12build-ansible": Remote call on build4-deb12build-ansible failed. The channel is closing down or has closed down at hudson.remoting.Channel.call(Channel.java:1105) at hudson.FilePath.act(FilePath.java:1207) at hudson.FilePath.act(FilePath.java:1196) at hudson.FilePath.delete(FilePath.java:1743) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:163) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:92) at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:818) at hudson.model.Build$BuildExecution.build(Build.java:199) at hudson.model.Build$BuildExecution.doRun(Build.java:164) at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:527) at hudson.model.Run.execute(Run.java:1833) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:44) at hudson.model.ResourceController.execute(ResourceController.java:101) at hudson.model.Executor.run(Executor.java:446) Build step 'Execute shell' marked build as failure ERROR: Step ‘Publish JUnit test result report’ failed: no workspace for ttcn3-ggsn-test-asan #565

1 1

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

23 Dec '25

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-pcu-test-sns-asan/425/display…> Changes: [Pau Espin Pedrol] library/NGAP_Templates: PDUSessionResourceSetupRequest: Fix criticality ------------------------------------------ [...truncated 459.75 KiB...] [1;35m20251222164034039 [1;34mDPCU[0;m[1;35m [1;34mDEBUG[0;m[1;35m NS: indication Unknown affecting cause NSVC failure / 0 from NS (gprs_bssgp_pcu.c:702)[0;m [38;5;57m20251222164034039 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23000){UNBLOCKED}: Terminating (cause = OSMO_FSM_TERM_REQUEST) (gprs_ns2.c:656)[0;m [38;5;57m20251222164034039 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23000){UNBLOCKED}: Freeing instance (gprs_ns2.c:656)[0;m [38;5;57m20251222164034039 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23000){UNBLOCKED}: Deallocated (fsm.c:568)[0;m [38;5;57m20251222164034039 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(01234)-NSVC(none) NS-STATUS.ind(bvci=00000): cause=NSVC failure, transfer=0, first=0, mtu=65523 (gprs_ns2.c:571)[0;m [1;35m20251222164034039 [1;34mDPCU[0;m[1;35m [1;34mDEBUG[0;m[1;35m NS: indication Unknown affecting cause NSVC failure / 0 from NS (gprs_bssgp_pcu.c:702)[0;m [38;5;57m20251222164034039 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23001){UNBLOCKED}: Terminating (cause = OSMO_FSM_TERM_REQUEST) (gprs_ns2.c:656)[0;m [38;5;57m20251222164034039 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23001){UNBLOCKED}: Freeing instance (gprs_ns2.c:656)[0;m [38;5;57m20251222164034040 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23001){UNBLOCKED}: Deallocated (fsm.c:568)[0;m [38;5;57m20251222164034040 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(01234)-NSVC(none) NS-STATUS.ind(bvci=00000): cause=NSVC failure, transfer=0, first=0, mtu=65523 (gprs_ns2.c:571)[0;m [1;35m20251222164034040 [1;34mDPCU[0;m[1;35m [1;34mDEBUG[0;m[1;35m NS: indication Unknown affecting cause NSVC failure / 0 from NS (gprs_bssgp_pcu.c:702)[0;m [38;5;57m20251222164034040 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23002){UNBLOCKED}: Terminating (cause = OSMO_FSM_TERM_REQUEST) (gprs_ns2.c:656)[0;m [38;5;57m20251222164034040 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23002){UNBLOCKED}: Freeing instance (gprs_ns2.c:656)[0;m [38;5;57m20251222164034040 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23002){UNBLOCKED}: Deallocated (fsm.c:568)[0;m MTC@613b72e27ae4: Test case TC_sns_add finished. Verdict: pass MTC@613b72e27ae4: Starting external command `/home/osmocom-build/jenkins/workspace/ttcn3-pcu-test-sns-asan/ttcn3-tcpdump-stop.sh PCU_Tests_SNS.TC_sns_add pass'. Mon Dec 22 16:40:34 UTC 2025 [1;32m====== PCU_Tests_SNS.TC_sns_add pass ======[0m Saving talloc report from 127.0.0.1:4240 to PCU_Tests_SNS.TC_sns_add.talloc respawn: 11: stopped pid 7579 with status 0 respawn: 12: starting: osmo-pcu -c osmo-pcu-sns.cfg % 'alpha <0-10>' is now deprecated: use osmo-bsc's 'gprs power-control alpha <0-10>' instead 20251222164034075 [1;33mDLGLOBAL[0;m [1;33mNOTICE[0;m Available via telnet 127.0.0.1 4240 (telnet_interface.c:88) [1;32m20251222164034075 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m Opening OsmoPCU L1 interface v12 to OsmoBTS/OsmoBSC (pcuif_sock.c:204)[0;m [1;32m20251222164034075 [1;31mDL1IF[0;m[1;32m [1;31mERROR[0;m[1;32m Failed to connect to the BTS (/tmp/pcu_bts). Retrying... (pcuif_sock.c:212)[0;m 20251222164034076 [1;32mDLGLOBAL[0;m [1;32mINFO[0;m Accept()ed new telnet connection r=127.0.0.1:54800<->l=127.0.0.1:4240 (telnet_interface.c:192) 20251222164034077 [1;32mDLGLOBAL[0;m [1;32mINFO[0;m Closing telnet connection r=127.0.0.1:54800<->l=127.0.0.1:4240 (telnet_interface.c:138) Waiting for packet dumper to finish... 0 (prev_count=-1, count=36008) Waiting for packet dumper to finish... 1 (prev_count=36008, count=63100) MTC@613b72e27ae4: External command `/home/osmocom-build/jenkins/workspace/ttcn3-pcu-test-sns-asan/ttcn3-tcpdump-stop.sh PCU_Tests_SNS.TC_sns_add pass' was executed successfully (exit status: 0). MTC@613b72e27ae4: Starting external command `/home/osmocom-build/jenkins/workspace/ttcn3-pcu-test-sns-asan/ttcn3-tcpdump-start.sh PCU_Tests_SNS.TC_sns_add_nack'. ------ PCU_Tests_SNS.TC_sns_add_nack ------ Mon Dec 22 16:40:36 UTC 2025 /usr/bin/dumpcap -q -s 1520 -n -i any -w "/home/osmocom-build/jenkins/workspace/ttcn3-pcu-test-sns-asan/logs/testsuite/PCU_Tests_SNS.TC_sns_add_nack.pcap" >/home/osmocom-build/jenkins/workspace/ttcn3-pcu-test-sns-asan/logs/testsuite/PCU_Tests_SNS.TC_sns_add_nack.pcap.stdout 2>/tmp/cmderr & Waiting for packet dumper to start... 0 MTC@613b72e27ae4: External command `/home/osmocom-build/jenkins/workspace/ttcn3-pcu-test-sns-asan/ttcn3-tcpdump-start.sh PCU_Tests_SNS.TC_sns_add_nack' was executed successfully (exit status: 0). MTC@613b72e27ae4: Test case TC_sns_add_nack started. MTC@613b72e27ae4: Start timer g_T_guard: 60 s MTC@613b72e27ae4: Start timer T: 5 s [1;32m20251222164039076 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m Opening OsmoPCU L1 interface v12 to OsmoBTS/OsmoBSC (pcuif_sock.c:204)[0;m [1;32m20251222164039077 [1;33mDL1IF[0;m[1;32m [1;33mNOTICE[0;m[1;32m osmo-bts PCU socket /tmp/pcu_bts has been connected (pcuif_sock.c:222)[0;m [1;32m20251222164039077 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m Sending 1.5.2.1-c4d5d.202512212029~asan TXT as PCU_VERSION to BTS (pcu_l1_if.cpp:152)[0;m [1;32m20251222164039083 [1;33mDL1IF[0;m[1;32m [1;33mNOTICE[0;m[1;32m Received message for new BTS0 (pcu_l1_if.cpp:1246)[0;m 20251222164039084 [1;34mDLGLOBAL[0;m [1;34mDEBUG[0;m validating counter group 0x55b0c60be1a0(bts) with 102 counters (rate_ctr.c:86) [1;32m20251222164039084 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Info indication received: (pcu_l1_if.cpp:775)[0;m [1;32m20251222164039084 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m BTS available (pcu_l1_if.cpp:801)[0;m [1;32m20251222164039084 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m mcc=262 (pcu_l1_if.cpp:802)[0;m [1;32m20251222164039084 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m mnc=42 (pcu_l1_if.cpp:803)[0;m [1;32m20251222164039084 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m lac=13135 (pcu_l1_if.cpp:804)[0;m [1;32m20251222164039084 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m rac=0 (pcu_l1_if.cpp:805)[0;m MTC@613b72e27ae4: f_incoming_sns_size(idx=0) MTC@613b72e27ae4: f_ns_exp() expecting { pDU_SNS_Size := { nsPduType := '12'O, nSEI_NS := { iEI := '04'O, ext := '1'B, lengthIndicator := { length1 := 2 }, nSEI := '04D2'O }, resetFlag := { iEI := '0A'O ("\n"), resetBIT := '1'B, spare := '0000000'B }, maxNumberOfNSVCs := { iEI := '07'O ("\a"), maxNumberOfNSVCs := ? }, numberOfIP4_Endpoints := ?, numberOfIP6_Endpoints := omit } } [1;32m20251222164039084 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m cell_id=20960 (pcu_l1_if.cpp:806)[0;m [1;32m20251222164039084 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m bsic=7 (pcu_l1_if.cpp:807)[0;m [1;32m20251222164039084 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m nsei=1234 (pcu_l1_if.cpp:808)[0;m [1;32m20251222164039084 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m nse_timer=3 3 3 3 30 3 10 (pcu_l1_if.cpp:809)[0;m [1;32m20251222164039084 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m cell_timer=3 3 3 3 3 10 3 10 3 10 3 (pcu_l1_if.cpp:814)[0;m [1;32m20251222164039084 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m repeat_time=250 (pcu_l1_if.cpp:822)[0;m [1;32m20251222164039084 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m repeat_count=3 (pcu_l1_if.cpp:823)[0;m [1;32m20251222164039084 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m bvci=1234 (pcu_l1_if.cpp:824)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m t3142=20 (pcu_l1_if.cpp:825)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m t3169=5 (pcu_l1_if.cpp:826)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m t3191=5 (pcu_l1_if.cpp:827)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m t3193=1600 (ms) (pcu_l1_if.cpp:828)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m t3195=5 (pcu_l1_if.cpp:829)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m n3101=10 (pcu_l1_if.cpp:830)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m n3103=4 (pcu_l1_if.cpp:831)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m n3105=8 (pcu_l1_if.cpp:832)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m cv_countdown=15 (pcu_l1_if.cpp:833)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m dl_tbf_ext=2500 (pcu_l1_if.cpp:834)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m ul_tbf_ext=2500 (pcu_l1_if.cpp:835)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Use CS1 (pcu_l1_if.cpp:849)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Use CS2 (pcu_l1_if.cpp:849)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Use CS3 (pcu_l1_if.cpp:849)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Use CS4 (pcu_l1_if.cpp:849)[0;m [0;33m20251222164039085 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m New max CS: DL=4 UL=4 (bts.cpp:1422)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Use MCS1 (pcu_l1_if.cpp:858)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Use MCS2 (pcu_l1_if.cpp:858)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Use MCS3 (pcu_l1_if.cpp:858)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Use MCS4 (pcu_l1_if.cpp:858)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Use MCS5 (pcu_l1_if.cpp:858)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Use MCS6 (pcu_l1_if.cpp:858)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Use MCS7 (pcu_l1_if.cpp:858)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Use MCS8 (pcu_l1_if.cpp:858)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m Use MCS9 (pcu_l1_if.cpp:858)[0;m [0;33m20251222164039085 [1;34mDRLCMAC[0;m[0;33m [1;34mDEBUG[0;m[0;33m New max MCS: DL=9 UL=9 (bts.cpp:1449)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m initial_cs=2 (VTY forced, ignoring) (pcu_l1_if.cpp:863)[0;m [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m initial_mcs=1 (pcu_l1_if.cpp:868)[0;m 20251222164039085 [1;34mDLGLOBAL[0;m [1;34mDEBUG[0;m validating counter group 0x7f21e74de0a0(bssgp:bss_ctx) with 7 counters (rate_ctr.c:86) 20251222164039085 [1;34mDLGLOBAL[0;m [1;34mDEBUG[0;m validating counter group 0x55b0c60bd5a0(pcu:sgsn) with 2 counters (rate_ctr.c:86) [1;32m20251222164039085 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m NS0 nsvci=1234 (pcu_l1_if.cpp:715)[0;m [1;32m20251222164039086 [1;34mDL1IF[0;m[1;32m [1;34mDEBUG[0;m[1;32m NS0 address: r=127.0.0.1:23000<->l=NULL:22000 (pcu_l1_if.cpp:719)[0;m [38;5;57m20251222164039086 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){UNCONFIGURED}: Allocated (fsm.c:456)[0;m 20251222164039086 [1;34mDLGLOBAL[0;m [1;34mDEBUG[0;m validating counter group 0x7f21e74dee00(ns:nse) with 15 counters (rate_ctr.c:86) [38;5;57m20251222164039086 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){UNCONFIGURED}: Received Event REQ_ADD_BIND (gprs_ns2_sns.c:2680)[0;m [38;5;57m20251222164039086 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){UNCONFIGURED}: Received Event REQ_SELECT_ENDPOINT (gprs_ns2_sns.c:2028)[0;m [38;5;57m20251222164039086 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(01234) NS-STATUS.ind(bvci=00000): cause=NSE SNS no endpoints, transfer=0, first=1, mtu=0 (gprs_ns2.c:575)[0;m [1;35m20251222164039086 [1;34mDPCU[0;m[1;35m [1;34mDEBUG[0;m[1;35m NS: indication Unknown affecting cause NSE SNS no endpoints / 6 from NS (gprs_bssgp_pcu.c:702)[0;m [38;5;57m20251222164039086 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){UNCONFIGURED}: state_chg to UNCONFIGURED (gprs_ns2_sns.c:2240)[0;m [38;5;57m20251222164039086 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){UNCONFIGURED}: Received Event REQ_SELECT_ENDPOINT (gprs_ns2_sns.c:2513)[0;m [38;5;57m20251222164039086 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){UNCONFIGURED}: state_chg to BSS_SIZE (gprs_ns2_sns.c:2255)[0;m 20251222164039086 [1;34mDLGLOBAL[0;m [1;34mDEBUG[0;m validating counter group 0x7f21e74dedc0(ns:nsvc) with 15 counters (rate_ctr.c:86) [38;5;57m20251222164039086 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23000){UNCONFIGURED}: Allocated (fsm.c:456)[0;m [38;5;63m20251222164039086 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Tx SNS-SIZE (reset=1, max_nr_nsvc=8192, num_ip4=1, num_ip6=-1) (gprs_ns2_message.c:807)[0;m [1;32m20251222164039086 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH(bts=0,trx=0,ts=7) Sending activate request (pcu_l1_if.cpp:165)[0;m [0;33m20251222164039086 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=0,ts=7) PDCH state: disabled => enabled (pdch.cpp:171)[0;m [1;32m20251222164039086 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH (trx=0, ts=7): tsc=7, hopping=no (pcu_l1_if.cpp:959)[0;m [1;32m20251222164039086 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH(bts=0,trx=1,ts=7) Sending activate request (pcu_l1_if.cpp:165)[0;m [0;33m20251222164039087 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=1,ts=7) PDCH state: disabled => enabled (pdch.cpp:171)[0;m [1;32m20251222164039087 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH (trx=1, ts=7): tsc=7, hopping=no (pcu_l1_if.cpp:959)[0;m [1;32m20251222164039087 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH(bts=0,trx=2,ts=7) Sending activate request (pcu_l1_if.cpp:165)[0;m [0;33m20251222164039087 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=2,ts=7) PDCH state: disabled => enabled (pdch.cpp:171)[0;m [1;32m20251222164039087 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH (trx=2, ts=7): tsc=7, hopping=no (pcu_l1_if.cpp:959)[0;m [1;32m20251222164039087 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH(bts=0,trx=3,ts=7) Sending activate request (pcu_l1_if.cpp:165)[0;m [0;33m20251222164039087 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=3,ts=7) PDCH state: disabled => enabled (pdch.cpp:171)[0;m [1;32m20251222164039087 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH (trx=3, ts=7): tsc=7, hopping=no (pcu_l1_if.cpp:959)[0;m [1;32m20251222164039087 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH(bts=0,trx=4,ts=7) Sending activate request (pcu_l1_if.cpp:165)[0;m [0;33m20251222164039087 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=4,ts=7) PDCH state: disabled => enabled (pdch.cpp:171)[0;m [1;32m20251222164039087 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH (trx=4, ts=7): tsc=7, hopping=no (pcu_l1_if.cpp:959)[0;m [1;32m20251222164039087 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH(bts=0,trx=5,ts=7) Sending activate request (pcu_l1_if.cpp:165)[0;m [0;33m20251222164039087 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=5,ts=7) PDCH state: disabled => enabled (pdch.cpp:171)[0;m [1;32m20251222164039087 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH (trx=5, ts=7): tsc=7, hopping=no (pcu_l1_if.cpp:959)[0;m [1;32m20251222164039087 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH(bts=0,trx=6,ts=7) Sending activate request (pcu_l1_if.cpp:165)[0;m [0;33m20251222164039087 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=6,ts=7) PDCH state: disabled => enabled (pdch.cpp:171)[0;m [1;32m20251222164039087 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH (trx=6, ts=7): tsc=7, hopping=no (pcu_l1_if.cpp:959)[0;m [1;32m20251222164039087 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH(bts=0,trx=7,ts=7) Sending activate request (pcu_l1_if.cpp:165)[0;m [0;33m20251222164039087 [1;32mDRLCMAC[0;m[0;33m [1;32mINFO[0;m[0;33m PDCH(bts=0,trx=7,ts=7) PDCH state: disabled => enabled (pdch.cpp:171)[0;m [1;32m20251222164039087 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m PDCH (trx=7, ts=7): tsc=7, hopping=no (pcu_l1_if.cpp:959)[0;m [1;32m20251222164039087 [1;32mDL1IF[0;m[1;32m [1;32mINFO[0;m[1;32m BTS model: osmo-bts-trx (pcu_l1_if.cpp:974)[0;m MTC@613b72e27ae4: f_incoming_sns_config(idx=0) MTC@613b72e27ae4: f_ns_exp() expecting { pDU_SNS_Config := { nsPduType := '0F'O, endFlag := { eBIT := '1'B, spare := '0000000'B }, nSEI_NS := { iEI := '04'O, ext := '1'B, lengthIndicator := { length1 := 2 }, nSEI := '04D2'O }, listofIP4Elements := { iEI := '05'O, ext := '1'B, lengthIndicator := { length1 := ? }, iP4_Elements := { { ipAddress := '7F000001'O, uDP_Port := '55F0'O, signallingWeight := ?, dataWeight := ? } } }, listofIP6Elements := omit } } [38;5;63m20251222164039093 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Rx SNS-SIZE-ACK (gprs_ns2.c:1309)[0;m [38;5;57m20251222164039093 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){BSS_SIZE}: NSEI=1234 Rx SNS PDU type SNS-SIZE-ACK (gprs_ns2_sns.c:2341)[0;m [38;5;57m20251222164039094 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){BSS_SIZE}: Received Event RX_SIZE_ACK (gprs_ns2_sns.c:2349)[0;m [38;5;57m20251222164039094 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){BSS_SIZE}: state_chg to BSS_CONFIG_BSS (gprs_ns2_sns.c:827)[0;m [38;5;63m20251222164039094 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Tx SNS-CONFIG (end_flag=1, num_ip4=1, num_ip6=0) (gprs_ns2_message.c:713)[0;m MTC@613b72e27ae4: f_outgoing_sns_config_1c1u(idx=0) MTC@613b72e27ae4: f_ns_exp() expecting { pDU_SNS_Config_Ack := { nsPduType := '10'O, nSEI_NS := { iEI := '04'O, ext := '1'B, lengthIndicator := { length1 := 2 }, nSEI := '04D2'O }, causeNS := omit } } [38;5;63m20251222164039096 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Rx SNS-CONFIG-ACK (gprs_ns2.c:1309)[0;m [38;5;57m20251222164039096 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){BSS_CONFIG_BSS}: NSEI=1234 Rx SNS PDU type SNS-CONFIG-ACK (gprs_ns2_sns.c:2341)[0;m [38;5;57m20251222164039096 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){BSS_CONFIG_BSS}: Received Event RX_CONFIG_ACK (gprs_ns2_sns.c:2358)[0;m [38;5;57m20251222164039096 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){BSS_CONFIG_BSS}: state_chg to BSS_CONFIG_SGSN (gprs_ns2_sns.c:1059)[0;m [38;5;63m20251222164039096 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Rx SNS-CONFIG (gprs_ns2.c:1309)[0;m [38;5;57m20251222164039096 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){BSS_CONFIG_SGSN}: NSEI=1234 Rx SNS PDU type SNS-CONFIG (gprs_ns2_sns.c:2341)[0;m [38;5;57m20251222164039096 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){BSS_CONFIG_SGSN}: Received Event RX_CONFIG_END (gprs_ns2_sns.c:2353)[0;m [38;5;57m20251222164039097 [1;32mDLNS[0;m[38;5;57m [1;32mINFO[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){BSS_CONFIG_SGSN}: Rx SNS-CONFIG: Remote IPv4 list now 2 entries (gprs_ns2_sns.c:1126)[0;m 20251222164039097 [1;34mDLGLOBAL[0;m [1;34mDEBUG[0;m validating counter group 0x7f21e74dedc0(ns:nsvc) with 15 counters (rate_ctr.c:86) [38;5;57m20251222164039097 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23001){UNCONFIGURED}: Allocated (fsm.c:456)[0;m [38;5;57m20251222164039097 [1;32mDLNS[0;m[38;5;57m [1;32mINFO[0;m[38;5;57m NSE(01234)-NSVC(none) Tx SNS-CONFIG-ACK (cause=NULL) (gprs_ns2_message.c:755)[0;m [38;5;63m20251222164039097 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Tx SNS-CONFIG-ACK (gprs_ns2_message.c:757)[0;m [38;5;57m20251222164039097 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23000){UNCONFIGURED}: Received Event REQ-START (gprs_ns2_vc_fsm.c:817)[0;m [38;5;57m20251222164039097 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23000){UNCONFIGURED}: state_chg to UNBLOCKED (gprs_ns2_vc_fsm.c:278)[0;m [38;5;57m20251222164039097 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(01234)-NSVC(none) NS-STATUS.ind(bvci=00000): cause=NSVC recovery, transfer=0, first=1, mtu=65523 (gprs_ns2.c:571)[0;m [1;35m20251222164039097 [1;34mDPCU[0;m[1;35m [1;34mDEBUG[0;m[1;35m NS: indication Unknown affecting cause NSVC recovery / 1 from NS (gprs_bssgp_pcu.c:702)[0;m [38;5;63m20251222164039097 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Tx NS-ALIVE (gprs_ns2_message.c:193)[0;m [38;5;57m20251222164039097 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23000){UNBLOCKED}: Starting Tns-alive of 3 seconds (gprs_ns2_vc_fsm.c:177)[0;m [38;5;57m20251222164039097 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23001){UNCONFIGURED}: Received Event REQ-START (gprs_ns2_vc_fsm.c:817)[0;m [38;5;57m20251222164039097 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23001){UNCONFIGURED}: state_chg to UNBLOCKED (gprs_ns2_vc_fsm.c:278)[0;m [38;5;57m20251222164039097 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(01234) NS-STATUS.ind(bvci=00000): cause=NSE recovery, transfer=100, first=1, mtu=65523 (gprs_ns2.c:575)[0;m [1;35m20251222164039097 [1;33mDPCU[0;m[1;35m [1;33mNOTICE[0;m[1;35m NS-NSE 1234 became available (gprs_bssgp_pcu.c:681)[0;m [38;5;59m20251222164039097 [1;32mDLBSSGP[0;m[38;5;59m [1;32mINFO[0;m[38;5;59m Sending reset on BVCI 0 (gprs_bssgp_pcu.c:1045)[0;m [38;5;59m20251222164039097 [1;33mDLBSSGP[0;m[38;5;59m [1;33mNOTICE[0;m[38;5;59m BSSGP (BVCI=0) Tx BVC-RESET CAUSE=O&M intervention (gprs_bssgp.c:124)[0;m [38;5;61m20251222164039097 [1;32mDLNSDATA[0;m[38;5;61m [1;32mINFO[0;m[38;5;61m NSE(01234)-NSVC(none) Tx NS-UNITDATA (gprs_ns2_message.c:412)[0;m [38;5;57m20251222164039097 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(01234)-NSVC(none) NS-STATUS.ind(bvci=00000): cause=NSVC recovery, transfer=100, first=0, mtu=65523 (gprs_ns2.c:571)[0;m [1;35m20251222164039097 [1;34mDPCU[0;m[1;35m [1;34mDEBUG[0;m[1;35m NS: indication Unknown affecting cause NSVC recovery / 1 from NS (gprs_bssgp_pcu.c:702)[0;m [38;5;63m20251222164039097 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Tx NS-ALIVE (gprs_ns2_message.c:193)[0;m [38;5;57m20251222164039097 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23001){UNBLOCKED}: Starting Tns-alive of 3 seconds (gprs_ns2_vc_fsm.c:177)[0;m [38;5;57m20251222164039098 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-SNS-BSS(NSE00000-SNS){BSS_CONFIG_SGSN}: state_chg to CONFIGURED (gprs_ns2_sns.c:1195)[0;m [38;5;57m20251222164039098 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(01234) NS-STATUS.ind(bvci=00000): cause=NSE SNS configured, transfer=100, first=0, mtu=65523 (gprs_ns2.c:575)[0;m [1;35m20251222164039098 [1;33mDPCU[0;m[1;35m [1;33mNOTICE[0;m[1;35m NS-NSE 1234 SNS configured. (gprs_bssgp_pcu.c:678)[0;m [38;5;63m20251222164039099 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Rx NS-ALIVE-ACK (gprs_ns2.c:1309)[0;m [38;5;57m20251222164039100 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23000){UNBLOCKED}: Received Event RX-ALIVE_ACK (gprs_ns2_vc_fsm.c:960)[0;m [38;5;63m20251222164039101 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Rx NS-ALIVE (gprs_ns2.c:1309)[0;m [38;5;57m20251222164039101 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23000){UNBLOCKED}: Received Event RX-ALIVE (gprs_ns2_vc_fsm.c:957)[0;m [38;5;63m20251222164039101 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Tx NS-ALIVE-ACK (gprs_ns2_message.c:193)[0;m [38;5;63m20251222164039101 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Rx NS-ALIVE-ACK (gprs_ns2.c:1309)[0;m [38;5;57m20251222164039101 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23001){UNBLOCKED}: Received Event RX-ALIVE_ACK (gprs_ns2_vc_fsm.c:960)[0;m [38;5;63m20251222164039102 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Rx NS-ALIVE (gprs_ns2.c:1309)[0;m [38;5;57m20251222164039102 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23001){UNBLOCKED}: Received Event RX-ALIVE (gprs_ns2_vc_fsm.c:957)[0;m [38;5;63m20251222164039102 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Tx NS-ALIVE-ACK (gprs_ns2_message.c:193)[0;m [38;5;63m20251222164049100 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Tx NS-ALIVE (gprs_ns2_message.c:193)[0;m [38;5;63m20251222164049102 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Tx NS-ALIVE (gprs_ns2_message.c:193)[0;m [38;5;63m20251222164049102 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Rx NS-ALIVE-ACK (gprs_ns2.c:1309)[0;m [38;5;57m20251222164049102 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23000){UNBLOCKED}: Received Event RX-ALIVE_ACK (gprs_ns2_vc_fsm.c:960)[0;m [38;5;63m20251222164049102 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(01234)-NSVC(none) Rx NS-ALIVE-ACK (gprs_ns2.c:1309)[0;m [38;5;57m20251222164049102 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(NSE01234-NSVC-UDP-0_0_0_0:22000-127_0_0_1:23001){UNBLOCKED}: Received Event RX-ALIVE_ACK (gprs_ns2_vc_fsm.c:960)[0;m FATAL: command execution failed java.io.EOFException at java.base/java.io.ObjectInputStream$PeekInputStream.readFully(Unknown Source) at java.base/java.io.ObjectInputStream$BlockDataInputStream.readShort(Unknown Source) at java.base/java.io.ObjectInputStream.readStreamHeader(Unknown Source) at java.base/java.io.ObjectInputStream.<init>(Unknown Source) at hudson.remoting.ObjectInputStreamEx.<init>(ObjectInputStreamEx.java:50) at hudson.remoting.Command.readFrom(Command.java:141) at hudson.remoting.Command.readFrom(Command.java:127) at hudson.remoting.AbstractSynchronousByteArrayCommandTransport.read(AbstractSynchronousByteArrayCommandTransport.java:35) at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:62) Caused: java.io.IOException: Unexpected termination of the channel at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:80) Caused: hudson.remoting.ChannelClosedException: Channel "hudson.remoting.Channel@187572d1:build5-deb12build-ansible": Remote call on build5-deb12build-ansible failed. The channel is closing down or has closed down at hudson.remoting.Channel.call(Channel.java:1105) at hudson.remoting.RemoteInvocationHandler.invoke(RemoteInvocationHandler.java:306) at jdk.proxy2/jdk.proxy2.$Proxy156.isAlive(Unknown Source) at hudson.Launcher$RemoteLauncher$ProcImpl.isAlive(Launcher.java:1212) at hudson.Launcher$RemoteLauncher$ProcImpl.join(Launcher.java:1204) at hudson.tasks.CommandInterpreter.join(CommandInterpreter.java:195) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:145) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:92) at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:818) at hudson.model.Build$BuildExecution.build(Build.java:199) at hudson.model.Build$BuildExecution.doRun(Build.java:164) at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:527) at hudson.model.Run.execute(Run.java:1833) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:44) at hudson.model.ResourceController.execute(ResourceController.java:101) at hudson.model.Executor.run(Executor.java:446) FATAL: Unable to delete script file /tmp/jenkins14745648370965389386.sh java.io.EOFException at java.base/java.io.ObjectInputStream$PeekInputStream.readFully(Unknown Source) at java.base/java.io.ObjectInputStream$BlockDataInputStream.readShort(Unknown Source) at java.base/java.io.ObjectInputStream.readStreamHeader(Unknown Source) at java.base/java.io.ObjectInputStream.<init>(Unknown Source) at hudson.remoting.ObjectInputStreamEx.<init>(ObjectInputStreamEx.java:50) at hudson.remoting.Command.readFrom(Command.java:141) at hudson.remoting.Command.readFrom(Command.java:127) at hudson.remoting.AbstractSynchronousByteArrayCommandTransport.read(AbstractSynchronousByteArrayCommandTransport.java:35) at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:62) Caused: java.io.IOException: Unexpected termination of the channel at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:80) Caused: hudson.remoting.ChannelClosedException: Channel "hudson.remoting.Channel@187572d1:build5-deb12build-ansible": Remote call on build5-deb12build-ansible failed. The channel is closing down or has closed down at hudson.remoting.Channel.call(Channel.java:1105) at hudson.FilePath.act(FilePath.java:1207) at hudson.FilePath.act(FilePath.java:1196) at hudson.FilePath.delete(FilePath.java:1743) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:163) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:92) at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:818) at hudson.model.Build$BuildExecution.build(Build.java:199) at hudson.model.Build$BuildExecution.doRun(Build.java:164) at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:527) at hudson.model.Run.execute(Run.java:1833) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:44) at hudson.model.ResourceController.execute(ResourceController.java:101) at hudson.model.Executor.run(Executor.java:446) Build step 'Execute shell' marked build as failure ERROR: Step ‘Publish JUnit test result report’ failed: no workspace for ttcn3-pcu-test-sns-asan #425

1 1

Build failed in Jenkins: ttcn3-bsc-test-sccplite-latest #2536
by jenkins＠lists.osmocom.org 23 Dec '25

23 Dec '25

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-sccplite-latest/2536…> Changes: [Pau Espin Pedrol] library/NGAP_Templates: PDUSessionResourceSetupRequest: Fix criticality ------------------------------------------ [...truncated 95.63 MiB...] MTC@e0eada7afaad: Matching on port BSCVTY succeeded: "OsmoBSC(config)# " with pattern "[\w-]+$*$\# " matched MTC@e0eada7afaad: Receive operation on port BSCVTY succeeded, message from system(): charstring : "OsmoBSC(config)# " id 8 MTC@e0eada7afaad: Message with id 8 was extracted from the queue of BSCVTY. MTC@e0eada7afaad: Stop timer T: 2 s MTC@e0eada7afaad: Sent on BSCVTY to system charstring : "msc 0" MTC@e0eada7afaad: Start timer T: 2 s MTC@e0eada7afaad: Message enqueued on BSCVTY from system charstring : "OsmoBSC(config-msc)# " id 9 MTC@e0eada7afaad: Matching on port BSCVTY "OsmoBSC(config-msc)# " with pattern "[\w-]+> " unmatched: First message in the queue does not match the template: MTC@e0eada7afaad: Matching on port BSCVTY "OsmoBSC(config-msc)# " with pattern "[\w-]+\# " unmatched: First message in the queue does not match the template: MTC@e0eada7afaad: Matching on port BSCVTY succeeded: "OsmoBSC(config-msc)# " with pattern "[\w-]+$*$\# " matched MTC@e0eada7afaad: Receive operation on port BSCVTY succeeded, message from system(): charstring : "OsmoBSC(config-msc)# " id 9 MTC@e0eada7afaad: Message with id 9 was extracted from the queue of BSCVTY. MTC@e0eada7afaad: Stop timer T: 2 s MTC@e0eada7afaad: Sent on BSCVTY to system charstring : "osmux off" MTC@e0eada7afaad: Start timer T: 2 s MTC@e0eada7afaad: Message enqueued on BSCVTY from system charstring : "OsmoBSC(config-msc)# " id 10 MTC@e0eada7afaad: Matching on port BSCVTY "OsmoBSC(config-msc)# " with pattern "[\w-]+> " unmatched: First message in the queue does not match the template: MTC@e0eada7afaad: Matching on port BSCVTY "OsmoBSC(config-msc)# " with pattern "[\w-]+\# " unmatched: First message in the queue does not match the template: MTC@e0eada7afaad: Matching on port BSCVTY succeeded: "OsmoBSC(config-msc)# " with pattern "[\w-]+$*$\# " matched MTC@e0eada7afaad: Receive operation on port BSCVTY succeeded, message from system(): charstring : "OsmoBSC(config-msc)# " id 10 MTC@e0eada7afaad: Message with id 10 was extracted from the queue of BSCVTY. MTC@e0eada7afaad: Stop timer T: 2 s MTC@e0eada7afaad: Sent on BSCVTY to system charstring : "exit" MTC@e0eada7afaad: Start timer T: 2 s MTC@e0eada7afaad: Message enqueued on BSCVTY from system charstring : "OsmoBSC(config)# " id 11 MTC@e0eada7afaad: Matching on port BSCVTY "OsmoBSC(config)# " with pattern "[\w-]+> " unmatched: First message in the queue does not match the template: MTC@e0eada7afaad: Matching on port BSCVTY "OsmoBSC(config)# " with pattern "[\w-]+\# " unmatched: First message in the queue does not match the template: MTC@e0eada7afaad: Matching on port BSCVTY succeeded: "OsmoBSC(config)# " with pattern "[\w-]+$*$\# " matched MTC@e0eada7afaad: Receive operation on port BSCVTY succeeded, message from system(): charstring : "OsmoBSC(config)# " id 11 MTC@e0eada7afaad: Message with id 11 was extracted from the queue of BSCVTY. MTC@e0eada7afaad: Stop timer T: 2 s MTC@e0eada7afaad: Sent on BSCVTY to system charstring : "exit" MTC@e0eada7afaad: Start timer T: 2 s MTC@e0eada7afaad: Message enqueued on BSCVTY from system charstring : "OsmoBSC# " id 12 MTC@e0eada7afaad: Matching on port BSCVTY "OsmoBSC# " with pattern "[\w-]+> " unmatched: First message in the queue does not match the template: MTC@e0eada7afaad: Matching on port BSCVTY succeeded: "OsmoBSC# " with pattern "[\w-]+\# " matched MTC@e0eada7afaad: Receive operation on port BSCVTY succeeded, message from system(): charstring : "OsmoBSC# " id 12 MTC@e0eada7afaad: Message with id 12 was extracted from the queue of BSCVTY. MTC@e0eada7afaad: Stop timer T: 2 s MTC@e0eada7afaad: Creating new PTC with component type StatsD_Checker.StatsD_Checker_CT, component name: VirtMSC-STATS. HC@e0eada7afaad: PTC VirtMSC-STATS(1164) finished. Process statistics: { process id: 19177, terminated normally, exit status: 0, user time: 0.038806 s, system time: 0.017247 s, maximum resident set size: 102808, integral resident set size: 0, page faults not requiring physical I/O: 4615, page faults requiring physical I/O: 3, swaps: 0, block input operations: 0, block output operations: 32, messages sent: 0, messages received: 0, signals received: 0, voluntary context switches: 81, involuntary context switches: 5 } HC@e0eada7afaad: PTC VirtMSC-SCCP(1165) finished. Process statistics: { process id: 19178, terminated normally, exit status: 0, user time: 0.041452 s, system time: 0.018423 s, maximum resident set size: 102792, integral resident set size: 0, page faults not requiring physical I/O: 4612, page faults requiring physical I/O: 2, swaps: 0, block input operations: 0, block output operations: 96, messages sent: 0, messages received: 0, signals received: 0, voluntary context switches: 235, involuntary context switches: 18 } HC@e0eada7afaad: PTC VirtMSC-IPA(1166) finished. Process statistics: { process id: 19179, terminated normally, exit status: 0, user time: 0.050993 s, system time: 0.027458 s, maximum resident set size: 103044, integral resident set size: 0, page faults not requiring physical I/O: 4585, page faults requiring physical I/O: 5, swaps: 0, block input operations: 0, block output operations: 88, messages sent: 0, messages received: 0, signals received: 0, voluntary context switches: 239, involuntary context switches: 8 } HC@e0eada7afaad: PTC VirtMSC-IPA-WAIT(1167) finished. Process statistics: { process id: 19180, terminated normally, exit status: 0, user time: 0.039210 s, system time: 0.007129 s, maximum resident set size: 100492, integral resident set size: 0, page faults not requiring physical I/O: 4506, page faults requiring physical I/O: 0, swaps: 0, block input operations: 0, block output operations: 16, messages sent: 0, messages received: 0, signals received: 0, voluntary context switches: 39, involuntary context switches: 9 } HC@e0eada7afaad: PTC VirtMSC-RAN(1168) finished. Process statistics: { process id: 19182, terminated normally, exit status: 0, user time: 0.053956 s, system time: 0.020233 s, maximum resident set size: 106964, integral resident set size: 0, page faults not requiring physical I/O: 4682, page faults requiring physical I/O: 2, swaps: 0, block input operations: 0, block output operations: 120, messages sent: 0, messages received: 0, signals received: 0, voluntary context switches: 196, involuntary context switches: 15 } HC@e0eada7afaad: PTC IPA-CTRL-CLI-IPA(1169) finished. Process statistics: { process id: 19183, terminated normally, exit status: 0, user time: 0.100597 s, system time: 0.048111 s, maximum resident set size: 101696, integral resident set size: 0, page faults not requiring physical I/O: 4597, page faults requiring physical I/O: 0, swaps: 0, block input operations: 0, block output operations: 2240, messages sent: 0, messages received: 0, signals received: 0, voluntary context switches: 5321, involuntary context switches: 52 } HC@e0eada7afaad: PTC VirtMGW-MGCP-0(1170) finished. Process statistics: { process id: 19184, terminated normally, exit status: 0, user time: 0.043743 s, system time: 0.035790 s, maximum resident set size: 102948, integral resident set size: 0, page faults not requiring physical I/O: 4595, page faults requiring physical I/O: 7, swaps: 0, block input operations: 0, block output operations: 104, messages sent: 0, messages received: 0, signals received: 0, voluntary context switches: 152, involuntary context switches: 10 } HC@e0eada7afaad: PTC IPA-BTS0-TRX0-RSL-IPA(1171) finished. Process statistics: { process id: 19186, terminated normally, exit status: 0, user time: 0.063228 s, system time: 0.012645 s, maximum resident set size: 102592, integral resident set size: 0, page faults not requiring physical I/O: 4587, page faults requiring physical I/O: 4, swaps: 0, block input operations: 0, block output operations: 168, messages sent: 0, messages received: 0, signals received: 0, voluntary context switches: 342, involuntary context switches: 9 } HC@e0eada7afaad: PTC IPA-BTS0-TRX0-RSL-RSL(1172) finished. Process statistics: { process id: 19187, terminated normally, exit status: 0, user time: 0.055844 s, system time: 0.042957 s, maximum resident set size: 104032, integral resident set size: 0, page faults not requiring physical I/O: 4610, page faults requiring physical I/O: 0, swaps: 0, block input operations: 0, block output operations: 304, messages sent: 0, messages received: 0, signals received: 0, voluntary context switches: 498, involuntary context switches: 15 } HC@e0eada7afaad: PTC IPA-BTS1-TRX0-RSL-IPA(1173) finished. Process statistics: { process id: 19189, terminated normally, exit status: 0, user time: 0.072395 s, system time: 0.025551 s, maximum resident set size: 102348, integral resident set size: 0, page faults not requiring physical I/O: 4582, page faults requiring physical I/O: 13, swaps: 0, block input operations: 0, block output operations: 128, messages sent: 0, messages received: 0, signals received: 0, voluntary context switches: 264, involuntary context switches: 10 } HC@e0eada7afaad: PTC IPA-BTS1-TRX0-RSL-RSL(1174) finished. Process statistics: { process id: 19190, terminated normally, exit status: 0, user time: 0.042339 s, system time: 0.034641 s, maximum resident set size: 102968, integral resident set size: 0, page faults not requiring physical I/O: 4617, page faults requiring physical I/O: 1, swaps: 0, block input operations: 0, block output operations: 232, messages sent: 0, messages received: 0, signals received: 0, voluntary context switches: 384, involuntary context switches: 10 } HC@e0eada7afaad: PTC TC_ho_int_a5_0(1175) finished. Process statistics: { process id: 19192, terminated normally, exit status: 0, user time: 0.108794 s, system time: 0.027198 s, maximum resident set size: 110188, integral resident set size: 0, page faults not requiring physical I/O: 4780, page faults requiring physical I/O: 0, swaps: 0, block input operations: 0, block output operations: 256, messages sent: 0, messages received: 0, signals received: 0, voluntary context switches: 538, involuntary context switches: 14 } HC@e0eada7afaad: PTC was created. Component reference: 1176, component type: StatsD_Checker.StatsD_Checker_CT, component name: VirtMSC-STATS, testcase name: TC_ho_int_a5_1, process id: 19255. VirtMSC-STATS(1176)@e0eada7afaad: Initializing variables, timers and ports of component type StatsD_Checker.StatsD_Checker_CT inside testcase TC_ho_int_a5_1. MTC@e0eada7afaad: PTC was created. Component reference: 1176, alive: no, type: StatsD_Checker.StatsD_Checker_CT, component name: VirtMSC-STATS. MTC@e0eada7afaad: Starting function main("127.0.0.1", 8125, 5.000000) on component VirtMSC-STATS(1176). MTC@e0eada7afaad: Function was started. MTC@e0eada7afaad: Sent on BSCVTY to system charstring : "msc 0 bssmap reset" 20251222142558384 [1;33mDMSC[0;m [1;33mNOTICE[0;m (msc=0) VTY requests BSSMAP RESET (bsc_vty.c:3616) MTC@e0eada7afaad: Start timer T: 2 s MTC@e0eada7afaad: Message enqueued on BSCVTY from system charstring : "OsmoBSC# " id 13 MTC@e0eada7afaad: Matching on port BSCVTY "OsmoBSC# " with pattern "[\w-]+> " unmatched: First message in the queue does not match the template: MTC@e0eada7afaad: Matching on port BSCVTY succeeded: "OsmoBSC# " with pattern "[\w-]+\# " matched MTC@e0eada7afaad: Receive operation on port BSCVTY succeeded, message from system(): charstring : "OsmoBSC# " id 13 MTC@e0eada7afaad: Message with id 13 was extracted from the queue of BSCVTY. MTC@e0eada7afaad: Stop timer T: 2 s MTC@e0eada7afaad: Sent on BSCVTY to system charstring : "msc 1 bssmap reset" MTC@e0eada7afaad: Start timer T: 2 s MTC@e0eada7afaad: Message enqueued on BSCVTY from system charstring : "% No such MSC: nr 1" id 14 MTC@e0eada7afaad: Message enqueued on BSCVTY from system charstring : "OsmoBSC# " id 15 MTC@e0eada7afaad: Matching on port BSCVTY "% No such MSC: nr 1" with pattern "[\w-]+> " unmatched: First message in the queue does not match the template: MTC@e0eada7afaad: Matching on port BSCVTY "% No such MSC: nr 1" with pattern "[\w-]+\# " unmatched: First message in the queue does not match the template: MTC@e0eada7afaad: Matching on port BSCVTY "% No such MSC: nr 1" with pattern "[\w-]+$*$\# " unmatched: First message in the queue does not match the template: MTC@e0eada7afaad: Matching on port BSCVTY "% No such MSC: nr 1" with pattern "*% Unknown command." unmatched: First message in the queue does not match the template: MTC@e0eada7afaad: Matching on port BSCVTY succeeded: "% No such MSC: nr 1" with ? matched MTC@e0eada7afaad: Receive operation on port BSCVTY succeeded, message from system(): charstring : "% No such MSC: nr 1" id 14 MTC@e0eada7afaad: Message with id 14 was extracted from the queue of BSCVTY. MTC@e0eada7afaad: Matching on port BSCVTY "OsmoBSC# " with pattern "[\w-]+> " unmatched: First message in the queue does not match the template: MTC@e0eada7afaad: Matching on port BSCVTY succeeded: "OsmoBSC# " with pattern "[\w-]+\# " matched MTC@e0eada7afaad: Receive operation on port BSCVTY succeeded, message from system(): charstring : "OsmoBSC# " id 15 MTC@e0eada7afaad: Message with id 15 was extracted from the queue of BSCVTY. [38;5;45m20251222142558387 [1;31mDLSCCP[0;m[38;5;45m [1;31mERROR[0;m[38;5;45m 0: SCCP(rctx=0) MTP-TRANSFER.req from SCCP for OPC=187=0.23.3,DPC=185=0.23.1,SLS=2: no route! (sccp_scrc.c:177)[0;m MTC@e0eada7afaad: Stop timer T: 2 s MTC@e0eada7afaad: Sent on BSCVTY to system charstring : "msc 2 bssmap reset" [38;5;45m20251222142558387 [1;33mDLSCCP[0;m[38;5;45m [1;33mNOTICE[0;m[38;5;45m 0: SCCP(rctx=0) SCRC Routing Failure (MTP failure) for CL message CL:CLDT (sccp_sclc.c:359)[0;m MTC@e0eada7afaad: Start timer T: 2 s 20251222142558387 [1;33mDMSC[0;m [1;33mNOTICE[0;m (msc=0) (calling_addr=RI=2,PC=185,SSN=254) N-NOTICE.ind cause=5='MTP failure' importance=0 (osmo_bsc_sigtran.c:168) MTC@e0eada7afaad: Message enqueued on BSCVTY from system charstring : "% No such MSC: nr 2" id 16 MTC@e0eada7afaad: Message enqueued on BSCVTY from system charstring : "OsmoBSC# " id 17 MTC@e0eada7afaad: Matching on port BSCVTY "% No such MSC: nr 2" with pattern "[\w-]+> " unmatched: First message in the queue does not match the template: MTC@e0eada7afaad: Matching on port BSCVTY "% No such MSC: nr 2" with pattern "[\w-]+\# " unmatched: First message in the queue does not match the template: MTC@e0eada7afaad: Matching on port BSCVTY "% No such MSC: nr 2" with pattern "[\w-]+$*$\# " unmatched: First message in the queue does not match the template: MTC@e0eada7afaad: Matching on port BSCVTY "% No such MSC: nr 2" with pattern "*% Unknown command." unmatched: First message in the queue does not match the template: MTC@e0eada7afaad: Matching on port BSCVTY succeeded: "% No such MSC: nr 2" with ? matched MTC@e0eada7afaad: Receive operation on port BSCVTY succeeded, message from system(): charstring : "% No such MSC: nr 2" id 16 MTC@e0eada7afaad: Message with id 16 was extracted from the queue of BSCVTY. MTC@e0eada7afaad: Matching on port BSCVTY "OsmoBSC# " with pattern "[\w-]+> " unmatched: First message in the queue does not match the template: MTC@e0eada7afaad: Matching on port BSCVTY succeeded: "OsmoBSC# " with pattern "[\w-]+\# " matched MTC@e0eada7afaad: Receive operation on port BSCVTY succeeded, message from system(): charstring : "OsmoBSC# " id 17 MTC@e0eada7afaad: Message with id 17 was extracted from the queue of BSCVTY. MTC@e0eada7afaad: Stop timer T: 2 s MTC@e0eada7afaad: Creating new alive PTC with component type SCCP_Emulation.SCCP_CT, component name: VirtMSC-SCCP. VirtMSC-STATS(1176)@e0eada7afaad: Port STATSVTY was started. VirtMSC-STATS(1176)@e0eada7afaad: Port STATSD_PROC was started. VirtMSC-STATS(1176)@e0eada7afaad: Port STATS was started. VirtMSC-STATS(1176)@e0eada7afaad: Component type StatsD_Checker.StatsD_Checker_CT was initialized. VirtMSC-STATS(1176)@e0eada7afaad: Starting function main("127.0.0.1", 8125, 5.000000). VirtMSC-STATS(1176)@e0eada7afaad: Mapping port VirtMSC-STATS(1176):STATS to system:STATS. VirtMSC-STATS(1176)@e0eada7afaad: Port STATS was mapped to system:STATS. VirtMSC-STATS(1176)@e0eada7afaad: Map operation of VirtMSC-STATS(1176):STATS to system:STATS finished. VirtMSC-STATS(1176)@e0eada7afaad: entering f__IPL4__PROVIDER__listen: 127.0.0.1:8125 / UDP VirtMSC-STATS(1176)@e0eada7afaad: Mapping port VirtMSC-STATS(1176):STATSVTY to system:STATSVTY. VirtMSC-STATS(1176)@e0eada7afaad: Message enqueued on STATSVTY from system integer : 2 id 1 VirtMSC-STATS(1176)@e0eada7afaad: Port STATSVTY was mapped to system:STATSVTY. VirtMSC-STATS(1176)@e0eada7afaad: Map operation of VirtMSC-STATS(1176):STATSVTY to system:STATSVTY finished. HC@e0eada7afaad: PTC was created. Component reference: 1177, component type: SCCP_Emulation.SCCP_CT, component name: VirtMSC-SCCP, testcase name: TC_ho_int_a5_1, process id: 19256. VirtMSC-STATS(1176)@e0eada7afaad: Sent on STATSVTY to system @TELNETasp_PortType.ASP_TelnetDynamicConfig : { prompt := { id := 1, prompt := "OsmoBSC> ", has_wildcards := false } } VirtMSC-STATS(1176)@e0eada7afaad: Sent on STATSVTY to system @TELNETasp_PortType.ASP_TelnetDynamicConfig : { prompt := { id := 2, prompt := "OsmoBSC# ", has_wildcards := false } } VirtMSC-SCCP(1177)@e0eada7afaad: Initializing variables, timers and ports of component type SCCP_Emulation.SCCP_CT inside testcase TC_ho_int_a5_1. MTC@e0eada7afaad: PTC was created. Component reference: 1177, alive: yes, type: SCCP_Emulation.SCCP_CT, component name: VirtMSC-SCCP. MTC@e0eada7afaad: Creating new alive PTC with component type IPA_Emulation.IPA_Emulation_CT, component name: VirtMSC-IPA. VirtMSC-STATS(1176)@e0eada7afaad: Sent on STATSVTY to system @TELNETasp_PortType.ASP_TelnetDynamicConfig : { prompt := { id := 3, prompt := "OsmoBSC(*)", has_wildcards := true } } VirtMSC-STATS(1176)@e0eada7afaad: Sent on STATSVTY to system charstring : "enable" VirtMSC-STATS(1176)@e0eada7afaad: Start timer T: 2 s VirtMSC-STATS(1176)@e0eada7afaad: Matching on port STATSVTY failed: Type of the first message in the queue is not charstring. VirtMSC-STATS(1176)@e0eada7afaad: Matching on port STATSVTY failed: Type of the first message in the queue is not charstring. VirtMSC-STATS(1176)@e0eada7afaad: Matching on port STATSVTY failed: Type of the first message in the queue is not charstring. VirtMSC-STATS(1176)@e0eada7afaad: Matching on port STATSVTY failed: Type of the first message in the queue is not charstring. VirtMSC-STATS(1176)@e0eada7afaad: Matching on port STATSVTY failed: Type of the first message in the queue is not charstring. VirtMSC-STATS(1176)@e0eada7afaad: Matching on port STATSVTY succeeded: 2 with ? matched VirtMSC-STATS(1176)@e0eada7afaad: Receive operation on port STATSVTY succeeded, message from system(): integer : 2 id 1 VirtMSC-STATS(1176)@e0eada7afaad: Message with id 1 was extracted from the queue of STATSVTY. VirtMSC-SCCP(1177)@e0eada7afaad: Port SCCP_SP_PORT was started. VirtMSC-SCCP(1177)@e0eada7afaad: Port MTP3_SCCP_PORT was started. VirtMSC-STATS(1176)@e0eada7afaad: Message enqueued on STATSVTY from system charstring : "OsmoBSC# " id 2 VirtMSC-SCCP(1177)@e0eada7afaad: Component type SCCP_Emulation.SCCP_CT was initialized. VirtMSC-STATS(1176)@e0eada7afaad: Matching on port STATSVTY "OsmoBSC# " with pattern "[\w-]+> " unmatched: First message in the queue does not match the template: VirtMSC-STATS(1176)@e0eada7afaad: Matching on port STATSVTY succeeded: "OsmoBSC# " with pattern "[\w-]+\# " matched VirtMSC-STATS(1176)@e0eada7afaad: Receive operation on port STATSVTY succeeded, message from system(): charstring : "OsmoBSC# " id 2 VirtMSC-STATS(1176)@e0eada7afaad: Message with id 2 was extracted from the queue of STATSVTY. VirtMSC-STATS(1176)@e0eada7afaad: Stop timer T: 2 s VirtMSC-STATS(1176)@e0eada7afaad: Sent on STATSVTY to system charstring : "stats reset" VirtMSC-STATS(1176)@e0eada7afaad: Start timer T: 2 s VirtMSC-STATS(1176)@e0eada7afaad: Message enqueued on STATSVTY from system charstring : "OsmoBSC# " id 3 VirtMSC-STATS(1176)@e0eada7afaad: Matching on port STATSVTY "OsmoBSC# " with pattern "[\w-]+> " unmatched: First message in the queue does not match the template: VirtMSC-STATS(1176)@e0eada7afaad: Matching on port STATSVTY succeeded: "OsmoBSC# " with pattern "[\w-]+\# " matched VirtMSC-STATS(1176)@e0eada7afaad: Receive operation on port STATSVTY succeeded, message from system(): charstring : "OsmoBSC# " id 3 VirtMSC-STATS(1176)@e0eada7afaad: Message with id 3 was extracted from the queue of STATSVTY. VirtMSC-STATS(1176)@e0eada7afaad: Stop timer T: 2 s HC@e0eada7afaad: PTC was created. Component reference: 1178, component type: IPA_Emulation.IPA_Emulation_CT, component name: VirtMSC-IPA, testcase name: TC_ho_int_a5_1, process id: 19257. VirtMSC-IPA(1178)@e0eada7afaad: Initializing variables, timers and ports of component type IPA_Emulation.IPA_Emulation_CT inside testcase TC_ho_int_a5_1. MTC@e0eada7afaad: PTC was created. Component reference: 1178, alive: yes, type: IPA_Emulation.IPA_Emulation_CT, component name: VirtMSC-IPA. MTC@e0eada7afaad: Mapping port VirtMSC-IPA(1178):IPA_PORT to system:IPA_CODEC_PT. VirtMSC-IPA(1178)@e0eada7afaad: Port IPA_PORT was started. VirtMSC-IPA(1178)@e0eada7afaad: Port CFG_PORT was started. VirtMSC-IPA(1178)@e0eada7afaad: Port MTP3_SP_PORT was started. VirtMSC-IPA(1178)@e0eada7afaad: Port IPA_MGCP_PORT was started. VirtMSC-IPA(1178)@e0eada7afaad: Port IPA_RSL_PORT was started. VirtMSC-IPA(1178)@e0eada7afaad: Port IPA_OML_PORT was started. VirtMSC-IPA(1178)@e0eada7afaad: Port IPA_CTRL_PORT was started. VirtMSC-IPA(1178)@e0eada7afaad: Port IPA_SP_PORT was started. VirtMSC-IPA(1178)@e0eada7afaad: Component type IPA_Emulation.IPA_Emulation_CT was initialized. VirtMSC-IPA(1178)@e0eada7afaad: Port IPA_PORT was mapped to system:IPA_CODEC_PT. MTC@e0eada7afaad: Map operation of VirtMSC-IPA(1178):IPA_PORT to system:IPA_CODEC_PT finished. MTC@e0eada7afaad: Connecting ports VirtMSC-IPA(1178):MTP3_SP_PORT and VirtMSC-SCCP(1177):MTP3_SCCP_PORT. VirtMSC-SCCP(1177)@e0eada7afaad: Port MTP3_SCCP_PORT is waiting for connection from VirtMSC-IPA(1178):MTP3_SP_PORT on UNIX pathname /tmp/ttcn3-portconn-7511f0e4. VirtMSC-IPA(1178)@e0eada7afaad: Port MTP3_SP_PORT has established the connection with VirtMSC-SCCP(1177):MTP3_SCCP_PORT using transport type UNIX. VirtMSC-SCCP(1177)@e0eada7afaad: Port MTP3_SCCP_PORT has accepted the connection from VirtMSC-IPA(1178):MTP3_SP_PORT. MTC@e0eada7afaad: Connect operation on VirtMSC-IPA(1178):MTP3_SP_PORT and VirtMSC-SCCP(1177):MTP3_SCCP_PORT finished. MTC@e0eada7afaad: Creating new alive PTC with component type IPA_Emulation.IPA_EventWaiter_CT, component name: VirtMSC-IPA-WAIT. HC@e0eada7afaad: PTC was created. Component reference: 1179, component type: IPA_Emulation.IPA_EventWaiter_CT, component name: VirtMSC-IPA-WAIT, testcase name: TC_ho_int_a5_1, process id: 19258. VirtMSC-IPA-WAIT(1179)@e0eada7afaad: Initializing variables, timers and ports of component type IPA_Emulation.IPA_EventWaiter_CT inside testcase TC_ho_int_a5_1. MTC@e0eada7afaad: PTC was created. Component reference: 1179, alive: yes, type: IPA_Emulation.IPA_EventWaiter_CT, component name: VirtMSC-IPA-WAIT. MTC@e0eada7afaad: Connecting ports VirtMSC-IPA(1178):IPA_SP_PORT and VirtMSC-IPA-WAIT(1179):IPA_SP_PORT. VirtMSC-IPA(1178)@e0eada7afaad: Port IPA_SP_PORT is waiting for connection from VirtMSC-IPA-WAIT(1179):IPA_SP_PORT on UNIX pathname /tmp/ttcn3-portconn-5202b695. VirtMSC-IPA-WAIT(1179)@e0eada7afaad: Port IPA_SP_PORT was started. VirtMSC-IPA-WAIT(1179)@e0eada7afaad: Component type IPA_Emulation.IPA_EventWaiter_CT was initialized. VirtMSC-IPA-WAIT(1179)@e0eada7afaad: Port IPA_SP_PORT has established the connection with VirtMSC-IPA(1178):IPA_SP_PORT using transport type UNIX. VirtMSC-IPA(1178)@e0eada7afaad: Port IPA_SP_PORT has accepted the connection from VirtMSC-IPA-WAIT(1179):IPA_SP_PORT. MTC@e0eada7afaad: Connect operation on VirtMSC-IPA(1178):IPA_SP_PORT and VirtMSC-IPA-WAIT(1179):IPA_SP_PORT finished. MTC@e0eada7afaad: Starting function waiter_main({ ev_type := ASP_IPA_EVENT_ID_ACK (3), conn_id := ?, id_resp := * }) on component VirtMSC-IPA-WAIT(1179). MTC@e0eada7afaad: Function was started. MTC@e0eada7afaad: Starting function main_server("127.0.0.1", 5000, true, IPA_INIT_SEND_IPA_ID_ACK (1), true) on component VirtMSC-IPA(1178). MTC@e0eada7afaad: Function was started. VirtMSC-IPA-WAIT(1179)@e0eada7afaad: Starting function waiter_main({ ev_type := ASP_IPA_EVENT_ID_ACK (3), conn_id := ?, id_resp := * }). VirtMSC-IPA(1178)@e0eada7afaad: Starting function main_server("127.0.0.1", 5000, true, IPA_INIT_SEND_IPA_ID_ACK (1), true). VirtMSC-IPA(1178)@e0eada7afaad: entering f__IPL4__PROVIDER__listen: 127.0.0.1:5000 / TCP FATAL: command execution failed java.io.EOFException at java.base/java.io.ObjectInputStream$PeekInputStream.readFully(Unknown Source) at java.base/java.io.ObjectInputStream$BlockDataInputStream.readShort(Unknown Source) at java.base/java.io.ObjectInputStream.readStreamHeader(Unknown Source) at java.base/java.io.ObjectInputStream.<init>(Unknown Source) at hudson.remoting.ObjectInputStreamEx.<init>(ObjectInputStreamEx.java:50) at hudson.remoting.Command.readFrom(Command.java:141) at hudson.remoting.Command.readFrom(Command.java:127) at hudson.remoting.AbstractSynchronousByteArrayCommandTransport.read(AbstractSynchronousByteArrayCommandTransport.java:35) at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:62) Caused: java.io.IOException: Unexpected termination of the channel at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:80) Caused: java.io.IOException: Backing channel 'build5-deb12build-ansible' is disconnected. at hudson.remoting.RemoteInvocationHandler.channelOrFail(RemoteInvocationHandler.java:227) at hudson.remoting.RemoteInvocationHandler.invoke(RemoteInvocationHandler.java:306) at jdk.proxy2/jdk.proxy2.$Proxy156.isAlive(Unknown Source) at hudson.Launcher$RemoteLauncher$ProcImpl.isAlive(Launcher.java:1212) at hudson.Launcher$RemoteLauncher$ProcImpl.join(Launcher.java:1204) at hudson.tasks.CommandInterpreter.join(CommandInterpreter.java:195) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:145) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:92) at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:818) at hudson.model.Build$BuildExecution.build(Build.java:199) at hudson.model.Build$BuildExecution.doRun(Build.java:164) at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:527) at hudson.model.Run.execute(Run.java:1833) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:44) at hudson.model.ResourceController.execute(ResourceController.java:101) at hudson.model.Executor.run(Executor.java:446) FATAL: Unable to delete script file /tmp/jenkins2152644542038171758.sh java.io.EOFException at java.base/java.io.ObjectInputStream$PeekInputStream.readFully(Unknown Source) at java.base/java.io.ObjectInputStream$BlockDataInputStream.readShort(Unknown Source) at java.base/java.io.ObjectInputStream.readStreamHeader(Unknown Source) at java.base/java.io.ObjectInputStream.<init>(Unknown Source) at hudson.remoting.ObjectInputStreamEx.<init>(ObjectInputStreamEx.java:50) at hudson.remoting.Command.readFrom(Command.java:141) at hudson.remoting.Command.readFrom(Command.java:127) at hudson.remoting.AbstractSynchronousByteArrayCommandTransport.read(AbstractSynchronousByteArrayCommandTransport.java:35) at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:62) Caused: java.io.IOException: Unexpected termination of the channel at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:80) Caused: hudson.remoting.ChannelClosedException: Channel "hudson.remoting.Channel@3d67a435:build5-deb12build-ansible": Remote call on build5-deb12build-ansible failed. The channel is closing down or has closed down at hudson.remoting.Channel.call(Channel.java:1105) at hudson.FilePath.act(FilePath.java:1207) at hudson.FilePath.act(FilePath.java:1196) at hudson.FilePath.delete(FilePath.java:1743) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:163) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:92) at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:818) at hudson.model.Build$BuildExecution.build(Build.java:199) at hudson.model.Build$BuildExecution.doRun(Build.java:164) at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:527) at hudson.model.Run.execute(Run.java:1833) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:44) at hudson.model.ResourceController.execute(ResourceController.java:101) at hudson.model.Executor.run(Executor.java:446) Build step 'Execute shell' marked build as failure ERROR: Step ‘Publish JUnit test result report’ failed: no workspace for ttcn3-bsc-test-sccplite-latest #2536

1 1

2026

2025

2024

2023

2022

2021

2020

2019

jenkins-notifications