From 16d29c7da4ee516b4ef0a6ec18cec18f8a76d7fa Mon Sep 17 00:00:00 2001 From: Jacob Erlbeck Date: Mon, 24 Aug 2015 13:30:39 +0200 Subject: tbf: Add logging for polling This commit adds the relevant frame number to the "poll timeout" logging message. In addition, logging is added to the places where poll_fn gets set. The goal is to track down the source for frequent "poll timeout" messages. Sponsored-by: On-Waves ehf --- src/tbf.cpp | 10 ++++++++-- src/tbf_dl.cpp | 4 ++++ 2 files changed, 12 insertions(+), 2 deletions(-) (limited to 'src') diff --git a/src/tbf.cpp b/src/tbf.cpp index 29aed154..42d522bd 100644 --- a/src/tbf.cpp +++ b/src/tbf.cpp @@ -430,8 +430,8 @@ void gprs_rlcmac_tbf::stop_timer() void gprs_rlcmac_tbf::poll_timeout() { - LOGP(DRLCMAC, LOGL_NOTICE, "%s poll timeout\n", - tbf_name(this)); + LOGP(DRLCMAC, LOGL_NOTICE, "%s poll timeout for FN=%d (curr FN %d)\n", + tbf_name(this), poll_fn, bts->current_frame_number()); poll_state = GPRS_RLCMAC_POLL_NONE; @@ -864,6 +864,9 @@ struct msgb *gprs_rlcmac_tbf::create_dl_ass(uint32_t fn) poll_state = GPRS_RLCMAC_POLL_SCHED; poll_fn = (fn + 13) % 2715648; dl_ass_state = GPRS_RLCMAC_DL_ASS_WAIT_ACK; + LOGP(DRLCMACDL, LOGL_INFO, + "%s Scheduled DL Assignment polling on FN=%d\n", + name(), poll_fn); } else { dl_ass_state = GPRS_RLCMAC_DL_ASS_NONE; new_dl_tbf->set_state(GPRS_RLCMAC_FLOW); @@ -930,6 +933,9 @@ struct msgb *gprs_rlcmac_tbf::create_ul_ass(uint32_t fn) poll_state = GPRS_RLCMAC_POLL_SCHED; poll_fn = (fn + 13) % 2715648; ul_ass_state = GPRS_RLCMAC_UL_ASS_WAIT_ACK; + LOGP(DRLCMACDL, LOGL_INFO, + "%s Scheduled UL Assignment polling on FN=%d\n", + name(), poll_fn); return msg; } diff --git a/src/tbf_dl.cpp b/src/tbf_dl.cpp index 39a1f334..b57e10e0 100644 --- a/src/tbf_dl.cpp +++ b/src/tbf_dl.cpp @@ -686,6 +686,10 @@ struct msgb *gprs_rlcmac_dl_tbf::create_dl_acked_block( rh->s_p = 1; /* Polling */ m_last_dl_poll_fn = poll_fn; + + LOGP(DRLCMACDL, LOGL_INFO, + "%s Scheduled Ack/Nack polling on FN=%d, TS=%d\n", + name(), poll_fn, ts); } } -- cgit v1.2.3