<p>pespin has uploaded this change for <strong>review</strong>.</p><p><a href="https://gerrit.osmocom.org/c/osmo-pcu/+/25092">View Change</a></p><pre style="font-family: monospace,monospace; white-space: pre-wrap;">Move timer X2001 to tbf_fsm<br><br>The side effect is that the timer is enabled for other scenarios where a<br>PACCH assignment happens, like an Assignment Reject or Ul Assignment<br>(that's why there's more lines showing up now in TbfTest.err).<br><br>Change-Id: Ib8ab2f7397ad05c6fcd5dd74af55a1e2c56e1463<br>---<br>M src/tbf.cpp<br>M src/tbf_dl.cpp<br>M src/tbf_fsm.c<br>M tests/tbf/TbfTest.cpp<br>M tests/tbf/TbfTest.err<br>5 files changed, 68 insertions(+), 37 deletions(-)<br><br></pre><pre style="font-family: monospace,monospace; white-space: pre-wrap;">git pull ssh://gerrit.osmocom.org:29418/osmo-pcu refs/changes/92/25092/1</pre><pre style="font-family: monospace,monospace; white-space: pre-wrap;"><span>diff --git a/src/tbf.cpp b/src/tbf.cpp</span><br><span>index 2407fc2..b9c62fb 100644</span><br><span>--- a/src/tbf.cpp</span><br><span>+++ b/src/tbf.cpp</span><br><span>@@ -741,16 +741,6 @@</span><br><span> </span><br><span> LOGPTBF(this, LOGL_DEBUG, "timer 0 expired. cur_fn=%d\n", current_fn);</span><br><span> </span><br><span style="color: hsl(0, 100%, 40%);">- /* PACCH assignment timeout (see timer X2001) */</span><br><span style="color: hsl(0, 100%, 40%);">- if ((state_fsm.state_flags & (1 << GPRS_RLCMAC_FLAG_PACCH))) {</span><br><span style="color: hsl(0, 100%, 40%);">- if (state_is(TBF_ST_ASSIGN)) {</span><br><span style="color: hsl(0, 100%, 40%);">- LOGPTBF(this, LOGL_NOTICE, "releasing due to PACCH assignment timeout.\n");</span><br><span style="color: hsl(0, 100%, 40%);">- tbf_free(this);</span><br><span style="color: hsl(0, 100%, 40%);">- return;</span><br><span style="color: hsl(0, 100%, 40%);">- } else</span><br><span style="color: hsl(0, 100%, 40%);">- LOGPTBF(this, LOGL_ERROR, "Error: TBF is not in assign state\n");</span><br><span style="color: hsl(0, 100%, 40%);">- }</span><br><span style="color: hsl(0, 100%, 40%);">-</span><br><span> /* Finish waiting after IMM.ASS confirm timer for CCCH assignment (see timer X2002) */</span><br><span> if ((state_fsm.state_flags & (1 << GPRS_RLCMAC_FLAG_CCCH))) {</span><br><span> gprs_rlcmac_dl_tbf *dl_tbf = as_dl_tbf(this);</span><br><span>diff --git a/src/tbf_dl.cpp b/src/tbf_dl.cpp</span><br><span>index 01cd173..ea7f18e 100644</span><br><span>--- a/src/tbf_dl.cpp</span><br><span>+++ b/src/tbf_dl.cpp</span><br><span>@@ -611,9 +611,6 @@</span><br><span> </span><br><span> /* change state */</span><br><span> osmo_fsm_inst_dispatch(this->state_fsm.fi, TBF_EV_ASSIGN_ADD_PACCH, NULL);</span><br><span style="color: hsl(0, 100%, 40%);">-</span><br><span style="color: hsl(0, 100%, 40%);">- /* Start timer, expiry in gprs_rlcmac_tbf::handle_timeout tbf_free()s the TBF */</span><br><span style="color: hsl(0, 100%, 40%);">- T_START(this, T0, -2001, "assignment (PACCH)", true);</span><br><span> } else {</span><br><span> LOGPTBFDL(this, LOGL_DEBUG, "Send dowlink assignment on PCH, no TBF exist (IMSI=%s)\n",</span><br><span> imsi());</span><br><span>diff --git a/src/tbf_fsm.c b/src/tbf_fsm.c</span><br><span>index a57dd08..0dbf04c 100644</span><br><span>--- a/src/tbf_fsm.c</span><br><span>+++ b/src/tbf_fsm.c</span><br><span>@@ -113,6 +113,32 @@</span><br><span> }</span><br><span> }</span><br><span> </span><br><span style="color: hsl(120, 100%, 40%);">+static void st_assign_on_enter(struct osmo_fsm_inst *fi, uint32_t prev_state)</span><br><span style="color: hsl(120, 100%, 40%);">+{</span><br><span style="color: hsl(120, 100%, 40%);">+ struct tbf_fsm_ctx *ctx = (struct tbf_fsm_ctx *)fi->priv;</span><br><span style="color: hsl(120, 100%, 40%);">+ unsigned long val;</span><br><span style="color: hsl(120, 100%, 40%);">+ unsigned int sec, micro;</span><br><span style="color: hsl(120, 100%, 40%);">+</span><br><span style="color: hsl(120, 100%, 40%);">+ /* If assignment for this TBF is happening on PACCH, that means the</span><br><span style="color: hsl(120, 100%, 40%);">+ * actual Assignment procedure (tx/rx) is happening on another TBF (eg</span><br><span style="color: hsl(120, 100%, 40%);">+ * Ul TBF vs DL TBF). Hence we add a security timer here to free it in</span><br><span style="color: hsl(120, 100%, 40%);">+ * case the other TBF doesn't succeed in informing (assigning) the MS</span><br><span style="color: hsl(120, 100%, 40%);">+ * about this TBF, or simply because the scheduler takes too long to</span><br><span style="color: hsl(120, 100%, 40%);">+ * schedule it. This timer can probably be dropped once we make the</span><br><span style="color: hsl(120, 100%, 40%);">+ * other TBF always signal us assignment failure (we already get</span><br><span style="color: hsl(120, 100%, 40%);">+ * assignment success through TBF_EV_ASSIGN_ACK_PACCH) */</span><br><span style="color: hsl(120, 100%, 40%);">+ if (ctx->state_flags & (1 << GPRS_RLCMAC_FLAG_PACCH)) {</span><br><span style="color: hsl(120, 100%, 40%);">+ fi->T = -2001;</span><br><span style="color: hsl(120, 100%, 40%);">+ val = osmo_tdef_get(the_pcu->T_defs, fi->T, OSMO_TDEF_MS, -1);</span><br><span style="color: hsl(120, 100%, 40%);">+ sec = val / 1000;</span><br><span style="color: hsl(120, 100%, 40%);">+ micro = (val % 1000) * 1000;</span><br><span style="color: hsl(120, 100%, 40%);">+ LOGPTBF(ctx->tbf, LOGL_DEBUG,</span><br><span style="color: hsl(120, 100%, 40%);">+ "Starting timer X2001 [assignment (PACCH)] with %u sec. %u microsec\n",</span><br><span style="color: hsl(120, 100%, 40%);">+ sec, micro);</span><br><span style="color: hsl(120, 100%, 40%);">+ osmo_timer_schedule(&fi->timer, sec, micro);</span><br><span style="color: hsl(120, 100%, 40%);">+ }</span><br><span style="color: hsl(120, 100%, 40%);">+}</span><br><span style="color: hsl(120, 100%, 40%);">+</span><br><span> static void st_assign(struct osmo_fsm_inst *fi, uint32_t event, void *data)</span><br><span> {</span><br><span> struct tbf_fsm_ctx *ctx = (struct tbf_fsm_ctx *)fi->priv;</span><br><span>@@ -245,6 +271,9 @@</span><br><span> {</span><br><span> struct tbf_fsm_ctx *ctx = (struct tbf_fsm_ctx *)fi->priv;</span><br><span> switch (fi->T) {</span><br><span style="color: hsl(120, 100%, 40%);">+ case -2001:</span><br><span style="color: hsl(120, 100%, 40%);">+ LOGPTBF(ctx->tbf, LOGL_NOTICE, "releasing due to PACCH assignment timeout.\n");</span><br><span style="color: hsl(120, 100%, 40%);">+ /* fall-through */</span><br><span> case 3169:</span><br><span> case 3195:</span><br><span> tbf_free(ctx->tbf);</span><br><span>@@ -279,6 +308,7 @@</span><br><span> X(TBF_ST_RELEASING),</span><br><span> .name = "ASSIGN",</span><br><span> .action = st_assign,</span><br><span style="color: hsl(120, 100%, 40%);">+ .onenter = st_assign_on_enter,</span><br><span> },</span><br><span> [TBF_ST_FLOW] = {</span><br><span> .in_event_mask =</span><br><span>diff --git a/tests/tbf/TbfTest.cpp b/tests/tbf/TbfTest.cpp</span><br><span>index ce5f784..9ba1753 100644</span><br><span>--- a/tests/tbf/TbfTest.cpp</span><br><span>+++ b/tests/tbf/TbfTest.cpp</span><br><span>@@ -3182,6 +3182,7 @@</span><br><span> fprintf(stderr, "=== start %s ===\n", __func__);</span><br><span> </span><br><span> setup_bts(bts, ts_no, 4);</span><br><span style="color: hsl(120, 100%, 40%);">+ OSMO_ASSERT(osmo_tdef_set(the_pcu->T_defs, -2000, 0, OSMO_TDEF_MS) == 0);</span><br><span> </span><br><span> int rc = 0;</span><br><span> </span><br><span>@@ -3198,8 +3199,7 @@</span><br><span> trx_no, ts_no, fn, bn);</span><br><span> </span><br><span> OSMO_ASSERT(rc == 0);</span><br><span style="color: hsl(0, 100%, 40%);">-</span><br><span style="color: hsl(0, 100%, 40%);">- ul_tbf->handle_timeout();</span><br><span style="color: hsl(120, 100%, 40%);">+ osmo_select_main(0);</span><br><span> </span><br><span> TALLOC_FREE(the_pcu);</span><br><span> fprintf(stderr, "=== end %s ===\n", __func__);</span><br><span>diff --git a/tests/tbf/TbfTest.err b/tests/tbf/TbfTest.err</span><br><span>index aed1bfd..ce1aa3c 100644</span><br><span>--- a/tests/tbf/TbfTest.err</span><br><span>+++ b/tests/tbf/TbfTest.err</span><br><span>@@ -139,7 +139,7 @@</span><br><span> TBF(DL-TFI_1){NULL}: Received Event ASSIGN_ADD_PACCH</span><br><span> TBF(TFI=1 TLLI=0xffeeddcc DIR=DL STATE=NULL) set ass. type PACCH [prev CCCH:0, PACCH:0]</span><br><span> TBF(DL-TFI_1){NULL}: state_chg to ASSIGN</span><br><span style="color: hsl(0, 100%, 40%);">-TBF(TFI=1 TLLI=0xffeeddcc DIR=DL STATE=ASSIGN) starting timer T0 [assignment (PACCH)] with 2 sec. 0 microsec, cur_fn=2654167</span><br><span style="color: hsl(120, 100%, 40%);">+TBF(TFI=1 TLLI=0xffeeddcc DIR=DL STATE=ASSIGN) Starting timer X2001 [assignment (PACCH)] with 2 sec. 0 microsec</span><br><span> TBF(TFI=0 TLLI=0xffeeddcc DIR=DL STATE=WAIT_RELEASE) free</span><br><span> TBF(TFI=0 TLLI=0xffeeddcc DIR=DL STATE=WAIT_RELEASE) stopping timer T3193 [freeing TBF]</span><br><span> PDCH(bts=0,trx=0,ts=4) Detaching TBF(TFI=0 TLLI=0xffeeddcc DIR=DL STATE=WAIT_RELEASE), 1 TBFs, USFs = 00, TFIs = 00000002.</span><br><span>@@ -149,7 +149,6 @@</span><br><span> UL_ASS_TBF(DL-TFI_0){NONE}: Deallocated</span><br><span> DL_ASS_TBF(DL-TFI_0){WAIT_ACK}: Deallocated</span><br><span> TBF(TFI=1 TLLI=0xffeeddcc DIR=DL STATE=ASSIGN) free</span><br><span style="color: hsl(0, 100%, 40%);">-TBF(TFI=1 TLLI=0xffeeddcc DIR=DL STATE=ASSIGN) stopping timer T0 [freeing TBF]</span><br><span> PDCH(bts=0,trx=0,ts=4) Detaching TBF(TFI=1 TLLI=0xffeeddcc DIR=DL STATE=ASSIGN), 0 TBFs, USFs = 00, TFIs = 00000000.</span><br><span> MS(TLLI=0xffeeddcc, IMSI=, TA=0, 45/0,) Detaching TBF: TBF(TFI=1 TLLI=0xffeeddcc DIR=DL STATE=ASSIGN)</span><br><span> ********** DL-TBF ends here **********</span><br><span>@@ -252,9 +251,8 @@</span><br><span> TBF(DL-TFI_1){NULL}: Received Event ASSIGN_ADD_PACCH</span><br><span> TBF(TFI=1 TLLI=0xffeeddcc DIR=DL STATE=NULL) set ass. type PACCH [prev CCCH:0, PACCH:0]</span><br><span> TBF(DL-TFI_1){NULL}: state_chg to ASSIGN</span><br><span style="color: hsl(0, 100%, 40%);">-TBF(TFI=1 TLLI=0xffeeddcc DIR=DL STATE=ASSIGN) starting timer T0 [assignment (PACCH)] with 2 sec. 0 microsec, cur_fn=2654167</span><br><span style="color: hsl(120, 100%, 40%);">+TBF(TFI=1 TLLI=0xffeeddcc DIR=DL STATE=ASSIGN) Starting timer X2001 [assignment (PACCH)] with 2 sec. 0 microsec</span><br><span> TBF(TFI=1 TLLI=0xffeeddcc DIR=DL STATE=ASSIGN) free</span><br><span style="color: hsl(0, 100%, 40%);">-TBF(TFI=1 TLLI=0xffeeddcc DIR=DL STATE=ASSIGN) stopping timer T0 [freeing TBF]</span><br><span> PDCH(bts=0,trx=0,ts=4) Detaching TBF(TFI=1 TLLI=0xffeeddcc DIR=DL STATE=ASSIGN), 1 TBFs, USFs = 00, TFIs = 00000001.</span><br><span> MS(TLLI=0xffeeddcc, IMSI=, TA=0, 45/0,) Detaching TBF: TBF(TFI=1 TLLI=0xffeeddcc DIR=DL STATE=ASSIGN)</span><br><span> ********** DL-TBF ends here **********</span><br><span>@@ -1826,6 +1824,7 @@</span><br><span> TBF(UL-TFI_0){NULL}: Received Event ASSIGN_ADD_PACCH</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=UL STATE=NULL) set ass. type PACCH [prev CCCH:0, PACCH:0]</span><br><span> TBF(UL-TFI_0){NULL}: state_chg to ASSIGN</span><br><span style="color: hsl(120, 100%, 40%);">+TBF(TFI=0 TLLI=0xf1223344 DIR=UL STATE=ASSIGN) Starting timer X2001 [assignment (PACCH)] with 2 sec. 0 microsec</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=UL STATE=ASSIGN) change control TS 7 -> 7 until assignment is complete.</span><br><span> UL_ASS_TBF(UL-TFI_0){NONE}: Received Event SCHED_ASS</span><br><span> UL_ASS_TBF(UL-TFI_0){NONE}: state_chg to SEND_ASS</span><br><span>@@ -1885,7 +1884,7 @@</span><br><span> TBF(DL-TFI_0){NULL}: Received Event ASSIGN_ADD_PACCH</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=NULL) set ass. type PACCH [prev CCCH:0, PACCH:0]</span><br><span> TBF(DL-TFI_0){NULL}: state_chg to ASSIGN</span><br><span style="color: hsl(0, 100%, 40%);">-TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=ASSIGN) starting timer T0 [assignment (PACCH)] with 2 sec. 0 microsec, cur_fn=2654275</span><br><span style="color: hsl(120, 100%, 40%);">+TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=ASSIGN) Starting timer X2001 [assignment (PACCH)] with 2 sec. 0 microsec</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=ASSIGN) appending 4 bytes</span><br><span> === end test_tbf_two_phase ===</span><br><span> === start test_tbf_ra_update_rach ===</span><br><span>@@ -1923,6 +1922,7 @@</span><br><span> TBF(UL-TFI_0){NULL}: Received Event ASSIGN_ADD_PACCH</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=UL STATE=NULL) set ass. type PACCH [prev CCCH:0, PACCH:0]</span><br><span> TBF(UL-TFI_0){NULL}: state_chg to ASSIGN</span><br><span style="color: hsl(120, 100%, 40%);">+TBF(TFI=0 TLLI=0xf1223344 DIR=UL STATE=ASSIGN) Starting timer X2001 [assignment (PACCH)] with 2 sec. 0 microsec</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=UL STATE=ASSIGN) change control TS 7 -> 7 until assignment is complete.</span><br><span> UL_ASS_TBF(UL-TFI_0){NONE}: Received Event SCHED_ASS</span><br><span> UL_ASS_TBF(UL-TFI_0){NONE}: state_chg to SEND_ASS</span><br><span>@@ -1981,7 +1981,7 @@</span><br><span> TBF(DL-TFI_0){NULL}: Received Event ASSIGN_ADD_PACCH</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=NULL) set ass. type PACCH [prev CCCH:0, PACCH:0]</span><br><span> TBF(DL-TFI_0){NULL}: state_chg to ASSIGN</span><br><span style="color: hsl(0, 100%, 40%);">-TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=ASSIGN) starting timer T0 [assignment (PACCH)] with 2 sec. 0 microsec, cur_fn=2654275</span><br><span style="color: hsl(120, 100%, 40%);">+TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=ASSIGN) Starting timer X2001 [assignment (PACCH)] with 2 sec. 0 microsec</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=ASSIGN) appending 10 bytes</span><br><span> Old MS: TLLI = 0xf1223344, TA = 7, IMSI = 0011223344, LLC = 1</span><br><span> PDCH(bts=0,trx=0,ts=7) Received RTS for PDCH: FN=2654275 block_nr=9 scheduling USF=0 for TBF(TFI=0 TLLI=0xf1223344 DIR=UL STATE=FLOW), expect answer on UL FN=2654279</span><br><span>@@ -2002,7 +2002,6 @@</span><br><span> DL_ASS_TBF(UL-TFI_0){WAIT_ACK}: state_chg to NONE</span><br><span> TBF(DL-TFI_0){ASSIGN}: Received Event ASSIGN_ACK_PACCH</span><br><span> TBF(DL-TFI_0){ASSIGN}: state_chg to FLOW</span><br><span style="color: hsl(0, 100%, 40%);">-TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=FLOW) stopping timer T0 [control acked (DL-TBF)]</span><br><span> PDCH(bts=0,trx=0,ts=7) Expiring FN=2654288 but previous FN=2654279 is still reserved!</span><br><span> PDCH(bts=0,trx=0,ts=7) Timeout for registered USF (FN=2654279): TBF(TFI=0 TLLI=0xf1223344 DIR=UL STATE=FLOW)</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=UL STATE=FLOW) N3101 0 => 1 (< MAX 10)</span><br><span>@@ -2086,6 +2085,7 @@</span><br><span> TBF(UL-TFI_1){NULL}: Received Event ASSIGN_ADD_PACCH</span><br><span> TBF(TFI=1 TLLI=0xf5667788 DIR=UL STATE=NULL) set ass. type PACCH [prev CCCH:0, PACCH:0]</span><br><span> TBF(UL-TFI_1){NULL}: state_chg to ASSIGN</span><br><span style="color: hsl(120, 100%, 40%);">+TBF(TFI=1 TLLI=0xf5667788 DIR=UL STATE=ASSIGN) Starting timer X2001 [assignment (PACCH)] with 2 sec. 0 microsec</span><br><span> TBF(TFI=1 TLLI=0xf5667788 DIR=UL STATE=ASSIGN) change control TS 7 -> 7 until assignment is complete.</span><br><span> UL_ASS_TBF(UL-TFI_1){NONE}: Received Event SCHED_ASS</span><br><span> UL_ASS_TBF(UL-TFI_1){NONE}: state_chg to SEND_ASS</span><br><span>@@ -2181,6 +2181,7 @@</span><br><span> TBF(UL-TFI_0){NULL}: Received Event ASSIGN_ADD_PACCH</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=UL STATE=NULL) set ass. type PACCH [prev CCCH:0, PACCH:0]</span><br><span> TBF(UL-TFI_0){NULL}: state_chg to ASSIGN</span><br><span style="color: hsl(120, 100%, 40%);">+TBF(TFI=0 TLLI=0xf1223344 DIR=UL STATE=ASSIGN) Starting timer X2001 [assignment (PACCH)] with 2 sec. 0 microsec</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=UL STATE=ASSIGN) change control TS 7 -> 7 until assignment is complete.</span><br><span> UL_ASS_TBF(UL-TFI_0){NONE}: Received Event SCHED_ASS</span><br><span> UL_ASS_TBF(UL-TFI_0){NONE}: state_chg to SEND_ASS</span><br><span>@@ -2241,7 +2242,7 @@</span><br><span> TBF(DL-TFI_0){NULL}: Received Event ASSIGN_ADD_PACCH</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=NULL) set ass. type PACCH [prev CCCH:0, PACCH:0]</span><br><span> TBF(DL-TFI_0){NULL}: state_chg to ASSIGN</span><br><span style="color: hsl(0, 100%, 40%);">-TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=ASSIGN) starting timer T0 [assignment (PACCH)] with 2 sec. 0 microsec, cur_fn=2654275</span><br><span style="color: hsl(120, 100%, 40%);">+TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=ASSIGN) Starting timer X2001 [assignment (PACCH)] with 2 sec. 0 microsec</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=ASSIGN) appending 20 bytes</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=ASSIGN) appending 20 bytes</span><br><span> Old MS: TLLI = 0xf1223344, TA = 7, IMSI = 0011223344, LLC = 2</span><br><span>@@ -2282,6 +2283,7 @@</span><br><span> TBF(UL-TFI_0){NULL}: Received Event ASSIGN_ADD_PACCH</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=UL STATE=NULL) set ass. type PACCH [prev CCCH:0, PACCH:0]</span><br><span> TBF(UL-TFI_0){NULL}: state_chg to ASSIGN</span><br><span style="color: hsl(120, 100%, 40%);">+TBF(TFI=0 TLLI=0xf1223344 DIR=UL STATE=ASSIGN) Starting timer X2001 [assignment (PACCH)] with 2 sec. 0 microsec</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=UL STATE=ASSIGN) change control TS 7 -> 7 until assignment is complete.</span><br><span> UL_ASS_TBF(UL-TFI_0){NONE}: Received Event SCHED_ASS</span><br><span> UL_ASS_TBF(UL-TFI_0){NONE}: state_chg to SEND_ASS</span><br><span>@@ -2349,6 +2351,7 @@</span><br><span> TBF(UL-TFI_0){NULL}: Received Event ASSIGN_ADD_PACCH</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=UL STATE=NULL) set ass. type PACCH [prev CCCH:0, PACCH:0]</span><br><span> TBF(UL-TFI_0){NULL}: state_chg to ASSIGN</span><br><span style="color: hsl(120, 100%, 40%);">+TBF(TFI=0 TLLI=0xf1223344 DIR=UL STATE=ASSIGN) Starting timer X2001 [assignment (PACCH)] with 2 sec. 0 microsec</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=UL STATE=ASSIGN) change control TS 7 -> 7 until assignment is complete.</span><br><span> UL_ASS_TBF(UL-TFI_0){NONE}: Received Event SCHED_ASS</span><br><span> UL_ASS_TBF(UL-TFI_0){NONE}: state_chg to SEND_ASS</span><br><span>@@ -2409,7 +2412,7 @@</span><br><span> TBF(DL-TFI_0){NULL}: Received Event ASSIGN_ADD_PACCH</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=NULL) set ass. type PACCH [prev CCCH:0, PACCH:0]</span><br><span> TBF(DL-TFI_0){NULL}: state_chg to ASSIGN</span><br><span style="color: hsl(0, 100%, 40%);">-TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=ASSIGN) starting timer T0 [assignment (PACCH)] with 2 sec. 0 microsec, cur_fn=2654275</span><br><span style="color: hsl(120, 100%, 40%);">+TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=ASSIGN) Starting timer X2001 [assignment (PACCH)] with 2 sec. 0 microsec</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=ASSIGN) appending 20 bytes</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=ASSIGN) appending 20 bytes</span><br><span> Old MS: TLLI = 0xf1223344, TA = 7, IMSI = 0011223344, LLC = 2</span><br><span>@@ -2456,7 +2459,6 @@</span><br><span> TBF(TFI=0 TLLI=0xffffffff DIR=UL STATE=FLOW) data_length=20, data=f1 22 33 44 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 </span><br><span> TBF(TFI=0 TLLI=0xffffffff DIR=UL STATE=FLOW) Decoded premier TLLI=0xf1223344 of UL DATA TFI=0.</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=ASSIGN) free</span><br><span style="color: hsl(0, 100%, 40%);">-TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=ASSIGN) stopping timer T0 [freeing TBF]</span><br><span> PDCH(bts=0,trx=0,ts=7) Detaching TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=ASSIGN), 0 TBFs, USFs = 01, TFIs = 00000000.</span><br><span> MS(TLLI=0xf1223344, IMSI=0011223344, TA=7, 1/0,) Detaching TBF: TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=ASSIGN)</span><br><span> ********** DL-TBF ends here **********</span><br><span>@@ -2516,6 +2518,7 @@</span><br><span> TBF(UL-TFI_0){NULL}: Received Event ASSIGN_ADD_PACCH</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=UL STATE=NULL) set ass. type PACCH [prev CCCH:0, PACCH:0]</span><br><span> TBF(UL-TFI_0){NULL}: state_chg to ASSIGN</span><br><span style="color: hsl(120, 100%, 40%);">+TBF(TFI=0 TLLI=0xf1223344 DIR=UL STATE=ASSIGN) Starting timer X2001 [assignment (PACCH)] with 2 sec. 0 microsec</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=UL STATE=ASSIGN) change control TS 7 -> 7 until assignment is complete.</span><br><span> UL_ASS_TBF(UL-TFI_0){NONE}: Received Event SCHED_ASS</span><br><span> UL_ASS_TBF(UL-TFI_0){NONE}: state_chg to SEND_ASS</span><br><span>@@ -2576,7 +2579,7 @@</span><br><span> TBF(DL-TFI_0){NULL}: Received Event ASSIGN_ADD_PACCH</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=NULL) set ass. type PACCH [prev CCCH:0, PACCH:0]</span><br><span> TBF(DL-TFI_0){NULL}: state_chg to ASSIGN</span><br><span style="color: hsl(0, 100%, 40%);">-TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=ASSIGN) starting timer T0 [assignment (PACCH)] with 2 sec. 0 microsec, cur_fn=2654275</span><br><span style="color: hsl(120, 100%, 40%);">+TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=ASSIGN) Starting timer X2001 [assignment (PACCH)] with 2 sec. 0 microsec</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=ASSIGN) appending 13 bytes</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=ASSIGN) appending 13 bytes</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=ASSIGN) appending 13 bytes</span><br><span>@@ -2636,7 +2639,6 @@</span><br><span> DL_ASS_TBF(UL-TFI_0){WAIT_ACK}: state_chg to NONE</span><br><span> TBF(DL-TFI_0){ASSIGN}: Received Event ASSIGN_ACK_PACCH</span><br><span> TBF(DL-TFI_0){ASSIGN}: state_chg to FLOW</span><br><span style="color: hsl(0, 100%, 40%);">-TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=FLOW) stopping timer T0 [control acked (DL-TBF)]</span><br><span> PDCH(bts=0,trx=0,ts=7) Expiring FN=2654288 but previous FN=2654279 is still reserved!</span><br><span> PDCH(bts=0,trx=0,ts=7) Timeout for registered USF (FN=2654279): TBF(TFI=0 TLLI=0xf1223344 DIR=UL STATE=FLOW)</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=UL STATE=FLOW) N3101 0 => 1 (< MAX 10)</span><br><span>@@ -3229,7 +3231,7 @@</span><br><span> TBF(DL-TFI_1){NULL}: Received Event ASSIGN_ADD_PACCH</span><br><span> TBF(TFI=1 TLLI=0xf1223344 DIR=DL STATE=NULL) set ass. type PACCH [prev CCCH:0, PACCH:0]</span><br><span> TBF(DL-TFI_1){NULL}: state_chg to ASSIGN</span><br><span style="color: hsl(0, 100%, 40%);">-TBF(TFI=1 TLLI=0xf1223344 DIR=DL STATE=ASSIGN) starting timer T0 [assignment (PACCH)] with 2 sec. 0 microsec, cur_fn=2654379</span><br><span style="color: hsl(120, 100%, 40%);">+TBF(TFI=1 TLLI=0xf1223344 DIR=DL STATE=ASSIGN) Starting timer X2001 [assignment (PACCH)] with 2 sec. 0 microsec</span><br><span> PDCH(bts=0,trx=0,ts=7) Expiring FN=2654379 but previous FN=2654283 is still reserved!</span><br><span> PDCH(bts=0,trx=0,ts=7) Timeout for registered USF (FN=2654283): TBF(TFI=0 TLLI=0xf1223344 DIR=UL STATE=FLOW)</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=UL STATE=FLOW) N3101 1 => 2 (< MAX 10)</span><br><span>@@ -3324,7 +3326,6 @@</span><br><span> DL_ASS_TBF(DL-TFI_0){NONE}: Deallocated</span><br><span> TBF(DL-TFI_1){ASSIGN}: Received Event ASSIGN_ACK_PACCH</span><br><span> TBF(DL-TFI_1){ASSIGN}: state_chg to FLOW</span><br><span style="color: hsl(0, 100%, 40%);">-TBF(TFI=1 TLLI=0xf1223344 DIR=DL STATE=FLOW) stopping timer T0 [control acked (DL-TBF)]</span><br><span> PDCH(bts=0,trx=0,ts=7) Expiring FN=2654409 but previous FN=2654383 is still reserved!</span><br><span> PDCH(bts=0,trx=0,ts=7) Timeout for registered USF (FN=2654383): TBF(TFI=0 TLLI=0xf1223344 DIR=UL STATE=RELEASING)</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=UL STATE=RELEASING) N3101 23 => 24 (< MAX 10)</span><br><span>@@ -3694,6 +3695,7 @@</span><br><span> TBF(UL-TFI_0){NULL}: Received Event ASSIGN_ADD_PACCH</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=UL STATE=NULL EGPRS) set ass. type PACCH [prev CCCH:0, PACCH:0]</span><br><span> TBF(UL-TFI_0){NULL}: state_chg to ASSIGN</span><br><span style="color: hsl(120, 100%, 40%);">+TBF(TFI=0 TLLI=0xf1223344 DIR=UL STATE=ASSIGN EGPRS) Starting timer X2001 [assignment (PACCH)] with 2 sec. 0 microsec</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=UL STATE=ASSIGN EGPRS) change control TS 7 -> 7 until assignment is complete.</span><br><span> UL_ASS_TBF(UL-TFI_0){NONE}: Received Event SCHED_ASS</span><br><span> UL_ASS_TBF(UL-TFI_0){NONE}: state_chg to SEND_ASS</span><br><span>@@ -3757,7 +3759,7 @@</span><br><span> TBF(DL-TFI_0){NULL}: Received Event ASSIGN_ADD_PACCH</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=NULL EGPRS) set ass. type PACCH [prev CCCH:0, PACCH:0]</span><br><span> TBF(DL-TFI_0){NULL}: state_chg to ASSIGN</span><br><span style="color: hsl(0, 100%, 40%);">-TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=ASSIGN EGPRS) starting timer T0 [assignment (PACCH)] with 2 sec. 0 microsec, cur_fn=2654275</span><br><span style="color: hsl(120, 100%, 40%);">+TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=ASSIGN EGPRS) Starting timer X2001 [assignment (PACCH)] with 2 sec. 0 microsec</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=ASSIGN EGPRS) appending 256 bytes</span><br><span> === end test_tbf_egprs_two_phase ===</span><br><span> === start test_tbf_egprs_two_phase_spb ===</span><br><span>@@ -3799,6 +3801,7 @@</span><br><span> TBF(UL-TFI_0){NULL}: Received Event ASSIGN_ADD_PACCH</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=UL STATE=NULL EGPRS) set ass. type PACCH [prev CCCH:0, PACCH:0]</span><br><span> TBF(UL-TFI_0){NULL}: state_chg to ASSIGN</span><br><span style="color: hsl(120, 100%, 40%);">+TBF(TFI=0 TLLI=0xf1223344 DIR=UL STATE=ASSIGN EGPRS) Starting timer X2001 [assignment (PACCH)] with 2 sec. 0 microsec</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=UL STATE=ASSIGN EGPRS) change control TS 7 -> 7 until assignment is complete.</span><br><span> UL_ASS_TBF(UL-TFI_0){NONE}: Received Event SCHED_ASS</span><br><span> UL_ASS_TBF(UL-TFI_0){NONE}: state_chg to SEND_ASS</span><br><span>@@ -3980,7 +3983,7 @@</span><br><span> TBF(DL-TFI_0){NULL}: Received Event ASSIGN_ADD_PACCH</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=NULL EGPRS) set ass. type PACCH [prev CCCH:0, PACCH:0]</span><br><span> TBF(DL-TFI_0){NULL}: state_chg to ASSIGN</span><br><span style="color: hsl(0, 100%, 40%);">-TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=ASSIGN EGPRS) starting timer T0 [assignment (PACCH)] with 2 sec. 0 microsec, cur_fn=2654275</span><br><span style="color: hsl(120, 100%, 40%);">+TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=ASSIGN EGPRS) Starting timer X2001 [assignment (PACCH)] with 2 sec. 0 microsec</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=ASSIGN EGPRS) appending 256 bytes</span><br><span> === end test_tbf_egprs_two_phase_spb ===</span><br><span> === start test_tbf_egprs_dl ===</span><br><span>@@ -6711,6 +6714,7 @@</span><br><span> TBF(UL-TFI_0){NULL}: Received Event ASSIGN_ADD_PACCH</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=UL STATE=NULL EGPRS) set ass. type PACCH [prev CCCH:0, PACCH:0]</span><br><span> TBF(UL-TFI_0){NULL}: state_chg to ASSIGN</span><br><span style="color: hsl(120, 100%, 40%);">+TBF(TFI=0 TLLI=0xf1223344 DIR=UL STATE=ASSIGN EGPRS) Starting timer X2001 [assignment (PACCH)] with 2 sec. 0 microsec</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=UL STATE=ASSIGN EGPRS) change control TS 7 -> 7 until assignment is complete.</span><br><span> UL_ASS_TBF(UL-TFI_0){NONE}: Received Event SCHED_ASS</span><br><span> UL_ASS_TBF(UL-TFI_0){NONE}: state_chg to SEND_ASS</span><br><span>@@ -6791,7 +6795,7 @@</span><br><span> TBF(DL-TFI_0){NULL}: Received Event ASSIGN_ADD_PACCH</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=NULL EGPRS) set ass. type PACCH [prev CCCH:0, PACCH:0]</span><br><span> TBF(DL-TFI_0){NULL}: state_chg to ASSIGN</span><br><span style="color: hsl(0, 100%, 40%);">-TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=ASSIGN EGPRS) starting timer T0 [assignment (PACCH)] with 2 sec. 0 microsec, cur_fn=2654275</span><br><span style="color: hsl(120, 100%, 40%);">+TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=ASSIGN EGPRS) Starting timer X2001 [assignment (PACCH)] with 2 sec. 0 microsec</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=ASSIGN EGPRS) appending 256 bytes</span><br><span> === end test_tbf_puan_urbb_len ===</span><br><span> === start test_tbf_update_ws ===</span><br><span>@@ -6892,6 +6896,7 @@</span><br><span> TBF(UL-TFI_0){NULL}: Received Event ASSIGN_ADD_PACCH</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=UL STATE=NULL EGPRS) set ass. type PACCH [prev CCCH:0, PACCH:0]</span><br><span> TBF(UL-TFI_0){NULL}: state_chg to ASSIGN</span><br><span style="color: hsl(120, 100%, 40%);">+TBF(TFI=0 TLLI=0xf1223344 DIR=UL STATE=ASSIGN EGPRS) Starting timer X2001 [assignment (PACCH)] with 2 sec. 0 microsec</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=UL STATE=ASSIGN EGPRS) change control TS 7 -> 7 until assignment is complete.</span><br><span> UL_ASS_TBF(UL-TFI_0){NONE}: Received Event SCHED_ASS</span><br><span> UL_ASS_TBF(UL-TFI_0){NONE}: state_chg to SEND_ASS</span><br><span>@@ -6967,7 +6972,7 @@</span><br><span> TBF(DL-TFI_0){NULL}: Received Event ASSIGN_ADD_PACCH</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=NULL EGPRS) set ass. type PACCH [prev CCCH:0, PACCH:0]</span><br><span> TBF(DL-TFI_0){NULL}: state_chg to ASSIGN</span><br><span style="color: hsl(0, 100%, 40%);">-TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=ASSIGN EGPRS) starting timer T0 [assignment (PACCH)] with 2 sec. 0 microsec, cur_fn=2654275</span><br><span style="color: hsl(120, 100%, 40%);">+TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=ASSIGN EGPRS) Starting timer X2001 [assignment (PACCH)] with 2 sec. 0 microsec</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=ASSIGN EGPRS) appending 256 bytes</span><br><span> === end test_tbf_li_decoding ===</span><br><span> === start test_tbf_epdan_out_of_rx_window ===</span><br><span>@@ -7276,6 +7281,7 @@</span><br><span> TBF(UL-TFI_0){NULL}: Received Event ASSIGN_ADD_PACCH</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=UL STATE=NULL EGPRS) set ass. type PACCH [prev CCCH:0, PACCH:0]</span><br><span> TBF(UL-TFI_0){NULL}: state_chg to ASSIGN</span><br><span style="color: hsl(120, 100%, 40%);">+TBF(TFI=0 TLLI=0xf1223344 DIR=UL STATE=ASSIGN EGPRS) Starting timer X2001 [assignment (PACCH)] with 2 sec. 0 microsec</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=UL STATE=ASSIGN EGPRS) change control TS 7 -> 7 until assignment is complete.</span><br><span> UL_ASS_TBF(UL-TFI_0){NONE}: Received Event SCHED_ASS</span><br><span> UL_ASS_TBF(UL-TFI_0){NONE}: state_chg to SEND_ASS</span><br><span>@@ -7828,7 +7834,7 @@</span><br><span> TBF(DL-TFI_0){NULL}: Received Event ASSIGN_ADD_PACCH</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=NULL EGPRS) set ass. type PACCH [prev CCCH:0, PACCH:0]</span><br><span> TBF(DL-TFI_0){NULL}: state_chg to ASSIGN</span><br><span style="color: hsl(0, 100%, 40%);">-TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=ASSIGN EGPRS) starting timer T0 [assignment (PACCH)] with 2 sec. 0 microsec, cur_fn=2654275</span><br><span style="color: hsl(120, 100%, 40%);">+TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=ASSIGN EGPRS) Starting timer X2001 [assignment (PACCH)] with 2 sec. 0 microsec</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=DL STATE=ASSIGN EGPRS) appending 256 bytes</span><br><span> PDCH(bts=0,trx=0,ts=7) Got MCS-4 RLC block: R=0, SI=0, TFI=0, CPS=0, RSB=0, rc=385</span><br><span> TBF(TFI=0 TLLI=0xf1223344 DIR=UL STATE=FLOW EGPRS) UL DATA TFI=0 received (V(Q)=0 .. V(R)=0)</span><br><span>@@ -8586,6 +8592,7 @@</span><br><span> TBF(UL-TFI_0){NULL}: Received Event ASSIGN_ADD_PACCH</span><br><span> TBF(TFI=0 TLLI=0xffeeddcc DIR=UL STATE=NULL EGPRS) set ass. type PACCH [prev CCCH:0, PACCH:0]</span><br><span> TBF(UL-TFI_0){NULL}: state_chg to ASSIGN</span><br><span style="color: hsl(120, 100%, 40%);">+TBF(TFI=0 TLLI=0xffeeddcc DIR=UL STATE=ASSIGN EGPRS) Starting timer X2001 [assignment (PACCH)] with 2 sec. 0 microsec</span><br><span> TBF(TFI=0 TLLI=0xffeeddcc DIR=UL STATE=ASSIGN EGPRS) change control TS 7 -> 7 until assignment is complete.</span><br><span> UL_ASS_TBF(UL-TFI_0){NONE}: Received Event SCHED_ASS</span><br><span> UL_ASS_TBF(UL-TFI_0){NONE}: state_chg to SEND_ASS</span><br><span>@@ -8630,6 +8637,7 @@</span><br><span> TBF(UL-TFI_1){NULL}: Received Event ASSIGN_ADD_PACCH</span><br><span> TBF(TFI=1 TLLI=0xffeeddcd DIR=UL STATE=NULL EGPRS) set ass. type PACCH [prev CCCH:0, PACCH:0]</span><br><span> TBF(UL-TFI_1){NULL}: state_chg to ASSIGN</span><br><span style="color: hsl(120, 100%, 40%);">+TBF(TFI=1 TLLI=0xffeeddcd DIR=UL STATE=ASSIGN EGPRS) Starting timer X2001 [assignment (PACCH)] with 2 sec. 0 microsec</span><br><span> TBF(TFI=1 TLLI=0xffeeddcd DIR=UL STATE=ASSIGN EGPRS) change control TS 7 -> 7 until assignment is complete.</span><br><span> UL_ASS_TBF(UL-TFI_1){NONE}: Received Event SCHED_ASS</span><br><span> UL_ASS_TBF(UL-TFI_1){NONE}: state_chg to SEND_ASS</span><br><span>@@ -8681,6 +8689,7 @@</span><br><span> TBF(UL-TFI_2){NULL}: Received Event ASSIGN_ADD_PACCH</span><br><span> TBF(TFI=2 TLLI=0xffeeddce DIR=UL STATE=NULL EGPRS) set ass. type PACCH [prev CCCH:0, PACCH:0]</span><br><span> TBF(UL-TFI_2){NULL}: state_chg to ASSIGN</span><br><span style="color: hsl(120, 100%, 40%);">+TBF(TFI=2 TLLI=0xffeeddce DIR=UL STATE=ASSIGN EGPRS) Starting timer X2001 [assignment (PACCH)] with 2 sec. 0 microsec</span><br><span> TBF(TFI=2 TLLI=0xffeeddce DIR=UL STATE=ASSIGN EGPRS) change control TS 7 -> 7 until assignment is complete.</span><br><span> UL_ASS_TBF(UL-TFI_2){NONE}: Received Event SCHED_ASS</span><br><span> UL_ASS_TBF(UL-TFI_2){NONE}: state_chg to SEND_ASS</span><br><span>@@ -8732,6 +8741,7 @@</span><br><span> TBF(UL-TFI_3){NULL}: Received Event ASSIGN_ADD_PACCH</span><br><span> TBF(TFI=3 TLLI=0xffeeddcf DIR=UL STATE=NULL EGPRS) set ass. type PACCH [prev CCCH:0, PACCH:0]</span><br><span> TBF(UL-TFI_3){NULL}: state_chg to ASSIGN</span><br><span style="color: hsl(120, 100%, 40%);">+TBF(TFI=3 TLLI=0xffeeddcf DIR=UL STATE=ASSIGN EGPRS) Starting timer X2001 [assignment (PACCH)] with 2 sec. 0 microsec</span><br><span> TBF(TFI=3 TLLI=0xffeeddcf DIR=UL STATE=ASSIGN EGPRS) change control TS 7 -> 7 until assignment is complete.</span><br><span> UL_ASS_TBF(UL-TFI_3){NONE}: Received Event SCHED_ASS</span><br><span> UL_ASS_TBF(UL-TFI_3){NONE}: state_chg to SEND_ASS</span><br><span>@@ -8783,6 +8793,7 @@</span><br><span> TBF(UL-TFI_4){NULL}: Received Event ASSIGN_ADD_PACCH</span><br><span> TBF(TFI=4 TLLI=0xffeeddd0 DIR=UL STATE=NULL EGPRS) set ass. type PACCH [prev CCCH:0, PACCH:0]</span><br><span> TBF(UL-TFI_4){NULL}: state_chg to ASSIGN</span><br><span style="color: hsl(120, 100%, 40%);">+TBF(TFI=4 TLLI=0xffeeddd0 DIR=UL STATE=ASSIGN EGPRS) Starting timer X2001 [assignment (PACCH)] with 2 sec. 0 microsec</span><br><span> TBF(TFI=4 TLLI=0xffeeddd0 DIR=UL STATE=ASSIGN EGPRS) change control TS 7 -> 7 until assignment is complete.</span><br><span> UL_ASS_TBF(UL-TFI_4){NONE}: Received Event SCHED_ASS</span><br><span> UL_ASS_TBF(UL-TFI_4){NONE}: state_chg to SEND_ASS</span><br><span>@@ -8834,6 +8845,7 @@</span><br><span> TBF(UL-TFI_5){NULL}: Received Event ASSIGN_ADD_PACCH</span><br><span> TBF(TFI=5 TLLI=0xffeeddd1 DIR=UL STATE=NULL EGPRS) set ass. type PACCH [prev CCCH:0, PACCH:0]</span><br><span> TBF(UL-TFI_5){NULL}: state_chg to ASSIGN</span><br><span style="color: hsl(120, 100%, 40%);">+TBF(TFI=5 TLLI=0xffeeddd1 DIR=UL STATE=ASSIGN EGPRS) Starting timer X2001 [assignment (PACCH)] with 2 sec. 0 microsec</span><br><span> TBF(TFI=5 TLLI=0xffeeddd1 DIR=UL STATE=ASSIGN EGPRS) change control TS 7 -> 7 until assignment is complete.</span><br><span> UL_ASS_TBF(UL-TFI_5){NONE}: Received Event SCHED_ASS</span><br><span> UL_ASS_TBF(UL-TFI_5){NONE}: state_chg to SEND_ASS</span><br><span>@@ -8885,6 +8897,7 @@</span><br><span> TBF(UL-TFI_6){NULL}: Received Event ASSIGN_ADD_PACCH</span><br><span> TBF(TFI=6 TLLI=0xffeeddd2 DIR=UL STATE=NULL EGPRS) set ass. type PACCH [prev CCCH:0, PACCH:0]</span><br><span> TBF(UL-TFI_6){NULL}: state_chg to ASSIGN</span><br><span style="color: hsl(120, 100%, 40%);">+TBF(TFI=6 TLLI=0xffeeddd2 DIR=UL STATE=ASSIGN EGPRS) Starting timer X2001 [assignment (PACCH)] with 2 sec. 0 microsec</span><br><span> TBF(TFI=6 TLLI=0xffeeddd2 DIR=UL STATE=ASSIGN EGPRS) change control TS 7 -> 7 until assignment is complete.</span><br><span> UL_ASS_TBF(UL-TFI_6){NONE}: Received Event SCHED_ASS</span><br><span> UL_ASS_TBF(UL-TFI_6){NONE}: state_chg to SEND_ASS</span><br><span>@@ -8940,6 +8953,7 @@</span><br><span> TBF{NULL}: Received Event ASSIGN_ADD_PACCH</span><br><span> TBF(TFI=0 TLLI=0xffeeddd3 DIR=UL STATE=NULL) set ass. type PACCH [prev CCCH:0, PACCH:0]</span><br><span> TBF{NULL}: state_chg to ASSIGN</span><br><span style="color: hsl(120, 100%, 40%);">+TBF(TFI=0 TLLI=0xffeeddd3 DIR=UL STATE=ASSIGN) Starting timer X2001 [assignment (PACCH)] with 2 sec. 0 microsec</span><br><span> UL_ASS_TBF{NONE}: Received Event SCHED_ASS_REJ</span><br><span> UL_ASS_TBF{NONE}: state_chg to SEND_ASS_REJ</span><br><span> PDCH(bts=0,trx=0,ts=7) Expiring FN=82 but previous FN=2654231 is still reserved!</span><br><span>@@ -8968,14 +8982,14 @@</span><br><span> TBF{NULL}: Received Event ASSIGN_ADD_PACCH</span><br><span> TBF(TFI=0 TLLI=0xffeeddcc DIR=UL STATE=NULL) set ass. type PACCH [prev CCCH:0, PACCH:0]</span><br><span> TBF{NULL}: state_chg to ASSIGN</span><br><span style="color: hsl(120, 100%, 40%);">+TBF(TFI=0 TLLI=0xffeeddcc DIR=UL STATE=ASSIGN) Starting timer X2001 [assignment (PACCH)] with 2 sec. 0 microsec</span><br><span> UL_ASS_TBF{NONE}: Received Event SCHED_ASS_REJ</span><br><span> UL_ASS_TBF{NONE}: state_chg to SEND_ASS_REJ</span><br><span> UL_ASS_TBF{SEND_ASS_REJ}: Received Event CREATE_RLCMAC_MSG</span><br><span> UL_ASS_TBF{SEND_ASS_REJ}: state_chg to NONE</span><br><span style="color: hsl(0, 100%, 40%);">-TBF(TFI=0 TLLI=0xffeeddcc DIR=UL STATE=ASSIGN) starting timer X2000 [reject (PACCH)] with 0 sec. 2000 microsec</span><br><span style="color: hsl(120, 100%, 40%);">+TBF(TFI=0 TLLI=0xffeeddcc DIR=UL STATE=ASSIGN) starting timer X2000 [reject (PACCH)] with 0 sec. 0 microsec</span><br><span> PDCH(bts=0,trx=0,ts=7) FN=2654218 Scheduling control message at RTS for TBF(TFI=0 TLLI=0xffeeddcc DIR=UL STATE=ASSIGN)</span><br><span style="color: hsl(0, 100%, 40%);">-TBF(TFI=0 TLLI=0xffeeddcc DIR=UL STATE=ASSIGN) timer 0 expired. cur_fn=2654167</span><br><span style="color: hsl(0, 100%, 40%);">-TBF(TFI=0 TLLI=0xffeeddcc DIR=UL STATE=ASSIGN) releasing due to PACCH assignment timeout.</span><br><span style="color: hsl(120, 100%, 40%);">+UL_ASS_TBF{NONE}: Timeout of X2000</span><br><span> TBF(TFI=0 TLLI=0xffeeddcc DIR=UL STATE=ASSIGN) free</span><br><span> MS(TLLI=0xffeeddcc, IMSI=, TA=220, 0/0,) Detaching TBF: TBF(TFI=0 TLLI=0xffeeddcc DIR=UL STATE=ASSIGN)</span><br><span> MS(TLLI=0xffeeddcc, IMSI=, TA=220, 0/0,) Destroying MS object</span><br><span></span><br></pre><p>To view, visit <a href="https://gerrit.osmocom.org/c/osmo-pcu/+/25092">change 25092</a>. To unsubscribe, or for help writing mail filters, visit <a href="https://gerrit.osmocom.org/settings">settings</a>.</p><div itemscope itemtype="http://schema.org/EmailMessage"><div itemscope itemprop="action" itemtype="http://schema.org/ViewAction"><link itemprop="url" href="https://gerrit.osmocom.org/c/osmo-pcu/+/25092"/><meta itemprop="name" content="View Change"/></div></div>
<div style="display:none"> Gerrit-Project: osmo-pcu </div>
<div style="display:none"> Gerrit-Branch: master </div>
<div style="display:none"> Gerrit-Change-Id: Ib8ab2f7397ad05c6fcd5dd74af55a1e2c56e1463 </div>
<div style="display:none"> Gerrit-Change-Number: 25092 </div>
<div style="display:none"> Gerrit-PatchSet: 1 </div>
<div style="display:none"> Gerrit-Owner: pespin <pespin@sysmocom.de> </div>
<div style="display:none"> Gerrit-MessageType: newchange </div>