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