See https://jenkins.osmocom.org/jenkins/job/ttcn3-bts-test-latest/2921/display/redirect

Changes:

------------------------------------------ [...truncated 324.41 KiB...] 20260923033035616 DL1C NOTICE We were 1 FN faster than TRX, compensating (scheduler_trx.c:622) [DEBUG] clck_gen.py:102 IND CLOCK 102 20260923033035648 DL1P NOTICE PH-RTS-IND: Unable to determine actual BS_AG_BLKS_RES value as SI3 is not available yet, fallback to 1 (sysinfo.c:162) 20260923033035648 DL1P NOTICE PH-DATA-REQ: Unable to determine actual BS_AG_BLKS_RES value as SI3 is not available yet, fallback to 1 (sysinfo.c:162) 20260923033035648 DL1P NOTICE GSMTAP: Unable to determine actual BS_AG_BLKS_RES value as SI3 is not available yet, fallback to 1 (sysinfo.c:162) 20260923033035667 DL1P NOTICE PH-RTS-IND: Unable to determine actual BS_AG_BLKS_RES value as SI3 is not available yet, fallback to 1 (sysinfo.c:162) 20260923033035667 DL1P NOTICE PH-DATA-REQ: Unable to determine actual BS_AG_BLKS_RES value as SI3 is not available yet, fallback to 1 (sysinfo.c:162) 20260923033035667 DL1P NOTICE GSMTAP: Unable to determine actual BS_AG_BLKS_RES value as SI3 is not available yet, fallback to 1 (sysinfo.c:162) MTC@c7d173d6b42f: 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@c7d173d6b42f: Setting RSL_SYSTEM_INFO_3 (3): '49061B001762F224002AC90001074740E504002C2B2B2B'O MTC@c7d173d6b42f: 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@c7d173d6b42f: Setting RSL_SYSTEM_INFO_2 (2): '59061A00000000000000000000000000000000FFE50400'O MTC@c7d173d6b42f: 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@c7d173d6b42f: Setting RSL_SYSTEM_INFO_4 (4): '31061C62F224002A4740E50400012B2B2B2B2B2B2B2B2B'O 20260923033035681 DRSL INFO (bts=0,trx=0,ts=0,ss=0) Rx RSL BCCH_INFO (rsl.c:3938) 20260923033035681 DRSL INFO Rx RSL BCCH INFO (SI3, 23 bytes) (rsl.c:616) 20260923033035681 DL1C INFO (bts=0,trx=0,ts=0,ss=4) state ACTIVE -> NONE (lchan.c:297) 20260923033035681 DL1C INFO (bts=0,trx=0,ts=0,ss=4) Deactivating BCCH (scheduler.c:1070) 20260923033035681 DTRX INFO phy0.0: Enqueuing TRX control command 'CMD NOHANDOVER 0 4' (trx_if.c:249) 20260923033035681 DL1C INFO (bts=0,trx=0,ts=0,ss=4) Activating BCCH (scheduler.c:1070) 20260923033035681 DL1C INFO (bts=0,trx=0,ts=0,ss=4) state NONE -> ACTIVE (lchan.c:297) 20260923033035681 DPCU NOTICE Disabling GPRS Indicator in SI (No PCU connected) (sysinfo.c:206) 20260923033035681 DPCU NOTICE PCU socket not connected, dropping message (pcu_sock.c:1038) 20260923033035682 DPCU NOTICE Failed to send SI3 to PCU: rc=-5 (pcu_sock.c:833) 20260923033035682 DPAG INFO Paging SI update (paging.c:837) 20260923033035682 DRSL INFO Updated AGCH max queue length to 12 (bts.c:541) 20260923033035682 DTRX INFO phy0.0: Response message: 'RSP NOHANDOVER 0 0 4' (trx_if.c:674) 20260923033035682 DRSL INFO (bts=0,trx=0,ts=0,ss=0) Rx RSL BCCH_INFO (rsl.c:3938) 20260923033035682 DRSL INFO Rx RSL BCCH INFO (SI2, 23 bytes) (rsl.c:616) [DEBUG] ctrl_if_trx.py:229 (BTS@127.0.0.1:5700) Ignore CMD NOHANDOVER 20260923033035682 DPCU NOTICE PCU socket not connected, dropping message (pcu_sock.c:1038) 20260923033035682 DPCU NOTICE Failed to send SI2 to PCU: rc=-5 (pcu_sock.c:833) 20260923033035682 DPAG INFO Paging SI update (paging.c:837) [DEBUG] fake_trx.py:272 (BTS@127.0.0.1:5700) Recv FAKE_TOA cmd 20260923033035683 DRSL INFO (bts=0,trx=0,ts=0,ss=0) Rx RSL BCCH_INFO (rsl.c:3938) 20260923033035683 DRSL INFO Rx RSL BCCH INFO (SI4, 23 bytes) (rsl.c:616) 20260923033035683 DPAG INFO Paging SI update (paging.c:837) MTC@c7d173d6b42f: Warning: dec_TrxcMessage(): Data remained at the end of the stream after successful decoding: '00'O [DEBUG] fake_trx.py:291 (BTS@127.0.0.1:5700) Recv FAKE_RSSI cmd MTC@c7d173d6b42f: Warning: dec_TrxcMessage(): Data remained at the end of the stream after successful decoding: '00'O [DEBUG] fake_trx.py:316 (BTS@127.0.0.1:5700) Recv FAKE_CI cmd MTC@c7d173d6b42f: Warning: dec_TrxcMessage(): Data remained at the end of the stream after successful decoding: '00'O TC_est_dchan(6)@c7d173d6b42f: Dynamic test case error: Stand-alone receive statement failed in file L1CTL_PortType.ttcn, line 283. TC_est_dchan(6)@c7d173d6b42f: setverdict(error): none -> error TC_est_dchan(6)@c7d173d6b42f: Final verdict of PTC: error TC_est_dchan(7)@c7d173d6b42f: Dynamic test case error: Stand-alone receive statement failed in file L1CTL_PortType.ttcn, line 283. TC_est_dchan(7)@c7d173d6b42f: setverdict(error): none -> error TC_est_dchan(7)@c7d173d6b42f: Final verdict of PTC: error TC_est_dchan(8)@c7d173d6b42f: Dynamic test case error: Stand-alone receive statement failed in file L1CTL_PortType.ttcn, line 283. TC_est_dchan(8)@c7d173d6b42f: setverdict(error): none -> error TC_est_dchan(8)@c7d173d6b42f: Final verdict of PTC: error TC_est_dchan(9)@c7d173d6b42f: Dynamic test case error: Stand-alone receive statement failed in file L1CTL_PortType.ttcn, line 283. TC_est_dchan(9)@c7d173d6b42f: setverdict(error): none -> error TC_est_dchan(9)@c7d173d6b42f: Final verdict of PTC: error TC_est_dchan(10)@c7d173d6b42f: Dynamic test case error: Stand-alone receive statement failed in file L1CTL_PortType.ttcn, line 283. TC_est_dchan(10)@c7d173d6b42f: setverdict(error): none -> error TC_est_dchan(10)@c7d173d6b42f: Final verdict of PTC: error TC_est_dchan(11)@c7d173d6b42f: Dynamic test case error: Stand-alone receive statement failed in file L1CTL_PortType.ttcn, line 283. TC_est_dchan(11)@c7d173d6b42f: setverdict(error): none -> error TC_est_dchan(11)@c7d173d6b42f: Final verdict of PTC: error TC_est_dchan(12)@c7d173d6b42f: Dynamic test case error: Stand-alone receive statement failed in file L1CTL_PortType.ttcn, line 283. TC_est_dchan(12)@c7d173d6b42f: setverdict(error): none -> error TC_est_dchan(12)@c7d173d6b42f: Final verdict of PTC: error TC_est_dchan(13)@c7d173d6b42f: Dynamic test case error: Stand-alone receive statement failed in file L1CTL_PortType.ttcn, line 283. TC_est_dchan(13)@c7d173d6b42f: setverdict(error): none -> error TC_est_dchan(13)@c7d173d6b42f: Final verdict of PTC: error TC_est_dchan(14)@c7d173d6b42f: Dynamic test case error: Stand-alone receive statement failed in file L1CTL_PortType.ttcn, line 283. TC_est_dchan(14)@c7d173d6b42f: setverdict(error): none -> error TC_est_dchan(14)@c7d173d6b42f: Final verdict of PTC: error TC_est_dchan(15)@c7d173d6b42f: Dynamic test case error: Stand-alone receive statement failed in file L1CTL_PortType.ttcn, line 283. TC_est_dchan(15)@c7d173d6b42f: setverdict(error): none -> error TC_est_dchan(15)@c7d173d6b42f: Final verdict of PTC: error TC_est_dchan(16)@c7d173d6b42f: Dynamic test case error: Stand-alone receive statement failed in file L1CTL_PortType.ttcn, line 283. TC_est_dchan(16)@c7d173d6b42f: setverdict(error): none -> error TC_est_dchan(16)@c7d173d6b42f: Final verdict of PTC: error TC_est_dchan(17)@c7d173d6b42f: Dynamic test case error: Stand-alone receive statement failed in file L1CTL_PortType.ttcn, line 283. TC_est_dchan(17)@c7d173d6b42f: setverdict(error): none -> error TC_est_dchan(17)@c7d173d6b42f: Final verdict of PTC: error TC_est_dchan(18)@c7d173d6b42f: Dynamic test case error: Stand-alone receive statement failed in file L1CTL_PortType.ttcn, line 283. TC_est_dchan(18)@c7d173d6b42f: setverdict(error): none -> error TC_est_dchan(18)@c7d173d6b42f: Final verdict of PTC: error TC_est_dchan(19)@c7d173d6b42f: Dynamic test case error: Stand-alone receive statement failed in file L1CTL_PortType.ttcn, line 283. TC_est_dchan(19)@c7d173d6b42f: setverdict(error): none -> error TC_est_dchan(19)@c7d173d6b42f: Final verdict of PTC: error TC_est_dchan(20)@c7d173d6b42f: Dynamic test case error: Stand-alone receive statement failed in file L1CTL_PortType.ttcn, line 283. TC_est_dchan(20)@c7d173d6b42f: setverdict(error): none -> error TC_est_dchan(20)@c7d173d6b42f: Final verdict of PTC: error TC_est_dchan(21)@c7d173d6b42f: Dynamic test case error: Stand-alone receive statement failed in file L1CTL_PortType.ttcn, line 283. TC_est_dchan(21)@c7d173d6b42f: setverdict(error): none -> error TC_est_dchan(21)@c7d173d6b42f: Final verdict of PTC: error TC_est_dchan(22)@c7d173d6b42f: Dynamic test case error: Stand-alone receive statement failed in file L1CTL_PortType.ttcn, line 283. TC_est_dchan(22)@c7d173d6b42f: setverdict(error): none -> error TC_est_dchan(22)@c7d173d6b42f: Final verdict of PTC: error TC_est_dchan(23)@c7d173d6b42f: Dynamic test case error: Stand-alone receive statement failed in file L1CTL_PortType.ttcn, line 283. TC_est_dchan(23)@c7d173d6b42f: setverdict(error): none -> error TC_est_dchan(23)@c7d173d6b42f: Final verdict of PTC: error TC_est_dchan(24)@c7d173d6b42f: Dynamic test case error: Stand-alone receive statement failed in file L1CTL_PortType.ttcn, line 283. TC_est_dchan(24)@c7d173d6b42f: setverdict(error): none -> error TC_est_dchan(24)@c7d173d6b42f: Final verdict of PTC: error TC_est_dchan(25)@c7d173d6b42f: Dynamic test case error: Stand-alone receive statement failed in file L1CTL_PortType.ttcn, line 283. TC_est_dchan(25)@c7d173d6b42f: setverdict(error): none -> error TC_est_dchan(25)@c7d173d6b42f: Final verdict of PTC: error MTC@c7d173d6b42f: Stopping testcase execution from "BTS_Tests.ttcn":9110 IPA-CTRL-CLI-IPA(5)@c7d173d6b42f: Final verdict of PTC: none TC_est_dchan-RSL(4)@c7d173d6b42f: Final verdict of PTC: none 20260923033035889 DLINP NOTICE CLICONN(ts-0-2-rsl-trx0,r=127.0.0.2:3003<->l=127.0.0.1:34025){CONNECTED} connection closed with srv (stream_cli.c:569) 20260923033035889 DABIS ERROR E1L(0) Signalling link down (abis.c:429) 20260923033035889 DABIS ERROR E1L(0) Signalling link down (abis.c:429) 20260923033035889 DLINP NOTICE SRVCONN(ts-0-1-OML,ipa-unit-1234/0/0,r=127.0.0.1:36661<->l=127.0.0.11:3002) connection closed with client (stream_srv.c:803) 20260923033035889 DLINP NOTICE E1TS(0:1) failed reading from socket: 0 (ipaccess.c:562) 20260923033035889 DABIS ERROR E1L(0) Signalling link down (abis.c:429) 20260923033035889 DLINP NOTICE E1L(0) Signalling link down (bts_ipaccess_nanobts.c:778) 20260923033035889 DLINP NOTICE (bts=0) Dropping OML link: link down (bts_ipaccess_nanobts.c:543) 20260923033035889 DLMI ERROR (bts=0,trx=0) Lost E1 OML link (osmo_bsc_main.c:487) 20260923033035889 DABIS ERROR E1L(0) Signalling link down (abis.c:429) 20260923033035889 DABIS ERROR E1L(0) Signalling link down (abis.c:429) 20260923033035889 DOML NOTICE BTS_SHUTDOWN(bts0)[0x5649cdd8ac90]{NONE}: Shutting down BTS, exit 0, reason: Abis close (bts_shutdown_fsm.c:268) TC_est_dchan-RSL-IPA(3)@c7d173d6b42f: Final verdict of PTC: none MTC@c7d173d6b42f: Setting final verdict of the test case. MTC@c7d173d6b42f: Local verdict of MTC: none MTC@c7d173d6b42f: Local verdict of PTC TC_est_dchan-RSL-IPA(3): none (none -> none) MTC@c7d173d6b42f: Local verdict of PTC TC_est_dchan-RSL(4): none (none -> none) MTC@c7d173d6b42f: Local verdict of PTC IPA-CTRL-CLI-IPA(5): none (none -> none) MTC@c7d173d6b42f: Local verdict of PTC TC_est_dchan(6): error (none -> error) MTC@c7d173d6b42f: Local verdict of PTC TC_est_dchan(7): error (error -> error) MTC@c7d173d6b42f: Local verdict of PTC TC_est_dchan(8): error (error -> error) MTC@c7d173d6b42f: Local verdict of PTC TC_est_dchan(9): error (error -> error) MTC@c7d173d6b42f: Local verdict of PTC TC_est_dchan(10): error (error -> error) MTC@c7d173d6b42f: Local verdict of PTC TC_est_dchan(11): error (error -> error) MTC@c7d173d6b42f: Local verdict of PTC TC_est_dchan(12): error (error -> error) 20260923033035890 DL1C INFO (bts=0,trx=0) power_ramp_start(cur=0, tgt=-10000, bypass) (tx_power.c:249) 20260923033035890 DL1C INFO (bts=0,trx=0) ramping TRX board output power to -10000 mdBm. (tx_power.c:179) 20260923033035890 DTRX INFO phy0.0: Enqueuing TRX control command 'CMD SETPOWER 60' (trx_if.c:249) 20260923033035890 DL1C INFO (bts=0,trx=1) power_ramp_start(cur=0, tgt=-10000, bypass) (tx_power.c:249) 20260923033035890 DL1C INFO (bts=0,trx=1) ramping TRX board output power to -10000 mdBm. (tx_power.c:179) 20260923033035890 DTRX INFO phy0.1: Enqueuing TRX control command 'CMD SETPOWER 60' (trx_if.c:249) 20260923033035890 DL1C INFO (bts=0,trx=2) power_ramp_start(cur=0, tgt=-10000, bypass) (tx_power.c:249) 20260923033035890 DL1C INFO (bts=0,trx=2) ramping TRX board output power to -10000 mdBm. (tx_power.c:179) 20260923033035890 DTRX INFO phy0.2: Enqueuing TRX control command 'CMD SETPOWER 60' (trx_if.c:249) 20260923033035890 DL1C INFO (bts=0,trx=3) power_ramp_start(cur=0, tgt=-10000, bypass) (tx_power.c:249) 20260923033035890 DL1C INFO (bts=0,trx=3) ramping TRX board output power to -10000 mdBm. (tx_power.c:179) 20260923033035890 DTRX INFO phy0.3: Enqueuing TRX control command 'CMD SETPOWER 60' (trx_if.c:249) 20260923033035890 DOML NOTICE BTS_SHUTDOWN(bts0)[0x5649cdd8ac90]{WAIT_RAMP_DOWN_COMPL}: BTS is already being shutdown. (bts_shutdown_fsm.c:261) 20260923033035890 DABIS ERROR abis_link(abis_link)[0x5649cdd91550]{WAIT_RECONNECT}: transition to state WAIT_RECONNECT not permitted! (abis.c:267) 20260923033035890 DOML NOTICE BTS_SHUTDOWN(bts0)[0x5649cdd8ac90]{WAIT_RAMP_DOWN_COMPL}: BTS is already being shutdown. (bts_shutdown_fsm.c:261) 20260923033035890 DABIS ERROR abis_link(abis_link)[0x5649cdd91550]{WAIT_RECONNECT}: transition to state WAIT_RECONNECT not permitted! (abis.c:267) 20260923033035890 DOML NOTICE BTS_SHUTDOWN(bts0)[0x5649cdd8ac90]{WAIT_RAMP_DOWN_COMPL}: BTS is already being shutdown. (bts_shutdown_fsm.c:261) 20260923033035890 DABIS ERROR abis_link(abis_link)[0x5649cdd91550]{WAIT_RECONNECT}: transition to state WAIT_RECONNECT not permitted! (abis.c:267) 20260923033035890 DOML NOTICE BTS_SHUTDOWN(bts0)[0x5649cdd8ac90]{WAIT_RAMP_DOWN_COMPL}: BTS is already being shutdown. (bts_shutdown_fsm.c:261) 20260923033035890 DABIS ERROR abis_link(abis_link)[0x5649cdd91550]{WAIT_RECONNECT}: transition to state WAIT_RECONNECT not permitted! (abis.c:267) [DEBUG] ctrl_if_trx.py:207 (BTS@127.0.0.1:5700) Recv SETPOWER cmd [DEBUG] ctrl_if_trx.py:207 (TRX1@127.0.0.1:5700/1) Recv SETPOWER cmd [DEBUG] ctrl_if_trx.py:207 (TRX2@127.0.0.1:5700/2) Recv SETPOWER cmd 20260923033035890 DTRX INFO phy0.0: Response message: 'RSP SETPOWER 0 60' (trx_if.c:674) 20260923033035890 DTRX INFO phy0.1: Response message: 'RSP SETPOWER 0 60' (trx_if.c:674) [DEBUG] ctrl_if_trx.py:207 (TRX3@127.0.0.1:5700/3) Recv SETPOWER cmd 20260923033035890 DTRX INFO phy0.2: Response message: 'RSP SETPOWER 0 60' (trx_if.c:674) 20260923033035890 DTRX INFO phy0.3: Response message: 'RSP SETPOWER 0 60' (trx_if.c:674) 20260923033035890 DTRX INFO phy0.3: Enqueuing TRX control command 'CMD RFMUTE 1' (trx_if.c:249) 20260923033035890 DTRX INFO phy0.2: Enqueuing TRX control command 'CMD RFMUTE 1' (trx_if.c:249) 20260923033035890 DTRX INFO phy0.1: Enqueuing TRX control command 'CMD RFMUTE 1' (trx_if.c:249) MTC@c7d173d6b42f: Local verdict of PTC TC_est_dchan(13): error (error -> error) MTC@c7d173d6b42f: Local verdict of PTC TC_est_dchan(14): error (error -> error) 20260923033035890 DTRX INFO phy0.0: Enqueuing TRX control command 'CMD RFMUTE 1' (trx_if.c:249) 20260923033035890 DL1C INFO TRX_PROV(phy0-3)[0x5649cdda46a0]{OPEN_POWERON}: Received Event TRX_PROV_EV_CLOSE (l1_if.c:193) 20260923033035890 DL1C INFO TRX_PROV(phy0-3)[0x5649cdda46a0]{OPEN_POWERON}: state_chg to OPEN_WAIT_POWEROFF_CNF (trx_provision_fsm.c:650) MTC@c7d173d6b42f: Local verdict of PTC TC_est_dchan(15): error (error -> error) MTC@c7d173d6b42f: Local verdict of PTC TC_est_dchan(16): error (error -> error) MTC@c7d173d6b42f: Local verdict of PTC TC_est_dchan(17): error (error -> error) MTC@c7d173d6b42f: Local verdict of PTC TC_est_dchan(18): error (error -> error) 20260923033035891 DLMI ERROR (bts=0,trx=0) Lost E1 OSMO link (osmo_bsc_main.c:487) 20260923033035891 DRSL DEBUG (bts=0,trx=0) ACC RAMP: nm_obj=BASEBAND-TRANSCEIVER running=0 (acc.c:443) 20260923033035891 DRSL DEBUG (bts=0,trx=0) ACC RAMP: nm_obj=RADIO-CARRIER running=0 (acc.c:443) MTC@c7d173d6b42f: Local verdict of PTC TC_est_dchan(19): error (error -> error) MTC@c7d173d6b42f: Local verdict of PTC TC_est_dchan(20): error (error -> error) MTC@c7d173d6b42f: Local verdict of PTC TC_est_dchan(21): error (error -> error) MTC@c7d173d6b42f: Local verdict of PTC TC_est_dchan(22): error (error -> error) 20260923033035891 DL1C INFO TRX_PROV(phy0-2)[0x5649cdda3410]{OPEN_POWERON}: Received Event TRX_PROV_EV_CLOSE (l1_if.c:193) 20260923033035891 DL1C INFO TRX_PROV(phy0-2)[0x5649cdda3410]{OPEN_POWERON}: state_chg to OPEN_WAIT_POWEROFF_CNF (trx_provision_fsm.c:650) MTC@c7d173d6b42f: Local verdict of PTC TC_est_dchan(23): error (error -> error) MTC@c7d173d6b42f: Local verdict of PTC TC_est_dchan(24): error (error -> error) MTC@c7d173d6b42f: Local verdict of PTC TC_est_dchan(25): error (error -> error) MTC@c7d173d6b42f: Test case TC_est_dchan finished. Verdict: error MTC@c7d173d6b42f: Starting external command `https://jenkins.osmocom.org/jenkins/job/ttcn3-bts-test-latest/ws/src/osmo-ttcn3-hacks/ttcn3-tcpdump-stop.sh BTS_Tests.TC_est_dchan error'. 20260923033035892 DL1C INFO TRX_PROV(phy0-1)[0x5649cdda21f0]{OPEN_POWERON}: Received Event TRX_PROV_EV_CLOSE (l1_if.c:193) 20260923033035892 DL1C INFO TRX_PROV(phy0-1)[0x5649cdda21f0]{OPEN_POWERON}: state_chg to OPEN_WAIT_POWEROFF_CNF (trx_provision_fsm.c:650) 20260923033035892 DL1C INFO TRX_PROV(phy0-0)[0x5649cdda0f30]{OPEN_POWERON}: Received Event TRX_PROV_EV_CLOSE (l1_if.c:193) 20260923033035892 DTRX INFO phy0.0: Enqueuing TRX control command 'CMD POWEROFF' (trx_if.c:249) 20260923033035893 DL1C INFO TRX_PROV(phy0-0)[0x5649cdda0f30]{OPEN_POWERON}: state_chg to OPEN_WAIT_POWEROFF_CNF (trx_provision_fsm.c:650) [DEBUG] ctrl_if_trx.py:220 (BTS@127.0.0.1:5700) Recv RFMUTE cmd 20260923033035894 DTRX INFO phy0.0: Response message: 'RSP RFMUTE 0 1' (trx_if.c:674) 20260923033035894 DTRX INFO phy0.1: Response message: 'RSP RFMUTE 0 1' (trx_if.c:674) [DEBUG] ctrl_if_trx.py:220 (TRX1@127.0.0.1:5700/1) Recv RFMUTE cmd 20260923033035894 DTRX INFO phy0.2: Response message: 'RSP RFMUTE 0 1' (trx_if.c:674) [DEBUG] ctrl_if_trx.py:220 (TRX2@127.0.0.1:5700/2) Recv RFMUTE cmd [DEBUG] ctrl_if_trx.py:115 (BTS@127.0.0.1:5700) Recv POWEROFF cmd [INFO] ctrl_if_trx.py:117 (BTS@127.0.0.1:5700) Stopping transceiver... [INFO] transceiver.py:239 Stopping clock generator 20260923033035894 DTRX INFO phy0.0: Response message: 'RSP POWEROFF 0' (trx_if.c:674) 20260923033035895 DL1C INFO TRX_PROV(phy0-0)[0x5649cdda0f30]{OPEN_WAIT_POWEROFF_CNF}: Received Event TRX_PROV_EV_POWEROFF_CNF (trx_provision_fsm.c:60) [DEBUG] ctrl_if_trx.py:220 (TRX3@127.0.0.1:5700/3) Recv RFMUTE cmd 20260923033035895 DL1C NOTICE GSM clock stopped (scheduler_trx.c:509) 20260923033035895 DTRX NOTICE phy0.0: Closing TRXC/TRXD connections to 127.0.0.1 (trx_if.c:1244) 20260923033035895 DL1C INFO (bts=0,trx=0,ts=0,ss=4) state ACTIVE -> NONE (lchan.c:297) [WARNING] transceiver.py:250 (BTS@127.0.0.1:5700) RX TRXD message (ver=1 fn=164 tn=1 bl=148 pwr=0), but transceiver is not running => dropping... 20260923033035895 DTRX NOTICE phy0.1: Closing TRXC/TRXD connections to 127.0.0.1 (trx_if.c:1244) [WARNING] transceiver.py:250 (BTS@127.0.0.1:5700) RX TRXD message (ver=1 fn=164 tn=2 bl=148 pwr=0), but transceiver is not running => dropping... 20260923033035895 DTRX NOTICE phy0.2: Closing TRXC/TRXD connections to 127.0.0.1 (trx_if.c:1244) [WARNING] transceiver.py:250 (BTS@127.0.0.1:5700) RX TRXD message (ver=1 fn=164 tn=3 bl=148 pwr=0), but transceiver is not running => dropping... 20260923033035895 DTRX NOTICE phy0.3: Closing TRXC/TRXD connections to 127.0.0.1 (trx_if.c:1244) 20260923033035895 DL1C INFO phy0: PHY link state change connected -> shutdown (phy_link.c:58) [WARNING] transceiver.py:250 (BTS@127.0.0.1:5700) RX TRXD message (ver=1 fn=164 tn=4 bl=148 pwr=0), but transceiver is not running => dropping... [WARNING] transceiver.py:250 (BTS@127.0.0.1:5700) RX TRXD message (ver=1 fn=164 tn=5 bl=148 pwr=0), but transceiver is not running => dropping... 20260923033035895 DL1C INFO TRX_PROV(phy0-0)[0x5649cdda0f30]{OPEN_WAIT_POWEROFF_CNF}: state_chg to CLOSED (trx_provision_fsm.c:352) [WARNING] transceiver.py:250 (BTS@127.0.0.1:5700) RX TRXD message (ver=1 fn=164 tn=6 bl=148 pwr=0), but transceiver is not running => dropping... [WARNING] transceiver.py:250 (BTS@127.0.0.1:5700) RX TRXD message (ver=1 fn=164 tn=7 bl=148 pwr=0), but transceiver is not running => dropping... 20260923033035896 DL1C INFO TRX_PROV(phy0-1)[0x5649cdda21f0]{OPEN_WAIT_POWEROFF_CNF}: state_chg to CLOSED (trx_provision_fsm.c:352) 20260923033035896 DOML ERROR BTS_SHUTDOWN(bts0)[0x5649cdd8ac90]{NONE}: Event BTS_SHUTDOWN_EV_TRX_CLOSED not permitted (bts_shutdown_fsm.c:282) 20260923033035896 DL1C INFO TRX_PROV(phy0-2)[0x5649cdda3410]{OPEN_WAIT_POWEROFF_CNF}: state_chg to CLOSED (trx_provision_fsm.c:352) 20260923033035896 DOML ERROR BTS_SHUTDOWN(bts0)[0x5649cdd8ac90]{NONE}: Event BTS_SHUTDOWN_EV_TRX_CLOSED not permitted (bts_shutdown_fsm.c:282) 20260923033035896 DL1C INFO TRX_PROV(phy0-3)[0x5649cdda46a0]{OPEN_WAIT_POWEROFF_CNF}: state_chg to CLOSED (trx_provision_fsm.c:352) 20260923033035896 DOML ERROR BTS_SHUTDOWN(bts0)[0x5649cdd8ac90]{NONE}: Event BTS_SHUTDOWN_EV_TRX_CLOSED not permitted (bts_shutdown_fsm.c:282) (03:30:35) load average: 3.45, 2.90, 1.60 ------ BTS_Tests.TC_est_dchan error ------

Saving talloc report from 127.0.0.1:4241 to BTS_Tests.TC_est_dchan.talloc Waiting for packet dumper to finish... 0 (prev_count=-1, count=2759956) Waiting for packet dumper to finish... 1 (prev_count=2759956, count=2760496) MTC@c7d173d6b42f: External command `https://jenkins.osmocom.org/jenkins/job/ttcn3-bts-test-latest/ws/src/osmo-ttcn3-hacks/ttcn3-tcpdump-stop.sh BTS_Tests.TC_est_dchan error' was executed successfully (exit status: 0). MTC@c7d173d6b42f: Starting external command `https://jenkins.osmocom.org/jenkins/job/ttcn3-bts-test-latest/ws/src/osmo-ttcn3-hacks/ttcn3-tcpdump-start.sh BTS_Tests.TC_chan_act_stress'. ------ BTS_Tests.TC_chan_act_stress ------ (03:30:39) load average: 3.89, 3.00, 1.64 /usr/bin/dumpcap -q -s 1520 -n -i any -w "https://jenkins.osmocom.org/jenkins/job/ttcn3-bts-test-latest/2921/artifact/logs/generic/testsuite/BTS_Tests.TC_chan_act_stress.pcap" >https://jenkins.osmocom.org/jenkins/job/ttcn3-bts-test-latest/2921/artifact/logs/generic/testsuite/BTS_Tests.TC_chan_act_stress.pcap.stdout 2>/tmp/cmderr & Waiting for packet dumper to start... 0 [testenv][generic] Stopping podman container [testenv][generic] + ['podman', 'kill', 'testenv-bts-all-osmocom-latest-20260923-0330-895831e1-1'] testenv-bts-all-osmocom-latest-20260923-0330-895831e1-1 [testenv][generic] Stopping testsuite (3141913) [testenv][generic] feed_watchdog_loop: podman container has stopped [testenv][generic] Logs saved to: https://jenkins.osmocom.org/jenkins/job/ttcn3-bts-test-latest/2921/artifact/...  + RC=1 + [ 1 = 0 ] + uptime + grep --color=always -o load.* load average: 3.89, 3.00, 1.64 + exit 1 Build step 'Execute shell' marked build as failure Recording test results [Checks API] No suitable checks publisher found.