 
            Changes:
[Oliver Smith] network_create: find free subnet automatically
------------------------------------------ [...truncated 64.49 KB...] + ADDR_SUFIX=20 + echo --network ttcn3-bts-test --ip 172.18.188.20 --ip6 fd02:db8:188::20 + docker run --rm --network ttcn3-bts-test --ip 172.18.188.20 --ip6 fd02:db8:188::20 --ulimit core=-1 -v https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-bts-test-latest/682/artifact/logs/bts:/data -v https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-bts-test-latest/682/artifact/logs/unix:/data/unix -e SLEEP_BEFORE_RESPAWN=1 --name jenkins-TTCN3-centos-bts-test-latest-682-bts -d osmocom-build/osmo-bts-latest-centos8 /bin/sh -c /usr/local/bin/respawn.sh osmo-bts-trx -c /data/osmo-bts.gen.cfg >>/data/osmo-bts.log 2>&1 67756523dfecd83ba5abf3883ca9f2e5f50a60efcb3a690fb34e3c31d7ac2790 + start_fake_trx + echo Starting container with fake_trx Starting container with fake_trx + docker_network_params 188 21 + NET=188 + ADDR_SUFIX=21 + echo --network ttcn3-bts-test --ip 172.18.188.21 --ip6 fd02:db8:188::21 + docker run --rm --network ttcn3-bts-test --ip 172.18.188.21 --ip6 fd02:db8:188::21 --ulimit core=-1 -v https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-bts-test-latest/682/artifact/logs/fake_trx:/data --name jenkins-TTCN3-centos-bts-test-latest-682-fake_trx -d osmocom-build/osmocom-bb-host-master /bin/sh -c /tmp/osmocom-bb/src/target/trx_toolkit/fake_trx.py --log-file-name /data/fake_trx.log --log-file-level DEBUG --log-file-time --log-level INFO -R 172.18.188.20 -r 172.18.188.22 --trx TRX1@172.18.188.20:5700/1 --trx TRX2@172.18.188.20:5700/2 --trx TRX3@172.18.188.20:5700/3 >>/data/fake_trx.out 2>&1 094fe91e5de5d26c3d5f4919e852afb33293015bbd347ae362f9b6fbff3a9aa8 + start_trxcon + echo Starting container with trxcon Starting container with trxcon + docker_network_params 188 22 + NET=188 + ADDR_SUFIX=22 + echo --network ttcn3-bts-test --ip 172.18.188.22 --ip6 fd02:db8:188::22 + docker run --rm --network ttcn3-bts-test --ip 172.18.188.22 --ip6 fd02:db8:188::22 --ulimit core=-1 -v https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-bts-test-latest/682/artifact/logs/trxcon:/data -v https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-bts-test-latest/682/artifact/logs/unix:/data/unix --name jenkins-TTCN3-centos-bts-test-latest-682-trxcon -d osmocom-build/osmocom-bb-host-master /bin/sh -c trxcon -i 172.18.188.21 -s /data/unix/osmocom_l2 >>/data/trxcon.log 2>&1 51875b3697e8211cf99dab6ddea461f52e4806daf9355253a45d83a1255c697a + start_testsuite generic + echo Starting container with BTS testsuite Starting container with BTS testsuite + variant=generic + docker_network_params 188 10 + NET=188 + ADDR_SUFIX=10 + echo --network ttcn3-bts-test --ip 172.18.188.10 --ip6 fd02:db8:188::10 + docker run --rm --network ttcn3-bts-test --ip 172.18.188.10 --ip6 fd02:db8:188::10 --ulimit core=-1 -e TTCN3_PCAP_PATH=/data -e OSMO_SUT_HOST=172.18.188.20 -e OSMO_SUT_PORT=4241 -v https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-bts-test-latest/682/artifact/logs/bts-tester-generic:/data -v https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-bts-test-latest/682/artifact/logs/unix:/data/unix --name jenkins-TTCN3-centos-bts-test-latest-682-ttcn3-bts-test osmocom-build/ttcn3-bts-test + SUBDIR=bts + SUITE=BTS_Tests + '[' -n '' ']' + cd /data + /osmo-ttcn3-hacks/start-testsuite.sh /osmo-ttcn3-hacks/bts/BTS_Tests ttcn3_start: Starting the test suite ttcn3_start: warning: TTCN3_DIR environment variable is not set spawn mctr_cli BTS_Tests.cfg
************************************************************************* * TTCN-3 Test Executor - Main Controller 2 * * Version: 8.0.0 * * Copyright (c) 2000-2021 Ericsson Telecom AB * * All rights reserved. This program and the accompanying materials * * are made available under the terms of the Eclipse Public License v2.0 * * which accompanies this distribution, and is available at * * https://www.eclipse.org/org/documents/epl-2.0/EPL-2.0.html * *************************************************************************
Using configuration file: BTS_Tests.cfg MC@e26f4d7b2b96: Unix server socket created successfully. MC@e26f4d7b2b96: Listening on TCP port 35501. MC2> e26f4d7b2b96 is the default spawn /osmo-ttcn3-hacks/bts/BTS_Tests e26f4d7b2b96 35501 TTCN-3 Host Controller (parallel mode), version 8.0.0 MC@e26f4d7b2b96: New HC connected from e26f4d7b2b96 [172.18.188.10]. e26f4d7b2b96: Linux 5.10.0-19-amd64 on x86_64. cmtc MC@e26f4d7b2b96: Downloading configuration file to all HCs. construct junitlogger Initializing `JUnitLogger' (v2.0): JUnitLogger writes JUnit-compatible XML HC@e26f4d7b2b96: Warning: Option `FileMask' was given more than once in section [LOGGING] of the configuration file. HC@e26f4d7b2b96: Warning: Option `FileMask' was given more than once in section [LOGGING] of the configuration file. HC@e26f4d7b2b96: Warning: Option `FileMask' was given more than once in section [LOGGING] of the configuration file. HC@e26f4d7b2b96: Warning: Option `FileMask' was given more than once in section [LOGGING] of the configuration file. MC@e26f4d7b2b96: Configuration file was processed on all HCs. MC@e26f4d7b2b96: Creating MTC on host e26f4d7b2b96. MC@e26f4d7b2b96: MTC is created. MC2> smtc Executing all items of [EXECUTE] section. MC2> MTC@e26f4d7b2b96: Starting external command `../ttcn3-tcpdump-start.sh BTS_Tests.TC_est_dchan'. ------ BTS_Tests.TC_est_dchan ------ Tue Jan 17 07:00:34 UTC 2023 NOTE: unable to use dumpcap due to missing capabilities or suid bit Waiting for packet dumper to start... 0 MTC@e26f4d7b2b96: External command `../ttcn3-tcpdump-start.sh BTS_Tests.TC_est_dchan' was executed successfully (exit status: 0). MTC@e26f4d7b2b96: Test case TC_est_dchan started. TC_est_dchan-RSL-IPA(3)@e26f4d7b2b96: Established a new IPA connection (conn_id=2) TC_est_dchan-RSL-IPA(3)@e26f4d7b2b96: CCM Tx:{ msg_type := IPAC_MSGT_ID_GET (4), u := { get := { { len := 1, tag := IPAC_IDTAG_UNITNAME (1) }, { len := 1, tag := IPAC_IDTAG_UNITID (8) } } } } TC_est_dchan-RSL(4)@e26f4d7b2b96: A new IPA/RSL connection has been established (conn_id=2), waiting for IDENTITY RESPONSE... TC_est_dchan-RSL-IPA(3)@e26f4d7b2b96: Established a new IPA connection (conn_id=3) TC_est_dchan-RSL(4)@e26f4d7b2b96: A new IPA/RSL connection has been established (conn_id=3), waiting for IDENTITY RESPONSE... TC_est_dchan-RSL-IPA(3)@e26f4d7b2b96: CCM Tx:{ msg_type := IPAC_MSGT_ID_GET (4), u := { get := { { len := 1, tag := IPAC_IDTAG_UNITNAME (1) }, { len := 1, tag := IPAC_IDTAG_UNITID (8) } } } } TC_est_dchan-RSL-IPA(3)@e26f4d7b2b96: CCM Rx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 28, tag := IPAC_IDTAG_UNITNAME (1), data := '6F736D6F2D6274732D30322D34322D61632D31322D62632D313400'O }, { len := 10, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3000'O } } } } TC_est_dchan-RSL-IPA(3)@e26f4d7b2b96: IPA ID RESP: { { len := 28, tag := IPAC_IDTAG_UNITNAME (1), data := '6F736D6F2D6274732D30322D34322D61632D31322D62632D313400'O }, { len := 10, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3000'O } } TC_est_dchan-RSL-IPA(3)@e26f4d7b2b96: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } TC_est_dchan-RSL(4)@e26f4d7b2b96: Got IDENTITY RESPONSE (conn_id=2): { { len := 28, tag := IPAC_IDTAG_UNITNAME (1), data := '6F736D6F2D6274732D30322D34322D61632D31322D62632D313400'O }, { len := 10, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3000'O } } TC_est_dchan-RSL-IPA(3)@e26f4d7b2b96: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } TC_est_dchan-RSL(4)@e26f4d7b2b96: Warning: The first argument (instr) of function regexp(), which is "1234/0/0" & char(0, 0, 0, 0), contains a character with zero character code at index 8. The rest of the string will be ignored during matching. TC_est_dchan-RSL(4)@e26f4d7b2b96: Mapped TRX#0 to TCP/IP conn_id=2 MTC@e26f4d7b2b96: 1/4 transceiver(s) connected TC_est_dchan-RSL-IPA(3)@e26f4d7b2b96: Established a new IPA connection (conn_id=4) TC_est_dchan-RSL(4)@e26f4d7b2b96: A new IPA/RSL connection has been established (conn_id=4), waiting for IDENTITY RESPONSE... TC_est_dchan-RSL-IPA(3)@e26f4d7b2b96: CCM Tx:{ msg_type := IPAC_MSGT_ID_GET (4), u := { get := { { len := 1, tag := IPAC_IDTAG_UNITNAME (1) }, { len := 1, tag := IPAC_IDTAG_UNITID (8) } } } } TC_est_dchan-RSL-IPA(3)@e26f4d7b2b96: CCM Rx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 28, tag := IPAC_IDTAG_UNITNAME (1), data := '6F736D6F2D6274732D30322D34322D61632D31322D62632D313400'O }, { len := 10, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3100'O } } } } TC_est_dchan-RSL-IPA(3)@e26f4d7b2b96: IPA ID RESP: { { len := 28, tag := IPAC_IDTAG_UNITNAME (1), data := '6F736D6F2D6274732D30322D34322D61632D31322D62632D313400'O }, { len := 10, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3100'O } } TC_est_dchan-RSL-IPA(3)@e26f4d7b2b96: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } TC_est_dchan-RSL(4)@e26f4d7b2b96: Got IDENTITY RESPONSE (conn_id=3): { { len := 28, tag := IPAC_IDTAG_UNITNAME (1), data := '6F736D6F2D6274732D30322D34322D61632D31322D62632D313400'O }, { len := 10, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3100'O } } TC_est_dchan-RSL-IPA(3)@e26f4d7b2b96: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } TC_est_dchan-RSL(4)@e26f4d7b2b96: Warning: The first argument (instr) of function regexp(), which is "1234/0/1" & char(0, 0, 0, 0), contains a character with zero character code at index 8. The rest of the string will be ignored during matching. TC_est_dchan-RSL(4)@e26f4d7b2b96: Mapped TRX#1 to TCP/IP conn_id=3 MTC@e26f4d7b2b96: 1/4 RF Resource Indication(s) received MTC@e26f4d7b2b96: 2/4 transceiver(s) connected TC_est_dchan-RSL-IPA(3)@e26f4d7b2b96: Established a new IPA connection (conn_id=5) MTC@e26f4d7b2b96: 2/4 RF Resource Indication(s) received TC_est_dchan-RSL(4)@e26f4d7b2b96: A new IPA/RSL connection has been established (conn_id=5), waiting for IDENTITY RESPONSE... TC_est_dchan-RSL-IPA(3)@e26f4d7b2b96: CCM Tx:{ msg_type := IPAC_MSGT_ID_GET (4), u := { get := { { len := 1, tag := IPAC_IDTAG_UNITNAME (1) }, { len := 1, tag := IPAC_IDTAG_UNITID (8) } } } } TC_est_dchan-RSL-IPA(3)@e26f4d7b2b96: CCM Rx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 28, tag := IPAC_IDTAG_UNITNAME (1), data := '6F736D6F2D6274732D30322D34322D61632D31322D62632D313400'O }, { len := 10, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3200'O } } } } TC_est_dchan-RSL-IPA(3)@e26f4d7b2b96: IPA ID RESP: { { len := 28, tag := IPAC_IDTAG_UNITNAME (1), data := '6F736D6F2D6274732D30322D34322D61632D31322D62632D313400'O }, { len := 10, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3200'O } } TC_est_dchan-RSL-IPA(3)@e26f4d7b2b96: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } TC_est_dchan-RSL(4)@e26f4d7b2b96: Got IDENTITY RESPONSE (conn_id=4): { { len := 28, tag := IPAC_IDTAG_UNITNAME (1), data := '6F736D6F2D6274732D30322D34322D61632D31322D62632D313400'O }, { len := 10, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3200'O } } TC_est_dchan-RSL(4)@e26f4d7b2b96: Warning: The first argument (instr) of function regexp(), which is "1234/0/2" & char(0, 0, 0, 0), contains a character with zero character code at index 8. The rest of the string will be ignored during matching. TC_est_dchan-RSL-IPA(3)@e26f4d7b2b96: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } TC_est_dchan-RSL(4)@e26f4d7b2b96: Mapped TRX#2 to TCP/IP conn_id=4 MTC@e26f4d7b2b96: 3/4 transceiver(s) connected TC_est_dchan-RSL-IPA(3)@e26f4d7b2b96: CCM Rx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 28, tag := IPAC_IDTAG_UNITNAME (1), data := '6F736D6F2D6274732D30322D34322D61632D31322D62632D313400'O }, { len := 10, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3300'O } } } } MTC@e26f4d7b2b96: 3/4 RF Resource Indication(s) received TC_est_dchan-RSL-IPA(3)@e26f4d7b2b96: IPA ID RESP: { { len := 28, tag := IPAC_IDTAG_UNITNAME (1), data := '6F736D6F2D6274732D30322D34322D61632D31322D62632D313400'O }, { len := 10, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3300'O } } TC_est_dchan-RSL-IPA(3)@e26f4d7b2b96: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } TC_est_dchan-RSL(4)@e26f4d7b2b96: Got IDENTITY RESPONSE (conn_id=5): { { len := 28, tag := IPAC_IDTAG_UNITNAME (1), data := '6F736D6F2D6274732D30322D34322D61632D31322D62632D313400'O }, { len := 10, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3300'O } } TC_est_dchan-RSL-IPA(3)@e26f4d7b2b96: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } TC_est_dchan-RSL(4)@e26f4d7b2b96: Warning: The first argument (instr) of function regexp(), which is "1234/0/3" & char(0, 0, 0, 0), contains a character with zero character code at index 8. The rest of the string will be ignored during matching. TC_est_dchan-RSL(4)@e26f4d7b2b96: Mapped TRX#3 to TCP/IP conn_id=5 MTC@e26f4d7b2b96: 4/4 transceiver(s) connected MTC@e26f4d7b2b96: 4/4 RF Resource Indication(s) received MTC@e26f4d7b2b96: Setting RSL_SYSTEM_INFO_3 (3): { header := { l2_plen := { l2_plen := 18, zero_one := '01'B }, skip_indicator := 0, rr_protocol_discriminator := 6, message_type := SYSTEM_INFORMATION_TYPE_3 (27) }, payload := { si3 := { cell_id := 23, lai := { mcc_mnc := '262F42'H, lac := 42 }, ctrl_chan_desc := { msc_r99 := true, att := true, bs_ag_blks_res := 1, ccch_conf := CCHAN_DESC_1CCCH_COMBINED (1), si22ind := false, cbq3 := CBQ3_IU_MODE_NOT_SUPPORTED (0), spare := '00'B, bs_pa_mfrms := 0, t3212 := 1 }, cell_options := { dn_ind := false, pwrc := false, dtx := MS_MAY_USE_UL_DTX (0), radio_link_tout_div4 := 7 }, cell_sel_par := { cell_resel_hyst_2dB := 2, ms_txpwr_max_cch := 7, acs := '0'B, neci := true, rxlev_access_min := 0 }, rach_control := { max_retrans := RACH_MAX_RETRANS_7 (3), tx_integer := '1001'B, cell_barr_access := false, re_not_allowed := true, acc := '0000010000000000'B }, rest_octets := { sel_params := { presence := '0'B, params := omit }, pwr_offset := { presence := '0'B, offset := omit }, si_2ter_ind := '0'B, early_cm_ind := '0'B, sched_where := { presence := '0'B, where := omit }, gprs_ind := { presence := '1'B, ind := { ra_colour := 0, si13_pos := '0'B } }, umts_early_cm_ind := '0'B, si2_quater_ind := { presence := '0'B, ind := omit }, iu_mode_ind := omit, si21_ind := { presence := '0'B, pos := omit } } } } } MTC@e26f4d7b2b96: Setting RSL_SYSTEM_INFO_3 (3): '49061B001762F224002AC90001074740E504002C2B2B2B'O MTC@e26f4d7b2b96: Setting RSL_SYSTEM_INFO_2 (2): { header := { l2_plen := { l2_plen := 22, zero_one := '01'B }, skip_indicator := 0, rr_protocol_discriminator := 6, message_type := SYSTEM_INFORMATION_TYPE_2 (26) }, payload := { si2 := { bcch_freq_list := '00000000000000000000000000000000'O, ncc_permitted := '11111111'B, rach_control := { max_retrans := RACH_MAX_RETRANS_7 (3), tx_integer := '1001'B, cell_barr_access := false, re_not_allowed := true, acc := '0000010000000000'B } } } } MTC@e26f4d7b2b96: Setting RSL_SYSTEM_INFO_2 (2): '59061A00000000000000000000000000000000FFE50400'O MTC@e26f4d7b2b96: Setting RSL_SYSTEM_INFO_4 (4): { header := { l2_plen := { l2_plen := 12, zero_one := '01'B }, skip_indicator := 0, rr_protocol_discriminator := 6, message_type := SYSTEM_INFORMATION_TYPE_4 (28) }, payload := { si4 := { lai := { mcc_mnc := '262F42'H, lac := 42 }, cell_sel_par := { cell_resel_hyst_2dB := 2, ms_txpwr_max_cch := 7, acs := '0'B, neci := true, rxlev_access_min := 0 }, rach_control := { max_retrans := RACH_MAX_RETRANS_7 (3), tx_integer := '1001'B, cell_barr_access := false, re_not_allowed := true, acc := '0000010000000000'B }, cbch_chan_desc := omit, cbch_mobile_alloc := omit, rest_octets := { sel_params := { presence := '0'B, params := omit }, pwr_offset := { presence := '0'B, offset := omit }, gprs_ind := { presence := '1'B, ind := { ra_colour := 0, si13_pos := '0'B } }, s_presence := '0'B, s := omit } } } } MTC@e26f4d7b2b96: Setting RSL_SYSTEM_INFO_4 (4): '31061C62F224002A4740E50400012B2B2B2B2B2B2B2B2B'O MTC@e26f4d7b2b96: Warning: dec_TrxcMessage(): Data remained at the end of the stream after successful decoding: '00'O MTC@e26f4d7b2b96: Warning: dec_TrxcMessage(): Data remained at the end of the stream after successful decoding: '00'O MTC@e26f4d7b2b96: Warning: dec_TrxcMessage(): Data remained at the end of the stream after successful decoding: '00'O TC_est_dchan(6)@e26f4d7b2b96: setverdict(pass): none -> pass reason: "Received CHAN-RQD from RACH REQ", new component reason: "Received CHAN-RQD from RACH REQ" TC_est_dchan(6)@e26f4d7b2b96: setverdict(pass): pass -> pass, component reason not changed TC_est_dchan(6)@e26f4d7b2b96: PCH/AGCH DL RR: { header := { l2_plen := { l2_plen := 0, zero_one := '01'B }, skip_indicator := 0, rr_protocol_discriminator := 6, message_type := IMMEDIATE_ASSIGNMENT (63) }, payload := { imm_ass := { ded_or_tbf := { spare := '0'B, tma := false, downlink := false, tbf := false }, page_mode := PAGE_MODE_NORMAL (0), chan_desc := { chan_nr := { u := { sdcch4 := { tag := '001'B, sub_chan := 0 } }, tn := 0 }, tsc := 7, h := false, arfcn := 871, maio_hsn := omit }, pkt_chan_desc := omit, req_ref := { ra := '11001111'B, t1p := 0, t3 := 4, t2 := 1 }, timing_advance := 0, mobile_allocation := { len := 0, ma := ''B }, rest_octets := { presence := '00'B, ll := { compressed_irat_ho_info_ind := '0'B }, lh := omit, hl := omit, hh := omit } } } } TC_est_dchan(6)@e26f4d7b2b96: Received IMM.ASS for our RACH! TC_est_dchan(6)@e26f4d7b2b96: setverdict(pass): pass -> pass, component reason not changed TC_est_dchan(6)@e26f4d7b2b96: setverdict(pass): pass -> pass, component reason not changed TC_est_dchan(6)@e26f4d7b2b96: Final verdict of PTC: pass reason: "Received CHAN-RQD from RACH REQ" TC_est_dchan(7)@e26f4d7b2b96: setverdict(pass): none -> pass reason: "Received CHAN-RQD from RACH REQ", new component reason: "Received CHAN-RQD from RACH REQ" TC_est_dchan(7)@e26f4d7b2b96: setverdict(pass): pass -> pass, component reason not changed TC_est_dchan(7)@e26f4d7b2b96: PCH/AGCH DL RR: { header := { l2_plen := { l2_plen := 0, zero_one := '11'B }, skip_indicator := 0, rr_protocol_discriminator := 3, message_type := SYSTEM_INFORMATION_TYPE_14 (1) }, payload := { other := '2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B'O ("++++++++++++++++++++") } } TC_est_dchan(7)@e26f4d7b2b96: PCH/AGCH DL RR: { header := { l2_plen := { l2_plen := 0, zero_one := '01'B }, skip_indicator := 0, rr_protocol_discriminator := 6, message_type := IMMEDIATE_ASSIGNMENT (63) }, payload := { imm_ass := { ded_or_tbf := { spare := '0'B, tma := false, downlink := false, tbf := false }, page_mode := PAGE_MODE_NORMAL (0), chan_desc := { chan_nr := { u := { sdcch4 := { tag := '001'B, sub_chan := 1 } }, tn := 0 }, tsc := 7, h := false, arfcn := 871, maio_hsn := omit }, pkt_chan_desc := omit, req_ref := { ra := '11001111'B, t1p := 0, t3 := 5, t2 := 0 }, timing_advance := 0, mobile_allocation := { len := 0, ma := ''B }, rest_octets := { presence := '00'B, ll := { compressed_irat_ho_info_ind := '0'B }, lh := omit, hl := omit, hh := omit } } } } TC_est_dchan(7)@e26f4d7b2b96: Received IMM.ASS for our RACH! TC_est_dchan(7)@e26f4d7b2b96: setverdict(pass): pass -> pass, component reason not changed TC_est_dchan(7)@e26f4d7b2b96: setverdict(pass): pass -> pass, component reason not changed TC_est_dchan(7)@e26f4d7b2b96: Final verdict of PTC: pass reason: "Received CHAN-RQD from RACH REQ" TC_est_dchan(8)@e26f4d7b2b96: setverdict(pass): none -> pass reason: "Received CHAN-RQD from RACH REQ", new component reason: "Received CHAN-RQD from RACH REQ" TC_est_dchan(8)@e26f4d7b2b96: setverdict(pass): pass -> pass, component reason not changed TC_est_dchan(8)@e26f4d7b2b96: PCH/AGCH DL RR: { header := { l2_plen := { l2_plen := 0, zero_one := '01'B }, skip_indicator := 0, rr_protocol_discriminator := 6, message_type := IMMEDIATE_ASSIGNMENT (63) }, payload := { imm_ass := { ded_or_tbf := { spare := '0'B, tma := false, downlink := false, tbf := false }, page_mode := PAGE_MODE_NORMAL (0), chan_desc := { chan_nr := { u := { sdcch4 := { tag := '001'B, sub_chan := 2 } }, tn := 0 }, tsc := 7, h := false, arfcn := 871, maio_hsn := omit }, pkt_chan_desc := omit, req_ref := { ra := '11001111'B, t1p := 0, t3 := 4, t2 := 22 }, timing_advance := 0, mobile_allocation := { len := 0, ma := ''B }, rest_octets := { presence := '00'B, ll := { compressed_irat_ho_info_ind := '0'B }, lh := omit, hl := omit, hh := omit } } } } TC_est_dchan(8)@e26f4d7b2b96: Received IMM.ASS for our RACH! TC_est_dchan(8)@e26f4d7b2b96: setverdict(pass): pass -> pass, component reason not changed TC_est_dchan(8)@e26f4d7b2b96: setverdict(pass): pass -> pass, component reason not changed TC_est_dchan(8)@e26f4d7b2b96: Final verdict of PTC: pass reason: "Received CHAN-RQD from RACH REQ" TC_est_dchan(9)@e26f4d7b2b96: setverdict(pass): none -> pass reason: "Received CHAN-RQD from RACH REQ", new component reason: "Received CHAN-RQD from RACH REQ" TC_est_dchan(9)@e26f4d7b2b96: setverdict(pass): pass -> pass, component reason not changed TC_est_dchan(9)@e26f4d7b2b96: PCH/AGCH DL RR: { header := { l2_plen := { l2_plen := 0, zero_one := '11'B }, skip_indicator := 0, rr_protocol_discriminator := 3, message_type := SYSTEM_INFORMATION_TYPE_14 (1) }, payload := { other := '2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B'O ("++++++++++++++++++++") } } TC_est_dchan(9)@e26f4d7b2b96: PCH/AGCH DL RR: { header := { l2_plen := { l2_plen := 0, zero_one := '01'B }, skip_indicator := 0, rr_protocol_discriminator := 6, message_type := IMMEDIATE_ASSIGNMENT (63) }, payload := { imm_ass := { ded_or_tbf := { spare := '0'B, tma := false, downlink := false, tbf := false }, page_mode := PAGE_MODE_NORMAL (0), chan_desc := { chan_nr := { u := { sdcch4 := { tag := '001'B, sub_chan := 3 } }, tn := 0 }, tsc := 7, h := false, arfcn := 871, maio_hsn := omit }, pkt_chan_desc := omit, req_ref := { ra := '11001111'B, t1p := 0, t3 := 5, t2 := 21 }, timing_advance := 0, mobile_allocation := { len := 0, ma := ''B }, rest_octets := { presence := '00'B, ll := { compressed_irat_ho_info_ind := '0'B }, lh := omit, hl := omit, hh := omit } } } } TC_est_dchan(9)@e26f4d7b2b96: Received IMM.ASS for our RACH! TC_est_dchan(9)@e26f4d7b2b96: setverdict(pass): pass -> pass, component reason not changed TC_est_dchan(9)@e26f4d7b2b96: setverdict(pass): pass -> pass, component reason not changed TC_est_dchan(9)@e26f4d7b2b96: Final verdict of PTC: pass reason: "Received CHAN-RQD from RACH REQ" TC_est_dchan(10)@e26f4d7b2b96: setverdict(pass): none -> pass reason: "Received CHAN-RQD from RACH REQ", new component reason: "Received CHAN-RQD from RACH REQ" TC_est_dchan(10)@e26f4d7b2b96: setverdict(pass): pass -> pass, component reason not changed TC_est_dchan(10)@e26f4d7b2b96: PCH/AGCH DL RR: { header := { l2_plen := { l2_plen := 0, zero_one := '01'B }, skip_indicator := 0, rr_protocol_discriminator := 6, message_type := IMMEDIATE_ASSIGNMENT (63) }, payload := { imm_ass := { ded_or_tbf := { spare := '0'B, tma := false, downlink := false, tbf := false }, page_mode := PAGE_MODE_NORMAL (0), chan_desc := { chan_nr := { u := { ch0 := RSL_CHAN_NR_Bm_ACCH (1) }, tn := 1 }, tsc := 7, h := false, arfcn := 871, maio_hsn := omit }, pkt_chan_desc := omit, req_ref := { ra := '11001111'B, t1p := 0, t3 := 4, t2 := 17 }, timing_advance := 0, mobile_allocation := { len := 0, ma := ''B }, rest_octets := { presence := '00'B, ll := { compressed_irat_ho_info_ind := '0'B }, lh := omit, hl := omit, hh := omit } } } } TC_est_dchan(10)@e26f4d7b2b96: Received IMM.ASS for our RACH! TC_est_dchan(10)@e26f4d7b2b96: setverdict(pass): pass -> pass, component reason not changed TC_est_dchan(10)@e26f4d7b2b96: setverdict(pass): pass -> pass, component reason not changed TC_est_dchan(10)@e26f4d7b2b96: Final verdict of PTC: pass reason: "Received CHAN-RQD from RACH REQ" TC_est_dchan(11)@e26f4d7b2b96: setverdict(pass): none -> pass reason: "Received CHAN-RQD from RACH REQ", new component reason: "Received CHAN-RQD from RACH REQ" TC_est_dchan(11)@e26f4d7b2b96: setverdict(pass): pass -> pass, component reason not changed TC_est_dchan(11)@e26f4d7b2b96: PCH/AGCH DL RR: { header := { l2_plen := { l2_plen := 0, zero_one := '01'B }, skip_indicator := 0, rr_protocol_discriminator := 6, message_type := IMMEDIATE_ASSIGNMENT (63) }, payload := { imm_ass := { ded_or_tbf := { spare := '0'B, tma := false, downlink := false, tbf := false }, page_mode := PAGE_MODE_NORMAL (0), chan_desc := { chan_nr := { u := { ch0 := RSL_CHAN_NR_Bm_ACCH (1) }, tn := 2 }, tsc := 7, h := false, arfcn := 871, maio_hsn := omit }, pkt_chan_desc := omit, req_ref := { ra := '11001111'B, t1p := 0, t3 := 4, t2 := 13 }, timing_advance := 0, mobile_allocation := { len := 0, ma := ''B }, rest_octets := { presence := '00'B, ll := { compressed_irat_ho_info_ind := '0'B }, lh := omit, hl := omit, hh := omit } } } } TC_est_dchan(11)@e26f4d7b2b96: Received IMM.ASS for our RACH! TC_est_dchan(11)@e26f4d7b2b96: setverdict(pass): pass -> pass, component reason not changed TC_est_dchan(11)@e26f4d7b2b96: setverdict(pass): pass -> pass, component reason not changed TC_est_dchan(11)@e26f4d7b2b96: Final verdict of PTC: pass reason: "Received CHAN-RQD from RACH REQ" TC_est_dchan(12)@e26f4d7b2b96: setverdict(pass): none -> pass reason: "Received CHAN-RQD from RACH REQ", new component reason: "Received CHAN-RQD from RACH REQ" TC_est_dchan(12)@e26f4d7b2b96: setverdict(pass): pass -> pass, component reason not changed TC_est_dchan(12)@e26f4d7b2b96: PCH/AGCH DL RR: { header := { l2_plen := { l2_plen := 0, zero_one := '01'B }, skip_indicator := 0, rr_protocol_discriminator := 6, message_type := IMMEDIATE_ASSIGNMENT (63) }, payload := { imm_ass := { ded_or_tbf := { spare := '0'B, tma := false, downlink := false, tbf := false }, page_mode := PAGE_MODE_NORMAL (0), chan_desc := { chan_nr := { u := { ch0 := RSL_CHAN_NR_Bm_ACCH (1) }, tn := 3 }, tsc := 7, h := false, arfcn := 871, maio_hsn := omit }, pkt_chan_desc := omit, req_ref := { ra := '11001111'B, t1p := 0, t3 := 4, t2 := 9 }, timing_advance := 0, mobile_allocation := { len := 0, ma := ''B }, rest_octets := { presence := '00'B, ll := { compressed_irat_ho_info_ind := '0'B }, lh := omit, hl := omit, hh := omit } } } } TC_est_dchan(12)@e26f4d7b2b96: Received IMM.ASS for our RACH! TC_est_dchan(12)@e26f4d7b2b96: setverdict(pass): pass -> pass, component reason not changed TC_est_dchan(12)@e26f4d7b2b96: setverdict(pass): pass -> pass, component reason not changed TC_est_dchan(12)@e26f4d7b2b96: Final verdict of PTC: pass reason: "Received CHAN-RQD from RACH REQ" TC_est_dchan(13)@e26f4d7b2b96: setverdict(pass): none -> pass reason: "Received CHAN-RQD from RACH REQ", new component reason: "Received CHAN-RQD from RACH REQ" TC_est_dchan(13)@e26f4d7b2b96: setverdict(pass): pass -> pass, component reason not changed TC_est_dchan(13)@e26f4d7b2b96: PCH/AGCH DL RR: { header := { l2_plen := { l2_plen := 0, zero_one := '11'B }, skip_indicator := 0, rr_protocol_discriminator := 3, message_type := SYSTEM_INFORMATION_TYPE_14 (1) }, payload := { other := '2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B'O ("++++++++++++++++++++") } } TC_est_dchan(13)@e26f4d7b2b96: PCH/AGCH DL RR: { header := { l2_plen := { l2_plen := 0, zero_one := '01'B }, skip_indicator := 0, rr_protocol_discriminator := 6, message_type := IMMEDIATE_ASSIGNMENT (63) }, payload := { imm_ass := { ded_or_tbf := { spare := '0'B, tma := false, downlink := false, tbf := false }, page_mode := PAGE_MODE_NORMAL (0), chan_desc := { chan_nr := { u := { lm := { tag := '0001'B, sub_chan := 0 } }, tn := 4 }, tsc := 7, h := false, arfcn := 871, maio_hsn := omit }, pkt_chan_desc := omit, req_ref := { ra := '11001111'B, t1p := 0, t3 := 5, t2 := 8 }, timing_advance := 0, mobile_allocation := { len := 0, ma := ''B }, rest_octets := { presence := '00'B, ll := { compressed_irat_ho_info_ind := '0'B }, lh := omit, hl := omit, hh := omit } } } } TC_est_dchan(13)@e26f4d7b2b96: Received IMM.ASS for our RACH! TC_est_dchan(13)@e26f4d7b2b96: setverdict(pass): pass -> pass, component reason not changed TC_est_dchan(13)@e26f4d7b2b96: setverdict(pass): pass -> pass, component reason not changed TC_est_dchan(13)@e26f4d7b2b96: Final verdict of PTC: pass reason: "Received CHAN-RQD from RACH REQ" TC_est_dchan(14)@e26f4d7b2b96: setverdict(pass): none -> pass reason: "Received CHAN-RQD from RACH REQ", new component reason: "Received CHAN-RQD from RACH REQ" TC_est_dchan(14)@e26f4d7b2b96: setverdict(pass): pass -> pass, component reason not changed TC_est_dchan(14)@e26f4d7b2b96: PCH/AGCH DL RR: { header := { l2_plen := { l2_plen := 0, zero_one := '01'B }, skip_indicator := 0, rr_protocol_discriminator := 6, message_type := IMMEDIATE_ASSIGNMENT (63) }, payload := { imm_ass := { ded_or_tbf := { spare := '0'B, tma := false, downlink := false, tbf := false }, page_mode := PAGE_MODE_NORMAL (0), chan_desc := { chan_nr := { u := { lm := { tag := '0001'B, sub_chan := 1 } }, tn := 4 }, tsc := 7, h := false, arfcn := 871, maio_hsn := omit }, pkt_chan_desc := omit, req_ref := { ra := '11001111'B, t1p := 1, t3 := 4, t2 := 4 }, timing_advance := 0, mobile_allocation := { len := 0, ma := ''B }, rest_octets := { presence := '00'B, ll := { compressed_irat_ho_info_ind := '0'B }, lh := omit, hl := omit, hh := omit } } } } TC_est_dchan(14)@e26f4d7b2b96: Received IMM.ASS for our RACH! TC_est_dchan(14)@e26f4d7b2b96: setverdict(pass): pass -> pass, component reason not changed TC_est_dchan(14)@e26f4d7b2b96: setverdict(pass): pass -> pass, component reason not changed TC_est_dchan(14)@e26f4d7b2b96: Final verdict of PTC: pass reason: "Received CHAN-RQD from RACH REQ" TC_est_dchan(15)@e26f4d7b2b96: setverdict(pass): none -> pass reason: "Received CHAN-RQD from RACH REQ", new component reason: "Received CHAN-RQD from RACH REQ" TC_est_dchan(15)@e26f4d7b2b96: setverdict(pass): pass -> pass, component reason not changed TC_est_dchan(15)@e26f4d7b2b96: PCH/AGCH DL RR: { header := { l2_plen := { l2_plen := 0, zero_one := '01'B }, skip_indicator := 0, rr_protocol_discriminator := 6, message_type := IMMEDIATE_ASSIGNMENT (63) }, payload := { imm_ass := { ded_or_tbf := { spare := '0'B, tma := false, downlink := false, tbf := false }, page_mode := PAGE_MODE_NORMAL (0), chan_desc := { chan_nr := { u := { ch0 := RSL_CHAN_NR_Bm_ACCH (1) }, tn := 4 }, tsc := 7, h := false, arfcn := 871, maio_hsn := omit }, pkt_chan_desc := omit, req_ref := { ra := '11001111'B, t1p := 1, t3 := 4, t2 := 0 }, timing_advance := 0, mobile_allocation := { len := 0, ma := ''B }, rest_octets := { presence := '00'B, ll := { compressed_irat_ho_info_ind := '0'B }, lh := omit, hl := omit, hh := omit } } } } TC_est_dchan(15)@e26f4d7b2b96: Received IMM.ASS for our RACH! TC_est_dchan(15)@e26f4d7b2b96: setverdict(pass): pass -> pass, component reason not changed TC_est_dchan(15)@e26f4d7b2b96: setverdict(pass): pass -> pass, component reason not changed TC_est_dchan(15)@e26f4d7b2b96: Final verdict of PTC: pass reason: "Received CHAN-RQD from RACH REQ" TC_est_dchan(16)@e26f4d7b2b96: setverdict(pass): none -> pass reason: "Received CHAN-RQD from RACH REQ", new component reason: "Received CHAN-RQD from RACH REQ" TC_est_dchan(16)@e26f4d7b2b96: setverdict(pass): pass -> pass, component reason not changed TC_est_dchan(16)@e26f4d7b2b96: PCH/AGCH DL RR: { header := { l2_plen := { l2_plen := 0, zero_one := '01'B }, skip_indicator := 0, rr_protocol_discriminator := 6, message_type := IMMEDIATE_ASSIGNMENT (63) }, payload := { imm_ass := { ded_or_tbf := { spare := '0'B, tma := false, downlink := false, tbf := false }, page_mode := PAGE_MODE_NORMAL (0), chan_desc := { chan_nr := { u := { lm := { tag := '0001'B, sub_chan := 0 } }, tn := 5 }, tsc := 7, h := false, arfcn := 871, maio_hsn := omit }, pkt_chan_desc := omit, req_ref := { ra := '11001111'B, t1p := 1, t3 := 4, t2 := 22 }, timing_advance := 0, mobile_allocation := { len := 0, ma := ''B }, rest_octets := { presence := '00'B, ll := { compressed_irat_ho_info_ind := '0'B }, lh := omit, hl := omit, hh := omit } } } } TC_est_dchan(16)@e26f4d7b2b96: Received IMM.ASS for our RACH! TC_est_dchan-RSL-IPA(3)@e26f4d7b2b96: IPA: Closed TC_est_dchan-RSL(4)@e26f4d7b2b96: Lost IPA connection! (conn_id=2) TC_est_dchan-RSL-IPA(3)@e26f4d7b2b96: Final verdict of PTC: none IPA-CTRL-CLI-IPA(5)@e26f4d7b2b96: IPA: Closed IPA-CTRL-CLI-IPA(5)@e26f4d7b2b96: Final verdict of PTC: none + clean_up_common + set +e + set +x
### Clean up ###
+ trap - EXIT INT TERM 0 + type clean_up + clean_up + sed -i s/classname='([^']+)'/classname='\1:hopping'/g https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-bts-test-latest/682/artifact/logs/bts-tester-hopping/junit-xml-hopping-*.log sed: can't read https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-bts-test-latest/682/artifact/logs/bts-tester-hopping/junit-xml-hopping-*.log: No such file or directory + network_clean + docker network inspect ttcn3-bts-test + grep Name + cut -d : -f2 + awk -F" NR>1{print $2} + xargs -rn1 docker kill Error: No such network: ttcn3-bts-test + network_remove + set +x Removing network ttcn3-bts-test + docker network remove ttcn3-bts-test Error: No such network: ttcn3-bts-test + rm -rf https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-bts-test-latest/682/artifact/logs/unix + fix_perms + set +x Fixing permissions + docker run --rm -v https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-bts-test-latest/ws/logs:/data -v https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-bts-test-latest/ws/_cache:/cache --name jenkins-TTCN3-centos-bts-test-latest-682-cleaner debian:bullseye chmod -R a+rX /data/ /cache/ + collect_logs + cat https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-bts-test-latest/682/artifact/logs/bts-tester-generic/junit-xml-21.log Build step 'Execute shell' marked build as failure Recording test results [Checks API] No suitable checks publisher found.
jenkins-notifications@lists.osmocom.org
