Build failed in Jenkins: master-osmo-bsc » a1=default,a2=default,a3=default,a4=default,osmocom-master-debian9 #5497

[jenkins at lists.osmocom.org]

See <https://jenkins.osmocom.org/jenkins/job/master-osmo-bsc/a1=default,a2=default,a3=default,a4=default,label=osmocom-master-debian9/5497/display/redirect>

------------------------------------------
[...truncated 1.53 MB...]
DTS handover_test.c:213 timeslot{NOT_INITIALIZED}: Received Event TS_EV_RSL_READY
DTS timeslot_fsm.c:240 timeslot{NOT_INITIALIZED}: (pchan_is=NONE) OML not ready yet
DTS handover_test.c:214 timeslot{NOT_INITIALIZED}: Received Event TS_EV_OML_READY
DTS timeslot_fsm.c:196 timeslot(0-0-1-TCH_F){NOT_INITIALIZED}: (pchan_is=NONE) max lchans: 1
DCHAN fsm.c:320 lchan(0-0-1-TCH_F){UNUSED}: Allocated
DCHAN fsm.c:350 lchan(0-0-1-TCH_F){UNUSED}: is child of timeslot(0-0-1-TCH_F)
DCHAN lchan_fsm.c:365 lchan(0-0-1-TCH_F-0){UNUSED}: new lchan
DTS timeslot_fsm.c:217 timeslot(0-0-1-TCH_F){NOT_INITIALIZED}: lchans initialized: 1
DTS timeslot_fsm.c:263 timeslot(0-0-1-TCH_F){NOT_INITIALIZED}: state_chg to UNUSED
DTS handover_test.c:213 timeslot{NOT_INITIALIZED}: Received Event TS_EV_RSL_READY
DTS timeslot_fsm.c:240 timeslot{NOT_INITIALIZED}: (pchan_is=NONE) OML not ready yet
DTS handover_test.c:214 timeslot{NOT_INITIALIZED}: Received Event TS_EV_OML_READY
DTS timeslot_fsm.c:196 timeslot(0-0-2-TCH_F){NOT_INITIALIZED}: (pchan_is=NONE) max lchans: 1
DCHAN fsm.c:320 lchan(0-0-2-TCH_F){UNUSED}: Allocated
DCHAN fsm.c:350 lchan(0-0-2-TCH_F){UNUSED}: is child of timeslot(0-0-2-TCH_F)
DCHAN lchan_fsm.c:365 lchan(0-0-2-TCH_F-0){UNUSED}: new lchan
DTS timeslot_fsm.c:217 timeslot(0-0-2-TCH_F){NOT_INITIALIZED}: lchans initialized: 1
DTS timeslot_fsm.c:263 timeslot(0-0-2-TCH_F){NOT_INITIALIZED}: state_chg to UNUSED
DTS handover_test.c:213 timeslot{NOT_INITIALIZED}: Received Event TS_EV_RSL_READY
DTS timeslot_fsm.c:240 timeslot{NOT_INITIALIZED}: (pchan_is=NONE) OML not ready yet
DTS handover_test.c:214 timeslot{NOT_INITIALIZED}: Received Event TS_EV_OML_READY
DTS timeslot_fsm.c:196 timeslot(0-0-3-TCH_F){NOT_INITIALIZED}: (pchan_is=NONE) max lchans: 1
DCHAN fsm.c:320 lchan(0-0-3-TCH_F){UNUSED}: Allocated
DCHAN fsm.c:350 lchan(0-0-3-TCH_F){UNUSED}: is child of timeslot(0-0-3-TCH_F)
DCHAN lchan_fsm.c:365 lchan(0-0-3-TCH_F-0){UNUSED}: new lchan
DTS timeslot_fsm.c:217 timeslot(0-0-3-TCH_F){NOT_INITIALIZED}: lchans initialized: 1
DTS timeslot_fsm.c:263 timeslot(0-0-3-TCH_F){NOT_INITIALIZED}: state_chg to UNUSED
DTS handover_test.c:213 timeslot{NOT_INITIALIZED}: Received Event TS_EV_RSL_READY
DTS timeslot_fsm.c:240 timeslot{NOT_INITIALIZED}: (pchan_is=NONE) OML not ready yet
DTS handover_test.c:214 timeslot{NOT_INITIALIZED}: Received Event TS_EV_OML_READY
DTS timeslot_fsm.c:196 timeslot(0-0-4-TCH_F){NOT_INITIALIZED}: (pchan_is=NONE) max lchans: 1
DCHAN fsm.c:320 lchan(0-0-4-TCH_F){UNUSED}: Allocated
DCHAN fsm.c:350 lchan(0-0-4-TCH_F){UNUSED}: is child of timeslot(0-0-4-TCH_F)
DCHAN lchan_fsm.c:365 lchan(0-0-4-TCH_F-0){UNUSED}: new lchan
DTS timeslot_fsm.c:217 timeslot(0-0-4-TCH_F){NOT_INITIALIZED}: lchans initialized: 1
DTS timeslot_fsm.c:263 timeslot(0-0-4-TCH_F){NOT_INITIALIZED}: state_chg to UNUSED
DTS handover_test.c:213 timeslot{NOT_INITIALIZED}: Received Event TS_EV_RSL_READY
DTS timeslot_fsm.c:240 timeslot{NOT_INITIALIZED}: (pchan_is=NONE) OML not ready yet
DTS handover_test.c:214 timeslot{NOT_INITIALIZED}: Received Event TS_EV_OML_READY
DTS timeslot_fsm.c:196 timeslot(0-0-5-TCH_H){NOT_INITIALIZED}: (pchan_is=NONE) max lchans: 2
DCHAN fsm.c:320 lchan(0-0-5-TCH_H){UNUSED}: Allocated
DCHAN fsm.c:350 lchan(0-0-5-TCH_H){UNUSED}: is child of timeslot(0-0-5-TCH_H)
DCHAN lchan_fsm.c:365 lchan(0-0-5-TCH_H-0){UNUSED}: new lchan
DCHAN fsm.c:320 lchan(0-0-5-TCH_H){UNUSED}: Allocated
DCHAN fsm.c:350 lchan(0-0-5-TCH_H){UNUSED}: is child of timeslot(0-0-5-TCH_H)
DCHAN lchan_fsm.c:365 lchan(0-0-5-TCH_H-1){UNUSED}: new lchan
DTS timeslot_fsm.c:217 timeslot(0-0-5-TCH_H){NOT_INITIALIZED}: lchans initialized: 2
DTS timeslot_fsm.c:263 timeslot(0-0-5-TCH_H){NOT_INITIALIZED}: state_chg to UNUSED
DTS handover_test.c:213 timeslot{NOT_INITIALIZED}: Received Event TS_EV_RSL_READY
DTS timeslot_fsm.c:240 timeslot{NOT_INITIALIZED}: (pchan_is=NONE) OML not ready yet
DTS handover_test.c:214 timeslot{NOT_INITIALIZED}: Received Event TS_EV_OML_READY
DTS timeslot_fsm.c:196 timeslot(0-0-6-TCH_H){NOT_INITIALIZED}: (pchan_is=NONE) max lchans: 2
DCHAN fsm.c:320 lchan(0-0-6-TCH_H){UNUSED}: Allocated
DCHAN fsm.c:350 lchan(0-0-6-TCH_H){UNUSED}: is child of timeslot(0-0-6-TCH_H)
DCHAN lchan_fsm.c:365 lchan(0-0-6-TCH_H-0){UNUSED}: new lchan
DCHAN fsm.c:320 lchan(0-0-6-TCH_H){UNUSED}: Allocated
DCHAN fsm.c:350 lchan(0-0-6-TCH_H){UNUSED}: is child of timeslot(0-0-6-TCH_H)
DCHAN lchan_fsm.c:365 lchan(0-0-6-TCH_H-1){UNUSED}: new lchan
DTS timeslot_fsm.c:217 timeslot(0-0-6-TCH_H){NOT_INITIALIZED}: lchans initialized: 2
DTS timeslot_fsm.c:263 timeslot(0-0-6-TCH_H){NOT_INITIALIZED}: state_chg to UNUSED
DTS handover_test.c:213 timeslot{NOT_INITIALIZED}: Received Event TS_EV_RSL_READY
DTS timeslot_fsm.c:240 timeslot{NOT_INITIALIZED}: OML not ready yet
DTS handover_test.c:214 timeslot{NOT_INITIALIZED}: Received Event TS_EV_OML_READY
DTS timeslot_fsm.c:196 timeslot(0-0-7-NONE){NOT_INITIALIZED}: max lchans: 0
DTS timeslot_fsm.c:217 timeslot(0-0-7-NONE){NOT_INITIALIZED}: lchans initialized: 0
DTS timeslot_fsm.c:263 timeslot(0-0-7-NONE){NOT_INITIALIZED}: state_chg to UNUSED
DTS fsm.c:320 timeslot{NOT_INITIALIZED}: Allocated
DTS fsm.c:320 timeslot{NOT_INITIALIZED}: Allocated
DTS fsm.c:320 timeslot{NOT_INITIALIZED}: Allocated
DTS fsm.c:320 timeslot{NOT_INITIALIZED}: Allocated
DTS fsm.c:320 timeslot{NOT_INITIALIZED}: Allocated
DTS fsm.c:320 timeslot{NOT_INITIALIZED}: Allocated
DTS fsm.c:320 timeslot{NOT_INITIALIZED}: Allocated
DTS fsm.c:320 timeslot{NOT_INITIALIZED}: Allocated
DTS handover_test.c:213 timeslot{NOT_INITIALIZED}: Received Event TS_EV_RSL_READY
DTS timeslot_fsm.c:240 timeslot{NOT_INITIALIZED}: (pchan_is=NONE) OML not ready yet
DTS handover_test.c:214 timeslot{NOT_INITIALIZED}: Received Event TS_EV_OML_READY
DTS timeslot_fsm.c:196 timeslot(1-0-0-CCCH_SDCCH4){NOT_INITIALIZED}: (pchan_is=NONE) max lchans: 4
DCHAN fsm.c:320 lchan(1-0-0-CCCH_SDCCH4){UNUSED}: Allocated
DCHAN fsm.c:350 lchan(1-0-0-CCCH_SDCCH4){UNUSED}: is child of timeslot(1-0-0-CCCH_SDCCH4)
DCHAN lchan_fsm.c:365 lchan(1-0-0-CCCH_SDCCH4-0){UNUSED}: new lchan
DCHAN fsm.c:320 lchan(1-0-0-CCCH_SDCCH4){UNUSED}: Allocated
DCHAN fsm.c:350 lchan(1-0-0-CCCH_SDCCH4){UNUSED}: is child of timeslot(1-0-0-CCCH_SDCCH4)
DCHAN lchan_fsm.c:365 lchan(1-0-0-CCCH_SDCCH4-1){UNUSED}: new lchan
DCHAN fsm.c:320 lchan(1-0-0-CCCH_SDCCH4){UNUSED}: Allocated
DCHAN fsm.c:350 lchan(1-0-0-CCCH_SDCCH4){UNUSED}: is child of timeslot(1-0-0-CCCH_SDCCH4)
DCHAN lchan_fsm.c:365 lchan(1-0-0-CCCH_SDCCH4-2){UNUSED}: new lchan
DCHAN fsm.c:320 lchan(1-0-0-CCCH_SDCCH4){UNUSED}: Allocated
DCHAN fsm.c:350 lchan(1-0-0-CCCH_SDCCH4){UNUSED}: is child of timeslot(1-0-0-CCCH_SDCCH4)
DCHAN lchan_fsm.c:365 lchan(1-0-0-CCCH_SDCCH4-3){UNUSED}: new lchan
DTS timeslot_fsm.c:217 timeslot(1-0-0-CCCH_SDCCH4){NOT_INITIALIZED}: lchans initialized: 4
DTS timeslot_fsm.c:263 timeslot(1-0-0-CCCH_SDCCH4){NOT_INITIALIZED}: state_chg to UNUSED
DTS handover_test.c:213 timeslot{NOT_INITIALIZED}: Received Event TS_EV_RSL_READY
DTS timeslot_fsm.c:240 timeslot{NOT_INITIALIZED}: (pchan_is=NONE) OML not ready yet
DTS handover_test.c:214 timeslot{NOT_INITIALIZED}: Received Event TS_EV_OML_READY
DTS timeslot_fsm.c:196 timeslot(1-0-1-TCH_F){NOT_INITIALIZED}: (pchan_is=NONE) max lchans: 1
DCHAN fsm.c:320 lchan(1-0-1-TCH_F){UNUSED}: Allocated
DCHAN fsm.c:350 lchan(1-0-1-TCH_F){UNUSED}: is child of timeslot(1-0-1-TCH_F)
DCHAN lchan_fsm.c:365 lchan(1-0-1-TCH_F-0){UNUSED}: new lchan
DTS timeslot_fsm.c:217 timeslot(1-0-1-TCH_F){NOT_INITIALIZED}: lchans initialized: 1
DTS timeslot_fsm.c:263 timeslot(1-0-1-TCH_F){NOT_INITIALIZED}: state_chg to UNUSED
DTS handover_test.c:213 timeslot{NOT_INITIALIZED}: Received Event TS_EV_RSL_READY
DTS timeslot_fsm.c:240 timeslot{NOT_INITIALIZED}: (pchan_is=NONE) OML not ready yet
DTS handover_test.c:214 timeslot{NOT_INITIALIZED}: Received Event TS_EV_OML_READY
DTS timeslot_fsm.c:196 timeslot(1-0-2-TCH_F){NOT_INITIALIZED}: (pchan_is=NONE) max lchans: 1
DCHAN fsm.c:320 lchan(1-0-2-TCH_F){UNUSED}: Allocated
DCHAN fsm.c:350 lchan(1-0-2-TCH_F){UNUSED}: is child of timeslot(1-0-2-TCH_F)
DCHAN lchan_fsm.c:365 lchan(1-0-2-TCH_F-0){UNUSED}: new lchan
DTS timeslot_fsm.c:217 timeslot(1-0-2-TCH_F){NOT_INITIALIZED}: lchans initialized: 1
DTS timeslot_fsm.c:263 timeslot(1-0-2-TCH_F){NOT_INITIALIZED}: state_chg to UNUSED
DTS handover_test.c:213 timeslot{NOT_INITIALIZED}: Received Event TS_EV_RSL_READY
DTS timeslot_fsm.c:240 timeslot{NOT_INITIALIZED}: (pchan_is=NONE) OML not ready yet
DTS handover_test.c:214 timeslot{NOT_INITIALIZED}: Received Event TS_EV_OML_READY
DTS timeslot_fsm.c:196 timeslot(1-0-3-TCH_F){NOT_INITIALIZED}: (pchan_is=NONE) max lchans: 1
DCHAN fsm.c:320 lchan(1-0-3-TCH_F){UNUSED}: Allocated
DCHAN fsm.c:350 lchan(1-0-3-TCH_F){UNUSED}: is child of timeslot(1-0-3-TCH_F)
DCHAN lchan_fsm.c:365 lchan(1-0-3-TCH_F-0){UNUSED}: new lchan
DTS timeslot_fsm.c:217 timeslot(1-0-3-TCH_F){NOT_INITIALIZED}: lchans initialized: 1
DTS timeslot_fsm.c:263 timeslot(1-0-3-TCH_F){NOT_INITIALIZED}: state_chg to UNUSED
DTS handover_test.c:213 timeslot{NOT_INITIALIZED}: Received Event TS_EV_RSL_READY
DTS timeslot_fsm.c:240 timeslot{NOT_INITIALIZED}: (pchan_is=NONE) OML not ready yet
DTS handover_test.c:214 timeslot{NOT_INITIALIZED}: Received Event TS_EV_OML_READY
DTS timeslot_fsm.c:196 timeslot(1-0-4-TCH_F){NOT_INITIALIZED}: (pchan_is=NONE) max lchans: 1
DCHAN fsm.c:320 lchan(1-0-4-TCH_F){UNUSED}: Allocated
DCHAN fsm.c:350 lchan(1-0-4-TCH_F){UNUSED}: is child of timeslot(1-0-4-TCH_F)
DCHAN lchan_fsm.c:365 lchan(1-0-4-TCH_F-0){UNUSED}: new lchan
DTS timeslot_fsm.c:217 timeslot(1-0-4-TCH_F){NOT_INITIALIZED}: lchans initialized: 1
DTS timeslot_fsm.c:263 timeslot(1-0-4-TCH_F){NOT_INITIALIZED}: state_chg to UNUSED
DTS handover_test.c:213 timeslot{NOT_INITIALIZED}: Received Event TS_EV_RSL_READY
DTS timeslot_fsm.c:240 timeslot{NOT_INITIALIZED}: (pchan_is=NONE) OML not ready yet
DTS handover_test.c:214 timeslot{NOT_INITIALIZED}: Received Event TS_EV_OML_READY
DTS timeslot_fsm.c:196 timeslot(1-0-5-TCH_H){NOT_INITIALIZED}: (pchan_is=NONE) max lchans: 2
DCHAN fsm.c:320 lchan(1-0-5-TCH_H){UNUSED}: Allocated
DCHAN fsm.c:350 lchan(1-0-5-TCH_H){UNUSED}: is child of timeslot(1-0-5-TCH_H)
DCHAN lchan_fsm.c:365 lchan(1-0-5-TCH_H-0){UNUSED}: new lchan
DCHAN fsm.c:320 lchan(1-0-5-TCH_H){UNUSED}: Allocated
DCHAN fsm.c:350 lchan(1-0-5-TCH_H){UNUSED}: is child of timeslot(1-0-5-TCH_H)
DCHAN lchan_fsm.c:365 lchan(1-0-5-TCH_H-1){UNUSED}: new lchan
DTS timeslot_fsm.c:217 timeslot(1-0-5-TCH_H){NOT_INITIALIZED}: lchans initialized: 2
DTS timeslot_fsm.c:263 timeslot(1-0-5-TCH_H){NOT_INITIALIZED}: state_chg to UNUSED
DTS handover_test.c:213 timeslot{NOT_INITIALIZED}: Received Event TS_EV_RSL_READY
DTS timeslot_fsm.c:240 timeslot{NOT_INITIALIZED}: (pchan_is=NONE) OML not ready yet
DTS handover_test.c:214 timeslot{NOT_INITIALIZED}: Received Event TS_EV_OML_READY
DTS timeslot_fsm.c:196 timeslot(1-0-6-TCH_H){NOT_INITIALIZED}: (pchan_is=NONE) max lchans: 2
DCHAN fsm.c:320 lchan(1-0-6-TCH_H){UNUSED}: Allocated
DCHAN fsm.c:350 lchan(1-0-6-TCH_H){UNUSED}: is child of timeslot(1-0-6-TCH_H)
DCHAN lchan_fsm.c:365 lchan(1-0-6-TCH_H-0){UNUSED}: new lchan
DCHAN fsm.c:320 lchan(1-0-6-TCH_H){UNUSED}: Allocated
DCHAN fsm.c:350 lchan(1-0-6-TCH_H){UNUSED}: is child of timeslot(1-0-6-TCH_H)
DCHAN lchan_fsm.c:365 lchan(1-0-6-TCH_H-1){UNUSED}: new lchan
DTS timeslot_fsm.c:217 timeslot(1-0-6-TCH_H){NOT_INITIALIZED}: lchans initialized: 2
DTS timeslot_fsm.c:263 timeslot(1-0-6-TCH_H){NOT_INITIALIZED}: state_chg to UNUSED
DTS handover_test.c:213 timeslot{NOT_INITIALIZED}: Received Event TS_EV_RSL_READY
DTS timeslot_fsm.c:240 timeslot{NOT_INITIALIZED}: OML not ready yet
DTS handover_test.c:214 timeslot{NOT_INITIALIZED}: Received Event TS_EV_OML_READY
DTS timeslot_fsm.c:196 timeslot(1-0-7-NONE){NOT_INITIALIZED}: max lchans: 0
DTS timeslot_fsm.c:217 timeslot(1-0-7-NONE){NOT_INITIALIZED}: lchans initialized: 0
DTS timeslot_fsm.c:263 timeslot(1-0-7-NONE){NOT_INITIALIZED}: state_chg to UNUSED
- Creating mobile #0 at BTS 0 on TCH/F with AMR codec
DRLL lchan_select.c:163 (bts=0) lchan_select_by_type(TCH_F)
DRLL lchan_select.c:71 looking for lchan TCH/F: (bts=0,trx=0,ts=0,pchan=CCCH+SDCCH4,state=UNUSED) is != TCH/F
DRLL lchan_select.c:86 looking for lchan TCH/F: (bts=0,trx=0,ts=1,pchan=TCH/F,state=UNUSED) ss=0 is available
DCHAN lchan_select.c:226 lchan(0-0-1-TCH_F-0){UNUSED}: (type=TCH_F) Selected
DCHAN handover_test.c:270 lchan(0-0-1-TCH_F-0){ESTABLISHED}: (type=TCH_F) activated by handover_test.c
DMSC fsm.c:320 SUBSCR_CONN{INIT}: Allocated
DRSL fsm.c:320 mgw-endpoint{UNUSED}: Allocated
DRSL fsm.c:350 mgw-endpoint{UNUSED}: is child of SUBSCR_CONN
DREF bsc_subscriber.c:94 BSC subscr IMSI:000001 usage increases to: 1
DMSC handover_test.c:252 SUBSCR_CONN{INIT}: Received Event MO-CONNECT.req
DMSC bsc_subscr_conn_fsm.c:276 SUBSCR_CONN{INIT}: state_chg to WAIT_CC
DMSC handover_test.c:253 SUBSCR_CONN(conn4294967295_IMSI000001){WAIT_CC}: Received Event MO-CONNECT.cfm
DMSC bsc_subscr_conn_fsm.c:333 SUBSCR_CONN(conn4294967295_IMSI000001){WAIT_CC}: state_chg to ACTIVE
 * New MS is at BTS 0 TS 1
- Setting minimum required free TCH/F slots at BTS 1 to 4
- Setting minimum required free TCH/H slots at BTS 1 to 4
- Sending measurement report from mobile #0 (rxlev=10, rxqual=0)
 * Neighbor cell #0, actual BTS 1 (rxlev=30)
DCHAN abis_rsl.c:1130 lchan(0-0-1-TCH_F-0){ESTABLISHED}: (type=TCH_F) Rx MEAS_RES
DRSL abis_rsl.c:1060 (bts=0,trx=0,ts=1,ss=0): meas_rep_count++=1 meas_rep_last_seen_nr=0
DMEAS abis_rsl.c:931 [IMSI:000001] MEASUREMENT RESULT NR=0 RXL-FULL-ul=-110dBm RXL-SUB-ul=-110dBm RXQ-FULL-ul=0 RXQ-SUB-ul=0 BS_POWER=0 L1_MS_PWR=-22dBm L1_FPC=0 L1_TA=0 RXL-FULL-dl=-100dBm RXL-SUB-dl=-100dBm RXQ-FULL-dl=0 RXQ-SUB-dl=0 NUM_NEIGH=1
DMEAS abis_rsl.c:964 IDX=0 ARFCN=871 BSIC=63 => -80 dBm
DHODEC handover_decision_2.c:1249 (lchan 0.010 TCH_F SPEECH_AMR) (subscr IMSI:000001) MEASUREMENT REPORT (1 neighbors)
DHODEC handover_decision_2.c:1254 (lchan 0.010 TCH_F SPEECH_AMR) (subscr IMSI:000001)   0: arfcn=871 bsic=63 neigh_idx=0 rxlev=30 flags=0
DHODEC handover_decision_2.c:338 (lchan 0.010 TCH_F SPEECH_AMR) (subscr IMSI:000001) neigh 871 new in report rxlev=30 last_seen_nr=0
DHODEC handover_decision_2.c:1307 (lchan 0.010 TCH_F SPEECH_AMR) (subscr IMSI:000001) Avg RX level = -100 dBm, +0 dBm AFS bias = -100 dBm; Avg RX quality = -1 (invalid), +0 AFS bias = -1
DHODEC handover_decision_2.c:835 (lchan 0.010 TCH_F SPEECH_AMR)->(BTS 0) (subscr IMSI:000001) RX level -100; TCH/F={free 3 (want 0), [-] not a candidate}; TCH/H={free 4 (want 0), [ABC] good}
DHODEC handover_decision_2.c:841 (lchan 0.010 TCH_F SPEECH_AMR)->(BTS 1) (subscr IMSI:000001) RX level -100 -> -80; TCH/F={free 4 (want 4), [A] more congestion}; TCH/H={free 4 (want 4), [A] more congestion}
DHODEC handover_decision_2.c:1177 (lchan 0.010 TCH_F SPEECH_AMR) (subscr IMSI:000001) No better/less congested neighbor cell found
- Expecting no channel request
 * Got no channel request
- Sending measurement report from mobile #0 (rxlev=9, rxqual=0)
 * Neighbor cell #0, actual BTS 1 (rxlev=30)
DCHAN abis_rsl.c:1130 lchan(0-0-1-TCH_F-0){ESTABLISHED}: (type=TCH_F) Rx MEAS_RES
DRSL abis_rsl.c:1060 (bts=0,trx=0,ts=1,ss=0): meas_rep_count++=2 meas_rep_last_seen_nr=1
DMEAS abis_rsl.c:931 [IMSI:000001] MEASUREMENT RESULT NR=1 RXL-FULL-ul=-110dBm RXL-SUB-ul=-110dBm RXQ-FULL-ul=0 RXQ-SUB-ul=0 BS_POWER=0 L1_MS_PWR=-22dBm L1_FPC=0 L1_TA=0 RXL-FULL-dl=-101dBm RXL-SUB-dl=-101dBm RXQ-FULL-dl=0 RXQ-SUB-dl=0 NUM_NEIGH=1
DMEAS abis_rsl.c:964 IDX=0 ARFCN=871 BSIC=63 => -80 dBm
DHODEC handover_decision_2.c:1249 (lchan 0.010 TCH_F SPEECH_AMR) (subscr IMSI:000001) MEASUREMENT REPORT (1 neighbors)
DHODEC handover_decision_2.c:1254 (lchan 0.010 TCH_F SPEECH_AMR) (subscr IMSI:000001)   0: arfcn=871 bsic=63 neigh_idx=0 rxlev=30 flags=0
DHODEC handover_decision_2.c:1307 (lchan 0.010 TCH_F SPEECH_AMR) (subscr IMSI:000001) Avg RX level = -101 dBm, +0 dBm AFS bias = -101 dBm; Avg RX quality = -1 (invalid), +0 AFS bias = -1
DHODEC handover_decision_2.c:1332 (lchan 0.010 TCH_F SPEECH_AMR) (subscr IMSI:000001) RX level is TOO LOW: -101 < -100
DHODEC handover_decision_2.c:835 (lchan 0.010 TCH_F SPEECH_AMR)->(BTS 0) (subscr IMSI:000001) RX level -101; TCH/F={free 3 (want 0), [-] not a candidate}; TCH/H={free 4 (want 0), [ABC] good}
DHODEC handover_decision_2.c:841 (lchan 0.010 TCH_F SPEECH_AMR)->(BTS 1) (subscr IMSI:000001) RX level -101 -> -80; TCH/F={free 4 (want 4), [A] more congestion}; TCH/H={free 4 (want 4), [A] more congestion}
DHODEC handover_decision_2.c:1210 (lchan 0.010 TCH_F SPEECH_AMR)->(BTS 1) (subscr IMSI:000001) Best candidate: RX level -80
DHODEC handover_decision_2.c:762 (lchan 0.010 TCH_F SPEECH_AMR)->(BTS 1) (subscr IMSI:000001) Triggering handover to TCH/F, due to low rxlevel
DMSC handover_fsm.c:203 SUBSCR_CONN(conn4294967295_IMSI000001){ACTIVE}: Received Event HANDOVER_START
DMSC bsc_subscr_conn_fsm.c:373 SUBSCR_CONN(conn4294967295_IMSI000001){ACTIVE}: state_chg to HANDOVER
DRSL fsm.c:320 handover(conn4294967295_IMSI000001){NOT_STARTED}: Allocated
DRSL fsm.c:350 handover(conn4294967295_IMSI000001){NOT_STARTED}: is child of SUBSCR_CONN(conn4294967295_IMSI000001)
DRLL lchan_select.c:163 (bts=1) lchan_select_by_type(TCH_F)
DRLL lchan_select.c:71 looking for lchan TCH/F: (bts=1,trx=0,ts=0,pchan=CCCH+SDCCH4,state=UNUSED) is != TCH/F
DRLL lchan_select.c:86 looking for lchan TCH/F: (bts=1,trx=0,ts=1,pchan=TCH/F,state=UNUSED) ss=0 is available
DCHAN lchan_select.c:226 lchan(1-0-1-TCH_F-0){UNUSED}: (type=TCH_F) Selected
DRSL handover_fsm.c:345 handover(intraBSC_conn4294967295_IMSI000001){NOT_STARTED}: (0-0-1-TCH_F-0-SPEECH_AMR) --HO-> (1-0-1-TCH/F-0) (subscr IMSI:000001) HO-intraBSC: incrementing rate counter: handover:attempted Intra-BSC handover attempts.
DRSL handover_fsm.c:353 handover(intraBSC_conn4294967295_IMSI000001){NOT_STARTED}: (0-0-1-TCH_F-0-SPEECH_AMR) --HO-> (1-0-1-TCH/F-0) (subscr IMSI:000001) HO-intraBSC: Selected lchan (bts=1,trx=0,ts=1,ss=0)
DRSL handover_fsm.c:355 handover(intraBSC_conn4294967295_IMSI000001){NOT_STARTED}: state_chg to WAIT_LCHAN_ACTIVE
DCHAN lchan_fsm.c:324 lchan(1-0-1-TCH_F-0){UNUSED}: Received Event LCHAN_EV_ACTIVATE
DCHAN lchan_fsm.c:496 lchan(1-0-1-TCH_F-0){UNUSED}: state_chg to WAIT_TS_READY
DCHAN lchan_fsm.c:440 lchan(1-0-1-TCH_F-0){WAIT_TS_READY}: (type=TCH_F) can not encode multirate configuration (invalid amr rate setting, MSC)
DCHAN lchan_fsm.c:544 lchan(1-0-1-TCH_F-0){WAIT_TS_READY}: (type=TCH_F) Handling failure, will then transition to state UNUSED
DCHAN lchan_fsm.c:78 lchan(1-0-1-TCH_F-0){WAIT_TS_READY}: (type=TCH_F) lchan allocation failed in state WAIT_TS_READY: Can not generate multirate configuration IE

DCHAN lchan_fsm.c:120 lchan(1-0-1-TCH_F-0){WAIT_TS_READY}: (type=TCH_F) Signalling Handover FSM of error (lchan allocation failed in state WAIT_TS_READY: Can not generate multirate configuration IE
)
DRSL lchan_fsm.c:544 handover(intraBSC_conn4294967295_IMSI000001){WAIT_LCHAN_ACTIVE}: Received Event HO_EV_LCHAN_ERROR
DRSL handover_fsm.c:1267 handover(intraBSC_conn4294967295_IMSI000001){WAIT_LCHAN_ACTIVE}: (0-0-1-TCH_F-0-SPEECH_AMR) --HO-> (1-0-1-TCH/F-0) (subscr IMSI:000001) HO-intraBSC: Handover failed in state HANDOVER, Failure: Error while establishing lchan (bts=1,trx=0,ts=1,ss=0)
DRSL handover_decision_2.c:1929 handover(intraBSC_conn4294967295_IMSI000001){WAIT_LCHAN_ACTIVE}: (0-0-1-TCH_F-0-SPEECH_AMR) --HO-> (1-0-1-TCH/F-0) (subscr IMSI:000001) HO-intraBSC: Failed, starting penalty timer (60 s)
DRSL handover_fsm.c:811 handover(intraBSC_conn4294967295_IMSI000001){WAIT_LCHAN_ACTIVE}: (0-0-1-TCH_F-0-SPEECH_AMR) --HO-> (1-0-1-TCH/F-0) (subscr IMSI:000001) HO-intraBSC: incrementing rate counter: handover:error Re-assigment failed for other reason.
DRSL handover_fsm.c:813 handover(intraBSC_conn4294967295_IMSI000001){WAIT_LCHAN_ACTIVE}: (0-0-1-TCH_F-0-SPEECH_AMR) --HO-> (1-0-1-TCH/F-0) (subscr IMSI:000001) HO-intraBSC: Result: Failure
DMSC handover_fsm.c:820 SUBSCR_CONN(conn4294967295_IMSI000001){HANDOVER}: Received Event HANDOVER_END
DMSC bsc_subscr_conn_fsm.c:423 SUBSCR_CONN(conn4294967295_IMSI000001){HANDOVER}: state_chg to ACTIVE
DCHAN lchan_fsm.c:1351 lchan(1-0-1-TCH_F-0){WAIT_TS_READY}: state_chg to WAIT_RLL_RTP_RELEASED
DRSL abis_rsl.c:633 (bts=1,trx=0,ts=1,ss=0) DEACTivate SACCH CMD
DCHAN lchan_fsm.c:988 lchan(1-0-1-TCH_F-0){WAIT_RLL_RTP_RELEASED}: state_chg to WAIT_BEFORE_RF_RELEASE
DRSL handover_fsm.c:842 handover(intraBSC_conn4294967295_IMSI000001){WAIT_LCHAN_ACTIVE}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
DRSL handover_fsm.c:842 handover(intraBSC_conn4294967295_IMSI000001){WAIT_LCHAN_ACTIVE}: Freeing instance
DRSL fsm.c:402 handover(intraBSC_conn4294967295_IMSI000001){WAIT_LCHAN_ACTIVE}: Deallocated
DCHAN lchan_fsm.c:544 lchan(1-0-1-TCH_F-0){WAIT_BEFORE_RF_RELEASE}: state_chg to UNUSED
DCHAN lchan_fsm.c:377 lchan(1-0-1-TCH_F-0){UNUSED}: (type=TCH_F) Clearing lchan state
DTS lchan_fsm.c:410 timeslot(1-0-1-TCH_F){UNUSED}: Received Event TS_EV_LCHAN_UNUSED
- Expecting channel request at BTS 1 TS 1
--- expout	2019-03-11 16:14:05.320894365 +0000
+++ /build/tests/testsuite.dir/at-groups/22/stdout	2019-03-11 16:14:05.336894161 +0000
@@ -1 +1 @@
-Test OK
+Test failed, because no channel was requested
./testsuite.at:135: exit code was 1, expected 0
22. testsuite.at:132: 22. handover test 14 (testsuite.at:132): FAILED (testsuite.at:135)
Build step 'Execute shell' marked build as failure
[WARNINGS]Skipping publisher since build result is FAILURE