Re: How to enable AUTHENTICATION procedure in osmo-nitb? - OpenBSC

23 Dec 2018

Hi Domi.
Thank you.  :  )
After changing encryption type to A5/1, I can see AUTH REQ. 

------------------------
from dechao iPad

------------------ Original ------------------
From: Tomcsányi, Domonkos &lt;domi(a)tomcsanyi.net&gt;
Date: Sun,Dec 23,2018 3:01 PM
To: 水云之间 &lt;dechaoforever(a)qq.com&gt;
Cc: openbsc &lt;openbsc(a)lists.osmocom.org&gt;
Subject: Re: How to enable AUTHENTICATION procedure in osmo-nitb?

Hello,

I would start by changing the encryption type to a5 1
;)

Cheers,
Domi

2018. dec. 23. dátummal, 6:23 időpontban 水云之间 &lt;dechaoforever(a)qq.com&gt; írta:

Hi ALL.
Recently I setup osmo-nitb on my laptop, by using it I can send SMS and make a call
between 2 phones successfully.
The solution is LimeSDR + osmo-trx-lms + osmo-bts-trx + osmo-nitb.
But when I check the logs of osmo-nitb, cannot find AUTHENTICATION message.
How to trigger AUTHENTICATION ? These days I went through the osmonitb-usermanual.pdf but
still cannot get it.
I will be appreciated if you can help me.
Here is the LOGS, running-config and test subscriber info. 
============================LOGS============================

ezio@mylab:~$  osmo-nitb -c ~/.osmocom/osmo-nitb.cfg -l ~/.osmocom/hlr.sqlite3 -P -C
--debug=DRLL:DCC:DMM:DRR:DRSL:DNM
DB: Database initialized.
DB: Database prepared.
<0005> abis_nm.c:1601 Get Attr (bts=0)
<0005> abis_nm.c:1601 Get Attr (bts=0)
<0005> abis_nm.c:381 OC=SITE-MANAGER(00) INST=(ff,ff,ff) STATE CHG: OP_STATE=Enabled
AVAIL=OK(ff) ADM=unknown 0x0 
<0005> abis_nm.c:1938 OC=SITE-MANAGER(00) INST=(ff,ff,ff) Sending OPSTART
<0005> abis_nm.c:381 OC=BTS(01) INST=(00,ff,ff) STATE CHG: OP_STATE=NULL
AVAIL=Dependency(05) ADM=unknown 0x0 
<0005> abis_nm.c:1619 Set BTS Attr (bts=0)
<0005> abis_nm.c:1938 OC=BTS(01) INST=(00,ff,ff) Sending OPSTART
<0005> abis_nm.c:381 OC=GPRS-NSE(f0) INST=(00,ff,ff) STATE CHG: OP_STATE=NULL
AVAIL=Dependency(05) ADM=unknown 0x0 
<0005> abis_nm.c:381 OC=GPRS-CELL(f1) INST=(00,ff,ff) STATE CHG: OP_STATE=NULL
AVAIL=Dependency(05) ADM=unknown 0x0 
<0005> abis_nm.c:381 OC=GPRS-NSVC(f2) INST=(00,00,ff) STATE CHG: OP_STATE=NULL
AVAIL=Dependency(05) ADM=unknown 0x0 
<0005> abis_nm.c:381 OC=GPRS-NSVC(f2) INST=(00,01,ff) STATE CHG: OP_STATE=Disabled
AVAIL=Off line(03) ADM=unknown 0x0 
<0005> abis_nm.c:381 OC=RADIO-CARRIER(02) INST=(00,00,ff) STATE CHG: OP_STATE=NULL
AVAIL=Power off(02) ADM=Unlocked 
<0005> abis_nm.c:381 OC=BASEBAND-TRANSCEIVER(04) INST=(00,00,ff) STATE CHG:
OP_STATE=NULL AVAIL=Power off(02) ADM=unknown 0x0 
<0005> abis_nm.c:381 OC=CHANNEL(03) INST=(00,00,00) STATE CHG: OP_STATE=NULL
AVAIL=Power off(02) ADM=unknown 0x0 
<0005> abis_nm.c:381 OC=CHANNEL(03) INST=(00,00,01) STATE CHG: OP_STATE=NULL
AVAIL=Power off(02) ADM=unknown 0x0 
<0005> abis_nm.c:381 OC=CHANNEL(03) INST=(00,00,02) STATE CHG: OP_STATE=NULL
AVAIL=Power off(02) ADM=unknown 0x0 
<0005> abis_nm.c:381 OC=CHANNEL(03) INST=(00,00,03) STATE CHG: OP_STATE=NULL
AVAIL=Power off(02) ADM=unknown 0x0 
<0005> abis_nm.c:381 OC=CHANNEL(03) INST=(00,00,04) STATE CHG: OP_STATE=NULL
AVAIL=Power off(02) ADM=unknown 0x0 
<0005> abis_nm.c:381 OC=CHANNEL(03) INST=(00,00,05) STATE CHG: OP_STATE=NULL
AVAIL=Power off(02) ADM=unknown 0x0 
<0005> abis_nm.c:381 OC=CHANNEL(03) INST=(00,00,06) STATE CHG: OP_STATE=NULL
AVAIL=Power off(02) ADM=unknown 0x0 
<0005> abis_nm.c:381 OC=CHANNEL(03) INST=(00,00,07) STATE CHG: OP_STATE=NULL
AVAIL=Power off(02) ADM=unknown 0x0 
<0005> abis_nm.c:550 OC=BTS(01) INST=(00,ff,ff) Get Attributes Response for BTS0
<0005> abis_nm.c:463 BTS0 Get Attributes Response Info: 70 bytes total with 0
unreported attributes
<0005> abis_nm.c:507 BTS0 feature 'EGPRS' reported via OML does not match
statically set feature: 0 != 1. Please fix.
<0005> abis_nm.c:507 BTS0 feature 'OML Alerts' reported via OML does not
match statically set feature: 1 != 0. Please fix.
<0005> abis_nm.c:507 BTS0 feature 'CBCH' reported via OML does not match
statically set feature: 1 != 0. Please fix.
<0005> abis_nm.c:573 BTS0: ARI reported sw[0/2]: osmobts is 0.8.1.192-fc88
<0005> abis_nm.c:446 BTS0 reported variant: omso-bts-trx
<0005> abis_nm.c:550 OC=BTS(01) INST=(00,ff,ff) Get Attributes Response for BTS0
<0005> abis_nm.c:463 BTS0 Get Attributes Response Info: 32 bytes total with 1
unreported attributes
<0005> abis_nm.c:468 BTS0 Attribute Manufacturer Dependent State is unreported
<0005> abis_nm.c:573 BTS0: ARI reported sw[0/1]: TRX_PHY_VERSION is Unknown
<0005> abis_nm.c:771 OC=SITE-MANAGER(00) INST=(ff,ff,ff) Opstart ACK
<0005> abis_nm.c:381 OC=RADIO-CARRIER(02) INST=(00,00,ff) STATE CHG:
OP_STATE=Disabled AVAIL=OK(ff) ADM=Unlocked 
<0004> acc_ramp.c:162 (bts=0,trx=0) ACC RAMP: administrative state Unlocked ->
Unlocked
<0004> acc_ramp.c:166 (bts=0,trx=0) ACC RAMP: operational state NULL ->
Disabled
<0004> acc_ramp.c:175 (bts=0,trx=0) ACC RAMP: ignoring state change because RSL link
is down
<0005> abis_nm.c:1938 OC=RADIO-CARRIER(02) INST=(00,00,ff) Sending OPSTART
<0005> abis_nm.c:381 OC=BASEBAND-TRANSCEIVER(04) INST=(00,00,ff) STATE CHG:
OP_STATE=NULL AVAIL=OK(ff) ADM=unknown 0x0 
<0005> abis_nm.c:381 OC=RADIO-CARRIER(02) INST=(00,00,ff) Software Activated
Report
<0005> abis_nm.c:1636 Set TRX Attr (bts=0,trx=0)
<0005> abis_nm.c:1938 OC=RADIO-CARRIER(02) INST=(00,00,ff) Sending OPSTART
<0005> abis_nm.c:381 OC=BASEBAND-TRANSCEIVER(04) INST=(00,00,ff) Software Activated
Report
<0005> abis_nm.c:1938 OC=BASEBAND-TRANSCEIVER(04) INST=(00,00,ff) Sending OPSTART
<0005> abis_nm.c:2757 (bts=0,trx=0) IPA RSL CONNECT IP=0.0.0.0 PORT=3003
STREAM=0x00
<0005> abis_nm.c:381 OC=CHANNEL(03) INST=(00,00,00) STATE CHG: OP_STATE=Disabled
AVAIL=Dependency(05) ADM=unknown 0x0 
<0005> abis_nm.c:1831 Set Chan Attr (bts=0,trx=0,ts=0)
<0005> abis_nm.c:1938 OC=CHANNEL(03) INST=(00,00,00) Sending OPSTART
<0005> abis_nm.c:381 OC=CHANNEL(03) INST=(00,00,01) STATE CHG: OP_STATE=Disabled
AVAIL=Dependency(05) ADM=unknown 0x0 
<0005> abis_nm.c:1831 Set Chan Attr (bts=0,trx=0,ts=1)
<0005> abis_nm.c:1938 OC=CHANNEL(03) INST=(00,00,01) Sending OPSTART
<0005> abis_nm.c:381 OC=CHANNEL(03) INST=(00,00,02) STATE CHG: OP_STATE=Disabled
AVAIL=Dependency(05) ADM=unknown 0x0 
<0005> abis_nm.c:1831 Set Chan Attr (bts=0,trx=0,ts=2)
<0005> abis_nm.c:1938 OC=CHANNEL(03) INST=(00,00,02) Sending OPSTART
<0005> abis_nm.c:381 OC=CHANNEL(03) INST=(00,00,03) STATE CHG: OP_STATE=Disabled
AVAIL=Dependency(05) ADM=unknown 0x0 
<0005> abis_nm.c:1831 Set Chan Attr (bts=0,trx=0,ts=3)
<0005> abis_nm.c:1938 OC=CHANNEL(03) INST=(00,00,03) Sending OPSTART
<0005> abis_nm.c:381 OC=CHANNEL(03) INST=(00,00,04) STATE CHG: OP_STATE=Disabled
AVAIL=Dependency(05) ADM=unknown 0x0 
<0005> abis_nm.c:1831 Set Chan Attr (bts=0,trx=0,ts=4)
<0005> abis_nm.c:1938 OC=CHANNEL(03) INST=(00,00,04) Sending OPSTART
<0005> abis_nm.c:381 OC=CHANNEL(03) INST=(00,00,05) STATE CHG: OP_STATE=Disabled
AVAIL=Dependency(05) ADM=unknown 0x0 
<0005> abis_nm.c:1831 Set Chan Attr (bts=0,trx=0,ts=5)
<0005> abis_nm.c:1938 OC=CHANNEL(03) INST=(00,00,05) Sending OPSTART
<0005> abis_nm.c:381 OC=CHANNEL(03) INST=(00,00,06) STATE CHG: OP_STATE=Disabled
AVAIL=Dependency(05) ADM=unknown 0x0 
<0005> abis_nm.c:1831 Set Chan Attr (bts=0,trx=0,ts=6)
<0005> abis_nm.c:1938 OC=CHANNEL(03) INST=(00,00,06) Sending OPSTART
<0005> abis_nm.c:381 OC=CHANNEL(03) INST=(00,00,07) STATE CHG: OP_STATE=Disabled
AVAIL=Dependency(05) ADM=unknown 0x0 
<0005> abis_nm.c:1831 Set Chan Attr (bts=0,trx=0,ts=7)
<0005> abis_nm.c:1938 OC=CHANNEL(03) INST=(00,00,07) Sending OPSTART
<0005> abis_nm.c:381 OC=BTS(01) INST=(00,ff,ff) STATE CHG: OP_STATE=NULL
AVAIL=Dependency(05) ADM=Unlocked 
<0005> abis_nm.c:381 OC=BTS(01) INST=(00,ff,ff) STATE CHG: OP_STATE=Enabled
AVAIL=OK(ff) ADM=Unlocked 
<0005> abis_nm.c:771 OC=BTS(01) INST=(00,ff,ff) Opstart ACK
<0005> abis_nm.c:381 OC=RADIO-CARRIER(02) INST=(00,00,ff) STATE CHG:
OP_STATE=Enabled AVAIL=OK(ff) ADM=Unlocked 
<0004> acc_ramp.c:162 (bts=0,trx=0) ACC RAMP: administrative state Unlocked ->
Unlocked
<0004> acc_ramp.c:166 (bts=0,trx=0) ACC RAMP: operational state Disabled ->
Enabled
<0004> acc_ramp.c:175 (bts=0,trx=0) ACC RAMP: ignoring state change because RSL link
is down
<0005> abis_nm.c:771 OC=RADIO-CARRIER(02) INST=(00,00,ff) Opstart ACK
<0005> abis_nm.c:779 OC=RADIO-CARRIER(02) INST=(00,00,ff) Set Radio Carrier
Attributes ACK
<0004> acc_ramp.c:162 (bts=0,trx=0) ACC RAMP: administrative state Unlocked ->
Unlocked
<0004> acc_ramp.c:166 (bts=0,trx=0) ACC RAMP: operational state Enabled ->
Enabled
<0004> acc_ramp.c:175 (bts=0,trx=0) ACC RAMP: ignoring state change because RSL link
is down
<0005> abis_nm.c:381 OC=RADIO-CARRIER(02) INST=(00,00,ff) STATE CHG:
OP_STATE=Enabled AVAIL=OK(ff) ADM=Unlocked 
<0005> abis_nm.c:771 OC=RADIO-CARRIER(02) INST=(00,00,ff) Opstart ACK
<0005> abis_nm.c:381 OC=BASEBAND-TRANSCEIVER(04) INST=(00,00,ff) STATE CHG:
OP_STATE=NULL AVAIL=OK(ff) ADM=Unlocked 
<0005> abis_nm.c:381 OC=BASEBAND-TRANSCEIVER(04) INST=(00,00,ff) STATE CHG:
OP_STATE=Enabled AVAIL=OK(ff) ADM=Unlocked 
<0005> abis_nm.c:771 OC=BASEBAND-TRANSCEIVER(04) INST=(00,00,ff) Opstart ACK
<0005> abis_nm.c:2599 OC=BASEBAND-TRANSCEIVER(04) INST=(00,00,ff) IPACCESS(0xe1):
RSL CONNECT ACK 
<0005> abis_nm.c:775 OC=CHANNEL(03) INST=(00,00,00) Set Channel Attributes ACK
<0004> bsc_init.c:312 bootstrapping RSL for BTS/TRX (0/0) on ARFCN 514 using MCC-MNC
001-01 LAC=1 CID=0 BSIC=63
<0003> system_information.c:637 Serving cell: 514
<0003> bsc_init.c:108 SI1: 55 06 19 8f 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00
e5 04 00 2b 
<0003> bsc_init.c:108 SI2: 59 06 1a 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
ff e5 04 00 
<0003> bsc_init.c:110 SI2bis: OFF
<0003> bsc_init.c:110 SI2ter: OFF
<0003> bsc_init.c:110 SI2quater: OFF
<0003> bsc_init.c:108 SI3: 49 06 1b 00 00 00 f1 10 00 01 49 03 05 27 47 40 e5 04 00
29 2b 2b 2b 
<0003> bsc_init.c:108 SI4: 31 06 1c 00 f1 10 00 01 47 40 e5 04 00 2b 2b 2b 2b 2b 2b
2b 2b 2b 2b 
<0003> bsc_init.c:108 SI5: 49 06 1d 10 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2b 2b 2b 2b 
<0003> bsc_init.c:110 SI5bis: OFF
<0003> bsc_init.c:110 SI5ter: OFF
<0003> bsc_init.c:108 SI6: 2d 06 1e 00 00 00 f1 10 00 01 27 ff 2b 2b 2b 2b 2b 2b 2b
2b 2b 2b 2b 
<0005> abis_nm.c:381 OC=CHANNEL(03) INST=(00,00,00) STATE CHG: OP_STATE=Disabled
AVAIL=Dependency(05) ADM=Unlocked 
<0005> abis_nm.c:381 OC=CHANNEL(03) INST=(00,00,00) STATE CHG: OP_STATE=Enabled
AVAIL=OK(ff) ADM=Unlocked 
<0005> abis_nm.c:771 OC=CHANNEL(03) INST=(00,00,00) Opstart ACK
<0005> abis_nm.c:775 OC=CHANNEL(03) INST=(00,00,01) Set Channel Attributes ACK
<0005> abis_nm.c:381 OC=CHANNEL(03) INST=(00,00,01) STATE CHG: OP_STATE=Disabled
AVAIL=Dependency(05) ADM=Unlocked 
<0005> abis_nm.c:381 OC=CHANNEL(03) INST=(00,00,01) STATE CHG: OP_STATE=Enabled
AVAIL=OK(ff) ADM=Unlocked 
<0005> abis_nm.c:771 OC=CHANNEL(03) INST=(00,00,01) Opstart ACK
<0005> abis_nm.c:775 OC=CHANNEL(03) INST=(00,00,02) Set Channel Attributes ACK
<0005> abis_nm.c:381 OC=CHANNEL(03) INST=(00,00,02) STATE CHG: OP_STATE=Disabled
AVAIL=Dependency(05) ADM=Unlocked 
<0005> abis_nm.c:381 OC=CHANNEL(03) INST=(00,00,02) STATE CHG: OP_STATE=Enabled
AVAIL=OK(ff) ADM=Unlocked 
<0005> abis_nm.c:771 OC=CHANNEL(03) INST=(00,00,02) Opstart ACK
<0005> abis_nm.c:775 OC=CHANNEL(03) INST=(00,00,03) Set Channel Attributes ACK
<0005> abis_nm.c:381 OC=CHANNEL(03) INST=(00,00,03) STATE CHG: OP_STATE=Disabled
AVAIL=Dependency(05) ADM=Unlocked 
<0005> abis_nm.c:381 OC=CHANNEL(03) INST=(00,00,03) STATE CHG: OP_STATE=Enabled
AVAIL=OK(ff) ADM=Unlocked 
<0005> abis_nm.c:771 OC=CHANNEL(03) INST=(00,00,03) Opstart ACK
<0005> abis_nm.c:775 OC=CHANNEL(03) INST=(00,00,04) Set Channel Attributes ACK
<0005> abis_nm.c:381 OC=CHANNEL(03) INST=(00,00,04) STATE CHG: OP_STATE=Disabled
AVAIL=Dependency(05) ADM=Unlocked 
<0005> abis_nm.c:381 OC=CHANNEL(03) INST=(00,00,04) STATE CHG: OP_STATE=Enabled
AVAIL=OK(ff) ADM=Unlocked 
<0005> abis_nm.c:771 OC=CHANNEL(03) INST=(00,00,04) Opstart ACK
<0005> abis_nm.c:775 OC=CHANNEL(03) INST=(00,00,05) Set Channel Attributes ACK
<0005> abis_nm.c:381 OC=CHANNEL(03) INST=(00,00,05) STATE CHG: OP_STATE=Disabled
AVAIL=Dependency(05) ADM=Unlocked 
<0005> abis_nm.c:381 OC=CHANNEL(03) INST=(00,00,05) STATE CHG: OP_STATE=Enabled
AVAIL=OK(ff) ADM=Unlocked 
<0005> abis_nm.c:771 OC=CHANNEL(03) INST=(00,00,05) Opstart ACK
<0005> abis_nm.c:775 OC=CHANNEL(03) INST=(00,00,06) Set Channel Attributes ACK
<0005> abis_nm.c:381 OC=CHANNEL(03) INST=(00,00,06) STATE CHG: OP_STATE=Disabled
AVAIL=Dependency(05) ADM=Unlocked 
<0005> abis_nm.c:381 OC=CHANNEL(03) INST=(00,00,06) STATE CHG: OP_STATE=Enabled
AVAIL=OK(ff) ADM=Unlocked 
<0005> abis_nm.c:771 OC=CHANNEL(03) INST=(00,00,06) Opstart ACK
<0005> abis_nm.c:775 OC=CHANNEL(03) INST=(00,00,07) Set Channel Attributes ACK
<0005> abis_nm.c:381 OC=CHANNEL(03) INST=(00,00,07) STATE CHG: OP_STATE=Disabled
AVAIL=Dependency(05) ADM=Unlocked 
<0005> abis_nm.c:381 OC=CHANNEL(03) INST=(00,00,07) STATE CHG: OP_STATE=Enabled
AVAIL=OK(ff) ADM=Unlocked 
<0005> abis_nm.c:771 OC=CHANNEL(03) INST=(00,00,07) Opstart ACK
<0000> chan_alloc.c:612 (bts=0) channel load average is 0.00%
<0000> chan_alloc.c:625 (bts=0) T3122 wait indicator set to 10 seconds
<0000> chan_alloc.c:612 (bts=0) channel load average is 0.00%
<0000> chan_alloc.c:625 (bts=0) T3122 wait indicator set to 10 seconds
<0000> chan_alloc.c:612 (bts=0) channel load average is 0.00%
<0000> chan_alloc.c:625 (bts=0) T3122 wait indicator set to 10 seconds
<0000> chan_alloc.c:612 (bts=0) channel load average is 0.00%
<0000> chan_alloc.c:625 (bts=0) T3122 wait indicator set to 10 seconds
<0000> chan_alloc.c:612 (bts=0) channel load average is 0.00%
<0000> chan_alloc.c:625 (bts=0) T3122 wait indicator set to 10 seconds
<0000> chan_alloc.c:612 (bts=0) channel load average is 0.00%
<0000> chan_alloc.c:625 (bts=0) T3122 wait indicator set to 10 seconds
<0000> chan_alloc.c:612 (bts=0) channel load average is 0.00%
<0000> chan_alloc.c:625 (bts=0) T3122 wait indicator set to 10 seconds
<0000> chan_alloc.c:612 (bts=0) channel load average is 0.00%
<0000> chan_alloc.c:625 (bts=0) T3122 wait indicator set to 10 seconds
<0000> chan_alloc.c:612 (bts=0) channel load average is 0.00%
<0000> chan_alloc.c:625 (bts=0) T3122 wait indicator set to 10 seconds
<0000> chan_alloc.c:612 (bts=0) channel load average is 0.00%
<0000> chan_alloc.c:625 (bts=0) T3122 wait indicator set to 10 seconds
<0000> chan_alloc.c:612 (bts=0) channel load average is 0.00%
<0000> chan_alloc.c:625 (bts=0) T3122 wait indicator set to 10 seconds
<0000> chan_alloc.c:612 (bts=0) channel load average is 0.00%
<0000> chan_alloc.c:625 (bts=0) T3122 wait indicator set to 10 seconds
<0000> chan_alloc.c:612 (bts=0) channel load average is 0.00%
<0000> chan_alloc.c:625 (bts=0) T3122 wait indicator set to 10 seconds
<0000> chan_alloc.c:612 (bts=0) channel load average is 0.00%
<0000> chan_alloc.c:625 (bts=0) T3122 wait indicator set to 10 seconds
<0000> chan_alloc.c:612 (bts=0) channel load average is 0.00%
<0000> chan_alloc.c:625 (bts=0) T3122 wait indicator set to 10 seconds
<0000> chan_alloc.c:612 (bts=0) channel load average is 0.00%
<0000> chan_alloc.c:625 (bts=0) T3122 wait indicator set to 10 seconds
<0004> abis_rsl.c:1848 (bts=0) CHAN RQD: reason: Location updating (ra=0x0b,
neci=0x01, chreq_reason=0x03)
<0000> chan_alloc.c:353 (bts=0,trx=0,ts=0,pchan=CCCH+SDCCH4) Allocating lchan=0 as
SDCCH
<0004> abis_rsl.c:1924 (bts=0,trx=0,ts=0,ss=0) Activating ARFCN(514) SS(0) lctype
SDCCH r=LOCATION_UPDATE ra=0x0b ta=0
<0004> abis_rsl.c:595 (bts=0,trx=0,ts=0,pchan=CCCH+SDCCH4) Tx RSL Channel Activate
with act_type=INITIAL
<0004> abis_rsl.c:1231 (bts=0,trx=0,ts=0,ss=0) state NONE -> ACTIVATION
REQUESTED
<0004> abis_rsl.c:1603 (bts=0,trx=0,ts=0,ss=0) CHANNEL ACTIVATE ACK
<0004> abis_rsl.c:1231 (bts=0,trx=0,ts=0,ss=0) state ACTIVATION REQUESTED ->
ACTIVE
<0000> abis_rsl.c:2127 (bts=0,trx=0,ts=0,ss=0) SAPI=0 ESTABLISH INDICATION
<0000> gsm_04_08.c:3999 Dispatching 04.08 message, pdisc=5
<0002> gsm_04_08.c:1407 LOCATION UPDATING REQUEST: MI(TMSI)=792947918 type=NORMAL 
<0001> gsm_04_08.c:144 (bts 0 trx 0 ts 0 pd 05) Sending 0x18 to MS.
<0001> gsm_04_08.c:144 (bts 0 trx 0 ts 0 pd 05) Sending 0x18 to MS.
<- Can't find any subscriber for this ID
<0000> abis_rsl.c:2127 (bts=0,trx=0,ts=0,ss=0) SAPI=0 DATA INDICATION
<0000> gsm_04_08.c:3999 Dispatching 04.08 message, pdisc=5
<0002> gsm_04_08.c:565 IDENTITY RESPONSE: MI(IMSI)=46001**********
<0000> abis_rsl.c:2127 (bts=0,trx=0,ts=0,ss=0) SAPI=0 DATA INDICATION
<0000> gsm_04_08.c:3999 Dispatching 04.08 message, pdisc=5
<0002> gsm_04_08.c:565 IDENTITY RESPONSE: MI(IMEI)=358634076823520
<0002> gsm_04_08.c:522 -> LOCATION UPDATE ACCEPT
<0001> gsm_04_08.c:144 (bts 0 trx 0 ts 0 pd 05) Sending 0x02 to MS.
<0002> gsm_04_08.c:892 -> MM INFO
<0001> gsm_04_08.c:144 (bts 0 trx 0 ts 0 pd 05) Sending 0x32 to MS.
<0002> gsm_subscriber.c:341 Subscriber 46001********** ATTACHED LAC=1
<0000> abis_rsl.c:2127 (bts=0,trx=0,ts=0,ss=0) SAPI=0 DATA INDICATION
<0000> gsm_04_08.c:3999 Dispatching 04.08 message, pdisc=5
<0002> gsm_04_08.c:1421 TMSI Reallocation Completed. Subscriber: 46001**********
<0002> gsm_04_08.c:323 Location Updating completed for 46001**********
<0000> chan_alloc.c:487 (bts=0,trx=0,ts=0,ss=0) starting release sequence
<0004> abis_rsl.c:1231 (bts=0,trx=0,ts=0,ss=0) state ACTIVE -> RELEASE
REQUESTED
<0003> gsm_04_08_utils.c:251 Sending Channel Release: Chan: Number: 0 Type: 1
<0004> abis_rsl.c:777 (bts=0,trx=0,ts=0,ss=0) DEACTivate SACCH CMD
<0000> abis_rsl.c:2127 (bts=0,trx=0,ts=0,ss=0) SAPI=0 RELEASE INDICATION
<0004> abis_rsl.c:1731 (bts=0,trx=0,ts=0,ss=0) T3111 expired: releasing RF Channel
<0004> abis_rsl.c:869 (bts=0,trx=0,ts=0,ss=0) RF Channel Release
<0004> abis_rsl.c:939 (bts=0,trx=0,ts=0,ss=0) RF CHANNEL RELEASE ACK
<0004> abis_rsl.c:1231 (bts=0,trx=0,ts=0,ss=0) state RELEASE REQUESTED -> NONE
<0000> chan_alloc.c:612 (bts=0) channel load average is 3.03%
<0000> chan_alloc.c:625 (bts=0) T3122 wait indicator set to 10 seconds
<0000> chan_alloc.c:612 (bts=0) channel load average is 0.00%
<0000> chan_alloc.c:625 (bts=0) T3122 wait indicator set to 10 seconds
<0000> chan_alloc.c:612 (bts=0) channel load average is 0.00%
<0000> chan_alloc.c:625 (bts=0) T3122 wait indicator set to 10 seconds
<0004> abis_rsl.c:1848 (bts=0) CHAN RQD: reason: other (ra=0x1e, neci=0x01,
chreq_reason=0x04)
<0000> chan_alloc.c:353 (bts=0,trx=0,ts=0,pchan=CCCH+SDCCH4) Allocating lchan=0 as
SDCCH
<0004> abis_rsl.c:1924 (bts=0,trx=0,ts=0,ss=0) Activating ARFCN(514) SS(0) lctype
SDCCH r=OTHER ra=0x1e ta=0
<0004> abis_rsl.c:595 (bts=0,trx=0,ts=0,pchan=CCCH+SDCCH4) Tx RSL Channel Activate
with act_type=INITIAL
<0004> abis_rsl.c:1231 (bts=0,trx=0,ts=0,ss=0) state NONE -> ACTIVATION
REQUESTED
<0004> abis_rsl.c:1603 (bts=0,trx=0,ts=0,ss=0) CHANNEL ACTIVATE ACK
<0004> abis_rsl.c:1231 (bts=0,trx=0,ts=0,ss=0) state ACTIVATION REQUESTED ->
ACTIVE
<0000> abis_rsl.c:2127 (bts=0,trx=0,ts=0,ss=0) SAPI=0 ESTABLISH INDICATION
<0000> gsm_04_08.c:3999 Dispatching 04.08 message, pdisc=5
<0002> gsm_04_08.c:1016 <- CM SERVICE REQUEST serv_type=0x04
MI(TMSI)=1532175428
<0002> gsm_04_08_utils.c:667 -> CM SERVICE ACK
<0000> chan_alloc.c:612 (bts=0) channel load average is 0.10%
<0000> chan_alloc.c:625 (bts=0) T3122 wait indicator set to 10 seconds
<0000> abis_rsl.c:2127 (bts=0,trx=0,ts=0,ss=0) SAPI=3 ESTABLISH INDICATION
<0000> abis_rsl.c:2127 (bts=0,trx=0,ts=0,ss=0) SAPI=3 DATA INDICATION
<0000> gsm_04_08.c:3999 Dispatching 04.08 message, pdisc=9
<0001> transaction.c:71 subscr=0x1728b00, net=0x15a78c0
<0000> abis_rsl.c:2127 (bts=0,trx=0,ts=0,ss=0) SAPI=3 DATA INDICATION
<0000> gsm_04_08.c:3999 Dispatching 04.08 message, pdisc=9
<0000> chan_alloc.c:487 (bts=0,trx=0,ts=0,ss=0) starting release sequence
<0004> abis_rsl.c:1231 (bts=0,trx=0,ts=0,ss=0) state ACTIVE -> RELEASE
REQUESTED
<0000> abis_rsl.c:1201 (bts=0,trx=0,ts=0,ss=0) RSL RLL RELEASE REQ (link_id=0x03,
reason=1)
<0003> gsm_04_08_utils.c:251 Sending Channel Release: Chan: Number: 0 Type: 1
<0004> abis_rsl.c:777 (bts=0,trx=0,ts=0,ss=0) DEACTivate SACCH CMD
<0000> abis_rsl.c:2127 (bts=0,trx=0,ts=0,ss=0) SAPI=3 RELEASE CONFIRMATION
<0004> abis_rsl.c:2098 (bts=0,trx=0,ts=0,ss=0) waiting for SAPI=0 to be released.
<0002> gsm_subscriber.c:190 Subscriber 46001********** not paged yet.
<0000> abis_rsl.c:2127 (bts=0,trx=0,ts=0,ss=0) SAPI=0 RELEASE INDICATION
<0004> abis_rsl.c:1848 (bts=0) CHAN RQD: reason: other (ra=0x1f, neci=0x01,
chreq_reason=0x04)
<0000> chan_alloc.c:353 (bts=0,trx=0,ts=0,pchan=CCCH+SDCCH4) Allocating lchan=1 as
SDCCH
<0004> abis_rsl.c:1924 (bts=0,trx=0,ts=0,ss=1) Activating ARFCN(514) SS(1) lctype
SDCCH r=OTHER ra=0x1f ta=0
<0004> abis_rsl.c:595 (bts=0,trx=0,ts=0,pchan=CCCH+SDCCH4) Tx RSL Channel Activate
with act_type=INITIAL
<0004> abis_rsl.c:1231 (bts=0,trx=0,ts=0,ss=1) state NONE -> ACTIVATION
REQUESTED
<0004> abis_rsl.c:1603 (bts=0,trx=0,ts=0,ss=1) CHANNEL ACTIVATE ACK
<0004> abis_rsl.c:1231 (bts=0,trx=0,ts=0,ss=1) state ACTIVATION REQUESTED ->
ACTIVE
<0000> abis_rsl.c:2127 (bts=0,trx=0,ts=0,ss=1) SAPI=0 ESTABLISH INDICATION
<0000> gsm_04_08.c:3999 Dispatching 04.08 message, pdisc=6
<0003> gsm_04_08.c:1462 PAGING RESPONSE: MI(TMSI)=1532175428
<0003> gsm_04_08.c:1495 <- Channel was requested by 46001**********
<0001> transaction.c:71 subscr=0x175ae00, net=0x15a78c0
<0000> abis_rsl.c:1159 (bts=0,trx=0,ts=0,ss=1) RSL RLL ESTABLISH REQ
(link_id=0x03)
<0004> abis_rsl.c:1731 (bts=0,trx=0,ts=0,ss=0) T3111 expired: releasing RF Channel
<0004> abis_rsl.c:869 (bts=0,trx=0,ts=0,ss=0) RF Channel Release
<0004> abis_rsl.c:939 (bts=0,trx=0,ts=0,ss=0) RF CHANNEL RELEASE ACK
<0004> abis_rsl.c:1231 (bts=0,trx=0,ts=0,ss=0) state RELEASE REQUESTED -> NONE
<0000> abis_rsl.c:2127 (bts=0,trx=0,ts=0,ss=1) SAPI=3 ESTABLISH CONFIRM
<0000> abis_rsl.c:2127 (bts=0,trx=0,ts=0,ss=1) SAPI=3 DATA INDICATION
<0000> gsm_04_08.c:3999 Dispatching 04.08 message, pdisc=9
<0000> abis_rsl.c:2127 (bts=0,trx=0,ts=0,ss=1) SAPI=3 DATA INDICATION
<0000> gsm_04_08.c:3999 Dispatching 04.08 message, pdisc=9
<0000> chan_alloc.c:487 (bts=0,trx=0,ts=0,ss=1) starting release sequence
<0004> abis_rsl.c:1231 (bts=0,trx=0,ts=0,ss=1) state ACTIVE -> RELEASE
REQUESTED
<0000> abis_rsl.c:1201 (bts=0,trx=0,ts=0,ss=1) RSL RLL RELEASE REQ (link_id=0x03,
reason=1)
<0003> gsm_04_08_utils.c:251 Sending Channel Release: Chan: Number: 1 Type: 1
<0004> abis_rsl.c:777 (bts=0,trx=0,ts=0,ss=1) DEACTivate SACCH CMD
<0000> abis_rsl.c:2127 (bts=0,trx=0,ts=0,ss=1) SAPI=3 RELEASE CONFIRMATION
<0004> abis_rsl.c:2098 (bts=0,trx=0,ts=0,ss=1) waiting for SAPI=0 to be released.
<0000> abis_rsl.c:2127 (bts=0,trx=0,ts=0,ss=1) SAPI=0 DATA INDICATION
<0004> bsc_api.c:686 Got data in non active state(RELEASE REQUESTED), discarding.
<0000> abis_rsl.c:2127 (bts=0,trx=0,ts=0,ss=1) SAPI=0 RELEASE INDICATION
<0004> abis_rsl.c:1731 (bts=0,trx=0,ts=0,ss=1) T3111 expired: releasing RF Channel
<0004> abis_rsl.c:869 (bts=0,trx=0,ts=0,ss=1) RF Channel Release
<0004> abis_rsl.c:939 (bts=0,trx=0,ts=0,ss=1) RF CHANNEL RELEASE ACK
<0004> abis_rsl.c:1231 (bts=0,trx=0,ts=0,ss=1) state RELEASE REQUESTED -> NONE
<0000> chan_alloc.c:612 (bts=0) channel load average is 5.12%
<0000> chan_alloc.c:625 (bts=0) T3122 wait indicator set to 10 seconds
<0004> abis_rsl.c:1848 (bts=0) CHAN RQD: reason: call re-establishment (ra=0x4f,
neci=0x01, chreq_reason=0x02)
<0000> chan_alloc.c:353 (bts=0,trx=0,ts=2,pchan=TCH/F) Allocating lchan=0 as TCH_F
<0004> abis_rsl.c:1924 (bts=0,trx=0,ts=2,ss=0) Activating ARFCN(514) SS(0) lctype
TCH_F r=CALL ra=0x4f ta=0
<0004> abis_rsl.c:595 (bts=0,trx=0,ts=2,pchan=TCH/F) Tx RSL Channel Activate with
act_type=INITIAL
<0004> abis_rsl.c:1231 (bts=0,trx=0,ts=2,ss=0) state NONE -> ACTIVATION
REQUESTED
<0004> abis_rsl.c:1603 (bts=0,trx=0,ts=2,ss=0) CHANNEL ACTIVATE ACK
<0004> abis_rsl.c:1231 (bts=0,trx=0,ts=2,ss=0) state ACTIVATION REQUESTED ->
ACTIVE
<0000> abis_rsl.c:2127 (bts=0,trx=0,ts=2,ss=0) SAPI=0 ESTABLISH INDICATION
<0000> gsm_04_08.c:3999 Dispatching 04.08 message, pdisc=5
<0002> gsm_04_08.c:1016 <- CM SERVICE REQUEST serv_type=0x01
MI(TMSI)=1532175428
<0002> gsm_04_08_utils.c:667 -> CM SERVICE ACK
<0000> abis_rsl.c:2127 (bts=0,trx=0,ts=2,ss=0) SAPI=0 DATA INDICATION
<0000> gsm_04_08.c:3999 Dispatching 04.08 message, pdisc=3
<0001> gsm_04_08.c:3894 (bts 0 trx 0 ts 2 ti 8 sub 25332) Received 'SETUP'
from MS in state 0 (NULL)
<0001> gsm_04_08.c:3899 Unknown transaction ID 8, creating new trans.
<0001> transaction.c:71 subscr=0x172d9a0, net=0x15a78c0
<0001> gsm_04_08.c:1623 new state NULL -> INITIATED
<0001> gsm_04_08.c:2302 Subscriber 46001********** (25332) sends SETUP to 25332
<0001> gsm_04_08.c:1685 (bts 0 trx 0 ts 2 ti 8 sub 25332) Sending
'MNCC_SETUP_IND' to MNCC.
<0001> gsm_04_08.c:3796 (bts 0 trx 0 ts 2 ti 08 sub 25332) Received
'MNCC_CALL_PROC_REQ' from MNCC in state 1 (INITIATED)
<0001> gsm_04_08.c:1623 new state INITIATED -> MO_CALL_PROC
<0001> gsm_04_08.c:138 (bts 0 trx 0 ts 2 ti 80) Sending 'CALL_PROC' to MS.
<0001> gsm_04_08.c:3796 (bts 0 trx 0 ts 2 ti 08 sub 25332) Received
'MNCC_LCHAN_MODIFY' from MNCC in state 3 (MO_CALL_PROC)
<0003> gsm_04_08_utils.c:517 -> CHANNEL MODE MODIFY mode=0x01
<0001> transaction.c:71 subscr=0x172d9a0, net=0x15a78c0
<0001> gsm_04_08.c:3796 (bts 0 trx 0 ts 2 ti ff sub 25332) Received
'MNCC_SETUP_REQ' from MNCC in state 0 (NULL)
<0001> gsm_04_08.c:2226 starting timer T303 with 30 seconds
<0001> gsm_04_08.c:1623 new state NULL -> CALL_PRESENT
<0001> gsm_04_08.c:138 (bts 0 trx 0 ts 2 ti 00) Sending 'SETUP' to MS.
<0000> abis_rsl.c:2127 (bts=0,trx=0,ts=2,ss=0) SAPI=0 DATA INDICATION
<0003> gsm_04_08_utils.c:542 CHANNEL MODE MODIFY ACK
<0004> abis_rsl.c:2344 (bts=0,trx=0,ts=2,ss=0) IPAC_BIND speech_mode=0x10
RTP_PAYLOAD=3
<0003> osmo_msc.c:76 MSC assign complete (do nothing).
<0004> abis_rsl.c:1624 (bts=0,trx=0,ts=2,ss=0) CHANNEL MODE MODIFY ACK
<0004> abis_rsl.c:2525 (bts=0,trx=0,ts=2,ss=0) IPAC_CRCX_ACK LOCAL_IP=127.0.0.1
LOCAL_PORT=16384 CON_ID=0 
<0000> abis_rsl.c:2127 (bts=0,trx=0,ts=2,ss=0) SAPI=0 DATA INDICATION
<0000> gsm_04_08.c:3999 Dispatching 04.08 message, pdisc=3
<0001> gsm_04_08.c:3894 (bts 0 trx 0 ts 2 ti 0 sub 25332) Received
'RELEASE_COMPL' from MS in state 6 (CALL_PRESENT)
<0001> gsm_04_08.c:1665 stopping pending timer T303
<0001> gsm_04_08.c:1685 (bts 0 trx 0 ts 2 ti 0 sub 25332) Sending
'MNCC_REJ_IND' to MNCC.
<0001> gsm_04_08.c:3796 (bts 0 trx 0 ts 2 ti 08 sub 25332) Received
'MNCC_REL_REQ' from MNCC in state 3 (MO_CALL_PROC)
<0001> gsm_04_08.c:2226 starting timer T308 with 10 seconds
<0001> gsm_04_08.c:1623 new state MO_CALL_PROC -> RELEASE_REQ
<0001> gsm_04_08.c:138 (bts 0 trx 0 ts 2 ti 80) Sending 'RELEASE' to MS.
<0001> gsm_04_08.c:1623 new state CALL_PRESENT -> NULL
<0000> abis_rsl.c:2127 (bts=0,trx=0,ts=2,ss=0) SAPI=0 DATA INDICATION
<0000> gsm_04_08.c:3999 Dispatching 04.08 message, pdisc=3
<0001> gsm_04_08.c:3894 (bts 0 trx 0 ts 2 ti 8 sub 25332) Received
'RELEASE_COMPL' from MS in state 19 (RELEASE_REQ)
<0001> gsm_04_08.c:1665 stopping pending timer T308
<0001> gsm_04_08.c:1685 (bts 0 trx 0 ts 2 ti 8 sub 25332) Sending
'MNCC_REL_CNF' to MNCC.
<0001> gsm_04_08.c:1623 new state RELEASE_REQ -> NULL
<0000> chan_alloc.c:487 (bts=0,trx=0,ts=2,ss=0) starting release sequence
<0004> abis_rsl.c:1231 (bts=0,trx=0,ts=2,ss=0) state ACTIVE -> RELEASE
REQUESTED
<0003> gsm_04_08_utils.c:251 Sending Channel Release: Chan: Number: 0 Type: 2
<0004> abis_rsl.c:777 (bts=0,trx=0,ts=2,ss=0) DEACTivate SACCH CMD
<0000> abis_rsl.c:2127 (bts=0,trx=0,ts=2,ss=0) SAPI=0 RELEASE INDICATION
<0004> abis_rsl.c:1731 (bts=0,trx=0,ts=2,ss=0) T3111 expired: releasing RF Channel
<0004> abis_rsl.c:869 (bts=0,trx=0,ts=2,ss=0) RF Channel Release
<0004> abis_rsl.c:2544 (bts=0,trx=0,ts=2,ss=0) IPAC_DLCX_IND 
<0004> abis_rsl.c:939 (bts=0,trx=0,ts=2,ss=0) RF CHANNEL RELEASE ACK
<0004> abis_rsl.c:1231 (bts=0,trx=0,ts=2,ss=0) state RELEASE REQUESTED -> NONE
<0000> chan_alloc.c:612 (bts=0) channel load average is 1.24%
<0000> chan_alloc.c:625 (bts=0) T3122 wait indicator set to 10 seconds

============================running-config============================
OpenBSC# sh run

Current configuration:
!
password foo
!
log stderr
 logging filter all 1
 logging color 1
 logging print category 0
 logging timestamp 0
 logging print file 1
% Invalid log level 0 for rll
% Invalid log level 0 for cc
% Invalid log level 0 for mm
% Invalid log level 0 for rr
% Invalid log level 0 for rsl
% Invalid log level 0 for nm
 logging level mncc notice
 logging level pag notice
 logging level meas notice
 logging level sccp notice
 logging level msc notice
 logging level mgcp notice
 logging level ho notice
 logging level db notice
 logging level ref notice
 logging level gprs debug
 logging level ns info
 logging level bssgp debug
 logging level llc debug
 logging level sndcp debug
 logging level nat notice
 logging level ctrl notice
 logging level smpp debug
 logging level filter debug
 logging level ranap debug
 logging level sua debug
 logging level pcu debug
 logging level lglobal notice
 logging level llapd notice
 logging level linp notice
 logging level lmux notice
 logging level lmi notice
 logging level lmib notice
 logging level lsms notice
 logging level lctrl notice
 logging level lgtp notice
 logging level lstats notice
 logging level lgsup notice
 logging level loap notice
 logging level lss7 notice
 logging level lsccp notice
 logging level lsua notice
 logging level lm3ua notice
 logging level lmgcp notice
 logging level ljibuf notice
!
stats interval 5
!
line vty
 no login
!
e1_input
 e1_line 0 driver ipa
 e1_line 0 port 0
 no e1_line 0 keepalive
network
 network country code 001
 mobile network code 01
 short name OpenBSC
 long name OpenBSC
 auth policy token
 authorized-regexp .*
 location updating reject cause 13
 encryption a5 0
 neci 1
 paging any use tch 0
 rrlp mode none
 mm info 1
 handover 0
 handover window rxlev averaging 10
 handover window rxqual averaging 1
 handover window rxlev neighbor averaging 10
 handover power budget interval 6
 handover power budget hysteresis 3
 handover maximum distance 9999
 dyn_ts_allow_tch_f 0
 subscriber-keep-in-ram 0
 bts 0
  type sysmobts
  band DCS1800
  cell_identity 0
  location_area_code 1
  base_station_id_code 63
  ms max power 15
  cell reselection hysteresis 4
  rxlev access min 0
  periodic location update 30
  radio-link-timeout 32
  channel allocator ascending
  rach tx integer 9
  rach max transmission 7
  channel-descrption attach 1
  channel-descrption bs-pa-mfrms 5
  channel-descrption bs-ag-blks-res 1
  no access-control-class-ramping
  access-control-class-ramping-step-interval dynamic
  access-control-class-ramping-step-size 1
  early-classmark-sending forbidden
  early-classmark-sending-3g allowed
  ip.access unit_id 1801 0
  oml ip.access stream_id 255 line 0
  neighbor-list mode automatic
  codec-support fr
  gprs mode none
  no force-combined-si
  trx 0
   rf_locked 0
   arfcn 514
   nominal power 23
   max_power_red 20
   rsl e1 tei 0
   timeslot 0
    phys_chan_config CCCH+SDCCH4
    hopping enabled 0
   timeslot 1
    phys_chan_config SDCCH8
    hopping enabled 0
   timeslot 2
    phys_chan_config TCH/F
    hopping enabled 0
   timeslot 3
    phys_chan_config TCH/F
    hopping enabled 0
   timeslot 4
    phys_chan_config TCH/F
    hopping enabled 0
   timeslot 5
    phys_chan_config TCH/F
    hopping enabled 0
   timeslot 6
    phys_chan_config TCH/F
    hopping enabled 0
   timeslot 7
    phys_chan_config TCH/F
    hopping enabled 0
mncc-int
 default-codec tch-f fr
 default-codec tch-h hr
nitb
 subscriber-create-on-demand
 assign-tmsi
end

============================test subscriber info============================
OpenBSC# sh sub id 4
    ID: 4, Authorized: 1
    Extension: 25332
    LAC: 1/0x1
    IMSI: 46001**********
    TMSI: 5B532444
    A3A8 algorithm id: 4
    A3A8 Ki: 00 01 02 03 04 05 06 07 08 09 0a 0b 0c 0d 0e 0f 
    Expiration Time: Sun, 23 Dec 2018 13:53:49 +0800
    Paging: not paging Requests: 0
    Use count: 1
OpenBSC# 

===========================
THANKS A LOT!