cellular-infrastructure
/
osmo-pcu
mirror of https://gerrit.osmocom.org/osmo-pcu
9
0
Fork 1
Code Issues Releases Wiki Activity

Make sure GprsMs free() also frees its tbfs 

This fixes TBF objects leaking and ending up alive when the MS object is
explicitly freed through talloc_free (and sporadically
crashing TbfTest once a timeout for them occur).

This mostly affects unit tests, where most of the explicit free()
happens.
In osmo-pcu, in general, the GprsMs object only gets _free() called when
its resource count reaches 0, aka no more TBFs are attached to it. Hence
in general GprsMs object is freed() only when no TBFs (to be leaked) are
present.
However, in the unit tests it's usual that we want to wipe the entire
context by eg. feeing the PCU, the BTS or MS object, which should also
free the related TBFs.

When running osmo-pcu this may only be an issue when the MS object is
freed explicitly, which could happen for instance when a BTS is torn down,
ie. PCUIF going down, moment at which all GprsMs of that BTS are freed.
But in there actually it iterates over PDCHs to free all TBFs, so it's
fine.
If we iterated over MS, this could have ended up in a crash, like
it happened in TbfTest sporadically, but it's not a bit problem if we
crash + restart at that time since anyway the BTS is gone ore just
getting up around that time.

Related: OS#6359
Change-Id: Ibbdec94acb8132be20508d3178d88da44bfaf91d
This commit is contained in:
Pau Espin 2024-03-25 20:13:47 +01:00
parent 5eb7699b6d
commit d76e3a3427
8 changed files with 4956 additions and 94 deletions
Show Stats Download Patch File Download Diff File Expand all files Collapse all files

10
src/gprs_ms.c
View File

@ -193,18 +193,18 @@ static int ms_talloc_destructor(struct GprsMs *ms)
osmo_timer_del(&ms->release_timer);
if (ms->ul_tbf) {
tbf_set_ms(ul_tbf_as_tbf(ms->ul_tbf), NULL);
ms->ul_tbf = NULL;
tbf_free(ul_tbf_as_tbf(ms->ul_tbf));
OSMO_ASSERT(ms->ul_tbf == NULL);
}
if (ms->dl_tbf) {
tbf_set_ms(dl_tbf_as_tbf(ms->dl_tbf), NULL);
ms->dl_tbf = NULL;
tbf_free(dl_tbf_as_tbf(ms->dl_tbf));
OSMO_ASSERT(ms->dl_tbf == NULL);
}
llist_for_each_entry_safe(pos, tmp, &ms->old_tbfs, list) {
struct gprs_rlcmac_tbf *tbf = (struct gprs_rlcmac_tbf *)pos->entry;
tbf_set_ms(tbf, NULL);
tbf_free(tbf);
}
llc_queue_clear(&ms->llc_queue, ms->bts);

4344
tests/alloc/AllocTest.err
View File

File diff suppressed because it is too large Load Diff

13
tests/ms/MsTest.cpp
View File

@ -443,8 +443,6 @@ static void test_ms_cs_selection()
dl_tbf = alloc_dl_tbf(bts, ms);
ms_attach_tbf(ms, dl_tbf);
ms_unref(ms, __func__);
OSMO_ASSERT(!ms_is_idle(ms));
OSMO_ASSERT(mcs_chan_code(ms_current_cs_dl(ms, ms_mode(ms))) == 3);
@ -453,8 +451,10 @@ static void test_ms_cs_selection()
OSMO_ASSERT(mcs_chan_code(ms_current_cs_dl(ms, ms_mode(ms))) == 2);
talloc_free(ms);
ms_detach_tbf(ms, dl_tbf);
talloc_free(dl_tbf);
ms_unref(ms, __func__);
/* MS has been freed here*/
talloc_free(bts);
printf("=== end %s ===\n", __func__);
}
@ -513,9 +513,8 @@ static void test_ms_mcs_mode()
ms_set_mode(ms2, EGPRS_GMSK);
dump_ms(ms2, "2: after mode set ");
// FIXME: following code triggers ASAN failure:
// ms2->detach_tbf(dl_tbf);
// dump_ms(ms2, "2: after TBF detach ");
ms_detach_tbf(ms2, dl_tbf);
dump_ms(ms2, "2: after TBF detach ");
ms_set_mode(ms1, GPRS);
dump_ms(ms1, "1: after mode set ");
@ -523,9 +522,9 @@ static void test_ms_mcs_mode()
ms_set_mode(ms2, GPRS);
dump_ms(ms2, "2: after mode set ");
talloc_free(dl_tbf);
talloc_free(ms1);
talloc_free(ms2);
talloc_free(dl_tbf);
talloc_free(bts);
printf("=== end %s ===\n", __func__);
}

8
tests/ms/MsTest.err
View File

@ -68,9 +68,9 @@ Creating MS object
MS(TA-220:MSCLS-0-0): + test_ms_cs_selection: now used by 1 (test_ms_cs_selection)
The MS object cannot fully confirm an unexpected TLLI: 0xffeeddbb, partly confirmed
MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0) Attaching DL TBF: TBF(DL:G:TLLI-0xffeeddbb){NEW}
MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0:DL) Destroying MS object
MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0:DL) Detaching TBF: TBF(DL:G:TLLI-0xffeeddbb){NEW}
MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0): - tbf: now used by 0 (-)
MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0): - test_ms_cs_selection: now used by 0 (-)
MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0) Destroying MS object
Creating MS object
MS(TA-220:MSCLS-0-0): + test_ms_mcs_mode: now used by 1 (test_ms_mcs_mode)
The MS object cannot fully confirm an unexpected TLLI: 0xdeadbeef, partly confirmed
@ -78,6 +78,6 @@ Creating MS object
MS(TA-220:MSCLS-0-0): + test_ms_mcs_mode: now used by 1 (test_ms_mcs_mode)
The MS object cannot fully confirm an unexpected TLLI: 0xdeadbef0, partly confirmed
MS(TLLI-0xdeadbef0:TA-220:MSCLS-0-0) Attaching DL TBF: TBF(DL:G:TLLI-0xdeadbef0){NEW}
MS(TLLI-0xdeadbeef:TA-220:MSCLS-0-0) Destroying MS object
MS(TLLI-0xdeadbef0:TA-220:MSCLS-0-0:DL) Destroying MS object
MS(TLLI-0xdeadbef0:TA-220:MSCLS-0-0:DL) Detaching TBF: TBF(DL:G:TLLI-0xdeadbef0){NEW}
MS(TLLI-0xdeadbeef:TA-220:MSCLS-0-0) Destroying MS object
MS(TLLI-0xdeadbef0:TA-220:MSCLS-0-0) Destroying MS object

3
tests/ms/MsTest.ok
View File

@ -20,6 +20,7 @@
2: after MCS set MS DL MCS-8/MCS-9, UL MCS-1/MCS-9, mode EGPRS, <ACTIVE>
1: after mode set MS DL MCS-1/MCS-4, UL MCS-1/MCS-4, mode EGPRS_GMSK-only, <IDLE>
2: after mode set MS DL MCS-1/MCS-4, UL MCS-1/MCS-4, mode EGPRS_GMSK-only, <ACTIVE>
2: after TBF detach MS DL MCS-1/MCS-4, UL MCS-1/MCS-4, mode EGPRS_GMSK-only, <IDLE>
1: after mode set MS DL CS-4/CS-4, UL CS-1/CS-4, mode GPRS, <IDLE>
2: after mode set MS DL CS-4/CS-4, UL CS-1/CS-4, mode GPRS, <ACTIVE>
2: after mode set MS DL CS-4/CS-4, UL CS-1/CS-4, mode GPRS, <IDLE>
=== end test_ms_mcs_mode ===

650
tests/tbf/TbfTest.err
View File

@ -61,10 +61,23 @@ Modifying MS object, TLLI: 0x00002342 -> 0x00004232, already confirmed partly
Modifying MS object, TLLI = 0x00004232, TA 4 -> 6
=== end test_tbf_tlli_update ===
MS(TLLI-0x00004232:TA-6:MSCLS-0-0:UL:DL) Destroying MS object
MS(TLLI-0x00004232:TA-6:MSCLS-0-0:UL:DL) Detaching TBF: TBF(UL:TFI-0-0-0:G:TLLI-0x00004232){ASSIGN}
TBF(UL:TFI-0-0-0:G:TLLI-0x00004232){ASSIGN} free
PDCH(bts=0,trx=0,ts=2) Detaching TBF(UL:TFI-0-0-0:G:TLLI-0x00004232){ASSIGN}, 1 TBFs, USFs = 01, TFIs = 00000001.
MS(TLLI-0x00004232:TA-6:MSCLS-0-0:UL:DL) Detaching TBF: TBF(UL:G:TLLI-0x00004232){ASSIGN}
MS(TLLI-0x00004232:TA-6:MSCLS-0-0:DL): - tbf: now used by 1 (tbf)
MS(TLLI-0x00004232:TA-6:MSCLS-0-0:DL) Detaching TBF: TBF(DL:TFI-0-0-0:G:TLLI-0x00004232){ASSIGN}
********** UL-TBF ends here **********
UL_ACK_TBF(UL:TFI-0-0-0:G:TLLI-0x00004232){NONE}: Deallocated
UL_TBF(UL:TFI-0-0-0:G:TLLI-0x00004232){ASSIGN}: Deallocated
UL_ASS_TBF(UL:TFI-0-0-0:G:TLLI-0x00004232){NONE}: Deallocated
DL_ASS_TBF(UL:TFI-0-0-0:G:TLLI-0x00004232){NONE}: Deallocated
TBF(DL:TFI-0-0-0:G:TLLI-0x00004232){ASSIGN} free
PDCH(bts=0,trx=0,ts=2) Detaching TBF(DL:TFI-0-0-0:G:TLLI-0x00004232){ASSIGN}, 1 TBFs, USFs = 00, TFIs = 00000001.
MS(TLLI-0x00004232:TA-6:MSCLS-0-0:DL) Detaching TBF: TBF(DL:G:TLLI-0x00004232){ASSIGN}
MS(TLLI-0x00004232:TA-6:MSCLS-0-0): - tbf: now used by 0 (-)
********** DL-TBF ends here **********
DL_TBF(DL:TFI-0-0-0:G:TLLI-0x00004232){ASSIGN}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-0:G:TLLI-0x00004232){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-0:G:TLLI-0x00004232){NONE}: Deallocated
=== start test_tbf_final_ack ===
PDCH(bts=0,trx=0,ts=4) PDCH state: disabled => enabled
Creating MS object
@ -2028,101 +2041,293 @@ MS(IMSI-001001000000032:TLLI-0xc0000020:TA-220:MSCLS-45-0): - dl_tbf_handle: now
MS(IMSI-001001000000032:TLLI-0xc0000020:TA-220:MSCLS-45-0) Destroying MS object
=== end test_tbf_exhaustion ===
MS(IMSI-001001000000031:TLLI-0xc000001f:TA-220:MSCLS-45-0:DL) Destroying MS object
MS(IMSI-001001000000031:TLLI-0xc000001f:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:TFI-0-0-31:G:IMSI-001001000000031:TLLI-0xc000001f){ASSIGN}
TBF(DL:TFI-0-0-31:G:IMSI-001001000000031:TLLI-0xc000001f){ASSIGN} free
PDCH(bts=0,trx=0,ts=4) Detaching TBF(DL:TFI-0-0-31:G:IMSI-001001000000031:TLLI-0xc000001f){ASSIGN}, 32 TBFs, USFs = 00, TFIs = ffffffff.
MS(IMSI-001001000000031:TLLI-0xc000001f:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:G:IMSI-001001000000031:TLLI-0xc000001f){ASSIGN}
MS(IMSI-001001000000031:TLLI-0xc000001f:TA-220:MSCLS-45-0): - tbf: now used by 0 (-)
********** DL-TBF ends here **********
DL_TBF(DL:TFI-0-0-31:G:IMSI-001001000000031:TLLI-0xc000001f){ASSIGN}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-31:G:IMSI-001001000000031:TLLI-0xc000001f){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-31:G:IMSI-001001000000031:TLLI-0xc000001f){NONE}: Deallocated
MS(IMSI-001001000000030:TLLI-0xc000001e:TA-220:MSCLS-45-0:DL) Destroying MS object
MS(IMSI-001001000000030:TLLI-0xc000001e:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:TFI-0-0-30:G:IMSI-001001000000030:TLLI-0xc000001e){ASSIGN}
TBF(DL:TFI-0-0-30:G:IMSI-001001000000030:TLLI-0xc000001e){ASSIGN} free
PDCH(bts=0,trx=0,ts=4) Detaching TBF(DL:TFI-0-0-30:G:IMSI-001001000000030:TLLI-0xc000001e){ASSIGN}, 31 TBFs, USFs = 00, TFIs = 7fffffff.
MS(IMSI-001001000000030:TLLI-0xc000001e:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:G:IMSI-001001000000030:TLLI-0xc000001e){ASSIGN}
MS(IMSI-001001000000030:TLLI-0xc000001e:TA-220:MSCLS-45-0): - tbf: now used by 0 (-)
********** DL-TBF ends here **********
DL_TBF(DL:TFI-0-0-30:G:IMSI-001001000000030:TLLI-0xc000001e){ASSIGN}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-30:G:IMSI-001001000000030:TLLI-0xc000001e){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-30:G:IMSI-001001000000030:TLLI-0xc000001e){NONE}: Deallocated
MS(IMSI-001001000000029:TLLI-0xc000001d:TA-220:MSCLS-45-0:DL) Destroying MS object
MS(IMSI-001001000000029:TLLI-0xc000001d:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:TFI-0-0-29:G:IMSI-001001000000029:TLLI-0xc000001d){ASSIGN}
TBF(DL:TFI-0-0-29:G:IMSI-001001000000029:TLLI-0xc000001d){ASSIGN} free
PDCH(bts=0,trx=0,ts=4) Detaching TBF(DL:TFI-0-0-29:G:IMSI-001001000000029:TLLI-0xc000001d){ASSIGN}, 30 TBFs, USFs = 00, TFIs = 3fffffff.
MS(IMSI-001001000000029:TLLI-0xc000001d:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:G:IMSI-001001000000029:TLLI-0xc000001d){ASSIGN}
MS(IMSI-001001000000029:TLLI-0xc000001d:TA-220:MSCLS-45-0): - tbf: now used by 0 (-)
********** DL-TBF ends here **********
DL_TBF(DL:TFI-0-0-29:G:IMSI-001001000000029:TLLI-0xc000001d){ASSIGN}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-29:G:IMSI-001001000000029:TLLI-0xc000001d){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-29:G:IMSI-001001000000029:TLLI-0xc000001d){NONE}: Deallocated
MS(IMSI-001001000000028:TLLI-0xc000001c:TA-220:MSCLS-45-0:DL) Destroying MS object
MS(IMSI-001001000000028:TLLI-0xc000001c:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:TFI-0-0-28:G:IMSI-001001000000028:TLLI-0xc000001c){ASSIGN}
TBF(DL:TFI-0-0-28:G:IMSI-001001000000028:TLLI-0xc000001c){ASSIGN} free
PDCH(bts=0,trx=0,ts=4) Detaching TBF(DL:TFI-0-0-28:G:IMSI-001001000000028:TLLI-0xc000001c){ASSIGN}, 29 TBFs, USFs = 00, TFIs = 1fffffff.
MS(IMSI-001001000000028:TLLI-0xc000001c:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:G:IMSI-001001000000028:TLLI-0xc000001c){ASSIGN}
MS(IMSI-001001000000028:TLLI-0xc000001c:TA-220:MSCLS-45-0): - tbf: now used by 0 (-)
********** DL-TBF ends here **********
DL_TBF(DL:TFI-0-0-28:G:IMSI-001001000000028:TLLI-0xc000001c){ASSIGN}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-28:G:IMSI-001001000000028:TLLI-0xc000001c){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-28:G:IMSI-001001000000028:TLLI-0xc000001c){NONE}: Deallocated
MS(IMSI-001001000000027:TLLI-0xc000001b:TA-220:MSCLS-45-0:DL) Destroying MS object
MS(IMSI-001001000000027:TLLI-0xc000001b:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:TFI-0-0-27:G:IMSI-001001000000027:TLLI-0xc000001b){ASSIGN}
TBF(DL:TFI-0-0-27:G:IMSI-001001000000027:TLLI-0xc000001b){ASSIGN} free
PDCH(bts=0,trx=0,ts=4) Detaching TBF(DL:TFI-0-0-27:G:IMSI-001001000000027:TLLI-0xc000001b){ASSIGN}, 28 TBFs, USFs = 00, TFIs = 0fffffff.
MS(IMSI-001001000000027:TLLI-0xc000001b:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:G:IMSI-001001000000027:TLLI-0xc000001b){ASSIGN}
MS(IMSI-001001000000027:TLLI-0xc000001b:TA-220:MSCLS-45-0): - tbf: now used by 0 (-)
********** DL-TBF ends here **********
DL_TBF(DL:TFI-0-0-27:G:IMSI-001001000000027:TLLI-0xc000001b){ASSIGN}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-27:G:IMSI-001001000000027:TLLI-0xc000001b){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-27:G:IMSI-001001000000027:TLLI-0xc000001b){NONE}: Deallocated
MS(IMSI-001001000000026:TLLI-0xc000001a:TA-220:MSCLS-45-0:DL) Destroying MS object
MS(IMSI-001001000000026:TLLI-0xc000001a:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:TFI-0-0-26:G:IMSI-001001000000026:TLLI-0xc000001a){ASSIGN}
TBF(DL:TFI-0-0-26:G:IMSI-001001000000026:TLLI-0xc000001a){ASSIGN} free
PDCH(bts=0,trx=0,ts=4) Detaching TBF(DL:TFI-0-0-26:G:IMSI-001001000000026:TLLI-0xc000001a){ASSIGN}, 27 TBFs, USFs = 00, TFIs = 07ffffff.
MS(IMSI-001001000000026:TLLI-0xc000001a:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:G:IMSI-001001000000026:TLLI-0xc000001a){ASSIGN}
MS(IMSI-001001000000026:TLLI-0xc000001a:TA-220:MSCLS-45-0): - tbf: now used by 0 (-)
********** DL-TBF ends here **********
DL_TBF(DL:TFI-0-0-26:G:IMSI-001001000000026:TLLI-0xc000001a){ASSIGN}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-26:G:IMSI-001001000000026:TLLI-0xc000001a){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-26:G:IMSI-001001000000026:TLLI-0xc000001a){NONE}: Deallocated
MS(IMSI-001001000000025:TLLI-0xc0000019:TA-220:MSCLS-45-0:DL) Destroying MS object
MS(IMSI-001001000000025:TLLI-0xc0000019:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:TFI-0-0-25:G:IMSI-001001000000025:TLLI-0xc0000019){ASSIGN}
TBF(DL:TFI-0-0-25:G:IMSI-001001000000025:TLLI-0xc0000019){ASSIGN} free
PDCH(bts=0,trx=0,ts=4) Detaching TBF(DL:TFI-0-0-25:G:IMSI-001001000000025:TLLI-0xc0000019){ASSIGN}, 26 TBFs, USFs = 00, TFIs = 03ffffff.
MS(IMSI-001001000000025:TLLI-0xc0000019:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:G:IMSI-001001000000025:TLLI-0xc0000019){ASSIGN}
MS(IMSI-001001000000025:TLLI-0xc0000019:TA-220:MSCLS-45-0): - tbf: now used by 0 (-)
********** DL-TBF ends here **********
DL_TBF(DL:TFI-0-0-25:G:IMSI-001001000000025:TLLI-0xc0000019){ASSIGN}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-25:G:IMSI-001001000000025:TLLI-0xc0000019){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-25:G:IMSI-001001000000025:TLLI-0xc0000019){NONE}: Deallocated
MS(IMSI-001001000000024:TLLI-0xc0000018:TA-220:MSCLS-45-0:DL) Destroying MS object
MS(IMSI-001001000000024:TLLI-0xc0000018:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:TFI-0-0-24:G:IMSI-001001000000024:TLLI-0xc0000018){ASSIGN}
TBF(DL:TFI-0-0-24:G:IMSI-001001000000024:TLLI-0xc0000018){ASSIGN} free
PDCH(bts=0,trx=0,ts=4) Detaching TBF(DL:TFI-0-0-24:G:IMSI-001001000000024:TLLI-0xc0000018){ASSIGN}, 25 TBFs, USFs = 00, TFIs = 01ffffff.
MS(IMSI-001001000000024:TLLI-0xc0000018:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:G:IMSI-001001000000024:TLLI-0xc0000018){ASSIGN}
MS(IMSI-001001000000024:TLLI-0xc0000018:TA-220:MSCLS-45-0): - tbf: now used by 0 (-)
********** DL-TBF ends here **********
DL_TBF(DL:TFI-0-0-24:G:IMSI-001001000000024:TLLI-0xc0000018){ASSIGN}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-24:G:IMSI-001001000000024:TLLI-0xc0000018){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-24:G:IMSI-001001000000024:TLLI-0xc0000018){NONE}: Deallocated
MS(IMSI-001001000000023:TLLI-0xc0000017:TA-220:MSCLS-45-0:DL) Destroying MS object
MS(IMSI-001001000000023:TLLI-0xc0000017:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:TFI-0-0-23:G:IMSI-001001000000023:TLLI-0xc0000017){ASSIGN}
TBF(DL:TFI-0-0-23:G:IMSI-001001000000023:TLLI-0xc0000017){ASSIGN} free
PDCH(bts=0,trx=0,ts=4) Detaching TBF(DL:TFI-0-0-23:G:IMSI-001001000000023:TLLI-0xc0000017){ASSIGN}, 24 TBFs, USFs = 00, TFIs = 00ffffff.
MS(IMSI-001001000000023:TLLI-0xc0000017:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:G:IMSI-001001000000023:TLLI-0xc0000017){ASSIGN}
MS(IMSI-001001000000023:TLLI-0xc0000017:TA-220:MSCLS-45-0): - tbf: now used by 0 (-)
********** DL-TBF ends here **********
DL_TBF(DL:TFI-0-0-23:G:IMSI-001001000000023:TLLI-0xc0000017){ASSIGN}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-23:G:IMSI-001001000000023:TLLI-0xc0000017){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-23:G:IMSI-001001000000023:TLLI-0xc0000017){NONE}: Deallocated
MS(IMSI-001001000000022:TLLI-0xc0000016:TA-220:MSCLS-45-0:DL) Destroying MS object
MS(IMSI-001001000000022:TLLI-0xc0000016:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:TFI-0-0-22:G:IMSI-001001000000022:TLLI-0xc0000016){ASSIGN}
TBF(DL:TFI-0-0-22:G:IMSI-001001000000022:TLLI-0xc0000016){ASSIGN} free
PDCH(bts=0,trx=0,ts=4) Detaching TBF(DL:TFI-0-0-22:G:IMSI-001001000000022:TLLI-0xc0000016){ASSIGN}, 23 TBFs, USFs = 00, TFIs = 007fffff.
MS(IMSI-001001000000022:TLLI-0xc0000016:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:G:IMSI-001001000000022:TLLI-0xc0000016){ASSIGN}
MS(IMSI-001001000000022:TLLI-0xc0000016:TA-220:MSCLS-45-0): - tbf: now used by 0 (-)
********** DL-TBF ends here **********
DL_TBF(DL:TFI-0-0-22:G:IMSI-001001000000022:TLLI-0xc0000016){ASSIGN}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-22:G:IMSI-001001000000022:TLLI-0xc0000016){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-22:G:IMSI-001001000000022:TLLI-0xc0000016){NONE}: Deallocated
MS(IMSI-001001000000021:TLLI-0xc0000015:TA-220:MSCLS-45-0:DL) Destroying MS object
MS(IMSI-001001000000021:TLLI-0xc0000015:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:TFI-0-0-21:G:IMSI-001001000000021:TLLI-0xc0000015){ASSIGN}
TBF(DL:TFI-0-0-21:G:IMSI-001001000000021:TLLI-0xc0000015){ASSIGN} free
PDCH(bts=0,trx=0,ts=4) Detaching TBF(DL:TFI-0-0-21:G:IMSI-001001000000021:TLLI-0xc0000015){ASSIGN}, 22 TBFs, USFs = 00, TFIs = 003fffff.
MS(IMSI-001001000000021:TLLI-0xc0000015:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:G:IMSI-001001000000021:TLLI-0xc0000015){ASSIGN}
MS(IMSI-001001000000021:TLLI-0xc0000015:TA-220:MSCLS-45-0): - tbf: now used by 0 (-)
********** DL-TBF ends here **********
DL_TBF(DL:TFI-0-0-21:G:IMSI-001001000000021:TLLI-0xc0000015){ASSIGN}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-21:G:IMSI-001001000000021:TLLI-0xc0000015){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-21:G:IMSI-001001000000021:TLLI-0xc0000015){NONE}: Deallocated
MS(IMSI-001001000000020:TLLI-0xc0000014:TA-220:MSCLS-45-0:DL) Destroying MS object
MS(IMSI-001001000000020:TLLI-0xc0000014:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:TFI-0-0-20:G:IMSI-001001000000020:TLLI-0xc0000014){ASSIGN}
TBF(DL:TFI-0-0-20:G:IMSI-001001000000020:TLLI-0xc0000014){ASSIGN} free
PDCH(bts=0,trx=0,ts=4) Detaching TBF(DL:TFI-0-0-20:G:IMSI-001001000000020:TLLI-0xc0000014){ASSIGN}, 21 TBFs, USFs = 00, TFIs = 001fffff.
MS(IMSI-001001000000020:TLLI-0xc0000014:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:G:IMSI-001001000000020:TLLI-0xc0000014){ASSIGN}
MS(IMSI-001001000000020:TLLI-0xc0000014:TA-220:MSCLS-45-0): - tbf: now used by 0 (-)
********** DL-TBF ends here **********
DL_TBF(DL:TFI-0-0-20:G:IMSI-001001000000020:TLLI-0xc0000014){ASSIGN}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-20:G:IMSI-001001000000020:TLLI-0xc0000014){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-20:G:IMSI-001001000000020:TLLI-0xc0000014){NONE}: Deallocated
MS(IMSI-001001000000019:TLLI-0xc0000013:TA-220:MSCLS-45-0:DL) Destroying MS object
MS(IMSI-001001000000019:TLLI-0xc0000013:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:TFI-0-0-19:G:IMSI-001001000000019:TLLI-0xc0000013){ASSIGN}
TBF(DL:TFI-0-0-19:G:IMSI-001001000000019:TLLI-0xc0000013){ASSIGN} free
PDCH(bts=0,trx=0,ts=4) Detaching TBF(DL:TFI-0-0-19:G:IMSI-001001000000019:TLLI-0xc0000013){ASSIGN}, 20 TBFs, USFs = 00, TFIs = 000fffff.
MS(IMSI-001001000000019:TLLI-0xc0000013:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:G:IMSI-001001000000019:TLLI-0xc0000013){ASSIGN}
MS(IMSI-001001000000019:TLLI-0xc0000013:TA-220:MSCLS-45-0): - tbf: now used by 0 (-)
********** DL-TBF ends here **********
DL_TBF(DL:TFI-0-0-19:G:IMSI-001001000000019:TLLI-0xc0000013){ASSIGN}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-19:G:IMSI-001001000000019:TLLI-0xc0000013){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-19:G:IMSI-001001000000019:TLLI-0xc0000013){NONE}: Deallocated
MS(IMSI-001001000000018:TLLI-0xc0000012:TA-220:MSCLS-45-0:DL) Destroying MS object
MS(IMSI-001001000000018:TLLI-0xc0000012:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:TFI-0-0-18:G:IMSI-001001000000018:TLLI-0xc0000012){ASSIGN}
TBF(DL:TFI-0-0-18:G:IMSI-001001000000018:TLLI-0xc0000012){ASSIGN} free
PDCH(bts=0,trx=0,ts=4) Detaching TBF(DL:TFI-0-0-18:G:IMSI-001001000000018:TLLI-0xc0000012){ASSIGN}, 19 TBFs, USFs = 00, TFIs = 0007ffff.
MS(IMSI-001001000000018:TLLI-0xc0000012:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:G:IMSI-001001000000018:TLLI-0xc0000012){ASSIGN}
MS(IMSI-001001000000018:TLLI-0xc0000012:TA-220:MSCLS-45-0): - tbf: now used by 0 (-)
********** DL-TBF ends here **********
DL_TBF(DL:TFI-0-0-18:G:IMSI-001001000000018:TLLI-0xc0000012){ASSIGN}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-18:G:IMSI-001001000000018:TLLI-0xc0000012){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-18:G:IMSI-001001000000018:TLLI-0xc0000012){NONE}: Deallocated
MS(IMSI-001001000000017:TLLI-0xc0000011:TA-220:MSCLS-45-0:DL) Destroying MS object
MS(IMSI-001001000000017:TLLI-0xc0000011:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:TFI-0-0-17:G:IMSI-001001000000017:TLLI-0xc0000011){ASSIGN}
TBF(DL:TFI-0-0-17:G:IMSI-001001000000017:TLLI-0xc0000011){ASSIGN} free
PDCH(bts=0,trx=0,ts=4) Detaching TBF(DL:TFI-0-0-17:G:IMSI-001001000000017:TLLI-0xc0000011){ASSIGN}, 18 TBFs, USFs = 00, TFIs = 0003ffff.
MS(IMSI-001001000000017:TLLI-0xc0000011:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:G:IMSI-001001000000017:TLLI-0xc0000011){ASSIGN}
MS(IMSI-001001000000017:TLLI-0xc0000011:TA-220:MSCLS-45-0): - tbf: now used by 0 (-)
********** DL-TBF ends here **********
DL_TBF(DL:TFI-0-0-17:G:IMSI-001001000000017:TLLI-0xc0000011){ASSIGN}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-17:G:IMSI-001001000000017:TLLI-0xc0000011){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-17:G:IMSI-001001000000017:TLLI-0xc0000011){NONE}: Deallocated
MS(IMSI-001001000000016:TLLI-0xc0000010:TA-220:MSCLS-45-0:DL) Destroying MS object
MS(IMSI-001001000000016:TLLI-0xc0000010:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:TFI-0-0-16:G:IMSI-001001000000016:TLLI-0xc0000010){ASSIGN}
TBF(DL:TFI-0-0-16:G:IMSI-001001000000016:TLLI-0xc0000010){ASSIGN} free
PDCH(bts=0,trx=0,ts=4) Detaching TBF(DL:TFI-0-0-16:G:IMSI-001001000000016:TLLI-0xc0000010){ASSIGN}, 17 TBFs, USFs = 00, TFIs = 0001ffff.
MS(IMSI-001001000000016:TLLI-0xc0000010:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:G:IMSI-001001000000016:TLLI-0xc0000010){ASSIGN}
MS(IMSI-001001000000016:TLLI-0xc0000010:TA-220:MSCLS-45-0): - tbf: now used by 0 (-)
********** DL-TBF ends here **********
DL_TBF(DL:TFI-0-0-16:G:IMSI-001001000000016:TLLI-0xc0000010){ASSIGN}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-16:G:IMSI-001001000000016:TLLI-0xc0000010){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-16:G:IMSI-001001000000016:TLLI-0xc0000010){NONE}: Deallocated
MS(IMSI-001001000000015:TLLI-0xc000000f:TA-220:MSCLS-45-0:DL) Destroying MS object
MS(IMSI-001001000000015:TLLI-0xc000000f:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:TFI-0-0-15:G:IMSI-001001000000015:TLLI-0xc000000f){ASSIGN}
TBF(DL:TFI-0-0-15:G:IMSI-001001000000015:TLLI-0xc000000f){ASSIGN} free
PDCH(bts=0,trx=0,ts=4) Detaching TBF(DL:TFI-0-0-15:G:IMSI-001001000000015:TLLI-0xc000000f){ASSIGN}, 16 TBFs, USFs = 00, TFIs = 0000ffff.
MS(IMSI-001001000000015:TLLI-0xc000000f:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:G:IMSI-001001000000015:TLLI-0xc000000f){ASSIGN}
MS(IMSI-001001000000015:TLLI-0xc000000f:TA-220:MSCLS-45-0): - tbf: now used by 0 (-)
********** DL-TBF ends here **********
DL_TBF(DL:TFI-0-0-15:G:IMSI-001001000000015:TLLI-0xc000000f){ASSIGN}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-15:G:IMSI-001001000000015:TLLI-0xc000000f){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-15:G:IMSI-001001000000015:TLLI-0xc000000f){NONE}: Deallocated
MS(IMSI-001001000000014:TLLI-0xc000000e:TA-220:MSCLS-45-0:DL) Destroying MS object
MS(IMSI-001001000000014:TLLI-0xc000000e:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:TFI-0-0-14:G:IMSI-001001000000014:TLLI-0xc000000e){ASSIGN}
TBF(DL:TFI-0-0-14:G:IMSI-001001000000014:TLLI-0xc000000e){ASSIGN} free
PDCH(bts=0,trx=0,ts=4) Detaching TBF(DL:TFI-0-0-14:G:IMSI-001001000000014:TLLI-0xc000000e){ASSIGN}, 15 TBFs, USFs = 00, TFIs = 00007fff.
MS(IMSI-001001000000014:TLLI-0xc000000e:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:G:IMSI-001001000000014:TLLI-0xc000000e){ASSIGN}
MS(IMSI-001001000000014:TLLI-0xc000000e:TA-220:MSCLS-45-0): - tbf: now used by 0 (-)
********** DL-TBF ends here **********
DL_TBF(DL:TFI-0-0-14:G:IMSI-001001000000014:TLLI-0xc000000e){ASSIGN}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-14:G:IMSI-001001000000014:TLLI-0xc000000e){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-14:G:IMSI-001001000000014:TLLI-0xc000000e){NONE}: Deallocated
MS(IMSI-001001000000013:TLLI-0xc000000d:TA-220:MSCLS-45-0:DL) Destroying MS object
MS(IMSI-001001000000013:TLLI-0xc000000d:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:TFI-0-0-13:G:IMSI-001001000000013:TLLI-0xc000000d){ASSIGN}
TBF(DL:TFI-0-0-13:G:IMSI-001001000000013:TLLI-0xc000000d){ASSIGN} free
PDCH(bts=0,trx=0,ts=4) Detaching TBF(DL:TFI-0-0-13:G:IMSI-001001000000013:TLLI-0xc000000d){ASSIGN}, 14 TBFs, USFs = 00, TFIs = 00003fff.
MS(IMSI-001001000000013:TLLI-0xc000000d:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:G:IMSI-001001000000013:TLLI-0xc000000d){ASSIGN}
MS(IMSI-001001000000013:TLLI-0xc000000d:TA-220:MSCLS-45-0): - tbf: now used by 0 (-)
********** DL-TBF ends here **********
DL_TBF(DL:TFI-0-0-13:G:IMSI-001001000000013:TLLI-0xc000000d){ASSIGN}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-13:G:IMSI-001001000000013:TLLI-0xc000000d){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-13:G:IMSI-001001000000013:TLLI-0xc000000d){NONE}: Deallocated
MS(IMSI-001001000000012:TLLI-0xc000000c:TA-220:MSCLS-45-0:DL) Destroying MS object
MS(IMSI-001001000000012:TLLI-0xc000000c:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:TFI-0-0-12:G:IMSI-001001000000012:TLLI-0xc000000c){ASSIGN}
TBF(DL:TFI-0-0-12:G:IMSI-001001000000012:TLLI-0xc000000c){ASSIGN} free
PDCH(bts=0,trx=0,ts=4) Detaching TBF(DL:TFI-0-0-12:G:IMSI-001001000000012:TLLI-0xc000000c){ASSIGN}, 13 TBFs, USFs = 00, TFIs = 00001fff.
MS(IMSI-001001000000012:TLLI-0xc000000c:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:G:IMSI-001001000000012:TLLI-0xc000000c){ASSIGN}
MS(IMSI-001001000000012:TLLI-0xc000000c:TA-220:MSCLS-45-0): - tbf: now used by 0 (-)
********** DL-TBF ends here **********
DL_TBF(DL:TFI-0-0-12:G:IMSI-001001000000012:TLLI-0xc000000c){ASSIGN}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-12:G:IMSI-001001000000012:TLLI-0xc000000c){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-12:G:IMSI-001001000000012:TLLI-0xc000000c){NONE}: Deallocated
MS(IMSI-001001000000011:TLLI-0xc000000b:TA-220:MSCLS-45-0:DL) Destroying MS object
MS(IMSI-001001000000011:TLLI-0xc000000b:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:TFI-0-0-11:G:IMSI-001001000000011:TLLI-0xc000000b){ASSIGN}
TBF(DL:TFI-0-0-11:G:IMSI-001001000000011:TLLI-0xc000000b){ASSIGN} free
PDCH(bts=0,trx=0,ts=4) Detaching TBF(DL:TFI-0-0-11:G:IMSI-001001000000011:TLLI-0xc000000b){ASSIGN}, 12 TBFs, USFs = 00, TFIs = 00000fff.
MS(IMSI-001001000000011:TLLI-0xc000000b:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:G:IMSI-001001000000011:TLLI-0xc000000b){ASSIGN}
MS(IMSI-001001000000011:TLLI-0xc000000b:TA-220:MSCLS-45-0): - tbf: now used by 0 (-)
********** DL-TBF ends here **********
DL_TBF(DL:TFI-0-0-11:G:IMSI-001001000000011:TLLI-0xc000000b){ASSIGN}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-11:G:IMSI-001001000000011:TLLI-0xc000000b){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-11:G:IMSI-001001000000011:TLLI-0xc000000b){NONE}: Deallocated
MS(IMSI-001001000000010:TLLI-0xc000000a:TA-220:MSCLS-45-0:DL) Destroying MS object
MS(IMSI-001001000000010:TLLI-0xc000000a:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:TFI-0-0-10:G:IMSI-001001000000010:TLLI-0xc000000a){ASSIGN}
TBF(DL:TFI-0-0-10:G:IMSI-001001000000010:TLLI-0xc000000a){ASSIGN} free
PDCH(bts=0,trx=0,ts=4) Detaching TBF(DL:TFI-0-0-10:G:IMSI-001001000000010:TLLI-0xc000000a){ASSIGN}, 11 TBFs, USFs = 00, TFIs = 000007ff.
MS(IMSI-001001000000010:TLLI-0xc000000a:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:G:IMSI-001001000000010:TLLI-0xc000000a){ASSIGN}
MS(IMSI-001001000000010:TLLI-0xc000000a:TA-220:MSCLS-45-0): - tbf: now used by 0 (-)
********** DL-TBF ends here **********
DL_TBF(DL:TFI-0-0-10:G:IMSI-001001000000010:TLLI-0xc000000a){ASSIGN}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-10:G:IMSI-001001000000010:TLLI-0xc000000a){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-10:G:IMSI-001001000000010:TLLI-0xc000000a){NONE}: Deallocated
MS(IMSI-001001000000009:TLLI-0xc0000009:TA-220:MSCLS-45-0:DL) Destroying MS object
MS(IMSI-001001000000009:TLLI-0xc0000009:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:TFI-0-0-9:G:IMSI-001001000000009:TLLI-0xc0000009){ASSIGN}
TBF(DL:TFI-0-0-9:G:IMSI-001001000000009:TLLI-0xc0000009){ASSIGN} free
PDCH(bts=0,trx=0,ts=4) Detaching TBF(DL:TFI-0-0-9:G:IMSI-001001000000009:TLLI-0xc0000009){ASSIGN}, 10 TBFs, USFs = 00, TFIs = 000003ff.
MS(IMSI-001001000000009:TLLI-0xc0000009:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:G:IMSI-001001000000009:TLLI-0xc0000009){ASSIGN}
MS(IMSI-001001000000009:TLLI-0xc0000009:TA-220:MSCLS-45-0): - tbf: now used by 0 (-)
********** DL-TBF ends here **********
DL_TBF(DL:TFI-0-0-9:G:IMSI-001001000000009:TLLI-0xc0000009){ASSIGN}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-9:G:IMSI-001001000000009:TLLI-0xc0000009){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-9:G:IMSI-001001000000009:TLLI-0xc0000009){NONE}: Deallocated
MS(IMSI-001001000000008:TLLI-0xc0000008:TA-220:MSCLS-45-0:DL) Destroying MS object
MS(IMSI-001001000000008:TLLI-0xc0000008:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:TFI-0-0-8:G:IMSI-001001000000008:TLLI-0xc0000008){ASSIGN}
TBF(DL:TFI-0-0-8:G:IMSI-001001000000008:TLLI-0xc0000008){ASSIGN} free
PDCH(bts=0,trx=0,ts=4) Detaching TBF(DL:TFI-0-0-8:G:IMSI-001001000000008:TLLI-0xc0000008){ASSIGN}, 9 TBFs, USFs = 00, TFIs = 000001ff.
MS(IMSI-001001000000008:TLLI-0xc0000008:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:G:IMSI-001001000000008:TLLI-0xc0000008){ASSIGN}
MS(IMSI-001001000000008:TLLI-0xc0000008:TA-220:MSCLS-45-0): - tbf: now used by 0 (-)
********** DL-TBF ends here **********
DL_TBF(DL:TFI-0-0-8:G:IMSI-001001000000008:TLLI-0xc0000008){ASSIGN}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-8:G:IMSI-001001000000008:TLLI-0xc0000008){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-8:G:IMSI-001001000000008:TLLI-0xc0000008){NONE}: Deallocated
MS(IMSI-001001000000007:TLLI-0xc0000007:TA-220:MSCLS-45-0:DL) Destroying MS object
MS(IMSI-001001000000007:TLLI-0xc0000007:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:TFI-0-0-7:G:IMSI-001001000000007:TLLI-0xc0000007){ASSIGN}
TBF(DL:TFI-0-0-7:G:IMSI-001001000000007:TLLI-0xc0000007){ASSIGN} free
PDCH(bts=0,trx=0,ts=4) Detaching TBF(DL:TFI-0-0-7:G:IMSI-001001000000007:TLLI-0xc0000007){ASSIGN}, 8 TBFs, USFs = 00, TFIs = 000000ff.
MS(IMSI-001001000000007:TLLI-0xc0000007:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:G:IMSI-001001000000007:TLLI-0xc0000007){ASSIGN}
MS(IMSI-001001000000007:TLLI-0xc0000007:TA-220:MSCLS-45-0): - tbf: now used by 0 (-)
********** DL-TBF ends here **********
DL_TBF(DL:TFI-0-0-7:G:IMSI-001001000000007:TLLI-0xc0000007){ASSIGN}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-7:G:IMSI-001001000000007:TLLI-0xc0000007){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-7:G:IMSI-001001000000007:TLLI-0xc0000007){NONE}: Deallocated
MS(IMSI-001001000000006:TLLI-0xc0000006:TA-220:MSCLS-45-0:DL) Destroying MS object
MS(IMSI-001001000000006:TLLI-0xc0000006:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:TFI-0-0-6:G:IMSI-001001000000006:TLLI-0xc0000006){ASSIGN}
TBF(DL:TFI-0-0-6:G:IMSI-001001000000006:TLLI-0xc0000006){ASSIGN} free
PDCH(bts=0,trx=0,ts=4) Detaching TBF(DL:TFI-0-0-6:G:IMSI-001001000000006:TLLI-0xc0000006){ASSIGN}, 7 TBFs, USFs = 00, TFIs = 0000007f.
MS(IMSI-001001000000006:TLLI-0xc0000006:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:G:IMSI-001001000000006:TLLI-0xc0000006){ASSIGN}
MS(IMSI-001001000000006:TLLI-0xc0000006:TA-220:MSCLS-45-0): - tbf: now used by 0 (-)
********** DL-TBF ends here **********
DL_TBF(DL:TFI-0-0-6:G:IMSI-001001000000006:TLLI-0xc0000006){ASSIGN}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-6:G:IMSI-001001000000006:TLLI-0xc0000006){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-6:G:IMSI-001001000000006:TLLI-0xc0000006){NONE}: Deallocated
MS(IMSI-001001000000005:TLLI-0xc0000005:TA-220:MSCLS-45-0:DL) Destroying MS object
MS(IMSI-001001000000005:TLLI-0xc0000005:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:TFI-0-0-5:G:IMSI-001001000000005:TLLI-0xc0000005){ASSIGN}
TBF(DL:TFI-0-0-5:G:IMSI-001001000000005:TLLI-0xc0000005){ASSIGN} free
PDCH(bts=0,trx=0,ts=4) Detaching TBF(DL:TFI-0-0-5:G:IMSI-001001000000005:TLLI-0xc0000005){ASSIGN}, 6 TBFs, USFs = 00, TFIs = 0000003f.
MS(IMSI-001001000000005:TLLI-0xc0000005:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:G:IMSI-001001000000005:TLLI-0xc0000005){ASSIGN}
MS(IMSI-001001000000005:TLLI-0xc0000005:TA-220:MSCLS-45-0): - tbf: now used by 0 (-)
********** DL-TBF ends here **********
DL_TBF(DL:TFI-0-0-5:G:IMSI-001001000000005:TLLI-0xc0000005){ASSIGN}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-5:G:IMSI-001001000000005:TLLI-0xc0000005){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-5:G:IMSI-001001000000005:TLLI-0xc0000005){NONE}: Deallocated
MS(IMSI-001001000000004:TLLI-0xc0000004:TA-220:MSCLS-45-0:DL) Destroying MS object
MS(IMSI-001001000000004:TLLI-0xc0000004:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:TFI-0-0-4:G:IMSI-001001000000004:TLLI-0xc0000004){ASSIGN}
TBF(DL:TFI-0-0-4:G:IMSI-001001000000004:TLLI-0xc0000004){ASSIGN} free
PDCH(bts=0,trx=0,ts=4) Detaching TBF(DL:TFI-0-0-4:G:IMSI-001001000000004:TLLI-0xc0000004){ASSIGN}, 5 TBFs, USFs = 00, TFIs = 0000001f.
MS(IMSI-001001000000004:TLLI-0xc0000004:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:G:IMSI-001001000000004:TLLI-0xc0000004){ASSIGN}
MS(IMSI-001001000000004:TLLI-0xc0000004:TA-220:MSCLS-45-0): - tbf: now used by 0 (-)
********** DL-TBF ends here **********
DL_TBF(DL:TFI-0-0-4:G:IMSI-001001000000004:TLLI-0xc0000004){ASSIGN}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-4:G:IMSI-001001000000004:TLLI-0xc0000004){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-4:G:IMSI-001001000000004:TLLI-0xc0000004){NONE}: Deallocated
MS(IMSI-001001000000003:TLLI-0xc0000003:TA-220:MSCLS-45-0:DL) Destroying MS object
MS(IMSI-001001000000003:TLLI-0xc0000003:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:TFI-0-0-3:G:IMSI-001001000000003:TLLI-0xc0000003){ASSIGN}
TBF(DL:TFI-0-0-3:G:IMSI-001001000000003:TLLI-0xc0000003){ASSIGN} free
PDCH(bts=0,trx=0,ts=4) Detaching TBF(DL:TFI-0-0-3:G:IMSI-001001000000003:TLLI-0xc0000003){ASSIGN}, 4 TBFs, USFs = 00, TFIs = 0000000f.
MS(IMSI-001001000000003:TLLI-0xc0000003:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:G:IMSI-001001000000003:TLLI-0xc0000003){ASSIGN}
MS(IMSI-001001000000003:TLLI-0xc0000003:TA-220:MSCLS-45-0): - tbf: now used by 0 (-)
********** DL-TBF ends here **********
DL_TBF(DL:TFI-0-0-3:G:IMSI-001001000000003:TLLI-0xc0000003){ASSIGN}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-3:G:IMSI-001001000000003:TLLI-0xc0000003){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-3:G:IMSI-001001000000003:TLLI-0xc0000003){NONE}: Deallocated
MS(IMSI-001001000000002:TLLI-0xc0000002:TA-220:MSCLS-45-0:DL) Destroying MS object
MS(IMSI-001001000000002:TLLI-0xc0000002:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:TFI-0-0-2:G:IMSI-001001000000002:TLLI-0xc0000002){ASSIGN}
TBF(DL:TFI-0-0-2:G:IMSI-001001000000002:TLLI-0xc0000002){ASSIGN} free
PDCH(bts=0,trx=0,ts=4) Detaching TBF(DL:TFI-0-0-2:G:IMSI-001001000000002:TLLI-0xc0000002){ASSIGN}, 3 TBFs, USFs = 00, TFIs = 00000007.
MS(IMSI-001001000000002:TLLI-0xc0000002:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:G:IMSI-001001000000002:TLLI-0xc0000002){ASSIGN}
MS(IMSI-001001000000002:TLLI-0xc0000002:TA-220:MSCLS-45-0): - tbf: now used by 0 (-)
********** DL-TBF ends here **********
DL_TBF(DL:TFI-0-0-2:G:IMSI-001001000000002:TLLI-0xc0000002){ASSIGN}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-2:G:IMSI-001001000000002:TLLI-0xc0000002){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-2:G:IMSI-001001000000002:TLLI-0xc0000002){NONE}: Deallocated
MS(IMSI-001001000000001:TLLI-0xc0000001:TA-220:MSCLS-45-0:DL) Destroying MS object
MS(IMSI-001001000000001:TLLI-0xc0000001:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:TFI-0-0-1:G:IMSI-001001000000001:TLLI-0xc0000001){ASSIGN}
TBF(DL:TFI-0-0-1:G:IMSI-001001000000001:TLLI-0xc0000001){ASSIGN} free
PDCH(bts=0,trx=0,ts=4) Detaching TBF(DL:TFI-0-0-1:G:IMSI-001001000000001:TLLI-0xc0000001){ASSIGN}, 2 TBFs, USFs = 00, TFIs = 00000003.
MS(IMSI-001001000000001:TLLI-0xc0000001:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:G:IMSI-001001000000001:TLLI-0xc0000001){ASSIGN}
MS(IMSI-001001000000001:TLLI-0xc0000001:TA-220:MSCLS-45-0): - tbf: now used by 0 (-)
********** DL-TBF ends here **********
DL_TBF(DL:TFI-0-0-1:G:IMSI-001001000000001:TLLI-0xc0000001){ASSIGN}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-1:G:IMSI-001001000000001:TLLI-0xc0000001){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-1:G:IMSI-001001000000001:TLLI-0xc0000001){NONE}: Deallocated
MS(IMSI-001001000000000:TLLI-0xc0000000:TA-220:MSCLS-45-0:DL) Destroying MS object
MS(IMSI-001001000000000:TLLI-0xc0000000:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:TFI-0-0-0:G:IMSI-001001000000000:TLLI-0xc0000000){ASSIGN}
TBF(DL:TFI-0-0-0:G:IMSI-001001000000000:TLLI-0xc0000000){ASSIGN} free
PDCH(bts=0,trx=0,ts=4) Detaching TBF(DL:TFI-0-0-0:G:IMSI-001001000000000:TLLI-0xc0000000){ASSIGN}, 1 TBFs, USFs = 00, TFIs = 00000001.
MS(IMSI-001001000000000:TLLI-0xc0000000:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:G:IMSI-001001000000000:TLLI-0xc0000000){ASSIGN}
MS(IMSI-001001000000000:TLLI-0xc0000000:TA-220:MSCLS-45-0): - tbf: now used by 0 (-)
********** DL-TBF ends here **********
DL_TBF(DL:TFI-0-0-0:G:IMSI-001001000000000:TLLI-0xc0000000){ASSIGN}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-0:G:IMSI-001001000000000:TLLI-0xc0000000){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-0:G:IMSI-001001000000000:TLLI-0xc0000000){NONE}: Deallocated
=== start test_tbf_dl_llc_loss ===
PDCH(bts=0,trx=0,ts=4) PDCH state: disabled => enabled
Creating MS object
@ -2240,8 +2445,15 @@ TBF(DL:TFI-0-0-0:G:IMSI-001001000123456:TLLI-0xc0123456){FINISHED} msg block (BS
MSG = 1f 01 04 4d 03 03 03 03 03 03 03 03 03 03 03 03 03 03 03 03 03 03 03
=== end test_tbf_dl_llc_loss ===
MS(IMSI-001001000123456:TLLI-0xc0123456:TA-0:MSCLS-45-0:DL) Destroying MS object
MS(IMSI-001001000123456:TLLI-0xc0123456:TA-0:MSCLS-45-0:DL) Detaching TBF: TBF(DL:TFI-0-0-0:G:IMSI-001001000123456:TLLI-0xc0123456){FINISHED}
TBF(DL:TFI-0-0-0:G:IMSI-001001000123456:TLLI-0xc0123456){FINISHED} free
TBF(DL:TFI-0-0-0:G:IMSI-001001000123456:TLLI-0xc0123456){FINISHED} stopping timer T3191 [freeing TBF]
PDCH(bts=0,trx=0,ts=4) Detaching TBF(DL:TFI-0-0-0:G:IMSI-001001000123456:TLLI-0xc0123456){FINISHED}, 1 TBFs, USFs = 00, TFIs = 00000001.
MS(IMSI-001001000123456:TLLI-0xc0123456:TA-0:MSCLS-45-0:DL) Detaching TBF: TBF(DL:G:IMSI-001001000123456:TLLI-0xc0123456){FINISHED}
MS(IMSI-001001000123456:TLLI-0xc0123456:TA-0:MSCLS-45-0): - tbf: now used by 0 (-)
********** DL-TBF ends here **********
DL_TBF(DL:TFI-0-0-0:G:IMSI-001001000123456:TLLI-0xc0123456){FINISHED}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-0:G:IMSI-001001000123456:TLLI-0xc0123456){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-0:G:IMSI-001001000123456:TLLI-0xc0123456){NONE}: Deallocated
=== start test_tbf_single_phase ===
PDCH(bts=0,trx=0,ts=7) PDCH state: disabled => enabled
Found first unallocated TRX=0 TFI=0
@ -2310,8 +2522,16 @@ Modifying MS object, TLLI: 0xf1223344 confirmed
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-0-0:UL) appending 4 bytes to DL LLC queue
=== end test_tbf_single_phase ===
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-0-0:UL) Destroying MS object
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-0-0:UL) Detaching TBF: TBF(UL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){FINISHED}
TBF(UL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){FINISHED} free
TBF(UL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){FINISHED} stopping timer T3141 [freeing TBF]
PDCH(bts=0,trx=0,ts=7) Detaching TBF(UL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){FINISHED}, 1 TBFs, USFs = 01, TFIs = 00000001.
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-0-0:UL) Detaching TBF: TBF(UL:G:IMSI-0011223344:TLLI-0xf1223344){FINISHED}
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-0-0): - tbf: now used by 0 (-)
********** UL-TBF ends here **********
UL_ACK_TBF(UL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){SCHED_UL_ACK}: Deallocated
UL_TBF(UL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){FINISHED}: Deallocated
UL_ASS_TBF(UL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){NONE}: Deallocated
DL_ASS_TBF(UL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){NONE}: Deallocated
=== start test_tbf_single_phase2 ===
PDCH(bts=0,trx=0,ts=7) PDCH state: disabled => enabled
Found first unallocated TRX=0 TFI=0
@ -2432,8 +2652,14 @@ TBF(DL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){ASSIGN} Tx CCCH (PCH) Immedi
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-0-0:DL): - st_finished: now used by 1 (tbf)
=== end test_tbf_single_phase2 ===
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-0-0:DL) Destroying MS object
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-0-0:DL) Detaching TBF: TBF(DL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){ASSIGN}
TBF(DL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){ASSIGN} free
PDCH(bts=0,trx=0,ts=7) Detaching TBF(DL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){ASSIGN}, 1 TBFs, USFs = 00, TFIs = 00000001.
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-0-0:DL) Detaching TBF: TBF(DL:G:IMSI-0011223344:TLLI-0xf1223344){ASSIGN}
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-0-0): - tbf: now used by 0 (-)
********** DL-TBF ends here **********
DL_TBF(DL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){ASSIGN}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){NONE}: Deallocated
=== start test_tbf_two_phase ===
PDCH(bts=0,trx=0,ts=7) PDCH state: disabled => enabled
MS requests Uplink resource on CCCH/RACH: ra=0x73 (8 bit) Fn=2654167 qta=31
@ -2540,10 +2766,23 @@ TBF(DL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){NEW} set ass. type PACCH [pr
DL_TBF(DL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){NEW}: state_chg to ASSIGN
TBF(DL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){ASSIGN} Starting timer X2001 [assignment (PACCH)] with 2 sec. 0 microsec
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:UL:DL) Destroying MS object
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:UL:DL) Detaching TBF: TBF(UL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){FLOW}
TBF(UL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){FLOW} free
PDCH(bts=0,trx=0,ts=7) Detaching TBF(UL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){FLOW}, 1 TBFs, USFs = 01, TFIs = 00000001.
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:UL:DL) Detaching TBF: TBF(UL:G:IMSI-0011223344:TLLI-0xf1223344){FLOW}
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:DL): - tbf: now used by 1 (tbf)
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:DL) Detaching TBF: TBF(DL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){ASSIGN}
********** UL-TBF ends here **********
UL_ACK_TBF(UL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){NONE}: Deallocated
UL_TBF(UL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){FLOW}: Deallocated
UL_ASS_TBF(UL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){NONE}: Deallocated
DL_ASS_TBF(UL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){SEND_ASS}: Deallocated
TBF(DL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){ASSIGN} free
PDCH(bts=0,trx=0,ts=7) Detaching TBF(DL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){ASSIGN}, 1 TBFs, USFs = 00, TFIs = 00000001.
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:DL) Detaching TBF: TBF(DL:G:IMSI-0011223344:TLLI-0xf1223344){ASSIGN}
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0): - tbf: now used by 0 (-)
********** DL-TBF ends here **********
DL_TBF(DL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){ASSIGN}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){NONE}: Deallocated
=== end test_tbf_two_phase ===
=== start test_tbf_ra_update_rach ===
PDCH(bts=0,trx=0,ts=7) PDCH state: disabled => enabled
@ -2854,13 +3093,33 @@ DL_TBF(DL:TFI-0-0-1:G:IMSI-0011223344:TLLI-0xf5667788){NEW}: state_chg to ASSIGN
TBF(DL:TFI-0-0-1:G:IMSI-0011223344:TLLI-0xf5667788){ASSIGN} Starting timer X2001 [assignment (PACCH)] with 2 sec. 0 microsec
New MS: TLLI = 0xf5667788, TA = 7, IMSI = 0011223344, LLC = 2
MS(IMSI-0011223344:TLLI-0xf5667788:TA-7:MSCLS-1-0:UL:DL) Destroying MS object
MS(IMSI-0011223344:TLLI-0xf5667788:TA-7:MSCLS-1-0:UL:DL) Detaching TBF: TBF(UL:TFI-0-0-1:G:IMSI-0011223344:TLLI-0xf5667788){FLOW}
TBF(UL:TFI-0-0-1:G:IMSI-0011223344:TLLI-0xf5667788){FLOW} free
PDCH(bts=0,trx=0,ts=7) Detaching TBF(UL:TFI-0-0-1:G:IMSI-0011223344:TLLI-0xf5667788){FLOW}, 1 TBFs, USFs = 02, TFIs = 00000002.
MS(IMSI-0011223344:TLLI-0xf5667788:TA-7:MSCLS-1-0:UL:DL) Detaching TBF: TBF(UL:G:IMSI-0011223344:TLLI-0xf5667788){FLOW}
MS(IMSI-0011223344:TLLI-0xf5667788:TA-7:MSCLS-1-0:DL): - tbf: now used by 1 (tbf)
MS(IMSI-0011223344:TLLI-0xf5667788:TA-7:MSCLS-1-0:DL) Detaching TBF: TBF(DL:TFI-0-0-1:G:IMSI-0011223344:TLLI-0xf5667788){ASSIGN}
********** UL-TBF ends here **********
UL_ACK_TBF(UL:TFI-0-0-1:G:IMSI-0011223344:TLLI-0xf5667788){NONE}: Deallocated
UL_TBF(UL:TFI-0-0-1:G:IMSI-0011223344:TLLI-0xf5667788){FLOW}: Deallocated
UL_ASS_TBF(UL:TFI-0-0-1:G:IMSI-0011223344:TLLI-0xf5667788){NONE}: Deallocated
DL_ASS_TBF(UL:TFI-0-0-1:G:IMSI-0011223344:TLLI-0xf5667788){SEND_ASS}: Deallocated
TBF(DL:TFI-0-0-1:G:IMSI-0011223344:TLLI-0xf5667788){ASSIGN} free
PDCH(bts=0,trx=0,ts=7) Detaching TBF(DL:TFI-0-0-1:G:IMSI-0011223344:TLLI-0xf5667788){ASSIGN}, 2 TBFs, USFs = 00, TFIs = 00000003.
MS(IMSI-0011223344:TLLI-0xf5667788:TA-7:MSCLS-1-0:DL) Detaching TBF: TBF(DL:G:IMSI-0011223344:TLLI-0xf5667788){ASSIGN}
MS(IMSI-0011223344:TLLI-0xf5667788:TA-7:MSCLS-1-0): - tbf: now used by 0 (-)
********** DL-TBF ends here **********
DL_TBF(DL:TFI-0-0-1:G:IMSI-0011223344:TLLI-0xf5667788){ASSIGN}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-1:G:IMSI-0011223344:TLLI-0xf5667788){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-1:G:IMSI-0011223344:TLLI-0xf5667788){NONE}: Deallocated
MS(TA-7:MSCLS-1-0:DL) Destroying MS object
MS(TA-7:MSCLS-1-0:DL) Detaching TBF: TBF(DL:TFI-0-0-0:G){FINISHED}
TBF(DL:TFI-0-0-0:G){FINISHED} free
TBF(DL:TFI-0-0-0:G){FINISHED} stopping timer T3191 [freeing TBF]
PDCH(bts=0,trx=0,ts=7) Detaching TBF(DL:TFI-0-0-0:G){FINISHED}, 1 TBFs, USFs = 00, TFIs = 00000001.
MS(TA-7:MSCLS-1-0:DL) Detaching TBF: TBF(DL:G){FINISHED}
MS(TA-7:MSCLS-1-0): - tbf: now used by 0 (-)
********** DL-TBF ends here **********
DL_TBF(DL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){FINISHED}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){NONE}: Deallocated
=== end test_tbf_ra_update_rach ===
=== start test_tbf_dl_flow_and_rach_two_phase ===
PDCH(bts=0,trx=0,ts=7) PDCH state: disabled => enabled
@ -3084,10 +3343,23 @@ TBF(UL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){FLOW} Frame 1 starts at offs
TBF(UL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){FLOW} No gaps in received block, last block: BSN=0 CV=15
New MS: TLLI = 0xf1223344, TA = 7, IMSI = 0011223344, LLC = 2
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:UL:DL) Destroying MS object
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:UL:DL) Detaching TBF: TBF(UL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){FLOW}
TBF(UL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){FLOW} free
PDCH(bts=0,trx=0,ts=7) Detaching TBF(UL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){FLOW}, 1 TBFs, USFs = 01, TFIs = 00000001.
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:UL:DL) Detaching TBF: TBF(UL:G:IMSI-0011223344:TLLI-0xf1223344){FLOW}
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:DL): - tbf: now used by 1 (tbf)
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:DL) Detaching TBF: TBF(DL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){ASSIGN}
********** UL-TBF ends here **********
UL_ACK_TBF(UL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){NONE}: Deallocated
UL_TBF(UL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){FLOW}: Deallocated
UL_ASS_TBF(UL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){NONE}: Deallocated
DL_ASS_TBF(UL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){SEND_ASS}: Deallocated
TBF(DL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){ASSIGN} free
PDCH(bts=0,trx=0,ts=7) Detaching TBF(DL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){ASSIGN}, 1 TBFs, USFs = 00, TFIs = 00000001.
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:DL) Detaching TBF: TBF(DL:G:IMSI-0011223344:TLLI-0xf1223344){ASSIGN}
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0): - tbf: now used by 0 (-)
********** DL-TBF ends here **********
DL_TBF(DL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){ASSIGN}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){NONE}: Deallocated
=== end test_tbf_dl_flow_and_rach_two_phase ===
=== start test_tbf_dl_flow_and_rach_single_phase ===
PDCH(bts=0,trx=0,ts=7) PDCH state: disabled => enabled
@ -3281,8 +3553,16 @@ UL_ACK_TBF(UL:TFI-0-0-0:G){NONE}: Received Event SCHED_ACK
UL_ACK_TBF(UL:TFI-0-0-0:G){NONE}: state_chg to SCHED_UL_ACK
New MS: TLLI = 0xf1223344, TA = 7, IMSI = 0011223344, LLC = 2
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:UL) Destroying MS object
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:UL) Detaching TBF: TBF(UL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){FINISHED}
TBF(UL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){FINISHED} free
TBF(UL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){FINISHED} stopping timer T3141 [freeing TBF]
PDCH(bts=0,trx=0,ts=7) Detaching TBF(UL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){FINISHED}, 1 TBFs, USFs = 01, TFIs = 00000001.
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:UL) Detaching TBF: TBF(UL:G:IMSI-0011223344:TLLI-0xf1223344){FINISHED}
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0): - tbf: now used by 0 (-)
********** UL-TBF ends here **********
UL_ACK_TBF(UL:TFI-0-0-0:G){SCHED_UL_ACK}: Deallocated
UL_TBF(UL:TFI-0-0-0:G){FINISHED}: Deallocated
UL_ASS_TBF(UL:TFI-0-0-0:G){NONE}: Deallocated
DL_ASS_TBF(UL:TFI-0-0-0:G){NONE}: Deallocated
=== end test_tbf_dl_flow_and_rach_single_phase ===
=== start test_tbf_dl_reuse ===
PDCH(bts=0,trx=0,ts=7) PDCH state: disabled => enabled
@ -4379,10 +4659,24 @@ TBF(DL:TFI-0-0-1:G:IMSI-0011223344:TLLI-0xf1223344){FINISHED} Starting timer T31
TBF(DL:TFI-0-0-1:G:IMSI-0011223344:TLLI-0xf1223344){FINISHED} Scheduled Ack/Nack polling on FN=2654461, TS=7
TBF(DL:TFI-0-0-1:G:IMSI-0011223344:TLLI-0xf1223344){FINISHED} msg block (BSN 10, CS-1): 0f 03 14 4d 43 20 50 41 43 4b 45 54 20 30 39 20 28 54 42 46 20 32 29
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:UL:DL) Destroying MS object
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:UL:DL) Detaching TBF: TBF(UL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){RELEASING}
TBF(UL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){RELEASING} free
PDCH(bts=0,trx=0,ts=7) Detaching TBF(UL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){RELEASING}, 1 TBFs, USFs = 01, TFIs = 00000001.
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:UL:DL) Detaching TBF: TBF(UL:G:IMSI-0011223344:TLLI-0xf1223344){RELEASING}
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:DL): - tbf: now used by 1 (tbf)
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:DL) Detaching TBF: TBF(DL:TFI-0-0-1:G:IMSI-0011223344:TLLI-0xf1223344){FINISHED}
********** UL-TBF ends here **********
UL_ACK_TBF(UL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){NONE}: Deallocated
UL_TBF(UL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){RELEASING}: Deallocated
UL_ASS_TBF(UL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){NONE}: Deallocated
DL_ASS_TBF(UL:TFI-0-0-0:G:IMSI-0011223344:TLLI-0xf1223344){NONE}: Deallocated
TBF(DL:TFI-0-0-1:G:IMSI-0011223344:TLLI-0xf1223344){FINISHED} free
TBF(DL:TFI-0-0-1:G:IMSI-0011223344:TLLI-0xf1223344){FINISHED} stopping timer T3191 [freeing TBF]
PDCH(bts=0,trx=0,ts=7) Detaching TBF(DL:TFI-0-0-1:G:IMSI-0011223344:TLLI-0xf1223344){FINISHED}, 1 TBFs, USFs = 00, TFIs = 00000002.
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:DL) Detaching TBF: TBF(DL:G:IMSI-0011223344:TLLI-0xf1223344){FINISHED}
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0): - tbf: now used by 0 (-)
********** DL-TBF ends here **********
DL_TBF(DL:TFI-0-0-1:G:IMSI-0011223344:TLLI-0xf1223344){FINISHED}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-1:G:IMSI-0011223344:TLLI-0xf1223344){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-1:G:IMSI-0011223344:TLLI-0xf1223344){NONE}: Deallocated
=== end test_tbf_dl_reuse ===
=== start test_tbf_gprs_egprs ===
PDCH(bts=0,trx=0,ts=4) PDCH state: disabled => enabled
@ -4420,8 +4714,14 @@ TBF(DL:TFI-0-0-0:G:IMSI-001001123456789:TLLI-0xc0006789){ASSIGN} Tx CCCH (PCH) I
MS(IMSI-001001123456789:TLLI-0xc0006789:TA-220:MSCLS-45-0:DL): - dl_tbf_handle: now used by 1 (tbf)
=== end test_tbf_gprs_egprs ===
MS(IMSI-001001123456789:TLLI-0xc0006789:TA-220:MSCLS-45-0:DL) Destroying MS object
MS(IMSI-001001123456789:TLLI-0xc0006789:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:TFI-0-0-0:G:IMSI-001001123456789:TLLI-0xc0006789){ASSIGN}
TBF(DL:TFI-0-0-0:G:IMSI-001001123456789:TLLI-0xc0006789){ASSIGN} free
PDCH(bts=0,trx=0,ts=4) Detaching TBF(DL:TFI-0-0-0:G:IMSI-001001123456789:TLLI-0xc0006789){ASSIGN}, 1 TBFs, USFs = 00, TFIs = 00000001.
MS(IMSI-001001123456789:TLLI-0xc0006789:TA-220:MSCLS-45-0:DL) Detaching TBF: TBF(DL:G:IMSI-001001123456789:TLLI-0xc0006789){ASSIGN}
MS(IMSI-001001123456789:TLLI-0xc0006789:TA-220:MSCLS-45-0): - tbf: now used by 0 (-)
********** DL-TBF ends here **********
DL_TBF(DL:TFI-0-0-0:G:IMSI-001001123456789:TLLI-0xc0006789){ASSIGN}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-0:G:IMSI-001001123456789:TLLI-0xc0006789){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-0:G:IMSI-001001123456789:TLLI-0xc0006789){NONE}: Deallocated
=== start test_tbf_ws ===
PDCH(bts=0,trx=0,ts=4) PDCH state: disabled => enabled
PDCH(bts=0,trx=0,ts=2) PDCH state: disabled => enabled
@ -4628,10 +4928,23 @@ TBF(DL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){NEW} set ass. type PACCH [pr
DL_TBF(DL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){NEW}: state_chg to ASSIGN
TBF(DL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){ASSIGN} Starting timer X2001 [assignment (PACCH)] with 2 sec. 0 microsec
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1:UL:DL) Destroying MS object
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1:UL:DL) Detaching TBF: TBF(UL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){FLOW}
TBF(UL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){FLOW} free
PDCH(bts=0,trx=0,ts=7) Detaching TBF(UL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){FLOW}, 1 TBFs, USFs = 01, TFIs = 00000001.
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1:UL:DL) Detaching TBF: TBF(UL:E:IMSI-0011223344:TLLI-0xf1223344){FLOW}
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1:DL): - tbf: now used by 1 (tbf)
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1:DL) Detaching TBF: TBF(DL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){ASSIGN}
********** UL-TBF ends here **********
UL_ACK_TBF(UL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){NONE}: Deallocated
UL_TBF(UL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){FLOW}: Deallocated
UL_ASS_TBF(UL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){NONE}: Deallocated
DL_ASS_TBF(UL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){SEND_ASS}: Deallocated
TBF(DL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){ASSIGN} free
PDCH(bts=0,trx=0,ts=7) Detaching TBF(DL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){ASSIGN}, 1 TBFs, USFs = 00, TFIs = 00000001.
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1:DL) Detaching TBF: TBF(DL:E:IMSI-0011223344:TLLI-0xf1223344){ASSIGN}
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1): - tbf: now used by 0 (-)
********** DL-TBF ends here **********
DL_TBF(DL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){ASSIGN}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){NONE}: Deallocated
=== end test_tbf_egprs_two_phase ===
=== start test_tbf_egprs_two_phase_spb ===
PDCH(bts=0,trx=0,ts=7) PDCH state: disabled => enabled
@ -4870,10 +5183,23 @@ TBF(DL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){NEW} set ass. type PACCH [pr
DL_TBF(DL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){NEW}: state_chg to ASSIGN
TBF(DL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){ASSIGN} Starting timer X2001 [assignment (PACCH)] with 2 sec. 0 microsec
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1:UL:DL) Destroying MS object
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1:UL:DL) Detaching TBF: TBF(UL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){FLOW}
TBF(UL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){FLOW} free
PDCH(bts=0,trx=0,ts=7) Detaching TBF(UL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){FLOW}, 1 TBFs, USFs = 01, TFIs = 00000001.
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1:UL:DL) Detaching TBF: TBF(UL:E:IMSI-0011223344:TLLI-0xf1223344){FLOW}
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1:DL): - tbf: now used by 1 (tbf)
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1:DL) Detaching TBF: TBF(DL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){ASSIGN}
********** UL-TBF ends here **********
UL_ACK_TBF(UL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){SCHED_UL_ACK}: Deallocated
UL_TBF(UL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){FLOW}: Deallocated
UL_ASS_TBF(UL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){NONE}: Deallocated
DL_ASS_TBF(UL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){SEND_ASS}: Deallocated
TBF(DL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){ASSIGN} free
PDCH(bts=0,trx=0,ts=7) Detaching TBF(DL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){ASSIGN}, 1 TBFs, USFs = 00, TFIs = 00000001.
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1:DL) Detaching TBF: TBF(DL:E:IMSI-0011223344:TLLI-0xf1223344){ASSIGN}
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1): - tbf: now used by 0 (-)
********** DL-TBF ends here **********
DL_TBF(DL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){ASSIGN}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){NONE}: Deallocated
=== end test_tbf_egprs_two_phase_spb ===
=== start test_tbf_egprs_dl ===
PDCH(bts=0,trx=0,ts=4) PDCH state: disabled => enabled
@ -9029,10 +9355,23 @@ TBF(DL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){NEW} set ass. type PACCH [pr
DL_TBF(DL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){NEW}: state_chg to ASSIGN
TBF(DL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){ASSIGN} Starting timer X2001 [assignment (PACCH)] with 2 sec. 0 microsec
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1:UL:DL) Destroying MS object
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1:UL:DL) Detaching TBF: TBF(UL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){FLOW}
TBF(UL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){FLOW} free
PDCH(bts=0,trx=0,ts=7) Detaching TBF(UL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){FLOW}, 1 TBFs, USFs = 01, TFIs = 00000001.
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1:UL:DL) Detaching TBF: TBF(UL:E:IMSI-0011223344:TLLI-0xf1223344){FLOW}
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1:DL): - tbf: now used by 1 (tbf)
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1:DL) Detaching TBF: TBF(DL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){ASSIGN}
********** UL-TBF ends here **********
UL_ACK_TBF(UL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){NONE}: Deallocated
UL_TBF(UL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){FLOW}: Deallocated
UL_ASS_TBF(UL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){NONE}: Deallocated
DL_ASS_TBF(UL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){SEND_ASS}: Deallocated
TBF(DL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){ASSIGN} free
PDCH(bts=0,trx=0,ts=7) Detaching TBF(DL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){ASSIGN}, 1 TBFs, USFs = 00, TFIs = 00000001.
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1:DL) Detaching TBF: TBF(DL:E:IMSI-0011223344:TLLI-0xf1223344){ASSIGN}
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1): - tbf: now used by 0 (-)
********** DL-TBF ends here **********
DL_TBF(DL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){ASSIGN}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){NONE}: Deallocated
=== end test_tbf_puan_urbb_len ===
=== start test_tbf_update_ws ===
PDCH(bts=0,trx=0,ts=4) PDCH state: disabled => enabled
@ -9239,10 +9578,23 @@ TBF(DL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){NEW} set ass. type PACCH [pr
DL_TBF(DL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){NEW}: state_chg to ASSIGN
TBF(DL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){ASSIGN} Starting timer X2001 [assignment (PACCH)] with 2 sec. 0 microsec
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1:UL:DL) Destroying MS object
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1:UL:DL) Detaching TBF: TBF(UL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){FLOW}
TBF(UL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){FLOW} free
PDCH(bts=0,trx=0,ts=7) Detaching TBF(UL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){FLOW}, 1 TBFs, USFs = 01, TFIs = 00000001.
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1:UL:DL) Detaching TBF: TBF(UL:E:IMSI-0011223344:TLLI-0xf1223344){FLOW}
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1:DL): - tbf: now used by 1 (tbf)
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1:DL) Detaching TBF: TBF(DL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){ASSIGN}
********** UL-TBF ends here **********
UL_ACK_TBF(UL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){NONE}: Deallocated
UL_TBF(UL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){FLOW}: Deallocated
UL_ASS_TBF(UL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){NONE}: Deallocated
DL_ASS_TBF(UL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){SEND_ASS}: Deallocated
TBF(DL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){ASSIGN} free
PDCH(bts=0,trx=0,ts=7) Detaching TBF(DL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){ASSIGN}, 1 TBFs, USFs = 00, TFIs = 00000001.
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1:DL) Detaching TBF: TBF(DL:E:IMSI-0011223344:TLLI-0xf1223344){ASSIGN}
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1): - tbf: now used by 0 (-)
********** DL-TBF ends here **********
DL_TBF(DL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){ASSIGN}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){NONE}: Deallocated
=== end test_tbf_li_decoding ===
=== start test_tbf_epdan_out_of_rx_window ===
PDCH(bts=0,trx=0,ts=4) PDCH state: disabled => enabled
@ -9546,26 +9898,82 @@ MS(TA-220:MSCLS-0-0): - bts_rcv_rach: now used by 0 (-)
MS(TA-220:MSCLS-0-0) Destroying MS object
Tx Immediate Assignment Reject on AGCH
MS(TA-7:MSCLS-0-0:UL) Destroying MS object
MS(TA-7:MSCLS-0-0:UL) Detaching TBF: TBF(UL:TFI-0-0-6:G){ASSIGN}
TBF(UL:TFI-0-0-6:G){ASSIGN} free
TBF(UL:TFI-0-0-6:G){ASSIGN} stopping timer T3141 [freeing TBF]
PDCH(bts=0,trx=0,ts=7) Detaching TBF(UL:TFI-0-0-6:G){ASSIGN}, 7 TBFs, USFs = 7f, TFIs = 0000007f.
MS(TA-7:MSCLS-0-0:UL) Detaching TBF: TBF(UL:G){ASSIGN}
MS(TA-7:MSCLS-0-0): - tbf: now used by 0 (-)
********** UL-TBF ends here **********
UL_ACK_TBF(UL:TFI-0-0-6:G){NONE}: Deallocated
UL_TBF(UL:TFI-0-0-6:G){ASSIGN}: Deallocated
UL_ASS_TBF(UL:TFI-0-0-6:G){NONE}: Deallocated
DL_ASS_TBF(UL:TFI-0-0-6:G){NONE}: Deallocated
MS(TA-7:MSCLS-0-0:UL) Destroying MS object
MS(TA-7:MSCLS-0-0:UL) Detaching TBF: TBF(UL:TFI-0-0-5:G){ASSIGN}
TBF(UL:TFI-0-0-5:G){ASSIGN} free
TBF(UL:TFI-0-0-5:G){ASSIGN} stopping timer T3141 [freeing TBF]
PDCH(bts=0,trx=0,ts=7) Detaching TBF(UL:TFI-0-0-5:G){ASSIGN}, 6 TBFs, USFs = 3f, TFIs = 0000003f.
MS(TA-7:MSCLS-0-0:UL) Detaching TBF: TBF(UL:G){ASSIGN}
MS(TA-7:MSCLS-0-0): - tbf: now used by 0 (-)
********** UL-TBF ends here **********
UL_ACK_TBF(UL:TFI-0-0-5:G){NONE}: Deallocated
UL_TBF(UL:TFI-0-0-5:G){ASSIGN}: Deallocated
UL_ASS_TBF(UL:TFI-0-0-5:G){NONE}: Deallocated
DL_ASS_TBF(UL:TFI-0-0-5:G){NONE}: Deallocated
MS(TA-7:MSCLS-0-0:UL) Destroying MS object
MS(TA-7:MSCLS-0-0:UL) Detaching TBF: TBF(UL:TFI-0-0-4:G){ASSIGN}
TBF(UL:TFI-0-0-4:G){ASSIGN} free
TBF(UL:TFI-0-0-4:G){ASSIGN} stopping timer T3141 [freeing TBF]
PDCH(bts=0,trx=0,ts=7) Detaching TBF(UL:TFI-0-0-4:G){ASSIGN}, 5 TBFs, USFs = 1f, TFIs = 0000001f.
MS(TA-7:MSCLS-0-0:UL) Detaching TBF: TBF(UL:G){ASSIGN}
MS(TA-7:MSCLS-0-0): - tbf: now used by 0 (-)
********** UL-TBF ends here **********
UL_ACK_TBF(UL:TFI-0-0-4:G){NONE}: Deallocated
UL_TBF(UL:TFI-0-0-4:G){ASSIGN}: Deallocated
UL_ASS_TBF(UL:TFI-0-0-4:G){NONE}: Deallocated
DL_ASS_TBF(UL:TFI-0-0-4:G){NONE}: Deallocated
MS(TA-7:MSCLS-0-0:UL) Destroying MS object
MS(TA-7:MSCLS-0-0:UL) Detaching TBF: TBF(UL:TFI-0-0-3:G){ASSIGN}
TBF(UL:TFI-0-0-3:G){ASSIGN} free
TBF(UL:TFI-0-0-3:G){ASSIGN} stopping timer T3141 [freeing TBF]
PDCH(bts=0,trx=0,ts=7) Detaching TBF(UL:TFI-0-0-3:G){ASSIGN}, 4 TBFs, USFs = 0f, TFIs = 0000000f.
MS(TA-7:MSCLS-0-0:UL) Detaching TBF: TBF(UL:G){ASSIGN}
MS(TA-7:MSCLS-0-0): - tbf: now used by 0 (-)
********** UL-TBF ends here **********
UL_ACK_TBF(UL:TFI-0-0-3:G){NONE}: Deallocated
UL_TBF(UL:TFI-0-0-3:G){ASSIGN}: Deallocated
UL_ASS_TBF(UL:TFI-0-0-3:G){NONE}: Deallocated
DL_ASS_TBF(UL:TFI-0-0-3:G){NONE}: Deallocated
MS(TA-7:MSCLS-0-0:UL) Destroying MS object
MS(TA-7:MSCLS-0-0:UL) Detaching TBF: TBF(UL:TFI-0-0-2:G){ASSIGN}
TBF(UL:TFI-0-0-2:G){ASSIGN} free
TBF(UL:TFI-0-0-2:G){ASSIGN} stopping timer T3141 [freeing TBF]
PDCH(bts=0,trx=0,ts=7) Detaching TBF(UL:TFI-0-0-2:G){ASSIGN}, 3 TBFs, USFs = 07, TFIs = 00000007.
MS(TA-7:MSCLS-0-0:UL) Detaching TBF: TBF(UL:G){ASSIGN}
MS(TA-7:MSCLS-0-0): - tbf: now used by 0 (-)
********** UL-TBF ends here **********
UL_ACK_TBF(UL:TFI-0-0-2:G){NONE}: Deallocated
UL_TBF(UL:TFI-0-0-2:G){ASSIGN}: Deallocated
UL_ASS_TBF(UL:TFI-0-0-2:G){NONE}: Deallocated
DL_ASS_TBF(UL:TFI-0-0-2:G){NONE}: Deallocated
MS(TA-7:MSCLS-0-0:UL) Destroying MS object
MS(TA-7:MSCLS-0-0:UL) Detaching TBF: TBF(UL:TFI-0-0-1:G){ASSIGN}
TBF(UL:TFI-0-0-1:G){ASSIGN} free
TBF(UL:TFI-0-0-1:G){ASSIGN} stopping timer T3141 [freeing TBF]
PDCH(bts=0,trx=0,ts=7) Detaching TBF(UL:TFI-0-0-1:G){ASSIGN}, 2 TBFs, USFs = 03, TFIs = 00000003.
MS(TA-7:MSCLS-0-0:UL) Detaching TBF: TBF(UL:G){ASSIGN}
MS(TA-7:MSCLS-0-0): - tbf: now used by 0 (-)
********** UL-TBF ends here **********
UL_ACK_TBF(UL:TFI-0-0-1:G){NONE}: Deallocated
UL_TBF(UL:TFI-0-0-1:G){ASSIGN}: Deallocated
UL_ASS_TBF(UL:TFI-0-0-1:G){NONE}: Deallocated
DL_ASS_TBF(UL:TFI-0-0-1:G){NONE}: Deallocated
MS(TA-7:MSCLS-0-0:UL) Destroying MS object
MS(TA-7:MSCLS-0-0:UL) Detaching TBF: TBF(UL:TFI-0-0-0:G){ASSIGN}
TBF(UL:TFI-0-0-0:G){ASSIGN} free
TBF(UL:TFI-0-0-0:G){ASSIGN} stopping timer T3141 [freeing TBF]
PDCH(bts=0,trx=0,ts=7) Detaching TBF(UL:TFI-0-0-0:G){ASSIGN}, 1 TBFs, USFs = 01, TFIs = 00000001.
MS(TA-7:MSCLS-0-0:UL) Detaching TBF: TBF(UL:G){ASSIGN}
MS(TA-7:MSCLS-0-0): - tbf: now used by 0 (-)
********** UL-TBF ends here **********
UL_ACK_TBF(UL:TFI-0-0-0:G){NONE}: Deallocated
UL_TBF(UL:TFI-0-0-0:G){ASSIGN}: Deallocated
UL_ASS_TBF(UL:TFI-0-0-0:G){NONE}: Deallocated
DL_ASS_TBF(UL:TFI-0-0-0:G){NONE}: Deallocated
=== end test_immediate_assign_rej_multi_block ===
=== start test_immediate_assign_rej_single_block ===
PDCH(bts=0,trx=0,ts=7) PDCH state: disabled => enabled
@ -10849,10 +11257,23 @@ Got 'TBF(UL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){FLOW}', TA=7
Got MS: TLLI = 0xf1223344, TA = 7
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1:UL:DL) appending 256 bytes to DL LLC queue
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1:UL:DL) Destroying MS object
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1:UL:DL) Detaching TBF: TBF(UL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){FLOW}
TBF(UL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){FLOW} free
PDCH(bts=0,trx=0,ts=7) Detaching TBF(UL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){FLOW}, 1 TBFs, USFs = 01, TFIs = 00000001.
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1:UL:DL) Detaching TBF: TBF(UL:E:IMSI-0011223344:TLLI-0xf1223344){FLOW}
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1:DL): - tbf: now used by 1 (tbf)
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1:DL) Detaching TBF: TBF(DL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){ASSIGN}
********** UL-TBF ends here **********
UL_ACK_TBF(UL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){NONE}: Deallocated
UL_TBF(UL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){FLOW}: Deallocated
UL_ASS_TBF(UL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){NONE}: Deallocated
DL_ASS_TBF(UL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){WAIT_ACK}: Deallocated
TBF(DL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){ASSIGN} free
PDCH(bts=0,trx=0,ts=7) Detaching TBF(DL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){ASSIGN}, 1 TBFs, USFs = 00, TFIs = 00000001.
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1:DL) Detaching TBF: TBF(DL:E:IMSI-0011223344:TLLI-0xf1223344){ASSIGN}
MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1): - tbf: now used by 0 (-)
********** DL-TBF ends here **********
DL_TBF(DL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){ASSIGN}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-0:E:IMSI-0011223344:TLLI-0xf1223344){NONE}: Deallocated
=== end test_tbf_egprs_two_phase_puan ===
=== start test_packet_access_rej_epdan ===
PDCH(bts=0,trx=0,ts=4) PDCH state: disabled => enabled
@ -10903,8 +11324,14 @@ UL_ASS_TBF(DL:TFI-0-0-0:E){SEND_ASS_REJ}: Received Event CREATE_RLCMAC_MSG
UL_ASS_TBF(DL:TFI-0-0-0:E){SEND_ASS_REJ}: state_chg to NONE
packet reject: 40 84 7f f7 6e e6 7e ab 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b 2b
MS(TLLI-0xffeeddcc:TA-0:MSCLS-11-11:DL) Destroying MS object
MS(TLLI-0xffeeddcc:TA-0:MSCLS-11-11:DL) Detaching TBF: TBF(DL:TFI-0-0-0:E:TLLI-0xffeeddcc){FLOW}
TBF(DL:TFI-0-0-0:E:TLLI-0xffeeddcc){FLOW} free
PDCH(bts=0,trx=0,ts=4) Detaching TBF(DL:TFI-0-0-0:E:TLLI-0xffeeddcc){FLOW}, 1 TBFs, USFs = 00, TFIs = 00000001.
MS(TLLI-0xffeeddcc:TA-0:MSCLS-11-11:DL) Detaching TBF: TBF(DL:E:TLLI-0xffeeddcc){FLOW}
MS(TLLI-0xffeeddcc:TA-0:MSCLS-11-11): - tbf: now used by 0 (-)
********** DL-TBF ends here **********
DL_TBF(DL:TFI-0-0-0:E){FLOW}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-0:E){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-0:E){NONE}: Deallocated
=== end test_packet_access_rej_epdan ===
=== start test_packet_access_rej_prr ===
PDCH(bts=0,trx=0,ts=7) PDCH state: disabled => enabled
@ -11396,29 +11823,84 @@ TBF(UL:TFI-0-0-0:E:TLLI-0xffeeddcc){ASSIGN} Scheduled UL Assignment polling on P
UL_ASS_TBF(UL:TFI-0-0-0:E:TLLI-0xffeeddcc){SEND_ASS}: state_chg to WAIT_ACK
PDCH(bts=0,trx=0,ts=7) FN=2654218 Scheduling control message at RTS for TBF(UL:TFI-0-0-0:E:TLLI-0xffeeddcc){ASSIGN}
MS(TLLI-0xffeeddd3:TA-7:MSCLS-11-11:UL) Destroying MS object
MS(TLLI-0xffeeddd3:TA-7:MSCLS-11-11:UL) Detaching TBF: TBF(UL:TFI-0-0--1:E:TLLI-0xffeeddd3){ASSIGN}
TBF(UL:TFI-0-0--1:E:TLLI-0xffeeddd3){ASSIGN} free
MS(TLLI-0xffeeddd3:TA-7:MSCLS-11-11:UL) Detaching TBF: TBF(UL:E:TLLI-0xffeeddd3){ASSIGN}
MS(TLLI-0xffeeddd3:TA-7:MSCLS-11-11): - tbf: now used by 0 (-)
********** UL-TBF ends here **********
UL_ACK_TBF(UL:TFI-0-0--1:E:TLLI-0xffeeddd3){NONE}: Deallocated
UL_TBF(UL:TFI-0-0--1:E:TLLI-0xffeeddd3){ASSIGN}: Deallocated
UL_ASS_TBF(UL:TFI-0-0--1:E:TLLI-0xffeeddd3){SEND_ASS_REJ}: Deallocated
DL_ASS_TBF(UL:TFI-0-0--1:E:TLLI-0xffeeddd3){NONE}: Deallocated
MS(TLLI-0xffeeddd2:TA-7:MSCLS-11-11:UL) Destroying MS object
MS(TLLI-0xffeeddd2:TA-7:MSCLS-11-11:UL) Detaching TBF: TBF(UL:TFI-0-0-6:E:TLLI-0xffeeddd2){ASSIGN}
TBF(UL:TFI-0-0-6:E:TLLI-0xffeeddd2){ASSIGN} free
PDCH(bts=0,trx=0,ts=7) Detaching TBF(UL:TFI-0-0-6:E:TLLI-0xffeeddd2){ASSIGN}, 7 TBFs, USFs = 7f, TFIs = 0000007f.
MS(TLLI-0xffeeddd2:TA-7:MSCLS-11-11:UL) Detaching TBF: TBF(UL:E:TLLI-0xffeeddd2){ASSIGN}
MS(TLLI-0xffeeddd2:TA-7:MSCLS-11-11): - tbf: now used by 0 (-)
********** UL-TBF ends here **********
UL_ACK_TBF(UL:TFI-0-0-6:E:TLLI-0xffeeddd2){NONE}: Deallocated
UL_TBF(UL:TFI-0-0-6:E:TLLI-0xffeeddd2){ASSIGN}: Deallocated
UL_ASS_TBF(UL:TFI-0-0-6:E:TLLI-0xffeeddd2){SEND_ASS}: Deallocated
DL_ASS_TBF(UL:TFI-0-0-6:E:TLLI-0xffeeddd2){NONE}: Deallocated
MS(TLLI-0xffeeddd1:TA-7:MSCLS-11-11:UL) Destroying MS object
MS(TLLI-0xffeeddd1:TA-7:MSCLS-11-11:UL) Detaching TBF: TBF(UL:TFI-0-0-5:E:TLLI-0xffeeddd1){ASSIGN}
TBF(UL:TFI-0-0-5:E:TLLI-0xffeeddd1){ASSIGN} free
PDCH(bts=0,trx=0,ts=7) Detaching TBF(UL:TFI-0-0-5:E:TLLI-0xffeeddd1){ASSIGN}, 6 TBFs, USFs = 3f, TFIs = 0000003f.
MS(TLLI-0xffeeddd1:TA-7:MSCLS-11-11:UL) Detaching TBF: TBF(UL:E:TLLI-0xffeeddd1){ASSIGN}
MS(TLLI-0xffeeddd1:TA-7:MSCLS-11-11): - tbf: now used by 0 (-)
********** UL-TBF ends here **********
UL_ACK_TBF(UL:TFI-0-0-5:E:TLLI-0xffeeddd1){NONE}: Deallocated
UL_TBF(UL:TFI-0-0-5:E:TLLI-0xffeeddd1){ASSIGN}: Deallocated
UL_ASS_TBF(UL:TFI-0-0-5:E:TLLI-0xffeeddd1){SEND_ASS}: Deallocated
DL_ASS_TBF(UL:TFI-0-0-5:E:TLLI-0xffeeddd1){NONE}: Deallocated
MS(TLLI-0xffeeddd0:TA-7:MSCLS-11-11:UL) Destroying MS object
MS(TLLI-0xffeeddd0:TA-7:MSCLS-11-11:UL) Detaching TBF: TBF(UL:TFI-0-0-4:E:TLLI-0xffeeddd0){ASSIGN}
TBF(UL:TFI-0-0-4:E:TLLI-0xffeeddd0){ASSIGN} free
PDCH(bts=0,trx=0,ts=7) Detaching TBF(UL:TFI-0-0-4:E:TLLI-0xffeeddd0){ASSIGN}, 5 TBFs, USFs = 1f, TFIs = 0000001f.
MS(TLLI-0xffeeddd0:TA-7:MSCLS-11-11:UL) Detaching TBF: TBF(UL:E:TLLI-0xffeeddd0){ASSIGN}
MS(TLLI-0xffeeddd0:TA-7:MSCLS-11-11): - tbf: now used by 0 (-)
********** UL-TBF ends here **********
UL_ACK_TBF(UL:TFI-0-0-4:E:TLLI-0xffeeddd0){NONE}: Deallocated
UL_TBF(UL:TFI-0-0-4:E:TLLI-0xffeeddd0){ASSIGN}: Deallocated
UL_ASS_TBF(UL:TFI-0-0-4:E:TLLI-0xffeeddd0){SEND_ASS}: Deallocated
DL_ASS_TBF(UL:TFI-0-0-4:E:TLLI-0xffeeddd0){NONE}: Deallocated
MS(TLLI-0xffeeddcf:TA-7:MSCLS-11-11:UL) Destroying MS object
MS(TLLI-0xffeeddcf:TA-7:MSCLS-11-11:UL) Detaching TBF: TBF(UL:TFI-0-0-3:E:TLLI-0xffeeddcf){ASSIGN}
TBF(UL:TFI-0-0-3:E:TLLI-0xffeeddcf){ASSIGN} free
PDCH(bts=0,trx=0,ts=7) Detaching TBF(UL:TFI-0-0-3:E:TLLI-0xffeeddcf){ASSIGN}, 4 TBFs, USFs = 0f, TFIs = 0000000f.
MS(TLLI-0xffeeddcf:TA-7:MSCLS-11-11:UL) Detaching TBF: TBF(UL:E:TLLI-0xffeeddcf){ASSIGN}
MS(TLLI-0xffeeddcf:TA-7:MSCLS-11-11): - tbf: now used by 0 (-)
********** UL-TBF ends here **********
UL_ACK_TBF(UL:TFI-0-0-3:E:TLLI-0xffeeddcf){NONE}: Deallocated
UL_TBF(UL:TFI-0-0-3:E:TLLI-0xffeeddcf){ASSIGN}: Deallocated
UL_ASS_TBF(UL:TFI-0-0-3:E:TLLI-0xffeeddcf){SEND_ASS}: Deallocated
DL_ASS_TBF(UL:TFI-0-0-3:E:TLLI-0xffeeddcf){NONE}: Deallocated
MS(TLLI-0xffeeddce:TA-7:MSCLS-11-11:UL) Destroying MS object
MS(TLLI-0xffeeddce:TA-7:MSCLS-11-11:UL) Detaching TBF: TBF(UL:TFI-0-0-2:E:TLLI-0xffeeddce){ASSIGN}
TBF(UL:TFI-0-0-2:E:TLLI-0xffeeddce){ASSIGN} free
PDCH(bts=0,trx=0,ts=7) Detaching TBF(UL:TFI-0-0-2:E:TLLI-0xffeeddce){ASSIGN}, 3 TBFs, USFs = 07, TFIs = 00000007.
MS(TLLI-0xffeeddce:TA-7:MSCLS-11-11:UL) Detaching TBF: TBF(UL:E:TLLI-0xffeeddce){ASSIGN}
MS(TLLI-0xffeeddce:TA-7:MSCLS-11-11): - tbf: now used by 0 (-)
********** UL-TBF ends here **********
UL_ACK_TBF(UL:TFI-0-0-2:E:TLLI-0xffeeddce){NONE}: Deallocated
UL_TBF(UL:TFI-0-0-2:E:TLLI-0xffeeddce){ASSIGN}: Deallocated
UL_ASS_TBF(UL:TFI-0-0-2:E:TLLI-0xffeeddce){SEND_ASS}: Deallocated
DL_ASS_TBF(UL:TFI-0-0-2:E:TLLI-0xffeeddce){NONE}: Deallocated
MS(TLLI-0xffeeddcd:TA-7:MSCLS-11-11:UL) Destroying MS object
MS(TLLI-0xffeeddcd:TA-7:MSCLS-11-11:UL) Detaching TBF: TBF(UL:TFI-0-0-1:E:TLLI-0xffeeddcd){ASSIGN}
TBF(UL:TFI-0-0-1:E:TLLI-0xffeeddcd){ASSIGN} free
PDCH(bts=0,trx=0,ts=7) Detaching TBF(UL:TFI-0-0-1:E:TLLI-0xffeeddcd){ASSIGN}, 2 TBFs, USFs = 03, TFIs = 00000003.
MS(TLLI-0xffeeddcd:TA-7:MSCLS-11-11:UL) Detaching TBF: TBF(UL:E:TLLI-0xffeeddcd){ASSIGN}
MS(TLLI-0xffeeddcd:TA-7:MSCLS-11-11): - tbf: now used by 0 (-)
********** UL-TBF ends here **********
UL_ACK_TBF(UL:TFI-0-0-1:E:TLLI-0xffeeddcd){NONE}: Deallocated
UL_TBF(UL:TFI-0-0-1:E:TLLI-0xffeeddcd){ASSIGN}: Deallocated
UL_ASS_TBF(UL:TFI-0-0-1:E:TLLI-0xffeeddcd){SEND_ASS}: Deallocated
DL_ASS_TBF(UL:TFI-0-0-1:E:TLLI-0xffeeddcd){NONE}: Deallocated
MS(TLLI-0xffeeddcc:TA-7:MSCLS-11-11:UL) Destroying MS object
MS(TLLI-0xffeeddcc:TA-7:MSCLS-11-11:UL) Detaching TBF: TBF(UL:TFI-0-0-0:E:TLLI-0xffeeddcc){ASSIGN}
TBF(UL:TFI-0-0-0:E:TLLI-0xffeeddcc){ASSIGN} free
PDCH(bts=0,trx=0,ts=7) Detaching TBF(UL:TFI-0-0-0:E:TLLI-0xffeeddcc){ASSIGN}, 1 TBFs, USFs = 01, TFIs = 00000001.
MS(TLLI-0xffeeddcc:TA-7:MSCLS-11-11:UL) Detaching TBF: TBF(UL:E:TLLI-0xffeeddcc){ASSIGN}
MS(TLLI-0xffeeddcc:TA-7:MSCLS-11-11): - tbf: now used by 0 (-)
********** UL-TBF ends here **********
UL_ACK_TBF(UL:TFI-0-0-0:E:TLLI-0xffeeddcc){NONE}: Deallocated
UL_TBF(UL:TFI-0-0-0:E:TLLI-0xffeeddcc){ASSIGN}: Deallocated
UL_ASS_TBF(UL:TFI-0-0-0:E:TLLI-0xffeeddcc){WAIT_ACK}: Deallocated
DL_ASS_TBF(UL:TFI-0-0-0:E:TLLI-0xffeeddcc){NONE}: Deallocated
=== end test_packet_access_rej_prr ===
=== start test_packet_access_rej_prr_no_other_tbfs ===
PDCH(bts=0,trx=0,ts=7) PDCH state: disabled => enabled
@ -11591,7 +12073,23 @@ DL_ASS_TBF(UL:TFI-0-0-0:G:IMSI-001001000000001:TLLI-0xecc1f953){SEND_ASS}: state
PDCH(bts=0,trx=0,ts=2) FN=0 Scheduling control message at RTS for TBF(UL:TFI-0-0-0:G:IMSI-001001000000001:TLLI-0xecc1f953){ASSIGN}
=== end test_ms_merge_dl_tbf_different_trx ===
MS(IMSI-001001000000001:TLLI-0xecc1f953:TA-220:MSCLS-11-0:UL:DL) Destroying MS object
MS(IMSI-001001000000001:TLLI-0xecc1f953:TA-220:MSCLS-11-0:UL:DL) Detaching TBF: TBF(UL:TFI-0-0-0:G:IMSI-001001000000001:TLLI-0xecc1f953){ASSIGN}
TBF(UL:TFI-0-0-0:G:IMSI-001001000000001:TLLI-0xecc1f953){ASSIGN} free
PDCH(bts=0,trx=0,ts=2) Detaching TBF(UL:TFI-0-0-0:G:IMSI-001001000000001:TLLI-0xecc1f953){ASSIGN}, 1 TBFs, USFs = 01, TFIs = 00000001.
PDCH(bts=0,trx=0,ts=3) Detaching TBF(UL:TFI-0-0-0:G:IMSI-001001000000001:TLLI-0xecc1f953){ASSIGN}, 1 TBFs, USFs = 01, TFIs = 00000001.
MS(IMSI-001001000000001:TLLI-0xecc1f953:TA-220:MSCLS-11-0:UL:DL) Detaching TBF: TBF(UL:G:IMSI-001001000000001:TLLI-0xecc1f953){ASSIGN}
MS(IMSI-001001000000001:TLLI-0xecc1f953:TA-220:MSCLS-11-0:DL): - tbf: now used by 1 (tbf)
MS(IMSI-001001000000001:TLLI-0xecc1f953:TA-220:MSCLS-11-0:DL) Detaching TBF: TBF(DL:TFI-0-0-0:G:IMSI-001001000000001:TLLI-0xecc1f953){ASSIGN}
********** UL-TBF ends here **********
UL_ACK_TBF(UL:TFI-0-0-0:G:IMSI-001001000000001:TLLI-0xecc1f953){NONE}: Deallocated
UL_TBF(UL:TFI-0-0-0:G:IMSI-001001000000001:TLLI-0xecc1f953){ASSIGN}: Deallocated
UL_ASS_TBF(UL:TFI-0-0-0:G:IMSI-001001000000001:TLLI-0xecc1f953){NONE}: Deallocated
DL_ASS_TBF(UL:TFI-0-0-0:G:IMSI-001001000000001:TLLI-0xecc1f953){WAIT_ACK}: Deallocated
TBF(DL:TFI-0-0-0:G:IMSI-001001000000001:TLLI-0xecc1f953){ASSIGN} free
PDCH(bts=0,trx=0,ts=1) Detaching TBF(DL:TFI-0-0-0:G:IMSI-001001000000001:TLLI-0xecc1f953){ASSIGN}, 1 TBFs, USFs = 00, TFIs = 00000001.
PDCH(bts=0,trx=0,ts=2) Detaching TBF(DL:TFI-0-0-0:G:IMSI-001001000000001:TLLI-0xecc1f953){ASSIGN}, 1 TBFs, USFs = 00, TFIs = 00000001.
PDCH(bts=0,trx=0,ts=3) Detaching TBF(DL:TFI-0-0-0:G:IMSI-001001000000001:TLLI-0xecc1f953){ASSIGN}, 1 TBFs, USFs = 00, TFIs = 00000001.
MS(IMSI-001001000000001:TLLI-0xecc1f953:TA-220:MSCLS-11-0:DL) Detaching TBF: TBF(DL:G:IMSI-001001000000001:TLLI-0xecc1f953){ASSIGN}
MS(IMSI-001001000000001:TLLI-0xecc1f953:TA-220:MSCLS-11-0): - tbf: now used by 0 (-)
********** DL-TBF ends here **********
DL_TBF(DL:TFI-0-0-0:G:IMSI-001001000000001:TLLI-0xecc1f953){ASSIGN}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-0:G:IMSI-001001000000001:TLLI-0xecc1f953){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-0:G:IMSI-001001000000001:TLLI-0xecc1f953){NONE}: Deallocated

19
tests/types/TypesTest.err
View File

@ -9,6 +9,10 @@ TBF(DL:TFI-0-0-0:G){NEW} set ass. type CCCH [prev CCCH:0, PACCH:0]
DL_TBF(DL:TFI-0-0-0:G){NEW}: state_chg to ASSIGN
TBF(DL:TFI-0-0-0:G){ASSIGN} Tx CCCH (PCH) Immediate Assignment [PktDlAss=PDCH(bts=0,trx=0,ts=2)] TA=220
PCU socket not connected, dropping message
TBF(DL:TFI-0-0-0:G){ASSIGN} free
DL_TBF(DL:TFI-0-0-0:G){ASSIGN}: Deallocated
UL_ASS_TBF(DL:TFI-0-0-0:G){NONE}: Deallocated
DL_ASS_TBF(DL:TFI-0-0-0:G){NONE}: Deallocated
MS(TA-220:MSCLS-1-0) Allocating UL TBF
UL_ASS_TBF{NONE}: Allocated
DL_ASS_TBF{NONE}: Allocated
@ -19,6 +23,11 @@ TBF(UL:TFI-0-0-0:G){NEW} Allocated: trx = 0, ul_slots = 10, dl_slots = 00
UL_TBF(UL:TFI-0-0-0:G){NEW}: Received Event ASSIGN_ADD_PACCH
TBF(UL:TFI-0-0-0:G){NEW} set ass. type PACCH [prev CCCH:0, PACCH:0]
UL_TBF(UL:TFI-0-0-0:G){NEW}: state_chg to ASSIGN
TBF(UL:TFI-0-0-0:G){ASSIGN} free
UL_ACK_TBF(UL:TFI-0-0-0:G){NONE}: Deallocated
UL_TBF(UL:TFI-0-0-0:G){ASSIGN}: Deallocated
UL_ASS_TBF(UL:TFI-0-0-0:G){NONE}: Deallocated
DL_ASS_TBF(UL:TFI-0-0-0:G){NONE}: Deallocated
MS(TA-220:MSCLS-1-1) Allocating UL TBF
UL_ASS_TBF{NONE}: Allocated
DL_ASS_TBF{NONE}: Allocated
@ -30,6 +39,11 @@ TBF(UL:TFI-0-0-0:E){NEW} setting EGPRS UL window size to 64, base(64) slots(1) w
UL_TBF(UL:TFI-0-0-0:E){NEW}: Received Event ASSIGN_ADD_PACCH
TBF(UL:TFI-0-0-0:E){NEW} set ass. type PACCH [prev CCCH:0, PACCH:0]
UL_TBF(UL:TFI-0-0-0:E){NEW}: state_chg to ASSIGN
TBF(UL:TFI-0-0-0:E){ASSIGN} free
UL_ACK_TBF(UL:TFI-0-0-0:E){NONE}: Deallocated
UL_TBF(UL:TFI-0-0-0:E){ASSIGN}: Deallocated
UL_ASS_TBF(UL:TFI-0-0-0:E){NONE}: Deallocated
DL_ASS_TBF(UL:TFI-0-0-0:E){NONE}: Deallocated
############## test_egprs_ul_ack_nack
MS(TA-220:MSCLS-1-1) Allocating UL TBF
UL_ASS_TBF{NONE}: Allocated
@ -46,3 +60,8 @@ UL_TBF(UL:TFI-0-0-0:E){NEW}: state_chg to ASSIGN
************** Test with 1 lost packet
************** Test with compressed window
************** Provoke an uncompressed ACK without EOW
TBF(UL:TFI-0-0-0:E){ASSIGN} free
UL_ACK_TBF(UL:TFI-0-0-0:E){NONE}: Deallocated
UL_TBF(UL:TFI-0-0-0:E){ASSIGN}: Deallocated
UL_ASS_TBF(UL:TFI-0-0-0:E){NONE}: Deallocated
DL_ASS_TBF(UL:TFI-0-0-0:E){NONE}: Deallocated

3
tests/ulc/PdchUlcTest.err
View File

@ -75,7 +75,8 @@ PDCH(bts=0,trx=0,ts=0) Timeout for registered POLL (FN=34, reason=UL_ASS): TBF(D
PDCH(bts=0,trx=0,ts=0) Expiring FN=43 but previous FN=39 is still reserved!
PDCH(bts=0,trx=0,ts=0) Timeout for registered POLL (FN=39, reason=UL_ASS): TBF(DL:TFI-0-0-0:G:TLLI-0x12345678){ASSIGN}
MS(TLLI-0x12345678:TA-220:MSCLS-0-0:DL) Destroying MS object
MS(TLLI-0x12345678:TA-220:MSCLS-0-0:DL) Detaching TBF: TBF(DL:TFI-0-0-0:G:TLLI-0x12345678){ASSIGN}
PDCH(bts=0,trx=0,ts=0) Detaching TBF(DL:TFI-0-0-0:G:TLLI-0x12345678){ASSIGN}, 1 TBFs, USFs = 00, TFIs = 00000001.
MS(TLLI-0x12345678:TA-220:MSCLS-0-0:DL) Detaching TBF: TBF(DL:G:TLLI-0x12345678){ASSIGN}
MS(TLLI-0x12345678:TA-220:MSCLS-0-0): - tbf: now used by 0 (-)
PDCH(bts=0,trx=0,ts=0) PDCH state: disabled => enabled
PDCH(bts=0,trx=0,ts=0) Reserving FN 104 for type SBA