24 Aug
2009
24 Aug
'09
5:05 p.m.
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
27 Aug
27 Aug
5:40 a.m.
Hi mike,
On Mon, Aug 24, 2009 at 04:05:43PM +0100, Mike wrote:
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!
thanks :)
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?
This is really strange. I have not observed this problem. Until July I did a
lot of development and testing on the nanoBTS. Though in August a lot of last
minute code was written for HAR2009 and only tested on the BS-11.
I am now again travelling and only have my nanoBTS with me. I can give some
testing, but maybe somebody else on the list can confirm that the current git
version works well on nanoBTS, too.
I'm still at an early stage in understanding the
OpenBSC code, but in time I
hope to be able to contribute some patches...
that's great, thanks a lot for wanting to help out! It would also be
interesting to know what exactly you're using it for :)
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.
Are you aware that you are posting to a publci mailinglist with a large number
of subscribers, whihc has a public, world-readable archive?
(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
This message definitely relates to the speech encoding algorithm that we
specify in ipaccess BIND and/or CONNECT. Maybe the BTS keeps track of the
transcoding that we specified the last time, and now we are asking it to use a
different codec?
Basically the BTS thinks that the IP/UDP/RTP side is configured to use one
codec (e.g. FR) and the RF side is nwo configured to a different codec (e.g.
EFR). Since the BTS does not have a transcoder, it rejects it.
Maybe this helps you to debug...
basically there are three parts where the same codec needs to be used
1) in the 04.08 bearer capability
2) in the RSL channel activation (RF side activation)
3) in the ipaccess bind and connect.
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)
9:02 a.m.
Hi,
Actually this is the issue I was having too. I just realized that my
link to the mailing list was not at all the good one (wrong cut &
paste ... sorry about that).
The attached patch fixes it. I've also added a patch to put a
autogen.sh script ... always useful imho :)
Note that the wireshark trace show that CAUSE=0x30 is "Service or
option not available" which reflects the problem slightly better than
"Transcoding not available".
BTW Harald, is it possible to get some credentials to edit the wiki ?
I'd like to put a little more info about getting started with the
nanoBTS (Especially the part about the power & reset dongle :)
Sylvain
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?
This is really strange. I have not observed this problem. Until July I did a
lot of development and testing on the nanoBTS. Though in August a lot of last
minute code was written for HAR2009 and only tested on the BS-11.
I am now again travelling and only have my nanoBTS with me. I can give some
testing, but maybe somebody else on the list can confirm that the current git
version works well on nanoBTS, too.
9:49 a.m.
please don't add an autogen.sh script. autoreconf
is really superior to that.
Mmm, I tried that originally but seeing you message, I retried using
that and it works if I use the '-i' script but that was not obvious to
me (I'm not an autotools guru ...) and apparently I'm not the only one
since the getting started on the wiki uses the autogen.sh script of
LCR to bootstrap OpenBSC :)
Maybe an autogen.sh that calls "autoreconf -i" ? Or maybe simple a
'How to build' in the README :)
Sylvain
10:52 a.m.
On Thursday 27 August 2009 09:49:44 Sylvain Munaut wrote:
Where would you expect such a note? On the website? Just in a readme?
z.
please
don't add an autogen.sh script. autoreconf is really superior to
that.
Mmm, I tried that originally but seeing you message, I retried using
that and it works if I use the '-i' script but that was not obvious to
me (I'm not an autotools guru ...) and apparently I'm not the only one
since the getting started on the wiki uses the autogen.sh script of
LCR to bootstrap OpenBSC :)
Maybe an autogen.sh that calls "autoreconf -i" ? Or maybe simple a
'How to build' in the README :)
11:03 a.m.
> Maybe an autogen.sh that calls "autoreconf
-i" ? Or maybe simple a
> 'How to build' in the README :)
Where would you expect such a note? On the website?
Just in a readme?
I'd say both. It's a pretty quick fix that can help a few people. And you
already fixed the wiki :) Or in the readme just a link to the wiki with a
generic 'Getting started' (generic and not bs-11 or nanoBTS specific), with
a little more guidance, config files and such. I'd be happy to write those
if I could edit the wiki.
If I had read the 'BS 11 getting started I would have seen it' but I only
saw the OpenBSC + LCR guide and the nanoBTS getting started.
Sylvain
11:42 a.m.
I agree with Sylvain.
If Holger, Harald and others are very busy with the sources, at least
Sylvain can improve the wiki guidance to help people having an easier start.
/(...after I recovered from Harald's wake-up call)
/246tnt(a)gmail.com schreef:
> Maybe an
autogen.sh that calls "autoreconf -i" ? Or maybe simple a
> 'How to build' in the README :)
Where would you expect such a note? On the
website? Just in a readme?
I'd say both. It's a pretty quick fix that can help a few people. And
you already fixed the wiki :) Or in the readme just a link to the wiki
with a generic 'Getting started' (generic and not bs-11 or nanoBTS
specific), with a little more guidance, config files and such. I'd be
happy to write those if I could edit the wiki.
If I had read the 'BS 11 getting started I would have seen it' but I
only saw the OpenBSC + LCR guide and the nanoBTS getting started.
Sylvain
11:08 a.m.
Hi all,
On Thu, Aug 27, 2009 at 09:02:28AM +0200, Sylvain Munaut wrote:
Actually this is the issue I was having too. I just
realized that my
link to the mailing list was not at all the good one (wrong cut &
paste ... sorry about that).
ok, thanks will commit that.
The attached patch fixes it. I've also added a
patch to put a
autogen.sh script ... always useful imho :)
"autoreconf -i" does the trick on any half-way recent autotools installation.
autogen.sh is a legacy of the past...
Note that the wireshark trace show that CAUSE=0x30 is
"Service or
option not available" which reflects the problem slightly better than
"Transcoding not available".
Ok, I have to double check what the specification says...
BTW Harald, is it possible to get some credentials to
edit the wiki ?
I'd like to put a little more info about getting started with the
nanoBTS (Especially the part about the power & reset dongle :)
of course! I'll send you private mail.
--
- 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)
5891
days inactive
5894
days old
8 comments
6 participants
participants (6)
-
246tnt＠gmail.com -
Harald Welte -
Holger Freyther -
Mike -
Nordin -
Sylvain Munaut