See
<https://jenkins.osmocom.org/jenkins/job/master-osmo-bsc/a1=default,a2=default,a3=default,a4=default,label=osmocom-master-debian9/5490/display/redirect>
------------------------------------------
[...truncated 1.50 MB...]
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:263
timeslot(5-0-1-TCH_F){NOT_INITIALIZED}: state_chg to UNUSED
[0;m[1;31m[1;34mDTS[0;m[1;31m handover_test.c:213 timeslot{NOT_INITIALIZED}: Received
Event TS_EV_RSL_READY
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:240 timeslot{NOT_INITIALIZED}:
(pchan_is=NONE) OML not ready yet
[0;m[1;31m[1;34mDTS[0;m[1;31m handover_test.c:214 timeslot{NOT_INITIALIZED}: Received
Event TS_EV_OML_READY
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:196
timeslot(5-0-2-TCH_F){NOT_INITIALIZED}: (pchan_is=NONE) max lchans: 1
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:320 lchan(5-0-2-TCH_F){UNUSED}: Allocated
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:350 lchan(5-0-2-TCH_F){UNUSED}: is child of
timeslot(5-0-2-TCH_F)
[0;m[1;32m[1;34mDCHAN[0;m[1;32m lchan_fsm.c:365 lchan(5-0-2-TCH_F-0){UNUSED}: new
lchan
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:217
timeslot(5-0-2-TCH_F){NOT_INITIALIZED}: lchans initialized: 1
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:263
timeslot(5-0-2-TCH_F){NOT_INITIALIZED}: state_chg to UNUSED
[0;m[1;31m[1;34mDTS[0;m[1;31m handover_test.c:213 timeslot{NOT_INITIALIZED}: Received
Event TS_EV_RSL_READY
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:240 timeslot{NOT_INITIALIZED}:
(pchan_is=NONE) OML not ready yet
[0;m[1;31m[1;34mDTS[0;m[1;31m handover_test.c:214 timeslot{NOT_INITIALIZED}: Received
Event TS_EV_OML_READY
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:196
timeslot(5-0-3-TCH_F){NOT_INITIALIZED}: (pchan_is=NONE) max lchans: 1
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:320 lchan(5-0-3-TCH_F){UNUSED}: Allocated
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:350 lchan(5-0-3-TCH_F){UNUSED}: is child of
timeslot(5-0-3-TCH_F)
[0;m[1;32m[1;34mDCHAN[0;m[1;32m lchan_fsm.c:365 lchan(5-0-3-TCH_F-0){UNUSED}: new
lchan
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:217
timeslot(5-0-3-TCH_F){NOT_INITIALIZED}: lchans initialized: 1
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:263
timeslot(5-0-3-TCH_F){NOT_INITIALIZED}: state_chg to UNUSED
[0;m[1;31m[1;34mDTS[0;m[1;31m handover_test.c:213 timeslot{NOT_INITIALIZED}: Received
Event TS_EV_RSL_READY
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:240 timeslot{NOT_INITIALIZED}:
(pchan_is=NONE) OML not ready yet
[0;m[1;31m[1;34mDTS[0;m[1;31m handover_test.c:214 timeslot{NOT_INITIALIZED}: Received
Event TS_EV_OML_READY
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:196
timeslot(5-0-4-TCH_F){NOT_INITIALIZED}: (pchan_is=NONE) max lchans: 1
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:320 lchan(5-0-4-TCH_F){UNUSED}: Allocated
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:350 lchan(5-0-4-TCH_F){UNUSED}: is child of
timeslot(5-0-4-TCH_F)
[0;m[1;32m[1;34mDCHAN[0;m[1;32m lchan_fsm.c:365 lchan(5-0-4-TCH_F-0){UNUSED}: new
lchan
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:217
timeslot(5-0-4-TCH_F){NOT_INITIALIZED}: lchans initialized: 1
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:263
timeslot(5-0-4-TCH_F){NOT_INITIALIZED}: state_chg to UNUSED
[0;m[1;31m[1;34mDTS[0;m[1;31m handover_test.c:213 timeslot{NOT_INITIALIZED}: Received
Event TS_EV_RSL_READY
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:240 timeslot{NOT_INITIALIZED}:
(pchan_is=NONE) OML not ready yet
[0;m[1;31m[1;34mDTS[0;m[1;31m handover_test.c:214 timeslot{NOT_INITIALIZED}: Received
Event TS_EV_OML_READY
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:196
timeslot(5-0-5-TCH_H){NOT_INITIALIZED}: (pchan_is=NONE) max lchans: 2
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:320 lchan(5-0-5-TCH_H){UNUSED}: Allocated
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:350 lchan(5-0-5-TCH_H){UNUSED}: is child of
timeslot(5-0-5-TCH_H)
[0;m[1;32m[1;34mDCHAN[0;m[1;32m lchan_fsm.c:365 lchan(5-0-5-TCH_H-0){UNUSED}: new
lchan
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:320 lchan(5-0-5-TCH_H){UNUSED}: Allocated
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:350 lchan(5-0-5-TCH_H){UNUSED}: is child of
timeslot(5-0-5-TCH_H)
[0;m[1;32m[1;34mDCHAN[0;m[1;32m lchan_fsm.c:365 lchan(5-0-5-TCH_H-1){UNUSED}: new
lchan
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:217
timeslot(5-0-5-TCH_H){NOT_INITIALIZED}: lchans initialized: 2
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:263
timeslot(5-0-5-TCH_H){NOT_INITIALIZED}: state_chg to UNUSED
[0;m[1;31m[1;34mDTS[0;m[1;31m handover_test.c:213 timeslot{NOT_INITIALIZED}: Received
Event TS_EV_RSL_READY
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:240 timeslot{NOT_INITIALIZED}:
(pchan_is=NONE) OML not ready yet
[0;m[1;31m[1;34mDTS[0;m[1;31m handover_test.c:214 timeslot{NOT_INITIALIZED}: Received
Event TS_EV_OML_READY
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:196
timeslot(5-0-6-TCH_H){NOT_INITIALIZED}: (pchan_is=NONE) max lchans: 2
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:320 lchan(5-0-6-TCH_H){UNUSED}: Allocated
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:350 lchan(5-0-6-TCH_H){UNUSED}: is child of
timeslot(5-0-6-TCH_H)
[0;m[1;32m[1;34mDCHAN[0;m[1;32m lchan_fsm.c:365 lchan(5-0-6-TCH_H-0){UNUSED}: new
lchan
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:320 lchan(5-0-6-TCH_H){UNUSED}: Allocated
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:350 lchan(5-0-6-TCH_H){UNUSED}: is child of
timeslot(5-0-6-TCH_H)
[0;m[1;32m[1;34mDCHAN[0;m[1;32m lchan_fsm.c:365 lchan(5-0-6-TCH_H-1){UNUSED}: new
lchan
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:217
timeslot(5-0-6-TCH_H){NOT_INITIALIZED}: lchans initialized: 2
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:263
timeslot(5-0-6-TCH_H){NOT_INITIALIZED}: state_chg to UNUSED
[0;m[1;31m[1;34mDTS[0;m[1;31m handover_test.c:213 timeslot{NOT_INITIALIZED}: Received
Event TS_EV_RSL_READY
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:240 timeslot{NOT_INITIALIZED}: OML not
ready yet
[0;m[1;31m[1;34mDTS[0;m[1;31m handover_test.c:214 timeslot{NOT_INITIALIZED}: Received
Event TS_EV_OML_READY
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:196
timeslot(5-0-7-NONE){NOT_INITIALIZED}: max lchans: 0
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:217
timeslot(5-0-7-NONE){NOT_INITIALIZED}: lchans initialized: 0
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:263
timeslot(5-0-7-NONE){NOT_INITIALIZED}: state_chg to UNUSED
[0;m[1;31m[1;34mDTS[0;m[1;31m fsm.c:320 timeslot{NOT_INITIALIZED}: Allocated
[0;m[1;31m[1;34mDTS[0;m[1;31m fsm.c:320 timeslot{NOT_INITIALIZED}: Allocated
[0;m[1;31m[1;34mDTS[0;m[1;31m fsm.c:320 timeslot{NOT_INITIALIZED}: Allocated
[0;m[1;31m[1;34mDTS[0;m[1;31m fsm.c:320 timeslot{NOT_INITIALIZED}: Allocated
[0;m[1;31m[1;34mDTS[0;m[1;31m fsm.c:320 timeslot{NOT_INITIALIZED}: Allocated
[0;m[1;31m[1;34mDTS[0;m[1;31m fsm.c:320 timeslot{NOT_INITIALIZED}: Allocated
[0;m[1;31m[1;34mDTS[0;m[1;31m fsm.c:320 timeslot{NOT_INITIALIZED}: Allocated
[0;m[1;31m[1;34mDTS[0;m[1;31m fsm.c:320 timeslot{NOT_INITIALIZED}: Allocated
[0;m[1;31m[1;34mDTS[0;m[1;31m handover_test.c:213 timeslot{NOT_INITIALIZED}: Received
Event TS_EV_RSL_READY
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:240 timeslot{NOT_INITIALIZED}:
(pchan_is=NONE) OML not ready yet
[0;m[1;31m[1;34mDTS[0;m[1;31m handover_test.c:214 timeslot{NOT_INITIALIZED}: Received
Event TS_EV_OML_READY
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:196
timeslot(6-0-0-CCCH_SDCCH4){NOT_INITIALIZED}: (pchan_is=NONE) max lchans: 4
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:320 lchan(6-0-0-CCCH_SDCCH4){UNUSED}:
Allocated
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:350 lchan(6-0-0-CCCH_SDCCH4){UNUSED}: is child
of timeslot(6-0-0-CCCH_SDCCH4)
[0;m[1;32m[1;34mDCHAN[0;m[1;32m lchan_fsm.c:365 lchan(6-0-0-CCCH_SDCCH4-0){UNUSED}:
new lchan
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:320 lchan(6-0-0-CCCH_SDCCH4){UNUSED}:
Allocated
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:350 lchan(6-0-0-CCCH_SDCCH4){UNUSED}: is child
of timeslot(6-0-0-CCCH_SDCCH4)
[0;m[1;32m[1;34mDCHAN[0;m[1;32m lchan_fsm.c:365 lchan(6-0-0-CCCH_SDCCH4-1){UNUSED}:
new lchan
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:320 lchan(6-0-0-CCCH_SDCCH4){UNUSED}:
Allocated
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:350 lchan(6-0-0-CCCH_SDCCH4){UNUSED}: is child
of timeslot(6-0-0-CCCH_SDCCH4)
[0;m[1;32m[1;34mDCHAN[0;m[1;32m lchan_fsm.c:365 lchan(6-0-0-CCCH_SDCCH4-2){UNUSED}:
new lchan
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:320 lchan(6-0-0-CCCH_SDCCH4){UNUSED}:
Allocated
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:350 lchan(6-0-0-CCCH_SDCCH4){UNUSED}: is child
of timeslot(6-0-0-CCCH_SDCCH4)
[0;m[1;32m[1;34mDCHAN[0;m[1;32m lchan_fsm.c:365 lchan(6-0-0-CCCH_SDCCH4-3){UNUSED}:
new lchan
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:217
timeslot(6-0-0-CCCH_SDCCH4){NOT_INITIALIZED}: lchans initialized: 4
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:263
timeslot(6-0-0-CCCH_SDCCH4){NOT_INITIALIZED}: state_chg to UNUSED
[0;m[1;31m[1;34mDTS[0;m[1;31m handover_test.c:213 timeslot{NOT_INITIALIZED}: Received
Event TS_EV_RSL_READY
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:240 timeslot{NOT_INITIALIZED}:
(pchan_is=NONE) OML not ready yet
[0;m[1;31m[1;34mDTS[0;m[1;31m handover_test.c:214 timeslot{NOT_INITIALIZED}: Received
Event TS_EV_OML_READY
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:196
timeslot(6-0-1-TCH_F){NOT_INITIALIZED}: (pchan_is=NONE) max lchans: 1
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:320 lchan(6-0-1-TCH_F){UNUSED}: Allocated
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:350 lchan(6-0-1-TCH_F){UNUSED}: is child of
timeslot(6-0-1-TCH_F)
[0;m[1;32m[1;34mDCHAN[0;m[1;32m lchan_fsm.c:365 lchan(6-0-1-TCH_F-0){UNUSED}: new
lchan
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:217
timeslot(6-0-1-TCH_F){NOT_INITIALIZED}: lchans initialized: 1
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:263
timeslot(6-0-1-TCH_F){NOT_INITIALIZED}: state_chg to UNUSED
[0;m[1;31m[1;34mDTS[0;m[1;31m handover_test.c:213 timeslot{NOT_INITIALIZED}: Received
Event TS_EV_RSL_READY
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:240 timeslot{NOT_INITIALIZED}:
(pchan_is=NONE) OML not ready yet
[0;m[1;31m[1;34mDTS[0;m[1;31m handover_test.c:214 timeslot{NOT_INITIALIZED}: Received
Event TS_EV_OML_READY
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:196
timeslot(6-0-2-TCH_F){NOT_INITIALIZED}: (pchan_is=NONE) max lchans: 1
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:320 lchan(6-0-2-TCH_F){UNUSED}: Allocated
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:350 lchan(6-0-2-TCH_F){UNUSED}: is child of
timeslot(6-0-2-TCH_F)
[0;m[1;32m[1;34mDCHAN[0;m[1;32m lchan_fsm.c:365 lchan(6-0-2-TCH_F-0){UNUSED}: new
lchan
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:217
timeslot(6-0-2-TCH_F){NOT_INITIALIZED}: lchans initialized: 1
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:263
timeslot(6-0-2-TCH_F){NOT_INITIALIZED}: state_chg to UNUSED
[0;m[1;31m[1;34mDTS[0;m[1;31m handover_test.c:213 timeslot{NOT_INITIALIZED}: Received
Event TS_EV_RSL_READY
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:240 timeslot{NOT_INITIALIZED}:
(pchan_is=NONE) OML not ready yet
[0;m[1;31m[1;34mDTS[0;m[1;31m handover_test.c:214 timeslot{NOT_INITIALIZED}: Received
Event TS_EV_OML_READY
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:196
timeslot(6-0-3-TCH_F){NOT_INITIALIZED}: (pchan_is=NONE) max lchans: 1
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:320 lchan(6-0-3-TCH_F){UNUSED}: Allocated
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:350 lchan(6-0-3-TCH_F){UNUSED}: is child of
timeslot(6-0-3-TCH_F)
[0;m[1;32m[1;34mDCHAN[0;m[1;32m lchan_fsm.c:365 lchan(6-0-3-TCH_F-0){UNUSED}: new
lchan
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:217
timeslot(6-0-3-TCH_F){NOT_INITIALIZED}: lchans initialized: 1
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:263
timeslot(6-0-3-TCH_F){NOT_INITIALIZED}: state_chg to UNUSED
[0;m[1;31m[1;34mDTS[0;m[1;31m handover_test.c:213 timeslot{NOT_INITIALIZED}: Received
Event TS_EV_RSL_READY
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:240 timeslot{NOT_INITIALIZED}:
(pchan_is=NONE) OML not ready yet
[0;m[1;31m[1;34mDTS[0;m[1;31m handover_test.c:214 timeslot{NOT_INITIALIZED}: Received
Event TS_EV_OML_READY
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:196
timeslot(6-0-4-TCH_F){NOT_INITIALIZED}: (pchan_is=NONE) max lchans: 1
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:320 lchan(6-0-4-TCH_F){UNUSED}: Allocated
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:350 lchan(6-0-4-TCH_F){UNUSED}: is child of
timeslot(6-0-4-TCH_F)
[0;m[1;32m[1;34mDCHAN[0;m[1;32m lchan_fsm.c:365 lchan(6-0-4-TCH_F-0){UNUSED}: new
lchan
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:217
timeslot(6-0-4-TCH_F){NOT_INITIALIZED}: lchans initialized: 1
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:263
timeslot(6-0-4-TCH_F){NOT_INITIALIZED}: state_chg to UNUSED
[0;m[1;31m[1;34mDTS[0;m[1;31m handover_test.c:213 timeslot{NOT_INITIALIZED}: Received
Event TS_EV_RSL_READY
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:240 timeslot{NOT_INITIALIZED}:
(pchan_is=NONE) OML not ready yet
[0;m[1;31m[1;34mDTS[0;m[1;31m handover_test.c:214 timeslot{NOT_INITIALIZED}: Received
Event TS_EV_OML_READY
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:196
timeslot(6-0-5-TCH_H){NOT_INITIALIZED}: (pchan_is=NONE) max lchans: 2
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:320 lchan(6-0-5-TCH_H){UNUSED}: Allocated
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:350 lchan(6-0-5-TCH_H){UNUSED}: is child of
timeslot(6-0-5-TCH_H)
[0;m[1;32m[1;34mDCHAN[0;m[1;32m lchan_fsm.c:365 lchan(6-0-5-TCH_H-0){UNUSED}: new
lchan
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:320 lchan(6-0-5-TCH_H){UNUSED}: Allocated
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:350 lchan(6-0-5-TCH_H){UNUSED}: is child of
timeslot(6-0-5-TCH_H)
[0;m[1;32m[1;34mDCHAN[0;m[1;32m lchan_fsm.c:365 lchan(6-0-5-TCH_H-1){UNUSED}: new
lchan
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:217
timeslot(6-0-5-TCH_H){NOT_INITIALIZED}: lchans initialized: 2
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:263
timeslot(6-0-5-TCH_H){NOT_INITIALIZED}: state_chg to UNUSED
[0;m[1;31m[1;34mDTS[0;m[1;31m handover_test.c:213 timeslot{NOT_INITIALIZED}: Received
Event TS_EV_RSL_READY
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:240 timeslot{NOT_INITIALIZED}:
(pchan_is=NONE) OML not ready yet
[0;m[1;31m[1;34mDTS[0;m[1;31m handover_test.c:214 timeslot{NOT_INITIALIZED}: Received
Event TS_EV_OML_READY
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:196
timeslot(6-0-6-TCH_H){NOT_INITIALIZED}: (pchan_is=NONE) max lchans: 2
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:320 lchan(6-0-6-TCH_H){UNUSED}: Allocated
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:350 lchan(6-0-6-TCH_H){UNUSED}: is child of
timeslot(6-0-6-TCH_H)
[0;m[1;32m[1;34mDCHAN[0;m[1;32m lchan_fsm.c:365 lchan(6-0-6-TCH_H-0){UNUSED}: new
lchan
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:320 lchan(6-0-6-TCH_H){UNUSED}: Allocated
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:350 lchan(6-0-6-TCH_H){UNUSED}: is child of
timeslot(6-0-6-TCH_H)
[0;m[1;32m[1;34mDCHAN[0;m[1;32m lchan_fsm.c:365 lchan(6-0-6-TCH_H-1){UNUSED}: new
lchan
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:217
timeslot(6-0-6-TCH_H){NOT_INITIALIZED}: lchans initialized: 2
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:263
timeslot(6-0-6-TCH_H){NOT_INITIALIZED}: state_chg to UNUSED
[0;m[1;31m[1;34mDTS[0;m[1;31m handover_test.c:213 timeslot{NOT_INITIALIZED}: Received
Event TS_EV_RSL_READY
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:240 timeslot{NOT_INITIALIZED}: OML not
ready yet
[0;m[1;31m[1;34mDTS[0;m[1;31m handover_test.c:214 timeslot{NOT_INITIALIZED}: Received
Event TS_EV_OML_READY
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:196
timeslot(6-0-7-NONE){NOT_INITIALIZED}: max lchans: 0
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:217
timeslot(6-0-7-NONE){NOT_INITIALIZED}: lchans initialized: 0
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:263
timeslot(6-0-7-NONE){NOT_INITIALIZED}: state_chg to UNUSED
[0;m- Creating mobile #0 at BTS 0 on TCH/F with AMR codec
[1;35m[1;34mDRLL[0;m[1;35m lchan_select.c:163 (bts=0) lchan_select_by_type(TCH_F)
[0;m[1;35m[1;34mDRLL[0;m[1;35m lchan_select.c:71 looking for lchan TCH/F:
(bts=0,trx=0,ts=0,pchan=CCCH+SDCCH4,state=UNUSED) is != TCH/F
[0;m[1;35m[1;34mDRLL[0;m[1;35m lchan_select.c:86 looking for lchan TCH/F:
(bts=0,trx=0,ts=1,pchan=TCH/F,state=UNUSED) ss=0 is available
[0;m[1;32m[1;32mDCHAN[0;m[1;32m lchan_select.c:226 lchan(0-0-1-TCH_F-0){UNUSED}:
(type=TCH_F) Selected
[0;m[1;32m[1;34mDCHAN[0;m[1;32m handover_test.c:270
lchan(0-0-1-TCH_F-0){ESTABLISHED}: (type=TCH_F) activated by handover_test.c
[0;m[1;34mDMSC[0;m fsm.c:320 SUBSCR_CONN{INIT}: Allocated
[0;m[1;35m[1;34mDRSL[0;m[1;35m fsm.c:320 mgw-endpoint{UNUSED}: Allocated
[0;m[1;35m[1;34mDRSL[0;m[1;35m fsm.c:350 mgw-endpoint{UNUSED}: is child of
SUBSCR_CONN
[0;m[1;34mDREF[0;m bsc_subscriber.c:94 BSC subscr IMSI:000001 usage increases to: 1
[0;m[1;34mDMSC[0;m handover_test.c:252 SUBSCR_CONN{INIT}: Received Event
MO-CONNECT.req
[0;m[1;34mDMSC[0;m bsc_subscr_conn_fsm.c:276 SUBSCR_CONN{INIT}: state_chg to WAIT_CC
[0;m[1;34mDMSC[0;m handover_test.c:253 SUBSCR_CONN(conn4294967295_IMSI000001){WAIT_CC}:
Received Event MO-CONNECT.cfm
[0;m[1;34mDMSC[0;m bsc_subscr_conn_fsm.c:333
SUBSCR_CONN(conn4294967295_IMSI000001){WAIT_CC}: state_chg to ACTIVE
[0;m * New MS is at BTS 0 TS 1
- Sending measurement report from mobile #0 (rxlev=10, rxqual=0)
* Neighbor cell #0, actual BTS 1 (rxlev=20)
* Neighbor cell #1, actual BTS 2 (rxlev=21)
* Neighbor cell #2, actual BTS 3 (rxlev=18)
* Neighbor cell #3, actual BTS 4 (rxlev=20)
* Neighbor cell #4, actual BTS 5 (rxlev=23)
* Neighbor cell #5, actual BTS 6 (rxlev=19)
[1;32m[1;34mDCHAN[0;m[1;32m abis_rsl.c:1130 lchan(0-0-1-TCH_F-0){ESTABLISHED}:
(type=TCH_F) Rx MEAS_RES
[0;m[1;35m[1;34mDRSL[0;m[1;35m abis_rsl.c:1060 (bts=0,trx=0,ts=1,ss=0):
meas_rep_count++=1 meas_rep_last_seen_nr=0
[0;m[1;34mDMEAS[0;m abis_rsl.c:931 [IMSI:000001] MEASUREMENT RESULT NR=0
[0;mRXL-FULL-ul=-110dBm RXL-SUB-ul=-110dBm [0;mRXQ-FULL-ul=0 RXQ-SUB-ul=0
[0;mBS_POWER=0 [0;mL1_MS_PWR=-22dBm [0;mL1_FPC=0 [0;mL1_TA=0 [0;mRXL-FULL-dl=-100dBm
RXL-SUB-dl=-100dBm [0;mRXQ-FULL-dl=0 RXQ-SUB-dl=0 [0;mNUM_NEIGH=6
[0;m[1;34mDMEAS[0;m abis_rsl.c:964 IDX=0 ARFCN=871 BSIC=63 => -90 dBm
[0;m[1;34mDMEAS[0;m abis_rsl.c:964 IDX=1 ARFCN=872 BSIC=63 => -89 dBm
[0;m[1;34mDMEAS[0;m abis_rsl.c:964 IDX=2 ARFCN=873 BSIC=63 => -92 dBm
[0;m[1;34mDMEAS[0;m abis_rsl.c:964 IDX=3 ARFCN=874 BSIC=63 => -90 dBm
[0;m[1;34mDMEAS[0;m abis_rsl.c:964 IDX=4 ARFCN=875 BSIC=63 => -87 dBm
[0;m[1;34mDMEAS[0;m abis_rsl.c:964 IDX=5 ARFCN=876 BSIC=63 => -91 dBm
[0;m[1;38m[1;34mDHODEC[0;m[1;38m handover_decision_2.c:1249 (lchan 0.010 TCH_F
SPEECH_AMR) (subscr IMSI:000001) MEASUREMENT REPORT (6 neighbors)
[0;m[1;38m[1;34mDHODEC[0;m[1;38m handover_decision_2.c:1254 (lchan 0.010 TCH_F
SPEECH_AMR) (subscr IMSI:000001) 0: arfcn=871 bsic=63 neigh_idx=0 rxlev=20 flags=0
[0;m[1;38m[1;34mDHODEC[0;m[1;38m handover_decision_2.c:1254 (lchan 0.010 TCH_F
SPEECH_AMR) (subscr IMSI:000001) 1: arfcn=872 bsic=63 neigh_idx=1 rxlev=21 flags=0
[0;m[1;38m[1;34mDHODEC[0;m[1;38m handover_decision_2.c:1254 (lchan 0.010 TCH_F
SPEECH_AMR) (subscr IMSI:000001) 2: arfcn=873 bsic=63 neigh_idx=2 rxlev=18 flags=0
[0;m[1;38m[1;34mDHODEC[0;m[1;38m handover_decision_2.c:1254 (lchan 0.010 TCH_F
SPEECH_AMR) (subscr IMSI:000001) 3: arfcn=874 bsic=63 neigh_idx=3 rxlev=20 flags=0
[0;m[1;38m[1;34mDHODEC[0;m[1;38m handover_decision_2.c:1254 (lchan 0.010 TCH_F
SPEECH_AMR) (subscr IMSI:000001) 4: arfcn=875 bsic=63 neigh_idx=4 rxlev=23 flags=0
[0;m[1;38m[1;34mDHODEC[0;m[1;38m handover_decision_2.c:1254 (lchan 0.010 TCH_F
SPEECH_AMR) (subscr IMSI:000001) 5: arfcn=876 bsic=63 neigh_idx=5 rxlev=19 flags=0
[0;m[1;38m[1;34mDHODEC[0;m[1;38m handover_decision_2.c:338 (lchan 0.010 TCH_F
SPEECH_AMR) (subscr IMSI:000001) neigh 871 new in report rxlev=20 last_seen_nr=0
[0;m[1;38m[1;34mDHODEC[0;m[1;38m handover_decision_2.c:338 (lchan 0.010 TCH_F
SPEECH_AMR) (subscr IMSI:000001) neigh 872 new in report rxlev=21 last_seen_nr=0
[0;m[1;38m[1;34mDHODEC[0;m[1;38m handover_decision_2.c:338 (lchan 0.010 TCH_F
SPEECH_AMR) (subscr IMSI:000001) neigh 873 new in report rxlev=18 last_seen_nr=0
[0;m[1;38m[1;34mDHODEC[0;m[1;38m handover_decision_2.c:338 (lchan 0.010 TCH_F
SPEECH_AMR) (subscr IMSI:000001) neigh 874 new in report rxlev=20 last_seen_nr=0
[0;m[1;38m[1;34mDHODEC[0;m[1;38m handover_decision_2.c:338 (lchan 0.010 TCH_F
SPEECH_AMR) (subscr IMSI:000001) neigh 875 new in report rxlev=23 last_seen_nr=0
[0;m[1;38m[1;34mDHODEC[0;m[1;38m handover_decision_2.c:338 (lchan 0.010 TCH_F
SPEECH_AMR) (subscr IMSI:000001) neigh 876 new in report rxlev=19 last_seen_nr=0
[0;m[1;38m[1;34mDHODEC[0;m[1;38m 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
[0;m[1;38m[1;34mDHODEC[0;m[1;38m 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}
[0;m[1;38m[1;34mDHODEC[0;m[1;38m handover_decision_2.c:841 (lchan 0.010 TCH_F
SPEECH_AMR)->(BTS 1) (subscr IMSI:000001) RX level -100 -> -90; TCH/F={free 4 (want
0), [ABC] good}; TCH/H={free 4 (want 0), [ABC] good}
[0;m[1;38m[1;34mDHODEC[0;m[1;38m handover_decision_2.c:841 (lchan 0.010 TCH_F
SPEECH_AMR)->(BTS 2) (subscr IMSI:000001) RX level -100 -> -89; TCH/F={free 4 (want
0), [ABC] good}; TCH/H={free 4 (want 0), [ABC] good}
[0;m[1;38m[1;34mDHODEC[0;m[1;38m handover_decision_2.c:841 (lchan 0.010 TCH_F
SPEECH_AMR)->(BTS 3) (subscr IMSI:000001) RX level -100 -> -92; TCH/F={free 4 (want
0), [ABC] good}; TCH/H={free 4 (want 0), [ABC] good}
[0;m[1;38m[1;34mDHODEC[0;m[1;38m handover_decision_2.c:841 (lchan 0.010 TCH_F
SPEECH_AMR)->(BTS 4) (subscr IMSI:000001) RX level -100 -> -90; TCH/F={free 4 (want
0), [ABC] good}; TCH/H={free 4 (want 0), [ABC] good}
[0;m[1;38m[1;34mDHODEC[0;m[1;38m handover_decision_2.c:841 (lchan 0.010 TCH_F
SPEECH_AMR)->(BTS 5) (subscr IMSI:000001) RX level -100 -> -87; TCH/F={free 4 (want
0), [ABC] good}; TCH/H={free 4 (want 0), [ABC] good}
[0;m[1;38m[1;34mDHODEC[0;m[1;38m handover_decision_2.c:841 (lchan 0.010 TCH_F
SPEECH_AMR)->(BTS 6) (subscr IMSI:000001) RX level -100 -> -91; TCH/F={free 4 (want
0), [ABC] good}; TCH/H={free 4 (want 0), [ABC] good}
[0;m[1;38m[1;32mDHODEC[0;m[1;38m handover_decision_2.c:1138 (lchan 0.010 TCH_F
SPEECH_AMR)->(BTS 5) (subscr IMSI:000001) Best candidate, RX level -87
[0;m[1;38m[1;32mDHODEC[0;m[1;38m handover_decision_2.c:762 (lchan 0.010 TCH_F
SPEECH_AMR)->(BTS 5) (subscr IMSI:000001) Triggering handover to TCH/F, due to better
cell
[0;m[1;34mDMSC[0;m handover_fsm.c:203 SUBSCR_CONN(conn4294967295_IMSI000001){ACTIVE}:
Received Event HANDOVER_START
[0;m[1;34mDMSC[0;m bsc_subscr_conn_fsm.c:373
SUBSCR_CONN(conn4294967295_IMSI000001){ACTIVE}: state_chg to HANDOVER
[0;m[1;35m[1;34mDRSL[0;m[1;35m fsm.c:320
handover(conn4294967295_IMSI000001){NOT_STARTED}: Allocated
[0;m[1;35m[1;34mDRSL[0;m[1;35m fsm.c:350
handover(conn4294967295_IMSI000001){NOT_STARTED}: is child of
SUBSCR_CONN(conn4294967295_IMSI000001)
[0;m[1;35m[1;34mDRLL[0;m[1;35m lchan_select.c:163 (bts=5)
lchan_select_by_type(TCH_F)
[0;m[1;35m[1;34mDRLL[0;m[1;35m lchan_select.c:71 looking for lchan TCH/F:
(bts=5,trx=0,ts=0,pchan=CCCH+SDCCH4,state=UNUSED) is != TCH/F
[0;m[1;35m[1;34mDRLL[0;m[1;35m lchan_select.c:86 looking for lchan TCH/F:
(bts=5,trx=0,ts=1,pchan=TCH/F,state=UNUSED) ss=0 is available
[0;m[1;32m[1;32mDCHAN[0;m[1;32m lchan_select.c:226 lchan(5-0-1-TCH_F-0){UNUSED}:
(type=TCH_F) Selected
[0;m[1;35m[1;34mDRSL[0;m[1;35m handover_fsm.c:345
handover(intraBSC_conn4294967295_IMSI000001){NOT_STARTED}: (0-0-1-TCH_F-0-SPEECH_AMR)
--HO-> (5-0-1-TCH/F-0) (subscr IMSI:000001) HO-intraBSC: incrementing rate counter:
handover:attempted Intra-BSC handover attempts.
[0;m[1;35m[1;34mDRSL[0;m[1;35m handover_fsm.c:353
handover(intraBSC_conn4294967295_IMSI000001){NOT_STARTED}: (0-0-1-TCH_F-0-SPEECH_AMR)
--HO-> (5-0-1-TCH/F-0) (subscr IMSI:000001) HO-intraBSC: Selected lchan
(bts=5,trx=0,ts=1,ss=0)
[0;m[1;35m[1;34mDRSL[0;m[1;35m handover_fsm.c:355
handover(intraBSC_conn4294967295_IMSI000001){NOT_STARTED}: state_chg to WAIT_LCHAN_ACTIVE
[0;m[1;32m[1;34mDCHAN[0;m[1;32m lchan_fsm.c:324 lchan(5-0-1-TCH_F-0){UNUSED}:
Received Event LCHAN_EV_ACTIVATE
[0;m[1;32m[1;34mDCHAN[0;m[1;32m lchan_fsm.c:496 lchan(5-0-1-TCH_F-0){UNUSED}:
state_chg to WAIT_TS_READY
[0;m[1;32m[1;31mDCHAN[0;m[1;32m lchan_fsm.c:440 lchan(5-0-1-TCH_F-0){WAIT_TS_READY}:
(type=TCH_F) can not encode multirate configuration (invalid amr rate setting, MSC)
[0;m[1;32m[1;34mDCHAN[0;m[1;32m lchan_fsm.c:544 lchan(5-0-1-TCH_F-0){WAIT_TS_READY}:
(type=TCH_F) Handling failure, will then transition to state UNUSED
[0;m[1;32m[1;31mDCHAN[0;m[1;32m lchan_fsm.c:78 lchan(5-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
[0;m[1;32m[1;33mDCHAN[0;m[1;32m lchan_fsm.c:120 lchan(5-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
)
[0;m[1;35m[1;34mDRSL[0;m[1;35m lchan_fsm.c:544
handover(intraBSC_conn4294967295_IMSI000001){WAIT_LCHAN_ACTIVE}: Received Event
HO_EV_LCHAN_ERROR
[0;m[1;35m[1;31mDRSL[0;m[1;35m handover_fsm.c:1267
handover(intraBSC_conn4294967295_IMSI000001){WAIT_LCHAN_ACTIVE}:
(0-0-1-TCH_F-0-SPEECH_AMR) --HO-> (5-0-1-TCH/F-0) (subscr IMSI:000001) HO-intraBSC:
Handover failed in state HANDOVER, Failure: Error while establishing lchan
(bts=5,trx=0,ts=1,ss=0)
[0;m[1;35m[1;33mDRSL[0;m[1;35m handover_decision_2.c:1929
handover(intraBSC_conn4294967295_IMSI000001){WAIT_LCHAN_ACTIVE}:
(0-0-1-TCH_F-0-SPEECH_AMR) --HO-> (5-0-1-TCH/F-0) (subscr IMSI:000001) HO-intraBSC:
Failed, starting penalty timer (60 s)
[0;m[1;35m[1;34mDRSL[0;m[1;35m handover_fsm.c:811
handover(intraBSC_conn4294967295_IMSI000001){WAIT_LCHAN_ACTIVE}:
(0-0-1-TCH_F-0-SPEECH_AMR) --HO-> (5-0-1-TCH/F-0) (subscr IMSI:000001) HO-intraBSC:
incrementing rate counter: handover:error Re-assigment failed for other reason.
[0;m[1;35m[1;32mDRSL[0;m[1;35m handover_fsm.c:813
handover(intraBSC_conn4294967295_IMSI000001){WAIT_LCHAN_ACTIVE}:
(0-0-1-TCH_F-0-SPEECH_AMR) --HO-> (5-0-1-TCH/F-0) (subscr IMSI:000001) HO-intraBSC:
Result: Failure
[0;m[1;34mDMSC[0;m handover_fsm.c:820 SUBSCR_CONN(conn4294967295_IMSI000001){HANDOVER}:
Received Event HANDOVER_END
[0;m[1;34mDMSC[0;m bsc_subscr_conn_fsm.c:423
SUBSCR_CONN(conn4294967295_IMSI000001){HANDOVER}: state_chg to ACTIVE
[0;m[1;32m[1;34mDCHAN[0;m[1;32m lchan_fsm.c:1351 lchan(5-0-1-TCH_F-0){WAIT_TS_READY}:
state_chg to WAIT_RLL_RTP_RELEASED
[0;m[1;35m[1;34mDRSL[0;m[1;35m abis_rsl.c:633 (bts=5,trx=0,ts=1,ss=0) DEACTivate
SACCH CMD
[0;m[1;32m[1;34mDCHAN[0;m[1;32m lchan_fsm.c:988
lchan(5-0-1-TCH_F-0){WAIT_RLL_RTP_RELEASED}: state_chg to WAIT_BEFORE_RF_RELEASE
[0;m[1;35m[1;34mDRSL[0;m[1;35m handover_fsm.c:842
handover(intraBSC_conn4294967295_IMSI000001){WAIT_LCHAN_ACTIVE}: Terminating (cause =
OSMO_FSM_TERM_REGULAR)
[0;m[1;35m[1;34mDRSL[0;m[1;35m handover_fsm.c:842
handover(intraBSC_conn4294967295_IMSI000001){WAIT_LCHAN_ACTIVE}: Freeing instance
[0;m[1;35m[1;34mDRSL[0;m[1;35m fsm.c:402
handover(intraBSC_conn4294967295_IMSI000001){WAIT_LCHAN_ACTIVE}: Deallocated
[0;m[1;32m[1;34mDCHAN[0;m[1;32m lchan_fsm.c:544
lchan(5-0-1-TCH_F-0){WAIT_BEFORE_RF_RELEASE}: state_chg to UNUSED
[0;m[1;32m[1;34mDCHAN[0;m[1;32m lchan_fsm.c:377 lchan(5-0-1-TCH_F-0){UNUSED}:
(type=TCH_F) Clearing lchan state
[0;m[1;31m[1;34mDTS[0;m[1;31m lchan_fsm.c:410 timeslot(5-0-1-TCH_F){UNUSED}: Received
Event TS_EV_LCHAN_UNUSED
[0;m- Expecting channel request at BTS 5 TS 1
--- expout 2019-03-11 13:26:43.089059519 +0000
+++ /build/tests/testsuite.dir/at-groups/34/stdout 2019-03-11 13:26:43.105059419 +0000
@@ -1 +1 @@
-Test OK
+Test failed, because no channel was requested
./testsuite.at:207: exit code was 1, expected 0
34. testsuite.at:204: 34. handover test 26 (testsuite.at:204): FAILED (testsuite.at:207)
Build step 'Execute shell' marked build as failure
[WARNINGS]Skipping publisher since build result is FAILURE