Hello,
I have built a local GSM network with the Osmocom Stack and an Ettus B200. I can see and attach to the network with my smartphone.
Now, i want to use another usrp to simulate a mobile device but i am having trouble with synchronization with the channel. I think the problem lays in the layer 1 control channel or maybe even with the mobile configuration file. It starts camping on the channel, gets sysinfo, ccch mode COMB but then following a FBSB RESP: result=255 i get a channel synch error.
Any help in steering me to the right direction would be greatly appreciated. Thank you in advance.
mobile configuration file:
!
! OsmocomBB example configuration for mobile application
!!
!
line vty
no login
!
gps device /dev/ttyACM0
gps baudrate default
no gps enable
!
no hide-default
!
ms 1
layer2-socket /tmp/osmocom_l2
sap-socket /tmp/osmocom_sap
sim sap
network-selection-mode auto
imei 123456789012345 0
imei-fixed
no emergency-imsi
no sms-service-center
no call-waiting
no auto-answer
no force-rekey
no clip
no clir
tx-power auto
no simulated-delay
stick 119
location-updating
neighbour-measurement
codec full-speed prefer
codec half-speed
no abbrev
support
sms
a5/1
a5/2
p-gsm
no e-gsm
no r-gsm
no gsm-850
no dcs
no pcs
class-900 4
class-850 4
class-dcs 1
class-pcs 1
channel-capability sdcch+tchf+tchh
full-speech-v1
full-speech-v2
half-speech-v1
min-rxlev -106
dsc-max 90
no skip-max-per-band
test-sim
imsi 001010000000000
ki comp128 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
no barred-access
rplmn 123 01
audio
io-handler none
no shutdown
trxcon logs:
20201203162008375 DL1C NOTICE l1ctl_link.c:236 Init L1CTL link (/tmp/osmocom_l2)
20201203162008375 DTRX NOTICE trx_if.c:675 Init transceiver interface (127.0.0.1:6700)
20201203162008375 DSCH NOTICE sched_trx.c:136 Init scheduler
20201203162008375 DAPP NOTICE trxcon.c:341 Init complete
20201203162016623 DL1C NOTICE l1ctl_link.c:180 L1CTL has a new connection
20201203162016623 DL1C NOTICE l1ctl.c:426 Received reset request (1)
20201203162016623 DSCH NOTICE sched_trx.c:177 Reset scheduler and clock counter
20201203162017260 DL1C NOTICE l1ctl.c:426 Received reset request (1)
20201203162017260 DSCH NOTICE sched_trx.c:177 Reset scheduler and clock counter
20201203162017260 DL1C NOTICE l1ctl.c:399 Received power measurement request (GSM900: 119 -> 119)
20201203162017261 DL1C NOTICE l1ctl.c:426 Received reset request (1)
20201203162017261 DSCH NOTICE sched_trx.c:177 Reset scheduler and clock counter
20201203162017261 DL1C NOTICE l1ctl.c:342 Received FBSB request (GSM900 119)
20201203162017261 DSCH NOTICE sched_trx.c:177 Reset scheduler and clock counter
20201203162017261 DSCH NOTICE sched_trx.c:199 Add a new TDMA timeslot #0
20201203162017261 DSCH NOTICE sched_trx.c:274 (Re)configure TDMA timeslot #0 as BCCH+CCCH
20201203162017261 DSCH NOTICE sched_trx.c:431 Activating lchan=SCH on ts=0
20201203162017261 DSCH NOTICE sched_trx.c:431 Activating lchan=BCCH on ts=0
20201203162017261 DSCH NOTICE sched_trx.c:431 Activating lchan=RACH on ts=0
20201203162017261 DSCH NOTICE sched_trx.c:431 Activating lchan=CCCH on ts=0
20201203162017513 DSCHD ERROR sched_lchan_xcch.c:102 Received bad data frame at fn=1481847 (42/51) for CCCH
….
20201203162017768 DSCHD ERROR sched_lchan_xcch.c:102 Received bad data frame at fn=1481902 (46/51) for CCCH
20201203162017777 DSCH NOTICE sched_clck.c:136 Clock indication: fn=1481907
20201203162017892 DSCHD ERROR sched_lchan_xcch.c:102 Received bad data frame at fn=1481929 (22/51) for CCCH
….
20201203162018004 DSCHD ERROR sched_lchan_xcch.c:102 Received bad data frame at fn=1481953 (46/51) for CCCH
20201203162018012 DSCH NOTICE sched_clck.c:136 Clock indication: fn=1481958
20201203162018037 DL1C NOTICE l1ctl.c:482 Received CCCH mode request (2)
20201203162018037 DSCH NOTICE sched_trx.c:274 (Re)configure TDMA timeslot #0 as BCCH+CCCH+SDCCH/4+SACCH/4
20201203162018037 DSCH NOTICE sched_trx.c:431 Activating lchan=SCH on ts=0
20201203162018037 DSCH NOTICE sched_trx.c:431 Activating lchan=BCCH on ts=0
20201203162018037 DSCH NOTICE sched_trx.c:431 Activating lchan=RACH on ts=0
20201203162018037 DSCH NOTICE sched_trx.c:431 Activating lchan=CCCH on ts=0
20201203162018037 DL1C NOTICE l1ctl.c:426 Received reset request (1)
20201203162018037 DSCH NOTICE sched_trx.c:177 Reset scheduler and clock counter
20201203162018037 DSCH NOTICE sched_trx.c:223 Delete TDMA timeslot #0
20201203162018037 DL1C NOTICE l1ctl.c:342 Received FBSB request (GSM900 119)
20201203162018037 DSCH NOTICE sched_trx.c:177 Reset scheduler and clock counter
20201203162018037 DSCH NOTICE sched_trx.c:199 Add a new TDMA timeslot #0
20201203162018037 DSCH NOTICE sched_trx.c:274 (Re)configure TDMA timeslot #0 as BCCH+CCCH+SDCCH/4+SACCH/4
20201203162018037 DSCH NOTICE sched_trx.c:431 Activating lchan=SCH on ts=0
20201203162018037 DSCH NOTICE sched_trx.c:431 Activating lchan=BCCH on ts=0
20201203162018037 DSCH NOTICE sched_trx.c:431 Activating lchan=RACH on ts=0
20201203162018037 DSCH NOTICE sched_trx.c:431 Activating lchan=CCCH on ts=0
20201203162018498 DL1C NOTICE l1ctl.c:307 FBSB timer fired for ARFCN 119
20201203162018498 DL1C NOTICE l1ctl.c:426 Received reset request (1)
20201203162018498 DSCH NOTICE sched_trx.c:177 Reset scheduler and clock counter
20201203162018498 DSCH NOTICE sched_trx.c:223 Delete TDMA timeslot #0
20201203162018498 DL1C NOTICE l1ctl.c:342 Received FBSB request (GSM900 119)
20201203162018498 DSCH NOTICE sched_trx.c:177 Reset scheduler and clock counter
20201203162018498 DSCH NOTICE sched_trx.c:199 Add a new TDMA timeslot #0
20201203162018498 DSCH NOTICE sched_trx.c:274 (Re)configure TDMA timeslot #0 as BCCH+CCCH+SDCCH/4+SACCH/4
20201203162018498 DSCH NOTICE sched_trx.c:431 Activating lchan=SCH on ts=0
20201203162018498 DSCH NOTICE sched_trx.c:431 Activating lchan=BCCH on ts=0
20201203162018498 DSCH NOTICE sched_trx.c:431 Activating lchan=RACH on ts=0
20201203162018498 DSCH NOTICE sched_trx.c:431 Activating lchan=CCCH on ts=0
20201203162018520 DSCHD ERROR sched_lchan_xcch.c:102 Received bad data frame at fn=1481964 (6/102) for CCCH
….
20201203162018806 DSCHD ERROR sched_lchan_xcch.c:102 Received bad data frame at fn=1482025 (67/102) for CCCH
20201203162018949 DSCH NOTICE sched_clck.c:136 Clock indication: fn=1482060
20201203162018960 DL1C NOTICE l1ctl.c:307 FBSB timer fired for ARFCN 119
20201203162018960 DL1C NOTICE l1ctl.c:694 Received L1CTL_DM_REL_REQ, switching back to CCCH
20201203162018960 DSCH NOTICE sched_trx.c:177 Reset scheduler
20201203162018960 DSCH NOTICE sched_trx.c:223 Delete TDMA timeslot #0
20201203162018960 DL1C NOTICE l1ctl.c:426 Received reset request (1)
20201203162018960 DSCH NOTICE sched_trx.c:177 Reset scheduler and clock counter
20201203162018961 DL1C NOTICE l1ctl.c:426 Received reset request (1)
20201203162018961 DSCH NOTICE sched_trx.c:177 Reset scheduler and clock counter
20201203162018961 DL1C NOTICE l1ctl.c:399 Received power measurement request (GSM900: 119 -> 119)
20201203162018963 DL1C NOTICE l1ctl.c:426 Received reset request (1)
20201203162018963 DSCH NOTICE sched_trx.c:177 Reset scheduler and clock counter
20201203162018963 DL1C NOTICE l1ctl.c:342 Received FBSB request (GSM900 119)
20201203162018963 DSCH NOTICE sched_trx.c:177 Reset scheduler and clock counter
20201203162018963 DSCH NOTICE sched_trx.c:199 Add a new TDMA timeslot #0
20201203162018963 DSCH NOTICE sched_trx.c:274 (Re)configure TDMA timeslot #0 as BCCH+CCCH
20201203162018963 DSCH NOTICE sched_trx.c:431 Activating lchan=SCH on ts=0
20201203162018963 DSCH NOTICE sched_trx.c:431 Activating lchan=BCCH on ts=0
20201203162018963 DSCH NOTICE sched_trx.c:431 Activating lchan=RACH on ts=0
20201203162018964 DSCH NOTICE sched_trx.c:431 Activating lchan=CCCH on ts=0
20201203162019070 DSCHD ERROR sched_lchan_xcch.c:102 Received bad data frame at fn=1482184 (22/51) for CCCH
….
20201203162019890 DSCHD ERROR sched_lchan_xcch.c:102 Received bad data frame at fn=1482361 (46/51) for CCCH
20201203162019898 DSCH NOTICE sched_clck.c:136 Clock indication: fn=1482366
20201203162019920 DL1C NOTICE l1ctl.c:482 Received CCCH mode request (2)
20201203162019920 DSCH NOTICE sched_trx.c:274 (Re)configure TDMA timeslot #0 as BCCH+CCCH+SDCCH/4+SACCH/4
20201203162019920 DSCH NOTICE sched_trx.c:431 Activating lchan=SCH on ts=0
20201203162019920 DSCH NOTICE sched_trx.c:431 Activating lchan=BCCH on ts=0
20201203162019920 DSCH NOTICE sched_trx.c:431 Activating lchan=RACH on ts=0
20201203162019920 DSCH NOTICE sched_trx.c:431 Activating lchan=CCCH on ts=0
20201203162019920 DL1C NOTICE l1ctl.c:426 Received reset request (1)
20201203162019920 DSCH NOTICE sched_trx.c:177 Reset scheduler and clock counter
20201203162019920 DSCH NOTICE sched_trx.c:223 Delete TDMA timeslot #0
20201203162019920 DL1C NOTICE l1ctl.c:342 Received FBSB request (GSM900 119)
20201203162019920 DSCH NOTICE sched_trx.c:177 Reset scheduler and clock counter
20201203162019920 DSCH NOTICE sched_trx.c:199 Add a new TDMA timeslot #0
20201203162019920 DSCH NOTICE sched_trx.c:274 (Re)configure TDMA timeslot #0 as BCCH+CCCH+SDCCH/4+SACCH/4
20201203162019920 DSCH NOTICE sched_trx.c:431 Activating lchan=SCH on ts=0
20201203162019920 DSCH NOTICE sched_trx.c:431 Activating lchan=BCCH on ts=0
20201203162019921 DSCH NOTICE sched_trx.c:431 Activating lchan=RACH on ts=0
20201203162019921 DSCH NOTICE sched_trx.c:431 Activating lchan=CCCH on ts=0
20201203162020382 DL1C NOTICE l1ctl.c:307 FBSB timer fired for ARFCN 119
20201203162020382 DL1C NOTICE l1ctl.c:426 Received reset request (1)
20201203162020382 DSCH NOTICE sched_trx.c:177 Reset scheduler and clock counter
20201203162020382 DSCH NOTICE sched_trx.c:223 Delete TDMA timeslot #0
20201203162020382 DL1C NOTICE l1ctl.c:342 Received FBSB request (GSM900 119)
20201203162020382 DSCH NOTICE sched_trx.c:177 Reset scheduler and clock counter
20201203162020382 DSCH NOTICE sched_trx.c:199 Add a new TDMA timeslot #0
20201203162020382 DSCH NOTICE sched_trx.c:274 (Re)configure TDMA timeslot #0 as BCCH+CCCH+SDCCH/4+SACCH/4
20201203162020382 DSCH NOTICE sched_trx.c:431 Activating lchan=SCH on ts=0
20201203162020382 DSCH NOTICE sched_trx.c:431 Activating lchan=BCCH on ts=0
20201203162020382 DSCH NOTICE sched_trx.c:431 Activating lchan=RACH on ts=0
20201203162020382 DSCH NOTICE sched_trx.c:431 Activating lchan=CCCH on ts=0
20201203162020405 DSCHD ERROR sched_lchan_xcch.c:102 Received bad data frame at fn=1482372 (6/102) for CCCH
….
20201203162020687 DSCHD ERROR sched_lchan_xcch.c:102 Received bad data frame at fn=1482433 (67/102) for CCCH
20201203162020834 DSCH NOTICE sched_clck.c:136 Clock indication: fn=1482468
20201203162020844 DL1C NOTICE l1ctl.c:307 FBSB timer fired for ARFCN 119
20201203162020844 DL1C NOTICE l1ctl.c:426 Received reset request (1)
20201203162020844 DSCH NOTICE sched_trx.c:177 Reset scheduler and clock counter
20201203162020844 DSCH NOTICE sched_trx.c:223 Delete TDMA timeslot #0
20201203162020844 DL1C NOTICE l1ctl.c:399 Received power measurement request (GSM900: 119 -> 119)
20201203162020846 DL1C NOTICE l1ctl.c:426 Received reset request (1)
20201203162020846 DSCH NOTICE sched_trx.c:177 Reset scheduler and clock counter
20201203162020846 DL1C NOTICE l1ctl.c:342 Received FBSB request (GSM900 119)
20201203162020846 DSCH NOTICE sched_trx.c:177 Reset scheduler and clock counter
20201203162020846 DSCH NOTICE sched_trx.c:199 Add a new TDMA timeslot #0
20201203162020846 DSCH NOTICE sched_trx.c:274 (Re)configure TDMA timeslot #0 as BCCH+CCCH
20201203162020846 DSCH NOTICE sched_trx.c:431 Activating lchan=SCH on ts=0
20201203162020846 DSCH NOTICE sched_trx.c:431 Activating lchan=BCCH on ts=0
20201203162020846 DSCH NOTICE sched_trx.c:431 Activating lchan=RACH on ts=0
20201203162020846 DSCH NOTICE sched_trx.c:431 Activating lchan=CCCH on ts=0
20201203162020953 DSCHD ERROR sched_lchan_xcch.c:102 Received bad data frame at fn=1482592 (22/51) for CCCH
….
20201203162021298 DSCHD ERROR sched_lchan_xcch.c:102 Received bad data frame at fn=1482667 (46/51) for CCCH
20201203162021307 DSCH NOTICE sched_clck.c:136 Clock indication: fn=1482672
20201203162021424 DSCHD ERROR sched_lchan_xcch.c:102 Received bad data frame at fn=1482694 (22/51) for CCCH
….
20201203162021533 DSCHD ERROR sched_lchan_xcch.c:102 Received bad data frame at fn=1482718 (46/51) for CCCH
20201203162021543 DSCH NOTICE sched_clck.c:136 Clock indication: fn=1482723
20201203162021660 DSCHD ERROR sched_lchan_xcch.c:102 Received bad data frame at fn=1482745 (22/51) for CCCH
….
20201203162021770 DSCHD ERROR sched_lchan_xcch.c:102 Received bad data frame at fn=1482769 (46/51) for CCCH
20201203162021779 DSCH NOTICE sched_clck.c:136 Clock indication: fn=1482774
20201203162021803 DL1C NOTICE l1ctl.c:482 Received CCCH mode request (2)
20201203162021803 DSCH NOTICE sched_trx.c:274 (Re)configure TDMA timeslot #0 as BCCH+CCCH+SDCCH/4+SACCH/4
20201203162021803 DSCH NOTICE sched_trx.c:431 Activating lchan=SCH on ts=0
20201203162021803 DSCH NOTICE sched_trx.c:431 Activating lchan=BCCH on ts=0
20201203162021803 DSCH NOTICE sched_trx.c:431 Activating lchan=RACH on ts=0
20201203162021803 DSCH NOTICE sched_trx.c:431 Activating lchan=CCCH on ts=0
20201203162021803 DL1C NOTICE l1ctl.c:426 Received reset request (1)
20201203162021803 DSCH NOTICE sched_trx.c:177 Reset scheduler and clock counter
20201203162021803 DSCH NOTICE sched_trx.c:223 Delete TDMA timeslot #0
20201203162021803 DL1C NOTICE l1ctl.c:342 Received FBSB request (GSM900 119)
20201203162021803 DSCH NOTICE sched_trx.c:177 Reset scheduler and clock counter
20201203162021803 DSCH NOTICE sched_trx.c:199 Add a new TDMA timeslot #0
20201203162021803 DSCH NOTICE sched_trx.c:274 (Re)configure TDMA timeslot #0 as BCCH+CCCH+SDCCH/4+SACCH/4
20201203162021803 DSCH NOTICE sched_trx.c:431 Activating lchan=SCH on ts=0
20201203162021803 DSCH NOTICE sched_trx.c:431 Activating lchan=BCCH on ts=0
20201203162021803 DSCH NOTICE sched_trx.c:431 Activating lchan=RACH on ts=0
20201203162021803 DSCH NOTICE sched_trx.c:431 Activating lchan=CCCH on ts=0
20201203162022265 DL1C NOTICE l1ctl.c:307 FBSB timer fired for ARFCN 119
20201203162022265 DL1C NOTICE l1ctl.c:426 Received reset request (1)
20201203162022265 DSCH NOTICE sched_trx.c:177 Reset scheduler and clock counter
20201203162022265 DSCH NOTICE sched_trx.c:223 Delete TDMA timeslot #0
20201203162022265 DL1C NOTICE l1ctl.c:342 Received FBSB request (GSM900 119)
20201203162022265 DSCH NOTICE sched_trx.c:177 Reset scheduler and clock counter
20201203162022265 DSCH NOTICE sched_trx.c:199 Add a new TDMA timeslot #0
20201203162022265 DSCH NOTICE sched_trx.c:274 (Re)configure TDMA timeslot #0 as BCCH+CCCH+SDCCH/4+SACCH/4
20201203162022265 DSCH NOTICE sched_trx.c:431 Activating lchan=SCH on ts=0
20201203162022265 DSCH NOTICE sched_trx.c:431 Activating lchan=BCCH on ts=0
20201203162022265 DSCH NOTICE sched_trx.c:431 Activating lchan=RACH on ts=0
20201203162022265 DSCH NOTICE sched_trx.c:431 Activating lchan=CCCH on ts=0
20201203162022309 DSCHD ERROR sched_lchan_xcch.c:102 Received bad data frame at fn=1482786 (12/102) for CCCH
….
20201203162022518 DSCHD ERROR sched_lchan_xcch.c:102 Received bad data frame at fn=1482831 (57/102) for CCCH
20201203162022523 DL1C NOTICE l1ctl.c:426 Received reset request (1)
20201203162022523 DSCH NOTICE sched_trx.c:177 Reset scheduler and clock counter
20201203162022523 DSCH NOTICE sched_trx.c:223 Delete TDMA timeslot #0
20201203162022523 DL1D NOTICE l1ctl_link.c:81 L1CTL has lost connection
….
mobile application logs:
000e> gsm322.c:1380 Start search of last registered PLMN (mcc=123 mnc=01 One, Two)
<0002> gsm322.c:1384 Use RPLMN (mcc=123 mnc=01 One, Two)
<0002> gsm322.c:810 new state 'A0 null' -> 'A1 trying RPLMN'
<0003> gsm322.c:4060 (ms 1) Event 'EVENT_NEW_PLMN' for Cell selection in state 'C0 null'
<000e> gsm322.c:3642 Selecting PLMN (mcc=123 mnc=01 One, Two)
<0003> gsm322.c:3650 Start stored cell selection.
<0003> gsm322.c:833 new state 'C0 null' -> 'C2 stored cell selection'
<0003> gsm322.c:2793 Scanning power for sticked cell.
<0003> gsm322.c:2868 Scanning frequencies. (119..119)
<0003> gsm322.c:2916 Found signal (ARFCN 119 rxlev -55 (55))
<0003> gsm322.c:2933 Done with power scanning range.
<0003> gsm322.c:2793 Scanning power for sticked cell.
<0003> gsm322.c:2845 Found 1 frequencies.
<0003> gsm322.c:2258 Scanning frequency 119 (rxlev -55).
<0003> gsm322.c:477 Sync to ARFCN=119 rxlev=-55 (No sysinfo yet, ccch mode NONE)
<0003> gsm322.c:2278 30 frequencies left in band 955..124
<000c> l1ctl.c:300 Dropping frame with 55 bit errors
….
<000c> l1ctl.c:300 Dropping frame with 55 bit errors
<0003> gsm322.c:2956 Channel synched. (ARFCN=119, snr=0, BSIC=63)
<0003> gsm322.c:703 Starting CS timer with 4 seconds.
<0001> gsm322.c:2979 using DSC of 90
<0003> gsm48_rr.c:4822 Channel provides data.
<0003> gsm322.c:703 Starting CS timer with 4 seconds.
<0001> gsm48_rr.c:1816 New SYSTEM INFORMATION 2
<000c> l1ctl.c:300 Dropping frame with 55 bit errors
….
<000c> l1ctl.c:300 Dropping frame with 55 bit errors
<0001> sysinfo.c:703 New SYSTEM INFORMATION 3 (mcc 123 mnc 01 lac 0x007c)
<0001> gsm48_rr.c:1907 Changing CCCH_MODE to 2
<0001> gsm48_rr.c:1939 New SYSTEM INFORMATION 4 (mcc 123 mnc 01 lac 0x007c)
<0001> sysinfo.c:615 Now updating previously received SYSTEM INFORMATION 4
<0001> gsm48_rr.c:1787 New SYSTEM INFORMATION 1
<0003> gsm322.c:2721 Received relevant sysinfo.
<0003> gsm322.c:713 stopping pending CS timer.
<0003> gsm322.c:2327 Scan frequency 119: Cell found. (rxlev -55 mcc 123 mnc 01 lac 007c)
<0003> gsm322.c:1841 Select using access class
<0003> gsm322.c:383 A (RLA_C (-55) - RXLEV_ACC_MIN (-110)) = 55
<0003> gsm322.c:385 B (MS_TXPWR_MAX_CCH (35) - p (33)) = 2
<0003> gsm322.c:387 C1 (A - MAX(B,0)) = 53
<0003> gsm322.c:1955 Cell ARFCN 119: Cell found, (rxlev=-55 mcc=123 mnc=01 lac=007c One, Two)
<0003> gsm322.c:1970 Cell ARFCN 119 selected.
<0003> gsm322.c:2428 Tune to frequency 119.
<0003> gsm322.c:464 Sync to ARFCN=119 rxlev=-55 (Sysinfo, ccch mode COMB)
<0003> gsm322.c:2455 Cell available.
<0003> gsm322.c:4060 (ms 1) Event 'EVENT_CELL_FOUND' for Cell selection in state 'C2 stored cell selection'
<000e> gsm322.c:3392 Camping normally on cell (ARFCN=119 mcc=123 mnc=01 One, Two)
<0003> gsm322.c:833 new state 'C2 stored cell selection' -> 'C3 camped normally'
<0005> gsm48_mm.c:4305 (ms 1) Received 'MM_EVENT_CELL_SELECTED' event in state MM IDLE, PLMN search
<0005> gsm48_mm.c:904 new MM IDLE state PLMN search -> location updating needed
<0005> gsm48_mm.c:904 new MM IDLE state location updating needed -> attempting to update
<0005> gsm48_mm.c:424 starting T3212 (periodic loc. upd. delay) with 1800 seconds
<0005> gsm48_mm.c:2285 Do Loc. upd. for IMSI attach.
<000e> gsm48_mm.c:2204 Perform location update (MCC 123, MNC 01 LAC 0x007c)
<0005> gsm48_mm.c:2340 LOCATION UPDATING REQUEST
<0005> gsm48_mm.c:2360 using LAI (mcc 123 mnc 01 lac 0x007c)
<0005> gsm48_mm.c:2370 using TMSI 0x9d4f59da
<0005> gsm48_mm.c:909 new state MM IDLE, attempting to update -> wait for RR connection (location updating)
<0001> gsm48_rr.c:5449 (ms 1) Message 'RR_EST_REQ' received in state idle (sapi 0)
<000e> gsm48_rr.c:1299 Establish radio link due to mobility management request
<0003> gsm322.c:4060 (ms 1) Event 'EVENT_LEAVE_IDLE' for Cell selection in state 'C3 camped normally'
<0003> gsm322.c:833 new state 'C3 camped normally' -> 'connected mode 1'
<0003> gsm322.c:3677 Going to camping (normal) ARFCN 119.
<0003> gsm322.c:456 Sync to ARFCN=119, but there is a sync already pending
<0001> gsm48_rr.c:359 new state idle -> connection pending
<0001> gsm48_rr.c:1446 CHANNEL REQUEST: 00 (Location Update with NECI)
<000c> l1ctl.c:169 FBSB RESP: result=255
<0003> gsm322.c:3001 Channel sync error, try again
<0003> gsm322.c:464 Sync to ARFCN=119 rxlev=-55 (Sysinfo, ccch mode COMB)
<000c> l1ctl.c:300 Dropping frame with 60 bit errors
….
<000c> l1ctl.c:300 Dropping frame with 61 bit errors
<0001> gsm48_rr.c:667 MON: f=119 lev=>=-47 snr= 0 ber= 61 LAI=123 01 007c ID=1e61
<000c> l1ctl.c:169 FBSB RESP: result=255
<0003> gsm322.c:3014 Channel sync error.
<0003> gsm322.c:3018 free sysinfo ARFCN=119
<0003> gsm322.c:3027 Unselect cell due to sync error!
<0003> gsm322.c:509 Unselecting serving cell.
<0003> gsm322.c:3111 Loss of SACCH, Trigger RR abort.
<000e> gsm48_rr.c:2879 Radio link lost signal
<0001> gsm48_rr.c:2886 LOS during RACH request
<0001> gsm48_rr.c:359 new state connection pending -> idle
<0003> gsm322.c:4060 (ms 1) Event 'EVENT_RET_IDLE' for Cell selection in state 'connected mode 1'
<0003> gsm322.c:3581 Cell not selected anymore, choose cell!
<0003> gsm322.c:833 new state 'connected mode 1' -> 'C5 choose cell'
<0003> gsm322.c:3523 No BA range(s), try sysinfo.
<0003> gsm322.c:2480 No BA, because no sysinfo
<0003> gsm322.c:3527 No BA on sysinfo, try stored BA list.
<0003> gsm322.c:3537 No BA list to use.
<0003> gsm322.c:4060 (ms 1) Event 'EVENT_NO_CELL_FOUND' for Cell selection in state 'C5 choose cell'
<0003> gsm322.c:833 new state 'C5 choose cell' -> 'C1 normal cell selection'
<0003> gsm322.c:2793 Scanning power for sticked cell.
<0003> gsm322.c:2868 Scanning frequencies. (119..119)
<0005> gsm48_mm.c:3902 (ms 1) Received 'RR_REL_IND' from RR in state wait for RR connection (location updating) (sapi 0)
<0005> gsm48_mm.c:2739 RR link released after loc. upd.
<000e> gsm48_mm.c:2683 Location update failed
<000e> gsm48_mm.c:2693 Try location update later
<0005> gsm48_mm.c:2694 Loc. upd. failed, retry #0
<0005> gsm48_mm.c:411 starting T3211 (loc. upd. retry delay) with 15.0 seconds
<0005> gsm48_mm.c:1086 Not camping, wait for CS process to camp, it sends us CELL_SELECTED then.
<0003> gsm322.c:2916 Found signal (ARFCN 119 rxlev -55 (55))
<0003> gsm322.c:2933 Done with power scanning range.
<0003> gsm322.c:2793 Scanning power for sticked cell.
<0003> gsm322.c:2845 Found 1 frequencies.
<0003> gsm322.c:2258 Scanning frequency 119 (rxlev -55).
<0003> gsm322.c:477 Sync to ARFCN=119 rxlev=-55 (No sysinfo yet, ccch mode NONE)
<0003> gsm322.c:2278 30 frequencies left in band 955..124
<0003> gsm322.c:2956 Channel synched. (ARFCN=119, snr=0, BSIC=63)
<0003> gsm322.c:703 Starting CS timer with 4 seconds.
<0001> gsm322.c:2979 using DSC of 90
<000c> l1ctl.c:300 Dropping frame with 55 bit errors
….
<000c> l1ctl.c:300 Dropping frame with 55 bit errors
<0003> gsm48_rr.c:4822 Channel provides data.
<0003> gsm322.c:703 Starting CS timer with 4 seconds.
<0001> gsm48_rr.c:1816 New SYSTEM INFORMATION 2
<000c> l1ctl.c:300 Dropping frame with 55 bit errors
….
<000c> l1ctl.c:300 Dropping frame with 55 bit errors
<0001> sysinfo.c:703 New SYSTEM INFORMATION 3 (mcc 123 mnc 01 lac 0x007c)
<0001> gsm48_rr.c:1907 Changing CCCH_MODE to 2
<0001> gsm48_rr.c:1939 New SYSTEM INFORMATION 4 (mcc 123 mnc 01 lac 0x007c)
<0001> sysinfo.c:615 Now updating previously received SYSTEM INFORMATION 4
<0001> gsm48_rr.c:1787 New SYSTEM INFORMATION 1
<0003> gsm322.c:2721 Received relevant sysinfo.
<0003> gsm322.c:713 stopping pending CS timer.
<0003> gsm322.c:2327 Scan frequency 119: Cell found. (rxlev -55 mcc 123 mnc 01 lac 007c)
<0003> gsm322.c:1841 Select using access class
<0003> gsm322.c:383 A (RLA_C (-55) - RXLEV_ACC_MIN (-110)) = 55
<0003> gsm322.c:385 B (MS_TXPWR_MAX_CCH (35) - p (33)) = 2
<0003> gsm322.c:387 C1 (A - MAX(B,0)) = 53
<0003> gsm322.c:1955 Cell ARFCN 119: Cell found, (rxlev=-55 mcc=123 mnc=01 lac=007c One, Two)
<0003> gsm322.c:1970 Cell ARFCN 119 selected.
<0003> gsm322.c:2428 Tune to frequency 119.
<0003> gsm322.c:464 Sync to ARFCN=119 rxlev=-55 (Sysinfo, ccch mode COMB)
<0003> gsm322.c:2455 Cell available.
<0003> gsm322.c:4060 (ms 1) Event 'EVENT_CELL_FOUND' for Cell selection in state 'C1 normal cell selection'
<000e> gsm322.c:3392 Camping normally on cell (ARFCN=119 mcc=123 mnc=01 One, Two)
<0003> gsm322.c:833 new state 'C1 normal cell selection' -> 'C3 camped normally'
<0005> gsm48_mm.c:4310 (ms 1) Received 'MM_EVENT_CELL_SELECTED' event in state wait for RR connection (location updating)
<0005> gsm48_mm.c:1141 We are camping normally as returning to MM IDLE
<0005> gsm48_mm.c:1166 Loc. upd. allowed.
<0005> gsm48_mm.c:914 new state wait for RR connection (location updating) -> MM IDLE, location updating needed
<0005> gsm48_mm.c:904 new MM IDLE state location updating needed -> attempting to update
<0005> gsm48_mm.c:2222 Loc. upd. already pending.
<000c> l1ctl.c:169 FBSB RESP: result=255
<0003> gsm322.c:3001 Channel sync error, try again
<0003> gsm322.c:464 Sync to ARFCN=119 rxlev=-55 (Sysinfo, ccch mode COMB)
<000c> l1ctl.c:300 Dropping frame with 60 bit errors
….
<000c> l1ctl.c:300 Dropping frame with 60 bit errors
<000c> l1ctl.c:169 FBSB RESP: result=255
<0003> gsm322.c:3014 Channel sync error.
<0003> gsm322.c:3018 free sysinfo ARFCN=119
<0003> gsm322.c:3027 Unselect cell due to sync error!
