<-- complete log of failed handover between two Nokia InSite units --> Mon Jul 22 19:18:40 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1296 Mon Jul 22 19:18:40 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b da 19 04 1c 1c 00 ff 04 05 0a 28 00 0b 00 12 06 15 13 13 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:40 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:40 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b d5 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:40 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:41 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b db 19 04 1c 1c 00 ff 04 05 0a 28 00 0b 00 12 06 15 14 14 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:41 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:41 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b d6 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:41 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:41 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b dc 19 04 1c 1c 00 ff 04 05 0a 28 00 0b 00 12 06 15 14 14 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:41 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:41 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b d7 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:41 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:42 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b dd 19 04 1c 1c 00 ff 04 05 0a 28 00 0b 00 12 06 15 14 14 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:42 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:42 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b d8 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:42 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:42 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b de 19 04 1c 1c 00 ff 04 05 0a 28 00 0b 00 12 06 15 14 14 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:42 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:42 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b d9 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:42 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:43 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b df 19 04 1c 1c 00 ff 04 05 0a 28 00 0b 00 12 06 15 14 14 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:43 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:43 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b da 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:43 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:43 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b e0 19 04 1c 1c 00 ff 04 05 0a 28 00 0b 00 12 06 15 14 14 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:43 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:43 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b db 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:43 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:44 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b e1 19 04 1c 1c 00 ff 04 05 0a 28 00 0b 00 12 06 15 14 14 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:44 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:44 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b dc 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:44 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:44 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b e2 19 04 1c 1c 00 ff 04 05 0a 28 00 0b 00 12 06 15 14 14 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:44 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:44 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b dd 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:44 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:45 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b e3 19 04 1c 1c 00 ff 04 05 0a 28 00 0b 00 12 06 15 14 14 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:45 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:45 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b de 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:45 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:45 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b e4 19 04 1c 1c 00 ff 04 05 0a 28 00 0b 00 12 06 15 14 14 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:45 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:45 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b df 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:45 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:46 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b e5 19 04 1c 1c 00 ff 04 05 0a 28 00 0b 00 12 06 15 14 14 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:46 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:46 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b e0 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:46 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:46 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b e6 19 04 1c 1c 00 ff 04 05 0a 28 00 0b 00 12 06 15 14 14 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:46 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:46 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b e1 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:46 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:47 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b e7 19 04 1c 1c 00 ff 04 05 0a 28 00 0b 00 12 06 15 14 14 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:47 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:47 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b e2 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:47 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:47 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b e8 19 04 1b 1b 00 ff 04 05 0a 28 00 0b 00 12 06 15 13 13 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:47 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:47 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b e3 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:47 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:48 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b e9 19 04 21 20 00 ff 04 05 0a 28 00 0b 00 12 06 15 0e 07 02 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:48 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:48 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b e4 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:48 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:48 2013 <0018> input/lapd.c:347 RX: 00 03 70 16 10 19 15 16 20 00 28 00 30 00 38 00 09 00 0a 00 0b 00 0c 00 0d 00 0e 00 0f 00 Mon Jul 22 19:18:48 2013 <0018> lapd_core.c:1465 I received in state LAPD_STATE_MF_EST Mon Jul 22 19:18:48 2013 <0018> lapd_core.c:1548 incrementing V(R) to 57 Mon Jul 22 19:18:48 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:18:48 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:18:48 2013 <0018> lapd_core.c:1557 message in single I frame Mon Jul 22 19:18:48 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1615 Mon Jul 22 19:18:48 2013 <0018> lapd_core.c:1618 we are not busy and have no pending data, send RR Mon Jul 22 19:18:48 2013 <0018> input/lapd.c:600 TX: 00 03 01 72 Mon Jul 22 19:18:48 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b ea 19 04 1b 1d 18 ff 04 05 0a 28 00 0b 00 12 06 15 12 15 46 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:48 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:48 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b e5 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:48 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:48 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b eb 19 04 1c 1e 00 ff 04 05 0a 28 00 0b 00 12 06 15 11 14 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:48 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:49 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b e6 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:49 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:49 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b ec 19 04 1b 18 00 ff 04 05 0a 28 00 0b 00 12 06 15 10 12 20 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:49 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:49 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b e7 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:49 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:49 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b ed 19 04 18 1b 00 ff 04 05 0a 28 00 0b 00 12 06 15 10 10 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:49 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:49 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b e8 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:49 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:50 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b ee 19 04 1a 1b 00 ff 04 05 0a 28 00 0b 00 12 06 15 09 0d 10 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:50 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:50 2013 <0018> lapd_core.c:680 Timeout T203 (0x979ad08) state=7 Mon Jul 22 19:18:50 2013 <0018> lapd_core.c:229 new state LAPD_STATE_MF_EST -> LAPD_STATE_TIMER_RECOV Mon Jul 22 19:18:50 2013 <0018> lapd_core.c:694 transmit an RR poll command Mon Jul 22 19:18:50 2013 <0018> input/lapd.c:600 TX: 02 03 01 b9 Mon Jul 22 19:18:50 2013 <0018> lapd_core.c:198 start T200 Mon Jul 22 19:18:50 2013 <0018> input/lapd.c:347 RX: 02 03 01 69 Mon Jul 22 19:18:50 2013 <0018> lapd_core.c:1258 RR received in state LAPD_STATE_TIMER_RECOV Mon Jul 22 19:18:50 2013 <0018> lapd_core.c:1287 RR response with F==1, and we are in timer recovery state, so we leave that state Mon Jul 22 19:18:50 2013 <0018> lapd_core.c:214 stop T200 Mon Jul 22 19:18:50 2013 <0018> lapd_core.c:229 new state LAPD_STATE_TIMER_RECOV -> LAPD_STATE_MF_EST Mon Jul 22 19:18:50 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:18:50 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1296 Mon Jul 22 19:18:50 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b e9 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:50 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:50 2013 <0018> lapd_core.c:680 Timeout T203 (0x9757180) state=7 Mon Jul 22 19:18:50 2013 <0018> lapd_core.c:229 new state LAPD_STATE_MF_EST -> LAPD_STATE_TIMER_RECOV Mon Jul 22 19:18:50 2013 <0018> lapd_core.c:694 transmit an RR poll command Mon Jul 22 19:18:50 2013 <0018> input/lapd.c:600 TX: fa 03 01 07 Mon Jul 22 19:18:50 2013 <0018> lapd_core.c:198 start T200 Mon Jul 22 19:18:50 2013 <0018> input/lapd.c:347 RX: fa 03 01 07 Mon Jul 22 19:18:50 2013 <0018> lapd_core.c:1258 RR received in state LAPD_STATE_TIMER_RECOV Mon Jul 22 19:18:50 2013 <0018> lapd_core.c:1287 RR response with F==1, and we are in timer recovery state, so we leave that state Mon Jul 22 19:18:50 2013 <0018> lapd_core.c:214 stop T200 Mon Jul 22 19:18:50 2013 <0018> lapd_core.c:229 new state LAPD_STATE_TIMER_RECOV -> LAPD_STATE_MF_EST Mon Jul 22 19:18:50 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:18:50 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1296 Mon Jul 22 19:18:50 2013 <0018> lapd_core.c:680 Timeout T203 (0x9793ae8) state=7 Mon Jul 22 19:18:50 2013 <0018> lapd_core.c:229 new state LAPD_STATE_MF_EST -> LAPD_STATE_TIMER_RECOV Mon Jul 22 19:18:50 2013 <0018> lapd_core.c:694 transmit an RR poll command Mon Jul 22 19:18:50 2013 <0018> input/lapd.c:600 TX: fa 03 01 03 Mon Jul 22 19:18:50 2013 <0018> lapd_core.c:198 start T200 Mon Jul 22 19:18:50 2013 <0018> input/lapd.c:347 RX: fa 03 01 03 Mon Jul 22 19:18:50 2013 <0018> lapd_core.c:1258 RR received in state LAPD_STATE_TIMER_RECOV Mon Jul 22 19:18:50 2013 <0018> lapd_core.c:1287 RR response with F==1, and we are in timer recovery state, so we leave that state Mon Jul 22 19:18:50 2013 <0018> lapd_core.c:214 stop T200 Mon Jul 22 19:18:50 2013 <0018> lapd_core.c:229 new state LAPD_STATE_TIMER_RECOV -> LAPD_STATE_MF_EST Mon Jul 22 19:18:50 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:18:50 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1296 Mon Jul 22 19:18:50 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b ef 19 04 17 14 00 ff 04 05 0a 28 00 0b 00 12 06 15 08 0c 32 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:50 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:50 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b ea 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:50 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:51 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b f0 19 04 13 10 09 ff 04 05 0a 28 00 0b 00 12 06 15 09 0a 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:51 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:51 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b eb 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:51 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:51 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b f1 19 04 15 0e 01 ff 04 05 0a 28 00 0b 00 12 06 15 0c 0d 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:51 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:51 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b ec 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:51 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:52 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b f2 19 04 1b 1d 00 ff 04 05 0a 28 00 0b 00 12 06 15 0d 0e 10 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:52 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:52 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b ed 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:52 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:52 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b f3 19 04 1c 1d 00 ff 04 05 0a 28 00 0b 00 12 06 15 17 18 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:52 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:52 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b ee 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:52 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:53 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b f4 19 04 17 17 00 ff 04 05 0a 28 00 0b 00 12 06 15 13 15 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:53 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:53 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b ef 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:53 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:53 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b f5 19 04 1c 1c 00 ff 04 05 0a 28 00 0b 00 12 06 15 0e 0e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:53 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:53 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b f0 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:53 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:54 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b f6 19 04 18 1b 00 ff 04 05 0a 28 00 0b 00 12 06 15 0b 0a 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:54 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:54 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b f1 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:54 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:54 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b f7 19 04 16 17 00 ff 04 05 0a 28 00 0b 00 12 06 15 09 0b 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:54 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:54 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b f2 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:54 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:55 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b f8 19 04 19 19 00 ff 04 05 0a 28 00 0b 00 12 06 15 03 02 20 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:55 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:55 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b f3 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:55 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:55 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b f9 19 04 1a 1a 00 ff 04 05 0a 28 00 0b 00 12 06 15 08 08 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:55 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:55 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b f4 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:55 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:56 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b fa 19 04 19 19 00 ff 04 05 0a 28 00 0b 00 12 06 15 0a 0a 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:56 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:56 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b f5 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:56 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:56 2013 <0018> input/lapd.c:347 RX: 00 03 b8 68 10 19 15 1c 41 00 49 00 51 00 59 00 61 00 69 00 71 00 79 00 0a 00 0b 00 0c 00 0d 00 0e 00 0f 00 Mon Jul 22 19:18:56 2013 <0018> lapd_core.c:1465 I received in state LAPD_STATE_MF_EST Mon Jul 22 19:18:56 2013 <0018> lapd_core.c:1548 incrementing V(R) to 93 Mon Jul 22 19:18:56 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:18:56 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:18:56 2013 <0018> lapd_core.c:1557 message in single I frame Mon Jul 22 19:18:56 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1615 Mon Jul 22 19:18:56 2013 <0018> lapd_core.c:1618 we are not busy and have no pending data, send RR Mon Jul 22 19:18:56 2013 <0018> input/lapd.c:600 TX: 00 03 01 ba Mon Jul 22 19:18:56 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b fb 19 04 19 19 00 ff 04 05 0a 28 00 0b 00 12 06 15 0b 0b 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:56 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:56 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b f6 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:56 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:57 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b fc 19 04 19 19 00 ff 04 05 0a 28 00 0b 00 12 06 15 0b 0b 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:57 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:57 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b f7 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:57 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:57 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b fd 19 04 18 18 00 ff 04 05 0a 28 00 0b 00 12 06 15 0b 0b 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:57 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:57 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b f8 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:57 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:58 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b fe 19 04 17 17 00 ff 04 05 0a 28 00 0b 00 12 06 15 0b 0b 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:58 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:58 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b f9 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:58 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:58 2013 <0018> lapd_core.c:680 Timeout T203 (0x979a068) state=7 Mon Jul 22 19:18:58 2013 <0018> lapd_core.c:229 new state LAPD_STATE_MF_EST -> LAPD_STATE_TIMER_RECOV Mon Jul 22 19:18:58 2013 <0018> lapd_core.c:694 transmit an RR poll command Mon Jul 22 19:18:58 2013 <0018> input/lapd.c:600 TX: 02 03 01 73 Mon Jul 22 19:18:58 2013 <0018> lapd_core.c:198 start T200 Mon Jul 22 19:18:58 2013 <0018> input/lapd.c:347 RX: 02 03 01 17 Mon Jul 22 19:18:58 2013 <0018> lapd_core.c:1258 RR received in state LAPD_STATE_TIMER_RECOV Mon Jul 22 19:18:58 2013 <0018> lapd_core.c:1287 RR response with F==1, and we are in timer recovery state, so we leave that state Mon Jul 22 19:18:58 2013 <0018> lapd_core.c:214 stop T200 Mon Jul 22 19:18:58 2013 <0018> lapd_core.c:229 new state LAPD_STATE_TIMER_RECOV -> LAPD_STATE_MF_EST Mon Jul 22 19:18:58 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:18:58 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1296 Mon Jul 22 19:18:58 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b ff 19 04 17 17 00 ff 04 05 0a 28 00 0b 00 12 06 15 07 07 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:58 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:58 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b fa 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:58 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:59 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 00 19 04 16 16 00 ff 04 05 0a 28 00 0b 00 12 06 15 00 00 6c 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:998 UI received <-- Beginning of the failed handover --> Mon Jul 22 19:18:59 2013 <000c> handover_decision.c:203 (bts=0,trx=0,ts=2): Cell on ARFCN 123 is better: Mon Jul 22 19:18:59 2013 <000c> handover_logic.c:96 (old_lchan on BTS 0, new BTS 1) Starting handover Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:2183 Message DL-DATA-REQUEST received in state LAPD_STATE_MF_EST Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:1758 writing message to send-queue: l3len: 28 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1764 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:1821 get message from send-queue Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:1833 msg-len 28 sent 0 left 28 N201 260 length 28 first byte 08 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:1842 send I frame V(S)=11 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:198 start T200 Mon Jul 22 19:18:59 2013 <0018> input/lapd.c:600 TX: 02 03 16 72 08 21 01 0a 03 02 06 04 00 01 08 11 05 06 64 0a e0 7b 72 00 09 00 04 00 0d 05 18 00 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:1833 msg-len 28 sent 28 left 0 N201 260 length 0 first byte 08 Mon Jul 22 19:18:59 2013 <0018> input/lapd.c:347 RX: 02 03 01 18 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:1258 RR received in state LAPD_STATE_MF_EST Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:727 ack frame 11 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:214 stop T200 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1296 Mon Jul 22 19:18:59 2013 <0018> input/lapd.c:347 RX: 00 03 72 18 08 22 01 0a 08 21 75 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:1465 I received in state LAPD_STATE_MF_EST Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:1548 incrementing V(R) to 58 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:1557 message in single I frame Mon Jul 22 19:18:59 2013 <0004> abis_rsl.c:1165 (bts=1,trx=0,ts=2,ss=0) CHANNEL ACTIVATE ACK Mon Jul 22 19:18:59 2013 <000c> handover_logic.c:204 handover activate ack, send HO Command Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1615 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:1618 we are not busy and have no pending data, send RR Mon Jul 22 19:18:59 2013 <0018> input/lapd.c:600 TX: 00 03 01 74 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:2183 Message DL-DATA-REQUEST received in state LAPD_STATE_MF_EST Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:1758 writing message to send-queue: l3len: 18 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1764 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:1821 get message from send-queue Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:1833 msg-len 18 sent 0 left 18 N201 260 length 18 first byte 03 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:1842 send I frame V(S)=52 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:198 start T200 Mon Jul 22 19:18:59 2013 <0018> input/lapd.c:600 TX: 02 03 68 ba 03 01 01 0a 02 00 0b 00 09 06 2b 3f 7b 0a e0 7b 00 00 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:1833 msg-len 18 sent 18 left 0 N201 260 length 0 first byte 03 Mon Jul 22 19:18:59 2013 <0018> input/lapd.c:347 RX: 02 03 01 6a Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:1258 RR received in state LAPD_STATE_MF_EST Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:727 ack frame 52 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:214 stop T200 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1296 Mon Jul 22 19:18:59 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b fb 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:59 2013 <0018> input/lapd.c:347 RX: 00 03 74 18 08 27 01 0a 11 00 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:1465 I received in state LAPD_STATE_MF_EST Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:1548 incrementing V(R) to 59 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:1557 message in single I frame Mon Jul 22 19:18:59 2013 <0004> abis_rsl.c:1138 (bts=1,trx=0,ts=2,ss=0) HANDOVER DETECT access delay = 0 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1615 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:1618 we are not busy and have no pending data, send RR Mon Jul 22 19:18:59 2013 <0018> input/lapd.c:600 TX: 00 03 01 76 Mon Jul 22 19:18:59 2013 <0018> input/lapd.c:347 RX: 00 03 76 18 02 06 01 0a 02 00 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:1465 I received in state LAPD_STATE_MF_EST Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:1548 incrementing V(R) to 60 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:1557 message in single I frame Mon Jul 22 19:18:59 2013 <0000> abis_rsl.c:1621 (bts=1,trx=0,ts=2,ss=0) SAPI=0 ESTABLISH INDICATION Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1615 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:1618 we are not busy and have no pending data, send RR Mon Jul 22 19:18:59 2013 <0018> input/lapd.c:600 TX: 00 03 01 78 Mon Jul 22 19:18:59 2013 <0018> input/lapd.c:347 RX: 00 03 78 18 03 02 01 0a 02 00 0b 00 03 06 2c 00 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:1465 I received in state LAPD_STATE_MF_EST Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:1548 incrementing V(R) to 61 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:1557 message in single I frame Mon Jul 22 19:18:59 2013 <0000> abis_rsl.c:1621 (bts=1,trx=0,ts=2,ss=0) SAPI=0 DATA INDICATION Mon Jul 22 19:18:59 2013 <0003> bsc_api.c:515 HANDOVER COMPLETE cause = Normal event Mon Jul 22 19:18:59 2013 <000c> handover_logic.c:261 Subscriber 244153333330126 HO from BTS 0->1 on ARFCN 885->123 Mon Jul 22 19:18:59 2013 <0000> chan_alloc.c:405 (bts=0,trx=0,ts=2,ss=0) starting release sequence Mon Jul 22 19:18:59 2013 <0000> abis_rsl.c:891 (bts=0,trx=0,ts=2,ss=0) RSL RLL RELEASE REQ (link_id=0x00, reason=1) Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1615 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:1618 we are not busy and have no pending data, send RR Mon Jul 22 19:18:59 2013 <0018> input/lapd.c:600 TX: 00 03 01 7a Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:2183 Message DL-DATA-REQUEST received in state LAPD_STATE_MF_EST Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:1758 writing message to send-queue: l3len: 8 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1764 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:1821 get message from send-queue Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:1833 msg-len 8 sent 0 left 8 N201 260 length 8 first byte 02 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:1842 send I frame V(S)=53 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:198 start T200 Mon Jul 22 19:18:59 2013 <0018> input/lapd.c:600 TX: 02 03 6a ba 02 07 01 0a 02 00 14 01 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:1833 msg-len 8 sent 8 left 0 N201 260 length 0 first byte 02 Mon Jul 22 19:18:59 2013 <0018> input/lapd.c:347 RX: 02 03 01 6c Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:1258 RR received in state LAPD_STATE_MF_EST Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:727 ack frame 53 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:214 stop T200 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1296 Mon Jul 22 19:18:59 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 01 19 04 15 15 00 ff 04 05 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:18:59 2013 <0004> abis_rsl.c:1069 (bts=0,trx=0,ts=2,ss=0): MEAS RES for inactive channel Mon Jul 22 19:18:59 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b fc 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:18:59 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:00 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 02 19 04 04 01 3f ff 04 05 Mon Jul 22 19:19:00 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:00 2013 <0004> abis_rsl.c:1069 (bts=0,trx=0,ts=2,ss=0): MEAS RES for inactive channel Mon Jul 22 19:19:00 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b fd 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:00 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:00 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 00 19 04 38 2f 2e ff 04 00 0a 28 00 0b 00 12 06 15 39 79 71 c0 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:00 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:00 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 03 19 04 00 00 3f ff 04 05 Mon Jul 22 19:19:00 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:00 2013 <0004> abis_rsl.c:1069 (bts=0,trx=0,ts=2,ss=0): MEAS RES for inactive channel Mon Jul 22 19:19:00 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b fe 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:00 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:00 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 01 19 04 3f 3f 00 ff 04 00 0a 28 00 0b 00 12 06 15 39 39 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:00 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:00 2013 <0018> lapd_core.c:680 Timeout T203 (0x9757180) state=7 Mon Jul 22 19:19:00 2013 <0018> lapd_core.c:229 new state LAPD_STATE_MF_EST -> LAPD_STATE_TIMER_RECOV Mon Jul 22 19:19:00 2013 <0018> lapd_core.c:694 transmit an RR poll command Mon Jul 22 19:19:00 2013 <0018> input/lapd.c:600 TX: fa 03 01 07 Mon Jul 22 19:19:00 2013 <0018> lapd_core.c:198 start T200 Mon Jul 22 19:19:00 2013 <0018> input/lapd.c:347 RX: fa 03 01 07 Mon Jul 22 19:19:00 2013 <0018> lapd_core.c:1258 RR received in state LAPD_STATE_TIMER_RECOV Mon Jul 22 19:19:00 2013 <0018> lapd_core.c:1287 RR response with F==1, and we are in timer recovery state, so we leave that state Mon Jul 22 19:19:00 2013 <0018> lapd_core.c:214 stop T200 Mon Jul 22 19:19:00 2013 <0018> lapd_core.c:229 new state LAPD_STATE_TIMER_RECOV -> LAPD_STATE_MF_EST Mon Jul 22 19:19:00 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:00 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1296 Mon Jul 22 19:19:00 2013 <0018> lapd_core.c:680 Timeout T203 (0x9793ae8) state=7 Mon Jul 22 19:19:00 2013 <0018> lapd_core.c:229 new state LAPD_STATE_MF_EST -> LAPD_STATE_TIMER_RECOV Mon Jul 22 19:19:00 2013 <0018> lapd_core.c:694 transmit an RR poll command Mon Jul 22 19:19:00 2013 <0018> input/lapd.c:600 TX: fa 03 01 03 Mon Jul 22 19:19:00 2013 <0018> lapd_core.c:198 start T200 Mon Jul 22 19:19:00 2013 <0018> input/lapd.c:347 RX: fa 03 01 03 Mon Jul 22 19:19:00 2013 <0018> lapd_core.c:1258 RR received in state LAPD_STATE_TIMER_RECOV Mon Jul 22 19:19:00 2013 <0018> lapd_core.c:1287 RR response with F==1, and we are in timer recovery state, so we leave that state Mon Jul 22 19:19:00 2013 <0018> lapd_core.c:214 stop T200 Mon Jul 22 19:19:00 2013 <0018> lapd_core.c:229 new state LAPD_STATE_TIMER_RECOV -> LAPD_STATE_MF_EST Mon Jul 22 19:19:00 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:00 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1296 Mon Jul 22 19:19:00 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 04 19 04 00 00 3f ff 04 05 Mon Jul 22 19:19:00 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:00 2013 <0004> abis_rsl.c:1069 (bts=0,trx=0,ts=2,ss=0): MEAS RES for inactive channel Mon Jul 22 19:19:01 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b ff 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:01 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:01 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 02 19 04 3f 3f 00 ff 04 00 0a 28 00 0b 00 12 06 15 39 39 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:01 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:01 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 05 19 04 00 00 3f ff 04 05 Mon Jul 22 19:19:01 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:01 2013 <0004> abis_rsl.c:1069 (bts=0,trx=0,ts=2,ss=0): MEAS RES for inactive channel Mon Jul 22 19:19:01 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b 00 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:01 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:01 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 03 19 04 3f 3f 00 ff 04 00 0a 28 00 0b 00 12 06 15 3a 39 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:01 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:01 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 06 19 04 00 00 3f ff 04 05 Mon Jul 22 19:19:01 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:01 2013 <0004> abis_rsl.c:1069 (bts=0,trx=0,ts=2,ss=0): MEAS RES for inactive channel Mon Jul 22 19:19:01 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b 01 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:01 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:02 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 04 19 04 3f 3f 00 ff 04 00 0a 28 00 0b 00 12 06 15 3a 3c 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:02 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:02 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 07 19 04 00 00 3f ff 04 05 Mon Jul 22 19:19:02 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:02 2013 <0004> abis_rsl.c:1069 (bts=0,trx=0,ts=2,ss=0): MEAS RES for inactive channel Mon Jul 22 19:19:02 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b 02 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:02 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:02 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 05 19 04 3f 3f 00 ff 04 00 0a 28 00 0b 00 12 06 15 3c 3d 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:02 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:02 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 08 19 04 00 00 3f ff 04 05 Mon Jul 22 19:19:02 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:02 2013 <0004> abis_rsl.c:1069 (bts=0,trx=0,ts=2,ss=0): MEAS RES for inactive channel Mon Jul 22 19:19:02 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b 03 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:02 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:02 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 06 19 04 3f 3f 00 ff 04 00 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:02 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:03 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 09 19 04 00 00 3f ff 04 05 Mon Jul 22 19:19:03 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:03 2013 <0004> abis_rsl.c:1069 (bts=0,trx=0,ts=2,ss=0): MEAS RES for inactive channel Mon Jul 22 19:19:03 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b 04 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:03 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:03 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 07 19 04 3f 3f 00 ff 04 00 0a 28 00 0b 00 12 06 15 3b 3b 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:03 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:03 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 0a 19 04 00 00 3f ff 04 05 Mon Jul 22 19:19:03 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:03 2013 <0004> abis_rsl.c:1069 (bts=0,trx=0,ts=2,ss=0): MEAS RES for inactive channel Mon Jul 22 19:19:03 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b 05 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:03 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:03 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 08 19 04 3f 3f 00 ff 04 00 0a 28 00 0b 00 12 06 15 39 32 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:03 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:03 2013 <0018> input/lapd.c:347 RX: 00 03 ba 6c 0c 12 01 88 12 06 17 e8 00 00 00 00 Mon Jul 22 19:19:03 2013 <0018> lapd_core.c:1465 I received in state LAPD_STATE_MF_EST Mon Jul 22 19:19:03 2013 <0018> lapd_core.c:1548 incrementing V(R) to 94 Mon Jul 22 19:19:03 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:19:03 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:03 2013 <0018> lapd_core.c:1557 message in single I frame Mon Jul 22 19:19:03 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1615 Mon Jul 22 19:19:03 2013 <0018> lapd_core.c:1618 we are not busy and have no pending data, send RR Mon Jul 22 19:19:03 2013 <0018> input/lapd.c:600 TX: 00 03 01 bc Mon Jul 22 19:19:03 2013 <0018> input/lapd.c:347 RX: 00 03 bc 6c 0c 12 01 90 0f 01 90 ff 00 00 00 00 fa 03 00 00 00 Mon Jul 22 19:19:03 2013 <0018> lapd_core.c:1465 I received in state LAPD_STATE_MF_EST Mon Jul 22 19:19:03 2013 <0018> lapd_core.c:1548 incrementing V(R) to 95 Mon Jul 22 19:19:03 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:19:03 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:03 2013 <0018> lapd_core.c:1557 message in single I frame Mon Jul 22 19:19:03 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1615 Mon Jul 22 19:19:03 2013 <0018> lapd_core.c:1618 we are not busy and have no pending data, send RR Mon Jul 22 19:19:03 2013 <0018> input/lapd.c:600 TX: 00 03 01 be Mon Jul 22 19:19:04 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 0b 19 04 00 00 3f ff 04 05 Mon Jul 22 19:19:04 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:04 2013 <0004> abis_rsl.c:1069 (bts=0,trx=0,ts=2,ss=0): MEAS RES for inactive channel Mon Jul 22 19:19:04 2013 <0018> input/lapd.c:347 RX: 00 03 7a 18 10 19 15 16 20 00 28 00 30 00 38 00 09 00 0a 00 0b 00 0c 00 0d 00 0e 00 0f 00 Mon Jul 22 19:19:04 2013 <0018> lapd_core.c:1465 I received in state LAPD_STATE_MF_EST Mon Jul 22 19:19:04 2013 <0018> lapd_core.c:1548 incrementing V(R) to 62 Mon Jul 22 19:19:04 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:19:04 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:04 2013 <0018> lapd_core.c:1557 message in single I frame Mon Jul 22 19:19:04 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1615 Mon Jul 22 19:19:04 2013 <0018> lapd_core.c:1618 we are not busy and have no pending data, send RR Mon Jul 22 19:19:04 2013 <0018> input/lapd.c:600 TX: 00 03 01 7c Mon Jul 22 19:19:04 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b 06 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:04 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:04 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 09 19 04 3f 3f 10 ff 04 00 0a 28 00 0b 00 12 06 15 3e 38 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:04 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:04 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 0c 19 04 00 00 3f ff 04 05 Mon Jul 22 19:19:04 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:04 2013 <0004> abis_rsl.c:1069 (bts=0,trx=0,ts=2,ss=0): MEAS RES for inactive channel Mon Jul 22 19:19:04 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b 07 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:04 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:04 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 0a 19 04 3f 3f 00 ff 04 00 0a 28 00 0b 00 12 06 15 3f 3f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:04 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:05 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 0d 19 04 00 00 3f ff 04 05 Mon Jul 22 19:19:05 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:05 2013 <0004> abis_rsl.c:1069 (bts=0,trx=0,ts=2,ss=0): MEAS RES for inactive channel Mon Jul 22 19:19:05 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b 08 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:05 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:05 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 0b 19 04 3f 3f 00 ff 04 00 0a 28 00 0b 00 12 06 15 3f 3f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:05 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:05 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 0e 19 04 00 00 3f ff 04 05 Mon Jul 22 19:19:05 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:05 2013 <0004> abis_rsl.c:1069 (bts=0,trx=0,ts=2,ss=0): MEAS RES for inactive channel Mon Jul 22 19:19:05 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b 09 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:05 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:05 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 0c 19 04 3f 3f 00 ff 04 00 0a 28 00 0b 00 12 06 15 3f 3f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:05 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:06 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 0f 19 04 00 00 3f ff 04 05 Mon Jul 22 19:19:06 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:06 2013 <0004> abis_rsl.c:1069 (bts=0,trx=0,ts=2,ss=0): MEAS RES for inactive channel Mon Jul 22 19:19:06 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b 0a 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:06 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:06 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 0d 19 04 3f 3f 00 ff 04 00 0a 28 00 0b 00 12 06 15 3f 3f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:06 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:06 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 10 19 04 00 00 3f ff 04 05 Mon Jul 22 19:19:06 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:06 2013 <0004> abis_rsl.c:1069 (bts=0,trx=0,ts=2,ss=0): MEAS RES for inactive channel Mon Jul 22 19:19:06 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b 0b 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:06 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:06 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 0e 19 04 3f 3f 00 ff 04 00 0a 28 00 0b 00 12 06 15 3f 3f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:06 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:07 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 11 19 04 00 00 3f ff 04 05 Mon Jul 22 19:19:07 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:07 2013 <0004> abis_rsl.c:1069 (bts=0,trx=0,ts=2,ss=0): MEAS RES for inactive channel Mon Jul 22 19:19:07 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b 0c 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:07 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:07 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 0f 19 04 3f 3f 00 ff 04 00 0a 28 00 0b 00 12 06 15 3f 3f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:07 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:07 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 12 19 04 00 00 3f ff 04 05 Mon Jul 22 19:19:07 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:07 2013 <0004> abis_rsl.c:1069 (bts=0,trx=0,ts=2,ss=0): MEAS RES for inactive channel Mon Jul 22 19:19:07 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b 0d 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:07 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:07 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 10 19 04 3f 3f 00 ff 04 00 0a 28 00 0b 00 12 06 15 3f 3f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:07 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:08 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 13 19 04 00 00 3f ff 04 05 Mon Jul 22 19:19:08 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:08 2013 <0004> abis_rsl.c:1069 (bts=0,trx=0,ts=2,ss=0): MEAS RES for inactive channel Mon Jul 22 19:19:08 2013 <0018> input/lapd.c:347 RX: 00 03 7c 18 0c 12 01 88 12 06 0c a8 00 02 00 00 Mon Jul 22 19:19:08 2013 <0018> lapd_core.c:1465 I received in state LAPD_STATE_MF_EST Mon Jul 22 19:19:08 2013 <0018> lapd_core.c:1548 incrementing V(R) to 63 Mon Jul 22 19:19:08 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:19:08 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:08 2013 <0018> lapd_core.c:1557 message in single I frame Mon Jul 22 19:19:08 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1615 Mon Jul 22 19:19:08 2013 <0018> lapd_core.c:1618 we are not busy and have no pending data, send RR Mon Jul 22 19:19:08 2013 <0018> input/lapd.c:600 TX: 00 03 01 7e Mon Jul 22 19:19:08 2013 <0018> input/lapd.c:347 RX: 00 03 7e 18 0c 12 01 90 0f 00 64 ff 00 00 00 00 fa 03 00 00 00 Mon Jul 22 19:19:08 2013 <0018> lapd_core.c:1465 I received in state LAPD_STATE_MF_EST Mon Jul 22 19:19:08 2013 <0018> lapd_core.c:1548 incrementing V(R) to 64 Mon Jul 22 19:19:08 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:19:08 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:08 2013 <0018> lapd_core.c:1557 message in single I frame Mon Jul 22 19:19:08 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1615 Mon Jul 22 19:19:08 2013 <0018> lapd_core.c:1618 we are not busy and have no pending data, send RR Mon Jul 22 19:19:08 2013 <0018> input/lapd.c:600 TX: 00 03 01 80 Mon Jul 22 19:19:08 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b 0e 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:08 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:08 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 11 19 04 3f 3f 00 ff 04 00 0a 28 00 0b 00 12 06 15 3c 3d 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:08 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:08 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 14 19 04 00 00 3f ff 04 05 Mon Jul 22 19:19:08 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:08 2013 <0004> abis_rsl.c:1069 (bts=0,trx=0,ts=2,ss=0): MEAS RES for inactive channel Mon Jul 22 19:19:08 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b 0f 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:08 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:08 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 12 19 04 3f 3f 00 ff 04 00 0a 28 00 0b 00 12 06 15 38 38 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:08 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:09 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 15 19 04 00 00 3f ff 04 05 Mon Jul 22 19:19:09 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:09 2013 <0004> abis_rsl.c:1069 (bts=0,trx=0,ts=2,ss=0): MEAS RES for inactive channel Mon Jul 22 19:19:09 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b 10 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:09 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:09 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 13 19 04 3f 3f 00 ff 04 00 0a 28 00 0b 00 12 06 15 37 37 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:09 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:09 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 16 19 04 00 00 3f ff 04 05 Mon Jul 22 19:19:09 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:09 2013 <0004> abis_rsl.c:1069 (bts=0,trx=0,ts=2,ss=0): MEAS RES for inactive channel Mon Jul 22 19:19:09 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b 11 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:09 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:09 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 14 19 04 3f 3f 00 ff 04 00 0a 28 00 0b 00 12 06 15 35 35 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:09 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:10 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 17 19 04 00 00 3f ff 04 05 Mon Jul 22 19:19:10 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:10 2013 <0004> abis_rsl.c:1069 (bts=0,trx=0,ts=2,ss=0): MEAS RES for inactive channel Mon Jul 22 19:19:10 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b 12 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:10 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:10 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 15 19 04 3f 3f 00 ff 04 00 0a 28 00 0b 00 12 06 15 36 35 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:10 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:10 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 18 19 04 00 00 3f ff 04 05 Mon Jul 22 19:19:10 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:10 2013 <0004> abis_rsl.c:1069 (bts=0,trx=0,ts=2,ss=0): MEAS RES for inactive channel Mon Jul 22 19:19:10 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b 13 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:10 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:10 2013 <0018> lapd_core.c:680 Timeout T203 (0x9757180) state=7 Mon Jul 22 19:19:10 2013 <0018> lapd_core.c:229 new state LAPD_STATE_MF_EST -> LAPD_STATE_TIMER_RECOV Mon Jul 22 19:19:10 2013 <0018> lapd_core.c:694 transmit an RR poll command Mon Jul 22 19:19:10 2013 <0018> input/lapd.c:600 TX: fa 03 01 07 Mon Jul 22 19:19:10 2013 <0018> lapd_core.c:198 start T200 Mon Jul 22 19:19:10 2013 <0018> input/lapd.c:347 RX: fa 03 01 07 Mon Jul 22 19:19:10 2013 <0018> lapd_core.c:1258 RR received in state LAPD_STATE_TIMER_RECOV Mon Jul 22 19:19:10 2013 <0018> lapd_core.c:1287 RR response with F==1, and we are in timer recovery state, so we leave that state Mon Jul 22 19:19:10 2013 <0018> lapd_core.c:214 stop T200 Mon Jul 22 19:19:10 2013 <0018> lapd_core.c:229 new state LAPD_STATE_TIMER_RECOV -> LAPD_STATE_MF_EST Mon Jul 22 19:19:10 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:10 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1296 Mon Jul 22 19:19:10 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 16 19 04 3f 3f 00 ff 04 00 0a 28 00 0b 00 12 06 15 37 37 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:10 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:10 2013 <0018> lapd_core.c:680 Timeout T203 (0x9793ae8) state=7 Mon Jul 22 19:19:10 2013 <0018> lapd_core.c:229 new state LAPD_STATE_MF_EST -> LAPD_STATE_TIMER_RECOV Mon Jul 22 19:19:10 2013 <0018> lapd_core.c:694 transmit an RR poll command Mon Jul 22 19:19:10 2013 <0018> input/lapd.c:600 TX: fa 03 01 03 Mon Jul 22 19:19:10 2013 <0018> lapd_core.c:198 start T200 Mon Jul 22 19:19:10 2013 <0018> input/lapd.c:347 RX: fa 03 01 03 Mon Jul 22 19:19:10 2013 <0018> lapd_core.c:1258 RR received in state LAPD_STATE_TIMER_RECOV Mon Jul 22 19:19:10 2013 <0018> lapd_core.c:1287 RR response with F==1, and we are in timer recovery state, so we leave that state Mon Jul 22 19:19:10 2013 <0018> lapd_core.c:214 stop T200 Mon Jul 22 19:19:10 2013 <0018> lapd_core.c:229 new state LAPD_STATE_TIMER_RECOV -> LAPD_STATE_MF_EST Mon Jul 22 19:19:10 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:10 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1296 Mon Jul 22 19:19:11 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 19 19 04 00 00 3f ff 04 05 Mon Jul 22 19:19:11 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:11 2013 <0004> abis_rsl.c:1069 (bts=0,trx=0,ts=2,ss=0): MEAS RES for inactive channel Mon Jul 22 19:19:11 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b 14 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:11 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:11 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 17 19 04 3f 3f 00 ff 04 00 0a 28 00 0b 00 12 06 15 39 38 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:11 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:11 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 1a 19 04 00 00 3f ff 04 05 Mon Jul 22 19:19:11 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:11 2013 <0004> abis_rsl.c:1069 (bts=0,trx=0,ts=2,ss=0): MEAS RES for inactive channel Mon Jul 22 19:19:11 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b 15 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:11 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:11 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 18 19 04 3f 3f 00 ff 04 00 0a 28 00 0b 00 12 06 15 3e 3d 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:11 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:12 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 1b 19 04 00 00 3f ff 04 05 Mon Jul 22 19:19:12 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:12 2013 <0004> abis_rsl.c:1069 (bts=0,trx=0,ts=2,ss=0): MEAS RES for inactive channel Mon Jul 22 19:19:12 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b 16 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:12 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:12 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 19 19 04 3f 3f 00 ff 04 00 0a 28 00 0b 00 12 06 15 3f 3f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:12 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:12 2013 <0018> input/lapd.c:347 RX: 00 03 be 6c 10 19 15 1c 41 00 49 00 51 00 59 00 61 00 69 00 71 00 79 00 0a 00 0b 00 0c 00 0d 00 0e 00 0f 00 Mon Jul 22 19:19:12 2013 <0018> lapd_core.c:1465 I received in state LAPD_STATE_MF_EST Mon Jul 22 19:19:12 2013 <0018> lapd_core.c:1548 incrementing V(R) to 96 Mon Jul 22 19:19:12 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:19:12 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:12 2013 <0018> lapd_core.c:1557 message in single I frame Mon Jul 22 19:19:12 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1615 Mon Jul 22 19:19:12 2013 <0018> lapd_core.c:1618 we are not busy and have no pending data, send RR Mon Jul 22 19:19:12 2013 <0018> input/lapd.c:600 TX: 00 03 01 c0 Mon Jul 22 19:19:12 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 1c 19 04 00 00 3f ff 04 05 Mon Jul 22 19:19:12 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:12 2013 <0004> abis_rsl.c:1069 (bts=0,trx=0,ts=2,ss=0): MEAS RES for inactive channel Mon Jul 22 19:19:12 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b 17 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:12 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:12 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 1a 19 04 3f 3f 00 ff 04 00 0a 28 00 0b 00 12 06 15 3f 3f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:12 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:12 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 1d 19 04 00 00 3f ff 04 05 Mon Jul 22 19:19:12 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:12 2013 <0004> abis_rsl.c:1069 (bts=0,trx=0,ts=2,ss=0): MEAS RES for inactive channel Mon Jul 22 19:19:13 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b 18 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:13 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:13 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 1b 19 04 3f 3f 00 ff 04 00 0a 28 00 0b 00 12 06 15 3f 3f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:13 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:13 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 1e 19 04 00 00 3f ff 04 05 Mon Jul 22 19:19:13 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:13 2013 <0004> abis_rsl.c:1069 (bts=0,trx=0,ts=2,ss=0): MEAS RES for inactive channel Mon Jul 22 19:19:13 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b 19 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:13 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:13 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 1c 19 04 3f 3f 00 ff 04 00 0a 28 00 0b 00 12 06 15 3f 3f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:13 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:13 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 1f 19 04 00 00 3f ff 04 05 Mon Jul 22 19:19:13 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:13 2013 <0004> abis_rsl.c:1069 (bts=0,trx=0,ts=2,ss=0): MEAS RES for inactive channel Mon Jul 22 19:19:13 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b 1a 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:13 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:14 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 1d 19 04 3f 3f 00 ff 04 00 0a 28 00 0b 00 12 06 15 3f 3f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:14 2013 <0018> input/lapd.c:347 RX: 00 03 c0 6c 08 24 01 0a 1a 01 01 Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:1465 I received in state LAPD_STATE_MF_EST Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:1548 incrementing V(R) to 97 Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:1557 message in single I frame Mon Jul 22 19:19:14 2013 <0004> abis_rsl.c:988 (bts=0,trx=0,ts=2,ss=0) CONNECTION FAIL: RELEASING CAUSE=0x01(Radio Link Failure)  Mon Jul 22 19:19:14 2013 <0004> abis_rsl.c:679 (bts=0,trx=0,ts=2,ss=0) RF Channel Release CMD due error 1 Mon Jul 22 19:19:14 2013 <0004> abis_rsl.c:633 (bts=0,trx=0,ts=2,ss=0) DEACTivate SACCH CMD Mon Jul 22 19:19:14 2013 <0000> abis_rsl.c:891 (bts=0,trx=0,ts=2,ss=0) RSL RLL RELEASE REQ (link_id=0x40, reason=1) Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1615 Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:1618 we are not busy and have no pending data, send RR Mon Jul 22 19:19:14 2013 <0018> input/lapd.c:600 TX: 00 03 01 c2 Mon Jul 22 19:19:14 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 20 19 04 00 00 3f ff 04 05 Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:14 2013 <0004> abis_rsl.c:1069 (bts=0,trx=0,ts=2,ss=0): MEAS RES for inactive channel Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:2183 Message DL-DATA-REQUEST received in state LAPD_STATE_MF_EST Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:1758 writing message to send-queue: l3len: 4 Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1764 Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:1821 get message from send-queue Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:1833 msg-len 4 sent 0 left 4 N201 260 length 4 first byte 08 Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:1842 send I frame V(S)=54 Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:198 start T200 Mon Jul 22 19:19:14 2013 <0018> input/lapd.c:600 TX: 02 03 6c c2 08 25 01 0a Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:1833 msg-len 4 sent 4 left 0 N201 260 length 0 first byte 08 Mon Jul 22 19:19:14 2013 <0018> input/lapd.c:347 RX: 02 03 01 6e Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:1258 RR received in state LAPD_STATE_MF_EST Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:727 ack frame 54 Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:214 stop T200 Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1296 Mon Jul 22 19:19:14 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b 1b 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:2183 Message DL-DATA-REQUEST received in state LAPD_STATE_MF_EST Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:1758 writing message to send-queue: l3len: 8 Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1764 Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:1821 get message from send-queue Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:1833 msg-len 8 sent 0 left 8 N201 260 length 8 first byte 02 Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:1842 send I frame V(S)=55 Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:198 start T200 Mon Jul 22 19:19:14 2013 <0018> input/lapd.c:600 TX: 02 03 6e c2 02 07 01 0a 02 40 14 01 Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:1833 msg-len 8 sent 8 left 0 N201 260 length 0 first byte 02 Mon Jul 22 19:19:14 2013 <0018> input/lapd.c:347 RX: 02 03 01 70 Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:1258 RR received in state LAPD_STATE_MF_EST Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:727 ack frame 55 Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:214 stop T200 Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1296 Mon Jul 22 19:19:14 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 1e 19 04 3f 3f 00 ff 04 00 0a 28 00 0b 00 12 06 15 3f 3f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:2183 Message DL-DATA-REQUEST received in state LAPD_STATE_MF_EST Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:1758 writing message to send-queue: l3len: 4 Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1764 Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:1821 get message from send-queue Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:1833 msg-len 4 sent 0 left 4 N201 260 length 4 first byte 08 Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:1842 send I frame V(S)=56 Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:198 start T200 Mon Jul 22 19:19:14 2013 <0018> input/lapd.c:600 TX: 02 03 70 c2 08 2e 01 0a Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:1833 msg-len 4 sent 4 left 0 N201 260 length 0 first byte 08 Mon Jul 22 19:19:14 2013 <0018> input/lapd.c:347 RX: 02 03 01 72 Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:1258 RR received in state LAPD_STATE_MF_EST Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:727 ack frame 56 Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:214 stop T200 Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1296 Mon Jul 22 19:19:14 2013 <0018> input/lapd.c:347 RX: 00 03 c2 72 08 33 01 0a Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:1465 I received in state LAPD_STATE_MF_EST Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:1548 incrementing V(R) to 98 Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:1557 message in single I frame Mon Jul 22 19:19:14 2013 <0004> abis_rsl.c:731 (bts=0,trx=0,ts=2,ss=0) RF CHANNEL RELEASE ACK Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1615 Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:1618 we are not busy and have no pending data, send RR Mon Jul 22 19:19:14 2013 <0018> input/lapd.c:600 TX: 00 03 01 c4 Mon Jul 22 19:19:14 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:14 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:14 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:14 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:14 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:14 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:14 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:14 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:14 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:14 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:14 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:14 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:14 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:14 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:14 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:14 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:14 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:14 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:14 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:14 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:14 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:14 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:14 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:14 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:14 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:14 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:14 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:14 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:14 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:14 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:14 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:14 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:14 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:14 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:14 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:14 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:14 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:14 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:14 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:14 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:14 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b 1c 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:14 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:14 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:14 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:14 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:14 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 1f 19 04 3f 3f 00 ff 04 00 0a 28 00 0b 00 12 06 15 3f 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:14 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:14 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:14 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0b 1b 1d 19 04 3f 3f 00 ff 04 05 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 20 19 04 3f 3f 00 ff 04 00 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <0018> input/lapd.c:347 RX: 00 03 c4 72 08 24 01 0b 1a 01 28 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1465 I received in state LAPD_STATE_MF_EST Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1548 incrementing V(R) to 99 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1557 message in single I frame Mon Jul 22 19:19:15 2013 <0004> abis_rsl.c:988 (bts=0,trx=0,ts=3,ss=0) CONNECTION FAIL: RELEASING CAUSE=0x28(unknown 0x28)  Mon Jul 22 19:19:15 2013 <0004> abis_rsl.c:679 (bts=0,trx=0,ts=3,ss=0) RF Channel Release CMD due error 1 Mon Jul 22 19:19:15 2013 <0004> abis_rsl.c:633 (bts=0,trx=0,ts=3,ss=0) DEACTivate SACCH CMD Mon Jul 22 19:19:15 2013 <0000> abis_rsl.c:891 (bts=0,trx=0,ts=3,ss=0) RSL RLL RELEASE REQ (link_id=0x40, reason=1) Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1615 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1618 we are not busy and have no pending data, send RR Mon Jul 22 19:19:15 2013 <0018> input/lapd.c:600 TX: 00 03 01 c6 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:2183 Message DL-DATA-REQUEST received in state LAPD_STATE_MF_EST Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1758 writing message to send-queue: l3len: 4 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1764 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1821 get message from send-queue Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1833 msg-len 4 sent 0 left 4 N201 260 length 4 first byte 08 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1842 send I frame V(S)=57 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:198 start T200 Mon Jul 22 19:19:15 2013 <0018> input/lapd.c:600 TX: 02 03 72 c6 08 25 01 0b Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1833 msg-len 4 sent 4 left 0 N201 260 length 0 first byte 08 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <0018> input/lapd.c:347 RX: 02 03 01 74 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1258 RR received in state LAPD_STATE_MF_EST Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:727 ack frame 57 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:214 stop T200 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1296 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:2183 Message DL-DATA-REQUEST received in state LAPD_STATE_MF_EST Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1758 writing message to send-queue: l3len: 8 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1764 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1821 get message from send-queue Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1833 msg-len 8 sent 0 left 8 N201 260 length 8 first byte 02 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1842 send I frame V(S)=58 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:198 start T200 Mon Jul 22 19:19:15 2013 <0018> input/lapd.c:600 TX: 02 03 74 c6 02 07 01 0b 02 40 14 01 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1833 msg-len 8 sent 8 left 0 N201 260 length 0 first byte 02 Mon Jul 22 19:19:15 2013 <0018> input/lapd.c:347 RX: 02 03 01 76 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1258 RR received in state LAPD_STATE_MF_EST Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:727 ack frame 58 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:214 stop T200 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1296 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:2183 Message DL-DATA-REQUEST received in state LAPD_STATE_MF_EST Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1758 writing message to send-queue: l3len: 4 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1764 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1821 get message from send-queue Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1833 msg-len 4 sent 0 left 4 N201 260 length 4 first byte 08 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1842 send I frame V(S)=59 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:198 start T200 Mon Jul 22 19:19:15 2013 <0018> input/lapd.c:600 TX: 02 03 76 c6 08 2e 01 0b Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1833 msg-len 4 sent 4 left 0 N201 260 length 0 first byte 08 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:196 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <001a> trau_frame.c:275 unimplemented TRAU Frame Type 0x06 Mon Jul 22 19:19:15 2013 <0018> input/lapd.c:347 RX: 02 03 01 78 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1258 RR received in state LAPD_STATE_MF_EST Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:727 ack frame 59 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:214 stop T200 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1296 Mon Jul 22 19:19:15 2013 <0018> input/lapd.c:347 RX: 00 03 c6 78 08 33 01 0b Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1465 I received in state LAPD_STATE_MF_EST Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1548 incrementing V(R) to 100 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1557 message in single I frame Mon Jul 22 19:19:15 2013 <0004> abis_rsl.c:731 (bts=0,trx=0,ts=3,ss=0) RF CHANNEL RELEASE ACK Mon Jul 22 19:19:15 2013 <0001> gsm_04_08.c:1265 (bts 0 trx 0 ts 3 ti 0 sub 12346) Sending 'MNCC_REL_IND' to MNCC. Mon Jul 22 19:19:15 2013 <0006> mncc_builtin.c:348 (call 1) Received message MNCC_REL_IND Mon Jul 22 19:19:15 2013 <0006> mncc_builtin.c:257 (call 1) Releasing remote with cause 47 Mon Jul 22 19:19:15 2013 <0006> mncc_builtin.c:52 (call 1) Call removed. Mon Jul 22 19:19:15 2013 <0006> gsm_04_08.c:2877 receive message MNCC_REL_REQ Mon Jul 22 19:19:15 2013 <0001> gsm_04_08.c:3063 (bts 0 trx 0 ts 2 ti 08 sub 12345) Received 'MNCC_REL_REQ' from MNCC in state 10 (ACTIVE) Mon Jul 22 19:19:15 2013 <0001> gsm_04_08.c:1739 starting timer T308 with 10 seconds Mon Jul 22 19:19:15 2013 <0001> gsm_04_08.c:1204 new state ACTIVE -> RELEASE_REQ Mon Jul 22 19:19:15 2013 <0001> gsm_04_08.c:113 (bts 1 trx 0 ts 2 ti 80) Sending 'RELEASE' to MS. Mon Jul 22 19:19:15 2013 <0001> gsm_04_08.c:1204 new state ACTIVE -> NULL Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1615 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1618 we are not busy and have no pending data, send RR Mon Jul 22 19:19:15 2013 <0018> input/lapd.c:600 TX: 00 03 01 c8 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:2183 Message DL-DATA-REQUEST received in state LAPD_STATE_MF_EST Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1758 writing message to send-queue: l3len: 15 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1764 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1821 get message from send-queue Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1833 msg-len 15 sent 0 left 15 N201 260 length 15 first byte 03 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1842 send I frame V(S)=12 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:198 start T200 Mon Jul 22 19:19:15 2013 <0018> input/lapd.c:600 TX: 02 03 18 80 03 01 01 0a 02 00 0b 00 06 83 2d 08 02 81 af Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1833 msg-len 15 sent 15 left 0 N201 260 length 0 first byte 03 Mon Jul 22 19:19:15 2013 <0018> input/lapd.c:347 RX: 02 03 01 1a Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1258 RR received in state LAPD_STATE_MF_EST Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:727 ack frame 12 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:214 stop T200 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1296 Mon Jul 22 19:19:15 2013 <0018> input/lapd.c:347 RX: 00 03 03 08 28 01 0a 1b 21 19 04 3f 3f 00 ff 04 00 0a 28 00 0b 00 12 06 15 3e 3e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:998 UI received Mon Jul 22 19:19:15 2013 <0018> input/lapd.c:347 RX: 00 03 80 1a 03 02 01 0a 02 00 0b 00 02 03 6a Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1465 I received in state LAPD_STATE_MF_EST Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1548 incrementing V(R) to 65 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1557 message in single I frame Mon Jul 22 19:19:15 2013 <0000> abis_rsl.c:1621 (bts=1,trx=0,ts=2,ss=0) SAPI=0 DATA INDICATION Mon Jul 22 19:19:15 2013 <0001> gsm_04_08.c:3156 (bts 0 trx 0 ts 2 ti 8 sub 12345) Received 'RELEASE_COMPL' from MS in state 19 (RELEASE_REQ) Mon Jul 22 19:19:15 2013 <0001> gsm_04_08.c:1245 stopping pending timer T308 Mon Jul 22 19:19:15 2013 <0001> gsm_04_08.c:1265 (bts 1 trx 0 ts 2 ti 8 sub 12345) Sending 'MNCC_REL_CNF' to MNCC. Mon Jul 22 19:19:15 2013 <0006> mncc_builtin.c:348 (call 80000001) Received message MNCC_REL_CNF Mon Jul 22 19:19:15 2013 <0006> mncc_builtin.c:52 (call 80000001) Call removed. Mon Jul 22 19:19:15 2013 <0001> gsm_04_08.c:1204 new state RELEASE_REQ -> NULL Mon Jul 22 19:19:15 2013 <0000> chan_alloc.c:405 (bts=1,trx=0,ts=2,ss=0) starting release sequence Mon Jul 22 19:19:15 2013 <0003> gsm_04_08_utils.c:231 Sending Channel Release: Chan: Number: 0 Type: 2 Mon Jul 22 19:19:15 2013 <0004> abis_rsl.c:633 (bts=1,trx=0,ts=2,ss=0) DEACTivate SACCH CMD Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1615 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1618 we are not busy and have no pending data, send RR Mon Jul 22 19:19:15 2013 <0018> input/lapd.c:600 TX: 00 03 01 82 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:2183 Message DL-DATA-REQUEST received in state LAPD_STATE_MF_EST Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1758 writing message to send-queue: l3len: 12 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1764 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1821 get message from send-queue Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1833 msg-len 12 sent 0 left 12 N201 260 length 12 first byte 03 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1842 send I frame V(S)=13 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:198 start T200 Mon Jul 22 19:19:15 2013 <0018> input/lapd.c:600 TX: 02 03 1a 82 03 01 01 0a 02 00 0b 00 03 06 0d 00 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1833 msg-len 12 sent 12 left 0 N201 260 length 0 first byte 03 Mon Jul 22 19:19:15 2013 <0018> input/lapd.c:347 RX: 02 03 01 1c Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1258 RR received in state LAPD_STATE_MF_EST Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:727 ack frame 13 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:214 stop T200 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:15 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1296 Mon Jul 22 19:19:16 2013 <0018> lapd_core.c:2183 Message DL-DATA-REQUEST received in state LAPD_STATE_MF_EST Mon Jul 22 19:19:16 2013 <0018> lapd_core.c:1758 writing message to send-queue: l3len: 4 Mon Jul 22 19:19:16 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1764 Mon Jul 22 19:19:16 2013 <0018> lapd_core.c:1821 get message from send-queue Mon Jul 22 19:19:16 2013 <0018> lapd_core.c:1833 msg-len 4 sent 0 left 4 N201 260 length 4 first byte 08 Mon Jul 22 19:19:16 2013 <0018> lapd_core.c:1842 send I frame V(S)=14 Mon Jul 22 19:19:16 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:19:16 2013 <0018> lapd_core.c:198 start T200 Mon Jul 22 19:19:16 2013 <0018> input/lapd.c:600 TX: 02 03 1c 82 08 25 01 0a Mon Jul 22 19:19:16 2013 <0018> lapd_core.c:1833 msg-len 4 sent 4 left 0 N201 260 length 0 first byte 08 Mon Jul 22 19:19:16 2013 <0018> input/lapd.c:347 RX: 02 03 01 1e Mon Jul 22 19:19:16 2013 <0018> lapd_core.c:1258 RR received in state LAPD_STATE_MF_EST Mon Jul 22 19:19:16 2013 <0018> lapd_core.c:727 ack frame 14 Mon Jul 22 19:19:16 2013 <0018> lapd_core.c:214 stop T200 Mon Jul 22 19:19:16 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:16 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1296 Mon Jul 22 19:19:16 2013 <0018> input/lapd.c:347 RX: 00 03 82 1e 02 09 01 0a 02 00 Mon Jul 22 19:19:16 2013 <0018> lapd_core.c:1465 I received in state LAPD_STATE_MF_EST Mon Jul 22 19:19:16 2013 <0018> lapd_core.c:1548 incrementing V(R) to 66 Mon Jul 22 19:19:16 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:19:16 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:16 2013 <0018> lapd_core.c:1557 message in single I frame Mon Jul 22 19:19:16 2013 <0000> abis_rsl.c:1621 (bts=1,trx=0,ts=2,ss=0) SAPI=0 RELEASE INDICATION Mon Jul 22 19:19:16 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1615 Mon Jul 22 19:19:16 2013 <0018> lapd_core.c:1618 we are not busy and have no pending data, send RR Mon Jul 22 19:19:16 2013 <0018> input/lapd.c:600 TX: 00 03 01 84 Mon Jul 22 19:19:20 2013 <0018> input/lapd.c:347 RX: 00 03 84 1e 10 19 15 16 20 00 28 00 30 00 38 00 09 00 0a 00 0b 00 0c 00 0d 00 0e 00 0f 00 Mon Jul 22 19:19:20 2013 <0018> lapd_core.c:1465 I received in state LAPD_STATE_MF_EST Mon Jul 22 19:19:20 2013 <0018> lapd_core.c:1548 incrementing V(R) to 67 Mon Jul 22 19:19:20 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:19:20 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:20 2013 <0018> lapd_core.c:1557 message in single I frame Mon Jul 22 19:19:20 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1615 Mon Jul 22 19:19:20 2013 <0018> lapd_core.c:1618 we are not busy and have no pending data, send RR Mon Jul 22 19:19:20 2013 <0018> input/lapd.c:600 TX: 00 03 01 86 Mon Jul 22 19:19:20 2013 <0018> lapd_core.c:680 Timeout T203 (0x9757180) state=7 Mon Jul 22 19:19:20 2013 <0018> lapd_core.c:229 new state LAPD_STATE_MF_EST -> LAPD_STATE_TIMER_RECOV Mon Jul 22 19:19:20 2013 <0018> lapd_core.c:694 transmit an RR poll command Mon Jul 22 19:19:20 2013 <0018> input/lapd.c:600 TX: fa 03 01 07 Mon Jul 22 19:19:20 2013 <0018> lapd_core.c:198 start T200 Mon Jul 22 19:19:20 2013 <0018> input/lapd.c:347 RX: fa 03 01 07 Mon Jul 22 19:19:20 2013 <0018> lapd_core.c:1258 RR received in state LAPD_STATE_TIMER_RECOV Mon Jul 22 19:19:20 2013 <0018> lapd_core.c:1287 RR response with F==1, and we are in timer recovery state, so we leave that state Mon Jul 22 19:19:20 2013 <0018> lapd_core.c:214 stop T200 Mon Jul 22 19:19:20 2013 <0018> lapd_core.c:229 new state LAPD_STATE_TIMER_RECOV -> LAPD_STATE_MF_EST Mon Jul 22 19:19:20 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:20 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1296 Mon Jul 22 19:19:20 2013 <0018> lapd_core.c:680 Timeout T203 (0x9793ae8) state=7 Mon Jul 22 19:19:20 2013 <0018> lapd_core.c:229 new state LAPD_STATE_MF_EST -> LAPD_STATE_TIMER_RECOV Mon Jul 22 19:19:20 2013 <0018> lapd_core.c:694 transmit an RR poll command Mon Jul 22 19:19:20 2013 <0018> input/lapd.c:600 TX: fa 03 01 03 Mon Jul 22 19:19:20 2013 <0018> lapd_core.c:198 start T200 Mon Jul 22 19:19:20 2013 <0018> input/lapd.c:347 RX: fa 03 01 03 Mon Jul 22 19:19:20 2013 <0018> lapd_core.c:1258 RR received in state LAPD_STATE_TIMER_RECOV Mon Jul 22 19:19:20 2013 <0018> lapd_core.c:1287 RR response with F==1, and we are in timer recovery state, so we leave that state Mon Jul 22 19:19:20 2013 <0018> lapd_core.c:214 stop T200 Mon Jul 22 19:19:20 2013 <0018> lapd_core.c:229 new state LAPD_STATE_TIMER_RECOV -> LAPD_STATE_MF_EST Mon Jul 22 19:19:20 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:20 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1296 Mon Jul 22 19:19:25 2013 <0004> abis_rsl.c:679 (bts=1,trx=0,ts=2,ss=0) RF Channel Release CMD due error 0 Mon Jul 22 19:19:25 2013 <0018> lapd_core.c:2183 Message DL-DATA-REQUEST received in state LAPD_STATE_MF_EST Mon Jul 22 19:19:25 2013 <0018> lapd_core.c:1758 writing message to send-queue: l3len: 4 Mon Jul 22 19:19:25 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1764 Mon Jul 22 19:19:25 2013 <0018> lapd_core.c:1821 get message from send-queue Mon Jul 22 19:19:25 2013 <0018> lapd_core.c:1833 msg-len 4 sent 0 left 4 N201 260 length 4 first byte 08 Mon Jul 22 19:19:25 2013 <0018> lapd_core.c:1842 send I frame V(S)=15 Mon Jul 22 19:19:25 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:19:25 2013 <0018> lapd_core.c:198 start T200 Mon Jul 22 19:19:25 2013 <0018> input/lapd.c:600 TX: 02 03 1e 86 08 2e 01 0a Mon Jul 22 19:19:25 2013 <0018> lapd_core.c:1833 msg-len 4 sent 4 left 0 N201 260 length 0 first byte 08 Mon Jul 22 19:19:25 2013 <0018> input/lapd.c:347 RX: 02 03 01 20 Mon Jul 22 19:19:25 2013 <0018> lapd_core.c:1258 RR received in state LAPD_STATE_MF_EST Mon Jul 22 19:19:25 2013 <0018> lapd_core.c:727 ack frame 15 Mon Jul 22 19:19:25 2013 <0018> lapd_core.c:214 stop T200 Mon Jul 22 19:19:25 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:25 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1296 Mon Jul 22 19:19:25 2013 <0018> input/lapd.c:347 RX: 00 03 86 20 08 33 01 0a Mon Jul 22 19:19:25 2013 <0018> lapd_core.c:1465 I received in state LAPD_STATE_MF_EST Mon Jul 22 19:19:25 2013 <0018> lapd_core.c:1548 incrementing V(R) to 68 Mon Jul 22 19:19:25 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:19:25 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:25 2013 <0018> lapd_core.c:1557 message in single I frame Mon Jul 22 19:19:25 2013 <0004> abis_rsl.c:731 (bts=1,trx=0,ts=2,ss=0) RF CHANNEL RELEASE ACK Mon Jul 22 19:19:25 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1615 Mon Jul 22 19:19:25 2013 <0018> lapd_core.c:1618 we are not busy and have no pending data, send RR Mon Jul 22 19:19:25 2013 <0018> input/lapd.c:600 TX: 00 03 01 88 Mon Jul 22 19:19:25 2013 <0004> abis_rsl.c:648 (bts=0,trx=0,ts=2,ss=0) is back in operation. Mon Jul 22 19:19:25 2013 <0018> lapd_core.c:680 Timeout T203 (0x979ad08) state=7 Mon Jul 22 19:19:25 2013 <0018> lapd_core.c:229 new state LAPD_STATE_MF_EST -> LAPD_STATE_TIMER_RECOV Mon Jul 22 19:19:25 2013 <0018> lapd_core.c:694 transmit an RR poll command Mon Jul 22 19:19:25 2013 <0018> input/lapd.c:600 TX: 02 03 01 c9 Mon Jul 22 19:19:25 2013 <0018> lapd_core.c:198 start T200 Mon Jul 22 19:19:25 2013 <0018> input/lapd.c:347 RX: 02 03 01 79 Mon Jul 22 19:19:25 2013 <0018> lapd_core.c:1258 RR received in state LAPD_STATE_TIMER_RECOV Mon Jul 22 19:19:25 2013 <0018> lapd_core.c:1287 RR response with F==1, and we are in timer recovery state, so we leave that state Mon Jul 22 19:19:25 2013 <0018> lapd_core.c:214 stop T200 Mon Jul 22 19:19:25 2013 <0018> lapd_core.c:229 new state LAPD_STATE_TIMER_RECOV -> LAPD_STATE_MF_EST Mon Jul 22 19:19:25 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:25 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1296 Mon Jul 22 19:19:26 2013 <0004> abis_rsl.c:648 (bts=0,trx=0,ts=3,ss=0) is back in operation. Mon Jul 22 19:19:28 2013 <0018> input/lapd.c:347 RX: 00 03 c8 78 10 19 15 1c 41 00 49 00 51 00 59 00 61 00 69 00 71 00 79 00 0a 20 0b 00 0c 00 0d 00 0e 00 0f 00 Mon Jul 22 19:19:28 2013 <0018> lapd_core.c:1465 I received in state LAPD_STATE_MF_EST Mon Jul 22 19:19:28 2013 <0018> lapd_core.c:1548 incrementing V(R) to 101 Mon Jul 22 19:19:28 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:19:28 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:28 2013 <0018> lapd_core.c:1557 message in single I frame Mon Jul 22 19:19:28 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1615 Mon Jul 22 19:19:28 2013 <0018> lapd_core.c:1618 we are not busy and have no pending data, send RR Mon Jul 22 19:19:28 2013 <0018> input/lapd.c:600 TX: 00 03 01 ca Mon Jul 22 19:19:30 2013 <0018> lapd_core.c:680 Timeout T203 (0x9757180) state=7 Mon Jul 22 19:19:30 2013 <0018> lapd_core.c:229 new state LAPD_STATE_MF_EST -> LAPD_STATE_TIMER_RECOV Mon Jul 22 19:19:30 2013 <0018> lapd_core.c:694 transmit an RR poll command Mon Jul 22 19:19:30 2013 <0018> input/lapd.c:600 TX: fa 03 01 07 Mon Jul 22 19:19:30 2013 <0018> lapd_core.c:198 start T200 Mon Jul 22 19:19:30 2013 <0018> input/lapd.c:347 RX: fa 03 01 07 Mon Jul 22 19:19:30 2013 <0018> lapd_core.c:1258 RR received in state LAPD_STATE_TIMER_RECOV Mon Jul 22 19:19:30 2013 <0018> lapd_core.c:1287 RR response with F==1, and we are in timer recovery state, so we leave that state Mon Jul 22 19:19:30 2013 <0018> lapd_core.c:214 stop T200 Mon Jul 22 19:19:30 2013 <0018> lapd_core.c:229 new state LAPD_STATE_TIMER_RECOV -> LAPD_STATE_MF_EST Mon Jul 22 19:19:30 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:30 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1296 Mon Jul 22 19:19:30 2013 <0018> lapd_core.c:680 Timeout T203 (0x9793ae8) state=7 Mon Jul 22 19:19:30 2013 <0018> lapd_core.c:229 new state LAPD_STATE_MF_EST -> LAPD_STATE_TIMER_RECOV Mon Jul 22 19:19:30 2013 <0018> lapd_core.c:694 transmit an RR poll command Mon Jul 22 19:19:30 2013 <0018> input/lapd.c:600 TX: fa 03 01 03 Mon Jul 22 19:19:30 2013 <0018> lapd_core.c:198 start T200 Mon Jul 22 19:19:30 2013 <0018> input/lapd.c:347 RX: fa 03 01 03 Mon Jul 22 19:19:30 2013 <0018> lapd_core.c:1258 RR received in state LAPD_STATE_TIMER_RECOV Mon Jul 22 19:19:30 2013 <0018> lapd_core.c:1287 RR response with F==1, and we are in timer recovery state, so we leave that state Mon Jul 22 19:19:30 2013 <0018> lapd_core.c:214 stop T200 Mon Jul 22 19:19:30 2013 <0018> lapd_core.c:229 new state LAPD_STATE_TIMER_RECOV -> LAPD_STATE_MF_EST Mon Jul 22 19:19:30 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:30 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1296 Mon Jul 22 19:19:32 2013 <0018> input/lapd.c:347 RX: 00 03 ca 78 0c 12 01 88 12 06 17 e8 00 00 00 00 Mon Jul 22 19:19:32 2013 <0018> lapd_core.c:1465 I received in state LAPD_STATE_MF_EST Mon Jul 22 19:19:32 2013 <0018> lapd_core.c:1548 incrementing V(R) to 102 Mon Jul 22 19:19:32 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:19:32 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:32 2013 <0018> lapd_core.c:1557 message in single I frame Mon Jul 22 19:19:32 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1615 Mon Jul 22 19:19:32 2013 <0018> lapd_core.c:1618 we are not busy and have no pending data, send RR Mon Jul 22 19:19:32 2013 <0018> input/lapd.c:600 TX: 00 03 01 cc Mon Jul 22 19:19:32 2013 <0018> input/lapd.c:347 RX: 00 03 cc 78 0c 12 01 90 0f 01 90 ff 00 00 00 00 fa 03 00 00 00 Mon Jul 22 19:19:32 2013 <0018> lapd_core.c:1465 I received in state LAPD_STATE_MF_EST Mon Jul 22 19:19:32 2013 <0018> lapd_core.c:1548 incrementing V(R) to 103 Mon Jul 22 19:19:32 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:19:32 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:32 2013 <0018> lapd_core.c:1557 message in single I frame Mon Jul 22 19:19:32 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1615 Mon Jul 22 19:19:32 2013 <0018> lapd_core.c:1618 we are not busy and have no pending data, send RR Mon Jul 22 19:19:32 2013 <0018> input/lapd.c:600 TX: 00 03 01 ce Mon Jul 22 19:19:35 2013 <0018> lapd_core.c:680 Timeout T203 (0x979a068) state=7 Mon Jul 22 19:19:35 2013 <0018> lapd_core.c:229 new state LAPD_STATE_MF_EST -> LAPD_STATE_TIMER_RECOV Mon Jul 22 19:19:35 2013 <0018> lapd_core.c:694 transmit an RR poll command Mon Jul 22 19:19:35 2013 <0018> input/lapd.c:600 TX: 02 03 01 89 Mon Jul 22 19:19:35 2013 <0018> lapd_core.c:198 start T200 Mon Jul 22 19:19:35 2013 <0018> input/lapd.c:347 RX: 02 03 01 21 Mon Jul 22 19:19:35 2013 <0018> lapd_core.c:1258 RR received in state LAPD_STATE_TIMER_RECOV Mon Jul 22 19:19:35 2013 <0018> lapd_core.c:1287 RR response with F==1, and we are in timer recovery state, so we leave that state Mon Jul 22 19:19:35 2013 <0018> lapd_core.c:214 stop T200 Mon Jul 22 19:19:35 2013 <0018> lapd_core.c:229 new state LAPD_STATE_TIMER_RECOV -> LAPD_STATE_MF_EST Mon Jul 22 19:19:35 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:35 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1296 Mon Jul 22 19:19:36 2013 <0018> input/lapd.c:347 RX: 00 03 88 20 10 19 15 16 20 00 28 00 30 00 38 00 09 00 0a 00 0b 00 0c 00 0d 00 0e 00 0f 00 Mon Jul 22 19:19:36 2013 <0018> lapd_core.c:1465 I received in state LAPD_STATE_MF_EST Mon Jul 22 19:19:36 2013 <0018> lapd_core.c:1548 incrementing V(R) to 69 Mon Jul 22 19:19:36 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:19:36 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:36 2013 <0018> lapd_core.c:1557 message in single I frame Mon Jul 22 19:19:36 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1615 Mon Jul 22 19:19:36 2013 <0018> lapd_core.c:1618 we are not busy and have no pending data, send RR Mon Jul 22 19:19:36 2013 <0018> input/lapd.c:600 TX: 00 03 01 8a Mon Jul 22 19:19:36 2013 <0018> input/lapd.c:347 RX: 00 03 8a 20 0c 12 01 88 12 06 0c a8 00 04 00 00 Mon Jul 22 19:19:36 2013 <0018> lapd_core.c:1465 I received in state LAPD_STATE_MF_EST Mon Jul 22 19:19:36 2013 <0018> lapd_core.c:1548 incrementing V(R) to 70 Mon Jul 22 19:19:36 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:19:36 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:36 2013 <0018> lapd_core.c:1557 message in single I frame Mon Jul 22 19:19:36 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1615 Mon Jul 22 19:19:36 2013 <0018> lapd_core.c:1618 we are not busy and have no pending data, send RR Mon Jul 22 19:19:36 2013 <0018> input/lapd.c:600 TX: 00 03 01 8c Mon Jul 22 19:19:36 2013 <0018> input/lapd.c:347 RX: 00 03 8c 20 0c 12 01 90 0f 00 64 ff 00 00 00 00 fa 03 00 00 00 Mon Jul 22 19:19:36 2013 <0018> lapd_core.c:1465 I received in state LAPD_STATE_MF_EST Mon Jul 22 19:19:36 2013 <0018> lapd_core.c:1548 incrementing V(R) to 71 Mon Jul 22 19:19:36 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:19:36 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:36 2013 <0018> lapd_core.c:1557 message in single I frame Mon Jul 22 19:19:36 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1615 Mon Jul 22 19:19:36 2013 <0018> lapd_core.c:1618 we are not busy and have no pending data, send RR Mon Jul 22 19:19:36 2013 <0018> input/lapd.c:600 TX: 00 03 01 8e Mon Jul 22 19:19:40 2013 <0018> lapd_core.c:680 Timeout T203 (0x9757180) state=7 Mon Jul 22 19:19:40 2013 <0018> lapd_core.c:229 new state LAPD_STATE_MF_EST -> LAPD_STATE_TIMER_RECOV Mon Jul 22 19:19:40 2013 <0018> lapd_core.c:694 transmit an RR poll command Mon Jul 22 19:19:40 2013 <0018> input/lapd.c:600 TX: fa 03 01 07 Mon Jul 22 19:19:40 2013 <0018> lapd_core.c:198 start T200 Mon Jul 22 19:19:40 2013 <0018> input/lapd.c:347 RX: fa 03 01 07 Mon Jul 22 19:19:40 2013 <0018> lapd_core.c:1258 RR received in state LAPD_STATE_TIMER_RECOV Mon Jul 22 19:19:40 2013 <0018> lapd_core.c:1287 RR response with F==1, and we are in timer recovery state, so we leave that state Mon Jul 22 19:19:40 2013 <0018> lapd_core.c:214 stop T200 Mon Jul 22 19:19:40 2013 <0018> lapd_core.c:229 new state LAPD_STATE_TIMER_RECOV -> LAPD_STATE_MF_EST Mon Jul 22 19:19:40 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:40 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1296 Mon Jul 22 19:19:40 2013 <0018> lapd_core.c:680 Timeout T203 (0x9793ae8) state=7 Mon Jul 22 19:19:40 2013 <0018> lapd_core.c:229 new state LAPD_STATE_MF_EST -> LAPD_STATE_TIMER_RECOV Mon Jul 22 19:19:40 2013 <0018> lapd_core.c:694 transmit an RR poll command Mon Jul 22 19:19:40 2013 <0018> input/lapd.c:600 TX: fa 03 01 03 Mon Jul 22 19:19:40 2013 <0018> lapd_core.c:198 start T200 Mon Jul 22 19:19:40 2013 <0018> input/lapd.c:347 RX: fa 03 01 03 Mon Jul 22 19:19:40 2013 <0018> lapd_core.c:1258 RR received in state LAPD_STATE_TIMER_RECOV Mon Jul 22 19:19:40 2013 <0018> lapd_core.c:1287 RR response with F==1, and we are in timer recovery state, so we leave that state Mon Jul 22 19:19:40 2013 <0018> lapd_core.c:214 stop T200 Mon Jul 22 19:19:40 2013 <0018> lapd_core.c:229 new state LAPD_STATE_TIMER_RECOV -> LAPD_STATE_MF_EST Mon Jul 22 19:19:40 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:40 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1296 Mon Jul 22 19:19:42 2013 <0018> lapd_core.c:680 Timeout T203 (0x979ad08) state=7 Mon Jul 22 19:19:42 2013 <0018> lapd_core.c:229 new state LAPD_STATE_MF_EST -> LAPD_STATE_TIMER_RECOV Mon Jul 22 19:19:42 2013 <0018> lapd_core.c:694 transmit an RR poll command Mon Jul 22 19:19:42 2013 <0018> input/lapd.c:600 TX: 02 03 01 cf Mon Jul 22 19:19:42 2013 <0018> lapd_core.c:198 start T200 Mon Jul 22 19:19:42 2013 <0018> input/lapd.c:347 RX: 02 03 01 79 Mon Jul 22 19:19:42 2013 <0018> lapd_core.c:1258 RR received in state LAPD_STATE_TIMER_RECOV Mon Jul 22 19:19:42 2013 <0018> lapd_core.c:1287 RR response with F==1, and we are in timer recovery state, so we leave that state Mon Jul 22 19:19:42 2013 <0018> lapd_core.c:214 stop T200 Mon Jul 22 19:19:42 2013 <0018> lapd_core.c:229 new state LAPD_STATE_TIMER_RECOV -> LAPD_STATE_MF_EST Mon Jul 22 19:19:42 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:42 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1296 Mon Jul 22 19:19:44 2013 <0018> input/lapd.c:347 RX: 00 03 ce 78 10 19 15 1c 41 00 49 00 51 00 59 00 61 00 69 00 71 00 79 00 0a 00 0b 00 0c 00 0d 00 0e 00 0f 00 Mon Jul 22 19:19:44 2013 <0018> lapd_core.c:1465 I received in state LAPD_STATE_MF_EST Mon Jul 22 19:19:44 2013 <0018> lapd_core.c:1548 incrementing V(R) to 104 Mon Jul 22 19:19:44 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:19:44 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:44 2013 <0018> lapd_core.c:1557 message in single I frame Mon Jul 22 19:19:44 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1615 Mon Jul 22 19:19:44 2013 <0018> lapd_core.c:1618 we are not busy and have no pending data, send RR Mon Jul 22 19:19:44 2013 <0018> input/lapd.c:600 TX: 00 03 01 d0 Mon Jul 22 19:19:46 2013 <0018> lapd_core.c:680 Timeout T203 (0x979a068) state=7 Mon Jul 22 19:19:46 2013 <0018> lapd_core.c:229 new state LAPD_STATE_MF_EST -> LAPD_STATE_TIMER_RECOV Mon Jul 22 19:19:46 2013 <0018> lapd_core.c:694 transmit an RR poll command Mon Jul 22 19:19:46 2013 <0018> input/lapd.c:600 TX: 02 03 01 8f Mon Jul 22 19:19:46 2013 <0018> lapd_core.c:198 start T200 Mon Jul 22 19:19:46 2013 <0018> input/lapd.c:347 RX: 02 03 01 21 Mon Jul 22 19:19:46 2013 <0018> lapd_core.c:1258 RR received in state LAPD_STATE_TIMER_RECOV Mon Jul 22 19:19:46 2013 <0018> lapd_core.c:1287 RR response with F==1, and we are in timer recovery state, so we leave that state Mon Jul 22 19:19:46 2013 <0018> lapd_core.c:214 stop T200 Mon Jul 22 19:19:46 2013 <0018> lapd_core.c:229 new state LAPD_STATE_TIMER_RECOV -> LAPD_STATE_MF_EST Mon Jul 22 19:19:46 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:46 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1296 Mon Jul 22 19:19:50 2013 <0018> lapd_core.c:680 Timeout T203 (0x9757180) state=7 Mon Jul 22 19:19:50 2013 <0018> lapd_core.c:229 new state LAPD_STATE_MF_EST -> LAPD_STATE_TIMER_RECOV Mon Jul 22 19:19:50 2013 <0018> lapd_core.c:694 transmit an RR poll command Mon Jul 22 19:19:50 2013 <0018> input/lapd.c:600 TX: fa 03 01 07 Mon Jul 22 19:19:50 2013 <0018> lapd_core.c:198 start T200 Mon Jul 22 19:19:50 2013 <0018> input/lapd.c:347 RX: fa 03 01 07 Mon Jul 22 19:19:50 2013 <0018> lapd_core.c:1258 RR received in state LAPD_STATE_TIMER_RECOV Mon Jul 22 19:19:50 2013 <0018> lapd_core.c:1287 RR response with F==1, and we are in timer recovery state, so we leave that state Mon Jul 22 19:19:50 2013 <0018> lapd_core.c:214 stop T200 Mon Jul 22 19:19:50 2013 <0018> lapd_core.c:229 new state LAPD_STATE_TIMER_RECOV -> LAPD_STATE_MF_EST Mon Jul 22 19:19:50 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:50 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1296 Mon Jul 22 19:19:50 2013 <0018> lapd_core.c:680 Timeout T203 (0x9793ae8) state=7 Mon Jul 22 19:19:50 2013 <0018> lapd_core.c:229 new state LAPD_STATE_MF_EST -> LAPD_STATE_TIMER_RECOV Mon Jul 22 19:19:50 2013 <0018> lapd_core.c:694 transmit an RR poll command Mon Jul 22 19:19:50 2013 <0018> input/lapd.c:600 TX: fa 03 01 03 Mon Jul 22 19:19:50 2013 <0018> lapd_core.c:198 start T200 Mon Jul 22 19:19:50 2013 <0018> input/lapd.c:347 RX: fa 03 01 03 Mon Jul 22 19:19:50 2013 <0018> lapd_core.c:1258 RR received in state LAPD_STATE_TIMER_RECOV Mon Jul 22 19:19:50 2013 <0018> lapd_core.c:1287 RR response with F==1, and we are in timer recovery state, so we leave that state Mon Jul 22 19:19:50 2013 <0018> lapd_core.c:214 stop T200 Mon Jul 22 19:19:50 2013 <0018> lapd_core.c:229 new state LAPD_STATE_TIMER_RECOV -> LAPD_STATE_MF_EST Mon Jul 22 19:19:50 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:50 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1296 Mon Jul 22 19:19:52 2013 <0018> input/lapd.c:347 RX: 00 03 8e 20 10 19 15 16 20 00 28 00 30 00 38 00 09 00 0a 00 0b 00 0c 00 0d 00 0e 00 0f 00 Mon Jul 22 19:19:52 2013 <0018> lapd_core.c:1465 I received in state LAPD_STATE_MF_EST Mon Jul 22 19:19:52 2013 <0018> lapd_core.c:1548 incrementing V(R) to 72 Mon Jul 22 19:19:52 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:19:52 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:52 2013 <0018> lapd_core.c:1557 message in single I frame Mon Jul 22 19:19:52 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1615 Mon Jul 22 19:19:52 2013 <0018> lapd_core.c:1618 we are not busy and have no pending data, send RR Mon Jul 22 19:19:52 2013 <0018> input/lapd.c:600 TX: 00 03 01 90 Mon Jul 22 19:19:54 2013 <0018> lapd_core.c:680 Timeout T203 (0x979ad08) state=7 Mon Jul 22 19:19:54 2013 <0018> lapd_core.c:229 new state LAPD_STATE_MF_EST -> LAPD_STATE_TIMER_RECOV Mon Jul 22 19:19:54 2013 <0018> lapd_core.c:694 transmit an RR poll command Mon Jul 22 19:19:54 2013 <0018> input/lapd.c:600 TX: 02 03 01 d1 Mon Jul 22 19:19:54 2013 <0018> lapd_core.c:198 start T200 Mon Jul 22 19:19:54 2013 <0018> input/lapd.c:347 RX: 02 03 01 79 Mon Jul 22 19:19:54 2013 <0018> lapd_core.c:1258 RR received in state LAPD_STATE_TIMER_RECOV Mon Jul 22 19:19:54 2013 <0018> lapd_core.c:1287 RR response with F==1, and we are in timer recovery state, so we leave that state Mon Jul 22 19:19:54 2013 <0018> lapd_core.c:214 stop T200 Mon Jul 22 19:19:54 2013 <0018> lapd_core.c:229 new state LAPD_STATE_TIMER_RECOV -> LAPD_STATE_MF_EST Mon Jul 22 19:19:54 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:19:54 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1296 Mon Jul 22 19:20:00 2013 <0018> input/lapd.c:347 RX: 00 03 d0 78 10 19 15 1c 41 00 49 00 51 00 59 00 61 00 69 00 71 00 79 00 0a 00 0b 00 0c 00 0d 00 0e 00 0f 00 Mon Jul 22 19:20:00 2013 <0018> lapd_core.c:1465 I received in state LAPD_STATE_MF_EST Mon Jul 22 19:20:00 2013 <0018> lapd_core.c:1548 incrementing V(R) to 105 Mon Jul 22 19:20:00 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:20:00 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:20:00 2013 <0018> lapd_core.c:1557 message in single I frame Mon Jul 22 19:20:00 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1615 Mon Jul 22 19:20:00 2013 <0018> lapd_core.c:1618 we are not busy and have no pending data, send RR Mon Jul 22 19:20:00 2013 <0018> input/lapd.c:600 TX: 00 03 01 d2 Mon Jul 22 19:20:00 2013 <0018> input/lapd.c:347 RX: 00 03 d2 78 0c 12 01 88 12 06 17 e8 00 00 00 00 Mon Jul 22 19:20:00 2013 <0018> lapd_core.c:1465 I received in state LAPD_STATE_MF_EST Mon Jul 22 19:20:00 2013 <0018> lapd_core.c:1548 incrementing V(R) to 106 Mon Jul 22 19:20:00 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:20:00 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:20:00 2013 <0018> lapd_core.c:1557 message in single I frame Mon Jul 22 19:20:00 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1615 Mon Jul 22 19:20:00 2013 <0018> lapd_core.c:1618 we are not busy and have no pending data, send RR Mon Jul 22 19:20:00 2013 <0018> input/lapd.c:600 TX: 00 03 01 d4 Mon Jul 22 19:20:00 2013 <0018> input/lapd.c:347 RX: 00 03 d4 78 0c 12 01 90 0f 01 90 ff 00 00 00 00 fa 03 00 00 00 Mon Jul 22 19:20:00 2013 <0018> lapd_core.c:1465 I received in state LAPD_STATE_MF_EST Mon Jul 22 19:20:00 2013 <0018> lapd_core.c:1548 incrementing V(R) to 107 Mon Jul 22 19:20:00 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:20:00 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:20:00 2013 <0018> lapd_core.c:1557 message in single I frame Mon Jul 22 19:20:00 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1615 Mon Jul 22 19:20:00 2013 <0018> lapd_core.c:1618 we are not busy and have no pending data, send RR Mon Jul 22 19:20:00 2013 <0018> input/lapd.c:600 TX: 00 03 01 d6 Mon Jul 22 19:20:00 2013 <0018> lapd_core.c:680 Timeout T203 (0x9757180) state=7 Mon Jul 22 19:20:00 2013 <0018> lapd_core.c:229 new state LAPD_STATE_MF_EST -> LAPD_STATE_TIMER_RECOV Mon Jul 22 19:20:00 2013 <0018> lapd_core.c:694 transmit an RR poll command Mon Jul 22 19:20:00 2013 <0018> input/lapd.c:600 TX: fa 03 01 07 Mon Jul 22 19:20:00 2013 <0018> lapd_core.c:198 start T200 Mon Jul 22 19:20:00 2013 <0018> input/lapd.c:347 RX: fa 03 01 07 Mon Jul 22 19:20:00 2013 <0018> lapd_core.c:1258 RR received in state LAPD_STATE_TIMER_RECOV Mon Jul 22 19:20:00 2013 <0018> lapd_core.c:1287 RR response with F==1, and we are in timer recovery state, so we leave that state Mon Jul 22 19:20:00 2013 <0018> lapd_core.c:214 stop T200 Mon Jul 22 19:20:00 2013 <0018> lapd_core.c:229 new state LAPD_STATE_TIMER_RECOV -> LAPD_STATE_MF_EST Mon Jul 22 19:20:00 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:20:00 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1296 Mon Jul 22 19:20:00 2013 <0018> lapd_core.c:680 Timeout T203 (0x9793ae8) state=7 Mon Jul 22 19:20:00 2013 <0018> lapd_core.c:229 new state LAPD_STATE_MF_EST -> LAPD_STATE_TIMER_RECOV Mon Jul 22 19:20:00 2013 <0018> lapd_core.c:694 transmit an RR poll command Mon Jul 22 19:20:00 2013 <0018> input/lapd.c:600 TX: fa 03 01 03 Mon Jul 22 19:20:00 2013 <0018> lapd_core.c:198 start T200 Mon Jul 22 19:20:00 2013 <0018> input/lapd.c:347 RX: fa 03 01 03 Mon Jul 22 19:20:00 2013 <0018> lapd_core.c:1258 RR received in state LAPD_STATE_TIMER_RECOV Mon Jul 22 19:20:00 2013 <0018> lapd_core.c:1287 RR response with F==1, and we are in timer recovery state, so we leave that state Mon Jul 22 19:20:00 2013 <0018> lapd_core.c:214 stop T200 Mon Jul 22 19:20:00 2013 <0018> lapd_core.c:229 new state LAPD_STATE_TIMER_RECOV -> LAPD_STATE_MF_EST Mon Jul 22 19:20:00 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:20:00 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1296 Mon Jul 22 19:20:02 2013 <0018> lapd_core.c:680 Timeout T203 (0x979a068) state=7 Mon Jul 22 19:20:02 2013 <0018> lapd_core.c:229 new state LAPD_STATE_MF_EST -> LAPD_STATE_TIMER_RECOV Mon Jul 22 19:20:02 2013 <0018> lapd_core.c:694 transmit an RR poll command Mon Jul 22 19:20:02 2013 <0018> input/lapd.c:600 TX: 02 03 01 91 Mon Jul 22 19:20:02 2013 <0018> lapd_core.c:198 start T200 Mon Jul 22 19:20:02 2013 <0018> input/lapd.c:347 RX: 02 03 01 21 Mon Jul 22 19:20:02 2013 <0018> lapd_core.c:1258 RR received in state LAPD_STATE_TIMER_RECOV Mon Jul 22 19:20:02 2013 <0018> lapd_core.c:1287 RR response with F==1, and we are in timer recovery state, so we leave that state Mon Jul 22 19:20:02 2013 <0018> lapd_core.c:214 stop T200 Mon Jul 22 19:20:02 2013 <0018> lapd_core.c:229 new state LAPD_STATE_TIMER_RECOV -> LAPD_STATE_MF_EST Mon Jul 22 19:20:02 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:20:02 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1296 Mon Jul 22 19:20:04 2013 <0018> input/lapd.c:347 RX: 00 03 90 20 0c 12 01 88 12 06 0c a8 00 05 00 00 Mon Jul 22 19:20:04 2013 <0018> lapd_core.c:1465 I received in state LAPD_STATE_MF_EST Mon Jul 22 19:20:04 2013 <0018> lapd_core.c:1548 incrementing V(R) to 73 Mon Jul 22 19:20:04 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:20:04 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:20:04 2013 <0018> lapd_core.c:1557 message in single I frame Mon Jul 22 19:20:04 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1615 Mon Jul 22 19:20:04 2013 <0018> lapd_core.c:1618 we are not busy and have no pending data, send RR Mon Jul 22 19:20:04 2013 <0018> input/lapd.c:600 TX: 00 03 01 92 Mon Jul 22 19:20:04 2013 <0018> input/lapd.c:347 RX: 00 03 92 20 0c 12 01 90 0f 00 64 ff 00 00 00 00 fa 03 00 00 00 Mon Jul 22 19:20:04 2013 <0018> lapd_core.c:1465 I received in state LAPD_STATE_MF_EST Mon Jul 22 19:20:04 2013 <0018> lapd_core.c:1548 incrementing V(R) to 74 Mon Jul 22 19:20:04 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:20:04 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:20:04 2013 <0018> lapd_core.c:1557 message in single I frame Mon Jul 22 19:20:04 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1615 Mon Jul 22 19:20:04 2013 <0018> lapd_core.c:1618 we are not busy and have no pending data, send RR Mon Jul 22 19:20:04 2013 <0018> input/lapd.c:600 TX: 00 03 01 94 Mon Jul 22 19:20:08 2013 <0018> input/lapd.c:347 RX: 00 03 94 20 10 19 15 16 20 00 28 00 30 00 38 00 09 00 0a 00 0b 00 0c 00 0d 00 0e 00 0f 00 Mon Jul 22 19:20:08 2013 <0018> lapd_core.c:1465 I received in state LAPD_STATE_MF_EST Mon Jul 22 19:20:08 2013 <0018> lapd_core.c:1548 incrementing V(R) to 75 Mon Jul 22 19:20:08 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:20:08 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:20:08 2013 <0018> lapd_core.c:1557 message in single I frame Mon Jul 22 19:20:08 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1615 Mon Jul 22 19:20:08 2013 <0018> lapd_core.c:1618 we are not busy and have no pending data, send RR Mon Jul 22 19:20:08 2013 <0018> input/lapd.c:600 TX: 00 03 01 96 Mon Jul 22 19:20:10 2013 <0018> lapd_core.c:680 Timeout T203 (0x979ad08) state=7 Mon Jul 22 19:20:10 2013 <0018> lapd_core.c:229 new state LAPD_STATE_MF_EST -> LAPD_STATE_TIMER_RECOV Mon Jul 22 19:20:10 2013 <0018> lapd_core.c:694 transmit an RR poll command Mon Jul 22 19:20:10 2013 <0018> input/lapd.c:600 TX: 02 03 01 d7 Mon Jul 22 19:20:10 2013 <0018> lapd_core.c:198 start T200 Mon Jul 22 19:20:10 2013 <0018> input/lapd.c:347 RX: 02 03 01 79 Mon Jul 22 19:20:10 2013 <0018> lapd_core.c:1258 RR received in state LAPD_STATE_TIMER_RECOV Mon Jul 22 19:20:10 2013 <0018> lapd_core.c:1287 RR response with F==1, and we are in timer recovery state, so we leave that state Mon Jul 22 19:20:10 2013 <0018> lapd_core.c:214 stop T200 Mon Jul 22 19:20:10 2013 <0018> lapd_core.c:229 new state LAPD_STATE_TIMER_RECOV -> LAPD_STATE_MF_EST Mon Jul 22 19:20:10 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:20:10 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1296 Mon Jul 22 19:20:10 2013 <0018> lapd_core.c:680 Timeout T203 (0x9757180) state=7 Mon Jul 22 19:20:10 2013 <0018> lapd_core.c:229 new state LAPD_STATE_MF_EST -> LAPD_STATE_TIMER_RECOV Mon Jul 22 19:20:10 2013 <0018> lapd_core.c:694 transmit an RR poll command Mon Jul 22 19:20:10 2013 <0018> input/lapd.c:600 TX: fa 03 01 07 Mon Jul 22 19:20:10 2013 <0018> lapd_core.c:198 start T200 Mon Jul 22 19:20:10 2013 <0018> input/lapd.c:347 RX: fa 03 01 07 Mon Jul 22 19:20:10 2013 <0018> lapd_core.c:1258 RR received in state LAPD_STATE_TIMER_RECOV Mon Jul 22 19:20:10 2013 <0018> lapd_core.c:1287 RR response with F==1, and we are in timer recovery state, so we leave that state Mon Jul 22 19:20:10 2013 <0018> lapd_core.c:214 stop T200 Mon Jul 22 19:20:10 2013 <0018> lapd_core.c:229 new state LAPD_STATE_TIMER_RECOV -> LAPD_STATE_MF_EST Mon Jul 22 19:20:10 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:20:10 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1296 Mon Jul 22 19:20:10 2013 <0018> lapd_core.c:680 Timeout T203 (0x9793ae8) state=7 Mon Jul 22 19:20:10 2013 <0018> lapd_core.c:229 new state LAPD_STATE_MF_EST -> LAPD_STATE_TIMER_RECOV Mon Jul 22 19:20:10 2013 <0018> lapd_core.c:694 transmit an RR poll command Mon Jul 22 19:20:10 2013 <0018> input/lapd.c:600 TX: fa 03 01 03 Mon Jul 22 19:20:10 2013 <0018> lapd_core.c:198 start T200 Mon Jul 22 19:20:10 2013 <0018> input/lapd.c:347 RX: fa 03 01 03 Mon Jul 22 19:20:10 2013 <0018> lapd_core.c:1258 RR received in state LAPD_STATE_TIMER_RECOV Mon Jul 22 19:20:10 2013 <0018> lapd_core.c:1287 RR response with F==1, and we are in timer recovery state, so we leave that state Mon Jul 22 19:20:10 2013 <0018> lapd_core.c:214 stop T200 Mon Jul 22 19:20:10 2013 <0018> lapd_core.c:229 new state LAPD_STATE_TIMER_RECOV -> LAPD_STATE_MF_EST Mon Jul 22 19:20:10 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:20:10 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1296 Mon Jul 22 19:20:16 2013 <0018> input/lapd.c:347 RX: 00 03 d6 78 10 19 15 1c 41 00 49 00 51 00 59 00 61 00 69 00 71 00 79 00 0a 00 0b 00 0c 00 0d 00 0e 00 0f 00 Mon Jul 22 19:20:16 2013 <0018> lapd_core.c:1465 I received in state LAPD_STATE_MF_EST Mon Jul 22 19:20:16 2013 <0018> lapd_core.c:1548 incrementing V(R) to 108 Mon Jul 22 19:20:16 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:20:16 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:20:16 2013 <0018> lapd_core.c:1557 message in single I frame Mon Jul 22 19:20:16 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1615 Mon Jul 22 19:20:16 2013 <0018> lapd_core.c:1618 we are not busy and have no pending data, send RR Mon Jul 22 19:20:16 2013 <0018> input/lapd.c:600 TX: 00 03 01 d8 Mon Jul 22 19:20:18 2013 <0018> lapd_core.c:680 Timeout T203 (0x979a068) state=7 Mon Jul 22 19:20:18 2013 <0018> lapd_core.c:229 new state LAPD_STATE_MF_EST -> LAPD_STATE_TIMER_RECOV Mon Jul 22 19:20:18 2013 <0018> lapd_core.c:694 transmit an RR poll command Mon Jul 22 19:20:18 2013 <0018> input/lapd.c:600 TX: 02 03 01 97 Mon Jul 22 19:20:18 2013 <0018> lapd_core.c:198 start T200 Mon Jul 22 19:20:18 2013 <0018> input/lapd.c:347 RX: 02 03 01 21 Mon Jul 22 19:20:18 2013 <0018> lapd_core.c:1258 RR received in state LAPD_STATE_TIMER_RECOV Mon Jul 22 19:20:18 2013 <0018> lapd_core.c:1287 RR response with F==1, and we are in timer recovery state, so we leave that state Mon Jul 22 19:20:18 2013 <0018> lapd_core.c:214 stop T200 Mon Jul 22 19:20:18 2013 <0018> lapd_core.c:229 new state LAPD_STATE_TIMER_RECOV -> LAPD_STATE_MF_EST Mon Jul 22 19:20:18 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:20:18 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1296 Mon Jul 22 19:20:20 2013 <0018> lapd_core.c:680 Timeout T203 (0x9757180) state=7 Mon Jul 22 19:20:20 2013 <0018> lapd_core.c:229 new state LAPD_STATE_MF_EST -> LAPD_STATE_TIMER_RECOV Mon Jul 22 19:20:20 2013 <0018> lapd_core.c:694 transmit an RR poll command Mon Jul 22 19:20:20 2013 <0018> input/lapd.c:600 TX: fa 03 01 07 Mon Jul 22 19:20:20 2013 <0018> lapd_core.c:198 start T200 Mon Jul 22 19:20:20 2013 <0018> input/lapd.c:347 RX: fa 03 01 07 Mon Jul 22 19:20:20 2013 <0018> lapd_core.c:1258 RR received in state LAPD_STATE_TIMER_RECOV Mon Jul 22 19:20:20 2013 <0018> lapd_core.c:1287 RR response with F==1, and we are in timer recovery state, so we leave that state Mon Jul 22 19:20:20 2013 <0018> lapd_core.c:214 stop T200 Mon Jul 22 19:20:20 2013 <0018> lapd_core.c:229 new state LAPD_STATE_TIMER_RECOV -> LAPD_STATE_MF_EST Mon Jul 22 19:20:20 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:20:20 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1296 Mon Jul 22 19:20:20 2013 <0018> lapd_core.c:680 Timeout T203 (0x9793ae8) state=7 Mon Jul 22 19:20:20 2013 <0018> lapd_core.c:229 new state LAPD_STATE_MF_EST -> LAPD_STATE_TIMER_RECOV Mon Jul 22 19:20:20 2013 <0018> lapd_core.c:694 transmit an RR poll command Mon Jul 22 19:20:20 2013 <0018> input/lapd.c:600 TX: fa 03 01 03 Mon Jul 22 19:20:20 2013 <0018> lapd_core.c:198 start T200 Mon Jul 22 19:20:20 2013 <0018> input/lapd.c:347 RX: fa 03 01 03 Mon Jul 22 19:20:20 2013 <0018> lapd_core.c:1258 RR received in state LAPD_STATE_TIMER_RECOV Mon Jul 22 19:20:20 2013 <0018> lapd_core.c:1287 RR response with F==1, and we are in timer recovery state, so we leave that state Mon Jul 22 19:20:20 2013 <0018> lapd_core.c:214 stop T200 Mon Jul 22 19:20:20 2013 <0018> lapd_core.c:229 new state LAPD_STATE_TIMER_RECOV -> LAPD_STATE_MF_EST Mon Jul 22 19:20:20 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:20:20 2013 <0018> lapd_core.c:1781 lapd_send_i() called from line 1296 Mon Jul 22 19:20:23 2013 <0018> input/lapd.c:347 RX: 00 03 96 20 0c 13 01 88 13 fc 92 a9 11 00 Mon Jul 22 19:20:23 2013 <0018> lapd_core.c:1465 I received in state LAPD_STATE_MF_EST Mon Jul 22 19:20:23 2013 <0018> lapd_core.c:1548 incrementing V(R) to 76 Mon Jul 22 19:20:23 2013 <0018> lapd_core.c:222 stop T203 Mon Jul 22 19:20:23 2013 <0018> lapd_core.c:206 start T203 Mon Jul 22 19:20:23 2013 <0018> lapd_core.c:1557 message in single I frame