osmocom-net-gprs

osmocom-net-gprs@lists.osmocom.org

482 discussions

Invalid decoding of some PACKET_RESOURCE_REQUESTs

by Steve Markgraf

Hi, I noticed a bug in the dissector, which seems to happen when then Exist_A5_bits field is set to 0, and results in the following fields (Exist_Multislot_capability/Exist_GPRS_multislot_class) being decoded incorrectly. Thus, get_ms_class_by_capability() is returning NULL instead of the GPRS_multislot_class, which is interestingly dissected correctly. This is one of the messages where this happens: 4017dfb83a3f628a7045500898f28109100297e0080b2b If I add it to the RLCMACTest it fails, so I propose it should be added ;) My quite recent version of Wireshark (1.8.2) is decoding this message fine, see the attachment. I've gone through the recent changes on packet-gsm_rlcmac.c and packet-csn1.c in Wireshark, but couldn't spot the commit that fixed it so far. Regards, Steve

12 years, 5 months

Idea for synchonous logging/review of debug + GSMTAP

by Harald Welte

Hi all, one of the problems when debugging PCU issues is: How to take proper logs on-site where a problem shows up and give them back to the developer(s) for analysis. If you capture the PCU stdout or configure file-based logging, you will have a hard time corellating that with PCAP traces taken on the RLC/MAC side and/or on the Gb side. Therefore I propose a new idea here and would like to get your review: What about implementing a "GSMTAP" log target for libosmocore? This way we could encapsulate the log messages into a special GSMTAP message, which would be sent synchronously in the GSMTAP stream together with RLC/MAC messages on the lower end. The same could in fact be utilized in osmo-bts, too. This way we could capture BTS log/debug output in-order to L1 messages that we receive and transmit. What do you think about it? I would be willing to draft the GSMTAP sub-header format and write + submit the wireshark dissector, if somebody wants to write libosmocore log target. Ideally we would log _all_ messages to that target, and include the subsystem and log level in a header field, so the log verbosity could still be adjusted at the point of review, instead only at the time of logging. Regards, Harald -- - Harald Welte <laforge(a)gnumonks.org> http://laforge.gnumonks.org/ ============================================================================ "Privacy in residential applications is a desirable marketing option." (ETSI EN 300 175-7 Ch. A6)

12 years, 6 months

Default build is broken since the 17th of January

by Holger Hans Peter Freyther

Hi, the default build is broken since the 17th of october. Anynone feels responsible to fix it after the merge? Build results can be seen here[1]. It probably has to with enabling sysmobts support but not direct PDCH queue access. holger [1] http://jenkins.osmocom.org/jenkins/job/osmo-pcu/label=linux_i386_debian_squ…

12 years, 6 months

lack of progress in PCU patch merging / git (ab)use

by Harald Welte

Dear all, it is a pity to see that since many weeks there has been no progress on merging any of Andreas' work. I think everyone involved is to be blamed to some extent. Andreas because the provided branches are not kept clean, Ivan probably for lack of time, and me for not paying more attention and helping this process along. The general life-cycle in any project, including osmo-pcu, should be as follows: * contributor starts on some improvements, creates private branch * master advances meanwhile * contributor rebases (not merges!) his changes on top of more recent master * contributor indicates that his branch is ready for merge * maintainer merges (some of?) the commits of the contributor branch * contributor re-bases remaining commits on new master * contributor requests merge of remaing patches ... If I open 'gitk' on any of the branches (master / jolly / jolly_dsp) I get grey hair (to say the least). One would normally expect: 1) 'jolly' to be based on some (maybe not current) master 2) 'jolly_dsp' to be based on 'jolly' Neither of the two is the case. I've tried to * rebase jolly on top of master as well as * rebase jolly_dsp on top of jolly and both are impossible due to the series of merges and the unclear ancestry / relationship of the branches. Or, just for fun, try 'git diff origin/jolly..origin/jolly_dsp. It shows you anything else but what one would expect Andreas: Please take some time to clean up the mess. Your 'jolly' branch should contain a series of clean per-feature commit's on top of current master, and 'jolly_dsp' should be a set of few patches on top of 'jolly'. This way there is always a clean queue of changesets that Ivan can merge (or cherry-pick). I can understand that if I was in Ivan's place, I would not really know where to even start merging some of those contributions. Thanks for everyone's attention. Please take some time to get this resolved. Let me know if you have some questions. There are plenty of people with lots of git experience around (Holger, Pablo, myself) who can help if something is unclear. Regards, Harald -- - Harald Welte <laforge(a)gnumonks.org> http://laforge.gnumonks.org/ ============================================================================ "Privacy in residential applications is a desirable marketing option." (ETSI EN 300 175-7 Ch. A6)

12 years, 6 months

Suspected memory leak in PCU

by Harald Welte

Hi all, while running osmo-pcu, it appears that it is leaking memory. At startup time it consumes 4188 kBytes of virtual memory (on sysmobts-v2), while after about 8MBytes of data transfer of a single GPRS-MS over 48 hours, the virtual size has expanded to 8584 kBytes. Now I know that VSS is not RSS, etc. - but the fact that it always only increasees, and increases with traffic is a clear indication of some leakage somewehere. Unfortunately it also seems talloc is not used correctly in osmo-pcu, as a USR1 signal doesn't really show any allocated objects beyond two. So somehow either not all allocations are done with talloc, or the allocation hierarchy does not link the objects to the root talloc context. Regards, Harald -- - Harald Welte <laforge(a)gnumonks.org> http://laforge.gnumonks.org/ ============================================================================ "Privacy in residential applications is a desirable marketing option." (ETSI EN 300 175-7 Ch. A6)

12 years, 6 months

Error: PLEASE FIX

by Harald Welte

Not sure if this is expected or not, I thought it might be useful to report it here: <0001> pcu_l1_if.cpp:295 RACH request received: sapi=1 qta=2, ra=121, fn=12615 <0002> gprs_rlcmac_data.cpp:100 Poll timeout for DL TBF=1 <0005> gprs_rlcmac_data.cpp:872 Got RACH from TLLI=0xc65c8728 while DL TBF=1 still exists. Killing pending DL TBF <0002> gprs_rlcmac.cpp:905 Software error: Pending downlink assignment. This may not happen, because the assignment message never gets transmitted. Please be shure not to free in this state. PLEASE FIX! This is a single BTS with a single GPRS-capable MS, using TS6+TS7 for PDCH Regards, Harald -- - Harald Welte <laforge(a)gnumonks.org> http://laforge.gnumonks.org/ ============================================================================ "Privacy in residential applications is a desirable marketing option." (ETSI EN 300 175-7 Ch. A6)

12 years, 6 months

Re: openbts-p2.8-gprs-exp and pcu issues

by jolly

12 years, 8 months

openbts-p2.8-gprs-exp and pcu issues

by Vladimir Rolbin

Hi Ivan. More pcu logs (Nokia 5000d-2). At the begining everything is ok (PACKET CONTROL ACK with expected FN only), but from line 1248 frome the start of log something occurs and a lot of "PACKET DOWNLINK ACK with unknown FN" appears. Possibly timing problems.. <0001> pcu_l1_if.cpp:334 BTS available <0001> pcu_l1_if.cpp:335 mcc=452 <0001> pcu_l1_if.cpp:336 mnc=2 <0001> pcu_l1_if.cpp:337 lac=1000 <0001> pcu_l1_if.cpp:338 rac=0 <0001> pcu_l1_if.cpp:339 cell_id=0 <0001> pcu_l1_if.cpp:340 nsei=8 <0001> pcu_l1_if.cpp:345 nse_timer=0 0 0 0 0 0 0 <0001> pcu_l1_if.cpp:353 cell_timer=0 0 0 0 0 0 0 0 0 0 0 <0001> pcu_l1_if.cpp:354 repeat_time=5 <0001> pcu_l1_if.cpp:355 repeat_count=10 <0001> pcu_l1_if.cpp:356 bvci=7 <0001> pcu_l1_if.cpp:357 t3142=20 <0001> pcu_l1_if.cpp:358 t3169=5 <0001> pcu_l1_if.cpp:359 t3191=5 <0001> pcu_l1_if.cpp:360 t3193=1000 (ms) <0001> pcu_l1_if.cpp:361 t3195=5 <0001> pcu_l1_if.cpp:362 n3101=10 <0001> pcu_l1_if.cpp:363 n3103=4 <0001> pcu_l1_if.cpp:364 n3105=8 <0001> pcu_l1_if.cpp:365 cv_countdown=15 <0001> pcu_l1_if.cpp:366 dl_tbf_ext=0 <0001> pcu_l1_if.cpp:367 ul_tbf_ext=0 <0001> pcu_l1_if.cpp:376 initial_cs=1 <0001> pcu_l1_if.cpp:377 initial_mcs=0 <0001> pcu_l1_if.cpp:378 nsvci=4 <0001> pcu_l1_if.cpp:379 local_port=5944 <0001> pcu_l1_if.cpp:380 remote_port=23000 <0001> pcu_l1_if.cpp:381 remote_ip=2130706433 <0008> gprs_bssgp_pcu.cpp:611 bctx mcc=452 <0008> gprs_bssgp_pcu.cpp:612 bctx mnc=2 <0001> pcu_l1_if.cpp:82 Sending activate request: trx=0 ts=7 <0001> pcu_l1_if.cpp:429 PDCH: trx=0 ts=7 <0009> gprs_bssgp_pcu.cpp:491 NS-VC 4 is unblocked. <0008> gprs_bssgp_pcu.cpp:532 Sending reset on BVCI 0 <0008> gprs_bssgp_pcu.cpp:540 Sending reset on BVCI 7 <0008> gprs_bssgp_pcu.cpp:548 Sending unblock on BVCI 7 <0001> pcu_l1_if.cpp:256 RACH request received: sapi=1 qta=0, ra=123, fn=153643 <0002> gprs_rlcmac.cpp:354 Allocating UL TBF: TFI=0 TRX=0 MS_CLASS=0 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1183 TX: START TFI: 0 Immediate Assignment Uplink (AGCH) <0002> gprs_rlcmac_data.cpp:1895 Got IMM.ASS confirm, but rest octets do not start with bit sequence 'HH01' (Packet Downlink Assignment) <0005> gprs_rlcmac_data.cpp:896 Decoded premier TLLI=0xb9e1d899 of UL DATA TBF=0. <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=44 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xb9e1d899 len=44 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xb9e1d899 IMSI: 000 len: 9 <0002> gprs_rlcmac.cpp:354 Allocating DL TBF: TFI=0 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1783 TBF: START TFI: 0 TLLI: 0xb9e1d899 Packet Downlink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=153716 block_nr=1 scheduling free USF for polling at FN=153720 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=153720 TLL=0xb9e1d899 (TRX 0 TS 7) <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=0: len=9 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=153746 block_nr=8 scheduling free USF for polling at FN=153751 of UL TFI=0 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=153751 block_nr=9 scheduling free USF for polling at FN=153755 of DL TFI=0 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=153751 TLL=0xb9e1d899 (TRX 0 TS 7) <0002> gprs_rlcmac.cpp:895 Free UL TBF=0 with TLLI=0xb9e1d899. <0001> pcu_l1_if.cpp:256 RACH request received: sapi=1 qta=0, ra=124, fn=153895 <0002> gprs_rlcmac.cpp:354 Allocating UL TBF: TFI=0 TRX=0 MS_CLASS=0 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1183 TX: START TFI: 0 Immediate Assignment Uplink (AGCH) <0002> gprs_rlcmac_data.cpp:1895 Got IMM.ASS confirm, but rest octets do not start with bit sequence 'HH01' (Packet Downlink Assignment) <0005> gprs_rlcmac_data.cpp:896 Decoded premier TLLI=0xb9e1d899 of UL DATA TBF=0. <0005> gprs_rlcmac_data.cpp:901 Got RACH from TLLI=0xb9e1d899 while DL TBF=0 still exists. Killing pending DL TBF <0002> gprs_rlcmac.cpp:895 Free DL TBF=0 with TLLI=0xb9e1d899. <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=17 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xb9e1d899 len=17 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xb9e1d899 IMSI: 000 len: 9 <0002> gprs_rlcmac.cpp:354 Allocating DL TBF: TFI=0 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1820 TX: START TFI: 0 TLLI: 0xb9e1d899 Immediate Assignment Downlink (PCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=153963 block_nr=10 scheduling free USF for polling at FN=153967 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=153967 TLL=0xb9e1d899 (TRX 0 TS 7) <0002> gprs_rlcmac.cpp:895 Free UL TBF=0 with TLLI=0xb9e1d899. <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=0: len=9 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=154011 block_nr=9 scheduling free USF for polling at FN=154015 of DL TFI=0 <0001> pcu_l1_if.cpp:256 RACH request received: sapi=1 qta=0, ra=120, fn=154136 <0002> gprs_rlcmac.cpp:354 Allocating UL TBF: TFI=0 TRX=0 MS_CLASS=0 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1183 TX: START TFI: 0 Immediate Assignment Uplink (AGCH) <0002> gprs_rlcmac_data.cpp:1895 Got IMM.ASS confirm, but rest octets do not start with bit sequence 'HH01' (Packet Downlink Assignment) <0005> gprs_rlcmac_data.cpp:896 Decoded premier TLLI=0xb9e1d899 of UL DATA TBF=0. <0005> gprs_rlcmac_data.cpp:901 Got RACH from TLLI=0xb9e1d899 while DL TBF=0 still exists. Killing pending DL TBF <0002> gprs_rlcmac.cpp:895 Free DL TBF=0 with TLLI=0xb9e1d899. <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=17 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xb9e1d899 len=17 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xb9e1d899 IMSI: 452022112638582 len: 24 <0002> gprs_rlcmac.cpp:354 Allocating DL TBF: TFI=0 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1783 TBF: START TFI: 0 TLLI: 0xb9e1d899 Packet Downlink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=154223 block_nr=10 scheduling free USF for polling at FN=154227 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=154227 TLL=0xb9e1d899 (TRX 0 TS 7) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=154249 block_nr=4 scheduling free USF for polling at FN=154253 of UL TFI=0 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=0: len=24 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=154258 block_nr=6 scheduling free USF for polling at FN=154262 of DL TFI=0 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=154253 TLL=0xb9e1d899 (TRX 0 TS 7) <0002> gprs_rlcmac.cpp:895 Free UL TBF=0 with TLLI=0xb9e1d899. <0001> pcu_l1_if.cpp:256 RACH request received: sapi=1 qta=0, ra=124, fn=154413 <0002> gprs_rlcmac.cpp:354 Allocating UL TBF: TFI=0 TRX=0 MS_CLASS=0 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1183 TX: START TFI: 0 Immediate Assignment Uplink (AGCH) <0002> gprs_rlcmac_data.cpp:1895 Got IMM.ASS confirm, but rest octets do not start with bit sequence 'HH01' (Packet Downlink Assignment) <0005> gprs_rlcmac_data.cpp:896 Decoded premier TLLI=0xe75a5338 of UL DATA TBF=0. <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=8 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=8 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=154470 block_nr=7 scheduling free USF for polling at FN=154474 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=154474 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac.cpp:895 Free UL TBF=0 with TLLI=0xe75a5338. <0002> gprs_rlcmac.cpp:895 Free DL TBF=0 with TLLI=0xb9e1d899. <0001> pcu_l1_if.cpp:256 RACH request received: sapi=1 qta=0, ra=125, fn=154507 <0002> gprs_rlcmac.cpp:354 Allocating UL TBF: TFI=0 TRX=0 MS_CLASS=0 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1183 TX: START TFI: 0 Immediate Assignment Uplink (AGCH) <0002> gprs_rlcmac_data.cpp:1895 Got IMM.ASS confirm, but rest octets do not start with bit sequence 'HH01' (Packet Downlink Assignment) <0005> gprs_rlcmac_data.cpp:896 Decoded premier TLLI=0xe75a5338 of UL DATA TBF=0. <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=59 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=59 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 55 <0002> gprs_rlcmac.cpp:354 Allocating DL TBF: TFI=0 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1783 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Downlink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=154587 block_nr=10 scheduling free USF for polling at FN=154591 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=154591 TLL=0xe75a5338 (TRX 0 TS 7) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=154613 block_nr=4 scheduling free USF for polling at FN=154617 of UL TFI=0 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=0: len=55 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=154626 block_nr=7 scheduling free USF for polling at FN=154630 of DL TFI=0 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=154617 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac.cpp:895 Free UL TBF=0 with TLLI=0xe75a5338. <0001> pcu_l1_if.cpp:256 RACH request received: sapi=1 qta=0, ra=112, fn=154767 <0002> gprs_rlcmac_data.cpp:1157 TX: Immediate Assignment Uplink (AGCH) <0002> gprs_rlcmac_data.cpp:1895 Got IMM.ASS confirm, but rest octets do not start with bit sequence 'HH01' (Packet Downlink Assignment) <0005> gprs_rlcmac_data.cpp:456 Got RACH from TLLI=0xe75a5338 while DL TBF=0 still exists. Killing pending DL TBF <0002> gprs_rlcmac.cpp:895 Free DL TBF=0 with TLLI=0xe75a5338. <0002> gprs_rlcmac.cpp:354 Allocating UL TBF: TFI=0 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=154851 block_nr=11 scheduling free USF for polling at FN=154856 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=154856 TLL=0xe75a5338 (TRX 0 TS 7) <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=70 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=70 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=154912 block_nr=1 scheduling free USF for polling at FN=154916 of UL TFI=0 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 107 <0002> gprs_rlcmac.cpp:354 Allocating DL TBF: TFI=0 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1820 TX: START TFI: 0 TLLI: 0xe75a5338 Immediate Assignment Downlink (PCH) <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=154916 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac.cpp:895 Free UL TBF=0 with TLLI=0xe75a5338. <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=0: len=107 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=155003 block_nr=10 scheduling free USF for polling at FN=155007 of DL TFI=0 <0001> pcu_l1_if.cpp:256 RACH request received: sapi=1 qta=0, ra=117, fn=155163 <0002> gprs_rlcmac_data.cpp:1157 TX: Immediate Assignment Uplink (AGCH) <0002> gprs_rlcmac_data.cpp:1895 Got IMM.ASS confirm, but rest octets do not start with bit sequence 'HH01' (Packet Downlink Assignment) <0002> gprs_rlcmac.cpp:895 Free DL TBF=0 with TLLI=0xe75a5338. <0002> gprs_rlcmac.cpp:354 Allocating UL TBF: TFI=0 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=155250 block_nr=7 scheduling free USF for polling at FN=155254 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=155254 TLL=0xe75a5338 (TRX 0 TS 7) <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=66 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=66 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=155315 block_nr=10 scheduling free USF for polling at FN=155319 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:500 RX: [PCU <- BTS] FIXME: Packet uplink dummy control block <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 66 <0002> gprs_rlcmac.cpp:354 Allocating DL TBF: TFI=0 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1820 TX: START TFI: 0 TLLI: 0xe75a5338 Immediate Assignment Downlink (PCH) <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=155319 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac.cpp:895 Free UL TBF=0 with TLLI=0xe75a5338. <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=0: len=66 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=155393 block_nr=4 scheduling free USF for polling at FN=155397 of DL TFI=0 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 66 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 0 TLLI: 0xe75a5338 <0002> gprs_rlcmac_data.cpp:1783 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Downlink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=155458 block_nr=7 scheduling free USF for polling at FN=155462 of DL TFI=0 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=155462 TLL=0xe75a5338 (TRX 0 TS 7) <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=0: len=66 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=155501 block_nr=5 scheduling free USF for polling at FN=155506 of DL TFI=0 <0002> gprs_rlcmac.cpp:354 Allocating UL TBF: TFI=0 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=155532 block_nr=0 scheduling free USF for polling at FN=155536 of DL TFI=0 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=155536 TLL=0xe75a5338 (TRX 0 TS 7) <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 66 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 0 TLLI: 0xe75a5338 <0002> gprs_rlcmac_data.cpp:1783 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Downlink Assignment (PACCH) <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=62 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=62 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=155588 block_nr=1 scheduling free USF for polling at FN=155592 of DL TFI=0 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=155592 TLL=0xe75a5338 (TRX 0 TS 7) <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=0: len=66 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=155627 block_nr=10 scheduling free USF for polling at FN=155631 of DL TFI=0 <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=506 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=506 <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=125 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=125 <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=62 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=62 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 62 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 0 TLLI: 0xe75a5338 <0002> gprs_rlcmac_data.cpp:1783 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Downlink Assignment (PACCH) <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=62 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=62 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=155761 block_nr=5 scheduling free USF for polling at FN=155766 of DL TFI=0 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=155770 block_nr=7 scheduling free USF for polling at FN=155774 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:500 RX: [PCU <- BTS] FIXME: Packet uplink dummy control block <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 0 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 0 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 416 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 0 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 214 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 0 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 0 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 414 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 0 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 79 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 0 TLLI: 0xe75a5338 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=155774 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac.cpp:895 Free UL TBF=0 with TLLI=0xe75a5338. <0002> gprs_rlcmac_data.cpp:100 Poll timeout for DL TBF=0 <0002> gprs_rlcmac_data.cpp:158 - Timeout for polling PACKET CONTROL ACK for PACKET DOWNLINK ASSIGNMENT. <0002> gprs_rlcmac_data.cpp:84 - Assignment was on PACCH <0002> gprs_rlcmac_data.cpp:92 - No downlink ACK received yet <0002> gprs_rlcmac_data.cpp:1783 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Downlink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=155896 block_nr=0 scheduling free USF for polling at FN=155900 of DL TFI=0 <0002> gprs_rlcmac_data.cpp:100 Poll timeout for DL TBF=0 <0002> gprs_rlcmac_data.cpp:1783 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Downlink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=156008 block_nr=2 scheduling free USF for polling at FN=156013 of DL TFI=0 <0002> gprs_rlcmac_data.cpp:100 Poll timeout for DL TBF=0 <0002> gprs_rlcmac_data.cpp:1783 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Downlink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=156078 block_nr=6 scheduling free USF for polling at FN=156082 of DL TFI=0 <0002> gprs_rlcmac_data.cpp:100 Poll timeout for DL TBF=0 <0002> gprs_rlcmac_data.cpp:1783 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Downlink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=156143 block_nr=9 scheduling free USF for polling at FN=156147 of DL TFI=0 <0002> gprs_rlcmac_data.cpp:534 Releasing due to PACCH assignment timeout. <0002> gprs_rlcmac.cpp:895 Free DL TBF=0 with TLLI=0xe75a5338. <0002> gprs_rlcmac.cpp:905 Software error: Pending downlink assignment. This may not happen, because the assignment message never gets transmitted. Please be shure not to free in this state. PLEASE FIX! <0001> pcu_l1_if.cpp:256 RACH request received: sapi=1 qta=0, ra=119, fn=156360 <0002> gprs_rlcmac_data.cpp:1157 TX: Immediate Assignment Uplink (AGCH) <0002> gprs_rlcmac_data.cpp:1895 Got IMM.ASS confirm, but rest octets do not start with bit sequence 'HH01' (Packet Downlink Assignment) <0002> gprs_rlcmac.cpp:354 Allocating UL TBF: TFI=0 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=156446 block_nr=7 scheduling free USF for polling at FN=156450 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=156450 TLL=0xe75a5338 (TRX 0 TS 7) <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=506 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=506 <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=125 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=125 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_rlcmac.cpp:354 Allocating DL TBF: TFI=0 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1820 TX: START TFI: 0 TLLI: 0xe75a5338 Immediate Assignment Downlink (PCH) <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 0 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 416 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 0 TLLI: 0xe75a5338 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=156632 block_nr=2 scheduling free USF for polling at FN=156637 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:500 RX: [PCU <- BTS] FIXME: Packet uplink dummy control block <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 62 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 0 TLLI: 0xe75a5338 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=156637 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac.cpp:895 Free UL TBF=0 with TLLI=0xe75a5338. <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=156771 block_nr=10 scheduling free USF for polling at FN=156775 of DL TFI=0 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=0: len=506 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=0 (len=506) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=156858 block_nr=6 scheduling free USF for polling at FN=156862 of DL TFI=0 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=0: len=506 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=0 (len=416) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=156944 block_nr=2 scheduling free USF for polling at FN=156949 of DL TFI=0 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=0: len=416 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=0 (len=62) <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=0: len=62 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=157027 block_nr=9 scheduling free USF for polling at FN=157031 of DL TFI=0 <0001> pcu_l1_if.cpp:256 RACH request received: sapi=1 qta=0, ra=118, fn=157151 <0002> gprs_rlcmac_data.cpp:1157 TX: Immediate Assignment Uplink (AGCH) <0002> gprs_rlcmac_data.cpp:1895 Got IMM.ASS confirm, but rest octets do not start with bit sequence 'HH01' (Packet Downlink Assignment) <0005> gprs_rlcmac_data.cpp:456 Got RACH from TLLI=0xe75a5338 while DL TBF=0 still exists. Killing pending DL TBF <0002> gprs_rlcmac.cpp:895 Free DL TBF=0 with TLLI=0xe75a5338. <0002> gprs_rlcmac.cpp:354 Allocating UL TBF: TFI=0 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=157235 block_nr=9 scheduling free USF for polling at FN=157239 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=157239 TLL=0xe75a5338 (TRX 0 TS 7) <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=62 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=62 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=157300 block_nr=0 scheduling free USF for polling at FN=157304 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:500 RX: [PCU <- BTS] FIXME: Packet uplink dummy control block <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_rlcmac.cpp:354 Allocating DL TBF: TFI=0 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1820 TX: START TFI: 0 TLLI: 0xe75a5338 Immediate Assignment Downlink (PCH) <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 0 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 86 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 0 TLLI: 0xe75a5338 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=157304 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac.cpp:895 Free UL TBF=0 with TLLI=0xe75a5338. <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=157451 block_nr=11 scheduling free USF for polling at FN=157456 of DL TFI=0 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=0: len=506 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=0 (len=506) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=157538 block_nr=7 scheduling free USF for polling at FN=157542 of DL TFI=0 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=0: len=506 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=0 (len=86) <0004> gprs_rlcmac_data.cpp:1412 Complete DL frame for TBF=0 that fits precisely in last block: len=86 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=157625 block_nr=3 scheduling free USF for polling at FN=157629 of DL TFI=0 <0002> gprs_rlcmac.cpp:354 Allocating UL TBF: TFI=0 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=157655 block_nr=10 scheduling free USF for polling at FN=157659 of DL TFI=0 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=157659 TLL=0xe75a5338 (TRX 0 TS 7) <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=62 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=62 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=157720 block_nr=1 scheduling free USF for polling at FN=157724 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:500 RX: [PCU <- BTS] FIXME: Packet uplink dummy control block <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=157724 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac.cpp:895 Free UL TBF=0 with TLLI=0xe75a5338. <0001> pcu_l1_if.cpp:256 RACH request received: sapi=1 qta=0, ra=112, fn=157748 <0002> gprs_rlcmac_data.cpp:1157 TX: Immediate Assignment Uplink (AGCH) <0002> gprs_rlcmac_data.cpp:1895 Got IMM.ASS confirm, but rest octets do not start with bit sequence 'HH01' (Packet Downlink Assignment) <0001> pcu_l1_if.cpp:256 RACH request received: sapi=1 qta=0, ra=116, fn=157823 <0002> gprs_rlcmac_data.cpp:1157 TX: Immediate Assignment Uplink (AGCH) <0002> gprs_rlcmac_data.cpp:1895 Got IMM.ASS confirm, but rest octets do not start with bit sequence 'HH01' (Packet Downlink Assignment) <0002> gprs_rlcmac.cpp:895 Free DL TBF=0 with TLLI=0xe75a5338. <0001> pcu_l1_if.cpp:256 RACH request received: sapi=1 qta=0, ra=116, fn=157931 <0002> gprs_rlcmac_data.cpp:1157 TX: Immediate Assignment Uplink (AGCH) <0002> gprs_rlcmac_data.cpp:1895 Got IMM.ASS confirm, but rest octets do not start with bit sequence 'HH01' (Packet Downlink Assignment) <0001> pcu_l1_if.cpp:256 RACH request received: sapi=1 qta=0, ra=114, fn=158014 <0002> gprs_rlcmac_data.cpp:1157 TX: Immediate Assignment Uplink (AGCH) <0002> gprs_rlcmac_data.cpp:1895 Got IMM.ASS confirm, but rest octets do not start with bit sequence 'HH01' (Packet Downlink Assignment) <0001> pcu_l1_if.cpp:256 RACH request received: sapi=1 qta=0, ra=119, fn=158768 <0002> gprs_rlcmac_data.cpp:1157 TX: Immediate Assignment Uplink (AGCH) <0002> gprs_rlcmac_data.cpp:1895 Got IMM.ASS confirm, but rest octets do not start with bit sequence 'HH01' (Packet Downlink Assignment) <0001> pcu_l1_if.cpp:256 RACH request received: sapi=1 qta=0, ra=118, fn=158831 <0002> gprs_rlcmac_data.cpp:1157 TX: Immediate Assignment Uplink (AGCH) <0002> gprs_rlcmac_data.cpp:1895 Got IMM.ASS confirm, but rest octets do not start with bit sequence 'HH01' (Packet Downlink Assignment) <0001> pcu_l1_if.cpp:256 RACH request received: sapi=1 qta=0, ra=113, fn=158946 <0002> gprs_rlcmac_data.cpp:1157 TX: Immediate Assignment Uplink (AGCH) <0002> gprs_rlcmac_data.cpp:1895 Got IMM.ASS confirm, but rest octets do not start with bit sequence 'HH01' (Packet Downlink Assignment) <0001> pcu_l1_if.cpp:256 RACH request received: sapi=1 qta=0, ra=113, fn=159038 <0002> gprs_rlcmac_data.cpp:1157 TX: Immediate Assignment Uplink (AGCH) <0002> gprs_rlcmac_data.cpp:1895 Got IMM.ASS confirm, but rest octets do not start with bit sequence 'HH01' (Packet Downlink Assignment) <0001> pcu_l1_if.cpp:256 RACH request received: sapi=1 qta=0, ra=114, fn=159853 <0002> gprs_rlcmac_data.cpp:1157 TX: Immediate Assignment Uplink (AGCH) <0002> gprs_rlcmac_data.cpp:1895 Got IMM.ASS confirm, but rest octets do not start with bit sequence 'HH01' (Packet Downlink Assignment) <0002> gprs_rlcmac.cpp:354 Allocating UL TBF: TFI=0 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=159939 block_nr=9 scheduling free USF for polling at FN=159943 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=159943 TLL=0xe75a5338 (TRX 0 TS 7) <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=75 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=75 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=159995 block_nr=10 scheduling free USF for polling at FN=159999 of UL TFI=0 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 185 <0002> gprs_rlcmac.cpp:354 Allocating DL TBF: TFI=0 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1820 TX: START TFI: 0 TLLI: 0xe75a5338 Immediate Assignment Downlink (PCH) <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=159999 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac.cpp:895 Free UL TBF=0 with TLLI=0xe75a5338. <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=0: len=185 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=160103 block_nr=11 scheduling free USF for polling at FN=160108 of DL TFI=0 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=160190 block_nr=7 scheduling free USF for polling at FN=160194 of DL TFI=0 <0002> gprs_rlcmac.cpp:354 Allocating UL TBF: TFI=0 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=160220 block_nr=2 scheduling free USF for polling at FN=160225 of DL TFI=0 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=160225 TLL=0xe75a5338 (TRX 0 TS 7) <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=66 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=66 <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=66 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=66 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 66 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 0 TLLI: 0xe75a5338 <0002> gprs_rlcmac_data.cpp:1783 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Downlink Assignment (PACCH) <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=66 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=66 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=160307 block_nr=10 scheduling free USF for polling at FN=160311 of DL TFI=0 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 66 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 0 TLLI: 0xe75a5338 <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=66 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=66 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=160311 TLL=0xe75a5338 (TRX 0 TS 7) <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 66 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 0 TLLI: 0xe75a5338 <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=66 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=66 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 66 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 0 TLLI: 0xe75a5338 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=0: len=66 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=0 (len=66) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=160346 block_nr=7 scheduling free USF for polling at FN=160350 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:500 RX: [PCU <- BTS] FIXME: Packet uplink dummy control block <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=0: len=66 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=0 (len=66) <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 66 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 0 TLLI: 0xe75a5338 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=160350 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac.cpp:895 Free UL TBF=0 with TLLI=0xe75a5338. <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=0: len=66 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=0 (len=66) <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=0: len=66 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=0 (len=66) <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=0: len=66 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=160407 block_nr=9 scheduling free USF for polling at FN=160411 of DL TFI=0 <0002> gprs_rlcmac.cpp:354 Allocating UL TBF: TFI=0 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=160437 block_nr=4 scheduling free USF for polling at FN=160441 of DL TFI=0 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=160441 TLL=0xe75a5338 (TRX 0 TS 7) <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=62 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=62 <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=506 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=506 <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=17 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=17 <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=62 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=62 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 66 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 0 TLLI: 0xe75a5338 <0002> gprs_rlcmac_data.cpp:1783 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Downlink Assignment (PACCH) <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 62 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 0 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 0 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 281 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 0 TLLI: 0xe75a5338 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=160632 block_nr=1 scheduling free USF for polling at FN=160636 of DL TFI=0 <0002> gprs_rlcmac_data.cpp:100 Poll timeout for DL TBF=0 <0002> gprs_rlcmac_data.cpp:158 - Timeout for polling PACKET CONTROL ACK for PACKET DOWNLINK ASSIGNMENT. <0002> gprs_rlcmac_data.cpp:84 - Assignment was on PACCH <0002> gprs_rlcmac_data.cpp:92 - No downlink ACK received yet <0002> gprs_rlcmac_data.cpp:1783 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Downlink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=160675 block_nr=11 scheduling free USF for polling at FN=160680 of DL TFI=0 <0002> gprs_rlcmac_data.cpp:100 Poll timeout for DL TBF=0 <0002> gprs_rlcmac_data.cpp:1783 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Downlink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=160723 block_nr=10 scheduling free USF for polling at FN=160727 of DL TFI=0 <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=506 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=506 <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=17 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=17 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 66 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 0 TLLI: 0xe75a5338 <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=62 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=62 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 62 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 0 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 0 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 0 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 416 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 0 TLLI: 0xe75a5338 <0002> gprs_rlcmac_data.cpp:100 Poll timeout for DL TBF=0 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 0 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 0 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 416 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 0 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 0 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 0 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 416 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 0 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 0 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 0 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 416 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 0 TLLI: 0xe75a5338 <0002> gprs_rlcmac_data.cpp:1783 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Downlink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=160771 block_nr=9 scheduling free USF for polling at FN=160775 of DL TFI=0 <0002> gprs_rlcmac_data.cpp:100 Poll timeout for DL TBF=0 <0002> gprs_rlcmac_data.cpp:1783 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Downlink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=160823 block_nr=9 scheduling free USF for polling at FN=160827 of DL TFI=0 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 66 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 0 TLLI: 0xe75a5338 <0002> gprs_rlcmac_data.cpp:100 Poll timeout for DL TBF=0 <0002> gprs_rlcmac_data.cpp:1783 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Downlink Assignment (PACCH) <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=506 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=506 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=160875 block_nr=9 scheduling free USF for polling at FN=160879 of DL TFI=0 <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=17 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=17 <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=62 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=62 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 0 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 281 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 0 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 62 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 0 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 0 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 452 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 0 TLLI: 0xe75a5338 <0002> gprs_rlcmac_data.cpp:100 Poll timeout for DL TBF=0 <0002> gprs_rlcmac_data.cpp:1783 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Downlink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=160927 block_nr=9 scheduling free USF for polling at FN=160931 of DL TFI=0 <0002> gprs_rlcmac_data.cpp:100 Poll timeout for DL TBF=0 <0002> gprs_rlcmac_data.cpp:1783 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Downlink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=160970 block_nr=7 scheduling free USF for polling at FN=160974 of DL TFI=0 <0002> gprs_rlcmac_data.cpp:100 Poll timeout for DL TBF=0 <0002> gprs_rlcmac_data.cpp:166 - N3105 exceeded <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=506 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=506 <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=17 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=17 <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=62 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=62 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 62 <0002> gprs_rlcmac.cpp:354 Allocating DL TBF: TFI=1 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 416 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 371 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0002> gprs_rlcmac_data.cpp:1783 TBF: START TFI: 1 TLLI: 0xe75a5338 Packet Downlink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=161048 block_nr=1 scheduling free USF for polling at FN=161052 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=161052 TLL=0xe75a5338 (TRX 0 TS 7) <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=62 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=506) <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=381 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=381 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=161126 block_nr=7 scheduling free USF for polling at FN=161130 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:500 RX: [PCU <- BTS] FIXME: Packet uplink dummy control block <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 62 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 487 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 416 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=161130 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac.cpp:895 Free UL TBF=0 with TLLI=0xe75a5338. <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=161169 block_nr=5 scheduling free USF for polling at FN=161174 of DL TFI=1 <0002> gprs_rlcmac.cpp:354 Allocating UL TBF: TFI=0 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=161200 block_nr=0 scheduling free USF for polling at FN=161204 of DL TFI=1 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=506 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=506) <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=161204 TLL=0xe75a5338 (TRX 0 TS 7) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=161260 block_nr=2 scheduling free USF for polling at FN=161265 of DL TFI=1 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=506 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=416) <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=506 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=506 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=161351 block_nr=11 scheduling free USF for polling at FN=161356 of DL TFI=1 <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=17 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=17 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=161369 block_nr=3 scheduling free USF for polling at FN=161373 of UL TFI=0 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 74 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=161373 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac.cpp:895 Free UL TBF=0 with TLLI=0xe75a5338. <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=416 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=506) <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 452 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 281 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=161442 block_nr=8 scheduling free USF for polling at FN=161447 of DL TFI=1 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=506 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=506) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=161529 block_nr=4 scheduling free USF for polling at FN=161533 of DL TFI=1 <0002> gprs_rlcmac.cpp:354 Allocating UL TBF: TFI=0 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=161555 block_nr=10 scheduling free USF for polling at FN=161559 of DL TFI=1 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=161559 TLL=0xe75a5338 (TRX 0 TS 7) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=161620 block_nr=1 scheduling free USF for polling at FN=161624 of DL TFI=1 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=506 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=371) <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 416 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=506 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=506 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=161711 block_nr=10 scheduling free USF for polling at FN=161715 of DL TFI=1 <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=17 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=17 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=371 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=62) <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=62 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=62 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=62 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=506) <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 74 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=161802 block_nr=7 scheduling free USF for polling at FN=161806 of DL TFI=1 <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=506 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=506 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=506 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=487) <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=17 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=17 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 74 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 487 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=161893 block_nr=4 scheduling free USF for polling at FN=161897 of DL TFI=1 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 416 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=381 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=381 <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=62 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=62 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=161958 block_nr=7 scheduling free USF for polling at FN=161962 of UL TFI=0 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=487 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=506) <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 74 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0002> gprs_rlcmac_data.cpp:500 RX: [PCU <- BTS] FIXME: Packet uplink dummy control block <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=161962 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac.cpp:895 Free UL TBF=0 with TLLI=0xe75a5338. <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=161984 block_nr=1 scheduling free USF for polling at FN=161988 of DL TFI=1 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=162071 block_nr=9 scheduling free USF for polling at FN=162075 of DL TFI=1 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=506 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=506) <0002> gprs_rlcmac_data.cpp:555 TBF T3195 timeout during transsmission <0002> gprs_rlcmac_data.cpp:84 - Assignment was on PACCH <0002> gprs_rlcmac_data.cpp:92 - No downlink ACK received yet <0002> gprs_rlcmac.cpp:895 Free DL TBF=0 with TLLI=0xe75a5338. <0002> gprs_rlcmac.cpp:354 Allocating UL TBF: TFI=0 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=162101 block_nr=4 scheduling free USF for polling at FN=162105 of DL TFI=1 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=162105 TLL=0xe75a5338 (TRX 0 TS 7) <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=62 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=62 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=162162 block_nr=6 scheduling free USF for polling at FN=162166 of DL TFI=1 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=162166 block_nr=7 scheduling free USF for polling at FN=162170 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:500 RX: [PCU <- BTS] FIXME: Packet uplink dummy control block <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=162170 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac.cpp:895 Free UL TBF=0 with TLLI=0xe75a5338. <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=506 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=416) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=162253 block_nr=3 scheduling free USF for polling at FN=162257 of DL TFI=1 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=416 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=74) <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=74 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=506) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=162339 block_nr=11 scheduling free USF for polling at FN=162344 of DL TFI=1 <0002> gprs_rlcmac.cpp:354 Allocating UL TBF: TFI=0 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=162370 block_nr=6 scheduling free USF for polling at FN=162374 of DL TFI=1 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=162374 TLL=0xe75a5338 (TRX 0 TS 7) <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=506 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=452) <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 452 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=62 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=62 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=162430 block_nr=8 scheduling free USF for polling at FN=162435 of DL TFI=1 <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=62 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=62 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=162448 block_nr=0 scheduling free USF for polling at FN=162452 of UL TFI=0 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 416 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 416 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=162452 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac.cpp:895 Free UL TBF=0 with TLLI=0xe75a5338. <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=452 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=506) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=162521 block_nr=5 scheduling free USF for polling at FN=162526 of DL TFI=1 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 281 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=162608 block_nr=1 scheduling free USF for polling at FN=162612 of DL TFI=1 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=506 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=281) <0002> gprs_rlcmac.cpp:354 Allocating UL TBF: TFI=0 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=162638 block_nr=8 scheduling free USF for polling at FN=162643 of DL TFI=1 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=162643 TLL=0xe75a5338 (TRX 0 TS 7) <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=281 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=506) <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=62 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=62 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=162699 block_nr=10 scheduling free USF for polling at FN=162703 of DL TFI=1 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=162790 block_nr=7 scheduling free USF for polling at FN=162794 of DL TFI=1 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=506 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=506) <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=506 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=506 <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=17 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=17 <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=62 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=62 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=162842 block_nr=7 scheduling free USF for polling at FN=162846 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:500 RX: [PCU <- BTS] FIXME: Packet uplink dummy control block <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 74 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=162846 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac.cpp:895 Free UL TBF=0 with TLLI=0xe75a5338. <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=162881 block_nr=4 scheduling free USF for polling at FN=162885 of DL TFI=1 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=506 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=416) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=162968 block_nr=0 scheduling free USF for polling at FN=162972 of DL TFI=1 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=416 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=74) <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=74 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=74) <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=74 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=506) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=163054 block_nr=8 scheduling free USF for polling at FN=163059 of DL TFI=1 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 62 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0002> gprs_rlcmac.cpp:354 Allocating UL TBF: TFI=0 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=163085 block_nr=3 scheduling free USF for polling at FN=163089 of DL TFI=1 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=163089 TLL=0xe75a5338 (TRX 0 TS 7) <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=74 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=74 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=163145 block_nr=5 scheduling free USF for polling at FN=163150 of DL TFI=1 <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=62 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=62 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=163163 block_nr=9 scheduling free USF for polling at FN=163167 of UL TFI=0 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=506 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=487) <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 416 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 416 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 416 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=163167 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac.cpp:895 Free UL TBF=0 with TLLI=0xe75a5338. <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=163236 block_nr=2 scheduling free USF for polling at FN=163241 of DL TFI=1 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=487 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=506) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=163323 block_nr=10 scheduling free USF for polling at FN=163327 of DL TFI=1 <0002> gprs_rlcmac.cpp:354 Allocating UL TBF: TFI=0 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=163353 block_nr=5 scheduling free USF for polling at FN=163358 of DL TFI=1 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=163358 TLL=0xe75a5338 (TRX 0 TS 7) <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=506 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=506) <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=74 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=74 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=163414 block_nr=7 scheduling free USF for polling at FN=163418 of DL TFI=1 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=163418 block_nr=8 scheduling free USF for polling at FN=163423 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:500 RX: [PCU <- BTS] FIXME: Packet uplink dummy control block <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=163423 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac.cpp:895 Free UL TBF=0 with TLLI=0xe75a5338. <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=506 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=416) <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 62 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=163505 block_nr=4 scheduling free USF for polling at FN=163509 of DL TFI=1 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=416 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=74) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=163592 block_nr=0 scheduling free USF for polling at FN=163596 of DL TFI=1 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=74 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=506) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=163678 block_nr=8 scheduling free USF for polling at FN=163683 of DL TFI=1 <0002> gprs_rlcmac.cpp:354 Allocating UL TBF: TFI=0 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=163709 block_nr=3 scheduling free USF for polling at FN=163713 of DL TFI=1 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=506 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=452) <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 62 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=163713 TLL=0xe75a5338 (TRX 0 TS 7) <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=74 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=74 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=163769 block_nr=5 scheduling free USF for polling at FN=163774 of DL TFI=1 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=163774 block_nr=6 scheduling free USF for polling at FN=163778 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:500 RX: [PCU <- BTS] FIXME: Packet uplink dummy control block <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=163778 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac.cpp:895 Free UL TBF=0 with TLLI=0xe75a5338. <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=452 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=506) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=163860 block_nr=2 scheduling free USF for polling at FN=163865 of DL TFI=1 <0002> gprs_rlcmac.cpp:354 Allocating UL TBF: TFI=0 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=163891 block_nr=9 scheduling free USF for polling at FN=163895 of DL TFI=1 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=163895 TLL=0xe75a5338 (TRX 0 TS 7) <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=506 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=506) <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=74 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=74 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=163951 block_nr=11 scheduling free USF for polling at FN=163956 of DL TFI=1 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=163956 block_nr=0 scheduling free USF for polling at FN=163960 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:500 RX: [PCU <- BTS] FIXME: Packet uplink dummy control block <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=163960 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac.cpp:895 Free UL TBF=0 with TLLI=0xe75a5338. <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=164042 block_nr=8 scheduling free USF for polling at FN=164047 of DL TFI=1 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=506 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=416) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=164129 block_nr=4 scheduling free USF for polling at FN=164133 of DL TFI=1 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=416 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=506) <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 62 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=164216 block_nr=0 scheduling free USF for polling at FN=164220 of DL TFI=1 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=506 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=506) <0002> gprs_rlcmac_data.cpp:100 Poll timeout for DL TBF=1 <0002> gprs_rlcmac_data.cpp:180 - Timeout for polling PACKET DOWNLINK ACK. <0002> gprs_rlcmac_data.cpp:84 - Assignment was on PACCH <0002> gprs_rlcmac_data.cpp:90 - Downlink ACK was received <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=164328 block_nr=2 scheduling free USF for polling at FN=164333 of DL TFI=1 <0002> gprs_rlcmac_data.cpp:407 Recovered downlink ack for DL TBF=1 <0002> gprs_rlcmac.cpp:354 Allocating UL TBF: TFI=0 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=164359 block_nr=9 scheduling free USF for polling at FN=164363 of DL TFI=1 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=506 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=416) <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=164363 TLL=0xe75a5338 (TRX 0 TS 7) <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=74 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=74 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=164419 block_nr=11 scheduling free USF for polling at FN=164424 of DL TFI=1 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=164424 block_nr=0 scheduling free USF for polling at FN=164428 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:500 RX: [PCU <- BTS] FIXME: Packet uplink dummy control block <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=164428 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac.cpp:895 Free UL TBF=0 with TLLI=0xe75a5338. <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=416 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=506) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=164510 block_nr=8 scheduling free USF for polling at FN=164515 of DL TFI=1 <0002> gprs_rlcmac.cpp:354 Allocating UL TBF: TFI=0 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=164541 block_nr=3 scheduling free USF for polling at FN=164545 of DL TFI=1 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=164545 TLL=0xe75a5338 (TRX 0 TS 7) <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=506 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=281) <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=74 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=74 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=164601 block_nr=5 scheduling free USF for polling at FN=164606 of DL TFI=1 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=164606 block_nr=6 scheduling free USF for polling at FN=164610 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:500 RX: [PCU <- BTS] FIXME: Packet uplink dummy control block <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=164610 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac.cpp:895 Free UL TBF=0 with TLLI=0xe75a5338. <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=281 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=74) <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=74 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=62) <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 416 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=62 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=506) <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 62 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=164692 block_nr=2 scheduling free USF for polling at FN=164697 of DL TFI=1 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=164779 block_nr=10 scheduling free USF for polling at FN=164783 of DL TFI=1 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=506 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=506) <0002> gprs_rlcmac.cpp:354 Allocating UL TBF: TFI=0 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=164809 block_nr=5 scheduling free USF for polling at FN=164814 of DL TFI=1 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 62 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=164814 TLL=0xe75a5338 (TRX 0 TS 7) <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=74 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=74 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=164870 block_nr=7 scheduling free USF for polling at FN=164874 of DL TFI=1 <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=62 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=62 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=164887 block_nr=11 scheduling free USF for polling at FN=164892 of UL TFI=0 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 62 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=164892 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac.cpp:895 Free UL TBF=0 with TLLI=0xe75a5338. <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=506 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=416) <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 62 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=164961 block_nr=4 scheduling free USF for polling at FN=164965 of DL TFI=1 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=416 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=506) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=165048 block_nr=0 scheduling free USF for polling at FN=165052 of DL TFI=1 <0002> gprs_rlcmac.cpp:354 Allocating UL TBF: TFI=0 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=165078 block_nr=7 scheduling free USF for polling at FN=165082 of DL TFI=1 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=165082 TLL=0xe75a5338 (TRX 0 TS 7) <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=506 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=506) <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=74 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=74 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=165139 block_nr=9 scheduling free USF for polling at FN=165143 of DL TFI=1 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=165143 block_nr=10 scheduling free USF for polling at FN=165147 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:500 RX: [PCU <- BTS] FIXME: Packet uplink dummy control block <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 416 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 416 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=165147 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac.cpp:895 Free UL TBF=0 with TLLI=0xe75a5338. <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=165230 block_nr=6 scheduling free USF for polling at FN=165234 of DL TFI=1 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=506 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=416) <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 62 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=165316 block_nr=2 scheduling free USF for polling at FN=165321 of DL TFI=1 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=416 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=506) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=165403 block_nr=10 scheduling free USF for polling at FN=165407 of DL TFI=1 <0002> gprs_rlcmac.cpp:354 Allocating UL TBF: TFI=0 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=165433 block_nr=5 scheduling free USF for polling at FN=165438 of DL TFI=1 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=506 <0004> gprs_rlcmac_data.cpp:1226 Discarding LLC PDU of DL TBF=1, because lifetime limit reached <0004> gprs_rlcmac_data.cpp:1226 Discarding LLC PDU of DL TBF=1, because lifetime limit reached <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=62) <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=62 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=62) <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=165438 TLL=0xe75a5338 (TRX 0 TS 7) <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=62 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=62) <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=62 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=506) <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=74 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=74 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=165494 block_nr=7 scheduling free USF for polling at FN=165498 of DL TFI=1 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=165498 block_nr=8 scheduling free USF for polling at FN=165503 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:500 RX: [PCU <- BTS] FIXME: Packet uplink dummy control block <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 416 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=165503 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac.cpp:895 Free UL TBF=0 with TLLI=0xe75a5338. <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=165585 block_nr=4 scheduling free USF for polling at FN=165589 of DL TFI=1 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=506 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=506) <0002> gprs_rlcmac.cpp:354 Allocating UL TBF: TFI=0 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=165615 block_nr=11 scheduling free USF for polling at FN=165620 of DL TFI=1 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=165620 TLL=0xe75a5338 (TRX 0 TS 7) <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=62 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=62 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=165676 block_nr=1 scheduling free USF for polling at FN=165680 of DL TFI=1 <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=62 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=62 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 62 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=62 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=62 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 62 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=506 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=416) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=165711 block_nr=9 scheduling free USF for polling at FN=165715 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:500 RX: [PCU <- BTS] FIXME: Packet uplink dummy control block <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 62 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=165715 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac.cpp:895 Free UL TBF=0 with TLLI=0xe75a5338. <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=165767 block_nr=10 scheduling free USF for polling at FN=165771 of DL TFI=1 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=416 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=62) <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=62 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=62) <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=62 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=62) <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=62 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=62) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=165854 block_nr=6 scheduling free USF for polling at FN=165858 of DL TFI=1 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=62 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=506) <0002> gprs_rlcmac.cpp:354 Allocating UL TBF: TFI=0 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=165884 block_nr=1 scheduling free USF for polling at FN=165888 of DL TFI=1 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=165888 TLL=0xe75a5338 (TRX 0 TS 7) <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=82 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=82 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=165945 block_nr=3 scheduling free USF for polling at FN=165949 of DL TFI=1 <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=62 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=62 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=506 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=506) <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=62 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=62 <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=62 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=62 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=165997 block_nr=3 scheduling free USF for polling at FN=166001 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:500 RX: [PCU <- BTS] FIXME: Packet uplink dummy control block <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=166001 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac.cpp:895 Free UL TBF=0 with TLLI=0xe75a5338. <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=166036 block_nr=0 scheduling free USF for polling at FN=166040 of DL TFI=1 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=506 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=416) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=166122 block_nr=8 scheduling free USF for polling at FN=166127 of DL TFI=1 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=416 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=506) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=166209 block_nr=4 scheduling free USF for polling at FN=166213 of DL TFI=1 <0002> gprs_rlcmac.cpp:354 Allocating UL TBF: TFI=0 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=166235 block_nr=10 scheduling free USF for polling at FN=166239 of DL TFI=1 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=166239 TLL=0xe75a5338 (TRX 0 TS 7) <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=506 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=506) <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=74 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=74 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=166300 block_nr=1 scheduling free USF for polling at FN=166304 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:500 RX: [PCU <- BTS] FIXME: Packet uplink dummy control block <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=166304 block_nr=2 scheduling free USF for polling at FN=166309 of DL TFI=1 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 416 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=166304 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac.cpp:895 Free UL TBF=0 with TLLI=0xe75a5338. <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=166391 block_nr=10 scheduling free USF for polling at FN=166395 of DL TFI=1 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=506 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=416) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=166478 block_nr=6 scheduling free USF for polling at FN=166482 of DL TFI=1 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=416 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=62) <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=62 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=506) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=166564 block_nr=2 scheduling free USF for polling at FN=166569 of DL TFI=1 <0002> gprs_rlcmac.cpp:354 Allocating UL TBF: TFI=0 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=166595 block_nr=9 scheduling free USF for polling at FN=166599 of DL TFI=1 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=166599 TLL=0xe75a5338 (TRX 0 TS 7) <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=506 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=506) <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=74 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=74 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=166655 block_nr=11 scheduling free USF for polling at FN=166660 of DL TFI=1 <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=62 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=62 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=166673 block_nr=3 scheduling free USF for polling at FN=166677 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=166677 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac.cpp:895 Free UL TBF=0 with TLLI=0xe75a5338. <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=506 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=416) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=166746 block_nr=8 scheduling free USF for polling at FN=166751 of DL TFI=1 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=416 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=62) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=166833 block_nr=4 scheduling free USF for polling at FN=166837 of DL TFI=1 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=62 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=62) <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=62 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=62) <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=62 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=506) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=166920 block_nr=0 scheduling free USF for polling at FN=166924 of DL TFI=1 <0002> gprs_rlcmac.cpp:354 Allocating UL TBF: TFI=0 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=166950 block_nr=7 scheduling free USF for polling at FN=166954 of DL TFI=1 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=166954 TLL=0xe75a5338 (TRX 0 TS 7) <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=506 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=506) <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=74 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=74 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=167011 block_nr=9 scheduling free USF for polling at FN=167015 of DL TFI=1 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=167015 block_nr=10 scheduling free USF for polling at FN=167019 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:500 RX: [PCU <- BTS] FIXME: Packet uplink dummy control block <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 416 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=167019 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac.cpp:895 Free UL TBF=0 with TLLI=0xe75a5338. <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=506 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=416) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=167102 block_nr=6 scheduling free USF for polling at FN=167106 of DL TFI=1 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=416 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=506) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=167188 block_nr=2 scheduling free USF for polling at FN=167193 of DL TFI=1 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=167275 block_nr=10 scheduling free USF for polling at FN=167279 of DL TFI=1 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=506 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=506) <0002> gprs_rlcmac.cpp:354 Allocating UL TBF: TFI=0 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=167305 block_nr=5 scheduling free USF for polling at FN=167310 of DL TFI=1 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=167310 TLL=0xe75a5338 (TRX 0 TS 7) <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=62 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=62 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=167366 block_nr=7 scheduling free USF for polling at FN=167370 of DL TFI=1 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=167370 block_nr=8 scheduling free USF for polling at FN=167375 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:500 RX: [PCU <- BTS] FIXME: Packet uplink dummy control block <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 416 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=167375 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac.cpp:895 Free UL TBF=0 with TLLI=0xe75a5338. <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=506 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=416) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=167457 block_nr=4 scheduling free USF for polling at FN=167461 of DL TFI=1 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=416 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=506) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=167544 block_nr=0 scheduling free USF for polling at FN=167548 of DL TFI=1 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=506 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=506) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=167630 block_nr=8 scheduling free USF for polling at FN=167635 of DL TFI=1 <0002> gprs_rlcmac.cpp:354 Allocating UL TBF: TFI=0 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=167661 block_nr=3 scheduling free USF for polling at FN=167665 of DL TFI=1 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=167665 TLL=0xe75a5338 (TRX 0 TS 7) <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=62 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=62 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=167721 block_nr=5 scheduling free USF for polling at FN=167726 of DL TFI=1 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=167726 block_nr=6 scheduling free USF for polling at FN=167730 of UL TFI=0 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=506 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=416) <0002> gprs_rlcmac_data.cpp:500 RX: [PCU <- BTS] FIXME: Packet uplink dummy control block <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 416 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 490 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 1 TLLI: 0xe75a5338 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=167730 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac.cpp:895 Free UL TBF=0 with TLLI=0xe75a5338. <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=167812 block_nr=2 scheduling free USF for polling at FN=167817 of DL TFI=1 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=416 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=506) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=167899 block_nr=10 scheduling free USF for polling at FN=167903 of DL TFI=1 <0002> gprs_rlcmac.cpp:354 Allocating UL TBF: TFI=0 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=167929 block_nr=5 scheduling free USF for polling at FN=167934 of DL TFI=1 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=506 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=506) <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=167934 TLL=0xe75a5338 (TRX 0 TS 7) <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=62 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=62 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=167990 block_nr=7 scheduling free USF for polling at FN=167994 of DL TFI=1 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=167994 block_nr=8 scheduling free USF for polling at FN=167999 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:500 RX: [PCU <- BTS] FIXME: Packet uplink dummy control block <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=167999 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac.cpp:895 Free UL TBF=0 with TLLI=0xe75a5338. <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=506 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=416) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=168081 block_nr=4 scheduling free USF for polling at FN=168085 of DL TFI=1 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=416 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=506) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=168168 block_nr=0 scheduling free USF for polling at FN=168172 of DL TFI=1 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=506 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=1 (len=490) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=168254 block_nr=8 scheduling free USF for polling at FN=168259 of DL TFI=1 <0002> gprs_rlcmac.cpp:354 Allocating UL TBF: TFI=0 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=168285 block_nr=3 scheduling free USF for polling at FN=168289 of DL TFI=1 <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=168289 TLL=0xe75a5338 (TRX 0 TS 7) <0005> gprs_rlcmac_data.cpp:759 Complete UL frame for TBF=0: len=62 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0xe75a5338 len=62 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=168345 block_nr=5 scheduling free USF for polling at FN=168350 of DL TFI=1 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=168350 block_nr=6 scheduling free USF for polling at FN=168354 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:500 RX: [PCU <- BTS] FIXME: Packet uplink dummy control block <0002> gprs_rlcmac_data.cpp:307 PACKET CONTROL ACK with expected FN=168354 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac.cpp:895 Free UL TBF=0 with TLLI=0xe75a5338. <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=1: len=490 <0002> gprs_rlcmac_data.cpp:395 PACKET DOWNLINK ACK with unknown FN=168402 TFI=1 (TRX 0 TS 7) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=168423 block_nr=11 scheduling free USF for polling at FN=168428 of DL TFI=1 <0002> gprs_rlcmac_data.cpp:395 PACKET DOWNLINK ACK with unknown FN=168432 TFI=1 (TRX 0 TS 7) <0002> gprs_rlcmac_data.cpp:100 Poll timeout for DL TBF=1 <0002> gprs_rlcmac_data.cpp:180 - Timeout for polling PACKET DOWNLINK ACK. <0002> gprs_rlcmac_data.cpp:84 - Assignment was on PACCH <0002> gprs_rlcmac_data.cpp:90 - Downlink ACK was received <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=168510 block_nr=7 scheduling free USF for polling at FN=168514 of DL TFI=1 <0002> gprs_rlcmac_data.cpp:395 PACKET DOWNLINK ACK with unknown FN=168519 TFI=1 (TRX 0 TS 7) <0002> gprs_rlcmac_data.cpp:100 Poll timeout for DL TBF=1 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=168597 block_nr=3 scheduling free USF for polling at FN=168601 of DL TFI=1 <0002> gprs_rlcmac_data.cpp:395 PACKET DOWNLINK ACK with unknown FN=168605 TFI=1 (TRX 0 TS 7) <0002> gprs_rlcmac_data.cpp:100 Poll timeout for DL TBF=1 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=168683 block_nr=11 scheduling free USF for polling at FN=168688 of DL TFI=1 <0002> gprs_rlcmac_data.cpp:395 PACKET DOWNLINK ACK with unknown FN=168692 TFI=1 (TRX 0 TS 7) <0002> gprs_rlcmac_data.cpp:100 Poll timeout for DL TBF=1 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=168770 block_nr=7 scheduling free USF for polling at FN=168774 of DL TFI=1 <0002> gprs_rlcmac_data.cpp:395 PACKET DOWNLINK ACK with unknown FN=168779 TFI=1 (TRX 0 TS 7) <0002> gprs_rlcmac_data.cpp:100 Poll timeout for DL TBF=1 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=168857 block_nr=3 scheduling free USF for polling at FN=168861 of DL TFI=1 <0002> gprs_rlcmac_data.cpp:395 PACKET DOWNLINK ACK with unknown FN=168865 TFI=1 (TRX 0 TS 7) <0002> gprs_rlcmac_data.cpp:100 Poll timeout for DL TBF=1 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=168948 block_nr=0 scheduling free USF for polling at FN=168952 of DL TFI=1 <0002> gprs_rlcmac_data.cpp:395 PACKET DOWNLINK ACK with unknown FN=168956 TFI=1 (TRX 0 TS 7) <0002> gprs_rlcmac_data.cpp:100 Poll timeout for DL TBF=1 <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=169034 block_nr=8 scheduling free USF for polling at FN=169039 of DL TFI=1 <0002> gprs_rlcmac_data.cpp:395 PACKET DOWNLINK ACK with unknown FN=169043 TFI=1 (TRX 0 TS 7) <0002> gprs_rlcmac_data.cpp:100 Poll timeout for DL TBF=1 <0002> gprs_rlcmac_data.cpp:187 - N3105 exceeded <0001> pcu_l1_if.cpp:256 RACH request received: sapi=1 qta=0, ra=117, fn=169172 <0002> gprs_rlcmac_data.cpp:1157 TX: Immediate Assignment Uplink (AGCH) <0002> gprs_rlcmac_data.cpp:1895 Got IMM.ASS confirm, but rest octets do not start with bit sequence 'HH01' (Packet Downlink Assignment) <0002> gprs_rlcmac.cpp:354 Allocating UL TBF: TFI=0 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=169260 block_nr=0 scheduling free USF for polling at FN=169264 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:281 PACKET CONTROL ACK with unknown FN=169268 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac_data.cpp:100 Poll timeout for UL TBF=0 <0002> gprs_rlcmac_data.cpp:135 - Timeout for polling PACKET CONTROL ACK for PACKET UPLINK ASSIGNMENT. <0002> gprs_rlcmac_data.cpp:84 - Assignment was on PACCH <0002> gprs_rlcmac_data.cpp:88 - No uplink data received yet <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=169320 block_nr=2 scheduling free USF for polling at FN=169325 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:281 PACKET CONTROL ACK with unknown FN=169329 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac_data.cpp:100 Poll timeout for UL TBF=0 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=169411 block_nr=11 scheduling free USF for polling at FN=169416 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:281 PACKET CONTROL ACK with unknown FN=169420 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac_data.cpp:100 Poll timeout for UL TBF=0 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=169507 block_nr=9 scheduling free USF for polling at FN=169511 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:281 PACKET CONTROL ACK with unknown FN=169515 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac_data.cpp:100 Poll timeout for UL TBF=0 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=169676 block_nr=0 scheduling free USF for polling at FN=169680 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:281 PACKET CONTROL ACK with unknown FN=169684 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac_data.cpp:100 Poll timeout for UL TBF=0 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=169866 block_nr=8 scheduling free USF for polling at FN=169871 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:281 PACKET CONTROL ACK with unknown FN=169875 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac_data.cpp:100 Poll timeout for UL TBF=0 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=170022 block_nr=8 scheduling free USF for polling at FN=170027 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:281 PACKET CONTROL ACK with unknown FN=170031 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac_data.cpp:100 Poll timeout for UL TBF=0 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=170066 block_nr=6 scheduling free USF for polling at FN=170070 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:281 PACKET CONTROL ACK with unknown FN=170074 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac_data.cpp:100 Poll timeout for UL TBF=0 <0002> gprs_rlcmac_data.cpp:143 - N3105 exceeded <0002> gprs_rlcmac_data.cpp:555 TBF T3195 timeout during transsmission <0002> gprs_rlcmac_data.cpp:84 - Assignment was on PACCH <0002> gprs_rlcmac_data.cpp:90 - Downlink ACK was received <0002> gprs_rlcmac.cpp:895 Free DL TBF=1 with TLLI=0xe75a5338. <0001> pcu_l1_if.cpp:256 RACH request received: sapi=1 qta=0, ra=119, fn=170372 <0002> gprs_rlcmac_data.cpp:1157 TX: Immediate Assignment Uplink (AGCH) <0002> gprs_rlcmac_data.cpp:1895 Got IMM.ASS confirm, but rest octets do not start with bit sequence 'HH01' (Packet Downlink Assignment) <0002> gprs_rlcmac.cpp:354 Allocating UL TBF: TFI=1 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 1 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=170460 block_nr=1 scheduling free USF for polling at FN=170464 of UL TFI=1 <0002> gprs_rlcmac_data.cpp:281 PACKET CONTROL ACK with unknown FN=170469 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac_data.cpp:100 Poll timeout for UL TBF=1 <0002> gprs_rlcmac_data.cpp:135 - Timeout for polling PACKET CONTROL ACK for PACKET UPLINK ASSIGNMENT. <0002> gprs_rlcmac_data.cpp:84 - Assignment was on PACCH <0002> gprs_rlcmac_data.cpp:88 - No uplink data received yet <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 1 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=170620 block_nr=2 scheduling free USF for polling at FN=170625 of UL TFI=1 <0002> gprs_rlcmac_data.cpp:281 PACKET CONTROL ACK with unknown FN=170629 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac_data.cpp:100 Poll timeout for UL TBF=1 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 1 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=170724 block_nr=2 scheduling free USF for polling at FN=170729 of UL TFI=1 <0002> gprs_rlcmac_data.cpp:281 PACKET CONTROL ACK with unknown FN=170733 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac_data.cpp:100 Poll timeout for UL TBF=1 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 1 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=170807 block_nr=9 scheduling free USF for polling at FN=170811 of UL TFI=1 <0002> gprs_rlcmac_data.cpp:281 PACKET CONTROL ACK with unknown FN=170815 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac_data.cpp:100 Poll timeout for UL TBF=1 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 1 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=170932 block_nr=2 scheduling free USF for polling at FN=170937 of UL TFI=1 <0002> gprs_rlcmac_data.cpp:281 PACKET CONTROL ACK with unknown FN=170941 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac_data.cpp:100 Poll timeout for UL TBF=1 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 1 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=170997 block_nr=5 scheduling free USF for polling at FN=171002 of UL TFI=1 <0002> gprs_rlcmac_data.cpp:281 PACKET CONTROL ACK with unknown FN=171006 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac_data.cpp:100 Poll timeout for UL TBF=1 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 1 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=171041 block_nr=3 scheduling free USF for polling at FN=171045 of UL TFI=1 <0002> gprs_rlcmac_data.cpp:281 PACKET CONTROL ACK with unknown FN=171049 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac_data.cpp:100 Poll timeout for UL TBF=1 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 1 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=171205 block_nr=5 scheduling free USF for polling at FN=171210 of UL TFI=1 <0002> gprs_rlcmac_data.cpp:281 PACKET CONTROL ACK with unknown FN=171214 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac_data.cpp:555 TBF T3195 timeout during transsmission <0002> gprs_rlcmac_data.cpp:84 - Assignment was on PACCH <0002> gprs_rlcmac_data.cpp:88 - No uplink data received yet <0002> gprs_rlcmac.cpp:895 Free UL TBF=0 with TLLI=0xe75a5338. <0002> gprs_rlcmac_data.cpp:100 Poll timeout for UL TBF=1 <0002> gprs_rlcmac_data.cpp:143 - N3105 exceeded <0001> pcu_l1_if.cpp:256 RACH request received: sapi=1 qta=0, ra=115, fn=171569 <0002> gprs_rlcmac_data.cpp:1157 TX: Immediate Assignment Uplink (AGCH) <0002> gprs_rlcmac_data.cpp:1895 Got IMM.ASS confirm, but rest octets do not start with bit sequence 'HH01' (Packet Downlink Assignment) <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 506 <0002> gprs_rlcmac.cpp:354 Allocating DL TBF: TFI=0 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1820 TX: START TFI: 0 TLLI: 0xe75a5338 Immediate Assignment Downlink (PCH) <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0xe75a5338 IMSI: 452022112638582 len: 490 <0002> gprs_bssgp_pcu.cpp:149 TBF: APPEND TFI: 0 TLLI: 0xe75a5338 <0002> gprs_rlcmac_data.cpp:555 TBF T3195 timeout during transsmission <0002> gprs_rlcmac_data.cpp:84 - Assignment was on PACCH <0002> gprs_rlcmac_data.cpp:88 - No uplink data received yet <0002> gprs_rlcmac.cpp:895 Free UL TBF=1 with TLLI=0xe75a5338. <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=172453 block_nr=5 scheduling free USF for polling at FN=172458 of DL TFI=0 <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=0: len=506 <0004> gprs_rlcmac_data.cpp:1480 - Dequeue next LLC for TBF=0 (len=490) <0002> gprs_rlcmac_data.cpp:395 PACKET DOWNLINK ACK with unknown FN=172462 TFI=0 (TRX 0 TS 7) <0002> gprs_rlcmac_data.cpp:100 Poll timeout for DL TBF=0 <0002> gprs_rlcmac_data.cpp:180 - Timeout for polling PACKET DOWNLINK ACK. <0002> gprs_rlcmac_data.cpp:82 - Assignment was on CCCH <0002> gprs_rlcmac_data.cpp:92 - No downlink ACK received yet <0002> gprs_rlcmac_data.cpp:1820 TX: START TFI: 0 TLLI: 0xe75a5338 Immediate Assignment Downlink (PCH) <0002> gprs_rlcmac_data.cpp:548 Continue flow after IMM.ASS confirm <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=172583 block_nr=11 scheduling free USF for polling at FN=172588 of DL TFI=0 <0002> gprs_rlcmac_data.cpp:395 PACKET DOWNLINK ACK with unknown FN=172592 TFI=0 (TRX 0 TS 7) <0004> gprs_rlcmac_data.cpp:1472 Complete DL frame for TBF=0: len=490 <0002> gprs_rlcmac_data.cpp:100 Poll timeout for DL TBF=0 <0002> gprs_rlcmac_data.cpp:1820 TX: START TFI: 0 TLLI: 0xe75a5338 Immediate Assignment Downlink (PCH) <0002> gprs_rlcmac_data.cpp:548 Continue flow after IMM.ASS confirm <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=172718 block_nr=6 scheduling free USF for polling at FN=172722 of DL TFI=0 <0002> gprs_rlcmac_data.cpp:100 Poll timeout for DL TBF=0 <0002> gprs_rlcmac_data.cpp:1820 TX: START TFI: 0 TLLI: 0xe75a5338 Immediate Assignment Downlink (PCH) <0002> gprs_rlcmac_data.cpp:548 Continue flow after IMM.ASS confirm <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=172913 block_nr=3 scheduling free USF for polling at FN=172917 of DL TFI=0 <0002> gprs_rlcmac_data.cpp:395 PACKET DOWNLINK ACK with unknown FN=172921 TFI=0 (TRX 0 TS 7) <0002> gprs_rlcmac_data.cpp:100 Poll timeout for DL TBF=0 <0002> gprs_rlcmac_data.cpp:1820 TX: START TFI: 0 TLLI: 0xe75a5338 Immediate Assignment Downlink (PCH) <0002> gprs_rlcmac_data.cpp:548 Continue flow after IMM.ASS confirm <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=173043 block_nr=9 scheduling free USF for polling at FN=173047 of DL TFI=0 <0002> gprs_rlcmac_data.cpp:100 Poll timeout for DL TBF=0 <0002> gprs_rlcmac_data.cpp:1820 TX: START TFI: 0 TLLI: 0xe75a5338 Immediate Assignment Downlink (PCH) <0002> gprs_rlcmac_data.cpp:548 Continue flow after IMM.ASS confirm <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=173203 block_nr=10 scheduling free USF for polling at FN=173207 of DL TFI=0 <0002> gprs_rlcmac_data.cpp:395 PACKET DOWNLINK ACK with unknown FN=173212 TFI=0 (TRX 0 TS 7) <0002> gprs_rlcmac_data.cpp:100 Poll timeout for DL TBF=0 <0002> gprs_rlcmac_data.cpp:1820 TX: START TFI: 0 TLLI: 0xe75a5338 Immediate Assignment Downlink (PCH) <0002> gprs_rlcmac_data.cpp:548 Continue flow after IMM.ASS confirm <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=173407 block_nr=9 scheduling free USF for polling at FN=173411 of DL TFI=0 <0002> gprs_rlcmac_data.cpp:395 PACKET DOWNLINK ACK with unknown FN=173415 TFI=0 (TRX 0 TS 7) <0002> gprs_rlcmac_data.cpp:100 Poll timeout for DL TBF=0 <0002> gprs_rlcmac_data.cpp:1820 TX: START TFI: 0 TLLI: 0xe75a5338 Immediate Assignment Downlink (PCH) <0001> pcu_l1_if.cpp:256 RACH request received: sapi=1 qta=0, ra=116, fn=173535 <0002> gprs_rlcmac_data.cpp:1157 TX: Immediate Assignment Uplink (AGCH) <0002> gprs_rlcmac_data.cpp:1895 Got IMM.ASS confirm, but rest octets do not start with bit sequence 'HH01' (Packet Downlink Assignment) <0002> gprs_rlcmac_data.cpp:548 Continue flow after IMM.ASS confirm <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=173589 block_nr=3 scheduling free USF for polling at FN=173593 of DL TFI=0 <0005> gprs_rlcmac_data.cpp:456 Got RACH from TLLI=0xe75a5338 while DL TBF=0 still exists. Killing pending DL TBF <0002> gprs_rlcmac.cpp:895 Free DL TBF=0 with TLLI=0xe75a5338. <0002> gprs_rlcmac.cpp:354 Allocating UL TBF: TFI=0 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=173623 block_nr=11 scheduling free USF for polling at FN=173628 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:281 PACKET CONTROL ACK with unknown FN=173632 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac_data.cpp:100 Poll timeout for UL TBF=0 <0002> gprs_rlcmac_data.cpp:135 - Timeout for polling PACKET CONTROL ACK for PACKET UPLINK ASSIGNMENT. <0002> gprs_rlcmac_data.cpp:84 - Assignment was on PACCH <0002> gprs_rlcmac_data.cpp:88 - No uplink data received yet <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=173719 block_nr=9 scheduling free USF for polling at FN=173723 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:281 PACKET CONTROL ACK with unknown FN=173727 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac_data.cpp:100 Poll timeout for UL TBF=0 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=173766 block_nr=8 scheduling free USF for polling at FN=173771 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:281 PACKET CONTROL ACK with unknown FN=173775 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac_data.cpp:100 Poll timeout for UL TBF=0 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=173870 block_nr=8 scheduling free USF for polling at FN=173875 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:281 PACKET CONTROL ACK with unknown FN=173879 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac_data.cpp:100 Poll timeout for UL TBF=0 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=173922 block_nr=8 scheduling free USF for polling at FN=173927 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:281 PACKET CONTROL ACK with unknown FN=173931 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac_data.cpp:100 Poll timeout for UL TBF=0 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=174018 block_nr=6 scheduling free USF for polling at FN=174022 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:281 PACKET CONTROL ACK with unknown FN=174026 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac_data.cpp:100 Poll timeout for UL TBF=0 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=174143 block_nr=11 scheduling free USF for polling at FN=174148 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:281 PACKET CONTROL ACK with unknown FN=174152 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac_data.cpp:100 Poll timeout for UL TBF=0 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 0 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=174299 block_nr=11 scheduling free USF for polling at FN=174304 of UL TFI=0 <0002> gprs_rlcmac_data.cpp:281 PACKET CONTROL ACK with unknown FN=174308 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac_data.cpp:100 Poll timeout for UL TBF=0 <0002> gprs_rlcmac_data.cpp:143 - N3105 exceeded <0001> pcu_l1_if.cpp:256 RACH request received: sapi=1 qta=0, ra=116, fn=174761 <0002> gprs_rlcmac_data.cpp:1157 TX: Immediate Assignment Uplink (AGCH) <0002> gprs_rlcmac_data.cpp:1895 Got IMM.ASS confirm, but rest octets do not start with bit sequence 'HH01' (Packet Downlink Assignment) <0002> gprs_rlcmac.cpp:354 Allocating UL TBF: TFI=1 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 1 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=174845 block_nr=5 scheduling free USF for polling at FN=174850 of UL TFI=1 <0002> gprs_rlcmac_data.cpp:281 PACKET CONTROL ACK with unknown FN=174854 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac_data.cpp:100 Poll timeout for UL TBF=1 <0002> gprs_rlcmac_data.cpp:135 - Timeout for polling PACKET CONTROL ACK for PACKET UPLINK ASSIGNMENT. <0002> gprs_rlcmac_data.cpp:84 - Assignment was on PACCH <0002> gprs_rlcmac_data.cpp:88 - No uplink data received yet <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 1 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=174902 block_nr=6 scheduling free USF for polling at FN=174906 of UL TFI=1 <0002> gprs_rlcmac_data.cpp:281 PACKET CONTROL ACK with unknown FN=174910 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac_data.cpp:100 Poll timeout for UL TBF=1 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 1 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=175001 block_nr=5 scheduling free USF for polling at FN=175006 of UL TFI=1 <0002> gprs_rlcmac_data.cpp:281 PACKET CONTROL ACK with unknown FN=175010 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac_data.cpp:100 Poll timeout for UL TBF=1 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 1 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=175118 block_nr=8 scheduling free USF for polling at FN=175123 of UL TFI=1 <0002> gprs_rlcmac_data.cpp:281 PACKET CONTROL ACK with unknown FN=175127 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac_data.cpp:100 Poll timeout for UL TBF=1 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 1 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=175292 block_nr=0 scheduling free USF for polling at FN=175296 of UL TFI=1 <0002> gprs_rlcmac_data.cpp:281 PACKET CONTROL ACK with unknown FN=175300 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac_data.cpp:100 Poll timeout for UL TBF=1 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 1 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=175404 block_nr=2 scheduling free USF for polling at FN=175409 of UL TFI=1 <0002> gprs_rlcmac_data.cpp:281 PACKET CONTROL ACK with unknown FN=175413 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac_data.cpp:100 Poll timeout for UL TBF=1 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 1 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=175474 block_nr=6 scheduling free USF for polling at FN=175478 of UL TFI=1 <0002> gprs_rlcmac_data.cpp:555 TBF T3195 timeout during transsmission <0002> gprs_rlcmac_data.cpp:84 - Assignment was on PACCH <0002> gprs_rlcmac_data.cpp:88 - No uplink data received yet <0002> gprs_rlcmac.cpp:895 Free UL TBF=0 with TLLI=0xe75a5338. <0002> gprs_rlcmac_data.cpp:281 PACKET CONTROL ACK with unknown FN=175482 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac_data.cpp:100 Poll timeout for UL TBF=1 <0002> gprs_rlcmac_data.cpp:1088 TBF: START TFI: 1 TLLI: 0xe75a5338 Packet Uplink Assignment (PACCH) <0006> gprs_rlcmac_sched.cpp:260 Received RTS for PDCH: TRX=0 TS=7 FN=175552 block_nr=0 scheduling free USF for polling at FN=175556 of UL TFI=1 <0002> gprs_rlcmac_data.cpp:281 PACKET CONTROL ACK with unknown FN=175560 TLL=0xe75a5338 (TRX 0 TS 7) <0002> gprs_rlcmac_data.cpp:100 Poll timeout for UL TBF=1 <0002> gprs_rlcmac_data.cpp:143 - N3105 exceeded <0002> gprs_rlcmac_data.cpp:555 TBF T3195 timeout during transsmission <0002> gprs_rlcmac_data.cpp:84 - Assignment was on PACCH <0002> gprs_rlcmac_data.cpp:88 - No uplink data received yet <0002> gprs_rlcmac.cpp:895 Free UL TBF=1 with TLLI=0xe75a5338. Regards, Vladimir Rolbin

12 years, 9 months

(no subject)

by Vladimir Rolbin

Similar problem with DL_PACKET_UPLINK_ACK_NACK..., now logs are synchronized: BTS: 1349870962.242903 3064806256: [ BTS -> PCU ] PhDataInd: FN 2543233 FN52 17 DATA raw=(0401018aae8c0f01c0050816083a853907018754033d89) 1349870962.261436 3064806256: [ BTS -> PCU ] PhDataInd: FN 2543237 FN52 21 DATA raw=(000102058aae8c0f1b2b2b2b2b2b2b2b2b2b2b2b2b2b2b) 1349870962.262173 3042884464: [ PCU -> BTS ] PCH: L2Length 11 primitive=UNIT_DATA raw=(063f300f40027d6ba40000d8aae8c0f81a022b2b2b2b) 1349870962.262261 3042884464: [ BTS -> PCU ] PhPchCnf: 2d063f300f40027d6ba40000d8aae8c0f81a022b2b2b2b 1349870962.263013 3042884464: [ PCU -> BTS ] PACCH FN 2543250 FN52 34 CNTRL1 raw=(4f2400208000000000000000f155d181e02b2b2b2b2b2b) DL_PACKET_UPLINK_ACK_NACK 1349870962.283385 3064806256: [ BTS -> PCU ] PhDataInd: FN 2543242 FN52 26 DATA raw=(0401018aae8c0f01c0050816083a853907018754033d89) 1349870962.302008 3064806256: [ BTS -> PCU ] PhDataInd: FN 2543246 FN52 30 DATA raw=(000102058aae8c0f1b2b2b2b2b2b2b2b2b2b2b2b2b2b2b) 1349870962.320769 3064806256: [ BTS -> PCU ] PhDataInd: FN 2543250 FN52 34 DATA raw=(0401018aae8c0f01c0050816083a853907018754033d89) 1349870962.362534 3064806256: [ BTS -> PCU ] PhDataInd: FN 2543259 FN52 43 CNTRL1 raw=(40062aba303f2b2b2b2b2b2b2b2b2b2b2b2b2b2b2b2b2b) UL_PACKET_CONTROL_ACKNOWLEDGEMENT 1349870962.480042 3042884464: [ PCU -> BTS ] PDTCH: FN 2543298 FN52 30 DATA raw=(0f01002541c001081501ff6cba2b2b2b2b2b2b2b2b2b2b) PCU: <0005> gprs_rlcmac_data.cpp:880 Decoded premier TLLI=0x8aae8c0f of UL DATA TBF=0. <0005> gprs_rlcmac_data.cpp:743 Complete UL frame for TBF=0: len=17 <0008> gprs_rlcmac.cpp:1760 LLC [PCU -> SGSN] TFI: 0 TLLI: 0x8aae8c0f len=17 <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0x8aae8c0f IMSI: 000 len: 9 <0002> gprs_rlcmac.cpp:354 Allocating DL TBF: TFI=0 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1804 TX: START TFI: 0 TLLI: 0x8aae8c0f Immediate Assignment Downlink (PCH) <0002> gprs_rlcmac_data.cpp:281 PACKET CONTROL ACK with unknown FN=2543259 TLL=0x8aae8c0f (TRX 0 TS 7) <0002> gprs_rlcmac.cpp:895 Free UL TBF=0 with TLLI=0x8aae8c0f. <0004> gprs_rlcmac_data.cpp:1456 Complete DL frame for TBF=0: len=9 <0002> gprs_rlcmac.cpp:895 Free DL TBF=0 with TLLI=0x8aae8c0f. <0008> gprs_bssgp_pcu.cpp:145 LLC [SGSN -> PCU] = TLLI: 0x8aae8c0f IMSI: 000 len: 9 <0002> gprs_rlcmac.cpp:354 Allocating DL TBF: TFI=0 TRX=0 MS_CLASS=10 <0002> gprs_rlcmac.cpp:948 - Setting Control TS 7 <0002> gprs_rlcmac_data.cpp:1804 TX: START TFI: 0 TLLI: 0x8aae8c0f Immediate Assignment Downlink (PCH) <0004> gprs_rlcmac_data.cpp:1456 Complete DL frame for TBF=0: len=9 <0002> gprs_rlcmac.cpp:895 Free DL TBF=0 with TLLI=0x8aae8c0f. ... *From:* Vladimir Rolbin [mailto:vrolbin@gmail.com] *Sent:* Tuesday, October 09, 2012 5:17 PM *To:* osmocom-pcu(a)lists.osmocom.org; openbts-discuss(a)lists.sourceforge.net *Subject:* [Openbts-discuss] gprs PACCH downlink assignment problem Hi, My set is openbts-p2.8-gprs-exp/osmo-pcu-jolly. I encounter the problem with PACCH downlink assignment: If the difference between FNs of DL_PACKET_DOWNLINK_ASSIGNMENT and corresponding UL_PACKET_CONTROL_ACKNOWLEDGEMENT is expected (RRBP = 0) 13 everything is OK (OpenBTS log): 1347379490.099008 3031505776: [ PCU -> BTS ] PACCH FN 2536893 FN52 21 CNTRL1 raw=(4f0800000c03400a0800802b2b2b2b2b2b2b2b2b2b2b2b) DL_PACKET_DOWNLINK_ASSIGNMENT 1347379490.118500 3063704432: [ BTS -> PCU ] PhDataInd: FN 2536885 FN52 13 DATA raw=(100101cc741f3203c005650000014500003c00010000ff) 1347379490.136108 3063704432: [ BTS -> PCU ] PhDataInd: FN 2536889 FN52 17 DATA raw=(080105cc741f3228e2a8c8aa0100000100000000000003) 1347379490.154624 3063704432: [ BTS -> PCU ] PhDataInd: FN 2536893 FN52 21 DATA raw=(040107cc741f32777777066b616e6e656c036f72670000) 1347379490.215582 3063704432: [ BTS -> PCU ] PhDataInd: FN 2536906 FN52 34 CNTRL1 raw=(400731d07ccb2b2b2b2b2b2b2b2b2b2b2b2b2b2b2b2b2b)UL_PACKET_CONTROL_ACKNOWLEDGEMENT But it's not always the case 1349788219.582282 3043367792: [ PCU -> BTS ] PACCH FN 2189728 FN52 8 CNTRL1 raw=(4f0800000c02800a0d00802b2b2b2b2b2b2b2b2b2b2b2b) DL_PACKET_DOWNLINK_ASSIGNMENT 1349788219.601978 3064179568: [ BTS -> PCU ] PhDataInd: FN 2189720 FN52 0 DATA raw=(040103ae3558373751f40103e800101773022a85464200) 1349788219.620378 3064179568: [ BTS -> PCU ] PhDataInd: FN 2189724 FN52 4 DATA raw=(0001042dae355837026a4e0432100000258ccc2b2b2b2b) 1349788219.638881 3064179568: [ BTS -> PCU ] PhDataInd: FN 2189728 FN52 8 DATA raw=(040103ae3558373751f40103e800101773022a85464200) 1349788219.680973 3064179568: [ BTS -> PCU ] PhDataInd: FN 2189737 FN52 17 CNTRL1 raw=(4006b8d560df2b2b2b2b2b2b2b2b2b2b2b2b2b2b2b2b2b) UL_PACKET_CONTROL_ACKNOWLEDGEMENT diff FN = 9, that causes the following behaviour in pcu: <0002> gprs_rlcmac_data.cpp:1753 TBF: START TFI: 0 TLLI: 0xae355837 Packet Downlink Assignment (PACCH) <0004> gprs_rlcmac_data.cpp:1442 Complete DL frame for TBF=0: len=9 <0002> gprs_rlcmac_data.cpp:281 PACKET CONTROL ACK with unknown FN=2163607 TLL=0xae355837 (TRX 0 TS 7) <0002> gprs_rlcmac_data.cpp:100 Poll timeout for UL TBF=0 <0002> gprs_rlcmac_data.cpp:107 - Timeout for polling PACKET CONTROL ACK for PACKET UPLINK ACK Any ideas? Is it the known issue? Regards, Vladimir Rolbin

12 years, 9 months

bug in wireshark decoder of osmo-pcu

by jolly

hi, i get wrong decoding of RLCMAC control block. the decoder of osmo-pcu is decoding following sequence: 0x40,0x16,0x76,0x67,0x74,0x02,0x26,0x64,0xe8,0x65,0x64,0x69,0x00,0x3e,0x4c,0x00,0x2b,0x2b,0x2b,0x2b,0x2b,0x2b,0x2 this is the result: PayloadType = 1 | spare = 0 | R = 0 | MESSAGE_TYPE = 5 | Exist_ACCESS_TYPE = 1 | ACCESS_TYPE = 0 | : ID | Choice PacketResourceRequestID = 1 | u.TLLI = 0xd99dd008 | : End ID | Exist_MS_Radio_Access_capability = 1 | : MS_Radio_Access_capability | MS_RA_capability_value[0] { | Choice MS_RA_capability_value_Choice = 3 | u.Content length = 25 ... at this point, the length of the content is 25 bits: | RF_Power_Capability = 1 | Exist_A5_bits = 1 | A5_bits = 80 | ES_IND = 1 | PS = 1 | VGCS = 0 | VBS = 0 | Exist_Multislot_capability = 1 | : Multislot_capability | Exist_HSCSD_multislot_class = 0 | Exist_GPRS_multislot_class = 1 | GPRS_multislot_class = 12 | GPRS_Extended_Dynamic_Allocation_Capability = 1 | Exist_SM = 0 ... at this point all 25 bits are decoded, so the decoder must abort decoding of content of Multislot_capability_t (see gsm_rlcmac.cpp). instead, it continues with the data found after these 25 bits: (all crap from now on) | Exist_ECSD_multislot_class = 0 | Exist_EGPRS_multislot_class = 0 | Exist_DTM_GPRS_multislot_class = 1 | DTM_GPRS_multislot_class = 2 | Single_Slot_DTM = 1 | : DTM_EGPRS_Params | Exist_DTM_EGPRS_multislot_class = 0 | : End DTM_EGPRS_Params | : End Multislot_capability | Exist_Eight_PSK_Power_Capability = 0 | COMPACT_Interference_Measurement_Capability = 1 | Revision_Level_Indicator = 0 | UMTS_FDD_Radio_Access_Technology_Capability = 0 | UMTS_384_TDD_Radio_Access_Technology_Capability = 0 | CDMA2000_Radio_Access_Technology_Capability = 0 | UMTS_128_TDD_Radio_Access_Technology_Capability = 0 | GERAN_Feature_Package_1 = 0 | Exist_Extended_DTM_multislot_class = 0 | Modulation_based_multislot_class_support = 0 | Exist_HighMultislotCapability = 0 | Exist_GERAN_lu_ModeCapability = 0 | GMSK_MultislotPowerProfile = 3 | EightPSK_MultislotProfile = 3 | MultipleTBF_Capability = 1 | DownlinkAdvancedReceiverPerformance = 0 | ExtendedRLC_MAC_ControlMessageSegmentionsCapability = 1 | DTM_EnhancementsCapability = 0 | Exist_DTM_GPRS_HighMultislotClass = 0 | PS_HandoverCapability = 1 | MS_RA_capability_value[0] } | MS_RA_capability_value[0] { | Choice MS_RA_capability_value_Choice = 0 | u.Content length = 0 | RF_Power_Capability = 2 | Exist_A5_bits = 1 | A5_bits = 50 | ES_IND = 1 | PS = 0 | VGCS = 1 | VBS = 1 | Exist_Multislot_capability = 0 | Exist_Eight_PSK_Power_Capability = 0 | COMPACT_Interference_Measurement_Capability = 1 | Revision_Level_Indicator = 0 | UMTS_FDD_Radio_Access_Technology_Capability = 1 | UMTS_384_TDD_Radio_Access_Technology_Capability = 0 | CDMA2000_Radio_Access_Technology_Capability = 1 | UMTS_128_TDD_Radio_Access_Technology_Capability = 1 | GERAN_Feature_Package_1 = 0 | Exist_Extended_DTM_multislot_class = 0 | Modulation_based_multislot_class_support = 1 | Exist_HighMultislotCapability = 0 | Exist_GERAN_lu_ModeCapability = 1 | GERAN_lu_ModeCapability = 6 | GMSK_MultislotPowerProfile = 1 | EightPSK_MultislotProfile = 1 | MultipleTBF_Capability = 0 | DownlinkAdvancedReceiverPerformance = 3 | ExtendedRLC_MAC_ControlMessageSegmentionsCapability = 0 | DTM_EnhancementsCapability = 0 | Exist_DTM_GPRS_HighMultislotClass = 1 | DTM_GPRS_HighMultislotClass = 2 | : DTM_EGPRS_HighMultislotClass | Exist_DTM_EGPRS_HighMultislotClass = 1 | : End DTM_EGPRS_HighMultislotClass | : End MS_Radio_Access_capability | there are two problems with the decoder: - it does not check if the length has been exceeded while decoding Multislot_capability_t content. if the length is lower than all elements in Multislot_capabilit_t, the decoder must abort decoding the content. this is no bug. (the definition used at that point should be M_NEXT_EXIST_OR_NULL instead of M_NEXT_EXIST, see gsm_rlcmac.cpp) - even if the correct definition is used, the csn1 decoder will not use the length given at "u.Content length" to abort. instead it checks for reaching total length of coded data. i played a bit with the code, but could not fix it without breaking other things. but decoding with wireshark works. would it be possible to port latest wireshark code? regards, andreas

12 years, 9 months

Jump to page:

2025

2024

2023

2022

2021

2020

2019

2018

2017

2016

2015

2014

2013

2012

osmocom-net-gprs