help need with the silent_call function in OsmoMSC/BSC

This is merely a historical archive of years 2008-2021, before the migration to mailman3.

A maintained and still updated list archive can be found at https://lists.osmocom.org/hyperkitty/list/OpenBSC@lists.osmocom.org/.

Mihai Ordean m.ordean at cs.bham.ac.uk
Fri Oct 12 15:35:04 UTC 2018 

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
-------------- next part --------------
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
-------------- next part --------------
A non-text attachment was scrubbed...
Name: m_ordean.vcf
Type: text/x-vcard
Size: 4 bytes
Desc: not available
URL: <http://lists.osmocom.org/pipermail/openbsc/attachments/20181012/46c8b2d0/attachment.vcf>

More information about the OpenBSC mailing list