pespin has submitted this change. ( https://gerrit.osmocom.org/c/osmo-pcu/+/32350 )

Change subject: ms: Use osmo_use_count to track references ......................................................................

ms: Use osmo_use_count to track references

Change-Id: Ib65629224e6bd5683bb9192ba4354e965e8d39ec --- M src/gprs_ms.c M src/gprs_ms.h M src/pdch.cpp M src/tbf_ul_fsm.c M tests/alloc/AllocTest.cpp M tests/app_info/AppInfoTest.err M tests/ms/MsTest.err M tests/tbf/TbfTest.cpp M tests/tbf/TbfTest.err M tests/ulc/PdchUlcTest.err 10 files changed, 414 insertions(+), 45 deletions(-)

Approvals: osmith: Looks good to me, but someone else must approve Jenkins Builder: Verified fixeria: Looks good to me, approved laforge: Looks good to me, but someone else must approve

diff --git a/src/gprs_ms.c b/src/gprs_ms.c index b9a1b76..f65ffb8 100644 --- a/src/gprs_ms.c +++ b/src/gprs_ms.c @@ -61,6 +61,40 @@ return (int64_t)(ts.tv_sec) * 1000 + ts.tv_nsec / 1000000; }

+static void ms_update_status(struct GprsMs *ms); + +static int ms_use_cb(struct osmo_use_count_entry *e, int32_t old_use_count, const char *file, int line) +{ + struct GprsMs *ms = e->use_count->talloc_object; + int32_t total; + int level; + char buf[1024]; + + if (!e->use) + return -EINVAL; + + total = osmo_use_count_total(&ms->use_count); + + if (total == 0 + || (total == 1 && old_use_count == 0 && e->count == 1)) + level = LOGL_INFO; + else + level = LOGL_DEBUG; + + + LOGPSRC(DRLCMAC, level, file, line, "%s: %s %s: now used by %s\n", + ms_name(ms), + (e->count - old_use_count) > 0 ? "+" : "-", e->use, + (osmo_use_count_to_str_buf(buf, sizeof(buf), &ms->use_count), buf)); + + if (e->count < 0) + return -ERANGE; + + if (total == 0) + ms_update_status(ms); + return 0; +} + void gprs_default_cb_ms_idle(struct GprsMs *ms) { if (ms_is_idle(ms)) @@ -84,7 +118,7 @@

if (ms->timer.data) { ms->timer.data = NULL; - ms_unref(ms); + ms_unref(ms, MS_USE_RELEASE_TIMER); } }

@@ -125,6 +159,11 @@ ms->is_idle = true; INIT_LLIST_HEAD(&ms->old_tbfs);

+ ms->use_count = (struct osmo_use_count){ + .talloc_object = ms, + .use_cb = ms_use_cb, + }; + int codel_interval = LLC_CODEL_USE_DEFAULT;

LOGP(DRLCMAC, LOGL_INFO, "Creating MS object\n"); @@ -204,7 +243,7 @@

static void ms_update_status(struct GprsMs *ms) { - if (ms->ref > 0) + if (osmo_use_count_total(&ms->use_count) > 0) return;

if (ms_is_idle(ms) && !ms->is_idle) { @@ -220,20 +259,6 @@ } }

-struct GprsMs *ms_ref(struct GprsMs *ms) -{ - ms->ref += 1; - return ms; -} - -void ms_unref(struct GprsMs *ms) -{ - OSMO_ASSERT(ms->ref >= 0); - ms->ref -= 1; - if (ms->ref == 0) - ms_update_status(ms); -} - static void ms_release_timer_start(struct GprsMs *ms) { /* Immediate free(): @@ -252,8 +277,10 @@

LOGPMS(ms, DRLCMAC, LOGL_DEBUG, "Schedule MS release in %u secs\n", ms->delay);

- if (!ms->timer.data) - ms->timer.data = ms_ref(ms); + if (!ms->timer.data) { + ms_ref(ms, MS_USE_RELEASE_TIMER); + ms->timer.data = ms; + }

osmo_timer_schedule(&ms->timer, ms->delay, 0); } @@ -267,7 +294,7 @@

osmo_timer_del(&ms->timer); ms->timer.data = NULL; - ms_unref(ms); + ms_unref(ms, MS_USE_RELEASE_TIMER); }

void ms_set_mode(struct GprsMs *ms, enum mcs_kind mode) @@ -328,7 +355,7 @@

LOGPMS(ms, DRLCMAC, LOGL_INFO, "Attaching UL TBF: %s\n", tbf_name((struct gprs_rlcmac_tbf *)tbf));

- ms_ref(ms); + ms_ref(ms, __func__);

if (ms->ul_tbf) llist_add_tail(tbf_ms_list(ul_tbf_as_tbf(ms->ul_tbf)), &ms->old_tbfs); @@ -338,7 +365,7 @@ if (tbf) ms_release_timer_stop(ms);

- ms_unref(ms); + ms_unref(ms, __func__); }

static void ms_attach_dl_tbf(struct GprsMs *ms, struct gprs_rlcmac_dl_tbf *tbf) @@ -348,7 +375,7 @@

LOGPMS(ms, DRLCMAC, LOGL_INFO, "Attaching DL TBF: %s\n", tbf_name((struct gprs_rlcmac_tbf *)tbf));

- ms_ref(ms); + ms_ref(ms, __func__);

if (ms->dl_tbf) llist_add_tail(tbf_ms_list(dl_tbf_as_tbf(ms->dl_tbf)), &ms->old_tbfs); @@ -358,7 +385,7 @@ if (tbf) ms_release_timer_stop(ms);

- ms_unref(ms); + ms_unref(ms, __func__); }

void ms_attach_tbf(struct GprsMs *ms, struct gprs_rlcmac_tbf *tbf) @@ -452,7 +479,7 @@ { char old_ms_name[128]; OSMO_ASSERT(old_ms != ms); - ms_ref(old_ms); + ms_ref(old_ms, __func__);

ms_name_buf(old_ms, old_ms_name, sizeof(old_ms_name));

@@ -478,7 +505,7 @@

ms_reset(old_ms);

- ms_unref(old_ms); + ms_unref(old_ms, __func__); }

/* Apply changes to the TLLI directly, used interally by functions below: */ diff --git a/src/gprs_ms.h b/src/gprs_ms.h index d976235..123db0e 100644 --- a/src/gprs_ms.h +++ b/src/gprs_ms.h @@ -31,6 +31,7 @@ #include <osmocom/core/timer.h> #include <osmocom/core/linuxlist.h> #include <osmocom/core/rate_ctr.h> +#include <osmocom/core/use_count.h>

#include <osmocom/gsm/protocol/gsm_23_003.h> #include <osmocom/gsm/gsm48.h> @@ -84,7 +85,7 @@ struct osmo_timer_list llc_timer;

bool is_idle; - int ref; + struct osmo_use_count use_count; struct osmo_timer_list timer; unsigned delay;

@@ -107,8 +108,6 @@ void ms_set_first_common_ts(struct GprsMs *ms, struct gprs_rlcmac_pdch *pdch); void ms_set_reserved_slots(struct GprsMs *ms, struct gprs_rlcmac_trx *trx, uint8_t ul_slots, uint8_t dl_slots); -struct GprsMs *ms_ref(struct GprsMs *ms); -void ms_unref(struct GprsMs *ms); void ms_set_mode(struct GprsMs *ms, enum mcs_kind mode); void ms_set_ms_class(struct GprsMs *ms, uint8_t ms_class_); void ms_set_egprs_ms_class(struct GprsMs *ms, uint8_t ms_class_); @@ -157,7 +156,9 @@

static inline bool ms_is_idle(const struct GprsMs *ms) { - return !ms->ul_tbf && !ms->dl_tbf && !ms->ref && llist_empty(&ms->old_tbfs); + return !ms->ul_tbf && !ms->dl_tbf && + llist_empty(&ms->old_tbfs) && + osmo_use_count_total(&ms->use_count) == 0; }

static inline struct gprs_llc_queue *ms_llc_queue(struct GprsMs *ms) @@ -250,6 +251,12 @@ return ms->current_trx; }

+#define MS_USE_RELEASE_TIMER "release_timer" +#define ms_ref(ms, use) \ + OSMO_ASSERT(osmo_use_count_get_put(&(ms)->use_count, use, 1) == 0) +#define ms_unref(ms, use) \ + OSMO_ASSERT(osmo_use_count_get_put(&(ms)->use_count, use, -1) == 0) + #define LOGPMS(ms, category, level, fmt, args...) \ LOGP(category, level, "%s " fmt, ms_name(ms), ## args)

diff --git a/src/pdch.cpp b/src/pdch.cpp index 1c39ceb..4495e21 100644 --- a/src/pdch.cpp +++ b/src/pdch.cpp @@ -697,7 +697,7 @@ * temporarily, in order to avoid it being freed if we free any of its * resources (TBF). */ OSMO_ASSERT(ms); - ms_ref(ms); + ms_ref(ms, __func__);

switch (item->type) { @@ -843,7 +843,7 @@ /* schedule uplink assignment */ osmo_fsm_inst_dispatch(new_ul_tbf->ul_ass_fsm.fi, TBF_UL_ASS_EV_SCHED_ASS, NULL); return_unref: - ms_unref(ms); + ms_unref(ms, __func__); return; }

diff --git a/src/tbf_ul_fsm.c b/src/tbf_ul_fsm.c index a7d24ec..8778e32 100644 --- a/src/tbf_ul_fsm.c +++ b/src/tbf_ul_fsm.c @@ -227,7 +227,7 @@ /* Ref the MS, otherwise it may be freed after ul_tbf is * detached when sending event below. */ ms = tbf_ms(ctx->tbf); - ms_ref(ms); + ms_ref(ms, __func__); /* UL TBF ACKed our transmitted UL ACK/NACK with final Ack * Indicator set to '1'. We can free the TBF right away, the MS * also just released its TBF on its side. */ @@ -242,7 +242,7 @@ * now. */ if (!new_ul_tbf_requested && ms_need_dl_tbf(ms)) ms_new_dl_tbf_assigned_on_pch(ms); - ms_unref(ms); + ms_unref(ms, __func__); break; case TBF_EV_MAX_N3103: ctx->T_release = 3169; diff --git a/tests/alloc/AllocTest.cpp b/tests/alloc/AllocTest.cpp index cf1bba7..065a257 100644 --- a/tests/alloc/AllocTest.cpp +++ b/tests/alloc/AllocTest.cpp @@ -465,7 +465,7 @@ if (ms_current_trx(old_ms)) trx_no = ms_current_trx(old_ms)->trx_no;

- ms_ref(old_ms); + ms_ref(old_ms, __func__);

/* Allocate what is needed first */ switch (mode) { @@ -477,7 +477,7 @@ tbf = ul_tbf_alloc(bts, old_ms, trx_no, false); if (tbf == NULL) { OSMO_ASSERT(trx_no != -1 || bts_all_pdch_allocated(bts)); - ms_unref(old_ms); + ms_unref(old_ms, __func__); return NULL; } break; @@ -489,7 +489,7 @@ tbf = dl_tbf_alloc(bts, old_ms, trx_no, false); if (tbf == NULL) { OSMO_ASSERT(trx_no != -1 || bts_all_pdch_allocated(bts)); - ms_unref(old_ms); + ms_unref(old_ms, __func__); return NULL; } } @@ -499,7 +499,7 @@ OSMO_ASSERT(old_ms == tbf->ms()); ms = tbf->ms();

- ms_ref(ms); + ms_ref(ms, __func__); new_ms = ms; /* Continue with what is needed next */ switch (mode) { @@ -534,8 +534,8 @@ if (!new_ms && tbf) tbf_free(tbf);

- ms_unref(old_ms); - ms_unref(ms); + ms_unref(old_ms, __func__); + ms_unref(ms, __func__); return new_ms; }

diff --git a/tests/app_info/AppInfoTest.err b/tests/app_info/AppInfoTest.err index 50ebd45..e8c8acf 100644 --- a/tests/app_info/AppInfoTest.err +++ b/tests/app_info/AppInfoTest.err @@ -24,6 +24,8 @@ PDCH(bts=0,trx=0,ts=6) Attaching TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS), 1 TBFs, USFs = 00, TFIs = 00000001. PDCH(bts=0,trx=0,ts=7) Attaching TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS), 1 TBFs, USFs = 00, TFIs = 00000001. MS(TA-220:MSCLS-10-11) Attaching DL TBF: TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) +MS(TA-220:MSCLS-10-11): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(TA-220:MSCLS-10-11:DL): - ms_attach_dl_tbf: now used by 0 (-) ws(64) Creating MS object Modifying MS object, TLLI = 0xffffffff, MS class 0 -> 12 @@ -34,6 +36,8 @@ PDCH(bts=0,trx=0,ts=5) Attaching TBF(DL:TFI-0-0-1:STATE-NEW:EGPRS), 2 TBFs, USFs = 00, TFIs = 00000003. PDCH(bts=0,trx=0,ts=6) Attaching TBF(DL:TFI-0-0-1:STATE-NEW:EGPRS), 2 TBFs, USFs = 00, TFIs = 00000003. MS(TA-220:MSCLS-12-13) Attaching DL TBF: TBF(DL:TFI-0-0-1:STATE-NEW:EGPRS) +MS(TA-220:MSCLS-12-13): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(TA-220:MSCLS-12-13:DL): - ms_attach_dl_tbf: now used by 0 (-) ws(64)

--- test_sched_app_info_ok --- diff --git a/tests/ms/MsTest.err b/tests/ms/MsTest.err index d667740..3d271ae 100644 --- a/tests/ms/MsTest.err +++ b/tests/ms/MsTest.err @@ -1,22 +1,36 @@ Creating MS object Modifying MS object, UL TLLI: 0xffffffff -> 0xffeeddbb, not yet confirmed MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0) Attaching UL TBF: TBF(UL:STATE-NEW:GPRS:TLLI-0xffeeddbb) +MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0): + ms_attach_ul_tbf: now used by 1 (ms_attach_ul_tbf) +MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0:UL): - ms_attach_ul_tbf: now used by 0 (-) MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0:UL) Attaching DL TBF: TBF(DL:STATE-NEW:GPRS:TLLI-0xffeeddbb) +MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0:UL): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0:UL:DL): - ms_attach_dl_tbf: now used by 0 (-) MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0:DL) Detaching TBF: TBF(UL:STATE-NEW:GPRS:TLLI-0xffeeddbb) MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0) Detaching TBF: TBF(DL:STATE-NEW:GPRS:TLLI-0xffeeddbb) MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0) Destroying MS object Creating MS object Modifying MS object, UL TLLI: 0xffffffff -> 0xffeeddbb, not yet confirmed MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0) Attaching UL TBF: TBF(UL:STATE-NEW:GPRS:TLLI-0xffeeddbb) +MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0): + ms_attach_ul_tbf: now used by 1 (ms_attach_ul_tbf) +MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0:UL): - ms_attach_ul_tbf: now used by 0 (-) MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0:UL) Attaching DL TBF: TBF(DL:STATE-NEW:GPRS:TLLI-0xffeeddbb) +MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0:UL): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0:UL:DL): - ms_attach_dl_tbf: now used by 0 (-) MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0:DL) Detaching TBF: TBF(UL:STATE-NEW:GPRS:TLLI-0xffeeddbb) MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0) Detaching TBF: TBF(DL:STATE-NEW:GPRS:TLLI-0xffeeddbb) MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0) Destroying MS object Creating MS object 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:STATE-NEW:GPRS:TLLI-0xffeeddbb) +MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0:DL): - ms_attach_dl_tbf: now used by 0 (-) MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0:DL) Attaching DL TBF: TBF(DL:STATE-NEW:GPRS:TLLI-0xffeeddbb) +MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0:DL): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0:DL): - ms_attach_dl_tbf: now used by 0 (-) MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0:DL) Attaching UL TBF: TBF(UL:STATE-NEW:GPRS:TLLI-0xffeeddbb) +MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0:DL): + ms_attach_ul_tbf: now used by 1 (ms_attach_ul_tbf) +MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0:UL:DL): - ms_attach_ul_tbf: now used by 0 (-) MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0:DL) Detaching TBF: TBF(UL:STATE-NEW:GPRS:TLLI-0xffeeddbb) MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0:DL) Detaching TBF: TBF(DL:STATE-NEW:GPRS:TLLI-0xffeeddbb) MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0) Detaching TBF: TBF(DL:STATE-NEW:GPRS:TLLI-0xffeeddbb) @@ -45,22 +59,34 @@ Modifying MS object, UL TLLI: 0xffffffff -> 0xffeeddbc, not yet confirmed Modifying MS object, TLLI = 0xffeeddbc, IMSI '' -> '001001987654322' MS(IMSI-001001987654321:TLLI-0xffeeddbb:TA-220:MSCLS-0-0) Attaching UL TBF: TBF(UL:STATE-NEW:GPRS:IMSI-001001987654321:TLLI-0xffeeddbb) +MS(IMSI-001001987654321:TLLI-0xffeeddbb:TA-220:MSCLS-0-0): + ms_attach_ul_tbf: now used by 1 (ms_attach_ul_tbf) +MS(IMSI-001001987654321:TLLI-0xffeeddbb:TA-220:MSCLS-0-0:UL): - ms_attach_ul_tbf: now used by 0 (-) MS(IMSI-001001987654321:TLLI-0xffeeddbb:TA-220:MSCLS-0-0) Detaching TBF: TBF(UL:STATE-NEW:GPRS:IMSI-001001987654321:TLLI-0xffeeddbb) MS(IMSI-001001987654321:TLLI-0xffeeddbb:TA-220:MSCLS-0-0) Destroying MS object MS(IMSI-001001987654322:TLLI-0xffeeddbc:TA-220:MSCLS-0-0) Attaching UL TBF: TBF(UL:STATE-NEW:GPRS) +MS(IMSI-001001987654322:TLLI-0xffeeddbc:TA-220:MSCLS-0-0): + ms_attach_ul_tbf: now used by 1 (ms_attach_ul_tbf) +MS(IMSI-001001987654322:TLLI-0xffeeddbc:TA-220:MSCLS-0-0:UL): - ms_attach_ul_tbf: now used by 0 (-) MS(IMSI-001001987654322:TLLI-0xffeeddbc:TA-220:MSCLS-0-0) Detaching TBF: TBF(UL:STATE-NEW:GPRS) MS(IMSI-001001987654322:TLLI-0xffeeddbc:TA-220:MSCLS-0-0) Destroying MS object Creating MS object Modifying MS object, UL TLLI: 0xffffffff -> 0xffeeddbb, not yet confirmed MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0) Attaching UL TBF: TBF(UL:STATE-NEW:GPRS:TLLI-0xffeeddbb) +MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0): + ms_attach_ul_tbf: now used by 1 (ms_attach_ul_tbf) +MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0:UL): - ms_attach_ul_tbf: now used by 0 (-) MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0:UL) Attaching DL TBF: TBF(DL:STATE-NEW:GPRS:TLLI-0xffeeddbb) +MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0:UL): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0:UL:DL): - ms_attach_dl_tbf: now used by 0 (-) MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0:DL) Detaching TBF: TBF(UL:STATE-NEW:GPRS:TLLI-0xffeeddbb) MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0) Detaching TBF: TBF(DL:STATE-NEW:GPRS:TLLI-0xffeeddbb) +MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0): + release_timer: now used by 1 (release_timer) MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0) Release timer expired +MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0): - release_timer: now used by 0 (-) MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0) Destroying MS object Creating MS object 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:STATE-NEW:GPRS:TLLI-0xffeeddbb) +MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0:DL): - ms_attach_dl_tbf: now used by 0 (-) MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0:DL) Destroying MS object MS(TLLI-0xffeeddbb:TA-220:MSCLS-0-0) Detaching TBF: TBF(DL:STATE-NEW:GPRS:TLLI-0xffeeddbb) Creating MS object @@ -68,6 +94,8 @@ Creating MS object 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:STATE-NEW:GPRS:TLLI-0xdeadbef0) +MS(TLLI-0xdeadbef0:TA-220:MSCLS-0-0): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(TLLI-0xdeadbef0:TA-220:MSCLS-0-0:DL): - ms_attach_dl_tbf: now used by 0 (-) 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) Detaching TBF: TBF(DL:STATE-NEW:GPRS:TLLI-0xdeadbef0) diff --git a/tests/tbf/TbfTest.cpp b/tests/tbf/TbfTest.cpp index 0f0960a..e67bd17 100644 --- a/tests/tbf/TbfTest.cpp +++ b/tests/tbf/TbfTest.cpp @@ -340,20 +340,20 @@ OSMO_ASSERT(new_tbf->tfi() == 1); check_tbf(dl_tbf); if (test_mode == TEST_MODE_REVERSE_FREE) { - ms_ref(ms); + ms_ref(ms, __func__); tbf_free(new_tbf); OSMO_ASSERT(ms_dl_tbf(ms) == NULL); check_tbf(dl_tbf); tbf_free(dl_tbf); - ms_unref(ms); + ms_unref(ms, __func__); } else { - ms_ref(ms); + ms_ref(ms, __func__); tbf_free(dl_tbf); OSMO_ASSERT(ms_dl_tbf(ms) == new_tbf); check_tbf(new_tbf); tbf_free(new_tbf); OSMO_ASSERT(ms_dl_tbf(ms) == NULL); - ms_unref(ms); + ms_unref(ms, __func__); }

TALLOC_FREE(the_pcu); @@ -469,14 +469,14 @@

/* use the same IMSI on TBF 1 */ { - ms_ref(ms2); + ms_ref(ms2, __func__); ms_set_imsi(dl_tbf[1]->ms(), "001001000000002"); ms1 = bts_get_ms(bts, GSM_RESERVED_TMSI, GSM_RESERVED_TMSI, "001001000000002"); OSMO_ASSERT(ms1 != NULL); OSMO_ASSERT(ms1 != ms2); OSMO_ASSERT(strcmp(ms_imsi(ms1), "001001000000002") == 0); OSMO_ASSERT(strcmp(ms_imsi(ms2), "") == 0); - ms_unref(ms2); + ms_unref(ms2, __func__); }

ms2 = bts_get_ms_by_tlli(bts, 0xf1000001, GSM_RESERVED_TMSI); diff --git a/tests/tbf/TbfTest.err b/tests/tbf/TbfTest.err index 73f2903..c65377a 100644 --- a/tests/tbf/TbfTest.err +++ b/tests/tbf/TbfTest.err @@ -22,6 +22,8 @@ TBF(DL:TFI-0-0-0:STATE-NEW:GPRS) Setting Control TS PDCH(bts=0,trx=0,ts=2) TBF(DL:TFI-0-0-0:STATE-NEW:GPRS) Allocated: trx = 0, ul_slots = 04, dl_slots = 04 MS(TA-220:MSCLS-0-0) Attaching DL TBF: TBF(DL:TFI-0-0-0:STATE-NEW:GPRS) +MS(TA-220:MSCLS-0-0): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(TA-220:MSCLS-0-0:DL): - ms_attach_dl_tbf: now used by 0 (-) The MS object cannot fully confirm an unexpected TLLI: 0x00002342, partly confirmed Modifying MS object, TLLI = 0x00002342, TA 220 -> 4 MS(TLLI-0x00002342:TA-4:MSCLS-0-0:DL) ********** UL-TBF starts here ********** @@ -43,6 +45,8 @@ TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0x00002342) Setting Control TS PDCH(bts=0,trx=0,ts=2) TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0x00002342) Allocated: trx = 0, ul_slots = 04, dl_slots = 00 MS(TLLI-0x00002342:TA-4:MSCLS-0-0:DL) Attaching UL TBF: TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0x00002342) +MS(TLLI-0x00002342:TA-4:MSCLS-0-0:DL): + ms_attach_ul_tbf: now used by 1 (ms_attach_ul_tbf) +MS(TLLI-0x00002342:TA-4:MSCLS-0-0:UL:DL): - ms_attach_ul_tbf: now used by 0 (-) Modifying MS object, TLLI: 0xffffffff -> 0x00002342, already confirmed partly The MS object cannot fully confirm an unexpected TLLI: 0x00004232, partly confirmed Modifying MS object, TLLI: 0x00002342 -> 0x00004232, already confirmed partly @@ -74,6 +78,8 @@ TBF(DL:TFI-0-0-0:STATE-NEW:GPRS) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-0:STATE-NEW:GPRS) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(TA-220:MSCLS-45-0) Attaching DL TBF: TBF(DL:TFI-0-0-0:STATE-NEW:GPRS) +MS(TA-220:MSCLS-45-0): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(TA-220:MSCLS-45-0:DL): - ms_attach_dl_tbf: now used by 0 (-) Modifying MS object, TLLI = 0xffffffff, TA 220 -> 0 DL_ASS_TBF(DL:TFI-0-0-0:STATE-NEW:GPRS){NONE}: Received Event SCHED_ASS DL_ASS_TBF(DL:TFI-0-0-0:STATE-NEW:GPRS){NONE}: state_chg to SEND_ASS @@ -141,6 +147,8 @@ TBF(DL:TFI-0-0-1:STATE-NEW:GPRS:TLLI-0xffeeddcc) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-1:STATE-NEW:GPRS:TLLI-0xffeeddcc) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(TLLI-0xffeeddcc:TA-0:MSCLS-45-0:DL) Attaching DL TBF: TBF(DL:TFI-0-0-1:STATE-NEW:GPRS:TLLI-0xffeeddcc) +MS(TLLI-0xffeeddcc:TA-0:MSCLS-45-0:DL): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(TLLI-0xffeeddcc:TA-0:MSCLS-45-0:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-1:STATE-NEW:GPRS:TLLI-0xffeeddcc) [DOWNLINK] START (PACCH) TBF(DL:TFI-0-0-1:STATE-NEW:GPRS:TLLI-0xffeeddcc) Send downlink assignment on PACCH, because TBF(DL:TFI-0-0-0:STATE-WAIT_RELEASE:GPRS:TLLI-0xffeeddcc) exists DL_ASS_TBF(DL:TFI-0-0-0:STATE-NEW:GPRS){WAIT_ACK}: Received Event SCHED_ASS @@ -149,6 +157,7 @@ TBF(DL:TFI-0-0-1:STATE-NEW:GPRS:TLLI-0xffeeddcc) set ass. type PACCH [prev CCCH:0, PACCH:0] DL_TBF(DL:TFI-0-0-1:STATE-NEW:GPRS:TLLI-0xffeeddcc){NEW}: state_chg to ASSIGN TBF(DL:TFI-0-0-1:STATE-ASSIGN:GPRS:TLLI-0xffeeddcc) Starting timer X2001 [assignment (PACCH)] with 2 sec. 0 microsec +MS(TLLI-0xffeeddcc:TA-0:MSCLS-45-0:DL): + test_tbf_final_ack: now used by 1 (test_tbf_final_ack) TBF(DL:TFI-0-0-0:STATE-WAIT_RELEASE:GPRS:TLLI-0xffeeddcc) free PDCH(bts=0,trx=0,ts=4) Detaching TBF(DL:TFI-0-0-0:STATE-WAIT_RELEASE:GPRS:TLLI-0xffeeddcc), 2 TBFs, USFs = 00, TFIs = 00000003. MS(TLLI-0xffeeddcc:TA-0:MSCLS-45-0:DL) Detaching TBF: TBF(DL:TFI-0-0-0:STATE-WAIT_RELEASE:GPRS:TLLI-0xffeeddcc) @@ -163,6 +172,7 @@ DL_TBF(DL:TFI-0-0-1:STATE-NEW:GPRS:TLLI-0xffeeddcc){ASSIGN}: Deallocated UL_ASS_TBF(DL:TFI-0-0-1:STATE-NEW:GPRS:TLLI-0xffeeddcc){NONE}: Deallocated DL_ASS_TBF(DL:TFI-0-0-1:STATE-NEW:GPRS:TLLI-0xffeeddcc){NONE}: Deallocated +MS(TLLI-0xffeeddcc:TA-0:MSCLS-45-0): - test_tbf_final_ack: now used by 0 (-) MS(TLLI-0xffeeddcc:TA-0:MSCLS-45-0) Destroying MS object === end test_tbf_final_ack === === start test_tbf_final_ack === @@ -188,6 +198,8 @@ TBF(DL:TFI-0-0-0:STATE-NEW:GPRS) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-0:STATE-NEW:GPRS) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(TA-220:MSCLS-45-0) Attaching DL TBF: TBF(DL:TFI-0-0-0:STATE-NEW:GPRS) +MS(TA-220:MSCLS-45-0): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(TA-220:MSCLS-45-0:DL): - ms_attach_dl_tbf: now used by 0 (-) Modifying MS object, TLLI = 0xffffffff, TA 220 -> 0 DL_ASS_TBF(DL:TFI-0-0-0:STATE-NEW:GPRS){NONE}: Received Event SCHED_ASS DL_ASS_TBF(DL:TFI-0-0-0:STATE-NEW:GPRS){NONE}: state_chg to SEND_ASS @@ -255,6 +267,8 @@ TBF(DL:TFI-0-0-1:STATE-NEW:GPRS:TLLI-0xffeeddcc) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-1:STATE-NEW:GPRS:TLLI-0xffeeddcc) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(TLLI-0xffeeddcc:TA-0:MSCLS-45-0:DL) Attaching DL TBF: TBF(DL:TFI-0-0-1:STATE-NEW:GPRS:TLLI-0xffeeddcc) +MS(TLLI-0xffeeddcc:TA-0:MSCLS-45-0:DL): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(TLLI-0xffeeddcc:TA-0:MSCLS-45-0:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-1:STATE-NEW:GPRS:TLLI-0xffeeddcc) [DOWNLINK] START (PACCH) TBF(DL:TFI-0-0-1:STATE-NEW:GPRS:TLLI-0xffeeddcc) Send downlink assignment on PACCH, because TBF(DL:TFI-0-0-0:STATE-WAIT_RELEASE:GPRS:TLLI-0xffeeddcc) exists DL_ASS_TBF(DL:TFI-0-0-0:STATE-NEW:GPRS){WAIT_ACK}: Received Event SCHED_ASS @@ -263,6 +277,7 @@ TBF(DL:TFI-0-0-1:STATE-NEW:GPRS:TLLI-0xffeeddcc) set ass. type PACCH [prev CCCH:0, PACCH:0] DL_TBF(DL:TFI-0-0-1:STATE-NEW:GPRS:TLLI-0xffeeddcc){NEW}: state_chg to ASSIGN TBF(DL:TFI-0-0-1:STATE-ASSIGN:GPRS:TLLI-0xffeeddcc) Starting timer X2001 [assignment (PACCH)] with 2 sec. 0 microsec +MS(TLLI-0xffeeddcc:TA-0:MSCLS-45-0:DL): + test_tbf_final_ack: now used by 1 (test_tbf_final_ack) TBF(DL:TFI-0-0-1:STATE-ASSIGN:GPRS:TLLI-0xffeeddcc) free PDCH(bts=0,trx=0,ts=4) Detaching TBF(DL:TFI-0-0-1:STATE-ASSIGN:GPRS:TLLI-0xffeeddcc), 2 TBFs, USFs = 00, TFIs = 00000003. MS(TLLI-0xffeeddcc:TA-0:MSCLS-45-0) Detaching TBF: TBF(DL:TFI-0-0-1:STATE-ASSIGN:GPRS:TLLI-0xffeeddcc) @@ -277,6 +292,7 @@ DL_TBF(DL:TFI-0-0-0:STATE-NEW:GPRS){WAIT_RELEASE}: Deallocated UL_ASS_TBF(DL:TFI-0-0-0:STATE-NEW:GPRS){NONE}: Deallocated DL_ASS_TBF(DL:TFI-0-0-0:STATE-NEW:GPRS){WAIT_ACK}: Deallocated +MS(TLLI-0xffeeddcc:TA-0:MSCLS-45-0): - test_tbf_final_ack: now used by 0 (-) MS(TLLI-0xffeeddcc:TA-0:MSCLS-45-0) Destroying MS object === end test_tbf_final_ack === === start test_tbf_delayed_release === @@ -302,6 +318,8 @@ TBF(DL:TFI-0-0-0:STATE-NEW:GPRS) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-0:STATE-NEW:GPRS) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(TA-220:MSCLS-45-0) Attaching DL TBF: TBF(DL:TFI-0-0-0:STATE-NEW:GPRS) +MS(TA-220:MSCLS-45-0): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(TA-220:MSCLS-45-0:DL): - ms_attach_dl_tbf: now used by 0 (-) Modifying MS object, TLLI = 0xffffffff, TA 220 -> 0 DL_ASS_TBF(DL:TFI-0-0-0:STATE-NEW:GPRS){NONE}: Received Event SCHED_ASS DL_ASS_TBF(DL:TFI-0-0-0:STATE-NEW:GPRS){NONE}: state_chg to SEND_ASS @@ -573,6 +591,8 @@ TBF(DL:TFI-0-0-0:STATE-NEW:GPRS) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-0:STATE-NEW:GPRS) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(TA-220:MSCLS-45-0) Attaching DL TBF: TBF(DL:TFI-0-0-0:STATE-NEW:GPRS) +MS(TA-220:MSCLS-45-0): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(TA-220:MSCLS-45-0:DL): - ms_attach_dl_tbf: now used by 0 (-) Modifying MS object, TLLI = 0xffffffff, TA 220 -> 0 DL_ASS_TBF(DL:TFI-0-0-0:STATE-NEW:GPRS){NONE}: Received Event SCHED_ASS DL_ASS_TBF(DL:TFI-0-0-0:STATE-NEW:GPRS){NONE}: state_chg to SEND_ASS @@ -605,6 +625,8 @@ TBF(DL:TFI-0-0-1:STATE-NEW:GPRS) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-1:STATE-NEW:GPRS) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(TA-220:MSCLS-45-0) Attaching DL TBF: TBF(DL:TFI-0-0-1:STATE-NEW:GPRS) +MS(TA-220:MSCLS-45-0): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(TA-220:MSCLS-45-0:DL): - ms_attach_dl_tbf: now used by 0 (-) Modifying MS object, TLLI = 0xffffffff, TA 220 -> 0 DL_ASS_TBF(DL:TFI-0-0-1:STATE-NEW:GPRS){NONE}: Received Event SCHED_ASS DL_ASS_TBF(DL:TFI-0-0-1:STATE-NEW:GPRS){NONE}: state_chg to SEND_ASS @@ -620,8 +642,10 @@ The MS object cannot fully confirm an unexpected TLLI: 0xf1000002, partly confirmed Modifying MS object, TLLI = 0xf1000001, IMSI '' -> '001001000000001' Modifying MS object, TLLI = 0xf1000001, IMSI '001001000000001' -> '001001000000002' +MS(IMSI-001001000000002:TLLI-0xf1000001:TA-0:MSCLS-45-0:DL): + test_tbf_imsi: now used by 1 (test_tbf_imsi) Modifying MS object, TLLI = 0xf1000002, IMSI '' -> '001001000000002' MS(TLLI-0xf1000002:TA-0:MSCLS-45-0:DL) IMSI '001001000000002' was already assigned to another MS object: TLLI = 0xf1000001, that IMSI will be removed +MS(IMSI-001001000000002:TLLI-0xf1000001:TA-0:MSCLS-45-0:DL): + ms_merge_and_clear_ms: now used by 2 (test_tbf_imsi,ms_merge_and_clear_ms) MS(TLLI-0xf1000002:TA-0:MSCLS-45-0:DL) Merge MS: MS(IMSI-001001000000002:TLLI-0xf1000001:TA-0:MSCLS-45-0:DL) TBF(DL:TFI-0-0-0:STATE-FLOW:GPRS:IMSI-001001000000002:TLLI-0xf1000001) free PDCH(bts=0,trx=0,ts=4) Detaching TBF(DL:TFI-0-0-0:STATE-FLOW:GPRS:IMSI-001001000000002:TLLI-0xf1000001), 2 TBFs, USFs = 00, TFIs = 00000003. @@ -631,6 +655,8 @@ UL_ASS_TBF(DL:TFI-0-0-0:STATE-FLOW:GPRS:IMSI-001001000000002:TLLI-0xf1000001){NONE}: Deallocated DL_ASS_TBF(DL:TFI-0-0-0:STATE-FLOW:GPRS:IMSI-001001000000002:TLLI-0xf1000001){SEND_ASS}: Deallocated MS(IMSI-001001000000002:TLLI-0xf1000001:TA-0:MSCLS-45-0) Clearing MS object +MS(TA-0:MSCLS-45-0): - ms_merge_and_clear_ms: now used by 1 (test_tbf_imsi) +MS(TA-0:MSCLS-45-0): - test_tbf_imsi: now used by 0 (-) MS(TA-0:MSCLS-45-0) Destroying MS object TBF(DL:TFI-0-0-1:STATE-FLOW:GPRS:IMSI-001001000000002:TLLI-0xf1000002) free PDCH(bts=0,trx=0,ts=4) Detaching TBF(DL:TFI-0-0-1:STATE-FLOW:GPRS:IMSI-001001000000002:TLLI-0xf1000002), 1 TBFs, USFs = 00, TFIs = 00000002. @@ -666,6 +692,8 @@ TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-001001000000000:TLLI-0xc0000000) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-001001000000000:TLLI-0xc0000000) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(IMSI-001001000000000:TLLI-0xc0000000:TA-220:MSCLS-45-0) Attaching DL TBF: TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-001001000000000:TLLI-0xc0000000) +MS(IMSI-001001000000000:TLLI-0xc0000000:TA-220:MSCLS-45-0): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-001001000000000:TLLI-0xc0000000:TA-220:MSCLS-45-0:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-001001000000000:TLLI-0xc0000000) [DOWNLINK] START (PCH) TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-001001000000000:TLLI-0xc0000000) Send downlink assignment on PCH, no TBF exist (IMSI=001001000000000) DL_TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-001001000000000:TLLI-0xc0000000){NEW}: Received Event ASSIGN_ADD_CCCH @@ -696,6 +724,8 @@ TBF(DL:TFI-0-0-1:STATE-NEW:GPRS:IMSI-001001000000001:TLLI-0xc0000001) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-1:STATE-NEW:GPRS:IMSI-001001000000001:TLLI-0xc0000001) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(IMSI-001001000000001:TLLI-0xc0000001:TA-220:MSCLS-45-0) Attaching DL TBF: TBF(DL:TFI-0-0-1:STATE-NEW:GPRS:IMSI-001001000000001:TLLI-0xc0000001) +MS(IMSI-001001000000001:TLLI-0xc0000001:TA-220:MSCLS-45-0): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-001001000000001:TLLI-0xc0000001:TA-220:MSCLS-45-0:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-1:STATE-NEW:GPRS:IMSI-001001000000001:TLLI-0xc0000001) [DOWNLINK] START (PCH) TBF(DL:TFI-0-0-1:STATE-NEW:GPRS:IMSI-001001000000001:TLLI-0xc0000001) Send downlink assignment on PCH, no TBF exist (IMSI=001001000000001) DL_TBF(DL:TFI-0-0-1:STATE-NEW:GPRS:IMSI-001001000000001:TLLI-0xc0000001){NEW}: Received Event ASSIGN_ADD_CCCH @@ -726,6 +756,8 @@ TBF(DL:TFI-0-0-2:STATE-NEW:GPRS:IMSI-001001000000002:TLLI-0xc0000002) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-2:STATE-NEW:GPRS:IMSI-001001000000002:TLLI-0xc0000002) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(IMSI-001001000000002:TLLI-0xc0000002:TA-220:MSCLS-45-0) Attaching DL TBF: TBF(DL:TFI-0-0-2:STATE-NEW:GPRS:IMSI-001001000000002:TLLI-0xc0000002) +MS(IMSI-001001000000002:TLLI-0xc0000002:TA-220:MSCLS-45-0): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-001001000000002:TLLI-0xc0000002:TA-220:MSCLS-45-0:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-2:STATE-NEW:GPRS:IMSI-001001000000002:TLLI-0xc0000002) [DOWNLINK] START (PCH) TBF(DL:TFI-0-0-2:STATE-NEW:GPRS:IMSI-001001000000002:TLLI-0xc0000002) Send downlink assignment on PCH, no TBF exist (IMSI=001001000000002) DL_TBF(DL:TFI-0-0-2:STATE-NEW:GPRS:IMSI-001001000000002:TLLI-0xc0000002){NEW}: Received Event ASSIGN_ADD_CCCH @@ -756,6 +788,8 @@ TBF(DL:TFI-0-0-3:STATE-NEW:GPRS:IMSI-001001000000003:TLLI-0xc0000003) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-3:STATE-NEW:GPRS:IMSI-001001000000003:TLLI-0xc0000003) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(IMSI-001001000000003:TLLI-0xc0000003:TA-220:MSCLS-45-0) Attaching DL TBF: TBF(DL:TFI-0-0-3:STATE-NEW:GPRS:IMSI-001001000000003:TLLI-0xc0000003) +MS(IMSI-001001000000003:TLLI-0xc0000003:TA-220:MSCLS-45-0): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-001001000000003:TLLI-0xc0000003:TA-220:MSCLS-45-0:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-3:STATE-NEW:GPRS:IMSI-001001000000003:TLLI-0xc0000003) [DOWNLINK] START (PCH) TBF(DL:TFI-0-0-3:STATE-NEW:GPRS:IMSI-001001000000003:TLLI-0xc0000003) Send downlink assignment on PCH, no TBF exist (IMSI=001001000000003) DL_TBF(DL:TFI-0-0-3:STATE-NEW:GPRS:IMSI-001001000000003:TLLI-0xc0000003){NEW}: Received Event ASSIGN_ADD_CCCH @@ -786,6 +820,8 @@ TBF(DL:TFI-0-0-4:STATE-NEW:GPRS:IMSI-001001000000004:TLLI-0xc0000004) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-4:STATE-NEW:GPRS:IMSI-001001000000004:TLLI-0xc0000004) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(IMSI-001001000000004:TLLI-0xc0000004:TA-220:MSCLS-45-0) Attaching DL TBF: TBF(DL:TFI-0-0-4:STATE-NEW:GPRS:IMSI-001001000000004:TLLI-0xc0000004) +MS(IMSI-001001000000004:TLLI-0xc0000004:TA-220:MSCLS-45-0): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-001001000000004:TLLI-0xc0000004:TA-220:MSCLS-45-0:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-4:STATE-NEW:GPRS:IMSI-001001000000004:TLLI-0xc0000004) [DOWNLINK] START (PCH) TBF(DL:TFI-0-0-4:STATE-NEW:GPRS:IMSI-001001000000004:TLLI-0xc0000004) Send downlink assignment on PCH, no TBF exist (IMSI=001001000000004) DL_TBF(DL:TFI-0-0-4:STATE-NEW:GPRS:IMSI-001001000000004:TLLI-0xc0000004){NEW}: Received Event ASSIGN_ADD_CCCH @@ -816,6 +852,8 @@ TBF(DL:TFI-0-0-5:STATE-NEW:GPRS:IMSI-001001000000005:TLLI-0xc0000005) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-5:STATE-NEW:GPRS:IMSI-001001000000005:TLLI-0xc0000005) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(IMSI-001001000000005:TLLI-0xc0000005:TA-220:MSCLS-45-0) Attaching DL TBF: TBF(DL:TFI-0-0-5:STATE-NEW:GPRS:IMSI-001001000000005:TLLI-0xc0000005) +MS(IMSI-001001000000005:TLLI-0xc0000005:TA-220:MSCLS-45-0): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-001001000000005:TLLI-0xc0000005:TA-220:MSCLS-45-0:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-5:STATE-NEW:GPRS:IMSI-001001000000005:TLLI-0xc0000005) [DOWNLINK] START (PCH) TBF(DL:TFI-0-0-5:STATE-NEW:GPRS:IMSI-001001000000005:TLLI-0xc0000005) Send downlink assignment on PCH, no TBF exist (IMSI=001001000000005) DL_TBF(DL:TFI-0-0-5:STATE-NEW:GPRS:IMSI-001001000000005:TLLI-0xc0000005){NEW}: Received Event ASSIGN_ADD_CCCH @@ -846,6 +884,8 @@ TBF(DL:TFI-0-0-6:STATE-NEW:GPRS:IMSI-001001000000006:TLLI-0xc0000006) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-6:STATE-NEW:GPRS:IMSI-001001000000006:TLLI-0xc0000006) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(IMSI-001001000000006:TLLI-0xc0000006:TA-220:MSCLS-45-0) Attaching DL TBF: TBF(DL:TFI-0-0-6:STATE-NEW:GPRS:IMSI-001001000000006:TLLI-0xc0000006) +MS(IMSI-001001000000006:TLLI-0xc0000006:TA-220:MSCLS-45-0): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-001001000000006:TLLI-0xc0000006:TA-220:MSCLS-45-0:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-6:STATE-NEW:GPRS:IMSI-001001000000006:TLLI-0xc0000006) [DOWNLINK] START (PCH) TBF(DL:TFI-0-0-6:STATE-NEW:GPRS:IMSI-001001000000006:TLLI-0xc0000006) Send downlink assignment on PCH, no TBF exist (IMSI=001001000000006) DL_TBF(DL:TFI-0-0-6:STATE-NEW:GPRS:IMSI-001001000000006:TLLI-0xc0000006){NEW}: Received Event ASSIGN_ADD_CCCH @@ -876,6 +916,8 @@ TBF(DL:TFI-0-0-7:STATE-NEW:GPRS:IMSI-001001000000007:TLLI-0xc0000007) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-7:STATE-NEW:GPRS:IMSI-001001000000007:TLLI-0xc0000007) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(IMSI-001001000000007:TLLI-0xc0000007:TA-220:MSCLS-45-0) Attaching DL TBF: TBF(DL:TFI-0-0-7:STATE-NEW:GPRS:IMSI-001001000000007:TLLI-0xc0000007) +MS(IMSI-001001000000007:TLLI-0xc0000007:TA-220:MSCLS-45-0): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-001001000000007:TLLI-0xc0000007:TA-220:MSCLS-45-0:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-7:STATE-NEW:GPRS:IMSI-001001000000007:TLLI-0xc0000007) [DOWNLINK] START (PCH) TBF(DL:TFI-0-0-7:STATE-NEW:GPRS:IMSI-001001000000007:TLLI-0xc0000007) Send downlink assignment on PCH, no TBF exist (IMSI=001001000000007) DL_TBF(DL:TFI-0-0-7:STATE-NEW:GPRS:IMSI-001001000000007:TLLI-0xc0000007){NEW}: Received Event ASSIGN_ADD_CCCH @@ -906,6 +948,8 @@ TBF(DL:TFI-0-0-8:STATE-NEW:GPRS:IMSI-001001000000008:TLLI-0xc0000008) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-8:STATE-NEW:GPRS:IMSI-001001000000008:TLLI-0xc0000008) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(IMSI-001001000000008:TLLI-0xc0000008:TA-220:MSCLS-45-0) Attaching DL TBF: TBF(DL:TFI-0-0-8:STATE-NEW:GPRS:IMSI-001001000000008:TLLI-0xc0000008) +MS(IMSI-001001000000008:TLLI-0xc0000008:TA-220:MSCLS-45-0): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-001001000000008:TLLI-0xc0000008:TA-220:MSCLS-45-0:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-8:STATE-NEW:GPRS:IMSI-001001000000008:TLLI-0xc0000008) [DOWNLINK] START (PCH) TBF(DL:TFI-0-0-8:STATE-NEW:GPRS:IMSI-001001000000008:TLLI-0xc0000008) Send downlink assignment on PCH, no TBF exist (IMSI=001001000000008) DL_TBF(DL:TFI-0-0-8:STATE-NEW:GPRS:IMSI-001001000000008:TLLI-0xc0000008){NEW}: Received Event ASSIGN_ADD_CCCH @@ -936,6 +980,8 @@ TBF(DL:TFI-0-0-9:STATE-NEW:GPRS:IMSI-001001000000009:TLLI-0xc0000009) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-9:STATE-NEW:GPRS:IMSI-001001000000009:TLLI-0xc0000009) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(IMSI-001001000000009:TLLI-0xc0000009:TA-220:MSCLS-45-0) Attaching DL TBF: TBF(DL:TFI-0-0-9:STATE-NEW:GPRS:IMSI-001001000000009:TLLI-0xc0000009) +MS(IMSI-001001000000009:TLLI-0xc0000009:TA-220:MSCLS-45-0): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-001001000000009:TLLI-0xc0000009:TA-220:MSCLS-45-0:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-9:STATE-NEW:GPRS:IMSI-001001000000009:TLLI-0xc0000009) [DOWNLINK] START (PCH) TBF(DL:TFI-0-0-9:STATE-NEW:GPRS:IMSI-001001000000009:TLLI-0xc0000009) Send downlink assignment on PCH, no TBF exist (IMSI=001001000000009) DL_TBF(DL:TFI-0-0-9:STATE-NEW:GPRS:IMSI-001001000000009:TLLI-0xc0000009){NEW}: Received Event ASSIGN_ADD_CCCH @@ -966,6 +1012,8 @@ TBF(DL:TFI-0-0-10:STATE-NEW:GPRS:IMSI-001001000000010:TLLI-0xc000000a) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-10:STATE-NEW:GPRS:IMSI-001001000000010:TLLI-0xc000000a) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(IMSI-001001000000010:TLLI-0xc000000a:TA-220:MSCLS-45-0) Attaching DL TBF: TBF(DL:TFI-0-0-10:STATE-NEW:GPRS:IMSI-001001000000010:TLLI-0xc000000a) +MS(IMSI-001001000000010:TLLI-0xc000000a:TA-220:MSCLS-45-0): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-001001000000010:TLLI-0xc000000a:TA-220:MSCLS-45-0:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-10:STATE-NEW:GPRS:IMSI-001001000000010:TLLI-0xc000000a) [DOWNLINK] START (PCH) TBF(DL:TFI-0-0-10:STATE-NEW:GPRS:IMSI-001001000000010:TLLI-0xc000000a) Send downlink assignment on PCH, no TBF exist (IMSI=001001000000010) DL_TBF(DL:TFI-0-0-10:STATE-NEW:GPRS:IMSI-001001000000010:TLLI-0xc000000a){NEW}: Received Event ASSIGN_ADD_CCCH @@ -996,6 +1044,8 @@ TBF(DL:TFI-0-0-11:STATE-NEW:GPRS:IMSI-001001000000011:TLLI-0xc000000b) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-11:STATE-NEW:GPRS:IMSI-001001000000011:TLLI-0xc000000b) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(IMSI-001001000000011:TLLI-0xc000000b:TA-220:MSCLS-45-0) Attaching DL TBF: TBF(DL:TFI-0-0-11:STATE-NEW:GPRS:IMSI-001001000000011:TLLI-0xc000000b) +MS(IMSI-001001000000011:TLLI-0xc000000b:TA-220:MSCLS-45-0): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-001001000000011:TLLI-0xc000000b:TA-220:MSCLS-45-0:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-11:STATE-NEW:GPRS:IMSI-001001000000011:TLLI-0xc000000b) [DOWNLINK] START (PCH) TBF(DL:TFI-0-0-11:STATE-NEW:GPRS:IMSI-001001000000011:TLLI-0xc000000b) Send downlink assignment on PCH, no TBF exist (IMSI=001001000000011) DL_TBF(DL:TFI-0-0-11:STATE-NEW:GPRS:IMSI-001001000000011:TLLI-0xc000000b){NEW}: Received Event ASSIGN_ADD_CCCH @@ -1026,6 +1076,8 @@ TBF(DL:TFI-0-0-12:STATE-NEW:GPRS:IMSI-001001000000012:TLLI-0xc000000c) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-12:STATE-NEW:GPRS:IMSI-001001000000012:TLLI-0xc000000c) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(IMSI-001001000000012:TLLI-0xc000000c:TA-220:MSCLS-45-0) Attaching DL TBF: TBF(DL:TFI-0-0-12:STATE-NEW:GPRS:IMSI-001001000000012:TLLI-0xc000000c) +MS(IMSI-001001000000012:TLLI-0xc000000c:TA-220:MSCLS-45-0): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-001001000000012:TLLI-0xc000000c:TA-220:MSCLS-45-0:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-12:STATE-NEW:GPRS:IMSI-001001000000012:TLLI-0xc000000c) [DOWNLINK] START (PCH) TBF(DL:TFI-0-0-12:STATE-NEW:GPRS:IMSI-001001000000012:TLLI-0xc000000c) Send downlink assignment on PCH, no TBF exist (IMSI=001001000000012) DL_TBF(DL:TFI-0-0-12:STATE-NEW:GPRS:IMSI-001001000000012:TLLI-0xc000000c){NEW}: Received Event ASSIGN_ADD_CCCH @@ -1056,6 +1108,8 @@ TBF(DL:TFI-0-0-13:STATE-NEW:GPRS:IMSI-001001000000013:TLLI-0xc000000d) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-13:STATE-NEW:GPRS:IMSI-001001000000013:TLLI-0xc000000d) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(IMSI-001001000000013:TLLI-0xc000000d:TA-220:MSCLS-45-0) Attaching DL TBF: TBF(DL:TFI-0-0-13:STATE-NEW:GPRS:IMSI-001001000000013:TLLI-0xc000000d) +MS(IMSI-001001000000013:TLLI-0xc000000d:TA-220:MSCLS-45-0): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-001001000000013:TLLI-0xc000000d:TA-220:MSCLS-45-0:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-13:STATE-NEW:GPRS:IMSI-001001000000013:TLLI-0xc000000d) [DOWNLINK] START (PCH) TBF(DL:TFI-0-0-13:STATE-NEW:GPRS:IMSI-001001000000013:TLLI-0xc000000d) Send downlink assignment on PCH, no TBF exist (IMSI=001001000000013) DL_TBF(DL:TFI-0-0-13:STATE-NEW:GPRS:IMSI-001001000000013:TLLI-0xc000000d){NEW}: Received Event ASSIGN_ADD_CCCH @@ -1086,6 +1140,8 @@ TBF(DL:TFI-0-0-14:STATE-NEW:GPRS:IMSI-001001000000014:TLLI-0xc000000e) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-14:STATE-NEW:GPRS:IMSI-001001000000014:TLLI-0xc000000e) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(IMSI-001001000000014:TLLI-0xc000000e:TA-220:MSCLS-45-0) Attaching DL TBF: TBF(DL:TFI-0-0-14:STATE-NEW:GPRS:IMSI-001001000000014:TLLI-0xc000000e) +MS(IMSI-001001000000014:TLLI-0xc000000e:TA-220:MSCLS-45-0): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-001001000000014:TLLI-0xc000000e:TA-220:MSCLS-45-0:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-14:STATE-NEW:GPRS:IMSI-001001000000014:TLLI-0xc000000e) [DOWNLINK] START (PCH) TBF(DL:TFI-0-0-14:STATE-NEW:GPRS:IMSI-001001000000014:TLLI-0xc000000e) Send downlink assignment on PCH, no TBF exist (IMSI=001001000000014) DL_TBF(DL:TFI-0-0-14:STATE-NEW:GPRS:IMSI-001001000000014:TLLI-0xc000000e){NEW}: Received Event ASSIGN_ADD_CCCH @@ -1116,6 +1172,8 @@ TBF(DL:TFI-0-0-15:STATE-NEW:GPRS:IMSI-001001000000015:TLLI-0xc000000f) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-15:STATE-NEW:GPRS:IMSI-001001000000015:TLLI-0xc000000f) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(IMSI-001001000000015:TLLI-0xc000000f:TA-220:MSCLS-45-0) Attaching DL TBF: TBF(DL:TFI-0-0-15:STATE-NEW:GPRS:IMSI-001001000000015:TLLI-0xc000000f) +MS(IMSI-001001000000015:TLLI-0xc000000f:TA-220:MSCLS-45-0): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-001001000000015:TLLI-0xc000000f:TA-220:MSCLS-45-0:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-15:STATE-NEW:GPRS:IMSI-001001000000015:TLLI-0xc000000f) [DOWNLINK] START (PCH) TBF(DL:TFI-0-0-15:STATE-NEW:GPRS:IMSI-001001000000015:TLLI-0xc000000f) Send downlink assignment on PCH, no TBF exist (IMSI=001001000000015) DL_TBF(DL:TFI-0-0-15:STATE-NEW:GPRS:IMSI-001001000000015:TLLI-0xc000000f){NEW}: Received Event ASSIGN_ADD_CCCH @@ -1146,6 +1204,8 @@ TBF(DL:TFI-0-0-16:STATE-NEW:GPRS:IMSI-001001000000016:TLLI-0xc0000010) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-16:STATE-NEW:GPRS:IMSI-001001000000016:TLLI-0xc0000010) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(IMSI-001001000000016:TLLI-0xc0000010:TA-220:MSCLS-45-0) Attaching DL TBF: TBF(DL:TFI-0-0-16:STATE-NEW:GPRS:IMSI-001001000000016:TLLI-0xc0000010) +MS(IMSI-001001000000016:TLLI-0xc0000010:TA-220:MSCLS-45-0): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-001001000000016:TLLI-0xc0000010:TA-220:MSCLS-45-0:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-16:STATE-NEW:GPRS:IMSI-001001000000016:TLLI-0xc0000010) [DOWNLINK] START (PCH) TBF(DL:TFI-0-0-16:STATE-NEW:GPRS:IMSI-001001000000016:TLLI-0xc0000010) Send downlink assignment on PCH, no TBF exist (IMSI=001001000000016) DL_TBF(DL:TFI-0-0-16:STATE-NEW:GPRS:IMSI-001001000000016:TLLI-0xc0000010){NEW}: Received Event ASSIGN_ADD_CCCH @@ -1176,6 +1236,8 @@ TBF(DL:TFI-0-0-17:STATE-NEW:GPRS:IMSI-001001000000017:TLLI-0xc0000011) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-17:STATE-NEW:GPRS:IMSI-001001000000017:TLLI-0xc0000011) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(IMSI-001001000000017:TLLI-0xc0000011:TA-220:MSCLS-45-0) Attaching DL TBF: TBF(DL:TFI-0-0-17:STATE-NEW:GPRS:IMSI-001001000000017:TLLI-0xc0000011) +MS(IMSI-001001000000017:TLLI-0xc0000011:TA-220:MSCLS-45-0): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-001001000000017:TLLI-0xc0000011:TA-220:MSCLS-45-0:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-17:STATE-NEW:GPRS:IMSI-001001000000017:TLLI-0xc0000011) [DOWNLINK] START (PCH) TBF(DL:TFI-0-0-17:STATE-NEW:GPRS:IMSI-001001000000017:TLLI-0xc0000011) Send downlink assignment on PCH, no TBF exist (IMSI=001001000000017) DL_TBF(DL:TFI-0-0-17:STATE-NEW:GPRS:IMSI-001001000000017:TLLI-0xc0000011){NEW}: Received Event ASSIGN_ADD_CCCH @@ -1206,6 +1268,8 @@ TBF(DL:TFI-0-0-18:STATE-NEW:GPRS:IMSI-001001000000018:TLLI-0xc0000012) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-18:STATE-NEW:GPRS:IMSI-001001000000018:TLLI-0xc0000012) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(IMSI-001001000000018:TLLI-0xc0000012:TA-220:MSCLS-45-0) Attaching DL TBF: TBF(DL:TFI-0-0-18:STATE-NEW:GPRS:IMSI-001001000000018:TLLI-0xc0000012) +MS(IMSI-001001000000018:TLLI-0xc0000012:TA-220:MSCLS-45-0): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-001001000000018:TLLI-0xc0000012:TA-220:MSCLS-45-0:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-18:STATE-NEW:GPRS:IMSI-001001000000018:TLLI-0xc0000012) [DOWNLINK] START (PCH) TBF(DL:TFI-0-0-18:STATE-NEW:GPRS:IMSI-001001000000018:TLLI-0xc0000012) Send downlink assignment on PCH, no TBF exist (IMSI=001001000000018) DL_TBF(DL:TFI-0-0-18:STATE-NEW:GPRS:IMSI-001001000000018:TLLI-0xc0000012){NEW}: Received Event ASSIGN_ADD_CCCH @@ -1236,6 +1300,8 @@ TBF(DL:TFI-0-0-19:STATE-NEW:GPRS:IMSI-001001000000019:TLLI-0xc0000013) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-19:STATE-NEW:GPRS:IMSI-001001000000019:TLLI-0xc0000013) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(IMSI-001001000000019:TLLI-0xc0000013:TA-220:MSCLS-45-0) Attaching DL TBF: TBF(DL:TFI-0-0-19:STATE-NEW:GPRS:IMSI-001001000000019:TLLI-0xc0000013) +MS(IMSI-001001000000019:TLLI-0xc0000013:TA-220:MSCLS-45-0): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-001001000000019:TLLI-0xc0000013:TA-220:MSCLS-45-0:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-19:STATE-NEW:GPRS:IMSI-001001000000019:TLLI-0xc0000013) [DOWNLINK] START (PCH) TBF(DL:TFI-0-0-19:STATE-NEW:GPRS:IMSI-001001000000019:TLLI-0xc0000013) Send downlink assignment on PCH, no TBF exist (IMSI=001001000000019) DL_TBF(DL:TFI-0-0-19:STATE-NEW:GPRS:IMSI-001001000000019:TLLI-0xc0000013){NEW}: Received Event ASSIGN_ADD_CCCH @@ -1266,6 +1332,8 @@ TBF(DL:TFI-0-0-20:STATE-NEW:GPRS:IMSI-001001000000020:TLLI-0xc0000014) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-20:STATE-NEW:GPRS:IMSI-001001000000020:TLLI-0xc0000014) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(IMSI-001001000000020:TLLI-0xc0000014:TA-220:MSCLS-45-0) Attaching DL TBF: TBF(DL:TFI-0-0-20:STATE-NEW:GPRS:IMSI-001001000000020:TLLI-0xc0000014) +MS(IMSI-001001000000020:TLLI-0xc0000014:TA-220:MSCLS-45-0): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-001001000000020:TLLI-0xc0000014:TA-220:MSCLS-45-0:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-20:STATE-NEW:GPRS:IMSI-001001000000020:TLLI-0xc0000014) [DOWNLINK] START (PCH) TBF(DL:TFI-0-0-20:STATE-NEW:GPRS:IMSI-001001000000020:TLLI-0xc0000014) Send downlink assignment on PCH, no TBF exist (IMSI=001001000000020) DL_TBF(DL:TFI-0-0-20:STATE-NEW:GPRS:IMSI-001001000000020:TLLI-0xc0000014){NEW}: Received Event ASSIGN_ADD_CCCH @@ -1296,6 +1364,8 @@ TBF(DL:TFI-0-0-21:STATE-NEW:GPRS:IMSI-001001000000021:TLLI-0xc0000015) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-21:STATE-NEW:GPRS:IMSI-001001000000021:TLLI-0xc0000015) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(IMSI-001001000000021:TLLI-0xc0000015:TA-220:MSCLS-45-0) Attaching DL TBF: TBF(DL:TFI-0-0-21:STATE-NEW:GPRS:IMSI-001001000000021:TLLI-0xc0000015) +MS(IMSI-001001000000021:TLLI-0xc0000015:TA-220:MSCLS-45-0): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-001001000000021:TLLI-0xc0000015:TA-220:MSCLS-45-0:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-21:STATE-NEW:GPRS:IMSI-001001000000021:TLLI-0xc0000015) [DOWNLINK] START (PCH) TBF(DL:TFI-0-0-21:STATE-NEW:GPRS:IMSI-001001000000021:TLLI-0xc0000015) Send downlink assignment on PCH, no TBF exist (IMSI=001001000000021) DL_TBF(DL:TFI-0-0-21:STATE-NEW:GPRS:IMSI-001001000000021:TLLI-0xc0000015){NEW}: Received Event ASSIGN_ADD_CCCH @@ -1326,6 +1396,8 @@ TBF(DL:TFI-0-0-22:STATE-NEW:GPRS:IMSI-001001000000022:TLLI-0xc0000016) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-22:STATE-NEW:GPRS:IMSI-001001000000022:TLLI-0xc0000016) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(IMSI-001001000000022:TLLI-0xc0000016:TA-220:MSCLS-45-0) Attaching DL TBF: TBF(DL:TFI-0-0-22:STATE-NEW:GPRS:IMSI-001001000000022:TLLI-0xc0000016) +MS(IMSI-001001000000022:TLLI-0xc0000016:TA-220:MSCLS-45-0): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-001001000000022:TLLI-0xc0000016:TA-220:MSCLS-45-0:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-22:STATE-NEW:GPRS:IMSI-001001000000022:TLLI-0xc0000016) [DOWNLINK] START (PCH) TBF(DL:TFI-0-0-22:STATE-NEW:GPRS:IMSI-001001000000022:TLLI-0xc0000016) Send downlink assignment on PCH, no TBF exist (IMSI=001001000000022) DL_TBF(DL:TFI-0-0-22:STATE-NEW:GPRS:IMSI-001001000000022:TLLI-0xc0000016){NEW}: Received Event ASSIGN_ADD_CCCH @@ -1356,6 +1428,8 @@ TBF(DL:TFI-0-0-23:STATE-NEW:GPRS:IMSI-001001000000023:TLLI-0xc0000017) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-23:STATE-NEW:GPRS:IMSI-001001000000023:TLLI-0xc0000017) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(IMSI-001001000000023:TLLI-0xc0000017:TA-220:MSCLS-45-0) Attaching DL TBF: TBF(DL:TFI-0-0-23:STATE-NEW:GPRS:IMSI-001001000000023:TLLI-0xc0000017) +MS(IMSI-001001000000023:TLLI-0xc0000017:TA-220:MSCLS-45-0): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-001001000000023:TLLI-0xc0000017:TA-220:MSCLS-45-0:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-23:STATE-NEW:GPRS:IMSI-001001000000023:TLLI-0xc0000017) [DOWNLINK] START (PCH) TBF(DL:TFI-0-0-23:STATE-NEW:GPRS:IMSI-001001000000023:TLLI-0xc0000017) Send downlink assignment on PCH, no TBF exist (IMSI=001001000000023) DL_TBF(DL:TFI-0-0-23:STATE-NEW:GPRS:IMSI-001001000000023:TLLI-0xc0000017){NEW}: Received Event ASSIGN_ADD_CCCH @@ -1386,6 +1460,8 @@ TBF(DL:TFI-0-0-24:STATE-NEW:GPRS:IMSI-001001000000024:TLLI-0xc0000018) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-24:STATE-NEW:GPRS:IMSI-001001000000024:TLLI-0xc0000018) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(IMSI-001001000000024:TLLI-0xc0000018:TA-220:MSCLS-45-0) Attaching DL TBF: TBF(DL:TFI-0-0-24:STATE-NEW:GPRS:IMSI-001001000000024:TLLI-0xc0000018) +MS(IMSI-001001000000024:TLLI-0xc0000018:TA-220:MSCLS-45-0): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-001001000000024:TLLI-0xc0000018:TA-220:MSCLS-45-0:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-24:STATE-NEW:GPRS:IMSI-001001000000024:TLLI-0xc0000018) [DOWNLINK] START (PCH) TBF(DL:TFI-0-0-24:STATE-NEW:GPRS:IMSI-001001000000024:TLLI-0xc0000018) Send downlink assignment on PCH, no TBF exist (IMSI=001001000000024) DL_TBF(DL:TFI-0-0-24:STATE-NEW:GPRS:IMSI-001001000000024:TLLI-0xc0000018){NEW}: Received Event ASSIGN_ADD_CCCH @@ -1416,6 +1492,8 @@ TBF(DL:TFI-0-0-25:STATE-NEW:GPRS:IMSI-001001000000025:TLLI-0xc0000019) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-25:STATE-NEW:GPRS:IMSI-001001000000025:TLLI-0xc0000019) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(IMSI-001001000000025:TLLI-0xc0000019:TA-220:MSCLS-45-0) Attaching DL TBF: TBF(DL:TFI-0-0-25:STATE-NEW:GPRS:IMSI-001001000000025:TLLI-0xc0000019) +MS(IMSI-001001000000025:TLLI-0xc0000019:TA-220:MSCLS-45-0): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-001001000000025:TLLI-0xc0000019:TA-220:MSCLS-45-0:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-25:STATE-NEW:GPRS:IMSI-001001000000025:TLLI-0xc0000019) [DOWNLINK] START (PCH) TBF(DL:TFI-0-0-25:STATE-NEW:GPRS:IMSI-001001000000025:TLLI-0xc0000019) Send downlink assignment on PCH, no TBF exist (IMSI=001001000000025) DL_TBF(DL:TFI-0-0-25:STATE-NEW:GPRS:IMSI-001001000000025:TLLI-0xc0000019){NEW}: Received Event ASSIGN_ADD_CCCH @@ -1446,6 +1524,8 @@ TBF(DL:TFI-0-0-26:STATE-NEW:GPRS:IMSI-001001000000026:TLLI-0xc000001a) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-26:STATE-NEW:GPRS:IMSI-001001000000026:TLLI-0xc000001a) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(IMSI-001001000000026:TLLI-0xc000001a:TA-220:MSCLS-45-0) Attaching DL TBF: TBF(DL:TFI-0-0-26:STATE-NEW:GPRS:IMSI-001001000000026:TLLI-0xc000001a) +MS(IMSI-001001000000026:TLLI-0xc000001a:TA-220:MSCLS-45-0): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-001001000000026:TLLI-0xc000001a:TA-220:MSCLS-45-0:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-26:STATE-NEW:GPRS:IMSI-001001000000026:TLLI-0xc000001a) [DOWNLINK] START (PCH) TBF(DL:TFI-0-0-26:STATE-NEW:GPRS:IMSI-001001000000026:TLLI-0xc000001a) Send downlink assignment on PCH, no TBF exist (IMSI=001001000000026) DL_TBF(DL:TFI-0-0-26:STATE-NEW:GPRS:IMSI-001001000000026:TLLI-0xc000001a){NEW}: Received Event ASSIGN_ADD_CCCH @@ -1476,6 +1556,8 @@ TBF(DL:TFI-0-0-27:STATE-NEW:GPRS:IMSI-001001000000027:TLLI-0xc000001b) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-27:STATE-NEW:GPRS:IMSI-001001000000027:TLLI-0xc000001b) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(IMSI-001001000000027:TLLI-0xc000001b:TA-220:MSCLS-45-0) Attaching DL TBF: TBF(DL:TFI-0-0-27:STATE-NEW:GPRS:IMSI-001001000000027:TLLI-0xc000001b) +MS(IMSI-001001000000027:TLLI-0xc000001b:TA-220:MSCLS-45-0): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-001001000000027:TLLI-0xc000001b:TA-220:MSCLS-45-0:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-27:STATE-NEW:GPRS:IMSI-001001000000027:TLLI-0xc000001b) [DOWNLINK] START (PCH) TBF(DL:TFI-0-0-27:STATE-NEW:GPRS:IMSI-001001000000027:TLLI-0xc000001b) Send downlink assignment on PCH, no TBF exist (IMSI=001001000000027) DL_TBF(DL:TFI-0-0-27:STATE-NEW:GPRS:IMSI-001001000000027:TLLI-0xc000001b){NEW}: Received Event ASSIGN_ADD_CCCH @@ -1506,6 +1588,8 @@ TBF(DL:TFI-0-0-28:STATE-NEW:GPRS:IMSI-001001000000028:TLLI-0xc000001c) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-28:STATE-NEW:GPRS:IMSI-001001000000028:TLLI-0xc000001c) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(IMSI-001001000000028:TLLI-0xc000001c:TA-220:MSCLS-45-0) Attaching DL TBF: TBF(DL:TFI-0-0-28:STATE-NEW:GPRS:IMSI-001001000000028:TLLI-0xc000001c) +MS(IMSI-001001000000028:TLLI-0xc000001c:TA-220:MSCLS-45-0): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-001001000000028:TLLI-0xc000001c:TA-220:MSCLS-45-0:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-28:STATE-NEW:GPRS:IMSI-001001000000028:TLLI-0xc000001c) [DOWNLINK] START (PCH) TBF(DL:TFI-0-0-28:STATE-NEW:GPRS:IMSI-001001000000028:TLLI-0xc000001c) Send downlink assignment on PCH, no TBF exist (IMSI=001001000000028) DL_TBF(DL:TFI-0-0-28:STATE-NEW:GPRS:IMSI-001001000000028:TLLI-0xc000001c){NEW}: Received Event ASSIGN_ADD_CCCH @@ -1536,6 +1620,8 @@ TBF(DL:TFI-0-0-29:STATE-NEW:GPRS:IMSI-001001000000029:TLLI-0xc000001d) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-29:STATE-NEW:GPRS:IMSI-001001000000029:TLLI-0xc000001d) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(IMSI-001001000000029:TLLI-0xc000001d:TA-220:MSCLS-45-0) Attaching DL TBF: TBF(DL:TFI-0-0-29:STATE-NEW:GPRS:IMSI-001001000000029:TLLI-0xc000001d) +MS(IMSI-001001000000029:TLLI-0xc000001d:TA-220:MSCLS-45-0): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-001001000000029:TLLI-0xc000001d:TA-220:MSCLS-45-0:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-29:STATE-NEW:GPRS:IMSI-001001000000029:TLLI-0xc000001d) [DOWNLINK] START (PCH) TBF(DL:TFI-0-0-29:STATE-NEW:GPRS:IMSI-001001000000029:TLLI-0xc000001d) Send downlink assignment on PCH, no TBF exist (IMSI=001001000000029) DL_TBF(DL:TFI-0-0-29:STATE-NEW:GPRS:IMSI-001001000000029:TLLI-0xc000001d){NEW}: Received Event ASSIGN_ADD_CCCH @@ -1566,6 +1652,8 @@ TBF(DL:TFI-0-0-30:STATE-NEW:GPRS:IMSI-001001000000030:TLLI-0xc000001e) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-30:STATE-NEW:GPRS:IMSI-001001000000030:TLLI-0xc000001e) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(IMSI-001001000000030:TLLI-0xc000001e:TA-220:MSCLS-45-0) Attaching DL TBF: TBF(DL:TFI-0-0-30:STATE-NEW:GPRS:IMSI-001001000000030:TLLI-0xc000001e) +MS(IMSI-001001000000030:TLLI-0xc000001e:TA-220:MSCLS-45-0): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-001001000000030:TLLI-0xc000001e:TA-220:MSCLS-45-0:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-30:STATE-NEW:GPRS:IMSI-001001000000030:TLLI-0xc000001e) [DOWNLINK] START (PCH) TBF(DL:TFI-0-0-30:STATE-NEW:GPRS:IMSI-001001000000030:TLLI-0xc000001e) Send downlink assignment on PCH, no TBF exist (IMSI=001001000000030) DL_TBF(DL:TFI-0-0-30:STATE-NEW:GPRS:IMSI-001001000000030:TLLI-0xc000001e){NEW}: Received Event ASSIGN_ADD_CCCH @@ -1596,6 +1684,8 @@ TBF(DL:TFI-0-0-31:STATE-NEW:GPRS:IMSI-001001000000031:TLLI-0xc000001f) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-31:STATE-NEW:GPRS:IMSI-001001000000031:TLLI-0xc000001f) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(IMSI-001001000000031:TLLI-0xc000001f:TA-220:MSCLS-45-0) Attaching DL TBF: TBF(DL:TFI-0-0-31:STATE-NEW:GPRS:IMSI-001001000000031:TLLI-0xc000001f) +MS(IMSI-001001000000031:TLLI-0xc000001f:TA-220:MSCLS-45-0): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-001001000000031:TLLI-0xc000001f:TA-220:MSCLS-45-0:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-31:STATE-NEW:GPRS:IMSI-001001000000031:TLLI-0xc000001f) [DOWNLINK] START (PCH) TBF(DL:TFI-0-0-31:STATE-NEW:GPRS:IMSI-001001000000031:TLLI-0xc000001f) Send downlink assignment on PCH, no TBF exist (IMSI=001001000000031) DL_TBF(DL:TFI-0-0-31:STATE-NEW:GPRS:IMSI-001001000000031:TLLI-0xc000001f){NEW}: Received Event ASSIGN_ADD_CCCH @@ -1711,6 +1801,8 @@ TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-001001000123456:TLLI-0xc0123456) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-001001000123456:TLLI-0xc0123456) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(IMSI-001001000123456:TLLI-0xc0123456:TA-220:MSCLS-45-0) Attaching DL TBF: TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-001001000123456:TLLI-0xc0123456) +MS(IMSI-001001000123456:TLLI-0xc0123456:TA-220:MSCLS-45-0): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-001001000123456:TLLI-0xc0123456:TA-220:MSCLS-45-0:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-001001000123456:TLLI-0xc0123456) [DOWNLINK] START (PCH) TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-001001000123456:TLLI-0xc0123456) Send downlink assignment on PCH, no TBF exist (IMSI=001001000123456) DL_TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-001001000123456:TLLI-0xc0123456){NEW}: Received Event ASSIGN_ADD_CCCH @@ -1724,6 +1816,7 @@ PDCH(bts=0,trx=0,ts=4) Detaching TBF(DL:TFI-0-0-0:STATE-ASSIGN:GPRS:IMSI-001001000123456:TLLI-0xc0123456), 1 TBFs, USFs = 00, TFIs = 00000001. MS(IMSI-001001000123456:TLLI-0xc0123456:TA-0:MSCLS-45-0) Detaching TBF: TBF(DL:TFI-0-0-0:STATE-ASSIGN:GPRS:IMSI-001001000123456:TLLI-0xc0123456) MS(IMSI-001001000123456:TLLI-0xc0123456:TA-0:MSCLS-45-0) Schedule MS release in 10 secs +MS(IMSI-001001000123456:TLLI-0xc0123456:TA-0:MSCLS-45-0): + release_timer: now used by 1 (release_timer) ********** DL-TBF ends here ********** DL_TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-001001000123456:TLLI-0xc0123456){ASSIGN}: Deallocated UL_ASS_TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-001001000123456:TLLI-0xc0123456){NONE}: Deallocated @@ -1747,7 +1840,10 @@ TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-001001000123456:TLLI-0xc0123456) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-001001000123456:TLLI-0xc0123456) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(IMSI-001001000123456:TLLI-0xc0123456:TA-0:MSCLS-45-0) Attaching DL TBF: TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-001001000123456:TLLI-0xc0123456) +MS(IMSI-001001000123456:TLLI-0xc0123456:TA-0:MSCLS-45-0): + ms_attach_dl_tbf: now used by 2 (release_timer,ms_attach_dl_tbf) MS(IMSI-001001000123456:TLLI-0xc0123456:TA-0:MSCLS-45-0:DL) Cancel scheduled MS release +MS(IMSI-001001000123456:TLLI-0xc0123456:TA-0:MSCLS-45-0:DL): - release_timer: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-001001000123456:TLLI-0xc0123456:TA-0:MSCLS-45-0:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-001001000123456:TLLI-0xc0123456) [DOWNLINK] START (PCH) TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-001001000123456:TLLI-0xc0123456) Send downlink assignment on PCH, no TBF exist (IMSI=001001000123456) DL_TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-001001000123456:TLLI-0xc0123456){NEW}: Received Event ASSIGN_ADD_CCCH @@ -1826,6 +1922,8 @@ TBF(UL:TFI-0-0-0:STATE-NEW:GPRS) Setting Control TS PDCH(bts=0,trx=0,ts=7) TBF(UL:TFI-0-0-0:STATE-NEW:GPRS) Allocated: trx = 0, ul_slots = 80, dl_slots = 00 MS(TA-220:MSCLS-0-0) Attaching UL TBF: TBF(UL:TFI-0-0-0:STATE-NEW:GPRS) +MS(TA-220:MSCLS-0-0): + ms_attach_ul_tbf: now used by 1 (ms_attach_ul_tbf) +MS(TA-220:MSCLS-0-0:UL): - ms_attach_ul_tbf: now used by 0 (-) UL_TBF(UL:TFI-0-0-0:STATE-NEW:GPRS){NEW}: Received Event ASSIGN_ADD_CCCH TBF(UL:TFI-0-0-0:STATE-NEW:GPRS) set ass. type CCCH [prev CCCH:0, PACCH:0] UL_TBF(UL:TFI-0-0-0:STATE-NEW:GPRS){NEW}: state_chg to FLOW @@ -1888,6 +1986,8 @@ TBF(UL:TFI-0-0-0:STATE-NEW:GPRS) Setting Control TS PDCH(bts=0,trx=0,ts=7) TBF(UL:TFI-0-0-0:STATE-NEW:GPRS) Allocated: trx = 0, ul_slots = 80, dl_slots = 00 MS(TA-220:MSCLS-0-0) Attaching UL TBF: TBF(UL:TFI-0-0-0:STATE-NEW:GPRS) +MS(TA-220:MSCLS-0-0): + ms_attach_ul_tbf: now used by 1 (ms_attach_ul_tbf) +MS(TA-220:MSCLS-0-0:UL): - ms_attach_ul_tbf: now used by 0 (-) UL_TBF(UL:TFI-0-0-0:STATE-NEW:GPRS){NEW}: Received Event ASSIGN_ADD_CCCH TBF(UL:TFI-0-0-0:STATE-NEW:GPRS) set ass. type CCCH [prev CCCH:0, PACCH:0] UL_TBF(UL:TFI-0-0-0:STATE-NEW:GPRS){NEW}: state_chg to FLOW @@ -1937,6 +2037,7 @@ UL_ACK_TBF(UL:TFI-0-0-0:STATE-FINISHED:GPRS:IMSI-0011223344:TLLI-0xf1223344){WAIT_ACK}: Received Event RX_CTRL_ACK UL_ACK_TBF(UL:TFI-0-0-0:STATE-FINISHED:GPRS:IMSI-0011223344:TLLI-0xf1223344){WAIT_ACK}: state_chg to NONE UL_TBF(UL:TFI-0-0-0:STATE-FINISHED:GPRS:IMSI-0011223344:TLLI-0xf1223344){FINISHED}: Received Event FINAL_UL_ACK_CONFIRMED +MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-0-0:UL): + st_finished: now used by 1 (st_finished) TBF(UL:TFI-0-0-0:STATE-FINISHED:GPRS:IMSI-0011223344:TLLI-0xf1223344) [UPLINK] END TBF(UL:TFI-0-0-0:STATE-FINISHED:GPRS:IMSI-0011223344:TLLI-0xf1223344) free PDCH(bts=0,trx=0,ts=7) Detaching TBF(UL:TFI-0-0-0:STATE-FINISHED:GPRS:IMSI-0011223344:TLLI-0xf1223344), 1 TBFs, USFs = 01, TFIs = 00000001. @@ -1964,6 +2065,8 @@ TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344) Setting Control TS PDCH(bts=0,trx=0,ts=7) TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344) Allocated: trx = 0, ul_slots = 80, dl_slots = 80 MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-0-0) Attaching DL TBF: TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344) +MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-0-0): + ms_attach_dl_tbf: now used by 2 (st_finished,ms_attach_dl_tbf) +MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-0-0:DL): - ms_attach_dl_tbf: now used by 1 (st_finished) TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344) [DOWNLINK] START (PCH) TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344) Send downlink assignment on PCH, no TBF exist (IMSI=0011223344) DL_TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344){NEW}: Received Event ASSIGN_ADD_CCCH @@ -1971,6 +2074,7 @@ DL_TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344){NEW}: state_chg to ASSIGN TBF(DL:TFI-0-0-0:STATE-ASSIGN:GPRS:IMSI-0011223344:TLLI-0xf1223344) TX: START Immediate Assignment Downlink (PCH) - TRX=0 (0) TS=7 TA=7 +MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-0-0:DL): - st_finished: now used by 0 (-) === 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) Detaching TBF: TBF(DL:TFI-0-0-0:STATE-ASSIGN:GPRS:IMSI-0011223344:TLLI-0xf1223344) @@ -1987,6 +2091,7 @@ PDCH(bts=0,trx=0,ts=7) FN=2654270 ------------------------- RX : Uplink Control Block ------------------------- Creating MS object Modifying MS object, UL TLLI: 0xffffffff -> 0xf1223344, not yet confirmed +MS(TLLI-0xf1223344:TA-220:MSCLS-0-0): + rcv_resource_request: now used by 1 (rcv_resource_request) PDCH(bts=0,trx=0,ts=7) FN=2654270 PKT RESOURCE REQ: MS requests UL TBF throguh SBA Modifying MS object, TLLI = 0xf1223344, TA 220 -> 7 Modifying MS object, TLLI = 0xf1223344, MS class 0 -> 1 @@ -2009,6 +2114,8 @@ TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0xf1223344) Setting Control TS PDCH(bts=0,trx=0,ts=7) TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0xf1223344) Allocated: trx = 0, ul_slots = 80, dl_slots = 00 MS(TLLI-0xf1223344:TA-7:MSCLS-1-0) Attaching UL TBF: TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0xf1223344) +MS(TLLI-0xf1223344:TA-7:MSCLS-1-0): + ms_attach_ul_tbf: now used by 2 (rcv_resource_request,ms_attach_ul_tbf) +MS(TLLI-0xf1223344:TA-7:MSCLS-1-0:UL): - ms_attach_ul_tbf: now used by 1 (rcv_resource_request) UL_TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0xf1223344){NEW}: Received Event ASSIGN_ADD_PACCH TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0xf1223344) set ass. type PACCH [prev CCCH:0, PACCH:0] UL_TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0xf1223344){NEW}: state_chg to ASSIGN @@ -2016,6 +2123,7 @@ TBF(UL:TFI-0-0-0:STATE-ASSIGN:GPRS:TLLI-0xf1223344) Change control TS PDCH(bts=0,trx=0,ts=7) -> PDCH(bts=0,trx=0,ts=7) until assignment is complete. UL_ASS_TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0xf1223344){NONE}: Received Event SCHED_ASS UL_ASS_TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0xf1223344){NONE}: state_chg to SEND_ASS +MS(TLLI-0xf1223344:TA-7:MSCLS-1-0:UL): - rcv_resource_request: now used by 0 (-) UL_ASS_TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0xf1223344){SEND_ASS}: Received Event CREATE_RLCMAC_MSG PDCH(bts=0,trx=0,ts=7) POLL scheduled at FN 2654270 + 13 = 2654283 TBF(UL:TFI-0-0-0:STATE-ASSIGN:GPRS:TLLI-0xf1223344) start Packet Uplink Assignment (PACCH) @@ -2067,6 +2175,8 @@ TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344) Setting Control TS PDCH(bts=0,trx=0,ts=7) TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344) Allocated: trx = 0, ul_slots = 80, dl_slots = 80 MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:UL) Attaching DL TBF: TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344) +MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:UL): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:UL:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344) [DOWNLINK] START (PACCH) TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344) Send downlink assignment on PACCH, because TBF(UL:TFI-0-0-0:STATE-FLOW:GPRS:IMSI-0011223344:TLLI-0xf1223344) exists DL_ASS_TBF(UL:TFI-0-0-0:STATE-FLOW:GPRS:IMSI-0011223344:TLLI-0xf1223344){NONE}: Received Event SCHED_ASS @@ -2092,6 +2202,7 @@ PDCH(bts=0,trx=0,ts=7) FN=2654270 ------------------------- RX : Uplink Control Block ------------------------- Creating MS object Modifying MS object, UL TLLI: 0xffffffff -> 0xf1223344, not yet confirmed +MS(TLLI-0xf1223344:TA-220:MSCLS-0-0): + rcv_resource_request: now used by 1 (rcv_resource_request) PDCH(bts=0,trx=0,ts=7) FN=2654270 PKT RESOURCE REQ: MS requests UL TBF throguh SBA Modifying MS object, TLLI = 0xf1223344, TA 220 -> 7 Modifying MS object, TLLI = 0xf1223344, MS class 0 -> 1 @@ -2114,6 +2225,8 @@ TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0xf1223344) Setting Control TS PDCH(bts=0,trx=0,ts=7) TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0xf1223344) Allocated: trx = 0, ul_slots = 80, dl_slots = 00 MS(TLLI-0xf1223344:TA-7:MSCLS-1-0) Attaching UL TBF: TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0xf1223344) +MS(TLLI-0xf1223344:TA-7:MSCLS-1-0): + ms_attach_ul_tbf: now used by 2 (rcv_resource_request,ms_attach_ul_tbf) +MS(TLLI-0xf1223344:TA-7:MSCLS-1-0:UL): - ms_attach_ul_tbf: now used by 1 (rcv_resource_request) UL_TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0xf1223344){NEW}: Received Event ASSIGN_ADD_PACCH TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0xf1223344) set ass. type PACCH [prev CCCH:0, PACCH:0] UL_TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0xf1223344){NEW}: state_chg to ASSIGN @@ -2121,6 +2234,7 @@ TBF(UL:TFI-0-0-0:STATE-ASSIGN:GPRS:TLLI-0xf1223344) Change control TS PDCH(bts=0,trx=0,ts=7) -> PDCH(bts=0,trx=0,ts=7) until assignment is complete. UL_ASS_TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0xf1223344){NONE}: Received Event SCHED_ASS UL_ASS_TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0xf1223344){NONE}: state_chg to SEND_ASS +MS(TLLI-0xf1223344:TA-7:MSCLS-1-0:UL): - rcv_resource_request: now used by 0 (-) UL_ASS_TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0xf1223344){SEND_ASS}: Received Event CREATE_RLCMAC_MSG PDCH(bts=0,trx=0,ts=7) POLL scheduled at FN 2654270 + 13 = 2654283 TBF(UL:TFI-0-0-0:STATE-ASSIGN:GPRS:TLLI-0xf1223344) start Packet Uplink Assignment (PACCH) @@ -2171,6 +2285,8 @@ TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344) Setting Control TS PDCH(bts=0,trx=0,ts=7) TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344) Allocated: trx = 0, ul_slots = 80, dl_slots = 80 MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:UL) Attaching DL TBF: TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344) +MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:UL): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:UL:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344) [DOWNLINK] START (PACCH) TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344) Send downlink assignment on PACCH, because TBF(UL:TFI-0-0-0:STATE-FLOW:GPRS:IMSI-0011223344:TLLI-0xf1223344) exists DL_ASS_TBF(UL:TFI-0-0-0:STATE-FLOW:GPRS:IMSI-0011223344:TLLI-0xf1223344){NONE}: Received Event SCHED_ASS @@ -2265,6 +2381,7 @@ PDCH(bts=0,trx=0,ts=7) FN=2654348 ------------------------- RX : Uplink Control Block ------------------------- Creating MS object Modifying MS object, UL TLLI: 0xffffffff -> 0xf5667788, not yet confirmed +MS(TLLI-0xf5667788:TA-220:MSCLS-0-0): + rcv_resource_request: now used by 1 (rcv_resource_request) PDCH(bts=0,trx=0,ts=7) FN=2654348 PKT RESOURCE REQ: MS requests UL TBF throguh SBA Modifying MS object, TLLI = 0xf5667788, TA 220 -> 7 Modifying MS object, TLLI = 0xf5667788, MS class 0 -> 1 @@ -2287,6 +2404,8 @@ TBF(UL:TFI-0-0-1:STATE-NEW:GPRS:TLLI-0xf5667788) Setting Control TS PDCH(bts=0,trx=0,ts=7) TBF(UL:TFI-0-0-1:STATE-NEW:GPRS:TLLI-0xf5667788) Allocated: trx = 0, ul_slots = 80, dl_slots = 00 MS(TLLI-0xf5667788:TA-7:MSCLS-1-0) Attaching UL TBF: TBF(UL:TFI-0-0-1:STATE-NEW:GPRS:TLLI-0xf5667788) +MS(TLLI-0xf5667788:TA-7:MSCLS-1-0): + ms_attach_ul_tbf: now used by 2 (rcv_resource_request,ms_attach_ul_tbf) +MS(TLLI-0xf5667788:TA-7:MSCLS-1-0:UL): - ms_attach_ul_tbf: now used by 1 (rcv_resource_request) UL_TBF(UL:TFI-0-0-1:STATE-NEW:GPRS:TLLI-0xf5667788){NEW}: Received Event ASSIGN_ADD_PACCH TBF(UL:TFI-0-0-1:STATE-NEW:GPRS:TLLI-0xf5667788) set ass. type PACCH [prev CCCH:0, PACCH:0] UL_TBF(UL:TFI-0-0-1:STATE-NEW:GPRS:TLLI-0xf5667788){NEW}: state_chg to ASSIGN @@ -2294,6 +2413,7 @@ TBF(UL:TFI-0-0-1:STATE-ASSIGN:GPRS:TLLI-0xf5667788) Change control TS PDCH(bts=0,trx=0,ts=7) -> PDCH(bts=0,trx=0,ts=7) until assignment is complete. UL_ASS_TBF(UL:TFI-0-0-1:STATE-NEW:GPRS:TLLI-0xf5667788){NONE}: Received Event SCHED_ASS UL_ASS_TBF(UL:TFI-0-0-1:STATE-NEW:GPRS:TLLI-0xf5667788){NONE}: state_chg to SEND_ASS +MS(TLLI-0xf5667788:TA-7:MSCLS-1-0:UL): - rcv_resource_request: now used by 0 (-) PDCH(bts=0,trx=0,ts=7) Expiring FN=2654348 but previous FN=2654301 is still reserved! PDCH(bts=0,trx=0,ts=7) Timeout for registered USF (FN=2654301): TBF(UL:TFI-0-0-0:STATE-FLOW:GPRS:IMSI-0011223344:TLLI-0xf1223344) TBF(UL:TFI-0-0-0:STATE-FLOW:GPRS:IMSI-0011223344:TLLI-0xf1223344) N3101 2 => 3 (< MAX 10) @@ -2338,6 +2458,7 @@ TBF(UL:TFI-0-0-1:STATE-FLOW:GPRS:TLLI-0xf5667788) No gaps in received block, last block: BSN=0 CV=15 Old MS: TLLI = 0xf1223344, TA = 7, IMSI = 0011223344, LLC = 0 There is a new MS object for the same MS: (0xf1223344, '0011223344') -> (0xf5667788, '') +MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:UL:DL): + ms_merge_and_clear_ms: now used by 1 (ms_merge_and_clear_ms) MS(TLLI-0xf5667788:TA-7:MSCLS-1-0:UL) Merge MS: MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:UL:DL) TBF(UL:TFI-0-0-0:STATE-FLOW:GPRS:IMSI-0011223344:TLLI-0xf1223344) free PDCH(bts=0,trx=0,ts=7) Detaching TBF(UL:TFI-0-0-0:STATE-FLOW:GPRS:IMSI-0011223344:TLLI-0xf1223344), 2 TBFs, USFs = 03, TFIs = 00000003. @@ -2348,6 +2469,7 @@ UL_ASS_TBF(UL:TFI-0-0-0:STATE-FLOW:GPRS:IMSI-0011223344:TLLI-0xf1223344){NONE}: Deallocated DL_ASS_TBF(UL:TFI-0-0-0:STATE-FLOW:GPRS:IMSI-0011223344:TLLI-0xf1223344){NONE}: Deallocated MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:DL) Clearing MS object +MS(TA-7:MSCLS-1-0:DL): - ms_merge_and_clear_ms: now used by 0 (-) Modifying MS object, TLLI: 0xf5667788 confirmed MS(IMSI-0011223344:TLLI-0xf5667788:TA-7:MSCLS-1-0:UL) appending 4 bytes to DL LLC queue MS(IMSI-0011223344:TLLI-0xf5667788:TA-7:MSCLS-1-0:UL) ********** DL-TBF starts here ********** @@ -2368,6 +2490,8 @@ TBF(DL:TFI-0-0-1:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf5667788) Setting Control TS PDCH(bts=0,trx=0,ts=7) TBF(DL:TFI-0-0-1:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf5667788) Allocated: trx = 0, ul_slots = 80, dl_slots = 80 MS(IMSI-0011223344:TLLI-0xf5667788:TA-7:MSCLS-1-0:UL) Attaching DL TBF: TBF(DL:TFI-0-0-1:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf5667788) +MS(IMSI-0011223344:TLLI-0xf5667788:TA-7:MSCLS-1-0:UL): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-0011223344:TLLI-0xf5667788:TA-7:MSCLS-1-0:UL:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-1:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf5667788) [DOWNLINK] START (PACCH) TBF(DL:TFI-0-0-1:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf5667788) Send downlink assignment on PACCH, because TBF(UL:TFI-0-0-1:STATE-FLOW:GPRS:IMSI-0011223344:TLLI-0xf5667788) exists DL_ASS_TBF(UL:TFI-0-0-1:STATE-FLOW:GPRS:IMSI-0011223344:TLLI-0xf5667788){NONE}: Received Event SCHED_ASS @@ -2396,6 +2520,7 @@ PDCH(bts=0,trx=0,ts=7) FN=2654270 ------------------------- RX : Uplink Control Block ------------------------- Creating MS object Modifying MS object, UL TLLI: 0xffffffff -> 0xf1223344, not yet confirmed +MS(TLLI-0xf1223344:TA-220:MSCLS-0-0): + rcv_resource_request: now used by 1 (rcv_resource_request) PDCH(bts=0,trx=0,ts=7) FN=2654270 PKT RESOURCE REQ: MS requests UL TBF throguh SBA Modifying MS object, TLLI = 0xf1223344, TA 220 -> 7 Modifying MS object, TLLI = 0xf1223344, MS class 0 -> 1 @@ -2419,6 +2544,8 @@ TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0xf1223344) Setting Control TS PDCH(bts=0,trx=0,ts=7) TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0xf1223344) Allocated: trx = 0, ul_slots = 80, dl_slots = 00 MS(TLLI-0xf1223344:TA-7:MSCLS-1-0) Attaching UL TBF: TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0xf1223344) +MS(TLLI-0xf1223344:TA-7:MSCLS-1-0): + ms_attach_ul_tbf: now used by 2 (rcv_resource_request,ms_attach_ul_tbf) +MS(TLLI-0xf1223344:TA-7:MSCLS-1-0:UL): - ms_attach_ul_tbf: now used by 1 (rcv_resource_request) UL_TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0xf1223344){NEW}: Received Event ASSIGN_ADD_PACCH TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0xf1223344) set ass. type PACCH [prev CCCH:0, PACCH:0] UL_TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0xf1223344){NEW}: state_chg to ASSIGN @@ -2426,6 +2553,7 @@ TBF(UL:TFI-0-0-0:STATE-ASSIGN:GPRS:TLLI-0xf1223344) Change control TS PDCH(bts=0,trx=0,ts=7) -> PDCH(bts=0,trx=0,ts=7) until assignment is complete. UL_ASS_TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0xf1223344){NONE}: Received Event SCHED_ASS UL_ASS_TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0xf1223344){NONE}: state_chg to SEND_ASS +MS(TLLI-0xf1223344:TA-7:MSCLS-1-0:UL): - rcv_resource_request: now used by 0 (-) UL_ASS_TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0xf1223344){SEND_ASS}: Received Event CREATE_RLCMAC_MSG PDCH(bts=0,trx=0,ts=7) POLL scheduled at FN 2654270 + 13 = 2654283 TBF(UL:TFI-0-0-0:STATE-ASSIGN:GPRS:TLLI-0xf1223344) start Packet Uplink Assignment (PACCH) @@ -2477,6 +2605,8 @@ TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344) Setting Control TS PDCH(bts=0,trx=0,ts=7) TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344) Allocated: trx = 0, ul_slots = 80, dl_slots = 80 MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:UL) Attaching DL TBF: TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344) +MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:UL): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:UL:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344) [DOWNLINK] START (PACCH) TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344) Send downlink assignment on PACCH, because TBF(UL:TFI-0-0-0:STATE-FLOW:GPRS:IMSI-0011223344:TLLI-0xf1223344) exists DL_ASS_TBF(UL:TFI-0-0-0:STATE-FLOW:GPRS:IMSI-0011223344:TLLI-0xf1223344){NONE}: Received Event SCHED_ASS @@ -2504,6 +2634,7 @@ Detected FN jump! 2654275 -> 2654327 (expected 2654279, delta 52) PDCH(bts=0,trx=0,ts=7) FN=2654327 +++++++++++++++++++++++++ RX : Uplink Control Block +++++++++++++++++++++++++ PDCH(bts=0,trx=0,ts=7) FN=2654327 ------------------------- RX : Uplink Control Block ------------------------- +MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:DL): + rcv_resource_request: now used by 1 (rcv_resource_request) PDCH(bts=0,trx=0,ts=7) FN=2654327 PKT RESOURCE REQ: MS requests UL TBF throguh SBA TBF(DL:TFI-0-0-0:STATE-ASSIGN:GPRS:IMSI-0011223344:TLLI-0xf1223344) Got PACKET RESOURCE REQ while DL-TBF pending, killing it TBF(DL:TFI-0-0-0:STATE-ASSIGN:GPRS:IMSI-0011223344:TLLI-0xf1223344) free @@ -2532,6 +2663,8 @@ TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344) Setting Control TS PDCH(bts=0,trx=0,ts=7) TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344) Allocated: trx = 0, ul_slots = 80, dl_slots = 00 MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0) Attaching UL TBF: TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344) +MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0): + ms_attach_ul_tbf: now used by 2 (rcv_resource_request,ms_attach_ul_tbf) +MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:UL): - ms_attach_ul_tbf: now used by 1 (rcv_resource_request) UL_TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344){NEW}: Received Event ASSIGN_ADD_PACCH TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344) set ass. type PACCH [prev CCCH:0, PACCH:0] UL_TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344){NEW}: state_chg to ASSIGN @@ -2539,6 +2672,7 @@ TBF(UL:TFI-0-0-0:STATE-ASSIGN:GPRS:IMSI-0011223344:TLLI-0xf1223344) Change control TS PDCH(bts=0,trx=0,ts=7) -> PDCH(bts=0,trx=0,ts=7) until assignment is complete. UL_ASS_TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344){NONE}: Received Event SCHED_ASS UL_ASS_TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344){NONE}: state_chg to SEND_ASS +MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:UL): - rcv_resource_request: now used by 0 (-) UL_ASS_TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344){SEND_ASS}: Received Event CREATE_RLCMAC_MSG PDCH(bts=0,trx=0,ts=7) POLL scheduled at FN 2654327 + 13 = 2654340 TBF(UL:TFI-0-0-0:STATE-ASSIGN:GPRS:IMSI-0011223344:TLLI-0xf1223344) start Packet Uplink Assignment (PACCH) @@ -2575,6 +2709,8 @@ TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344) Setting Control TS PDCH(bts=0,trx=0,ts=7) TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344) Allocated: trx = 0, ul_slots = 80, dl_slots = 80 MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:UL) Attaching DL TBF: TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344) +MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:UL): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:UL:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344) [DOWNLINK] START (PACCH) TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344) Send downlink assignment on PACCH, because TBF(UL:TFI-0-0-0:STATE-FLOW:GPRS:IMSI-0011223344:TLLI-0xf1223344) exists DL_ASS_TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344){NONE}: Received Event SCHED_ASS @@ -2611,6 +2747,7 @@ PDCH(bts=0,trx=0,ts=7) FN=2654270 ------------------------- RX : Uplink Control Block ------------------------- Creating MS object Modifying MS object, UL TLLI: 0xffffffff -> 0xf1223344, not yet confirmed +MS(TLLI-0xf1223344:TA-220:MSCLS-0-0): + rcv_resource_request: now used by 1 (rcv_resource_request) PDCH(bts=0,trx=0,ts=7) FN=2654270 PKT RESOURCE REQ: MS requests UL TBF throguh SBA Modifying MS object, TLLI = 0xf1223344, TA 220 -> 7 Modifying MS object, TLLI = 0xf1223344, MS class 0 -> 1 @@ -2634,6 +2771,8 @@ TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0xf1223344) Setting Control TS PDCH(bts=0,trx=0,ts=7) TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0xf1223344) Allocated: trx = 0, ul_slots = 80, dl_slots = 00 MS(TLLI-0xf1223344:TA-7:MSCLS-1-0) Attaching UL TBF: TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0xf1223344) +MS(TLLI-0xf1223344:TA-7:MSCLS-1-0): + ms_attach_ul_tbf: now used by 2 (rcv_resource_request,ms_attach_ul_tbf) +MS(TLLI-0xf1223344:TA-7:MSCLS-1-0:UL): - ms_attach_ul_tbf: now used by 1 (rcv_resource_request) UL_TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0xf1223344){NEW}: Received Event ASSIGN_ADD_PACCH TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0xf1223344) set ass. type PACCH [prev CCCH:0, PACCH:0] UL_TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0xf1223344){NEW}: state_chg to ASSIGN @@ -2641,6 +2780,7 @@ TBF(UL:TFI-0-0-0:STATE-ASSIGN:GPRS:TLLI-0xf1223344) Change control TS PDCH(bts=0,trx=0,ts=7) -> PDCH(bts=0,trx=0,ts=7) until assignment is complete. UL_ASS_TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0xf1223344){NONE}: Received Event SCHED_ASS UL_ASS_TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0xf1223344){NONE}: state_chg to SEND_ASS +MS(TLLI-0xf1223344:TA-7:MSCLS-1-0:UL): - rcv_resource_request: now used by 0 (-) UL_ASS_TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0xf1223344){SEND_ASS}: Received Event CREATE_RLCMAC_MSG PDCH(bts=0,trx=0,ts=7) POLL scheduled at FN 2654270 + 13 = 2654283 TBF(UL:TFI-0-0-0:STATE-ASSIGN:GPRS:TLLI-0xf1223344) start Packet Uplink Assignment (PACCH) @@ -2692,6 +2832,8 @@ TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344) Setting Control TS PDCH(bts=0,trx=0,ts=7) TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344) Allocated: trx = 0, ul_slots = 80, dl_slots = 80 MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:UL) Attaching DL TBF: TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344) +MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:UL): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:UL:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344) [DOWNLINK] START (PACCH) TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344) Send downlink assignment on PACCH, because TBF(UL:TFI-0-0-0:STATE-FLOW:GPRS:IMSI-0011223344:TLLI-0xf1223344) exists DL_ASS_TBF(UL:TFI-0-0-0:STATE-FLOW:GPRS:IMSI-0011223344:TLLI-0xf1223344){NONE}: Received Event SCHED_ASS @@ -2733,6 +2875,8 @@ TBF(UL:TFI-0-0-0:STATE-NEW:GPRS) Setting Control TS PDCH(bts=0,trx=0,ts=7) TBF(UL:TFI-0-0-0:STATE-NEW:GPRS) Allocated: trx = 0, ul_slots = 80, dl_slots = 00 MS(TA-220:MSCLS-0-0) Attaching UL TBF: TBF(UL:TFI-0-0-0:STATE-NEW:GPRS) +MS(TA-220:MSCLS-0-0): + ms_attach_ul_tbf: now used by 1 (ms_attach_ul_tbf) +MS(TA-220:MSCLS-0-0:UL): - ms_attach_ul_tbf: now used by 0 (-) UL_TBF(UL:TFI-0-0-0:STATE-NEW:GPRS){NEW}: Received Event ASSIGN_ADD_CCCH TBF(UL:TFI-0-0-0:STATE-NEW:GPRS) set ass. type CCCH [prev CCCH:0, PACCH:0] UL_TBF(UL:TFI-0-0-0:STATE-NEW:GPRS){NEW}: state_chg to FLOW @@ -2748,6 +2892,7 @@ TBF(UL:TFI-0-0-0:STATE-FLOW:GPRS) data_length=20, data=f1 22 33 44 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 TBF(UL:TFI-0-0-0:STATE-FLOW:GPRS) Decoded premier TLLI=0xf1223344 of UL DATA TFI=0. Modifying MS object, UL TLLI: 0xffffffff -> 0xf1223344, not yet confirmed +MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:DL): + ms_merge_and_clear_ms: now used by 1 (ms_merge_and_clear_ms) MS(TLLI-0xf1223344:TA-7:MSCLS-0-0:UL) Merge MS: MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:DL) Modifying MS object, TLLI = 0xf1223344, MS class 0 -> 1 TBF(DL:TFI-0-0-0:STATE-ASSIGN:GPRS:IMSI-0011223344:TLLI-0xf1223344) free @@ -2758,6 +2903,7 @@ UL_ASS_TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344){NONE}: Deallocated DL_ASS_TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344){NONE}: Deallocated MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0) Clearing MS object +MS(TA-7:MSCLS-1-0): - ms_merge_and_clear_ms: now used by 0 (-) MS(TA-7:MSCLS-1-0) Destroying MS object UL_TBF(UL:TFI-0-0-0:STATE-NEW:GPRS){FLOW}: Received Event FIRST_UL_DATA_RECVD TBF(UL:TFI-0-0-0:STATE-FLOW:GPRS:IMSI-0011223344:TLLI-0xf1223344) Assembling frames: (len=20) @@ -2790,6 +2936,7 @@ PDCH(bts=0,trx=0,ts=7) FN=2654270 ------------------------- RX : Uplink Control Block ------------------------- Creating MS object Modifying MS object, UL TLLI: 0xffffffff -> 0xf1223344, not yet confirmed +MS(TLLI-0xf1223344:TA-220:MSCLS-0-0): + rcv_resource_request: now used by 1 (rcv_resource_request) PDCH(bts=0,trx=0,ts=7) FN=2654270 PKT RESOURCE REQ: MS requests UL TBF throguh SBA Modifying MS object, TLLI = 0xf1223344, TA 220 -> 7 Modifying MS object, TLLI = 0xf1223344, MS class 0 -> 1 @@ -2813,6 +2960,8 @@ TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0xf1223344) Setting Control TS PDCH(bts=0,trx=0,ts=7) TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0xf1223344) Allocated: trx = 0, ul_slots = 80, dl_slots = 00 MS(TLLI-0xf1223344:TA-7:MSCLS-1-0) Attaching UL TBF: TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0xf1223344) +MS(TLLI-0xf1223344:TA-7:MSCLS-1-0): + ms_attach_ul_tbf: now used by 2 (rcv_resource_request,ms_attach_ul_tbf) +MS(TLLI-0xf1223344:TA-7:MSCLS-1-0:UL): - ms_attach_ul_tbf: now used by 1 (rcv_resource_request) UL_TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0xf1223344){NEW}: Received Event ASSIGN_ADD_PACCH TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0xf1223344) set ass. type PACCH [prev CCCH:0, PACCH:0] UL_TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0xf1223344){NEW}: state_chg to ASSIGN @@ -2820,6 +2969,7 @@ TBF(UL:TFI-0-0-0:STATE-ASSIGN:GPRS:TLLI-0xf1223344) Change control TS PDCH(bts=0,trx=0,ts=7) -> PDCH(bts=0,trx=0,ts=7) until assignment is complete. UL_ASS_TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0xf1223344){NONE}: Received Event SCHED_ASS UL_ASS_TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0xf1223344){NONE}: state_chg to SEND_ASS +MS(TLLI-0xf1223344:TA-7:MSCLS-1-0:UL): - rcv_resource_request: now used by 0 (-) UL_ASS_TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0xf1223344){SEND_ASS}: Received Event CREATE_RLCMAC_MSG PDCH(bts=0,trx=0,ts=7) POLL scheduled at FN 2654270 + 13 = 2654283 TBF(UL:TFI-0-0-0:STATE-ASSIGN:GPRS:TLLI-0xf1223344) start Packet Uplink Assignment (PACCH) @@ -2871,6 +3021,8 @@ TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344) Setting Control TS PDCH(bts=0,trx=0,ts=7) TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344) Allocated: trx = 0, ul_slots = 80, dl_slots = 80 MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:UL) Attaching DL TBF: TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344) +MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:UL): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:UL:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344) [DOWNLINK] START (PACCH) TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344) Send downlink assignment on PACCH, because TBF(UL:TFI-0-0-0:STATE-FLOW:GPRS:IMSI-0011223344:TLLI-0xf1223344) exists DL_ASS_TBF(UL:TFI-0-0-0:STATE-FLOW:GPRS:IMSI-0011223344:TLLI-0xf1223344){NONE}: Received Event SCHED_ASS @@ -3627,6 +3779,8 @@ TBF(DL:TFI-0-0-1:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344) Setting Control TS PDCH(bts=0,trx=0,ts=7) TBF(DL:TFI-0-0-1:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344) Allocated: trx = 0, ul_slots = 80, dl_slots = 80 MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:UL:DL) Attaching DL TBF: TBF(DL:TFI-0-0-1:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344) +MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:UL:DL): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-0:UL:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-1:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344) [DOWNLINK] START (PACCH) TBF(DL:TFI-0-0-1:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344) Send downlink assignment on PACCH, because TBF(DL:TFI-0-0-0:STATE-WAIT_RELEASE:GPRS:IMSI-0011223344:TLLI-0xf1223344) exists DL_ASS_TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-0011223344:TLLI-0xf1223344){NONE}: Received Event SCHED_ASS @@ -3896,6 +4050,8 @@ TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-001001123456789:TLLI-0xc0006789) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-001001123456789:TLLI-0xc0006789) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(IMSI-001001123456789:TLLI-0xc0006789:TA-220:MSCLS-45-0) Attaching DL TBF: TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-001001123456789:TLLI-0xc0006789) +MS(IMSI-001001123456789:TLLI-0xc0006789:TA-220:MSCLS-45-0): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-001001123456789:TLLI-0xc0006789:TA-220:MSCLS-45-0:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-001001123456789:TLLI-0xc0006789) [DOWNLINK] START (PCH) TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-001001123456789:TLLI-0xc0006789) Send downlink assignment on PCH, no TBF exist (IMSI=001001123456789) DL_TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-001001123456789:TLLI-0xc0006789){NEW}: Received Event ASSIGN_ADD_CCCH @@ -3941,6 +4097,8 @@ TBF(DL:TFI-0-0-0:STATE-NEW:GPRS) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-0:STATE-NEW:GPRS) Allocated: trx = 0, ul_slots = 10, dl_slots = 3c MS(TA-220:MSCLS-12-0) Attaching DL TBF: TBF(DL:TFI-0-0-0:STATE-NEW:GPRS) +MS(TA-220:MSCLS-12-0): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(TA-220:MSCLS-12-0:DL): - ms_attach_dl_tbf: now used by 0 (-) DL TBF slots: 0x3c, N: 4, WS: 64 TBF(DL:TFI-0-0-0:STATE-NEW:GPRS) free PDCH(bts=0,trx=0,ts=2) Detaching TBF(DL:TFI-0-0-0:STATE-NEW:GPRS), 1 TBFs, USFs = 00, TFIs = 00000001. @@ -3985,6 +4143,8 @@ TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) Allocated: trx = 0, ul_slots = 10, dl_slots = 3c MS(TA-220:MSCLS-12-12) Attaching DL TBF: TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) +MS(TA-220:MSCLS-12-12): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(TA-220:MSCLS-12-12:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) setting EGPRS DL window size to 384, base(128) slots(4) ws_pdch(64) ws(384) DL TBF slots: 0x3c, N: 4, WS: 384 @@ -4013,6 +4173,7 @@ PDCH(bts=0,trx=0,ts=7) FN=2654270 ------------------------- RX : Uplink Control Block ------------------------- Creating MS object Modifying MS object, UL TLLI: 0xffffffff -> 0xf1223344, not yet confirmed +MS(TLLI-0xf1223344:TA-220:MSCLS-0-0): + rcv_resource_request: now used by 1 (rcv_resource_request) PDCH(bts=0,trx=0,ts=7) FN=2654270 PKT RESOURCE REQ: MS requests UL TBF throguh SBA Modifying MS object, TLLI = 0xf1223344, TA 220 -> 7 Modifying MS object, TLLI = 0xf1223344, MS class 0 -> 1 @@ -4038,6 +4199,8 @@ TBF(UL:TFI-0-0-0:STATE-NEW:EGPRS:TLLI-0xf1223344) Setting Control TS PDCH(bts=0,trx=0,ts=7) TBF(UL:TFI-0-0-0:STATE-NEW:EGPRS:TLLI-0xf1223344) Allocated: trx = 0, ul_slots = 80, dl_slots = 00 MS(TLLI-0xf1223344:TA-7:MSCLS-1-1) Attaching UL TBF: TBF(UL:TFI-0-0-0:STATE-NEW:EGPRS:TLLI-0xf1223344) +MS(TLLI-0xf1223344:TA-7:MSCLS-1-1): + ms_attach_ul_tbf: now used by 2 (rcv_resource_request,ms_attach_ul_tbf) +MS(TLLI-0xf1223344:TA-7:MSCLS-1-1:UL): - ms_attach_ul_tbf: now used by 1 (rcv_resource_request) TBF(UL:TFI-0-0-0:STATE-NEW:EGPRS:TLLI-0xf1223344) setting EGPRS UL window size to 64, base(64) slots(1) ws_pdch(0) ws(64) UL_TBF(UL:TFI-0-0-0:STATE-NEW:EGPRS:TLLI-0xf1223344){NEW}: Received Event ASSIGN_ADD_PACCH @@ -4047,6 +4210,7 @@ TBF(UL:TFI-0-0-0:STATE-ASSIGN:EGPRS:TLLI-0xf1223344) Change control TS PDCH(bts=0,trx=0,ts=7) -> PDCH(bts=0,trx=0,ts=7) until assignment is complete. UL_ASS_TBF(UL:TFI-0-0-0:STATE-NEW:EGPRS:TLLI-0xf1223344){NONE}: Received Event SCHED_ASS UL_ASS_TBF(UL:TFI-0-0-0:STATE-NEW:EGPRS:TLLI-0xf1223344){NONE}: state_chg to SEND_ASS +MS(TLLI-0xf1223344:TA-7:MSCLS-1-1:UL): - rcv_resource_request: now used by 0 (-) UL_ASS_TBF(UL:TFI-0-0-0:STATE-NEW:EGPRS:TLLI-0xf1223344){SEND_ASS}: Received Event CREATE_RLCMAC_MSG PDCH(bts=0,trx=0,ts=7) POLL scheduled at FN 2654270 + 13 = 2654283 TBF(UL:TFI-0-0-0:STATE-ASSIGN:EGPRS:TLLI-0xf1223344) start Packet Uplink Assignment (PACCH) @@ -4099,6 +4263,8 @@ TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS:IMSI-0011223344:TLLI-0xf1223344) Setting Control TS PDCH(bts=0,trx=0,ts=7) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS:IMSI-0011223344:TLLI-0xf1223344) Allocated: trx = 0, ul_slots = 80, dl_slots = 80 MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1:UL) Attaching DL TBF: TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS:IMSI-0011223344:TLLI-0xf1223344) +MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1:UL): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1:UL:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS:IMSI-0011223344:TLLI-0xf1223344) setting EGPRS DL window size to 64, base(64) slots(1) ws_pdch(0) ws(64) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS:IMSI-0011223344:TLLI-0xf1223344) [DOWNLINK] START (PACCH) @@ -4126,6 +4292,7 @@ PDCH(bts=0,trx=0,ts=7) FN=2654270 ------------------------- RX : Uplink Control Block ------------------------- Creating MS object Modifying MS object, UL TLLI: 0xffffffff -> 0xf1223344, not yet confirmed +MS(TLLI-0xf1223344:TA-220:MSCLS-0-0): + rcv_resource_request: now used by 1 (rcv_resource_request) PDCH(bts=0,trx=0,ts=7) FN=2654270 PKT RESOURCE REQ: MS requests UL TBF throguh SBA Modifying MS object, TLLI = 0xf1223344, TA 220 -> 7 Modifying MS object, TLLI = 0xf1223344, MS class 0 -> 1 @@ -4151,6 +4318,8 @@ TBF(UL:TFI-0-0-0:STATE-NEW:EGPRS:TLLI-0xf1223344) Setting Control TS PDCH(bts=0,trx=0,ts=7) TBF(UL:TFI-0-0-0:STATE-NEW:EGPRS:TLLI-0xf1223344) Allocated: trx = 0, ul_slots = 80, dl_slots = 00 MS(TLLI-0xf1223344:TA-7:MSCLS-1-1) Attaching UL TBF: TBF(UL:TFI-0-0-0:STATE-NEW:EGPRS:TLLI-0xf1223344) +MS(TLLI-0xf1223344:TA-7:MSCLS-1-1): + ms_attach_ul_tbf: now used by 2 (rcv_resource_request,ms_attach_ul_tbf) +MS(TLLI-0xf1223344:TA-7:MSCLS-1-1:UL): - ms_attach_ul_tbf: now used by 1 (rcv_resource_request) TBF(UL:TFI-0-0-0:STATE-NEW:EGPRS:TLLI-0xf1223344) setting EGPRS UL window size to 64, base(64) slots(1) ws_pdch(0) ws(64) UL_TBF(UL:TFI-0-0-0:STATE-NEW:EGPRS:TLLI-0xf1223344){NEW}: Received Event ASSIGN_ADD_PACCH @@ -4160,6 +4329,7 @@ TBF(UL:TFI-0-0-0:STATE-ASSIGN:EGPRS:TLLI-0xf1223344) Change control TS PDCH(bts=0,trx=0,ts=7) -> PDCH(bts=0,trx=0,ts=7) until assignment is complete. UL_ASS_TBF(UL:TFI-0-0-0:STATE-NEW:EGPRS:TLLI-0xf1223344){NONE}: Received Event SCHED_ASS UL_ASS_TBF(UL:TFI-0-0-0:STATE-NEW:EGPRS:TLLI-0xf1223344){NONE}: state_chg to SEND_ASS +MS(TLLI-0xf1223344:TA-7:MSCLS-1-1:UL): - rcv_resource_request: now used by 0 (-) UL_ASS_TBF(UL:TFI-0-0-0:STATE-NEW:EGPRS:TLLI-0xf1223344){SEND_ASS}: Received Event CREATE_RLCMAC_MSG PDCH(bts=0,trx=0,ts=7) POLL scheduled at FN 2654270 + 13 = 2654283 TBF(UL:TFI-0-0-0:STATE-ASSIGN:EGPRS:TLLI-0xf1223344) start Packet Uplink Assignment (PACCH) @@ -4335,6 +4505,8 @@ TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS:IMSI-0011223344:TLLI-0xf1223344) Setting Control TS PDCH(bts=0,trx=0,ts=7) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS:IMSI-0011223344:TLLI-0xf1223344) Allocated: trx = 0, ul_slots = 80, dl_slots = 80 MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1:UL) Attaching DL TBF: TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS:IMSI-0011223344:TLLI-0xf1223344) +MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1:UL): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1:UL:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS:IMSI-0011223344:TLLI-0xf1223344) setting EGPRS DL window size to 64, base(64) slots(1) ws_pdch(0) ws(64) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS:IMSI-0011223344:TLLI-0xf1223344) [DOWNLINK] START (PACCH) @@ -4375,6 +4547,8 @@ TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(TA-220:MSCLS-11-11) Attaching DL TBF: TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) +MS(TA-220:MSCLS-11-11): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(TA-220:MSCLS-11-11:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) setting EGPRS DL window size to 64, base(64) slots(1) ws_pdch(0) ws(64) Modifying MS object, TLLI = 0xffffffff, TA 220 -> 0 @@ -4686,6 +4860,8 @@ TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(TA-220:MSCLS-11-11) Attaching DL TBF: TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) +MS(TA-220:MSCLS-11-11): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(TA-220:MSCLS-11-11:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) setting EGPRS DL window size to 64, base(64) slots(1) ws_pdch(0) ws(64) Modifying MS object, TLLI = 0xffffffff, TA 220 -> 0 @@ -4946,6 +5122,8 @@ TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(TA-220:MSCLS-11-11) Attaching DL TBF: TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) +MS(TA-220:MSCLS-11-11): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(TA-220:MSCLS-11-11:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) setting EGPRS DL window size to 64, base(64) slots(1) ws_pdch(0) ws(64) Modifying MS object, TLLI = 0xffffffff, TA 220 -> 0 @@ -5166,6 +5344,8 @@ TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(TA-220:MSCLS-11-11) Attaching DL TBF: TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) +MS(TA-220:MSCLS-11-11): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(TA-220:MSCLS-11-11:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) setting EGPRS DL window size to 64, base(64) slots(1) ws_pdch(0) ws(64) Modifying MS object, TLLI = 0xffffffff, TA 220 -> 0 @@ -5370,6 +5550,8 @@ TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(TA-220:MSCLS-11-11) Attaching DL TBF: TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) +MS(TA-220:MSCLS-11-11): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(TA-220:MSCLS-11-11:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) setting EGPRS DL window size to 64, base(64) slots(1) ws_pdch(0) ws(64) Modifying MS object, TLLI = 0xffffffff, TA 220 -> 0 @@ -5558,6 +5740,8 @@ TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(TA-220:MSCLS-11-11) Attaching DL TBF: TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) +MS(TA-220:MSCLS-11-11): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(TA-220:MSCLS-11-11:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) setting EGPRS DL window size to 64, base(64) slots(1) ws_pdch(0) ws(64) Modifying MS object, TLLI = 0xffffffff, TA 220 -> 0 @@ -5722,6 +5906,8 @@ TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(TA-220:MSCLS-11-11) Attaching DL TBF: TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) +MS(TA-220:MSCLS-11-11): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(TA-220:MSCLS-11-11:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) setting EGPRS DL window size to 64, base(64) slots(1) ws_pdch(0) ws(64) Modifying MS object, TLLI = 0xffffffff, TA 220 -> 0 @@ -5895,6 +6081,8 @@ TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(TA-220:MSCLS-11-11) Attaching DL TBF: TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) +MS(TA-220:MSCLS-11-11): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(TA-220:MSCLS-11-11:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) setting EGPRS DL window size to 64, base(64) slots(1) ws_pdch(0) ws(64) Modifying MS object, TLLI = 0xffffffff, TA 220 -> 0 @@ -6059,6 +6247,8 @@ TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(TA-220:MSCLS-11-11) Attaching DL TBF: TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) +MS(TA-220:MSCLS-11-11): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(TA-220:MSCLS-11-11:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) setting EGPRS DL window size to 64, base(64) slots(1) ws_pdch(0) ws(64) Modifying MS object, TLLI = 0xffffffff, TA 220 -> 0 @@ -6215,6 +6405,8 @@ TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(TA-220:MSCLS-11-11) Attaching DL TBF: TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) +MS(TA-220:MSCLS-11-11): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(TA-220:MSCLS-11-11:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) setting EGPRS DL window size to 64, base(64) slots(1) ws_pdch(0) ws(64) Modifying MS object, TLLI = 0xffffffff, TA 220 -> 0 @@ -6286,6 +6478,8 @@ TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(TA-220:MSCLS-11-11) Attaching DL TBF: TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) +MS(TA-220:MSCLS-11-11): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(TA-220:MSCLS-11-11:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) setting EGPRS DL window size to 64, base(64) slots(1) ws_pdch(0) ws(64) Modifying MS object, TLLI = 0xffffffff, TA 220 -> 0 @@ -6356,6 +6550,8 @@ TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(TA-220:MSCLS-11-11) Attaching DL TBF: TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) +MS(TA-220:MSCLS-11-11): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(TA-220:MSCLS-11-11:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) setting EGPRS DL window size to 64, base(64) slots(1) ws_pdch(0) ws(64) Modifying MS object, TLLI = 0xffffffff, TA 220 -> 0 @@ -6426,6 +6622,8 @@ TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(TA-220:MSCLS-11-11) Attaching DL TBF: TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) +MS(TA-220:MSCLS-11-11): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(TA-220:MSCLS-11-11:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) setting EGPRS DL window size to 64, base(64) slots(1) ws_pdch(0) ws(64) Modifying MS object, TLLI = 0xffffffff, TA 220 -> 0 @@ -6521,6 +6719,8 @@ TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(TA-220:MSCLS-11-11) Attaching DL TBF: TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) +MS(TA-220:MSCLS-11-11): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(TA-220:MSCLS-11-11:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) setting EGPRS DL window size to 64, base(64) slots(1) ws_pdch(0) ws(64) Modifying MS object, TLLI = 0xffffffff, TA 220 -> 0 @@ -6615,6 +6815,8 @@ TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(TA-220:MSCLS-11-11) Attaching DL TBF: TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) +MS(TA-220:MSCLS-11-11): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(TA-220:MSCLS-11-11:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) setting EGPRS DL window size to 64, base(64) slots(1) ws_pdch(0) ws(64) Modifying MS object, TLLI = 0xffffffff, TA 220 -> 0 @@ -6709,6 +6911,8 @@ TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(TA-220:MSCLS-11-11) Attaching DL TBF: TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) +MS(TA-220:MSCLS-11-11): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(TA-220:MSCLS-11-11:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) setting EGPRS DL window size to 64, base(64) slots(1) ws_pdch(0) ws(64) Modifying MS object, TLLI = 0xffffffff, TA 220 -> 0 @@ -6806,6 +7010,8 @@ TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(TA-220:MSCLS-11-11) Attaching DL TBF: TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) +MS(TA-220:MSCLS-11-11): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(TA-220:MSCLS-11-11:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) setting EGPRS DL window size to 64, base(64) slots(1) ws_pdch(0) ws(64) Modifying MS object, TLLI = 0xffffffff, TA 220 -> 0 @@ -6883,6 +7089,8 @@ TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(TA-220:MSCLS-11-11) Attaching DL TBF: TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) +MS(TA-220:MSCLS-11-11): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(TA-220:MSCLS-11-11:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) setting EGPRS DL window size to 64, base(64) slots(1) ws_pdch(0) ws(64) Modifying MS object, TLLI = 0xffffffff, TA 220 -> 0 @@ -6960,6 +7168,8 @@ TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(TA-220:MSCLS-11-11) Attaching DL TBF: TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) +MS(TA-220:MSCLS-11-11): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(TA-220:MSCLS-11-11:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) setting EGPRS DL window size to 64, base(64) slots(1) ws_pdch(0) ws(64) Modifying MS object, TLLI = 0xffffffff, TA 220 -> 0 @@ -7037,6 +7247,8 @@ TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(TA-220:MSCLS-11-11) Attaching DL TBF: TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) +MS(TA-220:MSCLS-11-11): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(TA-220:MSCLS-11-11:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) setting EGPRS DL window size to 64, base(64) slots(1) ws_pdch(0) ws(64) Modifying MS object, TLLI = 0xffffffff, TA 220 -> 0 @@ -7111,6 +7323,7 @@ PDCH(bts=0,trx=0,ts=7) FN=2654270 ------------------------- RX : Uplink Control Block ------------------------- Creating MS object Modifying MS object, UL TLLI: 0xffffffff -> 0xf1223344, not yet confirmed +MS(TLLI-0xf1223344:TA-220:MSCLS-0-0): + rcv_resource_request: now used by 1 (rcv_resource_request) PDCH(bts=0,trx=0,ts=7) FN=2654270 PKT RESOURCE REQ: MS requests UL TBF throguh SBA Modifying MS object, TLLI = 0xf1223344, TA 220 -> 7 Modifying MS object, TLLI = 0xf1223344, MS class 0 -> 1 @@ -7136,6 +7349,8 @@ TBF(UL:TFI-0-0-0:STATE-NEW:EGPRS:TLLI-0xf1223344) Setting Control TS PDCH(bts=0,trx=0,ts=7) TBF(UL:TFI-0-0-0:STATE-NEW:EGPRS:TLLI-0xf1223344) Allocated: trx = 0, ul_slots = 80, dl_slots = 00 MS(TLLI-0xf1223344:TA-7:MSCLS-1-1) Attaching UL TBF: TBF(UL:TFI-0-0-0:STATE-NEW:EGPRS:TLLI-0xf1223344) +MS(TLLI-0xf1223344:TA-7:MSCLS-1-1): + ms_attach_ul_tbf: now used by 2 (rcv_resource_request,ms_attach_ul_tbf) +MS(TLLI-0xf1223344:TA-7:MSCLS-1-1:UL): - ms_attach_ul_tbf: now used by 1 (rcv_resource_request) TBF(UL:TFI-0-0-0:STATE-NEW:EGPRS:TLLI-0xf1223344) setting EGPRS UL window size to 64, base(64) slots(1) ws_pdch(0) ws(64) UL_TBF(UL:TFI-0-0-0:STATE-NEW:EGPRS:TLLI-0xf1223344){NEW}: Received Event ASSIGN_ADD_PACCH @@ -7145,6 +7360,7 @@ TBF(UL:TFI-0-0-0:STATE-ASSIGN:EGPRS:TLLI-0xf1223344) Change control TS PDCH(bts=0,trx=0,ts=7) -> PDCH(bts=0,trx=0,ts=7) until assignment is complete. UL_ASS_TBF(UL:TFI-0-0-0:STATE-NEW:EGPRS:TLLI-0xf1223344){NONE}: Received Event SCHED_ASS UL_ASS_TBF(UL:TFI-0-0-0:STATE-NEW:EGPRS:TLLI-0xf1223344){NONE}: state_chg to SEND_ASS +MS(TLLI-0xf1223344:TA-7:MSCLS-1-1:UL): - rcv_resource_request: now used by 0 (-) UL_ASS_TBF(UL:TFI-0-0-0:STATE-NEW:EGPRS:TLLI-0xf1223344){SEND_ASS}: Received Event CREATE_RLCMAC_MSG PDCH(bts=0,trx=0,ts=7) POLL scheduled at FN 2654270 + 13 = 2654283 TBF(UL:TFI-0-0-0:STATE-ASSIGN:EGPRS:TLLI-0xf1223344) start Packet Uplink Assignment (PACCH) @@ -7220,6 +7436,8 @@ TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS:IMSI-0011223344:TLLI-0xf1223344) Setting Control TS PDCH(bts=0,trx=0,ts=7) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS:IMSI-0011223344:TLLI-0xf1223344) Allocated: trx = 0, ul_slots = 80, dl_slots = 80 MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1:UL) Attaching DL TBF: TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS:IMSI-0011223344:TLLI-0xf1223344) +MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1:UL): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1:UL:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS:IMSI-0011223344:TLLI-0xf1223344) setting EGPRS DL window size to 64, base(64) slots(1) ws_pdch(0) ws(64) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS:IMSI-0011223344:TLLI-0xf1223344) [DOWNLINK] START (PACCH) @@ -7265,6 +7483,8 @@ TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(TA-220:MSCLS-11-11) Attaching DL TBF: TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) +MS(TA-220:MSCLS-11-11): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(TA-220:MSCLS-11-11:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) setting EGPRS DL window size to 192, base(128) slots(1) ws_pdch(64) ws(192) DL TBF slots: 0x10, N: 1, WS: 192 @@ -7317,6 +7537,7 @@ PDCH(bts=0,trx=0,ts=7) FN=2654270 ------------------------- RX : Uplink Control Block ------------------------- Creating MS object Modifying MS object, UL TLLI: 0xffffffff -> 0xf1223344, not yet confirmed +MS(TLLI-0xf1223344:TA-220:MSCLS-0-0): + rcv_resource_request: now used by 1 (rcv_resource_request) PDCH(bts=0,trx=0,ts=7) FN=2654270 PKT RESOURCE REQ: MS requests UL TBF throguh SBA Modifying MS object, TLLI = 0xf1223344, TA 220 -> 7 Modifying MS object, TLLI = 0xf1223344, MS class 0 -> 1 @@ -7342,6 +7563,8 @@ TBF(UL:TFI-0-0-0:STATE-NEW:EGPRS:TLLI-0xf1223344) Setting Control TS PDCH(bts=0,trx=0,ts=7) TBF(UL:TFI-0-0-0:STATE-NEW:EGPRS:TLLI-0xf1223344) Allocated: trx = 0, ul_slots = 80, dl_slots = 00 MS(TLLI-0xf1223344:TA-7:MSCLS-1-1) Attaching UL TBF: TBF(UL:TFI-0-0-0:STATE-NEW:EGPRS:TLLI-0xf1223344) +MS(TLLI-0xf1223344:TA-7:MSCLS-1-1): + ms_attach_ul_tbf: now used by 2 (rcv_resource_request,ms_attach_ul_tbf) +MS(TLLI-0xf1223344:TA-7:MSCLS-1-1:UL): - ms_attach_ul_tbf: now used by 1 (rcv_resource_request) TBF(UL:TFI-0-0-0:STATE-NEW:EGPRS:TLLI-0xf1223344) setting EGPRS UL window size to 64, base(64) slots(1) ws_pdch(0) ws(64) UL_TBF(UL:TFI-0-0-0:STATE-NEW:EGPRS:TLLI-0xf1223344){NEW}: Received Event ASSIGN_ADD_PACCH @@ -7351,6 +7574,7 @@ TBF(UL:TFI-0-0-0:STATE-ASSIGN:EGPRS:TLLI-0xf1223344) Change control TS PDCH(bts=0,trx=0,ts=7) -> PDCH(bts=0,trx=0,ts=7) until assignment is complete. UL_ASS_TBF(UL:TFI-0-0-0:STATE-NEW:EGPRS:TLLI-0xf1223344){NONE}: Received Event SCHED_ASS UL_ASS_TBF(UL:TFI-0-0-0:STATE-NEW:EGPRS:TLLI-0xf1223344){NONE}: state_chg to SEND_ASS +MS(TLLI-0xf1223344:TA-7:MSCLS-1-1:UL): - rcv_resource_request: now used by 0 (-) UL_ASS_TBF(UL:TFI-0-0-0:STATE-NEW:EGPRS:TLLI-0xf1223344){SEND_ASS}: Received Event CREATE_RLCMAC_MSG PDCH(bts=0,trx=0,ts=7) POLL scheduled at FN 2654270 + 13 = 2654283 TBF(UL:TFI-0-0-0:STATE-ASSIGN:EGPRS:TLLI-0xf1223344) start Packet Uplink Assignment (PACCH) @@ -7415,6 +7639,8 @@ TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS:IMSI-0011223344:TLLI-0xf1223344) Setting Control TS PDCH(bts=0,trx=0,ts=7) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS:IMSI-0011223344:TLLI-0xf1223344) Allocated: trx = 0, ul_slots = 80, dl_slots = 80 MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1:UL) Attaching DL TBF: TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS:IMSI-0011223344:TLLI-0xf1223344) +MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1:UL): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1:UL:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS:IMSI-0011223344:TLLI-0xf1223344) setting EGPRS DL window size to 64, base(64) slots(1) ws_pdch(0) ws(64) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS:IMSI-0011223344:TLLI-0xf1223344) [DOWNLINK] START (PACCH) @@ -7454,6 +7680,8 @@ TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(TA-220:MSCLS-11-11) Attaching DL TBF: TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) +MS(TA-220:MSCLS-11-11): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(TA-220:MSCLS-11-11:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) setting EGPRS DL window size to 64, base(64) slots(1) ws_pdch(0) ws(64) Modifying MS object, TLLI = 0xffffffff, TA 220 -> 0 @@ -7506,6 +7734,8 @@ TBF(UL:TFI-0-0-0:STATE-NEW:GPRS) Setting Control TS PDCH(bts=0,trx=0,ts=7) TBF(UL:TFI-0-0-0:STATE-NEW:GPRS) Allocated: trx = 0, ul_slots = 80, dl_slots = 00 MS(TA-220:MSCLS-0-0) Attaching UL TBF: TBF(UL:TFI-0-0-0:STATE-NEW:GPRS) +MS(TA-220:MSCLS-0-0): + ms_attach_ul_tbf: now used by 1 (ms_attach_ul_tbf) +MS(TA-220:MSCLS-0-0:UL): - ms_attach_ul_tbf: now used by 0 (-) UL_TBF(UL:TFI-0-0-0:STATE-NEW:GPRS){NEW}: Received Event ASSIGN_ADD_CCCH TBF(UL:TFI-0-0-0:STATE-NEW:GPRS) set ass. type CCCH [prev CCCH:0, PACCH:0] UL_TBF(UL:TFI-0-0-0:STATE-NEW:GPRS){NEW}: state_chg to FLOW @@ -7534,6 +7764,8 @@ TBF(UL:TFI-0-0-1:STATE-NEW:GPRS) Setting Control TS PDCH(bts=0,trx=0,ts=7) TBF(UL:TFI-0-0-1:STATE-NEW:GPRS) Allocated: trx = 0, ul_slots = 80, dl_slots = 00 MS(TA-220:MSCLS-0-0) Attaching UL TBF: TBF(UL:TFI-0-0-1:STATE-NEW:GPRS) +MS(TA-220:MSCLS-0-0): + ms_attach_ul_tbf: now used by 1 (ms_attach_ul_tbf) +MS(TA-220:MSCLS-0-0:UL): - ms_attach_ul_tbf: now used by 0 (-) UL_TBF(UL:TFI-0-0-1:STATE-NEW:GPRS){NEW}: Received Event ASSIGN_ADD_CCCH TBF(UL:TFI-0-0-1:STATE-NEW:GPRS) set ass. type CCCH [prev CCCH:0, PACCH:0] UL_TBF(UL:TFI-0-0-1:STATE-NEW:GPRS){NEW}: state_chg to FLOW @@ -7562,6 +7794,8 @@ TBF(UL:TFI-0-0-2:STATE-NEW:GPRS) Setting Control TS PDCH(bts=0,trx=0,ts=7) TBF(UL:TFI-0-0-2:STATE-NEW:GPRS) Allocated: trx = 0, ul_slots = 80, dl_slots = 00 MS(TA-220:MSCLS-0-0) Attaching UL TBF: TBF(UL:TFI-0-0-2:STATE-NEW:GPRS) +MS(TA-220:MSCLS-0-0): + ms_attach_ul_tbf: now used by 1 (ms_attach_ul_tbf) +MS(TA-220:MSCLS-0-0:UL): - ms_attach_ul_tbf: now used by 0 (-) UL_TBF(UL:TFI-0-0-2:STATE-NEW:GPRS){NEW}: Received Event ASSIGN_ADD_CCCH TBF(UL:TFI-0-0-2:STATE-NEW:GPRS) set ass. type CCCH [prev CCCH:0, PACCH:0] UL_TBF(UL:TFI-0-0-2:STATE-NEW:GPRS){NEW}: state_chg to FLOW @@ -7590,6 +7824,8 @@ TBF(UL:TFI-0-0-3:STATE-NEW:GPRS) Setting Control TS PDCH(bts=0,trx=0,ts=7) TBF(UL:TFI-0-0-3:STATE-NEW:GPRS) Allocated: trx = 0, ul_slots = 80, dl_slots = 00 MS(TA-220:MSCLS-0-0) Attaching UL TBF: TBF(UL:TFI-0-0-3:STATE-NEW:GPRS) +MS(TA-220:MSCLS-0-0): + ms_attach_ul_tbf: now used by 1 (ms_attach_ul_tbf) +MS(TA-220:MSCLS-0-0:UL): - ms_attach_ul_tbf: now used by 0 (-) UL_TBF(UL:TFI-0-0-3:STATE-NEW:GPRS){NEW}: Received Event ASSIGN_ADD_CCCH TBF(UL:TFI-0-0-3:STATE-NEW:GPRS) set ass. type CCCH [prev CCCH:0, PACCH:0] UL_TBF(UL:TFI-0-0-3:STATE-NEW:GPRS){NEW}: state_chg to FLOW @@ -7618,6 +7854,8 @@ TBF(UL:TFI-0-0-4:STATE-NEW:GPRS) Setting Control TS PDCH(bts=0,trx=0,ts=7) TBF(UL:TFI-0-0-4:STATE-NEW:GPRS) Allocated: trx = 0, ul_slots = 80, dl_slots = 00 MS(TA-220:MSCLS-0-0) Attaching UL TBF: TBF(UL:TFI-0-0-4:STATE-NEW:GPRS) +MS(TA-220:MSCLS-0-0): + ms_attach_ul_tbf: now used by 1 (ms_attach_ul_tbf) +MS(TA-220:MSCLS-0-0:UL): - ms_attach_ul_tbf: now used by 0 (-) UL_TBF(UL:TFI-0-0-4:STATE-NEW:GPRS){NEW}: Received Event ASSIGN_ADD_CCCH TBF(UL:TFI-0-0-4:STATE-NEW:GPRS) set ass. type CCCH [prev CCCH:0, PACCH:0] UL_TBF(UL:TFI-0-0-4:STATE-NEW:GPRS){NEW}: state_chg to FLOW @@ -7646,6 +7884,8 @@ TBF(UL:TFI-0-0-5:STATE-NEW:GPRS) Setting Control TS PDCH(bts=0,trx=0,ts=7) TBF(UL:TFI-0-0-5:STATE-NEW:GPRS) Allocated: trx = 0, ul_slots = 80, dl_slots = 00 MS(TA-220:MSCLS-0-0) Attaching UL TBF: TBF(UL:TFI-0-0-5:STATE-NEW:GPRS) +MS(TA-220:MSCLS-0-0): + ms_attach_ul_tbf: now used by 1 (ms_attach_ul_tbf) +MS(TA-220:MSCLS-0-0:UL): - ms_attach_ul_tbf: now used by 0 (-) UL_TBF(UL:TFI-0-0-5:STATE-NEW:GPRS){NEW}: Received Event ASSIGN_ADD_CCCH TBF(UL:TFI-0-0-5:STATE-NEW:GPRS) set ass. type CCCH [prev CCCH:0, PACCH:0] UL_TBF(UL:TFI-0-0-5:STATE-NEW:GPRS){NEW}: state_chg to FLOW @@ -7674,6 +7914,8 @@ TBF(UL:TFI-0-0-6:STATE-NEW:GPRS) Setting Control TS PDCH(bts=0,trx=0,ts=7) TBF(UL:TFI-0-0-6:STATE-NEW:GPRS) Allocated: trx = 0, ul_slots = 80, dl_slots = 00 MS(TA-220:MSCLS-0-0) Attaching UL TBF: TBF(UL:TFI-0-0-6:STATE-NEW:GPRS) +MS(TA-220:MSCLS-0-0): + ms_attach_ul_tbf: now used by 1 (ms_attach_ul_tbf) +MS(TA-220:MSCLS-0-0:UL): - ms_attach_ul_tbf: now used by 0 (-) UL_TBF(UL:TFI-0-0-6:STATE-NEW:GPRS){NEW}: Received Event ASSIGN_ADD_CCCH TBF(UL:TFI-0-0-6:STATE-NEW:GPRS) set ass. type CCCH [prev CCCH:0, PACCH:0] UL_TBF(UL:TFI-0-0-6:STATE-NEW:GPRS){NEW}: state_chg to FLOW @@ -7744,6 +7986,7 @@ PDCH(bts=0,trx=0,ts=7) FN=2654270 ------------------------- RX : Uplink Control Block ------------------------- Creating MS object Modifying MS object, UL TLLI: 0xffffffff -> 0xf1223344, not yet confirmed +MS(TLLI-0xf1223344:TA-220:MSCLS-0-0): + rcv_resource_request: now used by 1 (rcv_resource_request) PDCH(bts=0,trx=0,ts=7) FN=2654270 PKT RESOURCE REQ: MS requests UL TBF throguh SBA Modifying MS object, TLLI = 0xf1223344, TA 220 -> 7 Modifying MS object, TLLI = 0xf1223344, MS class 0 -> 1 @@ -7769,6 +8012,8 @@ TBF(UL:TFI-0-0-0:STATE-NEW:EGPRS:TLLI-0xf1223344) Setting Control TS PDCH(bts=0,trx=0,ts=7) TBF(UL:TFI-0-0-0:STATE-NEW:EGPRS:TLLI-0xf1223344) Allocated: trx = 0, ul_slots = 80, dl_slots = 00 MS(TLLI-0xf1223344:TA-7:MSCLS-1-1) Attaching UL TBF: TBF(UL:TFI-0-0-0:STATE-NEW:EGPRS:TLLI-0xf1223344) +MS(TLLI-0xf1223344:TA-7:MSCLS-1-1): + ms_attach_ul_tbf: now used by 2 (rcv_resource_request,ms_attach_ul_tbf) +MS(TLLI-0xf1223344:TA-7:MSCLS-1-1:UL): - ms_attach_ul_tbf: now used by 1 (rcv_resource_request) TBF(UL:TFI-0-0-0:STATE-NEW:EGPRS:TLLI-0xf1223344) setting EGPRS UL window size to 192, base(128) slots(1) ws_pdch(64) ws(192) UL_TBF(UL:TFI-0-0-0:STATE-NEW:EGPRS:TLLI-0xf1223344){NEW}: Received Event ASSIGN_ADD_PACCH @@ -7778,6 +8023,7 @@ TBF(UL:TFI-0-0-0:STATE-ASSIGN:EGPRS:TLLI-0xf1223344) Change control TS PDCH(bts=0,trx=0,ts=7) -> PDCH(bts=0,trx=0,ts=7) until assignment is complete. UL_ASS_TBF(UL:TFI-0-0-0:STATE-NEW:EGPRS:TLLI-0xf1223344){NONE}: Received Event SCHED_ASS UL_ASS_TBF(UL:TFI-0-0-0:STATE-NEW:EGPRS:TLLI-0xf1223344){NONE}: state_chg to SEND_ASS +MS(TLLI-0xf1223344:TA-7:MSCLS-1-1:UL): - rcv_resource_request: now used by 0 (-) UL_ASS_TBF(UL:TFI-0-0-0:STATE-NEW:EGPRS:TLLI-0xf1223344){SEND_ASS}: Received Event CREATE_RLCMAC_MSG PDCH(bts=0,trx=0,ts=7) POLL scheduled at FN 2654270 + 13 = 2654283 TBF(UL:TFI-0-0-0:STATE-ASSIGN:EGPRS:TLLI-0xf1223344) start Packet Uplink Assignment (PACCH) @@ -8327,6 +8573,8 @@ TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS:IMSI-0011223344:TLLI-0xf1223344) Setting Control TS PDCH(bts=0,trx=0,ts=7) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS:IMSI-0011223344:TLLI-0xf1223344) Allocated: trx = 0, ul_slots = 80, dl_slots = 80 MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1:UL) Attaching DL TBF: TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS:IMSI-0011223344:TLLI-0xf1223344) +MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1:UL): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-0011223344:TLLI-0xf1223344:TA-7:MSCLS-1-1:UL:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS:IMSI-0011223344:TLLI-0xf1223344) setting EGPRS DL window size to 192, base(128) slots(1) ws_pdch(64) ws(192) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS:IMSI-0011223344:TLLI-0xf1223344) [DOWNLINK] START (PACCH) @@ -9028,6 +9276,8 @@ TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) Setting Control TS PDCH(bts=0,trx=0,ts=4) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) Allocated: trx = 0, ul_slots = 10, dl_slots = 10 MS(TA-220:MSCLS-11-11) Attaching DL TBF: TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) +MS(TA-220:MSCLS-11-11): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(TA-220:MSCLS-11-11:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-0:STATE-NEW:EGPRS) setting EGPRS DL window size to 64, base(64) slots(1) ws_pdch(0) ws(64) Modifying MS object, TLLI = 0xffffffff, TA 220 -> 0 @@ -9098,6 +9348,7 @@ PDCH(bts=0,trx=0,ts=7) FN=52 ------------------------- RX : Uplink Control Block ------------------------- Creating MS object Modifying MS object, UL TLLI: 0xffffffff -> 0xffeeddcc, not yet confirmed +MS(TLLI-0xffeeddcc:TA-220:MSCLS-0-0): + rcv_resource_request: now used by 1 (rcv_resource_request) PDCH(bts=0,trx=0,ts=7) FN=52 PKT RESOURCE REQ: MS requests UL TBF throguh SBA Modifying MS object, TLLI = 0xffeeddcc, TA 220 -> 7 Modifying MS object, TLLI = 0xffeeddcc, MS class 0 -> 11 @@ -9123,6 +9374,8 @@ TBF(UL:TFI-0-0-0:STATE-NEW:EGPRS:TLLI-0xffeeddcc) Setting Control TS PDCH(bts=0,trx=0,ts=7) TBF(UL:TFI-0-0-0:STATE-NEW:EGPRS:TLLI-0xffeeddcc) Allocated: trx = 0, ul_slots = 80, dl_slots = 00 MS(TLLI-0xffeeddcc:TA-7:MSCLS-11-11) Attaching UL TBF: TBF(UL:TFI-0-0-0:STATE-NEW:EGPRS:TLLI-0xffeeddcc) +MS(TLLI-0xffeeddcc:TA-7:MSCLS-11-11): + ms_attach_ul_tbf: now used by 2 (rcv_resource_request,ms_attach_ul_tbf) +MS(TLLI-0xffeeddcc:TA-7:MSCLS-11-11:UL): - ms_attach_ul_tbf: now used by 1 (rcv_resource_request) TBF(UL:TFI-0-0-0:STATE-NEW:EGPRS:TLLI-0xffeeddcc) setting EGPRS UL window size to 64, base(64) slots(1) ws_pdch(0) ws(64) UL_TBF(UL:TFI-0-0-0:STATE-NEW:EGPRS:TLLI-0xffeeddcc){NEW}: Received Event ASSIGN_ADD_PACCH @@ -9132,6 +9385,7 @@ TBF(UL:TFI-0-0-0:STATE-ASSIGN:EGPRS:TLLI-0xffeeddcc) Change control TS PDCH(bts=0,trx=0,ts=7) -> PDCH(bts=0,trx=0,ts=7) until assignment is complete. UL_ASS_TBF(UL:TFI-0-0-0:STATE-NEW:EGPRS:TLLI-0xffeeddcc){NONE}: Received Event SCHED_ASS UL_ASS_TBF(UL:TFI-0-0-0:STATE-NEW:EGPRS:TLLI-0xffeeddcc){NONE}: state_chg to SEND_ASS +MS(TLLI-0xffeeddcc:TA-7:MSCLS-11-11:UL): - rcv_resource_request: now used by 0 (-) UL_ASS_TBF(UL:TFI-0-0-0:STATE-NEW:EGPRS:TLLI-0xffeeddcc){SEND_ASS}: Received Event CREATE_RLCMAC_MSG PDCH(bts=0,trx=0,ts=7) POLL scheduled at FN 2654218 + 13 = 2654231 TBF(UL:TFI-0-0-0:STATE-ASSIGN:EGPRS:TLLI-0xffeeddcc) start Packet Uplink Assignment (PACCH) @@ -9145,6 +9399,7 @@ PDCH(bts=0,trx=0,ts=7) FN=56 ------------------------- RX : Uplink Control Block ------------------------- Creating MS object Modifying MS object, UL TLLI: 0xffffffff -> 0xffeeddcd, not yet confirmed +MS(TLLI-0xffeeddcd:TA-220:MSCLS-0-0): + rcv_resource_request: now used by 1 (rcv_resource_request) PDCH(bts=0,trx=0,ts=7) FN=56 PKT RESOURCE REQ: MS requests UL TBF throguh SBA Modifying MS object, TLLI = 0xffeeddcd, TA 220 -> 7 Modifying MS object, TLLI = 0xffeeddcd, MS class 0 -> 11 @@ -9170,6 +9425,8 @@ TBF(UL:TFI-0-0-1:STATE-NEW:EGPRS:TLLI-0xffeeddcd) Setting Control TS PDCH(bts=0,trx=0,ts=7) TBF(UL:TFI-0-0-1:STATE-NEW:EGPRS:TLLI-0xffeeddcd) Allocated: trx = 0, ul_slots = 80, dl_slots = 00 MS(TLLI-0xffeeddcd:TA-7:MSCLS-11-11) Attaching UL TBF: TBF(UL:TFI-0-0-1:STATE-NEW:EGPRS:TLLI-0xffeeddcd) +MS(TLLI-0xffeeddcd:TA-7:MSCLS-11-11): + ms_attach_ul_tbf: now used by 2 (rcv_resource_request,ms_attach_ul_tbf) +MS(TLLI-0xffeeddcd:TA-7:MSCLS-11-11:UL): - ms_attach_ul_tbf: now used by 1 (rcv_resource_request) TBF(UL:TFI-0-0-1:STATE-NEW:EGPRS:TLLI-0xffeeddcd) setting EGPRS UL window size to 64, base(64) slots(1) ws_pdch(0) ws(64) UL_TBF(UL:TFI-0-0-1:STATE-NEW:EGPRS:TLLI-0xffeeddcd){NEW}: Received Event ASSIGN_ADD_PACCH @@ -9179,6 +9436,7 @@ TBF(UL:TFI-0-0-1:STATE-ASSIGN:EGPRS:TLLI-0xffeeddcd) Change control TS PDCH(bts=0,trx=0,ts=7) -> PDCH(bts=0,trx=0,ts=7) until assignment is complete. UL_ASS_TBF(UL:TFI-0-0-1:STATE-NEW:EGPRS:TLLI-0xffeeddcd){NONE}: Received Event SCHED_ASS UL_ASS_TBF(UL:TFI-0-0-1:STATE-NEW:EGPRS:TLLI-0xffeeddcd){NONE}: state_chg to SEND_ASS +MS(TLLI-0xffeeddcd:TA-7:MSCLS-11-11:UL): - rcv_resource_request: now used by 0 (-) PDCH(bts=0,trx=0,ts=7) Expiring FN=56 but previous FN=2654231 is still reserved! PDCH(bts=0,trx=0,ts=7) Timeout for registered POLL (FN=2654231, reason=UL_ASS): TBF(UL:TFI-0-0-0:STATE-ASSIGN:EGPRS:TLLI-0xffeeddcc) TBF(UL:TFI-0-0-0:STATE-ASSIGN:EGPRS:TLLI-0xffeeddcc) poll timeout for FN=2654231, TS=7 (curr FN 56) @@ -9199,6 +9457,7 @@ PDCH(bts=0,trx=0,ts=7) FN=60 ------------------------- RX : Uplink Control Block ------------------------- Creating MS object Modifying MS object, UL TLLI: 0xffffffff -> 0xffeeddce, not yet confirmed +MS(TLLI-0xffeeddce:TA-220:MSCLS-0-0): + rcv_resource_request: now used by 1 (rcv_resource_request) PDCH(bts=0,trx=0,ts=7) FN=60 PKT RESOURCE REQ: MS requests UL TBF throguh SBA Modifying MS object, TLLI = 0xffeeddce, TA 220 -> 7 Modifying MS object, TLLI = 0xffeeddce, MS class 0 -> 11 @@ -9224,6 +9483,8 @@ TBF(UL:TFI-0-0-2:STATE-NEW:EGPRS:TLLI-0xffeeddce) Setting Control TS PDCH(bts=0,trx=0,ts=7) TBF(UL:TFI-0-0-2:STATE-NEW:EGPRS:TLLI-0xffeeddce) Allocated: trx = 0, ul_slots = 80, dl_slots = 00 MS(TLLI-0xffeeddce:TA-7:MSCLS-11-11) Attaching UL TBF: TBF(UL:TFI-0-0-2:STATE-NEW:EGPRS:TLLI-0xffeeddce) +MS(TLLI-0xffeeddce:TA-7:MSCLS-11-11): + ms_attach_ul_tbf: now used by 2 (rcv_resource_request,ms_attach_ul_tbf) +MS(TLLI-0xffeeddce:TA-7:MSCLS-11-11:UL): - ms_attach_ul_tbf: now used by 1 (rcv_resource_request) TBF(UL:TFI-0-0-2:STATE-NEW:EGPRS:TLLI-0xffeeddce) setting EGPRS UL window size to 64, base(64) slots(1) ws_pdch(0) ws(64) UL_TBF(UL:TFI-0-0-2:STATE-NEW:EGPRS:TLLI-0xffeeddce){NEW}: Received Event ASSIGN_ADD_PACCH @@ -9233,6 +9494,7 @@ TBF(UL:TFI-0-0-2:STATE-ASSIGN:EGPRS:TLLI-0xffeeddce) Change control TS PDCH(bts=0,trx=0,ts=7) -> PDCH(bts=0,trx=0,ts=7) until assignment is complete. UL_ASS_TBF(UL:TFI-0-0-2:STATE-NEW:EGPRS:TLLI-0xffeeddce){NONE}: Received Event SCHED_ASS UL_ASS_TBF(UL:TFI-0-0-2:STATE-NEW:EGPRS:TLLI-0xffeeddce){NONE}: state_chg to SEND_ASS +MS(TLLI-0xffeeddce:TA-7:MSCLS-11-11:UL): - rcv_resource_request: now used by 0 (-) PDCH(bts=0,trx=0,ts=7) Expiring FN=60 but previous FN=2654231 is still reserved! PDCH(bts=0,trx=0,ts=7) Timeout for registered POLL (FN=2654231, reason=UL_ASS): TBF(UL:TFI-0-0-1:STATE-ASSIGN:EGPRS:TLLI-0xffeeddcd) TBF(UL:TFI-0-0-1:STATE-ASSIGN:EGPRS:TLLI-0xffeeddcd) poll timeout for FN=2654231, TS=7 (curr FN 60) @@ -9253,6 +9515,7 @@ PDCH(bts=0,trx=0,ts=7) FN=65 ------------------------- RX : Uplink Control Block ------------------------- Creating MS object Modifying MS object, UL TLLI: 0xffffffff -> 0xffeeddcf, not yet confirmed +MS(TLLI-0xffeeddcf:TA-220:MSCLS-0-0): + rcv_resource_request: now used by 1 (rcv_resource_request) PDCH(bts=0,trx=0,ts=7) FN=65 PKT RESOURCE REQ: MS requests UL TBF throguh SBA Modifying MS object, TLLI = 0xffeeddcf, TA 220 -> 7 Modifying MS object, TLLI = 0xffeeddcf, MS class 0 -> 11 @@ -9278,6 +9541,8 @@ TBF(UL:TFI-0-0-3:STATE-NEW:EGPRS:TLLI-0xffeeddcf) Setting Control TS PDCH(bts=0,trx=0,ts=7) TBF(UL:TFI-0-0-3:STATE-NEW:EGPRS:TLLI-0xffeeddcf) Allocated: trx = 0, ul_slots = 80, dl_slots = 00 MS(TLLI-0xffeeddcf:TA-7:MSCLS-11-11) Attaching UL TBF: TBF(UL:TFI-0-0-3:STATE-NEW:EGPRS:TLLI-0xffeeddcf) +MS(TLLI-0xffeeddcf:TA-7:MSCLS-11-11): + ms_attach_ul_tbf: now used by 2 (rcv_resource_request,ms_attach_ul_tbf) +MS(TLLI-0xffeeddcf:TA-7:MSCLS-11-11:UL): - ms_attach_ul_tbf: now used by 1 (rcv_resource_request) TBF(UL:TFI-0-0-3:STATE-NEW:EGPRS:TLLI-0xffeeddcf) setting EGPRS UL window size to 64, base(64) slots(1) ws_pdch(0) ws(64) UL_TBF(UL:TFI-0-0-3:STATE-NEW:EGPRS:TLLI-0xffeeddcf){NEW}: Received Event ASSIGN_ADD_PACCH @@ -9287,6 +9552,7 @@ TBF(UL:TFI-0-0-3:STATE-ASSIGN:EGPRS:TLLI-0xffeeddcf) Change control TS PDCH(bts=0,trx=0,ts=7) -> PDCH(bts=0,trx=0,ts=7) until assignment is complete. UL_ASS_TBF(UL:TFI-0-0-3:STATE-NEW:EGPRS:TLLI-0xffeeddcf){NONE}: Received Event SCHED_ASS UL_ASS_TBF(UL:TFI-0-0-3:STATE-NEW:EGPRS:TLLI-0xffeeddcf){NONE}: state_chg to SEND_ASS +MS(TLLI-0xffeeddcf:TA-7:MSCLS-11-11:UL): - rcv_resource_request: now used by 0 (-) PDCH(bts=0,trx=0,ts=7) Expiring FN=65 but previous FN=2654231 is still reserved! PDCH(bts=0,trx=0,ts=7) Timeout for registered POLL (FN=2654231, reason=UL_ASS): TBF(UL:TFI-0-0-2:STATE-ASSIGN:EGPRS:TLLI-0xffeeddce) TBF(UL:TFI-0-0-2:STATE-ASSIGN:EGPRS:TLLI-0xffeeddce) poll timeout for FN=2654231, TS=7 (curr FN 65) @@ -9307,6 +9573,7 @@ PDCH(bts=0,trx=0,ts=7) FN=69 ------------------------- RX : Uplink Control Block ------------------------- Creating MS object Modifying MS object, UL TLLI: 0xffffffff -> 0xffeeddd0, not yet confirmed +MS(TLLI-0xffeeddd0:TA-220:MSCLS-0-0): + rcv_resource_request: now used by 1 (rcv_resource_request) PDCH(bts=0,trx=0,ts=7) FN=69 PKT RESOURCE REQ: MS requests UL TBF throguh SBA Modifying MS object, TLLI = 0xffeeddd0, TA 220 -> 7 Modifying MS object, TLLI = 0xffeeddd0, MS class 0 -> 11 @@ -9332,6 +9599,8 @@ TBF(UL:TFI-0-0-4:STATE-NEW:EGPRS:TLLI-0xffeeddd0) Setting Control TS PDCH(bts=0,trx=0,ts=7) TBF(UL:TFI-0-0-4:STATE-NEW:EGPRS:TLLI-0xffeeddd0) Allocated: trx = 0, ul_slots = 80, dl_slots = 00 MS(TLLI-0xffeeddd0:TA-7:MSCLS-11-11) Attaching UL TBF: TBF(UL:TFI-0-0-4:STATE-NEW:EGPRS:TLLI-0xffeeddd0) +MS(TLLI-0xffeeddd0:TA-7:MSCLS-11-11): + ms_attach_ul_tbf: now used by 2 (rcv_resource_request,ms_attach_ul_tbf) +MS(TLLI-0xffeeddd0:TA-7:MSCLS-11-11:UL): - ms_attach_ul_tbf: now used by 1 (rcv_resource_request) TBF(UL:TFI-0-0-4:STATE-NEW:EGPRS:TLLI-0xffeeddd0) setting EGPRS UL window size to 64, base(64) slots(1) ws_pdch(0) ws(64) UL_TBF(UL:TFI-0-0-4:STATE-NEW:EGPRS:TLLI-0xffeeddd0){NEW}: Received Event ASSIGN_ADD_PACCH @@ -9341,6 +9610,7 @@ TBF(UL:TFI-0-0-4:STATE-ASSIGN:EGPRS:TLLI-0xffeeddd0) Change control TS PDCH(bts=0,trx=0,ts=7) -> PDCH(bts=0,trx=0,ts=7) until assignment is complete. UL_ASS_TBF(UL:TFI-0-0-4:STATE-NEW:EGPRS:TLLI-0xffeeddd0){NONE}: Received Event SCHED_ASS UL_ASS_TBF(UL:TFI-0-0-4:STATE-NEW:EGPRS:TLLI-0xffeeddd0){NONE}: state_chg to SEND_ASS +MS(TLLI-0xffeeddd0:TA-7:MSCLS-11-11:UL): - rcv_resource_request: now used by 0 (-) PDCH(bts=0,trx=0,ts=7) Expiring FN=69 but previous FN=2654231 is still reserved! PDCH(bts=0,trx=0,ts=7) Timeout for registered POLL (FN=2654231, reason=UL_ASS): TBF(UL:TFI-0-0-3:STATE-ASSIGN:EGPRS:TLLI-0xffeeddcf) TBF(UL:TFI-0-0-3:STATE-ASSIGN:EGPRS:TLLI-0xffeeddcf) poll timeout for FN=2654231, TS=7 (curr FN 69) @@ -9361,6 +9631,7 @@ PDCH(bts=0,trx=0,ts=7) FN=73 ------------------------- RX : Uplink Control Block ------------------------- Creating MS object Modifying MS object, UL TLLI: 0xffffffff -> 0xffeeddd1, not yet confirmed +MS(TLLI-0xffeeddd1:TA-220:MSCLS-0-0): + rcv_resource_request: now used by 1 (rcv_resource_request) PDCH(bts=0,trx=0,ts=7) FN=73 PKT RESOURCE REQ: MS requests UL TBF throguh SBA Modifying MS object, TLLI = 0xffeeddd1, TA 220 -> 7 Modifying MS object, TLLI = 0xffeeddd1, MS class 0 -> 11 @@ -9386,6 +9657,8 @@ TBF(UL:TFI-0-0-5:STATE-NEW:EGPRS:TLLI-0xffeeddd1) Setting Control TS PDCH(bts=0,trx=0,ts=7) TBF(UL:TFI-0-0-5:STATE-NEW:EGPRS:TLLI-0xffeeddd1) Allocated: trx = 0, ul_slots = 80, dl_slots = 00 MS(TLLI-0xffeeddd1:TA-7:MSCLS-11-11) Attaching UL TBF: TBF(UL:TFI-0-0-5:STATE-NEW:EGPRS:TLLI-0xffeeddd1) +MS(TLLI-0xffeeddd1:TA-7:MSCLS-11-11): + ms_attach_ul_tbf: now used by 2 (rcv_resource_request,ms_attach_ul_tbf) +MS(TLLI-0xffeeddd1:TA-7:MSCLS-11-11:UL): - ms_attach_ul_tbf: now used by 1 (rcv_resource_request) TBF(UL:TFI-0-0-5:STATE-NEW:EGPRS:TLLI-0xffeeddd1) setting EGPRS UL window size to 64, base(64) slots(1) ws_pdch(0) ws(64) UL_TBF(UL:TFI-0-0-5:STATE-NEW:EGPRS:TLLI-0xffeeddd1){NEW}: Received Event ASSIGN_ADD_PACCH @@ -9395,6 +9668,7 @@ TBF(UL:TFI-0-0-5:STATE-ASSIGN:EGPRS:TLLI-0xffeeddd1) Change control TS PDCH(bts=0,trx=0,ts=7) -> PDCH(bts=0,trx=0,ts=7) until assignment is complete. UL_ASS_TBF(UL:TFI-0-0-5:STATE-NEW:EGPRS:TLLI-0xffeeddd1){NONE}: Received Event SCHED_ASS UL_ASS_TBF(UL:TFI-0-0-5:STATE-NEW:EGPRS:TLLI-0xffeeddd1){NONE}: state_chg to SEND_ASS +MS(TLLI-0xffeeddd1:TA-7:MSCLS-11-11:UL): - rcv_resource_request: now used by 0 (-) PDCH(bts=0,trx=0,ts=7) Expiring FN=73 but previous FN=2654231 is still reserved! PDCH(bts=0,trx=0,ts=7) Timeout for registered POLL (FN=2654231, reason=UL_ASS): TBF(UL:TFI-0-0-4:STATE-ASSIGN:EGPRS:TLLI-0xffeeddd0) TBF(UL:TFI-0-0-4:STATE-ASSIGN:EGPRS:TLLI-0xffeeddd0) poll timeout for FN=2654231, TS=7 (curr FN 73) @@ -9415,6 +9689,7 @@ PDCH(bts=0,trx=0,ts=7) FN=78 ------------------------- RX : Uplink Control Block ------------------------- Creating MS object Modifying MS object, UL TLLI: 0xffffffff -> 0xffeeddd2, not yet confirmed +MS(TLLI-0xffeeddd2:TA-220:MSCLS-0-0): + rcv_resource_request: now used by 1 (rcv_resource_request) PDCH(bts=0,trx=0,ts=7) FN=78 PKT RESOURCE REQ: MS requests UL TBF throguh SBA Modifying MS object, TLLI = 0xffeeddd2, TA 220 -> 7 Modifying MS object, TLLI = 0xffeeddd2, MS class 0 -> 11 @@ -9440,6 +9715,8 @@ TBF(UL:TFI-0-0-6:STATE-NEW:EGPRS:TLLI-0xffeeddd2) Setting Control TS PDCH(bts=0,trx=0,ts=7) TBF(UL:TFI-0-0-6:STATE-NEW:EGPRS:TLLI-0xffeeddd2) Allocated: trx = 0, ul_slots = 80, dl_slots = 00 MS(TLLI-0xffeeddd2:TA-7:MSCLS-11-11) Attaching UL TBF: TBF(UL:TFI-0-0-6:STATE-NEW:EGPRS:TLLI-0xffeeddd2) +MS(TLLI-0xffeeddd2:TA-7:MSCLS-11-11): + ms_attach_ul_tbf: now used by 2 (rcv_resource_request,ms_attach_ul_tbf) +MS(TLLI-0xffeeddd2:TA-7:MSCLS-11-11:UL): - ms_attach_ul_tbf: now used by 1 (rcv_resource_request) TBF(UL:TFI-0-0-6:STATE-NEW:EGPRS:TLLI-0xffeeddd2) setting EGPRS UL window size to 64, base(64) slots(1) ws_pdch(0) ws(64) UL_TBF(UL:TFI-0-0-6:STATE-NEW:EGPRS:TLLI-0xffeeddd2){NEW}: Received Event ASSIGN_ADD_PACCH @@ -9449,6 +9726,7 @@ TBF(UL:TFI-0-0-6:STATE-ASSIGN:EGPRS:TLLI-0xffeeddd2) Change control TS PDCH(bts=0,trx=0,ts=7) -> PDCH(bts=0,trx=0,ts=7) until assignment is complete. UL_ASS_TBF(UL:TFI-0-0-6:STATE-NEW:EGPRS:TLLI-0xffeeddd2){NONE}: Received Event SCHED_ASS UL_ASS_TBF(UL:TFI-0-0-6:STATE-NEW:EGPRS:TLLI-0xffeeddd2){NONE}: state_chg to SEND_ASS +MS(TLLI-0xffeeddd2:TA-7:MSCLS-11-11:UL): - rcv_resource_request: now used by 0 (-) PDCH(bts=0,trx=0,ts=7) Expiring FN=78 but previous FN=2654231 is still reserved! PDCH(bts=0,trx=0,ts=7) Timeout for registered POLL (FN=2654231, reason=UL_ASS): TBF(UL:TFI-0-0-5:STATE-ASSIGN:EGPRS:TLLI-0xffeeddd1) TBF(UL:TFI-0-0-5:STATE-ASSIGN:EGPRS:TLLI-0xffeeddd1) poll timeout for FN=2654231, TS=7 (curr FN 78) @@ -9469,6 +9747,7 @@ PDCH(bts=0,trx=0,ts=7) FN=82 ------------------------- RX : Uplink Control Block ------------------------- Creating MS object Modifying MS object, UL TLLI: 0xffffffff -> 0xffeeddd3, not yet confirmed +MS(TLLI-0xffeeddd3:TA-220:MSCLS-0-0): + rcv_resource_request: now used by 1 (rcv_resource_request) PDCH(bts=0,trx=0,ts=7) FN=82 PKT RESOURCE REQ: MS requests UL TBF throguh SBA Modifying MS object, TLLI = 0xffeeddd3, TA 220 -> 7 Modifying MS object, TLLI = 0xffeeddd3, MS class 0 -> 11 @@ -9503,12 +9782,15 @@ UL_ACK_TBF{NONE}: Allocated TBF(UL:TFI-0-0--1:STATE-NEW:GPRS:TLLI-0xffeeddd3) Setting Control TS PDCH(bts=0,trx=0,ts=7) MS(TLLI-0xffeeddd3:TA-7:MSCLS-11-11) Attaching UL TBF: TBF(UL:TFI-0-0--1:STATE-NEW:GPRS:TLLI-0xffeeddd3) +MS(TLLI-0xffeeddd3:TA-7:MSCLS-11-11): + ms_attach_ul_tbf: now used by 2 (rcv_resource_request,ms_attach_ul_tbf) +MS(TLLI-0xffeeddd3:TA-7:MSCLS-11-11:UL): - ms_attach_ul_tbf: now used by 1 (rcv_resource_request) UL_TBF(UL:TFI-0-0--1:STATE-NEW:GPRS:TLLI-0xffeeddd3){NEW}: Received Event ASSIGN_ADD_PACCH TBF(UL:TFI-0-0--1:STATE-NEW:GPRS:TLLI-0xffeeddd3) set ass. type PACCH [prev CCCH:0, PACCH:0] UL_TBF(UL:TFI-0-0--1:STATE-NEW:GPRS:TLLI-0xffeeddd3){NEW}: state_chg to ASSIGN TBF(UL:TFI-0-0--1:STATE-ASSIGN:GPRS:TLLI-0xffeeddd3) Starting timer X2001 [assignment (PACCH)] with 2 sec. 0 microsec UL_ASS_TBF(UL:TFI-0-0--1:STATE-NEW:GPRS:TLLI-0xffeeddd3){NONE}: Received Event SCHED_ASS_REJ UL_ASS_TBF(UL:TFI-0-0--1:STATE-NEW:GPRS:TLLI-0xffeeddd3){NONE}: state_chg to SEND_ASS_REJ +MS(TLLI-0xffeeddd3:TA-7:MSCLS-11-11:UL): - rcv_resource_request: now used by 0 (-) PDCH(bts=0,trx=0,ts=7) Expiring FN=82 but previous FN=2654231 is still reserved! PDCH(bts=0,trx=0,ts=7) Timeout for registered POLL (FN=2654231, reason=UL_ASS): TBF(UL:TFI-0-0-6:STATE-ASSIGN:EGPRS:TLLI-0xffeeddd2) TBF(UL:TFI-0-0-6:STATE-ASSIGN:EGPRS:TLLI-0xffeeddd2) poll timeout for FN=2654231, TS=7 (curr FN 82) @@ -9552,6 +9834,8 @@ UL_ACK_TBF{NONE}: Allocated TBF(UL:TFI-0-0--1:STATE-NEW:GPRS:TLLI-0xffeeddcc) Setting Control TS PDCH(bts=0,trx=0,ts=7) MS(TLLI-0xffeeddcc:TA-220:MSCLS-0-0) Attaching UL TBF: TBF(UL:TFI-0-0--1:STATE-NEW:GPRS:TLLI-0xffeeddcc) +MS(TLLI-0xffeeddcc:TA-220:MSCLS-0-0): + ms_attach_ul_tbf: now used by 1 (ms_attach_ul_tbf) +MS(TLLI-0xffeeddcc:TA-220:MSCLS-0-0:UL): - ms_attach_ul_tbf: now used by 0 (-) UL_TBF(UL:TFI-0-0--1:STATE-NEW:GPRS:TLLI-0xffeeddcc){NEW}: Received Event ASSIGN_ADD_PACCH TBF(UL:TFI-0-0--1:STATE-NEW:GPRS:TLLI-0xffeeddcc) set ass. type PACCH [prev CCCH:0, PACCH:0] UL_TBF(UL:TFI-0-0--1:STATE-NEW:GPRS:TLLI-0xffeeddcc){NEW}: state_chg to ASSIGN @@ -9607,6 +9891,8 @@ TBF(DL:TFI-0-1-0:STATE-NEW:GPRS:IMSI-001001000000001:TLLI-0xa3c2f953) Setting Control TS PDCH(bts=0,trx=1,ts=5) TBF(DL:TFI-0-1-0:STATE-NEW:GPRS:IMSI-001001000000001:TLLI-0xa3c2f953) Allocated: trx = 1, ul_slots = 20, dl_slots = 20 MS(IMSI-001001000000001:TLLI-0xa3c2f953:TA-220:MSCLS-11-0) Attaching DL TBF: TBF(DL:TFI-0-1-0:STATE-NEW:GPRS:IMSI-001001000000001:TLLI-0xa3c2f953) +MS(IMSI-001001000000001:TLLI-0xa3c2f953:TA-220:MSCLS-11-0): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-001001000000001:TLLI-0xa3c2f953:TA-220:MSCLS-11-0:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-1-0:STATE-NEW:GPRS:IMSI-001001000000001:TLLI-0xa3c2f953) [DOWNLINK] START (PCH) TBF(DL:TFI-0-1-0:STATE-NEW:GPRS:IMSI-001001000000001:TLLI-0xa3c2f953) Send downlink assignment on PCH, no TBF exist (IMSI=001001000000001) DL_TBF(DL:TFI-0-1-0:STATE-NEW:GPRS:IMSI-001001000000001:TLLI-0xa3c2f953){NEW}: Received Event ASSIGN_ADD_CCCH @@ -9643,7 +9929,10 @@ TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0xecc1f953) Setting Control TS PDCH(bts=0,trx=0,ts=2) TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0xecc1f953) Allocated: trx = 0, ul_slots = 04, dl_slots = 00 MS(TLLI-0xecc1f953:TA-220:MSCLS-0-0) Attaching UL TBF: TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:TLLI-0xecc1f953) +MS(TLLI-0xecc1f953:TA-220:MSCLS-0-0): + ms_attach_ul_tbf: now used by 1 (ms_attach_ul_tbf) +MS(TLLI-0xecc1f953:TA-220:MSCLS-0-0:UL): - ms_attach_ul_tbf: now used by 0 (-) There is a new MS object for the same MS: (0xa3c2f953, '001001000000001') -> (0xecc1f953, '') +MS(IMSI-001001000000001:TLLI-0xa3c2f953:TA-220:MSCLS-11-0:DL): + ms_merge_and_clear_ms: now used by 1 (ms_merge_and_clear_ms) MS(TLLI-0xecc1f953:TA-220:MSCLS-0-0:UL) Merge MS: MS(IMSI-001001000000001:TLLI-0xa3c2f953:TA-220:MSCLS-11-0:DL) Modifying MS object, TLLI = 0xecc1f953, MS class 0 -> 11 TBF(DL:TFI-0-1-0:STATE-ASSIGN:GPRS:IMSI-001001000000001:TLLI-0xa3c2f953) free @@ -9654,6 +9943,7 @@ UL_ASS_TBF(DL:TFI-0-1-0:STATE-NEW:GPRS:IMSI-001001000000001:TLLI-0xa3c2f953){NONE}: Deallocated DL_ASS_TBF(DL:TFI-0-1-0:STATE-NEW:GPRS:IMSI-001001000000001:TLLI-0xa3c2f953){NONE}: Deallocated MS(IMSI-001001000000001:TLLI-0xa3c2f953:TA-220:MSCLS-11-0) Clearing MS object +MS(TA-220:MSCLS-11-0): - ms_merge_and_clear_ms: now used by 0 (-) MS(TA-220:MSCLS-11-0) Destroying MS object Modifying MS object, TLLI: 0xecc1f953 confirmed MS(IMSI-001001000000001:TLLI-0xecc1f953:TA-220:MSCLS-11-0:UL) appending 19 bytes to DL LLC queue @@ -9675,6 +9965,8 @@ TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-001001000000001:TLLI-0xecc1f953) Setting Control TS PDCH(bts=0,trx=0,ts=2) TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-001001000000001:TLLI-0xecc1f953) Allocated: trx = 0, ul_slots = 04, dl_slots = 0e MS(IMSI-001001000000001:TLLI-0xecc1f953:TA-220:MSCLS-11-0:UL) Attaching DL TBF: TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-001001000000001:TLLI-0xecc1f953) +MS(IMSI-001001000000001:TLLI-0xecc1f953:TA-220:MSCLS-11-0:UL): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(IMSI-001001000000001:TLLI-0xecc1f953:TA-220:MSCLS-11-0:UL:DL): - ms_attach_dl_tbf: now used by 0 (-) TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-001001000000001:TLLI-0xecc1f953) [DOWNLINK] START (PACCH) TBF(DL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-001001000000001:TLLI-0xecc1f953) Send downlink assignment on PACCH, because TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-001001000000001:TLLI-0xecc1f953) exists DL_ASS_TBF(UL:TFI-0-0-0:STATE-NEW:GPRS:IMSI-001001000000001:TLLI-0xecc1f953){NONE}: Received Event SCHED_ASS diff --git a/tests/ulc/PdchUlcTest.err b/tests/ulc/PdchUlcTest.err index 41aa9b4..b402517 100644 --- a/tests/ulc/PdchUlcTest.err +++ b/tests/ulc/PdchUlcTest.err @@ -18,6 +18,8 @@ Creating MS object The MS object cannot fully confirm an unexpected TLLI: 0x12345678, partly confirmed MS(TLLI-0x12345678:TA-220:MSCLS-0-0) Attaching DL TBF: TBF(DL:TFI-0-0--1:STATE-NEW:GPRS:TLLI-0x12345678) +MS(TLLI-0x12345678:TA-220:MSCLS-0-0): + ms_attach_dl_tbf: now used by 1 (ms_attach_dl_tbf) +MS(TLLI-0x12345678:TA-220:MSCLS-0-0:DL): - ms_attach_dl_tbf: now used by 0 (-) PDCH(bts=0,trx=0,ts=0) Reserving FN 2715608 for type POLL PDCH(bts=0,trx=0,ts=0) Reserving FN 2715613 for type POLL PDCH(bts=0,trx=0,ts=0) Reserving FN 2715617 for type POLL