See https://jenkins.osmocom.org/jenkins/job/ttcn3-bts-test-asan/834/display/redirect
Changes:
------------------------------------------ [...truncated 2.39 MiB...] [1;33m20260923033223413 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.3: Response message: 'RSP SETSLOT 0 0 13' (trx_if.c:674)[0;m [1;33m20260923033223414 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.0: Response message: 'RSP SETPOWER 0 50' (trx_if.c:674)[0;m 20260923033223414 [1;32mDL1C[0;m [1;32mINFO[0;m TRX_PROV(phy0-0)[0x512000005aa0]{OPEN_POWEROFF}: Received Event TRX_PROV_EV_SETPOWER_CNF (trx_provision_fsm.c:109) 20260923033223414 [1;32mDL1C[0;m [1;32mINFO[0;m TRX_PROV(phy0-0)[0x512000005aa0]{OPEN_POWEROFF}: state_chg to OPEN_WAIT_POWERON_CNF (trx_provision_fsm.c:545) [1;33m20260923033223414 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.0: Enqueuing TRX control command 'CMD POWERON' (trx_if.c:249)[0;m [1;33m20260923033223414 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.2: Response message: 'RSP SETSLOT 0 1 13' (trx_if.c:674)[0;m [1;33m20260923033223414 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.1: Response message: 'RSP SETSLOT 0 2 13' (trx_if.c:674)[0;m [1;33m20260923033223414 [1;32mDTRXC[0;m[1;33m [1;32mINFO[0;m[1;33m (trx=bts) Power on (trx.c:129)[0;m [1;33m20260923033223414 [1;33mDTRXC[0;m[1;33m [1;33mNOTICE[0;m[1;33m TDMA clock generator started (fn=1068765, period=102) (clck_gen.c:146)[0;m [1;33m20260923033223414 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.3: Response message: 'RSP SETSLOT 0 1 3' (trx_if.c:674)[0;m [1;33m20260923033223414 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.0: Response message: 'RSP POWERON 0' (trx_if.c:674)[0;m 20260923033223414 [1;32mDL1C[0;m [1;32mINFO[0;m TRX_PROV(phy0-0)[0x512000005aa0]{OPEN_WAIT_POWERON_CNF}: Received Event TRX_PROV_EV_POWERON_CNF (trx_provision_fsm.c:57) 20260923033223414 [1;33mDL1C[0;m [1;33mNOTICE[0;m GSM clock started, waiting for clock indications (scheduler_trx.c:490) 20260923033223414 [1;32mDL1C[0;m [1;32mINFO[0;m phy0: PHY link state change connecting -> connected (phy_link.c:58) 20260923033223414 [1;32mDL1C[0;m [1;32mINFO[0;m TRX_PROV(phy0-0)[0x512000005aa0]{OPEN_WAIT_POWERON_CNF}: state_chg to OPEN_POWERON (trx_provision_fsm.c:586) [1;33m20260923033223414 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.0: Enqueuing TRX control command 'CMD SETRXGAIN 10' (trx_if.c:249)[0;m [1;33m20260923033223414 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.0: Enqueuing TRX control command 'CMD SETSLOT 0 5' (trx_if.c:249)[0;m [1;33m20260923033223414 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.0: Enqueuing TRX control command 'CMD SETSLOT 1 1' (trx_if.c:249)[0;m [1;33m20260923033223414 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.0: Enqueuing TRX control command 'CMD SETSLOT 2 1' (trx_if.c:249)[0;m [1;33m20260923033223414 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.0: Enqueuing TRX control command 'CMD SETSLOT 3 1' (trx_if.c:249)[0;m [1;33m20260923033223414 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.0: Enqueuing TRX control command 'CMD SETSLOT 4 8' (trx_if.c:249)[0;m [1;33m20260923033223414 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.0: Enqueuing TRX control command 'CMD SETSLOT 5 3' (trx_if.c:249)[0;m [1;33m20260923033223414 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.0: Enqueuing TRX control command 'CMD SETSLOT 6 7' (trx_if.c:249)[0;m [1;33m20260923033223414 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.0: Enqueuing TRX control command 'CMD SETSLOT 7 13' (trx_if.c:249)[0;m [1;33m20260923033223414 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.1: Response message: 'RSP SETSLOT 0 3 8' (trx_if.c:674)[0;m [1;33m20260923033223414 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.2: Response message: 'RSP SETSLOT 0 2 7' (trx_if.c:674)[0;m [1;33m20260923033223414 [1;32mDTRXC[0;m[1;33m [1;32mINFO[0;m[1;33m (trx=bts, chan=0) Unhandled command 'SETRXGAIN' (ctrl_cmd.c:471)[0;m [1;33m20260923033223414 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.0: Response message: 'RSP SETRXGAIN 0 10' (trx_if.c:674)[0;m [1;33m20260923033223414 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.3: Response message: 'RSP SETSLOT 0 2 3' (trx_if.c:674)[0;m [1;33m20260923033223415 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.1: Response message: 'RSP SETSLOT 0 4 1' (trx_if.c:674)[0;m [1;33m20260923033223415 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.0: Response message: 'RSP SETSLOT 0 0 5' (trx_if.c:674)[0;m [1;33m20260923033223415 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.2: Response message: 'RSP SETSLOT 0 3 13' (trx_if.c:674)[0;m [1;33m20260923033223415 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.1: Response message: 'RSP SETSLOT 0 5 7' (trx_if.c:674)[0;m [1;33m20260923033223415 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.3: Response message: 'RSP SETSLOT 0 3 7' (trx_if.c:674)[0;m [1;33m20260923033223415 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.0: Response message: 'RSP SETSLOT 0 1 1' (trx_if.c:674)[0;m [1;33m20260923033223415 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.2: Response message: 'RSP SETSLOT 0 4 7' (trx_if.c:674)[0;m [1;33m20260923033223415 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.1: Response message: 'RSP SETSLOT 0 6 13' (trx_if.c:674)[0;m [1;33m20260923033223415 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.3: Response message: 'RSP SETSLOT 0 4 13' (trx_if.c:674)[0;m TC_rach_load_idle_thresh0-RSL-IPA(248)@03d96655f345: Established a new IPA connection (conn_id=2) [1;33m20260923033223415 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.0: Response message: 'RSP SETSLOT 0 2 1' (trx_if.c:674)[0;m [1;33m20260923033223415 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.2: Response message: 'RSP SETSLOT 0 5 1' (trx_if.c:674)[0;m [1;33m20260923033223415 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.1: Response message: 'RSP SETSLOT 0 7 13' (trx_if.c:674)[0;m [1;33m20260923033223415 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.3: Response message: 'RSP SETSLOT 0 5 1' (trx_if.c:674)[0;m [1;33m20260923033223415 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.0: Response message: 'RSP SETSLOT 0 3 1' (trx_if.c:674)[0;m [1;33m20260923033223415 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.2: Response message: 'RSP SETSLOT 0 6 13' (trx_if.c:674)[0;m [1;33m20260923033223415 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.3: Response message: 'RSP SETSLOT 0 6 13' (trx_if.c:674)[0;m [1;33m20260923033223415 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.0: Response message: 'RSP SETSLOT 0 4 8' (trx_if.c:674)[0;m [1;33m20260923033223415 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.2: Response message: 'RSP SETSLOT 0 7 13' (trx_if.c:674)[0;m [1;33m20260923033223415 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.3: Response message: 'RSP SETSLOT 0 7 7' (trx_if.c:674)[0;m [1;33m20260923033223415 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.0: Response message: 'RSP SETSLOT 0 5 3' (trx_if.c:674)[0;m [1;33m20260923033223415 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.0: Response message: 'RSP SETSLOT 0 6 7' (trx_if.c:674)[0;m [1;33m20260923033223415 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.0: Response message: 'RSP SETSLOT 0 7 13' (trx_if.c:674)[0;m TC_rach_load_idle_thresh0-RSL-IPA(248)@03d96655f345: 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) } } } } [38;5;23m20260923033223416 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m received ID_GET for unit ID 1234/0/0 (ipaccess.c:901)[0;m [1;35m20260923033223416 [1;32mDRSL[0;m[1;35m [1;32mINFO[0;m[1;35m (bts=0,trx=0) RSL link up (bts_trx.c:234)[0;m 20260923033223416 [1;32mDL1C[0;m [1;32mINFO[0;m (bts=0,trx=0) power_ramp_start(cur=-10000, tgt=30000) (tx_power.c:249) TC_rach_load_idle_thresh0-RSL(249)@03d96655f345: A new IPA/RSL connection has been established (conn_id=2), waiting for IDENTITY RESPONSE... TC_rach_load_idle_thresh0-RSL-IPA(248)@03d96655f345: Established a new IPA connection (conn_id=3) [1;35m20260923033223416 [1;32mDRSL[0;m[1;35m [1;32mINFO[0;m[1;35m Tx RSL RF RESource INDication (rsl.c:519)[0;m TC_rach_load_idle_thresh0-RSL-IPA(248)@03d96655f345: 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) } } } } [1;35m20260923033223416 [1;34mDRSL[0;m[1;35m [1;34mDEBUG[0;m[1;35m (bts=0,trx=0) ACC RAMP: nm_obj=RADIO-CARRIER running=1 (acc.c:443)[0;m [1;35m20260923033223416 [1;34mDRSL[0;m[1;35m [1;34mDEBUG[0;m[1;35m (bts=0,trx=0) ACC RAMP: ignoring state change because TRX is not usable (acc.c:453)[0;m [1;35m20260923033223416 [1;34mDRSL[0;m[1;35m [1;34mDEBUG[0;m[1;35m (bts=0,trx=0) ACC RAMP: nm_obj=BASEBAND-TRANSCEIVER running=1 (acc.c:443)[0;m TC_rach_load_idle_thresh0-RSL(249)@03d96655f345: A new IPA/RSL connection has been established (conn_id=3), waiting for IDENTITY RESPONSE... [38;5;23m20260923033223417 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m received ID_GET for unit ID 1234/0/1 (ipaccess.c:901)[0;m [1;35m20260923033223417 [1;32mDRSL[0;m[1;35m [1;32mINFO[0;m[1;35m (bts=0,trx=1) RSL link up (bts_trx.c:234)[0;m 20260923033223417 [1;32mDL1C[0;m [1;32mINFO[0;m (bts=0,trx=1) power_ramp_start(cur=-10000, tgt=30000) (tx_power.c:249) [1;35m20260923033223417 [1;32mDRSL[0;m[1;35m [1;32mINFO[0;m[1;35m Tx RSL RF RESource INDication (rsl.c:519)[0;m TC_rach_load_idle_thresh0-RSL-IPA(248)@03d96655f345: Established a new IPA connection (conn_id=4) TC_rach_load_idle_thresh0-RSL-IPA(248)@03d96655f345: 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_rach_load_idle_thresh0-RSL(249)@03d96655f345: A new IPA/RSL connection has been established (conn_id=4), waiting for IDENTITY RESPONSE... [38;5;23m20260923033223418 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m received ID_GET for unit ID 1234/0/2 (ipaccess.c:901)[0;m [1;35m20260923033223418 [1;32mDRSL[0;m[1;35m [1;32mINFO[0;m[1;35m (bts=0,trx=2) RSL link up (bts_trx.c:234)[0;m 20260923033223418 [1;32mDL1C[0;m [1;32mINFO[0;m (bts=0,trx=2) power_ramp_start(cur=-10000, tgt=30000) (tx_power.c:249) [1;35m20260923033223418 [1;32mDRSL[0;m[1;35m [1;32mINFO[0;m[1;35m Tx RSL RF RESource INDication (rsl.c:519)[0;m TC_rach_load_idle_thresh0-RSL-IPA(248)@03d96655f345: CCM Rx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 10, tag := IPAC_IDTAG_UNITNAME (1), data := '6F736D6F2D62747300'O }, { len := 10, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3000'O } } } } TC_rach_load_idle_thresh0-RSL-IPA(248)@03d96655f345: IPA ID RESP: { { len := 10, tag := IPAC_IDTAG_UNITNAME (1), data := '6F736D6F2D62747300'O }, { len := 10, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3000'O } } TC_rach_load_idle_thresh0-RSL-IPA(248)@03d96655f345: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } TC_rach_load_idle_thresh0-RSL-IPA(248)@03d96655f345: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } TC_rach_load_idle_thresh0-RSL(249)@03d96655f345: Got IDENTITY RESPONSE (conn_id=2): { { len := 10, tag := IPAC_IDTAG_UNITNAME (1), data := '6F736D6F2D62747300'O }, { len := 10, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3000'O } } TC_rach_load_idle_thresh0-RSL(249)@03d96655f345: Warning: dec_IpaUnitId(): Data remained at the end of the stream after successful decoding: '00'O TC_rach_load_idle_thresh0-RSL(249)@03d96655f345: Mapped TRX#0 to TCP/IP conn_id=2 MTC@03d96655f345: 1/4 transceiver(s) connected TC_rach_load_idle_thresh0-RSL-IPA(248)@03d96655f345: Established a new IPA connection (conn_id=5) TC_rach_load_idle_thresh0-RSL-IPA(248)@03d96655f345: 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) } } } } [38;5;23m20260923033223424 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m received ID_GET for unit ID 1234/0/3 (ipaccess.c:901)[0;m [1;35m20260923033223424 [1;32mDRSL[0;m[1;35m [1;32mINFO[0;m[1;35m (bts=0,trx=3) RSL link up (bts_trx.c:234)[0;m 20260923033223424 [1;32mDL1C[0;m [1;32mINFO[0;m (bts=0,trx=3) power_ramp_start(cur=-10000, tgt=30000) (tx_power.c:249) [1;35m20260923033223425 [1;32mDRSL[0;m[1;35m [1;32mINFO[0;m[1;35m Tx RSL RF RESource INDication (rsl.c:519)[0;m TC_rach_load_idle_thresh0-RSL-IPA(248)@03d96655f345: CCM Rx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 10, tag := IPAC_IDTAG_UNITNAME (1), data := '6F736D6F2D62747300'O }, { len := 10, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3100'O } } } } TC_rach_load_idle_thresh0-RSL-IPA(248)@03d96655f345: IPA ID RESP: { { len := 10, tag := IPAC_IDTAG_UNITNAME (1), data := '6F736D6F2D62747300'O }, { len := 10, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3100'O } } TC_rach_load_idle_thresh0-RSL-IPA(248)@03d96655f345: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } TC_rach_load_idle_thresh0-RSL(249)@03d96655f345: A new IPA/RSL connection has been established (conn_id=5), waiting for IDENTITY RESPONSE... MTC@03d96655f345: 1/4 RF Resource Indication(s) received TC_rach_load_idle_thresh0-RSL-IPA(248)@03d96655f345: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } TC_rach_load_idle_thresh0-RSL(249)@03d96655f345: Got IDENTITY RESPONSE (conn_id=3): { { len := 10, tag := IPAC_IDTAG_UNITNAME (1), data := '6F736D6F2D62747300'O }, { len := 10, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3100'O } } TC_rach_load_idle_thresh0-RSL-IPA(248)@03d96655f345: CCM Rx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 10, tag := IPAC_IDTAG_UNITNAME (1), data := '6F736D6F2D62747300'O }, { len := 10, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3200'O } } } } TC_rach_load_idle_thresh0-RSL(249)@03d96655f345: Warning: dec_IpaUnitId(): Data remained at the end of the stream after successful decoding: '00'O TC_rach_load_idle_thresh0-RSL-IPA(248)@03d96655f345: IPA ID RESP: { { len := 10, tag := IPAC_IDTAG_UNITNAME (1), data := '6F736D6F2D62747300'O }, { len := 10, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3200'O } } TC_rach_load_idle_thresh0-RSL(249)@03d96655f345: Mapped TRX#1 to TCP/IP conn_id=3 TC_rach_load_idle_thresh0-RSL-IPA(248)@03d96655f345: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } MTC@03d96655f345: 2/4 transceiver(s) connected TC_rach_load_idle_thresh0-RSL-IPA(248)@03d96655f345: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } TC_rach_load_idle_thresh0-RSL(249)@03d96655f345: Got IDENTITY RESPONSE (conn_id=4): { { len := 10, tag := IPAC_IDTAG_UNITNAME (1), data := '6F736D6F2D62747300'O }, { len := 10, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3200'O } } MTC@03d96655f345: 2/4 RF Resource Indication(s) received TC_rach_load_idle_thresh0-RSL-IPA(248)@03d96655f345: CCM Rx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 10, tag := IPAC_IDTAG_UNITNAME (1), data := '6F736D6F2D62747300'O }, { len := 10, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3300'O } } } } TC_rach_load_idle_thresh0-RSL-IPA(248)@03d96655f345: IPA ID RESP: { { len := 10, tag := IPAC_IDTAG_UNITNAME (1), data := '6F736D6F2D62747300'O }, { len := 10, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3300'O } } TC_rach_load_idle_thresh0-RSL(249)@03d96655f345: Warning: dec_IpaUnitId(): Data remained at the end of the stream after successful decoding: '00'O TC_rach_load_idle_thresh0-RSL(249)@03d96655f345: Mapped TRX#2 to TCP/IP conn_id=4 TC_rach_load_idle_thresh0-RSL-IPA(248)@03d96655f345: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } MTC@03d96655f345: 3/4 transceiver(s) connected TC_rach_load_idle_thresh0-RSL-IPA(248)@03d96655f345: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } TC_rach_load_idle_thresh0-RSL(249)@03d96655f345: Got IDENTITY RESPONSE (conn_id=5): { { len := 10, tag := IPAC_IDTAG_UNITNAME (1), data := '6F736D6F2D62747300'O }, { len := 10, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3300'O } } MTC@03d96655f345: 3/4 RF Resource Indication(s) received TC_rach_load_idle_thresh0-RSL(249)@03d96655f345: Warning: dec_IpaUnitId(): Data remained at the end of the stream after successful decoding: '00'O TC_rach_load_idle_thresh0-RSL(249)@03d96655f345: Mapped TRX#3 to TCP/IP conn_id=5 MTC@03d96655f345: 4/4 transceiver(s) connected MTC@03d96655f345: 4/4 RF Resource Indication(s) received [1;33m20260923033223848 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.0: Clock indication: fn=1068858 (trx_if.c:103)[0;m 20260923033223848 [1;33mDL1C[0;m [1;33mNOTICE[0;m GSM clock started: first CLCK.ind fn=1068858 (scheduler_trx.c:554) 20260923033223854 [1;33mDL1P[0;m [1;33mNOTICE[0;m PH-RTS-IND: Unable to determine actual BS_AG_BLKS_RES value as SI3 is not available yet, fallback to 1 (sysinfo.c:162) 20260923033223854 [1;33mDL1P[0;m [1;33mNOTICE[0;m PH-DATA-REQ: Unable to determine actual BS_AG_BLKS_RES value as SI3 is not available yet, fallback to 1 (sysinfo.c:162) 20260923033223854 [1;33mDL1P[0;m [1;33mNOTICE[0;m GSMTAP: Unable to determine actual BS_AG_BLKS_RES value as SI3 is not available yet, fallback to 1 (sysinfo.c:162) 20260923033223881 [1;33mDL1P[0;m [1;33mNOTICE[0;m PH-RTS-IND: Unable to determine actual BS_AG_BLKS_RES value as SI3 is not available yet, fallback to 1 (sysinfo.c:162) 20260923033223881 [1;33mDL1P[0;m [1;33mNOTICE[0;m PH-DATA-REQ: Unable to determine actual BS_AG_BLKS_RES value as SI3 is not available yet, fallback to 1 (sysinfo.c:162) 20260923033223881 [1;33mDL1P[0;m [1;33mNOTICE[0;m GSMTAP: Unable to determine actual BS_AG_BLKS_RES value as SI3 is not available yet, fallback to 1 (sysinfo.c:162) 20260923033223900 [1;33mDL1P[0;m [1;33mNOTICE[0;m PH-RTS-IND: Unable to determine actual BS_AG_BLKS_RES value as SI3 is not available yet, fallback to 1 (sysinfo.c:162) 20260923033223900 [1;33mDL1P[0;m [1;33mNOTICE[0;m PH-DATA-REQ: Unable to determine actual BS_AG_BLKS_RES value as SI3 is not available yet, fallback to 1 (sysinfo.c:162) 20260923033223900 [1;33mDL1P[0;m [1;33mNOTICE[0;m GSMTAP: Unable to determine actual BS_AG_BLKS_RES value as SI3 is not available yet, fallback to 1 (sysinfo.c:162) MTC@03d96655f345: 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@03d96655f345: Setting RSL_SYSTEM_INFO_3 (3): '49061B001762F224002AC90001074740E504002C2B2B2B'O MTC@03d96655f345: 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@03d96655f345: Setting RSL_SYSTEM_INFO_2 (2): '59061A00000000000000000000000000000000FFE50400'O MTC@03d96655f345: 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@03d96655f345: Setting RSL_SYSTEM_INFO_4 (4): '31061C62F224002A4740E50400012B2B2B2B2B2B2B2B2B'O [1;35m20260923033223966 [1;32mDRSL[0;m[1;35m [1;32mINFO[0;m[1;35m (bts=0,trx=0,ts=0,ss=0) Rx RSL BCCH_INFO (rsl.c:3938)[0;m [1;35m20260923033223966 [1;32mDRSL[0;m[1;35m [1;32mINFO[0;m[1;35m Rx RSL BCCH INFO (SI3, 23 bytes) (rsl.c:616)[0;m 20260923033223966 [1;32mDL1C[0;m [1;32mINFO[0;m (bts=0,trx=0,ts=0,ss=4) state ACTIVE -> NONE (lchan.c:297) 20260923033223967 [1;32mDL1C[0;m [1;32mINFO[0;m (bts=0,trx=0,ts=0,ss=4) Deactivating BCCH (scheduler.c:1070) [1;33m20260923033223967 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.0: Enqueuing TRX control command 'CMD NOHANDOVER 0 4' (trx_if.c:249)[0;m 20260923033223967 [1;32mDL1C[0;m [1;32mINFO[0;m (bts=0,trx=0,ts=0,ss=4) Activating BCCH (scheduler.c:1070) 20260923033223967 [1;32mDL1C[0;m [1;32mINFO[0;m (bts=0,trx=0,ts=0,ss=4) state NONE -> ACTIVE (lchan.c:297) 20260923033223967 [1;33mDPCU[0;m [1;33mNOTICE[0;m PCU socket not connected, dropping message (pcu_sock.c:1038) 20260923033223967 [1;33mDPCU[0;m [1;33mNOTICE[0;m Failed to send SI3 to PCU: rc=-5 (pcu_sock.c:833) [1;38m20260923033223967 [1;32mDPAG[0;m[1;38m [1;32mINFO[0;m[1;38m Paging SI update (paging.c:837)[0;m [1;33m20260923033223967 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.0: Response message: 'RSP NOHANDOVER 0 0 4' (trx_if.c:674)[0;m [1;33m20260923033223967 [1;32mDTRXC[0;m[1;33m [1;32mINFO[0;m[1;33m (trx=bts, chan=0) Unhandled command 'NOHANDOVER' (ctrl_cmd.c:471)[0;m [1;35m20260923033223968 [1;32mDRSL[0;m[1;35m [1;32mINFO[0;m[1;35m (bts=0,trx=0,ts=0,ss=0) Rx RSL BCCH_INFO (rsl.c:3938)[0;m [1;35m20260923033223968 [1;32mDRSL[0;m[1;35m [1;32mINFO[0;m[1;35m Rx RSL BCCH INFO (SI2, 23 bytes) (rsl.c:616)[0;m 20260923033223968 [1;33mDPCU[0;m [1;33mNOTICE[0;m PCU socket not connected, dropping message (pcu_sock.c:1038) 20260923033223968 [1;33mDPCU[0;m [1;33mNOTICE[0;m Failed to send SI2 to PCU: rc=-5 (pcu_sock.c:833) [1;38m20260923033223968 [1;32mDPAG[0;m[1;38m [1;32mINFO[0;m[1;38m Paging SI update (paging.c:837)[0;m [1;35m20260923033223969 [1;32mDRSL[0;m[1;35m [1;32mINFO[0;m[1;35m (bts=0,trx=0,ts=0,ss=0) Rx RSL BCCH_INFO (rsl.c:3938)[0;m [1;35m20260923033223969 [1;32mDRSL[0;m[1;35m [1;32mINFO[0;m[1;35m Rx RSL BCCH INFO (SI4, 23 bytes) (rsl.c:616)[0;m [1;38m20260923033223969 [1;32mDPAG[0;m[1;38m [1;32mINFO[0;m[1;38m Paging SI update (paging.c:837)[0;m [1;35m20260923033224093 [1;32mDRSL[0;m[1;35m [1;32mINFO[0;m[1;35m Tx RSL RF RESource INDication (rsl.c:519)[0;m [1;35m20260923033224093 [1;32mDRSL[0;m[1;35m [1;32mINFO[0;m[1;35m Tx RSL RF RESource INDication (rsl.c:519)[0;m [1;35m20260923033224094 [1;32mDRSL[0;m[1;35m [1;32mINFO[0;m[1;35m Tx RSL RF RESource INDication (rsl.c:519)[0;m [1;35m20260923033224094 [1;32mDRSL[0;m[1;35m [1;32mINFO[0;m[1;35m Tx RSL RF RESource INDication (rsl.c:519)[0;m [1;33m20260923033224319 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.0: Clock indication: fn=1068960 (trx_if.c:103)[0;m 20260923033224319 [1;32mDL1C[0;m [1;32mINFO[0;m TRX Clock Ind: elapsed_us= 470454, elapsed_fn=102, error_us= -276 (scheduler_trx.c:578) 20260923033224319 [1;32mDL1C[0;m [1;32mINFO[0;m GSM clock jitter: 526us (elapsed_fn=1) (scheduler_trx.c:613) 20260923033224319 [1;33mDL1C[0;m [1;33mNOTICE[0;m We were 1 FN slower than TRX, compensated (scheduler_trx.c:643) 20260923033224417 [1;32mDL1C[0;m [1;32mINFO[0;m (bts=0,trx=0) ramping TRX board output power to 0 mdBm. (tx_power.c:179) [1;33m20260923033224417 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.0: Enqueuing TRX control command 'CMD SETPOWER 50' (trx_if.c:249)[0;m 20260923033224417 [1;32mDL1C[0;m [1;32mINFO[0;m (bts=0,trx=1) ramping TRX board output power to 0 mdBm. (tx_power.c:179) [1;33m20260923033224417 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.1: Enqueuing TRX control command 'CMD SETPOWER 50' (trx_if.c:249)[0;m [1;33m20260923033224417 [1;32mDTRXC[0;m[1;33m [1;32mINFO[0;m[1;33m (trx=bts, chan=0) Tx power attenuation set to 50 dB (ctrl_cmd.c:148)[0;m [1;33m20260923033224417 [1;32mDTRXC[0;m[1;33m [1;32mINFO[0;m[1;33m (trx=bts, chan=1) Tx power attenuation set to 50 dB (ctrl_cmd.c:148)[0;m [1;33m20260923033224417 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.0: Response message: 'RSP SETPOWER 0 50' (trx_if.c:674)[0;m [1;33m20260923033224417 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.1: Response message: 'RSP SETPOWER 0 50' (trx_if.c:674)[0;m 20260923033224419 [1;32mDL1C[0;m [1;32mINFO[0;m (bts=0,trx=2) ramping TRX board output power to 0 mdBm. (tx_power.c:179) [1;33m20260923033224419 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.2: Enqueuing TRX control command 'CMD SETPOWER 50' (trx_if.c:249)[0;m [1;33m20260923033224419 [1;32mDTRXC[0;m[1;33m [1;32mINFO[0;m[1;33m (trx=bts, chan=2) Tx power attenuation set to 50 dB (ctrl_cmd.c:148)[0;m [1;33m20260923033224419 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.2: Response message: 'RSP SETPOWER 0 50' (trx_if.c:674)[0;m 20260923033224425 [1;32mDL1C[0;m [1;32mINFO[0;m (bts=0,trx=3) ramping TRX board output power to 0 mdBm. (tx_power.c:179) [1;33m20260923033224425 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.3: Enqueuing TRX control command 'CMD SETPOWER 50' (trx_if.c:249)[0;m [1;33m20260923033224426 [1;32mDTRXC[0;m[1;33m [1;32mINFO[0;m[1;33m (trx=bts, chan=3) Tx power attenuation set to 50 dB (ctrl_cmd.c:148)[0;m [1;33m20260923033224426 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.3: Response message: 'RSP SETPOWER 0 50' (trx_if.c:674)[0;m [1;33m20260923033224790 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.0: Clock indication: fn=1069062 (trx_if.c:103)[0;m 20260923033224790 [1;32mDL1C[0;m [1;32mINFO[0;m TRX Clock Ind: elapsed_us= 470804, elapsed_fn=102, error_us= +74 (scheduler_trx.c:578) 20260923033224790 [1;32mDL1C[0;m [1;32mINFO[0;m GSM clock jitter: -4127us (elapsed_fn=0) (scheduler_trx.c:613) [38;5;23m20260923033224824 [1;31mDLINP[0;m[38;5;23m [1;31mERROR[0;m[38;5;23m CLICONN(asp-clnt-msc-0,){CONNECTING} connect failed (-111) (stream_cli.c:446)[0;m [38;5;45m20260923033224833 [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=12: no route! (sccp_scrc.c:177)[0;m [38;5;45m20260923033224833 [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 20260923033224833 [1;34mDMSC[0;m [1;34mDEBUG[0;m N-NOTICE.ind(00 04 30 04 01 20 ) (osmo_bsc_sigtran.c:348) 20260923033224833 [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) [1;33m20260923033225260 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.0: Clock indication: fn=1069164 (trx_if.c:103)[0;m 20260923033225261 [1;32mDL1C[0;m [1;32mINFO[0;m TRX Clock Ind: elapsed_us= 470805, elapsed_fn=102, error_us= +75 (scheduler_trx.c:578) 20260923033225261 [1;32mDL1C[0;m [1;32mINFO[0;m GSM clock jitter: -4034us (elapsed_fn=0) (scheduler_trx.c:613) 20260923033225418 [1;32mDL1C[0;m [1;32mINFO[0;m (bts=0,trx=1) ramping TRX board output power to 8000 mdBm. (tx_power.c:179) [1;33m20260923033225418 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.1: Enqueuing TRX control command 'CMD SETPOWER 42' (trx_if.c:249)[0;m 20260923033225418 [1;32mDL1C[0;m [1;32mINFO[0;m (bts=0,trx=0) ramping TRX board output power to 8000 mdBm. (tx_power.c:179) [1;33m20260923033225418 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.0: Enqueuing TRX control command 'CMD SETPOWER 42' (trx_if.c:249)[0;m [1;33m20260923033225419 [1;32mDTRXC[0;m[1;33m [1;32mINFO[0;m[1;33m (trx=bts, chan=0) Tx power attenuation set to 42 dB (ctrl_cmd.c:148)[0;m [1;33m20260923033225419 [1;32mDTRXC[0;m[1;33m [1;32mINFO[0;m[1;33m (trx=bts, chan=1) Tx power attenuation set to 42 dB (ctrl_cmd.c:148)[0;m [1;33m20260923033225419 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.0: Response message: 'RSP SETPOWER 0 42' (trx_if.c:674)[0;m [1;33m20260923033225419 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.1: Response message: 'RSP SETPOWER 0 42' (trx_if.c:674)[0;m 20260923033225420 [1;32mDL1C[0;m [1;32mINFO[0;m (bts=0,trx=2) ramping TRX board output power to 8000 mdBm. (tx_power.c:179) [1;33m20260923033225420 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.2: Enqueuing TRX control command 'CMD SETPOWER 42' (trx_if.c:249)[0;m [1;33m20260923033225420 [1;32mDTRXC[0;m[1;33m [1;32mINFO[0;m[1;33m (trx=bts, chan=2) Tx power attenuation set to 42 dB (ctrl_cmd.c:148)[0;m [1;33m20260923033225421 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.2: Response message: 'RSP SETPOWER 0 42' (trx_if.c:674)[0;m MTC@03d96655f345: setverdict(pass): none -> pass 20260923033225427 [1;32mDL1C[0;m [1;32mINFO[0;m (bts=0,trx=3) ramping TRX board output power to 8000 mdBm. (tx_power.c:179) [1;33m20260923033225427 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.3: Enqueuing TRX control command 'CMD SETPOWER 42' (trx_if.c:249)[0;m [1;33m20260923033225428 [1;32mDTRXC[0;m[1;33m [1;32mINFO[0;m[1;33m (trx=bts, chan=3) Tx power attenuation set to 42 dB (ctrl_cmd.c:148)[0;m [1;33m20260923033225428 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.3: Response message: 'RSP SETPOWER 0 42' (trx_if.c:674)[0;m [1;33m20260923033225731 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.0: Clock indication: fn=1069266 (trx_if.c:103)[0;m 20260923033225731 [1;32mDL1C[0;m [1;32mINFO[0;m TRX Clock Ind: elapsed_us= 470730, elapsed_fn=102, error_us= +0 (scheduler_trx.c:578) 20260923033225731 [1;32mDL1C[0;m [1;32mINFO[0;m GSM clock jitter: -4147us (elapsed_fn=0) (scheduler_trx.c:613) [1;33m20260923033226202 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.0: Clock indication: fn=1069368 (trx_if.c:103)[0;m 20260923033226202 [1;32mDL1C[0;m [1;32mINFO[0;m TRX Clock Ind: elapsed_us= 470760, elapsed_fn=102, error_us= +30 (scheduler_trx.c:578) 20260923033226202 [1;32mDL1C[0;m [1;32mINFO[0;m GSM clock jitter: -4106us (elapsed_fn=0) (scheduler_trx.c:613) 20260923033226419 [1;32mDL1C[0;m [1;32mINFO[0;m (bts=0,trx=1) ramping TRX board output power to 16000 mdBm. (tx_power.c:179) [1;33m20260923033226419 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.1: Enqueuing TRX control command 'CMD SETPOWER 34' (trx_if.c:249)[0;m 20260923033226419 [1;32mDL1C[0;m [1;32mINFO[0;m (bts=0,trx=0) ramping TRX board output power to 16000 mdBm. (tx_power.c:179) [1;33m20260923033226419 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.0: Enqueuing TRX control command 'CMD SETPOWER 34' (trx_if.c:249)[0;m [1;33m20260923033226420 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.0: Response message: 'RSP SETPOWER 0 34' (trx_if.c:674)[0;m [1;33m20260923033226420 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.1: Response message: 'RSP SETPOWER 0 34' (trx_if.c:674)[0;m [1;33m20260923033226420 [1;32mDTRXC[0;m[1;33m [1;32mINFO[0;m[1;33m (trx=bts, chan=0) Tx power attenuation set to 34 dB (ctrl_cmd.c:148)[0;m [1;33m20260923033226420 [1;32mDTRXC[0;m[1;33m [1;32mINFO[0;m[1;33m (trx=bts, chan=1) Tx power attenuation set to 34 dB (ctrl_cmd.c:148)[0;m 20260923033226421 [1;32mDL1C[0;m [1;32mINFO[0;m (bts=0,trx=2) ramping TRX board output power to 16000 mdBm. (tx_power.c:179) [1;33m20260923033226421 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.2: Enqueuing TRX control command 'CMD SETPOWER 34' (trx_if.c:249)[0;m [1;33m20260923033226421 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.2: Response message: 'RSP SETPOWER 0 34' (trx_if.c:674)[0;m [1;33m20260923033226421 [1;32mDTRXC[0;m[1;33m [1;32mINFO[0;m[1;33m (trx=bts, chan=2) Tx power attenuation set to 34 dB (ctrl_cmd.c:148)[0;m MTC@03d96655f345: setverdict(pass): pass -> pass, component reason not changed 20260923033226428 [1;32mDL1C[0;m [1;32mINFO[0;m (bts=0,trx=3) ramping TRX board output power to 16000 mdBm. (tx_power.c:179) [1;33m20260923033226428 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.3: Enqueuing TRX control command 'CMD SETPOWER 34' (trx_if.c:249)[0;m [1;33m20260923033226429 [1;32mDTRXC[0;m[1;33m [1;32mINFO[0;m[1;33m (trx=bts, chan=3) Tx power attenuation set to 34 dB (ctrl_cmd.c:148)[0;m [1;33m20260923033226429 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.3: Response message: 'RSP SETPOWER 0 34' (trx_if.c:674)[0;m [1;33m20260923033226673 [1;32mDTRX[0;m[1;33m [1;32mINFO[0;m[1;33m phy0.0: Clock indication: fn=1069470 (trx_if.c:103)[0;m 20260923033226673 [1;32mDL1C[0;m [1;32mINFO[0;m TRX Clock Ind: elapsed_us= 470634, elapsed_fn=102, error_us= -96 (scheduler_trx.c:578) 20260923033226673 [1;32mDL1C[0;m [1;32mINFO[0;m GSM clock jitter: -4141us (elapsed_fn=0) (scheduler_trx.c:613) [1;91m[testenv][generic] Timeout in wait_until_test_stopped()[0m [1;34m[testenv][generic] Stopping podman container[0m [0;94m[testenv][generic] + ['podman', 'kill', 'testenv-bts-all-osmocom-nightly-asan-20260923-0330-b34200d7-1'][0m testenv-bts-all-osmocom-nightly-asan-20260923-0330-b34200d7-1 [1;34m[testenv][generic] Stopping testsuite (3741460)[0m [0;94m[testenv][generic] feed_watchdog_loop: podman container has stopped[0m [1;34m[testenv][generic] Logs saved to: https://jenkins.osmocom.org/jenkins/job/ttcn3-bts-test-asan/834/artifact/log... [0m + RC=1 + [ 1 = 0 ] + uptime + grep --color=always -o load.* [01;31m[Kload average: 1.41, 1.96, 1.40[m[K + exit 1 Build step 'Execute shell' marked build as failure Recording test results [Checks API] No suitable checks publisher found.