August 2009 - OpenBSC - lists.osmocom.org

[PATCH 0/1] Add bport0/1 support for bs11_config
by Daniel Willmann 14 Dec '10

14 Dec '10

Hello, second try to add support to bs11_config for bport0/1 configuration. This time with enum abis_bs11_line_cfg. It seems sometimes creating bport1 fails, even LMT shows create obj greyed out. Don't know why yet. Regards, Daniel Willmann Daniel Willmann (1): Add {create,delete}-bport1 and bport0-{star,multidrop} to bs11-config openbsc/include/openbsc/abis_nm.h | 10 +++++++++- openbsc/src/abis_nm.c | 31 +++++++++++++++++++++++++++++-- openbsc/src/bs11_config.c | 26 ++++++++++++++++++++++++++ 3 files changed, 64 insertions(+), 3 deletions(-)

4 5

SMS delivery (Mobile Terminated): First ok, second fails
by 246tnt＠gmail.com 26 Sep '09

26 Sep '09

Hi, I've noticed a problem when delivering several SMS to a mobile back-to-back. The first one is ok, the second one is received by the MS but OpenBSC fails with "RX RP-ACK but no sms in transaction?!?" when receiving the "RX SMS RP-ACK (MO)" (full log below) From the symptoms and looking at the code, I would say that the transaction of the first SMS delivery was not freed, therefore, when we receive the RP-ACK of the second SMS, we do a trans_find_by_id in gsm0411_rcv_sms, and we actually find the transaction of the _first_ deliver (which has its .sms field cleared since it was released already). I think you wouldn't notice if you waited between SMS because SMC Timer TC1* would expire, calling trans_free(). The problem is that I don't really know _where_ trans_free should be called. There are commented out call to it at several places ... I would think the RP-ACK(MO) reception looks like a good place. But OTOH, this whole process can queue data, giving away a reference to lchan (without get) and trans_free does a put on lchan (so I guess that could invalidate the lchan ref that was queued ? didn't check that in details) Sylvain --- SNIP --- <0100> gsm_04_11.c:920 send_sms_lchan() <0002> transaction.c:69 subscr=0x1fe52d0, subscr->net=0x1f8fc30 <0002> gsm_subscriber_base.c:131 subscr 46332 usage increases usage to: 4 <0002> gsm_04_11.c:937 lchan (bts=0,trx=0,ts=0,ch=0) increases usage to: 1 <0100> gsm_04_11.c:972 TX: SMS DELIVER <0100> gsm_04_11.c:188 TX: CP-DATA trans=4 <0100> gsm_04_11.c:149 GSM4.11 TX 49 01 1e 01 2a 07 91 44 77 58 10 06 50 00 12 00 05 b9 72 65 f7 00 00 90 80 72 12 03 41 00 02 44 3a <0001> abis_rsl.c:1215 channel=(bts=0,trx=0,ts=0) chan_nr=0x20 sapi=3 DATA INDICATION <0100> gsm_04_11.c:817 trans_id=c RX SMS CP-ACK <0001> abis_rsl.c:1215 channel=(bts=0,trx=0,ts=0) chan_nr=0x20 sapi=3 DATA INDICATION <0100> gsm_04_11.c:817 trans_id=c RX SMS CP-DATA <0100> gsm_04_11.c:191 TX: CP-ACK trans=4 <0100> gsm_04_11.c:149 GSM4.11 TX 49 04 <0100> gsm_04_11.c:750 RX SMS RP-ACK (MO) <0002> gsm_subscriber_base.c:139 subscr 27567 usage decreased usage to: 0 <0002> gsm_subscriber_base.c:139 subscr 46332 usage decreased usage to: 3 DB: Found Subscriber: ID 2, IMSI 206205003327508, NAME '', TMSI 1666608304, EXTEN '27567', LAC 1, AUTH 1 DBI: -7: The requested variable type does not match what libdbi thinks it should be <0002> gsm_subscriber_base.c:131 subscr 46332 usage increases usage to: 4 <0100> gsm_04_11.c:920 send_sms_lchan() <0002> transaction.c:69 subscr=0x1fe52d0, subscr->net=0x1f8fc30 <0002> gsm_subscriber_base.c:131 subscr 46332 usage increases usage to: 5 <0002> gsm_04_11.c:937 lchan (bts=0,trx=0,ts=0,ch=0) increases usage to: 2 <0100> gsm_04_11.c:972 TX: SMS DELIVER <0100> gsm_04_11.c:188 TX: CP-DATA trans=4 <0100> gsm_04_11.c:149 GSM4.11 TX 49 01 1f 01 2a 07 91 44 77 58 10 06 50 00 13 00 05 b9 72 65 f7 00 00 90 80 72 12 03 41 00 03 47 39 19 <0001> abis_rsl.c:1215 channel=(bts=0,trx=0,ts=0) chan_nr=0x20 sapi=3 DATA INDICATION <0100> gsm_04_11.c:817 trans_id=c RX SMS CP-ACK <0001> abis_rsl.c:1215 channel=(bts=0,trx=0,ts=0) chan_nr=0x20 sapi=3 DATA INDICATION <0100> gsm_04_11.c:817 trans_id=c RX SMS CP-DATA <0100> gsm_04_11.c:191 TX: CP-ACK trans=4 <0100> gsm_04_11.c:149 GSM4.11 TX 49 04 <0100> gsm_04_11.c:750 RX SMS RP-ACK (MO) <0100> gsm_04_11.c:640 RX RP-ACK but no sms in transaction?!? <0100> gsm_04_11.c:561 TX: SMS RP ERROR, cause 111 (Protocol Error) <0100> gsm_04_11.c:188 TX: CP-DATA trans=4 <0100> gsm_04_11.c:149 GSM4.11 TX 49 01 04 05 2a 01 6f <0001> abis_rsl.c:1215 channel=(bts=0,trx=0,ts=0) chan_nr=0x20 sapi=3 DATA INDICATION <0100> gsm_04_11.c:817 trans_id=c RX SMS CP-ACK <0100> gsm_04_11.c:159 SMC Timer TC1* is expired, calling trans_free() <0002> transaction.c:101 lchan (bts=0,trx=0,ts=0,ch=0) decreases usage to: 1 <0002> gsm_subscriber_base.c:139 subscr 46332 usage decreased usage to: 4 --- /SNIP ---

3 3

Re: Some questions about OpenBSC / BS-11
by Dieter Spaar 31 Aug '09

31 Aug '09

Hello Sergey, On Mon, 31 Aug 2009 15:11:26 +0400, "Sergey V. Efimoff" <risky(a)mail.ru> wrote: > > What type of E1 adapter do you use? If you use an accurate clock source, > Work and Set values should be similiar (I have Set Value 1024, Work value 1023, > and I've used Nateks E1 SDH Mux with High-Stability Stratum oscillator when > calibrating). See http://bs11-abis.gnumonks.org/trac/wiki/isdnsync The observed difference of "Work Value 1104" and "Set Value 1082" should be no problem. From my measurements a difference of about 100 of those values means about 0.1 ppm. And 0.1 ppm is good enough that most phones should see the BS-11 and the offical networks. I guess there is some other problem, I would also check if bs11_config shows that the TRX is loaded. And maybe start with a configuration file which uses only one TRX. Best regards, Dieter -- Dieter Spaar, Germany spaar(a)mirider.augusta.de

2 1

Some questions about OpenBSC / BS-11
by openbsc＠elxsi.de 31 Aug '09

31 Aug '09

Hi, we have some troubles finding the network on our cell-phones. There are a lot of suspicious messages, we can't really make sense out of. Example: bs11_config_query shows the following line: BS11 Attributes" a line: E1 Channel: Port=255 Timeslot=161 Subslot=39 This looks completely broken (misparsed by bs11_config?) Another issue are the Set- and Work-values of the Phase-Locked-Loop: We synchronized it with the ISDN network (isdnsync) and it leveled off to 1104 for the Work-Value, Set-Value, however, is 1082. I suspect this is ok and set-value is some informational value only (perhaps the value of the last calibration?). One more question about the radio carrier: On startup of bsc_hack we get the following message: OC=RADIO CARRIER(02) INST=(00,01,ff) STATE CHG: OP_STATE=Disabled AVAIL=In test(00) Is this normal behaviour? Apart from all this questions, does anyone have some more hints what we should look at? We tried different cell phones, tried switching them off and on, but without any success. We're using the latest git version of openbsc. Thank you for your help! Martin

2 1

Ericsson RBS Support
by Caleb Pal 28 Aug '09

28 Aug '09

Hello Again, So as my previous post mentioned, I recently acquired an Ericsson RBS 2401. In looking through the configuration, it only allows for a T1 connection, not an E1. The first issue I see with this is I haven't been able to find a mISDN supported T1 card. I assume all of the BS-11 are E1, so I suppose T1 support has never come up. Not a coder myself, I'm not going to be a lot of help in that department. But I can get a T1 card and hook this RBS up to a Linux box and do any debugging that might help move things along. I'm open to any suggestions. I know Harald was looking into getting traces for Ericsson BSC <-> BTS traffic, so hopefully that will help as well. Thanks again, Caleb

3 2

Ericsson BTS's (US Based Testers?)
by Caleb Pal 28 Aug '09

28 Aug '09

Hello all, I'm new to the list, and new to GSM testing, although I've worked with a fair share of RF, Linux, and networks. I recently acquired an Ericsson RBS 2401 for testing. This is a 2 TRX BTS with a T1 interface. It works on the GSM 1900 band, and puts out 300mw at full power. There will be another post about getting started with that... Onto the main purpose of this post..I have a contact who has 5 Ericsson RBS 2308's, which are the same as the unit Harald recently purchased. They are probably a bit bigger than the BS-11, have 4 TRX, output about 2.5 watts, have a T1 interface, and support EDGE. They are banded for GSM 1900. They can be bought for a reasonable price, plus shipping from California. Obviously OpenBSC doesn't support these at the moment, but hopefully support will be added sometime in the future. These would be a great resource for anyone in the US wanting to do testing, or overseas testers, if you're willing to pay shipping. If you want more info on the unit itself, you can download the manual Harald posted to the list earlier this month. If anyone is interested, please contact me off list, and I will get you in contact with the seller. Thanks Caleb

1 0

wiki / trac user accounts
by Harald Welte 27 Aug '09

27 Aug '09

Hi all! By mistake I deleted the password file for the wiki/trac earlier today, so all of you will need new accounts I used this opportunity to enable new account registration, i.e. everyone can now create an account for themselves - you don't need to go through me to do that anymore. So sorry for the inconvenience to existing users, you will have to create a new account using the 'register' tab on http://openbsc.gnumonks.org/ Regards, -- - Harald Welte <laforge(a)gnumonks.org> http://laforge.gnumonks.org/ ============================================================================ "Privacy in residential applications is a desirable marketing option." (ETSI EN 300 175-7 Ch. A6)

1 0

I can only make one call!
by Mike 27 Aug '09

27 Aug '09

Hi all, first let me say what a hugely impressive achievement OpenBSC is - I was AMAZED to be able to get a test network up and running within a day, and make a call from one handset to another! A slight problem though - I can only make ONE call. After that, I get "CHANNEL ACTIVATE NACK CAUSE=0x30(Transcoding not available)" if I try to make another call, or even if I just switch off a handset. I'm using an IpAccess nanoBTS - is it likely that this is an IpAccess-specific issue? I thought initially that it might be to do with subscriber use_count - once a call has been set up, the called party has subscr->use_count one greater than the calling party, and when the call ends the called party finishes up with use_count = 1, but I added a call to subscr_put in gsm_rr_rx_pag_resp which cured the use_count issue and the one-call-only problem persisted. I've attached a log of the debug messages with annotations of what actions were happening, may be of use. I'm still at an early stage in understanding the OpenBSC code, but in time I hope to be able to contribute some patches... Thanks, Mike Haben The information contained in this message (and any attachments) may be confidential and is intended for the sole use of the named addressee. Access, copying, alteration or re-use of the e-mail by anyone other than the intended recipient is unauthorised. If you are not the intended recipient please advise the sender immediately by returning the e-mail and deleting it from your system. This information may be exempt from disclosure under Freedom Of Information Act 2000 and may be subject to exemption under other UK information legislation. Refer disclosure requests to the Information Officer. The original of this email was scanned for viruses by the Government Secure Intranet virus scanning service supplied by Cable&Wireless in partnership with MessageLabs. (CCTM Certificate Number 2007/11/0032.) On leaving the GSi this email was certified virus free. Communications via the GSi may be automatically logged, monitored and/or recorded for legal purposes. gnuradio@gnuradio-laptop:~/openbsc/openbsc/src$ ./bsc_hack DB: Database initialized. DB: Database prepared. Mon Aug 24 15:25:18 2009 <8000> input/ipaccess.c:430 accept()ed new OML link from 192.168.2.3 Mon Aug 24 15:25:18 2009 <8000> input/ipaccess.c:203 Identified BTS 1/0/0 bootstrapping OML for BTS 0 Mon Aug 24 15:25:18 2009 <0020> abis_nm.c:805 OC=SITE MANAGER(00) INST=(ff,ff,ff) STATE CHG: OP_STATE=Disabled AVAIL=Not installed(07) Mon Aug 24 15:25:18 2009 <0020> abis_nm.c:805 OC=BTS(01) INST=(00,ff,ff) STATE CHG: OP_STATE=Disabled AVAIL=Not installed(07) ADM=Locked Mon Aug 24 15:25:18 2009 <0020> abis_nm.c:805 OC=BASEBAND TRANSCEIVER(04) INST=(00,00,ff) STATE CHG: OP_STATE=Disabled AVAIL=Not installed(07) ADM=Locked Mon Aug 24 15:25:18 2009 <0020> abis_nm.c:805 OC=CHANNEL(03) INST=(00,00,00) STATE CHG: OP_STATE=Disabled AVAIL=Not installed(07) ADM=Locked Mon Aug 24 15:25:18 2009 <0020> abis_nm.c:805 OC=CHANNEL(03) INST=(00,00,01) STATE CHG: OP_STATE=Disabled AVAIL=Not installed(07) ADM=Locked Mon Aug 24 15:25:18 2009 <0020> abis_nm.c:805 OC=CHANNEL(03) INST=(00,00,02) STATE CHG: OP_STATE=Disabled AVAIL=Not installed(07) ADM=Locked Mon Aug 24 15:25:18 2009 <0020> abis_nm.c:805 OC=CHANNEL(03) INST=(00,00,03) STATE CHG: OP_STATE=Disabled AVAIL=Not installed(07) ADM=Locked Mon Aug 24 15:25:18 2009 <0020> abis_nm.c:805 OC=CHANNEL(03) INST=(00,00,04) STATE CHG: OP_STATE=Disabled AVAIL=Not installed(07) ADM=Locked Mon Aug 24 15:25:18 2009 <0020> abis_nm.c:805 OC=CHANNEL(03) INST=(00,00,05) STATE CHG: OP_STATE=Disabled AVAIL=Not installed(07) ADM=Locked Mon Aug 24 15:25:18 2009 <0020> abis_nm.c:805 OC=CHANNEL(03) INST=(00,00,06) STATE CHG: OP_STATE=Disabled AVAIL=Not installed(07) ADM=Locked Mon Aug 24 15:25:18 2009 <0020> abis_nm.c:805 OC=CHANNEL(03) INST=(00,00,07) STATE CHG: OP_STATE=Disabled AVAIL=Not installed(07) ADM=Locked Mon Aug 24 15:25:18 2009 <0020> abis_nm.c:805 OC=RADIO CARRIER(02) INST=(00,00,ff) STATE CHG: OP_STATE=Disabled AVAIL=Not installed(07) ADM=Locked Mon Aug 24 15:25:18 2009 <0020> abis_nm.c:805 OC=GPRS NSE(f0) INST=(00,ff,ff) STATE CHG: Mon Aug 24 15:25:18 2009 <0020> abis_nm.c:805 OC=GPRS CELL(f1) INST=(00,00,ff) STATE CHG: Mon Aug 24 15:25:18 2009 <0020> abis_nm.c:805 OC=GPRS NSVC(f2) INST=(00,00,ff) STATE CHG: Mon Aug 24 15:25:18 2009 <0020> abis_nm.c:805 OC=GPRS NSVC(f2) INST=(00,01,ff) STATE CHG: Mon Aug 24 15:25:20 2009 <0020> abis_nm.c:859 Software Activate Request ACKing and Activating Mon Aug 24 15:25:20 2009 <0020> abis_nm.c:805 OC=SITE MANAGER(00) INST=(ff,ff,ff) STATE CHG: OP_STATE=Enabled AVAIL=OK(ff) Mon Aug 24 15:25:20 2009 <0020> abis_nm.c:1828 Sending OPSTART obj_class=0x00 obj_inst=(0xff, 0xff, 0xff) Mon Aug 24 15:25:20 2009 <0020> abis_nm.c:805 OC=SITE MANAGER(00) INST=(ff,ff,ff) Software Activated Report Mon Aug 24 15:25:20 2009 <0020> abis_nm.c:859 Software Activate Request ACKing and Activating Mon Aug 24 15:25:20 2009 <0020> abis_nm.c:859 Software Activate Request NACKing for GPRS obj_class 0xf0 Mon Aug 24 15:25:20 2009 <0020> abis_nm.c:805 OC=BTS(01) INST=(00,ff,ff) STATE CHG: OP_STATE=Disabled AVAIL=Dependency(05) Mon Aug 24 15:25:20 2009 <0020> abis_nm.c:1644 Set BTS Attr (bts=0) Mon Aug 24 15:25:20 2009 <0020> abis_nm.c:1828 Sending OPSTART obj_class=0x01 obj_inst=(0x00, 0xff, 0xff) Mon Aug 24 15:25:20 2009 <0020> abis_nm.c:805 OC=BTS(01) INST=(00,ff,ff) Software Activated Report Mon Aug 24 15:25:20 2009 <0020> abis_nm.c:859 Software Activate Request ACKing and Activating Mon Aug 24 15:25:20 2009 <0020> abis_nm.c:859 Software Activate Request ACKing and Activating Mon Aug 24 15:25:21 2009 <0020> abis_nm.c:805 OC=BTS(01) INST=(00,ff,ff) STATE CHG: OP_STATE=Disabled AVAIL=Dependency(05) ADM=Unlocked Mon Aug 24 15:25:21 2009 <0020> abis_nm.c:805 OC=BASEBAND TRANSCEIVER(04) INST=(00,00,ff) STATE CHG: OP_STATE=Disabled AVAIL=Dependency(05) Mon Aug 24 15:25:21 2009 <0020> abis_nm.c:805 OC=BASEBAND TRANSCEIVER(04) INST=(00,00,ff) Software Activated Report Mon Aug 24 15:25:21 2009 <0020> abis_nm.c:1828 Sending OPSTART obj_class=0x04 obj_inst=(0x00, 0x00, 0xff) Mon Aug 24 15:25:21 2009 <0020> abis_nm.c:805 OC=CHANNEL(03) INST=(00,00,00) STATE CHG: OP_STATE=Disabled AVAIL=Dependency(05) Mon Aug 24 15:25:21 2009 <0020> abis_nm.c:1741 Set Chan Attr (bts=0,trx=0,ts=0) Mon Aug 24 15:25:21 2009 <0020> abis_nm.c:1828 Sending OPSTART obj_class=0x03 obj_inst=(0x00, 0x00, 0x00) Mon Aug 24 15:25:21 2009 <0020> abis_nm.c:805 OC=CHANNEL(03) INST=(00,00,01) STATE CHG: OP_STATE=Disabled AVAIL=Dependency(05) Mon Aug 24 15:25:21 2009 <0020> abis_nm.c:1741 Set Chan Attr (bts=0,trx=0,ts=1) Mon Aug 24 15:25:21 2009 <0020> abis_nm.c:1828 Sending OPSTART obj_class=0x03 obj_inst=(0x00, 0x00, 0x01) Mon Aug 24 15:25:21 2009 <0020> abis_nm.c:805 OC=CHANNEL(03) INST=(00,00,02) STATE CHG: OP_STATE=Disabled AVAIL=Dependency(05) Mon Aug 24 15:25:21 2009 <0020> abis_nm.c:1741 Set Chan Attr (bts=0,trx=0,ts=2) Mon Aug 24 15:25:21 2009 <0020> abis_nm.c:1828 Sending OPSTART obj_class=0x03 obj_inst=(0x00, 0x00, 0x02) Mon Aug 24 15:25:21 2009 <0020> abis_nm.c:805 OC=CHANNEL(03) INST=(00,00,03) STATE CHG: OP_STATE=Disabled AVAIL=Dependency(05) Mon Aug 24 15:25:21 2009 <0020> abis_nm.c:1741 Set Chan Attr (bts=0,trx=0,ts=3) Mon Aug 24 15:25:21 2009 <0020> abis_nm.c:1828 Sending OPSTART obj_class=0x03 obj_inst=(0x00, 0x00, 0x03) Mon Aug 24 15:25:21 2009 <0020> abis_nm.c:805 OC=CHANNEL(03) INST=(00,00,04) STATE CHG: OP_STATE=Disabled AVAIL=Dependency(05) Mon Aug 24 15:25:21 2009 <0020> abis_nm.c:1741 Set Chan Attr (bts=0,trx=0,ts=4) Mon Aug 24 15:25:21 2009 <0020> abis_nm.c:1828 Sending OPSTART obj_class=0x03 obj_inst=(0x00, 0x00, 0x04) Mon Aug 24 15:25:21 2009 <0020> abis_nm.c:805 OC=CHANNEL(03) INST=(00,00,05) STATE CHG: OP_STATE=Disabled AVAIL=Dependency(05) Mon Aug 24 15:25:21 2009 <0020> abis_nm.c:1741 Set Chan Attr (bts=0,trx=0,ts=5) Mon Aug 24 15:25:21 2009 <0020> abis_nm.c:1828 Sending OPSTART obj_class=0x03 obj_inst=(0x00, 0x00, 0x05) Mon Aug 24 15:25:22 2009 <0020> abis_nm.c:805 OC=CHANNEL(03) INST=(00,00,06) STATE CHG: OP_STATE=Disabled AVAIL=Dependency(05) Mon Aug 24 15:25:22 2009 <0020> abis_nm.c:1741 Set Chan Attr (bts=0,trx=0,ts=6) Mon Aug 24 15:25:22 2009 <0020> abis_nm.c:1828 Sending OPSTART obj_class=0x03 obj_inst=(0x00, 0x00, 0x06) Mon Aug 24 15:25:22 2009 <0020> abis_nm.c:805 OC=CHANNEL(03) INST=(00,00,07) STATE CHG: OP_STATE=Disabled AVAIL=Dependency(05) Mon Aug 24 15:25:22 2009 <0020> abis_nm.c:1741 Set Chan Attr (bts=0,trx=0,ts=7) Mon Aug 24 15:25:22 2009 <0020> abis_nm.c:1828 Sending OPSTART obj_class=0x03 obj_inst=(0x00, 0x00, 0x07) Mon Aug 24 15:25:22 2009 <0020> abis_nm.c:805 OC=CHANNEL(03) INST=(00,00,00) Software Activated Report Mon Aug 24 15:25:22 2009 <0020> abis_nm.c:805 OC=CHANNEL(03) INST=(00,00,01) Software Activated Report Mon Aug 24 15:25:22 2009 <0020> abis_nm.c:805 OC=CHANNEL(03) INST=(00,00,02) Software Activated Report Mon Aug 24 15:25:22 2009 <0020> abis_nm.c:805 OC=CHANNEL(03) INST=(00,00,03) Software Activated Report Mon Aug 24 15:25:22 2009 <0020> abis_nm.c:805 OC=CHANNEL(03) INST=(00,00,04) Software Activated Report Mon Aug 24 15:25:22 2009 <0020> abis_nm.c:805 OC=CHANNEL(03) INST=(00,00,05) Software Activated Report Mon Aug 24 15:25:22 2009 <0020> abis_nm.c:805 OC=CHANNEL(03) INST=(00,00,06) Software Activated Report Mon Aug 24 15:25:22 2009 <0020> abis_nm.c:805 OC=CHANNEL(03) INST=(00,00,07) Software Activated Report Mon Aug 24 15:25:23 2009 <0020> abis_nm.c:805 OC=RADIO CARRIER(02) INST=(00,00,ff) STATE CHG: OP_STATE=Disabled AVAIL=Off line(03) Mon Aug 24 15:25:23 2009 <0020> abis_nm.c:805 OC=RADIO CARRIER(02) INST=(00,00,ff) Software Activated Report Mon Aug 24 15:25:23 2009 <0020> abis_nm.c:1661 Set TRX Attr (bts=0,trx=0) Mon Aug 24 15:25:23 2009 <0020> abis_nm.c:1828 Sending OPSTART obj_class=0x02 obj_inst=(0x00, 0x00, 0xff) Mon Aug 24 15:25:23 2009 <0020> abis_nm.c:2450 IPACCESS(0xe1): RSL CONNECT ACK IP=192.168.2.2 PORT=3003 Mon Aug 24 15:25:23 2009 <0020> abis_nm.c:805 OC=BASEBAND TRANSCEIVER(04) INST=(00,00,ff) STATE CHG: OP_STATE=Disabled AVAIL=Dependency(05) ADM=Unlocked Mon Aug 24 15:25:24 2009 <0020> abis_nm.c:805 OC=CHANNEL(03) INST=(00,00,00) STATE CHG: OP_STATE=Disabled AVAIL=Dependency(05) ADM=Unlocked Mon Aug 24 15:25:24 2009 <0020> abis_nm.c:805 OC=CHANNEL(03) INST=(00,00,01) STATE CHG: OP_STATE=Disabled AVAIL=Dependency(05) ADM=Unlocked Mon Aug 24 15:25:24 2009 <0020> abis_nm.c:805 OC=CHANNEL(03) INST=(00,00,02) STATE CHG: OP_STATE=Disabled AVAIL=Dependency(05) ADM=Unlocked Mon Aug 24 15:25:24 2009 <0020> abis_nm.c:805 OC=CHANNEL(03) INST=(00,00,03) STATE CHG: OP_STATE=Disabled AVAIL=Dependency(05) ADM=Unlocked Mon Aug 24 15:25:24 2009 <0020> abis_nm.c:805 OC=CHANNEL(03) INST=(00,00,04) STATE CHG: OP_STATE=Disabled AVAIL=Dependency(05) ADM=Unlocked Mon Aug 24 15:25:24 2009 <0020> abis_nm.c:805 OC=CHANNEL(03) INST=(00,00,05) STATE CHG: OP_STATE=Disabled AVAIL=Dependency(05) ADM=Unlocked Mon Aug 24 15:25:24 2009 <0020> abis_nm.c:805 OC=CHANNEL(03) INST=(00,00,06) STATE CHG: OP_STATE=Disabled AVAIL=Dependency(05) ADM=Unlocked Mon Aug 24 15:25:24 2009 <0020> abis_nm.c:805 OC=CHANNEL(03) INST=(00,00,07) STATE CHG: OP_STATE=Disabled AVAIL=Dependency(05) ADM=Unlocked Mon Aug 24 15:25:24 2009 <0020> abis_nm.c:805 OC=RADIO CARRIER(02) INST=(00,00,ff) STATE CHG: OP_STATE=Disabled AVAIL=OK(ff) ADM=Unlocked Mon Aug 24 15:25:24 2009 <8000> input/ipaccess.c:491 accept()ed new RSL link from 192.168.2.3 Mon Aug 24 15:25:24 2009 <8000> input/ipaccess.c:203 Identified BTS 1/0/0 Mon Aug 24 15:25:24 2009 <0020> abis_nm.c:805 OC=RADIO CARRIER(02) INST=(00,00,ff) STATE CHG: OP_STATE=Enabled AVAIL=OK(ff) Mon Aug 24 15:25:24 2009 <0020> abis_nm.c:805 OC=BASEBAND TRANSCEIVER(04) INST=(00,00,ff) STATE CHG: OP_STATE=Disabled AVAIL=Off line(03) Mon Aug 24 15:25:24 2009 <0020> abis_nm.c:805 OC=BASEBAND TRANSCEIVER(04) INST=(00,00,ff) STATE CHG: OP_STATE=Enabled AVAIL=OK(ff) Mon Aug 24 15:25:24 2009 <0020> abis_nm.c:805 OC=CHANNEL(03) INST=(00,00,00) STATE CHG: OP_STATE=Disabled AVAIL=Off line(03) Mon Aug 24 15:25:24 2009 <0020> abis_nm.c:805 OC=CHANNEL(03) INST=(00,00,01) STATE CHG: OP_STATE=Disabled AVAIL=Off line(03) Mon Aug 24 15:25:24 2009 <0020> abis_nm.c:805 OC=CHANNEL(03) INST=(00,00,02) STATE CHG: OP_STATE=Disabled AVAIL=Off line(03) Mon Aug 24 15:25:24 2009 <0020> abis_nm.c:805 OC=CHANNEL(03) INST=(00,00,03) STATE CHG: OP_STATE=Disabled AVAIL=Off line(03) Mon Aug 24 15:25:24 2009 <0020> abis_nm.c:805 OC=CHANNEL(03) INST=(00,00,04) STATE CHG: OP_STATE=Disabled AVAIL=Off line(03) Mon Aug 24 15:25:24 2009 <0020> abis_nm.c:805 OC=CHANNEL(03) INST=(00,00,05) STATE CHG: OP_STATE=Disabled AVAIL=Off line(03) Mon Aug 24 15:25:24 2009 <0020> abis_nm.c:805 OC=CHANNEL(03) INST=(00,00,06) STATE CHG: OP_STATE=Disabled AVAIL=Off line(03) Mon Aug 24 15:25:24 2009 <0020> abis_nm.c:805 OC=CHANNEL(03) INST=(00,00,07) STATE CHG: OP_STATE=Disabled AVAIL=Off line(03) Mon Aug 24 15:25:24 2009 <0020> abis_nm.c:805 OC=CHANNEL(03) INST=(00,00,00) STATE CHG: OP_STATE=Enabled AVAIL=OK(ff) Mon Aug 24 15:25:24 2009 <0020> abis_nm.c:805 OC=BTS(01) INST=(00,ff,ff) STATE CHG: OP_STATE=Enabled AVAIL=OK(ff) Mon Aug 24 15:25:24 2009 <0020> abis_nm.c:805 OC=CHANNEL(03) INST=(00,00,01) STATE CHG: OP_STATE=Enabled AVAIL=OK(ff) Mon Aug 24 15:25:24 2009 <0020> abis_nm.c:805 OC=CHANNEL(03) INST=(00,00,02) STATE CHG: OP_STATE=Enabled AVAIL=OK(ff) Mon Aug 24 15:25:24 2009 <0020> abis_nm.c:805 OC=CHANNEL(03) INST=(00,00,03) STATE CHG: OP_STATE=Enabled AVAIL=OK(ff) Mon Aug 24 15:25:24 2009 <0020> abis_nm.c:805 OC=CHANNEL(03) INST=(00,00,04) STATE CHG: OP_STATE=Enabled AVAIL=OK(ff) Mon Aug 24 15:25:24 2009 <0020> abis_nm.c:805 OC=CHANNEL(03) INST=(00,00,05) STATE CHG: OP_STATE=Enabled AVAIL=OK(ff) Mon Aug 24 15:25:24 2009 <0020> abis_nm.c:805 OC=CHANNEL(03) INST=(00,00,06) STATE CHG: OP_STATE=Enabled AVAIL=OK(ff) Mon Aug 24 15:25:24 2009 <0020> abis_nm.c:805 OC=CHANNEL(03) INST=(00,00,07) STATE CHG: OP_STATE=Enabled AVAIL=OK(ff) bootstrapping RSL for BTS/TRX (0/0) using MCC=123 MNC=45 BSIC=63 TSC=7 (first handset is switched on) Mon Aug 24 15:26:05 2009 <0010> abis_rsl.c:1110 Activating ARFCN(600) TS(0) SS(0) lctype SDCCH chan_nr=0x20 r=LOCATION_UPDATE ra=0x05 Mon Aug 24 15:26:05 2009 <0010> abis_rsl.c:943 channel=(bts=0,trx=0,ts=0) chan_nr=0x20 CHANNEL ACTIVATE ACK Mon Aug 24 15:26:05 2009 <0001> abis_rsl.c:1214 channel=(bts=0,trx=0,ts=0) chan_nr=0x20 sapi=0 ESTABLISH INDICATION Mon Aug 24 15:26:05 2009 <0004> gsm_04_08.c:1438 LOCATION UPDATING REQUEST: mi_type=0x04 MI(1771098126) type=IMSI ATTACH Mon Aug 24 15:26:05 2009 <0002> gsm_04_08.c:285 lchan (bts=0,trx=0,ts=0,ch=0) increases usage to: 1 Mon Aug 24 15:26:05 2009 <0002> gsm_04_08_utils.c:141 (bts 0 trx 0 ts 0 pd 05) Sending 0x18 to MS. DB: Found Subscriber: ID 4, IMSI 234101183420817, NAME '', TMSI 1771098126, EXTEN '43076', LAC 1, AUTH 0 Mon Aug 24 15:26:06 2009 <0001> abis_rsl.c:1214 channel=(bts=0,trx=0,ts=0) chan_nr=0x20 sapi=0 DATA INDICATION Mon Aug 24 15:26:06 2009 <0004> gsm_04_08.c:943 IDENTITY RESPONSE: mi_type=0x02 MI(353969018688060) DB: Updated EquipmentWatch: ID 13, IMSI 234101183420817, IMEI 353969018688060 DB: Sync Equipment IMEI=353969018688060, classmark1=30, classmark2=30 18 81 , classmark3=45 52 52 4f 52 00 00 6c 69 62 64 62 69 DB: Allocated TMSI 1019739084 for IMSI 234101183420817. Mon Aug 24 15:26:06 2009 <0002> gsm_04_08.c:280 lchan (bts=0,trx=0,ts=0,ch=0) decreases usage to: 0 Mon Aug 24 15:26:06 2009 <0004> gsm_04_08.c:904 -> LOCATION UPDATE ACCEPT Mon Aug 24 15:26:06 2009 <0002> gsm_04_08_utils.c:141 (bts 0 trx 0 ts 0 pd 05) Sending 0x02 to MS. Mon Aug 24 15:26:06 2009 <0004> gsm_04_08.c:1226 -> MM INFO Mon Aug 24 15:26:06 2009 <0002> gsm_04_08_utils.c:141 (bts 0 trx 0 ts 0 pd 05) Sending 0x32 to MS. Mon Aug 24 15:26:06 2009 <0008> gsm_04_08.c:1697 TX APPLICATION INFO id=0x00, len=4 Mon Aug 24 15:26:06 2009 <0002> gsm_04_08_utils.c:141 (bts 0 trx 0 ts 0 pd 06) Sending 0x38 to MS. Mon Aug 24 15:26:06 2009 <0001> abis_rsl.c:1214 channel=(bts=0,trx=0,ts=0) chan_nr=0x20 sapi=0 DATA INDICATION Mon Aug 24 15:26:06 2009 <0004> gsm_04_08.c:1454 TMSI Reallocation Completed. Subscriber: 234101183420817 Mon Aug 24 15:26:07 2009 <0001> abis_rsl.c:1214 channel=(bts=0,trx=0,ts=0) chan_nr=0x20 sapi=0 DATA INDICATION Mon Aug 24 15:26:07 2009 <0008> gsm_04_08.c:1597 STATUS rr_cause = Message type non-existant or not implemented Mon Aug 24 15:26:16 2009 <0001> abis_rsl.c:1214 channel=(bts=0,trx=0,ts=0) chan_nr=0x20 sapi=0 RELEASE INDICATION Mon Aug 24 15:26:16 2009 <0010> abis_rsl.c:667 RF Channel Release CMD channel=(bts=0,trx=0,ts=0) chan_nr=0x20 Mon Aug 24 15:26:16 2009 <0010> abis_rsl.c:943 channel=(bts=0,trx=0,ts=0) chan_nr=0x20 RF CHANNEL RELEASE ACK Mon Aug 24 15:26:16 2009 <0002> gsm_subscriber_base.c:139 subscr 43076 usage decreased usage to: 0 (second handset is switched on) Mon Aug 24 15:27:13 2009 <0010> abis_rsl.c:1110 Activating ARFCN(600) TS(0) SS(0) lctype SDCCH chan_nr=0x20 r=LOCATION_UPDATE ra=0x0d Mon Aug 24 15:27:13 2009 <0010> abis_rsl.c:943 channel=(bts=0,trx=0,ts=0) chan_nr=0x20 CHANNEL ACTIVATE ACK Mon Aug 24 15:27:14 2009 <0001> abis_rsl.c:1214 channel=(bts=0,trx=0,ts=0) chan_nr=0x20 sapi=0 ESTABLISH INDICATION Mon Aug 24 15:27:14 2009 <0004> gsm_04_08.c:1438 LOCATION UPDATING REQUEST: mi_type=0x01 MI(234334906506238) type=NORMAL Mon Aug 24 15:27:14 2009 <0002> gsm_04_08.c:285 lchan (bts=0,trx=0,ts=0,ch=0) increases usage to: 1 Mon Aug 24 15:27:14 2009 <0002> gsm_04_08_utils.c:141 (bts 0 trx 0 ts 0 pd 05) Sending 0x18 to MS. DB: Found Subscriber: ID 1, IMSI 234334906506238, NAME '', TMSI 620409869, EXTEN '29153', LAC 1, AUTH 0 Mon Aug 24 15:27:14 2009 <0001> abis_rsl.c:1214 channel=(bts=0,trx=0,ts=0) chan_nr=0x20 sapi=0 DATA INDICATION Mon Aug 24 15:27:14 2009 <0004> gsm_04_08.c:943 IDENTITY RESPONSE: mi_type=0x02 MI(359602012862220) DB: Updated EquipmentWatch: ID 1, IMSI 234334906506238, IMEI 359602012862220 DB: Sync Equipment IMEI=359602012862220, classmark1=30, classmark2=30 18 95 , classmark3=45 52 52 4f 52 00 00 6c 69 DB: Allocated TMSI 475314068 for IMSI 234334906506238. Mon Aug 24 15:27:14 2009 <0002> gsm_04_08.c:280 lchan (bts=0,trx=0,ts=0,ch=0) decreases usage to: 0 Mon Aug 24 15:27:14 2009 <0004> gsm_04_08.c:904 -> LOCATION UPDATE ACCEPT Mon Aug 24 15:27:14 2009 <0002> gsm_04_08_utils.c:141 (bts 0 trx 0 ts 0 pd 05) Sending 0x02 to MS. Mon Aug 24 15:27:14 2009 <0004> gsm_04_08.c:1226 -> MM INFO Mon Aug 24 15:27:14 2009 <0002> gsm_04_08_utils.c:141 (bts 0 trx 0 ts 0 pd 05) Sending 0x32 to MS. Mon Aug 24 15:27:14 2009 <0008> gsm_04_08.c:1697 TX APPLICATION INFO id=0x00, len=4 Mon Aug 24 15:27:14 2009 <0002> gsm_04_08_utils.c:141 (bts 0 trx 0 ts 0 pd 06) Sending 0x38 to MS. Mon Aug 24 15:27:14 2009 <0001> abis_rsl.c:1214 channel=(bts=0,trx=0,ts=0) chan_nr=0x20 sapi=0 DATA INDICATION Mon Aug 24 15:27:14 2009 <0004> gsm_04_08.c:1454 TMSI Reallocation Completed. Subscriber: 234334906506238 Mon Aug 24 15:27:15 2009 <0001> abis_rsl.c:1214 channel=(bts=0,trx=0,ts=0) chan_nr=0x20 sapi=0 DATA INDICATION Mon Aug 24 15:27:15 2009 <0008> gsm_04_08.c:1597 STATUS rr_cause = Message type non-existant or not implemented Mon Aug 24 15:27:25 2009 <0001> abis_rsl.c:1214 channel=(bts=0,trx=0,ts=0) chan_nr=0x20 sapi=0 RELEASE INDICATION Mon Aug 24 15:27:25 2009 <0010> abis_rsl.c:667 RF Channel Release CMD channel=(bts=0,trx=0,ts=0) chan_nr=0x20 Mon Aug 24 15:27:25 2009 <0010> abis_rsl.c:943 channel=(bts=0,trx=0,ts=0) chan_nr=0x20 RF CHANNEL RELEASE ACK Mon Aug 24 15:27:25 2009 <0002> gsm_subscriber_base.c:139 subscr 29153 usage decreased usage to: 0 (handsets are left idle for a while, then 43076 calls 29153) Mon Aug 24 15:28:58 2009 <0010> abis_rsl.c:1110 Activating ARFCN(600) TS(2) SS(0) lctype TCH/F chan_nr=0x0a r=OTHER ra=0xf4 Mon Aug 24 15:28:58 2009 <0010> abis_rsl.c:943 channel=(bts=0,trx=0,ts=2) chan_nr=0x0a CHANNEL ACTIVATE ACK Mon Aug 24 15:28:59 2009 <0001> abis_rsl.c:1214 channel=(bts=0,trx=0,ts=2) chan_nr=0x0a sapi=0 ESTABLISH INDICATION Mon Aug 24 15:28:59 2009 <0004> gsm_04_08.c:1326 <- CM SERVICE REQUEST serv_type=0x01 mi_type=0x04 M(1019739084) DB: Found Subscriber: ID 4, IMSI 234101183420817, NAME '', TMSI 1019739084, EXTEN '43076', LAC 1, AUTH 0 DB: Sync Equipment IMEI=353969018688060, classmark1=30, classmark2=30 18 81 , classmark3=45 52 52 4f 52 00 00 6c 69 62 64 62 69 Mon Aug 24 15:28:59 2009 <0004> gsm_04_08.c:1261 -> CM SERVICE ACK Mon Aug 24 15:28:59 2009 <0002> gsm_04_08_utils.c:141 (bts 0 trx 0 ts 2 pd 05) Sending 0x21 to MS. Mon Aug 24 15:28:59 2009 <0001> abis_rsl.c:1214 channel=(bts=0,trx=0,ts=2) chan_nr=0x0a sapi=0 DATA INDICATION Mon Aug 24 15:28:59 2009 <0002> gsm_04_08.c:3529 (bts 0 trx 0 ts 2 ti 8 sub 43076) Received 'SETUP' from MS in state 0 (NULL) Mon Aug 24 15:28:59 2009 <0002> gsm_04_08.c:3534 Unknown transaction ID 8, creating new trans. Mon Aug 24 15:28:59 2009 <0002> transaction.c:69 subscr=0x8714d58, subscr->net=0x86d9e70 Mon Aug 24 15:28:59 2009 <0002> gsm_subscriber_base.c:131 subscr 43076 usage increases usage to: 2 Mon Aug 24 15:28:59 2009 <0002> gsm_04_08.c:3547 lchan (bts=0,trx=0,ts=2,ch=0) increases usage to: 1 Mon Aug 24 15:28:59 2009 <0002> gsm_04_08.c:1722 new state NULL -> INITIATED Mon Aug 24 15:28:59 2009 <0002> gsm_04_08.c:1782 (bts 0 trx 0 ts 2 ti 8 sub 43076) Sending 'MNCC_SETUP_IND' to MNCC. Mon Aug 24 15:28:59 2009 <0080> mncc.c:320 (call 80000001) Call created. Mon Aug 24 15:28:59 2009 <0080> mncc.c:324 (call 80000001) Received message MNCC_SETUP_IND Mon Aug 24 15:28:59 2009 <0080> mncc.c:157 (call 80000001) Creating new remote instance 1. Mon Aug 24 15:28:59 2009 <0080> mncc.c:167 (call 80000001) Modify channel mode. Mon Aug 24 15:28:59 2009 <0002> gsm_04_08.c:3435 (bts 0 trx 0 ts 2 ti 08 sub 43076) Received 'MNCC_LCHAN_MODIFY' from MNCC in state 1 (INITIATED) Mon Aug 24 15:28:59 2009 <0008> gsm_04_08.c:1100 -> CHANNEL MODE MODIFY mode=0x21 Mon Aug 24 15:28:59 2009 <0002> gsm_04_08_utils.c:141 (bts 0 trx 0 ts 2 pd 06) Sending 0x10 to MS. Mon Aug 24 15:28:59 2009 <0010> abis_rsl.c:1309 channel=(bts=0,trx=0,ts=2) chan_nr=0x0a IPAC_BIND speech_mode=0x11 Mon Aug 24 15:28:59 2009 <0080> mncc.c:173 (call 80000001) Accepting call. Mon Aug 24 15:28:59 2009 <0002> gsm_04_08.c:3435 (bts 0 trx 0 ts 2 ti 08 sub 43076) Received 'MNCC_CALL_PROC_REQ' from MNCC in state 1 (INITIATED) Mon Aug 24 15:28:59 2009 <0002> gsm_04_08.c:1722 new state INITIATED -> MO_CALL_PROC Mon Aug 24 15:28:59 2009 <0002> gsm_04_08_utils.c:136 (bts 0 trx 0 ts 2 ti 80) Sending 'CALL_PROC' to MS. Mon Aug 24 15:28:59 2009 <0080> mncc.c:180 (call 80000001) Forwarding SETUP to remote. DB: Found Subscriber: ID 1, IMSI 234334906506238, NAME '', TMSI 475314068, EXTEN '29153', LAC 1, AUTH 0 Mon Aug 24 15:28:59 2009 <0002> transaction.c:69 subscr=0x87150f8, subscr->net=0x86d9e70 Mon Aug 24 15:28:59 2009 <0002> gsm_subscriber_base.c:131 subscr 29153 usage increases usage to: 2 Mon Aug 24 15:28:59 2009 <0200> paging.c:228 Start paging of subscriber 1 on bts 0. Mon Aug 24 15:28:59 2009 <0002> gsm_subscriber_base.c:131 subscr 29153 usage increases usage to: 3 Mon Aug 24 15:28:59 2009 <0010> abis_rsl.c:1432 channel=(bts=0,trx=0,ts=2) chan_nr=0x0a IPAC_BIND_ACK IP=192.168.2.3 PORT=4020 CONN_ID=26 RTP_PAYLOAD2=0x71 Mon Aug 24 15:28:59 2009 <0001> abis_rsl.c:1214 channel=(bts=0,trx=0,ts=2) chan_nr=0x0a sapi=0 DATA INDICATION Mon Aug 24 15:28:59 2009 <0008> gsm_04_08.c:1663 CHANNEL MODE MODIFY ACK Mon Aug 24 15:28:59 2009 <0010> abis_rsl.c:943 channel=(bts=0,trx=0,ts=2) chan_nr=0x0a CHANNEL MODE MODIFY ACK Mon Aug 24 15:29:00 2009 <0200> paging.c:98 Going to send paging commands: '234334906506238' Mon Aug 24 15:29:00 2009 <0010> abis_rsl.c:1110 Activating ARFCN(600) TS(3) SS(0) lctype TCH/F chan_nr=0x0b r=PAGING ra=0x2d Mon Aug 24 15:29:01 2009 <0010> abis_rsl.c:943 channel=(bts=0,trx=0,ts=3) chan_nr=0x0b CHANNEL ACTIVATE ACK Mon Aug 24 15:29:01 2009 <0001> abis_rsl.c:1214 channel=(bts=0,trx=0,ts=3) chan_nr=0x0b sapi=0 ESTABLISH INDICATION Mon Aug 24 15:29:01 2009 <0008> gsm_04_08.c:1489 PAGING RESPONSE: mi_type=0x04 MI(475314068) Mon Aug 24 15:29:01 2009 <0002> gsm_subscriber_base.c:131 subscr 29153 usage increases usage to: 4 Mon Aug 24 15:29:01 2009 <0002> gsm_subscriber_base.c:139 subscr 29153 usage decreased usage to: 3 (*** this is a modification of mine ***) Mon Aug 24 15:29:01 2009 <0008> gsm_04_08.c:1512 <- Channel was requested by 234334906506238 DB: Sync Equipment IMEI=359602012862220, classmark1=30, classmark2=30 18 95 , classmark3=45 52 52 4f 52 00 00 6c 69 Mon Aug 24 15:29:01 2009 <0008> gsm_04_08.c:1697 TX APPLICATION INFO id=0x00, len=4 Mon Aug 24 15:29:01 2009 <0002> gsm_04_08_utils.c:141 (bts 0 trx 0 ts 3 pd 06) Sending 0x38 to MS. Mon Aug 24 15:29:01 2009 <0200> paging.c:282 Stop paging on bts 0, calling cbfn. Mon Aug 24 15:29:01 2009 <0002> gsm_04_08.c:1864 Paging subscr 29153 succeeded! Mon Aug 24 15:29:01 2009 <0002> gsm_04_08.c:1868 lchan (bts=0,trx=0,ts=3,ch=0) increases usage to: 1 Mon Aug 24 15:29:01 2009 <0002> gsm_04_08.c:2166 starting timer T303 with 30 seconds Mon Aug 24 15:29:01 2009 <0002> gsm_04_08.c:1722 new state NULL -> CALL_PRESENT Mon Aug 24 15:29:01 2009 <0002> gsm_04_08_utils.c:136 (bts 0 trx 0 ts 3 ti 00) Sending 'SETUP' to MS. Mon Aug 24 15:29:01 2009 <0002> gsm_subscriber_base.c:139 subscr 29153 usage decreased usage to: 2 Mon Aug 24 15:29:01 2009 <0001> abis_rsl.c:1214 channel=(bts=0,trx=0,ts=3) chan_nr=0x0b sapi=0 DATA INDICATION Mon Aug 24 15:29:01 2009 <0008> gsm_04_08.c:1597 STATUS rr_cause = Message type non-existant or not implemented Mon Aug 24 15:29:01 2009 <0001> abis_rsl.c:1214 channel=(bts=0,trx=0,ts=3) chan_nr=0x0b sapi=0 DATA INDICATION Mon Aug 24 15:29:01 2009 <0002> gsm_04_08.c:3529 (bts 0 trx 0 ts 3 ti 0 sub 29153) Received 'CALL_CONF' from MS in state 6 (CALL_PRESENT) Mon Aug 24 15:29:01 2009 <0002> gsm_04_08.c:1763 stopping pending timer T303 Mon Aug 24 15:29:01 2009 <0002> gsm_04_08.c:2166 starting timer T310 with 180 seconds Mon Aug 24 15:29:01 2009 <0002> gsm_04_08.c:1722 new state CALL_PRESENT -> MO_TERM_CALL_CONF Mon Aug 24 15:29:01 2009 <0002> gsm_04_08.c:1782 (bts 0 trx 0 ts 3 ti 0 sub 29153) Sending 'MNCC_CALL_CONF_IND' to MNCC. Mon Aug 24 15:29:01 2009 <0080> mncc.c:324 (call 1) Received message MNCC_CALL_CONF_IND Mon Aug 24 15:29:01 2009 <0002> gsm_04_08.c:3435 (bts 0 trx 0 ts 3 ti 00 sub 29153) Received 'MNCC_LCHAN_MODIFY' from MNCC in state 9 (MO_TERM_CALL_CONF) Mon Aug 24 15:29:01 2009 <0008> gsm_04_08.c:1100 -> CHANNEL MODE MODIFY mode=0x21 Mon Aug 24 15:29:01 2009 <0002> gsm_04_08_utils.c:141 (bts 0 trx 0 ts 3 pd 06) Sending 0x10 to MS. Mon Aug 24 15:29:01 2009 <0010> abis_rsl.c:1309 channel=(bts=0,trx=0,ts=3) chan_nr=0x0b IPAC_BIND speech_mode=0x11 Mon Aug 24 15:29:01 2009 <0001> abis_rsl.c:1214 channel=(bts=0,trx=0,ts=3) chan_nr=0x0b sapi=0 DATA INDICATION Mon Aug 24 15:29:01 2009 <0008> gsm_04_08.c:1663 CHANNEL MODE MODIFY ACK Mon Aug 24 15:29:01 2009 <0010> abis_rsl.c:1432 channel=(bts=0,trx=0,ts=3) chan_nr=0x0b IPAC_BIND_ACK IP=192.168.2.3 PORT=4022 CONN_ID=27 RTP_PAYLOAD2=0x72 Mon Aug 24 15:29:01 2009 <0010> abis_rsl.c:943 channel=(bts=0,trx=0,ts=3) chan_nr=0x0b CHANNEL MODE MODIFY ACK Mon Aug 24 15:29:01 2009 <0001> abis_rsl.c:1214 channel=(bts=0,trx=0,ts=3) chan_nr=0x0b sapi=0 DATA INDICATION Mon Aug 24 15:29:01 2009 <0002> gsm_04_08.c:3529 (bts 0 trx 0 ts 3 ti 0 sub 29153) Received 'ALERTING' from MS in state 9 (MO_TERM_CALL_CONF) Mon Aug 24 15:29:01 2009 <0002> gsm_04_08.c:1763 stopping pending timer T310 Mon Aug 24 15:29:01 2009 <0002> gsm_04_08.c:2166 starting timer T301 with 180 seconds Mon Aug 24 15:29:01 2009 <0002> gsm_04_08.c:1722 new state MO_TERM_CALL_CONF -> CALL_RECEIVED Mon Aug 24 15:29:01 2009 <0002> gsm_04_08.c:1782 (bts 0 trx 0 ts 3 ti 0 sub 29153) Sending 'MNCC_ALERT_IND' to MNCC. Mon Aug 24 15:29:01 2009 <0080> mncc.c:324 (call 1) Received message MNCC_ALERT_IND Mon Aug 24 15:29:01 2009 <0080> mncc.c:193 (call 1) Forwarding ALERT to remote. Mon Aug 24 15:29:01 2009 <0002> gsm_04_08.c:3435 (bts 0 trx 0 ts 2 ti 08 sub 43076) Received 'MNCC_ALERT_REQ' from MNCC in state 3 (MO_CALL_PROC) Mon Aug 24 15:29:01 2009 <0002> gsm_04_08.c:1722 new state MO_CALL_PROC -> CALL_DELIVERED Mon Aug 24 15:29:01 2009 <0002> gsm_04_08_utils.c:136 (bts 0 trx 0 ts 2 ti 80) Sending 'ALERTING' to MS. Mon Aug 24 15:29:04 2009 <0001> abis_rsl.c:1214 channel=(bts=0,trx=0,ts=3) chan_nr=0x0b sapi=0 DATA INDICATION Mon Aug 24 15:29:04 2009 <0002> gsm_04_08.c:3529 (bts 0 trx 0 ts 3 ti 0 sub 29153) Received 'CONNECT' from MS in state 7 (CALL_RECEIVED) Mon Aug 24 15:29:04 2009 <0002> gsm_04_08.c:1763 stopping pending timer T301 Mon Aug 24 15:29:04 2009 <0002> gsm_04_08.c:1722 new state CALL_RECEIVED -> CONNECT_REQUEST Mon Aug 24 15:29:04 2009 <0002> gsm_04_08.c:1782 (bts 0 trx 0 ts 3 ti 0 sub 29153) Sending 'MNCC_SETUP_CNF' to MNCC. Mon Aug 24 15:29:04 2009 <0080> mncc.c:324 (call 1) Received message MNCC_SETUP_CNF Mon Aug 24 15:29:04 2009 <0080> mncc.c:220 (call 1) Acknowledge SETUP. Mon Aug 24 15:29:04 2009 <0002> gsm_04_08.c:3435 (bts 0 trx 0 ts 3 ti 00 sub 29153) Received 'MNCC_SETUP_COMPL_REQ' from MNCC in state 8 (CONNECT_REQUEST) Mon Aug 24 15:29:04 2009 <0002> gsm_04_08.c:1722 new state CONNECT_REQUEST -> ACTIVE Mon Aug 24 15:29:04 2009 <0002> gsm_04_08_utils.c:136 (bts 0 trx 0 ts 3 ti 00) Sending 'CONNECT_ACK' to MS. Mon Aug 24 15:29:04 2009 <0080> mncc.c:227 (call 1) Sending CONNECT to remote. Mon Aug 24 15:29:04 2009 <0002> gsm_04_08.c:3435 (bts 0 trx 0 ts 2 ti 08 sub 43076) Received 'MNCC_SETUP_RSP' from MNCC in state 4 (CALL_DELIVERED) Mon Aug 24 15:29:04 2009 <0002> gsm_04_08.c:2166 starting timer T313 with 30 seconds Mon Aug 24 15:29:04 2009 <0002> gsm_04_08.c:1722 new state CALL_DELIVERED -> CONNECT_IND Mon Aug 24 15:29:04 2009 <0002> gsm_04_08_utils.c:136 (bts 0 trx 0 ts 2 ti 80) Sending 'CONNECT' to MS. Mon Aug 24 15:29:04 2009 <0080> mncc.c:233 (call 1) Bridging with remote. Mon Aug 24 15:29:04 2009 <0002> gsm_04_08.c:1966 Setting up TCH map between (bts=0,trx=0,ts=3) and (bts=0,trx=0,ts=2) Mon Aug 24 15:29:04 2009 <0010> abis_rsl.c:1337 channel=(bts=0,trx=0,ts=3) chan_nr=0x0b IPAC_CONNECT IP=192.168.2.3 PORT=4020 RTP_PAYLOAD2=113 CONN_ID=27 speech_mode=0x01 Mon Aug 24 15:29:04 2009 <0010> abis_rsl.c:1337 channel=(bts=0,trx=0,ts=2) chan_nr=0x0a IPAC_CONNECT IP=192.168.2.3 PORT=4022 RTP_PAYLOAD2=114 CONN_ID=26 speech_mode=0x01 Mon Aug 24 15:29:04 2009 <0001> abis_rsl.c:1214 channel=(bts=0,trx=0,ts=2) chan_nr=0x0a sapi=0 DATA INDICATION Mon Aug 24 15:29:04 2009 <0002> gsm_04_08.c:3529 (bts 0 trx 0 ts 2 ti 8 sub 43076) Received 'CONNECT_ACK' from MS in state 28 (CONNECT_IND) Mon Aug 24 15:29:04 2009 <0002> gsm_04_08.c:1763 stopping pending timer T313 Mon Aug 24 15:29:04 2009 <0002> gsm_04_08.c:1722 new state CONNECT_IND -> ACTIVE Mon Aug 24 15:29:04 2009 <0002> gsm_04_08.c:1782 (bts 0 trx 0 ts 2 ti 8 sub 43076) Sending 'MNCC_SETUP_COMPL_IND' to MNCC. Mon Aug 24 15:29:04 2009 <0080> mncc.c:324 (call 80000001) Received message MNCC_SETUP_COMPL_IND Mon Aug 24 15:29:04 2009 <0010> abis_rsl.c:1432 channel=(bts=0,trx=0,ts=3) chan_nr=0x0b IPAC_CONNECT_ACK Mon Aug 24 15:29:04 2009 <0010> abis_rsl.c:1432 channel=(bts=0,trx=0,ts=2) chan_nr=0x0a IPAC_CONNECT_ACK (call in progress for 30 seconds) Mon Aug 24 15:29:34 2009 <0001> abis_rsl.c:1214 channel=(bts=0,trx=0,ts=3) chan_nr=0x0b sapi=0 DATA INDICATION Mon Aug 24 15:29:34 2009 <0002> gsm_04_08.c:3529 (bts 0 trx 0 ts 3 ti 0 sub 29153) Received 'DISCONNECT' from MS in state 10 (ACTIVE) Mon Aug 24 15:29:34 2009 <0002> gsm_04_08.c:1722 new state ACTIVE -> DISCONNECT_IND Mon Aug 24 15:29:34 2009 <0002> gsm_04_08.c:1782 (bts 0 trx 0 ts 3 ti 0 sub 29153) Sending 'MNCC_DISC_IND' to MNCC. Mon Aug 24 15:29:34 2009 <0080> mncc.c:324 (call 1) Received message MNCC_DISC_IND Mon Aug 24 15:29:34 2009 <0080> mncc.c:244 (call 1) Releasing call with cause 16 Mon Aug 24 15:29:34 2009 <0002> gsm_04_08.c:3435 (bts 0 trx 0 ts 3 ti 00 sub 29153) Received 'MNCC_REL_REQ' from MNCC in state 12 (DISCONNECT_IND) Mon Aug 24 15:29:34 2009 <0002> gsm_04_08.c:2166 starting timer T308 with 10 seconds Mon Aug 24 15:29:34 2009 <0002> gsm_04_08.c:1722 new state DISCONNECT_IND -> RELEASE_REQ Mon Aug 24 15:29:34 2009 <0002> gsm_04_08_utils.c:136 (bts 0 trx 0 ts 3 ti 00) Sending 'RELEASE' to MS. Mon Aug 24 15:29:34 2009 <0080> mncc.c:253 (call 80000001) Disconnecting remote with cause 16 Mon Aug 24 15:29:34 2009 <0002> gsm_04_08.c:3435 (bts 0 trx 0 ts 2 ti 08 sub 43076) Received 'MNCC_DISC_REQ' from MNCC in state 10 (ACTIVE) Mon Aug 24 15:29:34 2009 <0002> gsm_04_08.c:2166 starting timer T306 with 30 seconds Mon Aug 24 15:29:34 2009 <0002> gsm_04_08.c:1722 new state ACTIVE -> DISCONNECT_IND Mon Aug 24 15:29:34 2009 <0002> gsm_04_08_utils.c:136 (bts 0 trx 0 ts 2 ti 80) Sending 'DISCONNECT' to MS. Mon Aug 24 15:29:34 2009 <0001> abis_rsl.c:1214 channel=(bts=0,trx=0,ts=3) chan_nr=0x0b sapi=0 DATA INDICATION Mon Aug 24 15:29:34 2009 <0002> gsm_04_08.c:3529 (bts 0 trx 0 ts 3 ti 0 sub 29153) Received 'RELEASE_COMPL' from MS in state 19 (RELEASE_REQ) Mon Aug 24 15:29:34 2009 <0002> gsm_04_08.c:1763 stopping pending timer T308 Mon Aug 24 15:29:34 2009 <0002> gsm_04_08.c:1722 new state RELEASE_REQ -> NULL Mon Aug 24 15:29:34 2009 <0002> transaction.c:101 lchan (bts=0,trx=0,ts=3,ch=0) decreases usage to: 0 Mon Aug 24 15:29:34 2009 <0002> gsm_subscriber_base.c:139 subscr 29153 usage decreased usage to: 1 Mon Aug 24 15:29:36 2009 <0001> abis_rsl.c:1214 channel=(bts=0,trx=0,ts=2) chan_nr=0x0a sapi=0 DATA INDICATION Mon Aug 24 15:29:36 2009 <0002> gsm_04_08.c:3529 (bts 0 trx 0 ts 2 ti 8 sub 43076) Received 'RELEASE' from MS in state 12 (DISCONNECT_IND) Mon Aug 24 15:29:36 2009 <0002> gsm_04_08.c:1763 stopping pending timer T306 Mon Aug 24 15:29:36 2009 <0002> gsm_04_08_utils.c:136 (bts 0 trx 0 ts 2 ti 80) Sending 'RELEASE_COMPL' to MS. Mon Aug 24 15:29:36 2009 <0002> gsm_04_08.c:1782 (bts 0 trx 0 ts 2 ti 8 sub 43076) Sending 'MNCC_REL_IND' to MNCC. Mon Aug 24 15:29:36 2009 <0002> gsm_04_08.c:1722 new state DISCONNECT_IND -> NULL Mon Aug 24 15:29:36 2009 <0002> transaction.c:101 lchan (bts=0,trx=0,ts=2,ch=0) decreases usage to: 0 Mon Aug 24 15:29:36 2009 <0002> gsm_subscriber_base.c:139 subscr 43076 usage decreased usage to: 1 Mon Aug 24 15:29:36 2009 <0080> mncc.c:324 (call 80000001) Received message MNCC_REL_IND Mon Aug 24 15:29:36 2009 <0080> mncc.c:268 (call 80000001) Releasing remote with cause 0 Mon Aug 24 15:29:36 2009 <0002> gsm_04_08.c:3326 (bts - trx - ts - ti -- sub ) Received 'MNCC_REL_REQ' from MNCC with unknown callref 1 Mon Aug 24 15:29:36 2009 <0002> gsm_04_08.c:1790 (bts - trx - ts - ti -- sub -) Sending 'MNCC_REL_IND' to MNCC. Mon Aug 24 15:29:36 2009 <0080> mncc.c:109 (call 80000001) Call removed. Mon Aug 24 15:29:36 2009 <0080> mncc.c:324 (call 1) Received message MNCC_REL_IND Mon Aug 24 15:29:36 2009 <0080> mncc.c:109 (call 1) Call removed. Mon Aug 24 15:29:39 2009 <0008> gsm_04_08_utils.c:345 Sending Channel Release: Chan: Number: 0 Type: 2 Mon Aug 24 15:29:39 2009 <0002> gsm_04_08_utils.c:141 (bts 0 trx 0 ts 2 pd 06) Sending 0x0d to MS. Mon Aug 24 15:29:39 2009 <0010> abis_rsl.c:648 DEACTivate SACCH CMD channel=(bts=0,trx=0,ts=2) chan_nr=0x0a Mon Aug 24 15:29:39 2009 <0001> chan_alloc.c:263 Recycling the channel with: 0 (0) Mon Aug 24 15:29:39 2009 <0010> abis_rsl.c:1432 channel=(bts=0,trx=0,ts=2) chan_nr=0x0a IPAC_DISCONNECT_IND CAUSE=0x0f(Normal event, unspecified) Mon Aug 24 15:29:39 2009 <0001> abis_rsl.c:1214 channel=(bts=0,trx=0,ts=2) chan_nr=0x0a sapi=0 RELEASE INDICATION Mon Aug 24 15:29:39 2009 <0010> abis_rsl.c:667 RF Channel Release CMD channel=(bts=0,trx=0,ts=2) chan_nr=0x0a Mon Aug 24 15:29:39 2009 <0010> abis_rsl.c:943 channel=(bts=0,trx=0,ts=2) chan_nr=0x0a RF CHANNEL RELEASE ACK Mon Aug 24 15:29:39 2009 <0002> gsm_subscriber_base.c:139 subscr 43076 usage decreased usage to: 0 Mon Aug 24 15:29:41 2009 <0008> gsm_04_08_utils.c:345 Sending Channel Release: Chan: Number: 0 Type: 2 Mon Aug 24 15:29:41 2009 <0002> gsm_04_08_utils.c:141 (bts 0 trx 0 ts 3 pd 06) Sending 0x0d to MS. Mon Aug 24 15:29:41 2009 <0010> abis_rsl.c:648 DEACTivate SACCH CMD channel=(bts=0,trx=0,ts=3) chan_nr=0x0b Mon Aug 24 15:29:41 2009 <0001> chan_alloc.c:263 Recycling the channel with: 0 (0) Mon Aug 24 15:29:41 2009 <0010> abis_rsl.c:1432 channel=(bts=0,trx=0,ts=3) chan_nr=0x0b IPAC_DISCONNECT_IND CAUSE=0x0f(Normal event, unspecified) Mon Aug 24 15:29:41 2009 <0001> abis_rsl.c:1214 channel=(bts=0,trx=0,ts=3) chan_nr=0x0b sapi=0 RELEASE INDICATION Mon Aug 24 15:29:41 2009 <0010> abis_rsl.c:667 RF Channel Release CMD channel=(bts=0,trx=0,ts=3) chan_nr=0x0b Mon Aug 24 15:29:41 2009 <0010> abis_rsl.c:943 channel=(bts=0,trx=0,ts=3) chan_nr=0x0b RF CHANNEL RELEASE ACK Mon Aug 24 15:29:41 2009 <0002> gsm_subscriber_base.c:139 subscr 29153 usage decreased usage to: 0 (wait a while, then try making another call) Mon Aug 24 15:30:04 2009 <0010> abis_rsl.c:1110 Activating ARFCN(600) TS(2) SS(0) lctype TCH/F chan_nr=0x0a r=OTHER ra=0xfd Mon Aug 24 15:30:04 2009 <0010> abis_rsl.c:943 channel=(bts=0,trx=0,ts=2) chan_nr=0x0a CHANNEL ACTIVATE NACK CAUSE=0x30(Transcoding not available) Mon Aug 24 15:30:14 2009 <0010> abis_rsl.c:667 RF Channel Release CMD channel=(bts=0,trx=0,ts=2) chan_nr=0x0a Mon Aug 24 15:30:14 2009 <0010> abis_rsl.c:943 channel=(bts=0,trx=0,ts=2) chan_nr=0x0a RF CHANNEL RELEASE ACK (wait a while, then try making another call) Mon Aug 24 15:30:34 2009 <0010> abis_rsl.c:1110 Activating ARFCN(600) TS(2) SS(0) lctype TCH/F chan_nr=0x0a r=OTHER ra=0xe7 Mon Aug 24 15:30:34 2009 <0010> abis_rsl.c:943 channel=(bts=0,trx=0,ts=2) chan_nr=0x0a CHANNEL ACTIVATE NACK CAUSE=0x30(Transcoding not available) Mon Aug 24 15:30:44 2009 <0010> abis_rsl.c:667 RF Channel Release CMD channel=(bts=0,trx=0,ts=2) chan_nr=0x0a Mon Aug 24 15:30:44 2009 <0010> abis_rsl.c:943 channel=(bts=0,trx=0,ts=2) chan_nr=0x0a RF CHANNEL RELEASE ACK (ctrl-C) ^Csignal 2 received shutting down OML for BTS 0

6 8

nanoBTS - First Call Ok, but not others -> I have an ugly hack to fix it
by Sylvain Munaut 27 Aug '09

27 Aug '09

Hi, I had the exact same problem has described here http://lists.gnumonks.org/pipermail/openbsc/2009-July/000627.html That is, the first call worked great but after the first call, no way to make a second, the BTS would respond with NACK to the CHANnel ACTIvation requests. I finally found that the chan_rate field of the channel_mode must be 0 for that request to work. And forcing it to 0 in rsl_chan_activate_lchan, like this : --- SNIP --- rc = channel_mode_from_lchan(&cm, lchan); if (rc < 0) return rc; cm.chan_rate = 0; // Hack ! ----------- makes the thing work. But that's obviously not the root cause. I thing the lchan tch_mode should be GSM48_CMODE_SIGN, so that channel_mode_from_lchan would set the proper value directly. But I haven't traced yet why tch_mode is not at the good value for the second call ... Sylvain

2 1

nanoBTS - Different version ?
by Sylvain Munaut 26 Aug '09

26 Aug '09

Hi, I just got hold of a ip.access nanoBTS model 139U, but so far my attemps at making it work with OpenBSC have been unsuccessful :( It's a 'used' one so there are config already done most likely and I don't know which. What differs from http://bs11-abis.gnumonks.org/trac/wiki/nanoBTS : * It doesn't request an IP by DHCP It seems to have IP/Netmask and GW hardcoded. * When plugging it, it tries to do an ARP request for what I guess his the GW address, and when it get a response, it tries to connect to TCP port 3022 (yes, it's 3022 and not 3002) of yet another IP in another subnet (which I guess is the configured Primarly OML IP) * ipaccess-find does find it : ---- ipaccess-find (C) 2009 by Harald Welte This is FREE SOFTWARE with ABSOLUTELY NO WARRANTY Trying to find ip.access BTS by broadcast UDP... MAC Address='00:02:95:00:31:22' IP Address='10.105.254.142' Unit ID='65143/0/0' Location 1='NanoBTS FXMarketspace' Location 2='BTS_NBT131G' Equipment Version='139_029_41' Software Version='120a352_v267b22d0' Unit Name='69419' Serial Number='00071582' ---- * ipaccess-config fails, the nanoBTS refuses incoming connections on port tcp 3006 * Here's the result of a NMAP : ---- sh$ sudo nmap -sS 10.105.254.142 -p 1-65535 Starting Nmap 4.76 ( http://nmap.org ) at 2009-08-26 16:21 CEST Interesting ports on 10.105.254.142: Not shown: 65533 closed ports PORT STATE SERVICE 3026/tcp open unknown 3034/tcp open unknown MAC Address: 00:02:95:00:31:22 (IP.Access Limited) ---- * I then tried to run ipaccess-config to port 3026 but it fails (just waits a while thensays short read / BTS disappeared) * I tried making openbsc listen on 3022 but with no real results : --- sh$ bsc_hack DB: Database initialized. DB: Database prepared. no matching signalling link for hh->proto=0x01 no matching signalling link for hh->proto=0x29 no matching signalling link for hh->proto=0x2b no matching signalling link for hh->proto=0x1b short read! no matching signalling link for hh->proto=0x01 short read! BTS disappeared, dead socket short read! no matching signalling link for hh->proto=0x01 no matching signalling link for hh->proto=0x29 no matching signalling link for hh->proto=0x2b no matching signalling link for hh->proto=0xc9 short read! no matching signalling link for hh->proto=0x01 short read! BTS disappeared, dead socket --- Any idea any one ? Sylvain

2 2