Hey, I am trying to set up test bench for base-band fuzzing using the Osmocom stack and a couple of SDRs (b210 and bladerf).
I have managed to setup everything to my liking in terms of a functional network using the tutorial (https://osmocom.org/projects/cellular-infrastructure/wiki/Osmocom_Network_In_The_Box) and the latest stable packages from https://osmocom.org/projects/cellular-infrastructure/wiki/Latest_Builds Now I want to enable the silent_call functionality to begin testing but I can't seem able to do so. I have reverted the silent_call patch (https://gerrit.osmocom.org/#/c/openbsc/+/1930/) for OpenBSC inside the "new" OsmoMSC but unfortunately that did not work. I have then started trying to figure out how the silent_call interacts with the rest of the state machine, but I don't seem to be making much progress. Please see attached a log for the communication between OsmoMSC (which triggers silent_call) and OsmoBSC. The connection seems to fail due to issues related to either "Congestion" (if GPRS is enabled) or a timeout of T0 (if GPRS is disabled). Can anyone help? Thanks -- Mihai
Oct 12 16:29:58 cca-132016 osmo-bsc[16394]: DHODEC <0009>
handover_decision_2.c:1632 (BTS 0) No congestion check: no minimum for free
TCH/F nor TCH/H set
Oct 12 16:29:58 cca-132016 osmo-bsc[16394]: DHODEC <0009>
handover_decision_2.c:138 HO algorithm 2: next periodical congestion check in
10 seconds
Oct 12 16:29:58 cca-132016 osmo-bsc[16394]: BTS 0 reported connected PCU
version 0.5.1
Oct 12 16:29:58 cca-132016 osmo-msc[16399]: DLGSUP <001b> gsup_client.c:242
GSUP ping callback (connected, got PONG)
Oct 12 16:29:58 cca-132016 osmo-msc[16399]: DLGSUP <001b> gsup_client.c:262
GSUP sending PING
Oct 12 16:29:58 cca-132016 osmo-msc[16399]: DLINP <0013> input/ipa.c:142
127.0.0.1:4222 connected write
Oct 12 16:29:58 cca-132016 osmo-msc[16399]: DLINP <0013> input/ipa.c:92
127.0.0.1:4222 sending data
Oct 12 16:29:58 cca-132016 osmo-msc[16399]: DLINP <0013> input/ipa.c:142
127.0.0.1:4222 connected write
Oct 12 16:29:58 cca-132016 osmo-msc[16399]: DLINP <0013> input/ipa.c:92
127.0.0.1:4222 sending data
Oct 12 16:29:58 cca-132016 osmo-msc[16399]: DLINP <0013> input/ipa.c:138
127.0.0.1:4222 connected read
Oct 12 16:29:58 cca-132016 osmo-msc[16399]: DLINP <0013> input/ipa.c:56
127.0.0.1:4222 message received
Oct 12 16:29:58 cca-132016 osmo-msc[16399]: DLGSUP <001b> gsup_client.c:199
GSUP receiving PONG
Oct 12 16:30:02 cca-132016 osmo-msc[16399]: DMM <0002> gsm_subscriber.c:158
Subscriber MSISDN:4090 not paged yet, start paging.
Oct 12 16:30:02 cca-132016 osmo-msc[16399]: DBSSAP <0010> a_iface.c:216 Tx
BSSMAP paging message from MSC RI=SSN_PC,PC=0.23.1,SSN=BSSAP to BSC
RI=SSN_PC,PC=0.23.1,SSN=BSSAP (imsi=001010000024090, tmsi=0x23d988ea, lac=23)
Oct 12 16:30:02 cca-132016 osmo-msc[16399]: DLSCCP <001e> sccp_scoc.c:1615
Received SCCP User Primitive N-UNITDATA.request)
Oct 12 16:30:02 cca-132016 osmo-msc[16399]: DLSS7 <001d> sccp_scrc.c:420
sccp_scrc_rx_sclc_msg: HDR=(CL:CLDT,V=0,LEN=0),
Oct 12 16:30:02 cca-132016 osmo-msc[16399]: PART(T=Routing
Context,L=4,D=00000000),
Oct 12 16:30:02 cca-132016 osmo-msc[16399]: PART(T=Protocol
Class,L=4,D=00000000),
Oct 12 16:30:02 cca-132016 osmo-msc[16399]: PART(T=Source
Address,L=20,D=0002000380020008000000b980030008000000fe),
Oct 12 16:30:02 cca-132016 osmo-msc[16399]: PART(T=Destination
Address,L=20,D=0002000380020008000000bb80030008000000fe),
Oct 12 16:30:02 cca-132016 osmo-msc[16399]: PART(T=Sequence
Control,L=4,D=00000000),
Oct 12 16:30:02 cca-132016 osmo-msc[16399]:
PART(T=Data,L=24,D=00165208080910100000200409090423d988ea1a03050017)
Oct 12 16:30:02 cca-132016 osmo-msc[16399]: DLSUA <001f> sua.c:385
sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 bb 80 03
00 08 00 00 00 fe
Oct 12 16:30:02 cca-132016 osmo-msc[16399]: DLSUA <001f> sua.c:439 SUA IEI
0x0103 pos 4/20: subpart tag 0x8002, len 8
Oct 12 16:30:02 cca-132016 osmo-msc[16399]: DLSUA <001f> sua.c:439 SUA IEI
0x0103 pos 12/20: subpart tag 0x8003, len 8
Oct 12 16:30:02 cca-132016 osmo-msc[16399]: DLSUA <001f> sua.c:385
sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 bb 80 03
00 08 00 00 00 fe
Oct 12 16:30:02 cca-132016 osmo-msc[16399]: DLSUA <001f> sua.c:439 SUA IEI
0x0103 pos 4/20: subpart tag 0x8002, len 8
Oct 12 16:30:02 cca-132016 osmo-msc[16399]: DLSUA <001f> sua.c:439 SUA IEI
0x0103 pos 12/20: subpart tag 0x8003, len 8
Oct 12 16:30:02 cca-132016 osmo-msc[16399]: DLSUA <001f> sua.c:385
sua_addr_parse_part(IEI=0x0102) (20) 00 02 00 03 80 02 00 08 00 00 00 b9 80 03
00 08 00 00 00 fe
Oct 12 16:30:02 cca-132016 osmo-msc[16399]: DLSUA <001f> sua.c:439 SUA IEI
0x0102 pos 4/20: subpart tag 0x8002, len 8
Oct 12 16:30:02 cca-132016 osmo-msc[16399]: DLSUA <001f> sua.c:439 SUA IEI
0x0102 pos 12/20: subpart tag 0x8003, len 8
Oct 12 16:30:02 cca-132016 osmo-msc[16399]: DLSS7 <001d> osmo_ss7_hmrt.c:278
m3ua_hmdc_rx_from_l2(): dpc=187=0.23.3 not local, message is for routing
Oct 12 16:30:02 cca-132016 osmo-msc[16399]: DLSS7 <001d> osmo_ss7_hmrt.c:227
Found route for dpc=187=0.23.3: pc=0=0.0.0 mask=0x0=0.0.0 via AS
as-clnt-OsmoMSC-A proto=m3ua
Oct 12 16:30:02 cca-132016 osmo-msc[16399]: DLSS7 <001d> osmo_ss7_hmrt.c:233
rt->dest.as proto is M3UA for dpc=187=0.23.3
Oct 12 16:30:02 cca-132016 osmo-msc[16399]: DLSS7 <001d> m3ua.c:507
XUA_AS(as-clnt-OsmoMSC-A)[0x55f605317960]{AS_ACTIVE}: Received Event
AS-TRANSFER.req
Oct 12 16:30:02 cca-132016 osmo-msc[16399]: DLINP <0013> stream.c:279 connected
write
Oct 12 16:30:02 cca-132016 osmo-msc[16399]: DLINP <0013> stream.c:204 sending
data
Oct 12 16:30:02 cca-132016 osmo-msc[16399]: DLINP <0013> stream.c:279 connected
write
Oct 12 16:30:02 cca-132016 osmo-msc[16399]: DLINP <0013> stream.c:204 sending
data
Oct 12 16:30:02 cca-132016 osmo-bsc[16394]: DLINP <0012> stream.c:275 connected
read
Oct 12 16:30:02 cca-132016 osmo-bsc[16394]: DLINP <0012> stream.c:189 message
received
Oct 12 16:30:02 cca-132016 osmo-bsc[16394]: DLSS7 <001c> osmo_ss7.c:1549
asp-asp-clnt-msc-0: xua_cli_read_cb(): sctp_recvmsg() returned 72 (flags=0x80)
Oct 12 16:30:02 cca-132016 osmo-bsc[16394]: DLM3UA <001f> m3ua.c:722
asp-asp-clnt-msc-0: Received M3UA Message (XFER:DATA)
Oct 12 16:30:02 cca-132016 osmo-bsc[16394]: DLM3UA <001f> m3ua.c:541
asp-asp-clnt-msc-0: m3ua_rx_xfer
Oct 12 16:30:02 cca-132016 osmo-bsc[16394]: DLM3UA <001f> m3ua.c:580
asp-asp-clnt-msc-0: m3ua_rx_xfer(): M3UA data header: opc=185=0.23.1
dpc=187=0.23.3
Oct 12 16:30:02 cca-132016 osmo-bsc[16394]: DLSS7 <001c> osmo_ss7_hmrt.c:274
m3ua_hmdc_rx_from_l2(): found dpc=187=0.23.3 as local
Oct 12 16:30:02 cca-132016 osmo-bsc[16394]: DLSUA <001e> sccp2sua.c:333 IEI
259: Parsed Addr: RI=2,PC=187,SSN=254
Oct 12 16:30:02 cca-132016 osmo-bsc[16394]: DLSUA <001e> sccp2sua.c:333 IEI
258: Parsed Addr: RI=2,PC=185,SSN=254
Oct 12 16:30:02 cca-132016 osmo-bsc[16394]: DLSS7 <001c> sccp_scrc.c:449
scrc_rx_mtp_xfer_ind_xua: HDR=(CL:CLDT,V=0,LEN=0),
Oct 12 16:30:02 cca-132016 osmo-bsc[16394]: PART(T=Protocol
Class,L=4,D=00000000),
Oct 12 16:30:02 cca-132016 osmo-bsc[16394]: PART(T=Destination
Address,L=20,D=0002000380020008000000bb80030008000000fe),
Oct 12 16:30:02 cca-132016 osmo-bsc[16394]: PART(T=Source
Address,L=20,D=0002000380020008000000b980030008000000fe),
Oct 12 16:30:02 cca-132016 osmo-bsc[16394]:
PART(T=Data,L=24,D=00165208080910100000200409090423d988ea1a03050017)
Oct 12 16:30:02 cca-132016 osmo-bsc[16394]: DLSUA <001e> sua.c:385
sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 bb 80 03
00 08 00 00 00 fe
Oct 12 16:30:02 cca-132016 osmo-bsc[16394]: DLSUA <001e> sua.c:439 SUA IEI
0x0103 pos 4/20: subpart tag 0x8002, len 8
Oct 12 16:30:02 cca-132016 osmo-bsc[16394]: DLSUA <001e> sua.c:439 SUA IEI
0x0103 pos 12/20: subpart tag 0x8003, len 8
Oct 12 16:30:02 cca-132016 osmo-bsc[16394]: DLSUA <001e> sua.c:385
sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 bb 80 03
00 08 00 00 00 fe
Oct 12 16:30:02 cca-132016 osmo-bsc[16394]: DLSUA <001e> sua.c:439 SUA IEI
0x0103 pos 4/20: subpart tag 0x8002, len 8
Oct 12 16:30:02 cca-132016 osmo-bsc[16394]: DLSUA <001e> sua.c:439 SUA IEI
0x0103 pos 12/20: subpart tag 0x8003, len 8
Oct 12 16:30:02 cca-132016 osmo-bsc[16394]: DLSUA <001e> sua.c:385
sua_addr_parse_part(IEI=0x0102) (20) 00 02 00 03 80 02 00 08 00 00 00 b9 80 03
00 08 00 00 00 fe
Oct 12 16:30:02 cca-132016 osmo-bsc[16394]: DLSUA <001e> sua.c:439 SUA IEI
0x0102 pos 4/20: subpart tag 0x8002, len 8
Oct 12 16:30:02 cca-132016 osmo-bsc[16394]: DLSUA <001e> sua.c:439 SUA IEI
0x0102 pos 12/20: subpart tag 0x8003, len 8
Oct 12 16:30:02 cca-132016 osmo-bsc[16394]: DLSCCP <001d> sccp_user.c:156
Delivering N-UNITDATA.indication to SCCP User 'msc-0'
Oct 12 16:30:02 cca-132016 osmo-bsc[16394]: DMSC <0007> osmo_bsc_sigtran.c:167
N-UNITDATA.ind(00 16 52 08 08 09 10 10 00 00 20 04 09 09 04 23 d9 88 ea 1a 03
05 00 17 )
Oct 12 16:30:02 cca-132016 osmo-bsc[16394]: DMSC <0007> osmo_bsc_bssap.c:864 Rx
MSC UDT: 00 16 52 08 08 09 10 10 00 00 20 04 09 09 04 23 d9 88 ea 1a 03 05 00 17
Oct 12 16:30:02 cca-132016 osmo-bsc[16394]: DMSC <0007> osmo_bsc_bssap.c:750 Rx
MSC UDT BSSMAP PAGING
Oct 12 16:30:02 cca-132016 osmo-bsc[16394]: DMSC <0007> osmo_bsc_bssap.c:92
Paging request from MSC BTS: 0 IMSI: '001010000024090' TMSI:
'0x23d988ea/601458922' LAC: 0x17
Oct 12 16:30:02 cca-132016 osmo-bsc[16394]: DPAG <0005> paging.c:310 (bts=0)
Start paging of subscriber IMSI:001010000024090
Oct 12 16:30:02 cca-132016 osmo-bsc[16394]: DRLL <0000> chan_alloc.c:721
(bts=0) channel load average is 0.00%
Oct 12 16:30:02 cca-132016 osmo-bsc[16394]: DRLL <0000> chan_alloc.c:734
(bts=0) T3122 wait indicator set to 10 seconds
Oct 12 16:30:02 cca-132016 osmo-bsc[16394]: DPAG <0005> paging.c:88 (bts=0)
Going to send paging commands: imsi: 001010000024090 tmsi: 0x23d988ea for ch.
type 0 (attempt 0)
Oct 12 16:30:03 cca-132016 osmo-bsc[16394]: DPAG <0005> paging.c:88 (bts=0)
Going to send paging commands: imsi: 001010000024090 tmsi: 0x23d988ea for ch.
type 0 (attempt 1)
Oct 12 16:30:03 cca-132016 osmo-bsc[16394]: BTS 0 reported connected PCU
version 0.5.1
Oct 12 16:30:03 cca-132016 osmo-bsc[16394]: DPAG <0005> paging.c:88 (bts=0)
Going to send paging commands: imsi: 001010000024090 tmsi: 0x23d988ea for ch.
type 0 (attempt 2)
Oct 12 16:30:03 cca-132016 osmo-bsc[16394]: DRSL <0003> abis_rsl.c:1877 (bts=0)
CHAN RQD: reason: answer to paging (ra=0x91, neci=0x00, chreq_reason=0x01)
Oct 12 16:30:03 cca-132016 osmo-bsc[16394]: DRLL <0000> chan_alloc.c:329
(bts=0) lchan_alloc(SDCCH)
Oct 12 16:30:03 cca-132016 osmo-bsc[16394]: DRLL <0000> chan_alloc.c:271
looking for lchan CCCH+SDCCH4 as CCCH+SDCCH4:
(bts=0,trx=0,ts=0,pchan=CCCH+SDCCH4) ss=0 is available
Oct 12 16:30:03 cca-132016 osmo-bsc[16394]: DRLL <0000> chan_alloc.c:462
(bts=0,trx=0,ts=0,pchan=CCCH+SDCCH4) Allocating lchan=0 as SDCCH
Oct 12 16:30:03 cca-132016 osmo-bsc[16394]: DRSL <0003> abis_rsl.c:1965
(bts=0,trx=0,ts=0,ss=0) Activating ARFCN(128) SS(0) lctype SDCCH r=PAGING
ra=0x91 ta=0
Oct 12 16:30:03 cca-132016 osmo-bsc[16394]: DRSL <0003> abis_rsl.c:582
(bts=0,trx=0,ts=0,pchan=CCCH+SDCCH4) Tx RSL Channel Activate with
act_type=INITIAL
Oct 12 16:30:03 cca-132016 osmo-bsc[16394]: DRSL <0003> abis_rsl.c:611
(bts=0,trx=0,ts=0,ss=0) state NONE -> ACTIVATION REQUESTED
Oct 12 16:30:03 cca-132016 osmo-bsc[16394]: DRSL <0003> abis_rsl.c:1630
(bts=0,trx=0,ts=0,ss=0) CHANNEL ACTIVATE ACK
Oct 12 16:30:03 cca-132016 osmo-bsc[16394]: DRSL <0003> abis_rsl.c:1277
(bts=0,trx=0,ts=0,ss=0) state ACTIVATION REQUESTED -> ACTIVE
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DPAG <0005> paging.c:88 (bts=0)
Going to send paging commands: imsi: 001010000024090 tmsi: 0x23d988ea for ch.
type 0 (attempt 3)
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DRLL <0000> abis_rsl.c:2180
(bts=0,trx=0,ts=0,ss=0) SAPI=0 ESTABLISH INDICATION
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DMSC <0007> fsm.c:299
SUBSCR_CONN[0x556b9f76ec90]{INIT}: Allocated
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DLCLS <000f> fsm.c:299
LCLS[0x556b9f76fbe0]{NO_LCLS}: Allocated
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DLCLS <000f> fsm.c:329
LCLS[0x556b9f76fbe0]{NO_LCLS}: is child of SUBSCR_CONN[0x556b9f76ec90]
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DMSC <0007> osmo_bsc_api.c:219 Tx
MSC COMPL L3
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DRR <0002> osmo_bsc_filter.c:77
PAGING RESPONSE: MI(TMSI)=601458922
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DMSC <0007> osmo_bsc_sigtran.c:250
Initializing resources for new SIGTRAN connection to MSC:
RI=SSN_PC,PC=0.23.1,SSN=BSSAP...
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DRR <0002> osmo_bsc_filter.c:77
PAGING RESPONSE: MI(TMSI)=601458922
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DPAG <0005> paging.c:374 (bts=0)
Stop paging IMSI:001010000024090
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DMSC <0007> osmo_bsc_api.c:290
SUBSCR_CONN[0x556b9f76ec90]{INIT}: Received Event MO-CONNECT.req
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DMSC <0007> osmo_bsc_sigtran.c:292
Allocated new connection id: 3
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DMSC <0007> osmo_bsc_sigtran.c:296
Opening new SIGTRAN connection (id=3) to MSC: RI=SSN_PC,PC=0.23.1,SSN=BSSAP
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DLSCCP <001d> sccp_scoc.c:1615
Received SCCP User Primitive N-CONNECT.request)
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DLSCCP <001d> fsm.c:299
SCCP-SCOC(3)[0x556b9f76eb60]{IDLE}: Allocated
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DLSCCP <001d> sccp_scoc.c:1657
SCCP-SCOC(3)[0x556b9f76eb60]{IDLE}: Received Event N-CONNECT.req
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DLSS7 <001c> sccp_scrc.c:398
sccp_scrc_rx_scoc_conn_msg: HDR=(CO:CORE,V=0,LEN=0),
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: PART(T=Routing
Context,L=4,D=00000000),
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: PART(T=Protocol
Class,L=4,D=00000002),
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: PART(T=Source
Reference,L=4,D=00000003),
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: PART(T=Destination
Address,L=20,D=0002000380020008000000b980030008000000fe),
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: PART(T=Sequence
Control,L=4,D=00000000),
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: PART(T=Source
Address,L=20,D=0002000380020008000000bb80030008000000fe),
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]:
PART(T=Data,L=28,D=001a5705080032f82000170000170d0627000353189205f423d988ea)
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DLSUA <001e> sua.c:385
sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 b9 80 03
00 08 00 00 00 fe
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DLSUA <001e> sua.c:439 SUA IEI
0x0103 pos 4/20: subpart tag 0x8002, len 8
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DLSUA <001e> sua.c:439 SUA IEI
0x0103 pos 12/20: subpart tag 0x8003, len 8
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DLSUA <001e> sua.c:385
sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 b9 80 03
00 08 00 00 00 fe
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DLSUA <001e> sua.c:439 SUA IEI
0x0103 pos 4/20: subpart tag 0x8002, len 8
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DLSUA <001e> sua.c:439 SUA IEI
0x0103 pos 12/20: subpart tag 0x8003, len 8
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DLSUA <001e> sua.c:385
sua_addr_parse_part(IEI=0x0102) (20) 00 02 00 03 80 02 00 08 00 00 00 bb 80 03
00 08 00 00 00 fe
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DLSUA <001e> sua.c:439 SUA IEI
0x0102 pos 4/20: subpart tag 0x8002, len 8
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DLSUA <001e> sua.c:439 SUA IEI
0x0102 pos 12/20: subpart tag 0x8003, len 8
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DLSS7 <001c> osmo_ss7_hmrt.c:278
m3ua_hmdc_rx_from_l2(): dpc=185=0.23.1 not local, message is for routing
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DLSS7 <001c> osmo_ss7_hmrt.c:227
Found route for dpc=185=0.23.1: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-msc-0
proto=m3ua
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DLSS7 <001c> osmo_ss7_hmrt.c:233
rt->dest.as proto is M3UA for dpc=185=0.23.1
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DLSS7 <001c> m3ua.c:507
XUA_AS(as-clnt-msc-0)[0x556b9f75fce0]{AS_ACTIVE}: Received Event AS-TRANSFER.req
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DLSCCP <001d> sccp_scoc.c:732
SCCP-SCOC(3)[0x556b9f76eb60]{IDLE}: state_chg to CONN_PEND_OUT
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DMSC <0007>
bsc_subscr_conn_fsm.c:335 SUBSCR_CONN[0x556b9f76ec90]{INIT}: state_chg to
WAIT_CC
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DLINP <0012> stream.c:279 connected
write
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DLINP <0012> stream.c:204 sending
data
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DLINP <0012> stream.c:279 connected
write
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DLINP <0012> stream.c:204 sending
data
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DLINP <0013> stream.c:275 connected
read
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DLINP <0013> stream.c:189 message
received
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DLSS7 <001d> osmo_ss7.c:1549
asp-asp-clnt-OsmoMSC-A: xua_cli_read_cb(): sctp_recvmsg() returned 84
(flags=0x80)
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DLM3UA <0020> m3ua.c:722
asp-asp-clnt-OsmoMSC-A: Received M3UA Message (XFER:DATA)
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DLM3UA <0020> m3ua.c:541
asp-asp-clnt-OsmoMSC-A: m3ua_rx_xfer
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DLM3UA <0020> m3ua.c:580
asp-asp-clnt-OsmoMSC-A: m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3
dpc=185=0.23.1
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DLSS7 <001d> osmo_ss7_hmrt.c:274
m3ua_hmdc_rx_from_l2(): found dpc=185=0.23.1 as local
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DLSUA <001f> sccp2sua.c:333 IEI
259: Parsed Addr: RI=2,PC=185,SSN=254
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DLSUA <001f> sccp2sua.c:333 IEI
258: Parsed Addr: RI=2,PC=187,SSN=254
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DLSS7 <001d> sccp_scrc.c:449
scrc_rx_mtp_xfer_ind_xua: HDR=(CO:CORE,V=0,LEN=0),
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: PART(T=Protocol
Class,L=4,D=00000002),
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: PART(T=Source
Reference,L=4,D=00000003),
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: PART(T=Destination
Address,L=20,D=0002000380020008000000b980030008000000fe),
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: PART(T=Source
Address,L=20,D=0002000380020008000000bb80030008000000fe),
Oct 12 16:30:04 cca-132016 osmo-msc[16399]:
PART(T=Data,L=28,D=001a5705080032f82000170000170d0627000353189205f423d988ea)
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DLSUA <001f> sua.c:385
sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 b9 80 03
00 08 00 00 00 fe
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DLSUA <001f> sua.c:439 SUA IEI
0x0103 pos 4/20: subpart tag 0x8002, len 8
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DLSUA <001f> sua.c:439 SUA IEI
0x0103 pos 12/20: subpart tag 0x8003, len 8
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DLSUA <001f> sua.c:385
sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 b9 80 03
00 08 00 00 00 fe
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DLSUA <001f> sua.c:439 SUA IEI
0x0103 pos 4/20: subpart tag 0x8002, len 8
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DLSUA <001f> sua.c:439 SUA IEI
0x0103 pos 12/20: subpart tag 0x8003, len 8
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DLSCCP <001e> fsm.c:299
SCCP-SCOC(2)[0x55f60531c0f0]{IDLE}: Allocated
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DLSCCP <001e> sccp_scoc.c:1548
Received CO:CORE for local reference 2
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DLSCCP <001e> sccp_scoc.c:1581
SCCP-SCOC(2)[0x55f60531c0f0]{IDLE}: Received Event RCOC-CONNECT.ind
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DLSUA <001f> sua.c:385
sua_addr_parse_part(IEI=0x0102) (20) 00 02 00 03 80 02 00 08 00 00 00 bb 80 03
00 08 00 00 00 fe
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DLSUA <001f> sua.c:439 SUA IEI
0x0102 pos 4/20: subpart tag 0x8002, len 8
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DLSUA <001f> sua.c:439 SUA IEI
0x0102 pos 12/20: subpart tag 0x8003, len 8
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DLSUA <001f> sua.c:385
sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 b9 80 03
00 08 00 00 00 fe
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DLSUA <001f> sua.c:439 SUA IEI
0x0103 pos 4/20: subpart tag 0x8002, len 8
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DLSUA <001f> sua.c:439 SUA IEI
0x0103 pos 12/20: subpart tag 0x8003, len 8
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DLSCCP <001e> sccp_scoc.c:775
SCCP-SCOC(2)[0x55f60531c0f0]{IDLE}: state_chg to CONN_PEND_IN
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DLSCCP <001e> sccp_user.c:156
Delivering N-CONNECT.indication to SCCP User 'OsmoMSC-A'
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DLSCCP <001e> sccp_scoc.c:1615
Received SCCP User Primitive N-CONNECT.response)
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DLSCCP <001e> sccp_scoc.c:1657
SCCP-SCOC(2)[0x55f60531c0f0]{CONN_PEND_IN}: Received Event N-CONNECT.resp
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DLSS7 <001d> sccp_scrc.c:398
sccp_scrc_rx_scoc_conn_msg: HDR=(CO:COAK,V=0,LEN=0),
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: PART(T=Routing
Context,L=4,D=00000000),
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: PART(T=Protocol
Class,L=4,D=00000002),
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: PART(T=Destination
Reference,L=4,D=00000003),
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: PART(T=Source
Reference,L=4,D=00000002),
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: PART(T=Sequence
Control,L=4,D=00000000),
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: PART(T=Source
Address,L=20,D=0002000380020008000000b980030008000000fe),
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: PART(T=Destination
Address,L=20,D=0002000380020008000000bb80030008000000fe)
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DLSUA <001f> sua.c:385
sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 bb 80 03
00 08 00 00 00 fe
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DLSUA <001f> sua.c:439 SUA IEI
0x0103 pos 4/20: subpart tag 0x8002, len 8
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DLSUA <001f> sua.c:439 SUA IEI
0x0103 pos 12/20: subpart tag 0x8003, len 8
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DLSUA <001f> sua.c:385
sua_addr_parse_part(IEI=0x0102) (20) 00 02 00 03 80 02 00 08 00 00 00 b9 80 03
00 08 00 00 00 fe
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DLSUA <001f> sua.c:439 SUA IEI
0x0102 pos 4/20: subpart tag 0x8002, len 8
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DLSUA <001f> sua.c:439 SUA IEI
0x0102 pos 12/20: subpart tag 0x8003, len 8
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DLSS7 <001d> osmo_ss7_hmrt.c:278
m3ua_hmdc_rx_from_l2(): dpc=187=0.23.3 not local, message is for routing
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DLSS7 <001d> osmo_ss7_hmrt.c:227
Found route for dpc=187=0.23.3: pc=0=0.0.0 mask=0x0=0.0.0 via AS
as-clnt-OsmoMSC-A proto=m3ua
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DLSS7 <001d> osmo_ss7_hmrt.c:233
rt->dest.as proto is M3UA for dpc=187=0.23.3
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DLSS7 <001d> m3ua.c:507
XUA_AS(as-clnt-OsmoMSC-A)[0x55f605317960]{AS_ACTIVE}: Received Event
AS-TRANSFER.req
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DLSCCP <001e> sccp_scoc.c:802
SCCP-SCOC(2)[0x55f60531c0f0]{CONN_PEND_IN}: state_chg to ACTIVE
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DBSSAP <0010> a_iface.c:535
N-CONNECT.ind(2, 00 1a 57 05 08 00 32 f8 20 00 17 00 00 17 0d 06 27 00 03 53 18
92 05 f4 23 d9 88 ea )
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DBSSAP <0010> a_iface_bssap.c:268
Rx BSSMAP COMPLETE L3 INFO (conn_id=2)
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DMSC <0006> a_iface_bssap.c:55
Allocating A-Interface subscriber conn: lac 23, conn_id 2
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DMM <0002> fsm.c:299
Subscr_Conn[0x55f60531c2c0]{SUBSCR_CONN_S_NEW}: Allocated
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DBSSAP <0010> a_iface_bssap.c:68
(subscr unknown, conn_id 2) A-Interface subscriber connection successfully
allocated!
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DRLL <0000> gsm_04_08.c:3481
Dispatching 04.08 message GSM48_MT_RR_PAG_RESP (0x6:0x27)
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DRR <0003> gsm_04_08.c:1184 PAGING
RESPONSE: MI(TMSI)=601458922
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DMM <0002> subscr_conn.c:587
Subscr_Conn(PAGING_RESP:601458922)[0x55f60531c2c0]{SUBSCR_CONN_S_NEW}: Updated
ID
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DLINP <0012> stream.c:275 connected
read
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DLINP <0012> stream.c:189 message
received
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DLSS7 <001c> osmo_ss7.c:1549
asp-asp-clnt-msc-0: xua_cli_read_cb(): sctp_recvmsg() returned 48 (flags=0x80)
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DLM3UA <001f> m3ua.c:722
asp-asp-clnt-msc-0: Received M3UA Message (XFER:DATA)
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DLM3UA <001f> m3ua.c:541
asp-asp-clnt-msc-0: m3ua_rx_xfer
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DLM3UA <001f> m3ua.c:580
asp-asp-clnt-msc-0: m3ua_rx_xfer(): M3UA data header: opc=185=0.23.1
dpc=187=0.23.3
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DLSS7 <001c> osmo_ss7_hmrt.c:274
m3ua_hmdc_rx_from_l2(): found dpc=187=0.23.3 as local
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DLSUA <001e> sccp2sua.c:333 IEI
259: Parsed Addr: RI=2,PC=185,SSN=254
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DLSS7 <001c> sccp_scrc.c:449
scrc_rx_mtp_xfer_ind_xua: HDR=(CO:COAK,V=0,LEN=0),
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: PART(T=Protocol
Class,L=4,D=00000002),
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: PART(T=Destination
Reference,L=4,D=00000003),
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: PART(T=Source
Reference,L=4,D=00000002),
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: PART(T=Destination
Address,L=20,D=0002000380020008000000b980030008000000fe)
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DLSCCP <001d> sccp_scoc.c:1548
Received CO:COAK for local reference 3
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DLSCCP <001d> sccp_scoc.c:1581
SCCP-SCOC(3)[0x556b9f76eb60]{CONN_PEND_OUT}: Received Event
RCOC-CONNECT_CONFIRM.ind
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DLSCCP <001d> sccp_scoc.c:883
SCCP-SCOC(3)[0x556b9f76eb60]{CONN_PEND_OUT}: state_chg to ACTIVE
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DLSCCP <001d> sccp_user.c:156
Delivering N-CONNECT.confirm to SCCP User 'msc-0'
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DMSC <0007> osmo_bsc_sigtran.c:186
N-CONNECT.cnf(3, )
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DMSC <0007> osmo_bsc_sigtran.c:189
SUBSCR_CONN[0x556b9f76ec90]{WAIT_CC}: Received Event MO-CONNECT.cfm
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DMSC <0007>
bsc_subscr_conn_fsm.c:368 SUBSCR_CONN[0x556b9f76ec90]{WAIT_CC}: state_chg to
ACTIVE
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DVLR <000e> fsm.c:299
Process_Access_Request_VLR(PAGING_RESP:601458922)[0x55f60531dc20]{PR_ARQ_S_INIT}:
Allocated
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DVLR <000e> fsm.c:329
Process_Access_Request_VLR(PAGING_RESP:601458922)[0x55f60531dc20]{PR_ARQ_S_INIT}:
is child of Subscr_Conn(PAGING_RESP:601458922)[0x55f60531c2c0]
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DVLR <000e>
vlr_access_req_fsm.c:669
Process_Access_Request_VLR(PAGING_RESP:601458922)[0x55f60531dc20]{PR_ARQ_S_INIT}:
rev=R99 net=GERAN (no Auth)
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DVLR <000e>
vlr_access_req_fsm.c:694
Process_Access_Request_VLR(PAGING_RESP:601458922)[0x55f60531dc20]{PR_ARQ_S_INIT}:
Received Event PR_ARQ_E_START
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DVLR <000e>
vlr_access_req_fsm.c:328
Process_Access_Request_VLR(PAGING_RESP:601458922)[0x55f60531dc20]{PR_ARQ_S_INIT}:
proc_arq_vlr_fn_post_imsi()
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DVLR <000e>
vlr_access_req_fsm.c:280
Process_Access_Request_VLR(PAGING_RESP:601458922)[0x55f60531dc20]{PR_ARQ_S_INIT}:
_proc_arq_vlr_node2()
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DVLR <000e>
vlr_access_req_fsm.c:246
Process_Access_Request_VLR(PAGING_RESP:601458922)[0x55f60531dc20]{PR_ARQ_S_INIT}:
_proc_arq_vlr_node2_post_ciph()
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DVLR <000e>
vlr_access_req_fsm.c:218
Process_Access_Request_VLR(PAGING_RESP:601458922)[0x55f60531dc20]{PR_ARQ_S_INIT}:
_proc_arq_vlr_node2_post_vlr()
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DVLR <000e>
vlr_access_req_fsm.c:203
Process_Access_Request_VLR(PAGING_RESP:601458922)[0x55f60531dc20]{PR_ARQ_S_INIT}:
_proc_arq_vlr_post_pres()
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DVLR <000e>
vlr_access_req_fsm.c:187
Process_Access_Request_VLR(PAGING_RESP:601458922)[0x55f60531dc20]{PR_ARQ_S_INIT}:
_proc_arq_vlr_post_trace()
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DVLR <000e>
vlr_access_req_fsm.c:165
Process_Access_Request_VLR(PAGING_RESP:601458922)[0x55f60531dc20]{PR_ARQ_S_INIT}:
_proc_arq_vlr_post_imei()
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DVLR <000e>
vlr_access_req_fsm.c:178
Process_Access_Request_VLR(PAGING_RESP:601458922)[0x55f60531dc20]{PR_ARQ_S_INIT}:
proc_arq_fsm_done(PASSED)
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DVLR <000e>
vlr_access_req_fsm.c:101
Process_Access_Request_VLR(PAGING_RESP:601458922)[0x55f60531dc20]{PR_ARQ_S_INIT}:
state_chg to PR_ARQ_S_DONE
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DVLR <000e>
vlr_access_req_fsm.c:110
Process_Access_Request_VLR(PAGING_RESP:601458922)[0x55f60531dc20]{PR_ARQ_S_DONE}:
Process Access Request result: PASSED
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DMM <0002> vlr_access_req_fsm.c:149
Subscr_Conn(PAGING_RESP:601458922)[0x55f60531c2c0]{SUBSCR_CONN_S_NEW}: Received
Event SUBSCR_CONN_E_ACCEPTED
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DPAG <0005> gsm_subscriber.c:73
Paging success for MSISDN:4090 (event=0)
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DPAG <0005> osmo_msc.c:338 Paging
can stop for MSISDN:4090
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DPAG <0005> gsm_subscriber.c:100
Calling paging cbfn.
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DLSMS <0017> silent_call.c:46
paging_cb_silent: DMM <0002> subscr_conn.c:122
Subscr_Conn(PAGING_RESP:601458922)[0x55f60531c2c0]{SUBSCR_CONN_S_NEW}:
state_chg to SUBSCR_CONN_S_ACCEPTED
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DMM <0002> subscr_conn.c:172
Subscr_Conn(PAGING_RESP:601458922)[0x55f60531c2c0]{SUBSCR_CONN_S_ACCEPTED}:
subscr_conn_fsm_has_active_transactions: silent call still active
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DMM <0002> subscr_conn.c:450
Subscr_Conn(PAGING_RESP:601458922)[0x55f60531c2c0]{SUBSCR_CONN_S_ACCEPTED}:
Received Event SUBSCR_CONN_E_COMPLETE_LAYER_3
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DMM <0002> subscr_conn.c:450
Subscr_Conn(PAGING_RESP:601458922)[0x55f60531c2c0]{SUBSCR_CONN_S_ACCEPTED}:
Event SUBSCR_CONN_E_COMPLETE_LAYER_3 not permitted
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DMSC <0006> a_iface_bssap.c:351
User has been accepted by MSC.
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DLINP <0013> stream.c:279 connected
write
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DLINP <0013> stream.c:204 sending
data
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DLINP <0013> stream.c:279 connected
write
Oct 12 16:30:04 cca-132016 osmo-msc[16399]: DLINP <0013> stream.c:204 sending
data
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DRSL <0003> abis_rsl.c:1528
(bts=0,trx=0,ts=0,ss=0): meas_rep_count++=1 meas_rep_last_seen_nr=0
Oct 12 16:30:04 cca-132016 osmo-bsc[16394]: DRSL <0003> abis_rsl.c:1528
(bts=0,trx=0,ts=0,ss=0): meas_rep_count++=2 meas_rep_last_seen_nr=1
Oct 12 16:30:05 cca-132016 osmo-bsc[16394]: DRSL <0003> abis_rsl.c:1528
(bts=0,trx=0,ts=0,ss=0): meas_rep_count++=3 meas_rep_last_seen_nr=2
Oct 12 16:30:05 cca-132016 osmo-bsc[16394]: DRSL <0003> abis_rsl.c:1528
(bts=0,trx=0,ts=0,ss=0): meas_rep_count++=4 meas_rep_last_seen_nr=3
Oct 12 16:30:06 cca-132016 osmo-bsc[16394]: DRSL <0003> abis_rsl.c:1528
(bts=0,trx=0,ts=0,ss=0): meas_rep_count++=5 meas_rep_last_seen_nr=4
Oct 12 16:30:06 cca-132016 osmo-bsc[16394]: DRSL <0003> abis_rsl.c:1528
(bts=0,trx=0,ts=0,ss=0): meas_rep_count++=6 meas_rep_last_seen_nr=5
Oct 12 16:30:07 cca-132016 osmo-bsc[16394]: DRSL <0003> abis_rsl.c:1528
(bts=0,trx=0,ts=0,ss=0): meas_rep_count++=7 meas_rep_last_seen_nr=6
Oct 12 16:30:07 cca-132016 osmo-bsc[16394]: DRSL <0003> abis_rsl.c:1528
(bts=0,trx=0,ts=0,ss=0): meas_rep_count++=8 meas_rep_last_seen_nr=7
Oct 12 16:30:08 cca-132016 osmo-bsc[16394]: DRSL <0003> abis_rsl.c:1528
(bts=0,trx=0,ts=0,ss=0): meas_rep_count++=9 meas_rep_last_seen_nr=8
Oct 12 16:30:08 cca-132016 osmo-bsc[16394]: DHODEC <0009>
handover_decision_2.c:1632 (BTS 0) No congestion check: no minimum for free
TCH/F nor TCH/H set
Oct 12 16:30:08 cca-132016 osmo-bsc[16394]: DHODEC <0009>
handover_decision_2.c:138 HO algorithm 2: next periodical congestion check in
10 seconds
Oct 12 16:30:08 cca-132016 osmo-bsc[16394]: BTS 0 reported connected PCU
version 0.5.1
Oct 12 16:30:08 cca-132016 osmo-bsc[16394]: DRSL <0003> abis_rsl.c:1528
(bts=0,trx=0,ts=0,ss=0): meas_rep_count++=10 meas_rep_last_seen_nr=9
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DMM <0002> fsm.c:189
Subscr_Conn(PAGING_RESP:601458922)[0x55f60531c2c0]{SUBSCR_CONN_S_ACCEPTED}:
Timeout of T0
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DMM <0002> subscr_conn.c:257
Subscr_Conn(PAGING_RESP:601458922)[0x55f60531c2c0]{SUBSCR_CONN_S_ACCEPTED}:
Received Event SUBSCR_CONN_E_CN_CLOSE
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DMM <0002> subscr_conn.c:110
Subscr_Conn(PAGING_RESP:601458922)[0x55f60531c2c0]{SUBSCR_CONN_S_ACCEPTED}:
Close event, cause: CONGESTION
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DMM <0002> subscr_conn.c:221
Subscr_Conn(PAGING_RESP:601458922)[0x55f60531c2c0]{SUBSCR_CONN_S_ACCEPTED}:
state_chg to SUBSCR_CONN_S_RELEASING
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DBSSAP <0010> a_iface.c:419 (subscr
MSISDN:4090, conn_id 2) Tx BSSMAP CLEAR COMMAND to BSC
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DLSCCP <001e> sccp_scoc.c:1615
Received SCCP User Primitive N-DATA.request)
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DLSCCP <001e> sccp_scoc.c:1657
SCCP-SCOC(2)[0x55f60531c0f0]{ACTIVE}: Received Event N-DATA.req
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DLSS7 <001d> sccp_scrc.c:398
sccp_scrc_rx_scoc_conn_msg: HDR=(CO:CODT,V=0,LEN=0),
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: PART(T=Routing
Context,L=4,D=00000000),
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: PART(T=Destination
Reference,L=4,D=00000003),
Oct 12 16:30:09 cca-132016 osmo-msc[16399]:
PART(T=Data,L=6,D=000420040109)
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DLSS7 <001d> osmo_ss7_hmrt.c:278
m3ua_hmdc_rx_from_l2(): dpc=187=0.23.3 not local, message is for routing
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DLSS7 <001d> osmo_ss7_hmrt.c:227
Found route for dpc=187=0.23.3: pc=0=0.0.0 mask=0x0=0.0.0 via AS
as-clnt-OsmoMSC-A proto=m3ua
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DLSS7 <001d> osmo_ss7_hmrt.c:233
rt->dest.as proto is M3UA for dpc=187=0.23.3
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DLSS7 <001d> m3ua.c:507
XUA_AS(as-clnt-OsmoMSC-A)[0x55f605317960]{AS_ACTIVE}: Received Event
AS-TRANSFER.req
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DLINP <0013> stream.c:279 connected
write
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DLINP <0013> stream.c:204 sending
data
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DLINP <0013> stream.c:279 connected
write
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DLINP <0013> stream.c:204 sending
data
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLINP <0012> stream.c:275 connected
read
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLINP <0012> stream.c:189 message
received
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLSS7 <001c> osmo_ss7.c:1549
asp-asp-clnt-msc-0: xua_cli_read_cb(): sctp_recvmsg() returned 48 (flags=0x80)
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLM3UA <001f> m3ua.c:722
asp-asp-clnt-msc-0: Received M3UA Message (XFER:DATA)
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLM3UA <001f> m3ua.c:541
asp-asp-clnt-msc-0: m3ua_rx_xfer
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLM3UA <001f> m3ua.c:580
asp-asp-clnt-msc-0: m3ua_rx_xfer(): M3UA data header: opc=185=0.23.1
dpc=187=0.23.3
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLSS7 <001c> osmo_ss7_hmrt.c:274
m3ua_hmdc_rx_from_l2(): found dpc=187=0.23.3 as local
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLSS7 <001c> sccp_scrc.c:449
scrc_rx_mtp_xfer_ind_xua: HDR=(CO:CODT,V=0,LEN=0),
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: PART(T=Destination
Reference,L=4,D=00000003),
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]:
PART(T=Segmentation,L=4,D=00000000),
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]:
PART(T=Data,L=6,D=000420040109)
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLSCCP <001d> sccp_scoc.c:1548
Received CO:CODT for local reference 3
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLSCCP <001d> sccp_scoc.c:1581
SCCP-SCOC(3)[0x556b9f76eb60]{ACTIVE}: Received Event RCOC-DT1.ind
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLSCCP <001d> sccp_user.c:156
Delivering N-DATA.indication to SCCP User 'msc-0'
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DMSC <0007> osmo_bsc_sigtran.c:203
N-DATA.ind(3, 00 04 20 04 01 09 )
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DMSC <0007> a_reset.c:190
A-RESET(msc-0)[0x556b9f760a80]{CONN}: Received Event EV_N_CONNECT
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DMSC <0007> osmo_bsc_bssap.c:782 Rx
MSC DT1 BSSMAP CLEAR COMMAND
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DMSC <0007> osmo_bsc_bssap.c:786
SUBSCR_CONN[0x556b9f76ec90]{ACTIVE}: Received Event CLEAR_CMD
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DMSC <0007>
bsc_subscr_conn_fsm.c:966 SUBSCR_CONN[0x556b9f76ec90]{ACTIVE}: state_chg to
CLEARING
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DRLL <0000> chan_alloc.c:596
(bts=0,trx=0,ts=0,ss=0) starting release sequence
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DRSL <0003> chan_alloc.c:597
(bts=0,trx=0,ts=0,ss=0) state ACTIVE -> RELEASE REQUESTED
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DRR <0002> gsm_04_08_utils.c:250
Sending Channel Release: Chan: Number: 0 Type: 1
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DRSL <0003> abis_rsl.c:769
(bts=0,trx=0,ts=0,ss=0) DEACTivate SACCH CMD
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DMSC <0007>
bsc_subscr_conn_fsm.c:295 SUBSCR_CONN[0x556b9f76ec90]{CLEARING}: tossing all
MGCP connections...
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DMSC <0007>
bsc_subscr_conn_fsm.c:978 SUBSCR_CONN[0x556b9f76ec90]{CLEARING}: Received Event
RSL_CLEAR_COMPLETE
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DMSC <0007> osmo_bsc_sigtran.c:325
Tx MSC CLEAR COMPLETE
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DLINP <0013> stream.c:275 connected
read
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DLINP <0013> stream.c:189 message
received
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DLSS7 <001d> osmo_ss7.c:1549
asp-asp-clnt-OsmoMSC-A: xua_cli_read_cb(): sctp_recvmsg() returned 44
(flags=0x80)
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DLM3UA <0020> m3ua.c:722
asp-asp-clnt-OsmoMSC-A: Received M3UA Message (XFER:DATA)
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DLM3UA <0020> m3ua.c:541
asp-asp-clnt-OsmoMSC-A: m3ua_rx_xfer
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DLM3UA <0020> m3ua.c:580
asp-asp-clnt-OsmoMSC-A: m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3
dpc=185=0.23.1
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DLSS7 <001d> osmo_ss7_hmrt.c:274
m3ua_hmdc_rx_from_l2(): found dpc=185=0.23.1 as local
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DLSS7 <001d> sccp_scrc.c:449
scrc_rx_mtp_xfer_ind_xua: HDR=(CO:CODT,V=0,LEN=0),
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: PART(T=Destination
Reference,L=4,D=00000002),
Oct 12 16:30:09 cca-132016 osmo-msc[16399]:
PART(T=Segmentation,L=4,D=00000000),
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: PART(T=Data,L=3,D=000121)
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DLSCCP <001e> sccp_scoc.c:1548
Received CO:CODT for local reference 2
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DLSCCP <001e> sccp_scoc.c:1581
SCCP-SCOC(2)[0x55f60531c0f0]{ACTIVE}: Received Event RCOC-DT1.ind
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DLSCCP <001e> sccp_user.c:156
Delivering N-DATA.indication to SCCP User 'OsmoMSC-A'
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DBSSAP <0010> a_iface.c:564
N-DATA.ind(2, 00 01 21 )
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DMSC <0006> a_iface_bssap.c:80
Looking for A subscriber: conn_id 2
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DBSSAP <0010> a_iface_bssap.c:88
(subscr MSISDN:4090, conn_id 2) Found A subscriber for conn_id 2
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DBSSAP <0010> a_iface_bssap.c:587
(subscr MSISDN:4090, conn_id 2) Rx BSSMAP DT1 CLEAR COMPLETE
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DBSSAP <0010> a_iface_bssap.c:241
(subscr MSISDN:4090, conn_id 2) Rx BSSMAP CLEAR COMPLETE, releasing SCCP
connection
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DLSCCP <001e> sccp_scoc.c:1615
Received SCCP User Primitive N-DISCONNECT.request)
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DLSCCP <001e> sccp_scoc.c:1657
SCCP-SCOC(2)[0x55f60531c0f0]{ACTIVE}: Received Event N-DISCONNECT.req
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DLSS7 <001d> sccp_scrc.c:398
sccp_scrc_rx_scoc_conn_msg: HDR=(CO:RELRE,V=0,LEN=0),
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: PART(T=Routing
Context,L=4,D=00000000),
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DMSC <0007> osmo_bsc_sigtran.c:346
Sending connection (id=3) oriented data to MSC: RI=SSN_PC,PC=0.23.1,SSN=BSSAP
(00 01 21 )
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLSCCP <001d> sccp_scoc.c:1615
Received SCCP User Primitive N-DATA.request)
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLSCCP <001d> sccp_scoc.c:1657
SCCP-SCOC(3)[0x556b9f76eb60]{ACTIVE}: Received Event N-DATA.req
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLSS7 <001c> sccp_scrc.c:398
sccp_scrc_rx_scoc_conn_msg: HDR=(CO:CODT,V=0,LEN=0),
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: PART(T=Routing
Context,L=4,D=00000000),
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: PART(T=Destination
Reference,L=4,D=00000002),
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: PART(T=Data,L=3,D=000121)
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLSS7 <001c> osmo_ss7_hmrt.c:278
m3ua_hmdc_rx_from_l2(): dpc=185=0.23.1 not local, message is for routing
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLSS7 <001c> osmo_ss7_hmrt.c:227
Found route for dpc=185=0.23.1: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-msc-0
proto=m3ua
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLSS7 <001c> osmo_ss7_hmrt.c:233
rt->dest.as proto is M3UA for dpc=185=0.23.1
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLSS7 <001c> m3ua.c:507
XUA_AS(as-clnt-msc-0)[0x556b9f75fce0]{AS_ACTIVE}: Received Event AS-TRANSFER.req
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLINP <0012> stream.c:279 connected
write
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLINP <0012> stream.c:204 sending
data
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLINP <0012> stream.c:279 connected
write
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLINP <0012> stream.c:204 sending
data
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLINP <0012> stream.c:275 connected
read
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLINP <0012> stream.c:189 message
received
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLSS7 <001c> osmo_ss7.c:1549
asp-asp-clnt-msc-0: xua_cli_read_cb(): sctp_recvmsg() returned 44 (flags=0x80)
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLM3UA <001f> m3ua.c:722
asp-asp-clnt-msc-0: Received M3UA Message (XFER:DATA)
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLM3UA <001f> m3ua.c:541
asp-asp-clnt-msc-0: m3ua_rx_xfer
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLM3UA <001f> m3ua.c:580
asp-asp-clnt-msc-0: m3ua_rx_xfer(): M3UA data header: opc=185=0.23.1
dpc=187=0.23.3
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLSS7 <001c> osmo_ss7_hmrt.c:274
m3ua_hmdc_rx_from_l2(): found dpc=187=0.23.3 as local
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLSS7 <001c> sccp_scrc.c:449
scrc_rx_mtp_xfer_ind_xua: HDR=(CO:RELRE,V=0,LEN=0),
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: PART(T=Destination
Reference,L=4,D=00000003),
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: PART(T=Source
Reference,L=4,D=00000002),
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: PART(T=Cause,L=4,D=00000300)
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLSCCP <001d> sccp_scoc.c:1548
Received CO:RELRE for local reference 3
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLSCCP <001d> sccp_scoc.c:1581
SCCP-SCOC(3)[0x556b9f76eb60]{ACTIVE}: Received Event RCOC-RELEASED.ind
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: PART(T=Destination
Reference,L=4,D=00000003),
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: PART(T=Source
Reference,L=4,D=00000002),
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: PART(T=Cause,L=4,D=00000300)
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DLSS7 <001d> osmo_ss7_hmrt.c:278
m3ua_hmdc_rx_from_l2(): dpc=187=0.23.3 not local, message is for routing
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DLSS7 <001d> osmo_ss7_hmrt.c:227
Found route for dpc=187=0.23.3: pc=0=0.0.0 mask=0x0=0.0.0 via AS
as-clnt-OsmoMSC-A proto=m3ua
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DLSS7 <001d> osmo_ss7_hmrt.c:233
rt->dest.as proto is M3UA for dpc=187=0.23.3
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DLSS7 <001d> m3ua.c:507
XUA_AS(as-clnt-OsmoMSC-A)[0x55f605317960]{AS_ACTIVE}: Received Event
AS-TRANSFER.req
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DLSCCP <001e> sccp_scoc.c:956
SCCP-SCOC(2)[0x55f60531c0f0]{ACTIVE}: state_chg to DISCONN_PEND
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DBSSAP <0010> a_iface.c:90 (conn_id
2) Removing A-interface conn
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DLINP <0013> stream.c:279 connected
write
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DLINP <0013> stream.c:204 sending
data
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DLINP <0013> stream.c:279 connected
write
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DLINP <0013> stream.c:204 sending
data
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DLINP <0013> stream.c:275 connected
read
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DLINP <0013> stream.c:189 message
received
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DLSS7 <001d> osmo_ss7.c:1549
asp-asp-clnt-OsmoMSC-A: xua_cli_read_cb(): sctp_recvmsg() returned 40
(flags=0x80)
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DLM3UA <0020> m3ua.c:722
asp-asp-clnt-OsmoMSC-A: Received M3UA Message (XFER:DATA)
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DLM3UA <0020> m3ua.c:541
asp-asp-clnt-OsmoMSC-A: m3ua_rx_xfer
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DLM3UA <0020> m3ua.c:580
asp-asp-clnt-OsmoMSC-A: m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3
dpc=185=0.23.1
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DLSS7 <001d> osmo_ss7_hmrt.c:274
m3ua_hmdc_rx_from_l2(): found dpc=185=0.23.1 as local
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DLSS7 <001d> sccp_scrc.c:449
scrc_rx_mtp_xfer_ind_xua: HDR=(CO:RELCO,V=0,LEN=0),
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: PART(T=Destination
Reference,L=4,D=00000002),
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: PART(T=Source
Reference,L=4,D=00000003)
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DLSCCP <001e> sccp_scoc.c:1548
Received CO:RELCO for local reference 2
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLSCCP <001d> sccp_user.c:156
Delivering N-DISCONNECT.indication to SCCP User 'msc-0'
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DMSC <0007> osmo_bsc_sigtran.c:216
N-DISCONNECT.ind(3, , cause=768)
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DMSC <0007> osmo_bsc_sigtran.c:223
SUBSCR_CONN[0x556b9f76ec90]{CLEARING}: Received Event DISCONNET.ind
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DMSC <0007>
bsc_subscr_conn_fsm.c:983 SUBSCR_CONN[0x556b9f76ec90]{CLEARING}: Terminating
(cause = OSMO_FSM_TERM_REGULAR)
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DMSC <0007>
bsc_subscr_conn_fsm.c:295 SUBSCR_CONN[0x556b9f76ec90]{CLEARING}: tossing all
MGCP connections...
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLCLS <000f>
bsc_subscr_conn_fsm.c:1064 LCLS[0x556b9f76fbe0]{NO_LCLS}: Terminating (cause =
OSMO_FSM_TERM_REGULAR)
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLCLS <000f>
bsc_subscr_conn_fsm.c:1064 LCLS[0x556b9f76fbe0]{NO_LCLS}: Removing from parent
SUBSCR_CONN[0x556b9f76ec90]
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLCLS <000f>
bsc_subscr_conn_fsm.c:1064 LCLS[0x556b9f76fbe0]{NO_LCLS}: Freeing instance
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLCLS <000f> fsm.c:381
LCLS[0x556b9f76fbe0]{NO_LCLS}: Deallocated
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DMSC <0007>
bsc_subscr_conn_fsm.c:1064 SUBSCR_CONN[0x556b9f76ec90]{CLEARING}: Received
Event LCLS_FAIL
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DMSC <0007>
bsc_subscr_conn_fsm.c:1045 SUBSCR_CONN[0x556b9f76ec90]{CLEARING}: Putting
bsc_subscr
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DMSC <0007>
bsc_subscr_conn_fsm.c:983 SUBSCR_CONN[0x556b9f76ec90]{CLEARING}: Freeing
instance
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DMSC <0007> fsm.c:381
SUBSCR_CONN[0x556b9f76ec90]{CLEARING}: Deallocated
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLSS7 <001c> sccp_scrc.c:398
sccp_scrc_rx_scoc_conn_msg: HDR=(CO:RELCO,V=0,LEN=0),
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: PART(T=Routing
Context,L=4,D=00000000),
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: PART(T=Destination
Reference,L=4,D=00000002),
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: PART(T=Source
Reference,L=4,D=00000003)
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLSS7 <001c> osmo_ss7_hmrt.c:278
m3ua_hmdc_rx_from_l2(): dpc=185=0.23.1 not local, message is for routing
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLSS7 <001c> osmo_ss7_hmrt.c:227
Found route for dpc=185=0.23.1: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-msc-0
proto=m3ua
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLSS7 <001c> osmo_ss7_hmrt.c:233
rt->dest.as proto is M3UA for dpc=185=0.23.1
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLSS7 <001c> m3ua.c:507
XUA_AS(as-clnt-msc-0)[0x556b9f75fce0]{AS_ACTIVE}: Received Event AS-TRANSFER.req
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLSCCP <001d> sccp_scoc.c:972
SCCP-SCOC(3)[0x556b9f76eb60]{ACTIVE}: state_chg to IDLE
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DLSCCP <001e> sccp_scoc.c:1581
SCCP-SCOC(2)[0x55f60531c0f0]{DISCONN_PEND}: Received Event
RCOC-RELEASE_COMPLETE.ind
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DLSCCP <001e> sccp_scoc.c:1060
SCCP-SCOC(2)[0x55f60531c0f0]{DISCONN_PEND}: state_chg to IDLE
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DLSCCP <001e> sccp_scoc.c:420
SCCP-SCOC(2)[0x55f60531c0f0]{IDLE}: Terminating (cause = OSMO_FSM_TERM_REQUEST)
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DLSCCP <001e> sccp_scoc.c:420
SCCP-SCOC(2)[0x55f60531c0f0]{IDLE}: Freeing instance
Oct 12 16:30:09 cca-132016 osmo-msc[16399]: DLSCCP <001e> fsm.c:381
SCCP-SCOC(2)[0x55f60531c0f0]{IDLE}: Deallocated
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLSCCP <001d> sccp_scoc.c:420
SCCP-SCOC(3)[0x556b9f76eb60]{IDLE}: Terminating (cause = OSMO_FSM_TERM_REQUEST)
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLSCCP <001d> sccp_scoc.c:420
SCCP-SCOC(3)[0x556b9f76eb60]{IDLE}: Freeing instance
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLSCCP <001d> fsm.c:381
SCCP-SCOC(3)[0x556b9f76eb60]{IDLE}: Deallocated
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLINP <0012> stream.c:279 connected
write
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLINP <0012> stream.c:204 sending
data
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLINP <0012> stream.c:279 connected
write
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DLINP <0012> stream.c:204 sending
data
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DRSL <0003> abis_rsl.c:1462
(bts=0,trx=0,ts=0,ss=0): MEAS RES for inactive channel
Oct 12 16:30:09 cca-132016 osmo-bsc[16394]: DRLL <0000> abis_rsl.c:2180
(bts=0,trx=0,ts=0,ss=0) SAPI=0 RELEASE INDICATION
Oct 12 16:30:10 cca-132016 osmo-bsc[16394]: DRLL <0000> chan_alloc.c:721
(bts=0) channel load average is 7.00%
Oct 12 16:30:10 cca-132016 osmo-bsc[16394]: DRLL <0000> chan_alloc.c:734
(bts=0) T3122 wait indicator set to 10 seconds
Oct 12 16:30:11 cca-132016 osmo-bsc[16394]: DRSL <0003> abis_rsl.c:1764
(bts=0,trx=0,ts=0,ss=0) T3111 expired: releasing RF Channel
Oct 12 16:30:11 cca-132016 osmo-bsc[16394]: DRSL <0003> abis_rsl.c:861
(bts=0,trx=0,ts=0,ss=0) RF Channel Release
Oct 12 16:30:11 cca-132016 osmo-bsc[16394]: DRSL <0003> abis_rsl.c:931
(bts=0,trx=0,ts=0,ss=0) RF CHANNEL RELEASE ACK
Oct 12 16:30:11 cca-132016 osmo-bsc[16394]: DRSL <0003> abis_rsl.c:82
(bts=0,trx=0,ts=0,ss=0) state RELEASE REQUESTED -> NONE
Oct 12 16:30:13 cca-132016 osmo-bsc[16394]: BTS 0 reported connected PCU
version 0.5.1
Oct 12 16:30:14 cca-132016 osmo-msc[16399]: DMM <0002> fsm.c:189
Subscr_Conn(PAGING_RESP:601458922)[0x55f60531c2c0]{SUBSCR_CONN_S_RELEASING}:
Timeout of T0
Oct 12 16:30:14 cca-132016 osmo-msc[16399]: DMM <0002> subscr_conn.c:253
Subscr_Conn(PAGING_RESP:601458922)[0x55f60531c2c0]{SUBSCR_CONN_S_RELEASING}:
Timeout while releasing, discarding right now
Oct 12 16:30:14 cca-132016 osmo-msc[16399]: DMM <0002> subscr_conn.c:254
Subscr_Conn(PAGING_RESP:601458922)[0x55f60531c2c0]{SUBSCR_CONN_S_RELEASING}:
Terminating (cause = OSMO_FSM_TERM_TIMEOUT)
Oct 12 16:30:14 cca-132016 osmo-msc[16399]: DVLR <000e> subscr_conn.c:254
Process_Access_Request_VLR(PAGING_RESP:601458922)[0x55f60531dc20]{PR_ARQ_S_DONE}:
Terminating (cause = OSMO_FSM_TERM_PARENT)
Oct 12 16:30:14 cca-132016 osmo-msc[16399]: DVLR <000e> subscr_conn.c:254
Process_Access_Request_VLR(PAGING_RESP:601458922)[0x55f60531dc20]{PR_ARQ_S_DONE}:
Removing from parent Subscr_Conn(PAGING_RESP:601458922)[0x55f60531c2c0]
Oct 12 16:30:14 cca-132016 osmo-msc[16399]: DVLR <000e> subscr_conn.c:254
Process_Access_Request_VLR(PAGING_RESP:601458922)[0x55f60531dc20]{PR_ARQ_S_DONE}:
Freeing instance
Oct 12 16:30:14 cca-132016 osmo-msc[16399]: DVLR <000e> fsm.c:381
Process_Access_Request_VLR(PAGING_RESP:601458922)[0x55f60531dc20]{PR_ARQ_S_DONE}:
Deallocated
Oct 12 16:30:14 cca-132016 osmo-msc[16399]: DMM <0002> subscr_conn.c:172
Subscr_Conn(PAGING_RESP:601458922)[0x55f60531c2c0]{SUBSCR_CONN_S_RELEASING}:
subscr_conn_fsm_has_active_transactions: silent call still active
Oct 12 16:30:14 cca-132016 osmo-msc[16399]: DMM <0002> subscr_conn.c:426
Subscr_Conn(PAGING_RESP:601458922)[0x55f60531c2c0]{SUBSCR_CONN_S_RELEASING}:
Deallocating despite active transactions
Oct 12 16:30:14 cca-132016 osmo-msc[16399]: DRLL <0000> subscr_conn.c:434
MSISDN:4090: Freeing subscriber connection
Oct 12 16:30:14 cca-132016 osmo-msc[16399]: DMM <0002> subscr_conn.c:254
Subscr_Conn(PAGING_RESP:601458922)[0x55f60531c2c0]{SUBSCR_CONN_S_RELEASING}:
Freeing instance
Oct 12 16:30:14 cca-132016 osmo-msc[16399]: DMM <0002> fsm.c:381
Subscr_Conn(PAGING_RESP:601458922)[0x55f60531c2c0]{SUBSCR_CONN_S_RELEASING}:
Deallocated
Oct 12 16:30:18 cca-132016 osmo-bsc[16394]: DHODEC <0009>
handover_decision_2.c:1632 (BTS 0) No congestion check: no minimum for free
TCH/F nor TCH/H set
Oct 12 16:30:18 cca-132016 osmo-bsc[16394]: DHODEC <0009>
handover_decision_2.c:138 HO algorithm 2: next periodical congestion check in
10 seconds
Oct 12 16:30:18 cca-132016 osmo-bsc[16394]: BTS 0 reported connected PCU
version 0.5.1
Oct 12 16:30:18 cca-132016 osmo-msc[16399]: DLGSUP <001b> gsup_client.c:242
GSUP ping callback (connected, got PONG)
Oct 12 16:30:18 cca-132016 osmo-msc[16399]: DLGSUP <001b> gsup_client.c:262
GSUP sending PING
Oct 12 16:30:18 cca-132016 osmo-msc[16399]: DLINP <0013> input/ipa.c:142
127.0.0.1:4222 connected write
Oct 12 16:30:18 cca-132016 osmo-msc[16399]: DLINP <0013> input/ipa.c:92
127.0.0.1:4222 sending data
Oct 12 16:30:18 cca-132016 osmo-msc[16399]: DLINP <0013> input/ipa.c:142
127.0.0.1:4222 connected write
Oct 12 16:30:18 cca-132016 osmo-msc[16399]: DLINP <0013> input/ipa.c:92
127.0.0.1:4222 sending data
Oct 12 16:30:18 cca-132016 osmo-msc[16399]: DLINP <0013> input/ipa.c:138
127.0.0.1:4222 connected read
Oct 12 16:30:18 cca-132016 osmo-msc[16399]: DLINP <0013> input/ipa.c:56
127.0.0.1:4222 message received
Oct 12 16:30:18 cca-132016 osmo-msc[16399]: DLGSUP <001b> gsup_client.c:199
GSUP receiving PONG
Oct 12 16:30:18 cca-132016 osmo-bsc[16394]: DRLL <0000> chan_alloc.c:721
(bts=0) channel load average is 0.20%
Oct 12 16:30:18 cca-132016 osmo-bsc[16394]: DRLL <0000> chan_alloc.c:734
(bts=0) T3122 wait indicator set to 10 seconds
<<attachment: m_ordean.vcf>>
