I can only make one call!

24 Aug 2009

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

2024

2023

2022

2021

2020

2019

2018

2017

2016

2015

2014

2013

2012

2011

2010

2009

I can only make one call!