See
<https://jenkins.osmocom.org/jenkins/job/master-osmo-bsc/a1=default,a2=default,a3=default,a4=default,label=osmocom-master-debian9/5496/display/redirect>
------------------------------------------
[...truncated 1.53 MB...]
[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(0-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(0-0-1-TCH_F){UNUSED}: Allocated
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:350 lchan(0-0-1-TCH_F){UNUSED}: is child of
timeslot(0-0-1-TCH_F)
[0;m[1;32m[1;34mDCHAN[0;m[1;32m lchan_fsm.c:365 lchan(0-0-1-TCH_F-0){UNUSED}: new
lchan
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:217
timeslot(0-0-1-TCH_F){NOT_INITIALIZED}: lchans initialized: 1
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:263
timeslot(0-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(0-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(0-0-2-TCH_F){UNUSED}: Allocated
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:350 lchan(0-0-2-TCH_F){UNUSED}: is child of
timeslot(0-0-2-TCH_F)
[0;m[1;32m[1;34mDCHAN[0;m[1;32m lchan_fsm.c:365 lchan(0-0-2-TCH_F-0){UNUSED}: new
lchan
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:217
timeslot(0-0-2-TCH_F){NOT_INITIALIZED}: lchans initialized: 1
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:263
timeslot(0-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(0-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(0-0-3-TCH_F){UNUSED}: Allocated
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:350 lchan(0-0-3-TCH_F){UNUSED}: is child of
timeslot(0-0-3-TCH_F)
[0;m[1;32m[1;34mDCHAN[0;m[1;32m lchan_fsm.c:365 lchan(0-0-3-TCH_F-0){UNUSED}: new
lchan
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:217
timeslot(0-0-3-TCH_F){NOT_INITIALIZED}: lchans initialized: 1
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:263
timeslot(0-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(0-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(0-0-4-TCH_F){UNUSED}: Allocated
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:350 lchan(0-0-4-TCH_F){UNUSED}: is child of
timeslot(0-0-4-TCH_F)
[0;m[1;32m[1;34mDCHAN[0;m[1;32m lchan_fsm.c:365 lchan(0-0-4-TCH_F-0){UNUSED}: new
lchan
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:217
timeslot(0-0-4-TCH_F){NOT_INITIALIZED}: lchans initialized: 1
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:263
timeslot(0-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(0-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(0-0-5-TCH_H){UNUSED}: Allocated
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:350 lchan(0-0-5-TCH_H){UNUSED}: is child of
timeslot(0-0-5-TCH_H)
[0;m[1;32m[1;34mDCHAN[0;m[1;32m lchan_fsm.c:365 lchan(0-0-5-TCH_H-0){UNUSED}: new
lchan
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:320 lchan(0-0-5-TCH_H){UNUSED}: Allocated
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:350 lchan(0-0-5-TCH_H){UNUSED}: is child of
timeslot(0-0-5-TCH_H)
[0;m[1;32m[1;34mDCHAN[0;m[1;32m lchan_fsm.c:365 lchan(0-0-5-TCH_H-1){UNUSED}: new
lchan
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:217
timeslot(0-0-5-TCH_H){NOT_INITIALIZED}: lchans initialized: 2
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:263
timeslot(0-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(0-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(0-0-6-TCH_H){UNUSED}: Allocated
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:350 lchan(0-0-6-TCH_H){UNUSED}: is child of
timeslot(0-0-6-TCH_H)
[0;m[1;32m[1;34mDCHAN[0;m[1;32m lchan_fsm.c:365 lchan(0-0-6-TCH_H-0){UNUSED}: new
lchan
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:320 lchan(0-0-6-TCH_H){UNUSED}: Allocated
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:350 lchan(0-0-6-TCH_H){UNUSED}: is child of
timeslot(0-0-6-TCH_H)
[0;m[1;32m[1;34mDCHAN[0;m[1;32m lchan_fsm.c:365 lchan(0-0-6-TCH_H-1){UNUSED}: new
lchan
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:217
timeslot(0-0-6-TCH_H){NOT_INITIALIZED}: lchans initialized: 2
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:263
timeslot(0-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(0-0-7-NONE){NOT_INITIALIZED}: max lchans: 0
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:217
timeslot(0-0-7-NONE){NOT_INITIALIZED}: lchans initialized: 0
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:263
timeslot(0-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(1-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(1-0-0-CCCH_SDCCH4){UNUSED}:
Allocated
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:350 lchan(1-0-0-CCCH_SDCCH4){UNUSED}: is child
of timeslot(1-0-0-CCCH_SDCCH4)
[0;m[1;32m[1;34mDCHAN[0;m[1;32m lchan_fsm.c:365 lchan(1-0-0-CCCH_SDCCH4-0){UNUSED}:
new lchan
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:320 lchan(1-0-0-CCCH_SDCCH4){UNUSED}:
Allocated
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:350 lchan(1-0-0-CCCH_SDCCH4){UNUSED}: is child
of timeslot(1-0-0-CCCH_SDCCH4)
[0;m[1;32m[1;34mDCHAN[0;m[1;32m lchan_fsm.c:365 lchan(1-0-0-CCCH_SDCCH4-1){UNUSED}:
new lchan
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:320 lchan(1-0-0-CCCH_SDCCH4){UNUSED}:
Allocated
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:350 lchan(1-0-0-CCCH_SDCCH4){UNUSED}: is child
of timeslot(1-0-0-CCCH_SDCCH4)
[0;m[1;32m[1;34mDCHAN[0;m[1;32m lchan_fsm.c:365 lchan(1-0-0-CCCH_SDCCH4-2){UNUSED}:
new lchan
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:320 lchan(1-0-0-CCCH_SDCCH4){UNUSED}:
Allocated
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:350 lchan(1-0-0-CCCH_SDCCH4){UNUSED}: is child
of timeslot(1-0-0-CCCH_SDCCH4)
[0;m[1;32m[1;34mDCHAN[0;m[1;32m lchan_fsm.c:365 lchan(1-0-0-CCCH_SDCCH4-3){UNUSED}:
new lchan
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:217
timeslot(1-0-0-CCCH_SDCCH4){NOT_INITIALIZED}: lchans initialized: 4
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:263
timeslot(1-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(1-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(1-0-1-TCH_F){UNUSED}: Allocated
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:350 lchan(1-0-1-TCH_F){UNUSED}: is child of
timeslot(1-0-1-TCH_F)
[0;m[1;32m[1;34mDCHAN[0;m[1;32m lchan_fsm.c:365 lchan(1-0-1-TCH_F-0){UNUSED}: new
lchan
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:217
timeslot(1-0-1-TCH_F){NOT_INITIALIZED}: lchans initialized: 1
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:263
timeslot(1-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(1-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(1-0-2-TCH_F){UNUSED}: Allocated
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:350 lchan(1-0-2-TCH_F){UNUSED}: is child of
timeslot(1-0-2-TCH_F)
[0;m[1;32m[1;34mDCHAN[0;m[1;32m lchan_fsm.c:365 lchan(1-0-2-TCH_F-0){UNUSED}: new
lchan
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:217
timeslot(1-0-2-TCH_F){NOT_INITIALIZED}: lchans initialized: 1
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:263
timeslot(1-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(1-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(1-0-3-TCH_F){UNUSED}: Allocated
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:350 lchan(1-0-3-TCH_F){UNUSED}: is child of
timeslot(1-0-3-TCH_F)
[0;m[1;32m[1;34mDCHAN[0;m[1;32m lchan_fsm.c:365 lchan(1-0-3-TCH_F-0){UNUSED}: new
lchan
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:217
timeslot(1-0-3-TCH_F){NOT_INITIALIZED}: lchans initialized: 1
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:263
timeslot(1-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(1-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(1-0-4-TCH_F){UNUSED}: Allocated
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:350 lchan(1-0-4-TCH_F){UNUSED}: is child of
timeslot(1-0-4-TCH_F)
[0;m[1;32m[1;34mDCHAN[0;m[1;32m lchan_fsm.c:365 lchan(1-0-4-TCH_F-0){UNUSED}: new
lchan
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:217
timeslot(1-0-4-TCH_F){NOT_INITIALIZED}: lchans initialized: 1
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:263
timeslot(1-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(1-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(1-0-5-TCH_H){UNUSED}: Allocated
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:350 lchan(1-0-5-TCH_H){UNUSED}: is child of
timeslot(1-0-5-TCH_H)
[0;m[1;32m[1;34mDCHAN[0;m[1;32m lchan_fsm.c:365 lchan(1-0-5-TCH_H-0){UNUSED}: new
lchan
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:320 lchan(1-0-5-TCH_H){UNUSED}: Allocated
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:350 lchan(1-0-5-TCH_H){UNUSED}: is child of
timeslot(1-0-5-TCH_H)
[0;m[1;32m[1;34mDCHAN[0;m[1;32m lchan_fsm.c:365 lchan(1-0-5-TCH_H-1){UNUSED}: new
lchan
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:217
timeslot(1-0-5-TCH_H){NOT_INITIALIZED}: lchans initialized: 2
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:263
timeslot(1-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(1-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(1-0-6-TCH_H){UNUSED}: Allocated
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:350 lchan(1-0-6-TCH_H){UNUSED}: is child of
timeslot(1-0-6-TCH_H)
[0;m[1;32m[1;34mDCHAN[0;m[1;32m lchan_fsm.c:365 lchan(1-0-6-TCH_H-0){UNUSED}: new
lchan
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:320 lchan(1-0-6-TCH_H){UNUSED}: Allocated
[0;m[1;32m[1;34mDCHAN[0;m[1;32m fsm.c:350 lchan(1-0-6-TCH_H){UNUSED}: is child of
timeslot(1-0-6-TCH_H)
[0;m[1;32m[1;34mDCHAN[0;m[1;32m lchan_fsm.c:365 lchan(1-0-6-TCH_H-1){UNUSED}: new
lchan
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:217
timeslot(1-0-6-TCH_H){NOT_INITIALIZED}: lchans initialized: 2
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:263
timeslot(1-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(1-0-7-NONE){NOT_INITIALIZED}: max lchans: 0
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:217
timeslot(1-0-7-NONE){NOT_INITIALIZED}: lchans initialized: 0
[0;m[1;31m[1;34mDTS[0;m[1;31m timeslot_fsm.c:263
timeslot(1-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
- 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)
[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=1
[0;m[1;34mDMEAS[0;m abis_rsl.c:964 IDX=0 ARFCN=871 BSIC=63 => -80 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 (1 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=30 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=30 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 -> -80; TCH/F={free 4 (want
4), [A] more congestion}; TCH/H={free 4 (want 4), [A] more congestion}
[0;m[1;38m[1;32mDHODEC[0;m[1;38m handover_decision_2.c:1177 (lchan 0.010 TCH_F
SPEECH_AMR) (subscr IMSI:000001) No better/less congested neighbor cell found
[0;m- 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)
[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++=2 meas_rep_last_seen_nr=1
[0;m[1;34mDMEAS[0;m abis_rsl.c:931 [IMSI:000001] MEASUREMENT RESULT NR=1
[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=-101dBm
RXL-SUB-dl=-101dBm [0;mRXQ-FULL-dl=0 RXQ-SUB-dl=0 [0;mNUM_NEIGH=1
[0;m[1;34mDMEAS[0;m abis_rsl.c:964 IDX=0 ARFCN=871 BSIC=63 => -80 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 (1 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=30 flags=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 = -101 dBm, +0 dBm AFS bias = -101 dBm; Avg
RX quality = -1 (invalid), +0 AFS bias = -1
[0;m[1;38m[1;33mDHODEC[0;m[1;38m handover_decision_2.c:1332 (lchan 0.010 TCH_F
SPEECH_AMR) (subscr IMSI:000001) RX level is TOO LOW: -101 < -100
[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 -101; 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 -101 -> -80; TCH/F={free 4 (want
4), [A] more congestion}; TCH/H={free 4 (want 4), [A] more congestion}
[0;m[1;38m[1;32mDHODEC[0;m[1;38m handover_decision_2.c:1210 (lchan 0.010 TCH_F
SPEECH_AMR)->(BTS 1) (subscr IMSI:000001) Best candidate: RX level -80
[0;m[1;38m[1;32mDHODEC[0;m[1;38m 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
[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=1)
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=1,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=1,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(1-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-> (1-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-> (1-0-1-TCH/F-0) (subscr IMSI:000001) HO-intraBSC: Selected lchan
(bts=1,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(1-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(1-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(1-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(1-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(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
[0;m[1;32m[1;33mDCHAN[0;m[1;32m 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
)
[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-> (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)
[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-> (1-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-> (1-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-> (1-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(1-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=1,trx=0,ts=1,ss=0) DEACTivate
SACCH CMD
[0;m[1;32m[1;34mDCHAN[0;m[1;32m lchan_fsm.c:988
lchan(1-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(1-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(1-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(1-0-1-TCH_F){UNUSED}: Received
Event TS_EV_LCHAN_UNUSED
[0;m- Expecting channel request at BTS 1 TS 1
--- expout 2019-03-11 16:11:12.631083824 +0000
+++ /build/tests/testsuite.dir/at-groups/22/stdout 2019-03-11 16:11:12.643083672 +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