Cellular Network Infrastructure » OsmoGSMTester

(launched: 2018-08-13_03:08:24.557549)

20180813030824679 DLSS7 <001d> osmo_ss7.c:363 0: Creating SS7 Instance

20180813030824679 DLSS7 <001d> osmo_ss7.c:687 0: Creating Route Table system

20180813030824680 DLSS7 <001d> osmo_ss7.c:1221 0: Restarting ASP asp0

20180813030824680 DLSS7 <001d> osmo_ss7.c:1270 0: ASP Restart for server not implemented yet!

20180813030824680 DLSS7 <001d> fsm.c:299 XUA_ASP(asp0)[0x61200000bda0]{ASP_DOWN}: Allocated

20180813030824680 DLSS7 <001d> osmo_ss7.c:936 0: Creating AS as0

20180813030824680 DLSS7 <001d> fsm.c:299 XUA_AS(as0)[0x61200000baa0]{AS_DOWN}: Allocated

20180813030824680 DLSS7 <001d> osmo_ss7.c:968 0: Adding ASP asp0 to AS as0

20180813030824682 DMNCC <0004> msc_main.c:585 Using internal MNCC handler.

20180813030824682 DLGLOBAL <0011> telnet_interface.c:104 telnet at 10.42.42.6 4254

20180813030824682 DSMPP <000c> smpp_smsc.c:1011 SMPP at 10.42.42.6 2775

20180813030824682 DLCTRL <0018> control_if.c:887 CTRL at 10.42.42.6 4255

20180813030824688 DLGSUP <001b> gsup_client.c:73 GSUP connecting to 10.42.42.2:4222

20180813030824835 DLSMS <0017> sms_queue.c:250 Attempting to send 20 SMS

20180813030824864 DLSMS <0017> sms_queue.c:234 SMS queue: no SMS to be sent

20180813030824864 DLSMS <0017> sms_queue.c:261 Sending SMS done (0 attempted)

20180813030824864 DLSMS <0017> sms_queue.c:317 SMSqueue added 0 messages in 0 rounds

20180813030824865 DLMGCP <0021> mgcp_client.c:854 MGCP GW connection: 0.0.0.0:2727 -> 10.42.42.3:2427

20180813030824865 DLSCCP <001e> sccp_user.c:397 OsmoMSC-A: Using SS7 instance 0, pc:0.0.1

20180813030824865 DLSCCP <001e> sccp_user.c:421 OsmoMSC-A: Using AS instance as0

20180813030824865 DLSCCP <001e> sccp_user.c:426 OsmoMSC-A: Creating default route

20180813030824865 DLSS7 <001d> osmo_ss7.c:811 0: Creating route: pc=0=0.0.0 mask=0x0 via AS 'as0'

20180813030824865 DLSS7 <001d> fsm.c:299 xua_default_lm(asp0)[0x612000016a20]{IDLE}: Allocated

20180813030824865 DLSS7 <001d> osmo_ss7.c:1221 0: Restarting ASP asp0

20180813030824873 DLSS7 <001d> osmo_ss7.c:1277 XUA_ASP(asp0)[0x61200000bda0]{ASP_DOWN}: Terminating (cause = OSMO_FSM_TERM_REQUEST)

20180813030824873 DLSS7 <001d> osmo_ss7.c:1277 XUA_ASP(asp0)[0x61200000bda0]{ASP_DOWN}: Freeing instance

20180813030824873 DLSS7 <001d> fsm.c:381 XUA_ASP(asp0)[0x61200000bda0]{ASP_DOWN}: Deallocated

20180813030824873 DLSS7 <001d> fsm.c:299 XUA_ASP(asp0)[0x612000016720]{ASP_DOWN}: Allocated

20180813030824873 DLSCCP <001e> sccp_user.c:481 OsmoMSC-A: Using ASP instance asp0

20180813030824873 DLSCCP <001e> sccp_user.c:484 OsmoMSC-A: Creating SCCP instance

20180813030824873 DLSS7 <001d> osmo_ss7.c:482 registering user=SCCP for SI 3 with priv 0x611000004060

20180813030824873 DBSSAP <0010> a_iface.c:641 Initalizing SCCP connection to stp...

20180813030824873 DLSCCP <001e> sccp_user.c:88 Binding user 'OsmoMSC-A' to SSN=254 PC=(no PC)

20180813030824873 DLINP <0013> input/ipa.c:131 10.42.42.2:4222 connection done

20180813030824873 DLGSUP <001b> gsup_client.c:131 GSUP link to 10.42.42.2:4222 UP

20180813030824873 DLGSUP <001b> gsup_client.c:261 GSUP sending PING

20180813030824874 DLINP <0013> stream.c:258 connection done.

20180813030824874 DLSS7 <001d> osmo_ss7.c:1472 asp-asp0: Client connected (r=10.42.42.5:2905<->l=10.42.42.1:37447)

20180813030824874 DLSS7 <001d> xua_default_lm_fsm.c:350 xua_default_lm(asp0)[0x612000016a20]{IDLE}: Received primitive M-SCTP_ESTABLISH.indication

20180813030824874 DLSS7 <001d> xua_default_lm_fsm.c:357 xua_default_lm(asp0)[0x612000016a20]{IDLE}: Received Event SCTP-ESTABLISH.ind

20180813030824874 DLSS7 <001d> xua_default_lm_fsm.c:142 xua_default_lm(asp0)[0x612000016a20]{IDLE}: state_chg to WAIT_ASP_UP

20180813030824874 DLSS7 <001d> xua_default_lm_fsm.c:143 XUA_ASP(asp0)[0x612000016720]{ASP_DOWN}: Received Event M-ASP_UP.req

20180813030824874 DLINP <0013> input/ipa.c:138 10.42.42.2:4222 connected read

20180813030824874 DLINP <0013> input/ipa.c:56 10.42.42.2:4222 message received

20180813030824874 DLINP <0013> input/ipaccess.c:703 received ID get from 0/0/0

20180813030824874 DLINP <0013> input/ipaccess.c:637  tag 8: 0/0/0

20180813030824874 DLINP <0013> input/ipaccess.c:637  tag 7: 00:00:00:00:00:00

20180813030824874 DLINP <0013> input/ipaccess.c:637  tag 2: 00:00:00:00:00:00

20180813030824874 DLINP <0013> input/ipaccess.c:637  tag 3: 00:00:00:00:00:00

20180813030824874 DLINP <0013> input/ipaccess.c:637  tag 4: 00:00:00:00:00:00

20180813030824875 DLINP <0013> input/ipaccess.c:637  tag 5: 00:00:00:00:00:00

20180813030824875 DLINP <0013> input/ipaccess.c:637  tag 1: MSC-00-00-00-00-00-00

20180813030824875 DLINP <0013> input/ipaccess.c:637  tag 0: MSC-00-00-00-00-00-00

20180813030824877 DLINP <0013> input/ipa.c:142 10.42.42.2:4222 connected write

20180813030824877 DLINP <0013> input/ipa.c:92 10.42.42.2:4222 sending data

20180813030824877 DLINP <0013> stream.c:279 connected write

20180813030824877 DLINP <0013> stream.c:204 sending data

20180813030824878 DLINP <0013> input/ipa.c:138 10.42.42.2:4222 connected read

20180813030824878 DLINP <0013> input/ipa.c:56 10.42.42.2:4222 message received

20180813030824879 DLINP <0013> input/ipa.c:142 10.42.42.2:4222 connected write

20180813030824879 DLINP <0013> input/ipa.c:92 10.42.42.2:4222 sending data

20180813030824879 DLINP <0013> stream.c:275 connected read

20180813030824879 DLINP <0013> stream.c:189 message received

20180813030824879 DLSS7 <001d> osmo_ss7.c:1549 asp-asp0: xua_cli_read_cb(): sctp_recvmsg() returned 8 (flags=0x80)

20180813030824879 DLM3UA <0020> m3ua.c:722 asp-asp0: Received M3UA Message (ASPSM:UP-ACK)

20180813030824879 DLSS7 <001d> m3ua.c:686 XUA_ASP(asp0)[0x612000016720]{ASP_DOWN}: Received Event ASPSM-ASP_UP_ACK

20180813030824879 DLSS7 <001d> xua_asp_fsm.c:306 XUA_ASP(asp0)[0x612000016720]{ASP_DOWN}: T(ack) stopped

20180813030824879 DLSS7 <001d> xua_asp_fsm.c:355 XUA_ASP(asp0)[0x612000016720]{ASP_DOWN}: state_chg to ASP_INACTIVE

20180813030824879 DLSS7 <001d> xua_asp_fsm.c:404 XUA_AS(as0)[0x61200000baa0]{AS_DOWN}: Received Event ASPAS-ASP_INACTIVE.ind

20180813030824879 DLSS7 <001d> xua_as_fsm.c:183 XUA_AS(as0)[0x61200000baa0]{AS_DOWN}: state_chg to AS_INACTIVE

20180813030824879 DLSS7 <001d> xua_default_lm_fsm.c:350 xua_default_lm(asp0)[0x612000016a20]{WAIT_ASP_UP}: Received primitive M-ASP_UP.confirm

20180813030824879 DLSS7 <001d> xua_default_lm_fsm.c:357 xua_default_lm(asp0)[0x612000016a20]{WAIT_ASP_UP}: Received Event ASP-UP.conf

20180813030824879 DLSS7 <001d> xua_default_lm_fsm.c:154 xua_default_lm(asp0)[0x612000016a20]{WAIT_ASP_UP}: state_chg to WAIT_NOTIFY

20180813030824880 DLINP <0013> stream.c:279 connected write

20180813030824880 DLINP <0013> stream.c:204 sending data

20180813030824880 DLINP <0013> input/ipa.c:138 10.42.42.2:4222 connected read

20180813030824880 DLINP <0013> input/ipa.c:56 10.42.42.2:4222 message received

20180813030824880 DLGSUP <001b> gsup_client.c:198 GSUP receiving PONG

20180813030824880 DLINP <0013> stream.c:279 connected write

20180813030824881 DLINP <0013> stream.c:204 sending data

20180813030826882 DLSS7 <001d> fsm.c:189 xua_default_lm(asp0)[0x612000016a20]{WAIT_NOTIFY}: Timeout of T1

20180813030826882 DLSS7 <001d> xua_default_lm_fsm.c:176 xua_default_lm(asp0)[0x612000016a20]{WAIT_NOTIFY}: state_chg to RKM_REG

20180813030826882 DLSS7 <001d> xua_rkm.c:531 asp-asp0: Received XUA Layer Manager Primitive: M-RK_REG.request)

20180813030826882 DLINP <0013> stream.c:279 connected write

20180813030826882 DLINP <0013> stream.c:204 sending data

20180813030826883 DLINP <0013> stream.c:279 connected write

20180813030826883 DLINP <0013> stream.c:204 sending data

20180813030826884 DLINP <0013> stream.c:275 connected read

20180813030826885 DLINP <0013> stream.c:189 message received

20180813030826885 DLSS7 <001d> osmo_ss7.c:1549 asp-asp0: xua_cli_read_cb(): sctp_recvmsg() returned 36 (flags=0x80)

20180813030826885 DLM3UA <0020> m3ua.c:722 asp-asp0: Received M3UA Message (RKM:REG-RESP)

20180813030826885 DLSS7 <001d> xua_rkm.c:409 asp-asp0: Received RKM REG RES rctx=1 status=SUCCESS

20180813030826885 DLSS7 <001d> xua_default_lm_fsm.c:350 xua_default_lm(asp0)[0x612000016a20]{RKM_REG}: Received primitive M-RK_REG.confirm

20180813030826885 DLSS7 <001d> xua_default_lm_fsm.c:357 xua_default_lm(asp0)[0x612000016a20]{RKM_REG}: Received Event RKM_REG.conf

20180813030826885 DLSS7 <001d> xua_default_lm_fsm.c:248 xua_default_lm(asp0)[0x612000016a20]{RKM_REG}: state_chg to WAIT_NOTIFY

20180813030826885 DLINP <0013> stream.c:275 connected read

20180813030826885 DLINP <0013> stream.c:189 message received

20180813030826885 DLSS7 <001d> osmo_ss7.c:1549 asp-asp0: xua_cli_read_cb(): sctp_recvmsg() returned 24 (flags=0x80)

20180813030826886 DLM3UA <0020> m3ua.c:722 asp-asp0: Received M3UA Message (MGMT:NOTIFY)

20180813030826886 DLM3UA <0020> m3ua.c:634 asp-asp0: Received NOTIFY Type State Change:AS Inactive ()

20180813030826886 DLSS7 <001d> xua_default_lm_fsm.c:350 xua_default_lm(asp0)[0x612000016a20]{WAIT_NOTIFY}: Received primitive M-NOTIFY.indication

20180813030826886 DLSS7 <001d> xua_default_lm_fsm.c:357 xua_default_lm(asp0)[0x612000016a20]{WAIT_NOTIFY}: Received Event NOTIFY.ind

20180813030826886 DLSS7 <001d> xua_default_lm_fsm.c:214 xua_default_lm(asp0)[0x612000016a20]{WAIT_NOTIFY}: state_chg to ACTIVE

20180813030826886 DLSS7 <001d> xua_default_lm_fsm.c:215 XUA_ASP(asp0)[0x612000016720]{ASP_INACTIVE}: Received Event M-ASP_ACTIVE.req

20180813030826886 DLINP <0013> stream.c:279 connected write

20180813030826886 DLINP <0013> stream.c:204 sending data

20180813030826886 DLINP <0013> stream.c:279 connected write

20180813030826886 DLINP <0013> stream.c:204 sending data

20180813030826888 DLINP <0013> stream.c:275 connected read

20180813030826888 DLINP <0013> stream.c:189 message received

20180813030826888 DLSS7 <001d> osmo_ss7.c:1549 asp-asp0: xua_cli_read_cb(): sctp_recvmsg() returned 8 (flags=0x80)

20180813030826888 DLM3UA <0020> m3ua.c:722 asp-asp0: Received M3UA Message (ASPTM:ACTIVE-ACK)

20180813030826888 DLSS7 <001d> m3ua.c:686 XUA_ASP(asp0)[0x612000016720]{ASP_INACTIVE}: Received Event ASPTM-ASP_AC_ACK

20180813030826888 DLSS7 <001d> xua_asp_fsm.c:306 XUA_ASP(asp0)[0x612000016720]{ASP_INACTIVE}: T(ack) stopped

20180813030826888 DLSS7 <001d> xua_asp_fsm.c:434 XUA_ASP(asp0)[0x612000016720]{ASP_INACTIVE}: state_chg to ASP_ACTIVE

20180813030826888 DLSS7 <001d> xua_asp_fsm.c:404 XUA_AS(as0)[0x61200000baa0]{AS_INACTIVE}: Received Event ASPAS-ASP_ACTIVE.ind

20180813030826888 DLSS7 <001d> xua_as_fsm.c:241 XUA_AS(as0)[0x61200000baa0]{AS_INACTIVE}: state_chg to AS_ACTIVE

20180813030826889 DLSS7 <001d> xua_default_lm_fsm.c:350 xua_default_lm(asp0)[0x612000016a20]{ACTIVE}: Received primitive M-ASP_ACTIVE.confirm

20180813030826889 DLSS7 <001d> xua_default_lm_fsm.c:353 xua_default_lm(asp0)[0x612000016a20]{ACTIVE}: Ignoring primitive M-ASP_ACTIVE.confirm

20180813030826889 DLINP <0013> stream.c:275 connected read

20180813030826889 DLINP <0013> stream.c:189 message received

20180813030826889 DLSS7 <001d> osmo_ss7.c:1549 asp-asp0: xua_cli_read_cb(): sctp_recvmsg() returned 24 (flags=0x80)

20180813030826889 DLM3UA <0020> m3ua.c:722 asp-asp0: Received M3UA Message (MGMT:NOTIFY)

20180813030826889 DLM3UA <0020> m3ua.c:634 asp-asp0: Received NOTIFY Type State Change:AS Active ()

20180813030826889 DLSS7 <001d> xua_default_lm_fsm.c:350 xua_default_lm(asp0)[0x612000016a20]{ACTIVE}: Received primitive M-NOTIFY.indication

20180813030826889 DLSS7 <001d> xua_default_lm_fsm.c:357 xua_default_lm(asp0)[0x612000016a20]{ACTIVE}: Received Event NOTIFY.ind

20180813030826889 DLINP <0013> stream.c:279 connected write

20180813030826889 DLINP <0013> stream.c:204 sending data

20180813030826890 DLINP <0013> stream.c:279 connected write

20180813030826890 DLINP <0013> stream.c:204 sending data

20180813030829749 DLINP <0013> stream.c:275 connected read

20180813030829749 DLINP <0013> stream.c:189 message received

20180813030829750 DLSS7 <001d> osmo_ss7.c:1549 asp-asp0: xua_cli_read_cb(): sctp_recvmsg() returned 56 (flags=0x80)

20180813030829750 DLM3UA <0020> m3ua.c:722 asp-asp0: Received M3UA Message (XFER:DATA)

20180813030829750 DLM3UA <0020> m3ua.c:541 asp-asp0: m3ua_rx_xfer

20180813030829750 DLM3UA <0020> m3ua.c:580 asp-asp0: m3ua_rx_xfer(): M3UA data header: opc=2=0.0.2 dpc=1=0.0.1

20180813030829750 DLSS7 <001d> osmo_ss7_hmrt.c:274 m3ua_hmdc_rx_from_l2(): found dpc=1=0.0.1 as local

20180813030829750 DLSUA <001f> sccp2sua.c:333 IEI 259: Parsed Addr: RI=2,PC=1,SSN=254

20180813030829750 DLSUA <001f> sccp2sua.c:333 IEI 258: Parsed Addr: RI=2,PC=2,SSN=254

20180813030829750 DLSS7 <001d> sccp_scrc.c:449 scrc_rx_mtp_xfer_ind_xua:  HDR=(CL:CLDT,V=0,LEN=0),

	PART(T=Protocol Class,L=4,D=00000000),

	PART(T=Destination Address,L=20,D=00020003800200080000000180030008000000fe),

	PART(T=Source Address,L=20,D=00020003800200080000000280030008000000fe),

	PART(T=Data,L=6,D=000430040120)

20180813030829750 DLSUA <001f> sua.c:385 sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 01 80 03 00 08 00 00 00 fe 

20180813030829750 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8

20180813030829751 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8

20180813030829751 DLSUA <001f> sua.c:385 sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 01 80 03 00 08 00 00 00 fe 

20180813030829751 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8

20180813030829751 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8

20180813030829751 DLSUA <001f> sua.c:385 sua_addr_parse_part(IEI=0x0102) (20) 00 02 00 03 80 02 00 08 00 00 00 02 80 03 00 08 00 00 00 fe 

20180813030829751 DLSUA <001f> sua.c:439 SUA IEI 0x0102 pos 4/20: subpart tag 0x8002, len 8

20180813030829751 DLSUA <001f> sua.c:439 SUA IEI 0x0102 pos 12/20: subpart tag 0x8003, len 8

20180813030829751 DLSCCP <001e> sccp_user.c:156 Delivering N-UNITDATA.indication to SCCP User 'OsmoMSC-A'

20180813030829751 DBSSAP <0010> a_iface.c:138 The calling BSC (RI=SSN_PC,PC=0.0.2,SSN=BSSAP) is unknown to this MSC ...

20180813030829751 DBSSAP <0010> a_iface.c:457 Adding new BSC connection for BSC RI=SSN_PC,PC=0.0.2,SSN=BSSAP...

20180813030829751 DMSC <0006> fsm.c:299 A-RESET(bsc-2)[0x612000016420]{DISC}: Allocated

20180813030829751 DMSC <0006> a_reset.c:124 A-RESET(bsc-2)[0x612000016420]{DISC}: state_chg to DISC

20180813030829751 DBSSAP <0010> a_iface.c:589 N-UNITDATA.ind(00 04 30 04 01 20 )

20180813030829751 DBSSAP <0010> a_iface_bssap.c:187 Rx BSSMAP UDT: 00 04 30 04 01 20 

20180813030829752 DBSSAP <0010> a_iface_bssap.c:161 Rx BSSMAP UDT RESET

20180813030829752 DBSSAP <0010> a_iface_bssap.c:110 Rx BSSMAP RESET from BSC RI=SSN_PC,PC=0.0.2,SSN=BSSAP, sending RESET ACK

20180813030829752 DLSCCP <001e> sccp_scoc.c:1615 Received SCCP User Primitive N-UNITDATA.request)

20180813030829752 DLSS7 <001d> sccp_scrc.c:420 sccp_scrc_rx_sclc_msg:  HDR=(CL:CLDT,V=0,LEN=0),

	PART(T=Routing Context,L=4,D=00000000),

	PART(T=Protocol Class,L=4,D=00000000),

	PART(T=Source Address,L=20,D=00020003800200080000000180030008000000fe),

	PART(T=Destination Address,L=20,D=00020003800200080000000280030008000000fe),

	PART(T=Sequence Control,L=4,D=00000000),

	PART(T=Data,L=3,D=000131)

20180813030829752 DLSUA <001f> sua.c:385 sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 02 80 03 00 08 00 00 00 fe 

20180813030829752 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8

20180813030829752 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8

20180813030829752 DLSUA <001f> sua.c:385 sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 02 80 03 00 08 00 00 00 fe 

20180813030829752 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8

20180813030829752 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8

20180813030829752 DLSUA <001f> sua.c:385 sua_addr_parse_part(IEI=0x0102) (20) 00 02 00 03 80 02 00 08 00 00 00 01 80 03 00 08 00 00 00 fe 

20180813030829752 DLSUA <001f> sua.c:439 SUA IEI 0x0102 pos 4/20: subpart tag 0x8002, len 8

20180813030829752 DLSUA <001f> sua.c:439 SUA IEI 0x0102 pos 12/20: subpart tag 0x8003, len 8

20180813030829752 DLSS7 <001d> osmo_ss7_hmrt.c:278 m3ua_hmdc_rx_from_l2(): dpc=2=0.0.2 not local, message is for routing

20180813030829753 DLSS7 <001d> osmo_ss7_hmrt.c:227 Found route for dpc=2=0.0.2: pc=0=0.0.0 mask=0x0=0.0.0 via AS as0 proto=m3ua

20180813030829753 DLSS7 <001d> osmo_ss7_hmrt.c:233 rt->dest.as proto is M3UA for dpc=2=0.0.2

20180813030829753 DLSS7 <001d> m3ua.c:507 XUA_AS(as0)[0x61200000baa0]{AS_ACTIVE}: Received Event AS-TRANSFER.req

20180813030829753 DMSC <0006> a_reset.c:134 A-RESET(bsc-2)[0x612000016420]{DISC}: Received Event EV_CONN_ACK

20180813030829753 DMSC <0006> a_reset.c:62 A-RESET(bsc-2)[0x612000016420]{DISC}: state_chg to CONN

20180813030829753 DLINP <0013> stream.c:279 connected write

20180813030829753 DLINP <0013> stream.c:204 sending data

20180813030829753 DLINP <0013> stream.c:279 connected write

20180813030829753 DLINP <0013> stream.c:204 sending data

20180813030844879 DLGSUP <001b> gsup_client.c:241 GSUP ping callback (connected, got PONG)

20180813030844879 DLGSUP <001b> gsup_client.c:261 GSUP sending PING

20180813030844879 DLINP <0013> input/ipa.c:142 10.42.42.2:4222 connected write

20180813030844879 DLINP <0013> input/ipa.c:92 10.42.42.2:4222 sending data

20180813030844880 DLINP <0013> input/ipa.c:142 10.42.42.2:4222 connected write

20180813030844880 DLINP <0013> input/ipa.c:92 10.42.42.2:4222 sending data

20180813030844880 DLINP <0013> input/ipa.c:138 10.42.42.2:4222 connected read

20180813030844881 DLINP <0013> input/ipa.c:56 10.42.42.2:4222 message received

20180813030844881 DLGSUP <001b> gsup_client.c:198 GSUP receiving PONG

20180813030904884 DLGSUP <001b> gsup_client.c:241 GSUP ping callback (connected, got PONG)

20180813030904884 DLGSUP <001b> gsup_client.c:261 GSUP sending PING

20180813030904884 DLINP <0013> input/ipa.c:142 10.42.42.2:4222 connected write

20180813030904884 DLINP <0013> input/ipa.c:92 10.42.42.2:4222 sending data

20180813030904885 DLINP <0013> input/ipa.c:142 10.42.42.2:4222 connected write

20180813030904885 DLINP <0013> input/ipa.c:92 10.42.42.2:4222 sending data

20180813030904885 DLINP <0013> input/ipa.c:138 10.42.42.2:4222 connected read

20180813030904885 DLINP <0013> input/ipa.c:56 10.42.42.2:4222 message received

20180813030904886 DLGSUP <001b> gsup_client.c:198 GSUP receiving PONG

20180813030924884 DLGSUP <001b> gsup_client.c:241 GSUP ping callback (connected, got PONG)

20180813030924885 DLGSUP <001b> gsup_client.c:261 GSUP sending PING

20180813030924885 DLINP <0013> input/ipa.c:142 10.42.42.2:4222 connected write

20180813030924885 DLINP <0013> input/ipa.c:92 10.42.42.2:4222 sending data

20180813030924885 DLINP <0013> input/ipa.c:142 10.42.42.2:4222 connected write

20180813030924885 DLINP <0013> input/ipa.c:92 10.42.42.2:4222 sending data

20180813030924886 DLINP <0013> input/ipa.c:138 10.42.42.2:4222 connected read

20180813030924886 DLINP <0013> input/ipa.c:56 10.42.42.2:4222 message received

20180813030924886 DLGSUP <001b> gsup_client.c:198 GSUP receiving PONG

20180813030944889 DLGSUP <001b> gsup_client.c:241 GSUP ping callback (connected, got PONG)

20180813030944889 DLGSUP <001b> gsup_client.c:261 GSUP sending PING

20180813030944890 DLINP <0013> input/ipa.c:142 10.42.42.2:4222 connected write

20180813030944890 DLINP <0013> input/ipa.c:92 10.42.42.2:4222 sending data

20180813030944890 DLINP <0013> input/ipa.c:142 10.42.42.2:4222 connected write

20180813030944890 DLINP <0013> input/ipa.c:92 10.42.42.2:4222 sending data

20180813030944891 DLINP <0013> input/ipa.c:138 10.42.42.2:4222 connected read

20180813030944891 DLINP <0013> input/ipa.c:56 10.42.42.2:4222 message received

20180813030944891 DLGSUP <001b> gsup_client.c:198 GSUP receiving PONG

20180813031004892 DLGSUP <001b> gsup_client.c:241 GSUP ping callback (connected, got PONG)

20180813031004892 DLGSUP <001b> gsup_client.c:261 GSUP sending PING

20180813031004892 DLINP <0013> input/ipa.c:142 10.42.42.2:4222 connected write

20180813031004892 DLINP <0013> input/ipa.c:92 10.42.42.2:4222 sending data

20180813031004892 DLINP <0013> input/ipa.c:142 10.42.42.2:4222 connected write

20180813031004892 DLINP <0013> input/ipa.c:92 10.42.42.2:4222 sending data

20180813031004893 DLINP <0013> input/ipa.c:138 10.42.42.2:4222 connected read

20180813031004893 DLINP <0013> input/ipa.c:56 10.42.42.2:4222 message received

20180813031004893 DLGSUP <001b> gsup_client.c:198 GSUP receiving PONG

20180813031024892 DLGSUP <001b> gsup_client.c:241 GSUP ping callback (connected, got PONG)

20180813031024892 DLGSUP <001b> gsup_client.c:261 GSUP sending PING

20180813031024893 DLINP <0013> input/ipa.c:142 10.42.42.2:4222 connected write

20180813031024893 DLINP <0013> input/ipa.c:92 10.42.42.2:4222 sending data

20180813031024893 DLINP <0013> input/ipa.c:142 10.42.42.2:4222 connected write

20180813031024893 DLINP <0013> input/ipa.c:92 10.42.42.2:4222 sending data

20180813031024894 DLINP <0013> input/ipa.c:138 10.42.42.2:4222 connected read

20180813031024894 DLINP <0013> input/ipa.c:56 10.42.42.2:4222 message received

20180813031024894 DLGSUP <001b> gsup_client.c:198 GSUP receiving PONG

20180813031044896 DLGSUP <001b> gsup_client.c:241 GSUP ping callback (connected, got PONG)

20180813031044896 DLGSUP <001b> gsup_client.c:261 GSUP sending PING

20180813031044896 DLINP <0013> input/ipa.c:142 10.42.42.2:4222 connected write

20180813031044896 DLINP <0013> input/ipa.c:92 10.42.42.2:4222 sending data

20180813031044896 DLINP <0013> input/ipa.c:142 10.42.42.2:4222 connected write

20180813031044896 DLINP <0013> input/ipa.c:92 10.42.42.2:4222 sending data

20180813031044897 DLINP <0013> input/ipa.c:138 10.42.42.2:4222 connected read

20180813031044897 DLINP <0013> input/ipa.c:56 10.42.42.2:4222 message received

20180813031044897 DLGSUP <001b> gsup_client.c:198 GSUP receiving PONG

20180813031051949 DLINP <0013> stream.c:275 connected read

20180813031051950 DLINP <0013> stream.c:189 message received

20180813031051950 DLSS7 <001d> osmo_ss7.c:1549 asp-asp0: xua_cli_read_cb(): sctp_recvmsg() returned 84 (flags=0x80)

20180813031051950 DLM3UA <0020> m3ua.c:722 asp-asp0: Received M3UA Message (XFER:DATA)

20180813031051950 DLM3UA <0020> m3ua.c:541 asp-asp0: m3ua_rx_xfer

20180813031051950 DLM3UA <0020> m3ua.c:580 asp-asp0: m3ua_rx_xfer(): M3UA data header: opc=2=0.0.2 dpc=1=0.0.1

20180813031051950 DLSS7 <001d> osmo_ss7_hmrt.c:274 m3ua_hmdc_rx_from_l2(): found dpc=1=0.0.1 as local

20180813031051950 DLSUA <001f> sccp2sua.c:333 IEI 259: Parsed Addr: RI=2,PC=1,SSN=254

20180813031051950 DLSUA <001f> sccp2sua.c:333 IEI 258: Parsed Addr: RI=2,PC=2,SSN=254

20180813031051950 DLSS7 <001d> sccp_scrc.c:449 scrc_rx_mtp_xfer_ind_xua:  HDR=(CO:CORE,V=0,LEN=0),

	PART(T=Protocol Class,L=4,D=00000002),

	PART(T=Source Reference,L=4,D=00000001),

	PART(T=Destination Address,L=20,D=00020003800200080000000180030008000000fe),

	PART(T=Source Address,L=20,D=00020003800200080000000280030008000000fe),

	PART(T=Data,L=30,D=001c5705080009f10704ab04ab170f05087009f10704aa5005f4c2aa7537)

20180813031051950 DLSUA <001f> sua.c:385 sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 01 80 03 00 08 00 00 00 fe 

20180813031051950 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8

20180813031051950 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8

20180813031051951 DLSUA <001f> sua.c:385 sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 01 80 03 00 08 00 00 00 fe 

20180813031051951 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8

20180813031051951 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8

20180813031051951 DLSCCP <001e> fsm.c:299 SCCP-SCOC(0)[0x612000015ca0]{IDLE}: Allocated

20180813031051951 DLSCCP <001e> sccp_scoc.c:1548 Received CO:CORE for local reference 0

20180813031051951 DLSCCP <001e> sccp_scoc.c:1581 SCCP-SCOC(0)[0x612000015ca0]{IDLE}: Received Event RCOC-CONNECT.ind

20180813031051951 DLSUA <001f> sua.c:385 sua_addr_parse_part(IEI=0x0102) (20) 00 02 00 03 80 02 00 08 00 00 00 02 80 03 00 08 00 00 00 fe 

20180813031051951 DLSUA <001f> sua.c:439 SUA IEI 0x0102 pos 4/20: subpart tag 0x8002, len 8

20180813031051951 DLSUA <001f> sua.c:439 SUA IEI 0x0102 pos 12/20: subpart tag 0x8003, len 8

20180813031051951 DLSUA <001f> sua.c:385 sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 01 80 03 00 08 00 00 00 fe 

20180813031051951 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8

20180813031051951 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8

20180813031051951 DLSCCP <001e> sccp_scoc.c:775 SCCP-SCOC(0)[0x612000015ca0]{IDLE}: state_chg to CONN_PEND_IN

20180813031051951 DLSCCP <001e> sccp_user.c:156 Delivering N-CONNECT.indication to SCCP User 'OsmoMSC-A'

20180813031051952 DLSCCP <001e> sccp_scoc.c:1615 Received SCCP User Primitive N-CONNECT.response)

20180813031051952 DLSCCP <001e> sccp_scoc.c:1657 SCCP-SCOC(0)[0x612000015ca0]{CONN_PEND_IN}: Received Event N-CONNECT.resp

20180813031051952 DLSS7 <001d> sccp_scrc.c:398 sccp_scrc_rx_scoc_conn_msg:  HDR=(CO:COAK,V=0,LEN=0),

	PART(T=Routing Context,L=4,D=00000000),

	PART(T=Protocol Class,L=4,D=00000002),

	PART(T=Destination Reference,L=4,D=00000001),

	PART(T=Source Reference,L=4,D=00000000),

	PART(T=Sequence Control,L=4,D=00000000),

	PART(T=Source Address,L=20,D=00020003800200080000000180030008000000fe),

	PART(T=Destination Address,L=20,D=00020003800200080000000280030008000000fe)

20180813031051952 DLSUA <001f> sua.c:385 sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 02 80 03 00 08 00 00 00 fe 

20180813031051952 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8

20180813031051952 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8

20180813031051952 DLSUA <001f> sua.c:385 sua_addr_parse_part(IEI=0x0102) (20) 00 02 00 03 80 02 00 08 00 00 00 01 80 03 00 08 00 00 00 fe 

20180813031051952 DLSUA <001f> sua.c:439 SUA IEI 0x0102 pos 4/20: subpart tag 0x8002, len 8

20180813031051952 DLSUA <001f> sua.c:439 SUA IEI 0x0102 pos 12/20: subpart tag 0x8003, len 8

20180813031051952 DLSS7 <001d> osmo_ss7_hmrt.c:278 m3ua_hmdc_rx_from_l2(): dpc=2=0.0.2 not local, message is for routing

20180813031051952 DLSS7 <001d> osmo_ss7_hmrt.c:227 Found route for dpc=2=0.0.2: pc=0=0.0.0 mask=0x0=0.0.0 via AS as0 proto=m3ua

20180813031051952 DLSS7 <001d> osmo_ss7_hmrt.c:233 rt->dest.as proto is M3UA for dpc=2=0.0.2

20180813031051952 DLSS7 <001d> m3ua.c:507 XUA_AS(as0)[0x61200000baa0]{AS_ACTIVE}: Received Event AS-TRANSFER.req

20180813031051952 DLSCCP <001e> sccp_scoc.c:802 SCCP-SCOC(0)[0x612000015ca0]{CONN_PEND_IN}: state_chg to ACTIVE

20180813031051952 DBSSAP <0010> a_iface.c:535 N-CONNECT.ind(0, 00 1c 57 05 08 00 09 f1 07 04 ab 04 ab 17 0f 05 08 70 09 f1 07 04 aa 50 05 f4 c2 aa 75 37 )

20180813031051952 DBSSAP <0010> a_iface_bssap.c:268 Rx BSSMAP COMPLETE L3 INFO (conn_id=0)

20180813031051953 DMSC <0006> a_iface_bssap.c:55 Allocating A-Interface subscriber conn: lac 1195, conn_id 0

20180813031051953 DMM <0002> fsm.c:299 Subscr_Conn[0x612000015b20]{SUBSCR_CONN_S_NEW}: Allocated

20180813031051953 DBSSAP <0010> a_iface_bssap.c:68 (subscr unknown, conn_id 0) A-Interface subscriber connection successfully allocated!

20180813031051953 DRLL <0000> gsm_04_08.c:1439 Dispatching 04.08 message GSM48_MT_MM_LOC_UPD_REQUEST (0x5:0x8)

20180813031051953 DMM <0002> subscr_conn.c:602 Subscr_Conn(LU:3265951031)[0x612000015b20]{SUBSCR_CONN_S_NEW}: Updated ID

20180813031051953 DMM <0002> gsm_04_08.c:353 LOCATION UPDATING REQUEST: MI(TMSI)=3265951031 type=NORMAL

20180813031051953 DMM <0002> gsm_04_08.c:396 LU/new-LAC: 1194/1195

20180813031051953 DVLR <000e> fsm.c:299 vlr_lu_fsm(LU:3265951031)[0x6120000159a0]{VLR_ULA_S_IDLE}: Allocated

20180813031051953 DVLR <000e> fsm.c:329 vlr_lu_fsm(LU:3265951031)[0x6120000159a0]{VLR_ULA_S_IDLE}: is child of Subscr_Conn(LU:3265951031)[0x612000015b20]

20180813031051953 DVLR <000e> vlr_lu_fsm.c:1455 vlr_lu_fsm(LU:3265951031)[0x6120000159a0]{VLR_ULA_S_IDLE}: rev=R99 net=GERAN (no Auth)

20180813031051953 DVLR <000e> vlr_lu_fsm.c:1461 vlr_lu_fsm(LU:3265951031)[0x6120000159a0]{VLR_ULA_S_IDLE}: Received Event VLR_ULA_E_UPDATE_LA

20180813031051953 DVLR <000e> vlr.c:389 New subscr, TMSI: 0xc2aa7537

20180813031051953 DVLR <000e> vlr_lu_fsm.c:919 vlr_lu_fsm(LU:3265951031)[0x6120000159a0]{VLR_ULA_S_IDLE}: vlr_loc_upd_want_imsi()

20180813031051953 DVLR <000e> vlr_lu_fsm.c:925 vlr_lu_fsm(LU:3265951031)[0x6120000159a0]{VLR_ULA_S_IDLE}: state_chg to VLR_ULA_S_WAIT_IMSI

20180813031051953 DMSC <0006> msc_ifaces.c:60 msc_tx 3 bytes to TMSI:0xc2aa7537 via RAN_GERAN_A

20180813031051953 DBSSAP <0010> a_iface.c:154 (subscr TMSI:0xc2aa7537, conn_id 0) Passing DTAP message (DLCI=0x00) from MSC to BSC

20180813031051953 DBSSAP <0010> a_iface.c:168 (subscr TMSI:0xc2aa7537, conn_id 0) N-DATA.req([])

20180813031051954 DLSCCP <001e> sccp_scoc.c:1615 Received SCCP User Primitive N-DATA.request)

20180813031051954 DLSCCP <001e> sccp_scoc.c:1657 SCCP-SCOC(0)[0x612000015ca0]{ACTIVE}: Received Event N-DATA.req

20180813031051954 DLSS7 <001d> sccp_scrc.c:398 sccp_scrc_rx_scoc_conn_msg:  HDR=(CO:CODT,V=0,LEN=0),

	PART(T=Routing Context,L=4,D=00000000),

	PART(T=Destination Reference,L=4,D=00000001),

	PART(T=Data,L=6,D=010003051801)

20180813031051954 DLSS7 <001d> osmo_ss7_hmrt.c:278 m3ua_hmdc_rx_from_l2(): dpc=2=0.0.2 not local, message is for routing

20180813031051954 DLSS7 <001d> osmo_ss7_hmrt.c:227 Found route for dpc=2=0.0.2: pc=0=0.0.0 mask=0x0=0.0.0 via AS as0 proto=m3ua

20180813031051954 DLSS7 <001d> osmo_ss7_hmrt.c:233 rt->dest.as proto is M3UA for dpc=2=0.0.2

20180813031051954 DLSS7 <001d> m3ua.c:507 XUA_AS(as0)[0x61200000baa0]{AS_ACTIVE}: Received Event AS-TRANSFER.req

20180813031051954 DMM <0002> subscr_conn.c:465 Subscr_Conn(LU:3265951031)[0x612000015b20]{SUBSCR_CONN_S_NEW}: Received Event SUBSCR_CONN_E_COMPLETE_LAYER_3

20180813031051954 DMM <0002> subscr_conn.c:117 Subscr_Conn(LU:3265951031)[0x612000015b20]{SUBSCR_CONN_S_NEW}: state_chg to SUBSCR_CONN_S_AUTH_CIPH

20180813031051954 DMM <0002> osmo_msc.c:315 Subscr_Conn(LU:3265951031)[0x612000015b20]{SUBSCR_CONN_S_AUTH_CIPH}: Received Event SUBSCR_CONN_E_UNUSED

20180813031051954 DMM <0002> subscr_conn.c:150 Subscr_Conn(LU:3265951031)[0x612000015b20]{SUBSCR_CONN_S_AUTH_CIPH}: Awaiting results for Auth+Ciph, overruling event SUBSCR_CONN_E_UNUSED

20180813031051954 DMSC <0006> a_iface_bssap.c:351 User has been accepted by MSC.

20180813031051954 DLINP <0013> stream.c:279 connected write

20180813031051954 DLINP <0013> stream.c:204 sending data

20180813031051955 DLINP <0013> stream.c:279 connected write

20180813031051955 DLINP <0013> stream.c:204 sending data

20180813031051955 DLINP <0013> stream.c:279 connected write

20180813031051955 DLINP <0013> stream.c:204 sending data

20180813031052143 DLINP <0013> stream.c:275 connected read

20180813031052143 DLINP <0013> stream.c:189 message received

20180813031052143 DLSS7 <001d> osmo_ss7.c:1549 asp-asp0: xua_cli_read_cb(): sctp_recvmsg() returned 48 (flags=0x80)

20180813031052143 DLM3UA <0020> m3ua.c:722 asp-asp0: Received M3UA Message (XFER:DATA)

20180813031052144 DLM3UA <0020> m3ua.c:541 asp-asp0: m3ua_rx_xfer

20180813031052144 DLM3UA <0020> m3ua.c:580 asp-asp0: m3ua_rx_xfer(): M3UA data header: opc=2=0.0.2 dpc=1=0.0.1

20180813031052144 DLSS7 <001d> osmo_ss7_hmrt.c:274 m3ua_hmdc_rx_from_l2(): found dpc=1=0.0.1 as local

20180813031052144 DLSS7 <001d> sccp_scrc.c:449 scrc_rx_mtp_xfer_ind_xua:  HDR=(CO:CODT,V=0,LEN=0),

	PART(T=Destination Reference,L=4,D=00000000),

	PART(T=Segmentation,L=4,D=00000000),

	PART(T=Data,L=6,D=000422040120)

20180813031052144 DLSCCP <001e> sccp_scoc.c:1548 Received CO:CODT for local reference 0

20180813031052144 DLSCCP <001e> sccp_scoc.c:1581 SCCP-SCOC(0)[0x612000015ca0]{ACTIVE}: Received Event RCOC-DT1.ind

20180813031052144 DLSCCP <001e> sccp_user.c:156 Delivering N-DATA.indication to SCCP User 'OsmoMSC-A'

20180813031052144 DBSSAP <0010> a_iface.c:564 N-DATA.ind(0, 00 04 22 04 01 20 )

20180813031052144 DMSC <0006> a_iface_bssap.c:80 Looking for A subscriber: conn_id 0

20180813031052144 DBSSAP <0010> a_iface_bssap.c:88 (subscr TMSI:0xc2aa7537, conn_id 0) Found A subscriber for conn_id 0

20180813031052144 DBSSAP <0010> a_iface_bssap.c:635 (subscr TMSI:0xc2aa7537, conn_id 0) Rx BSSMAP DT1 CLEAR REQUEST

20180813031052144 DBSSAP <0010> a_iface_bssap.c:221 (subscr TMSI:0xc2aa7537, conn_id 0) Rx BSSMAP CLEAR REQUEST

20180813031052144 DMM <0002> subscr_conn.c:498 Subscr_Conn(LU:3265951031)[0x612000015b20]{SUBSCR_CONN_S_AUTH_CIPH}: Received Event SUBSCR_CONN_E_MO_CLOSE

20180813031052144 DMM <0002> subscr_conn.c:110 Subscr_Conn(LU:3265951031)[0x612000015b20]{SUBSCR_CONN_S_AUTH_CIPH}: Close event, cause: SRV_OPT_NOT_SUPPORTED

20180813031052144 DMM <0002> subscr_conn.c:157 Subscr_Conn(LU:3265951031)[0x612000015b20]{SUBSCR_CONN_S_AUTH_CIPH}: state_chg to SUBSCR_CONN_S_RELEASING

20180813031052145 DMM <0002> gsm_04_08.c:208 Subscriber TMSI:0xc2aa7537: LOCATION UPDATING REJECT

20180813031052145 DMSC <0006> msc_ifaces.c:60 msc_tx 3 bytes to TMSI:0xc2aa7537 via RAN_GERAN_A

20180813031052145 DBSSAP <0010> a_iface.c:154 (subscr TMSI:0xc2aa7537, conn_id 0) Passing DTAP message (DLCI=0x00) from MSC to BSC

20180813031052145 DBSSAP <0010> a_iface.c:168 (subscr TMSI:0xc2aa7537, conn_id 0) N-DATA.req([])

20180813031052145 DLSCCP <001e> sccp_scoc.c:1615 Received SCCP User Primitive N-DATA.request)

20180813031052145 DLSCCP <001e> sccp_scoc.c:1657 SCCP-SCOC(0)[0x612000015ca0]{ACTIVE}: Received Event N-DATA.req

20180813031052145 DLSS7 <001d> sccp_scrc.c:398 sccp_scrc_rx_scoc_conn_msg:  HDR=(CO:CODT,V=0,LEN=0),

	PART(T=Routing Context,L=4,D=00000000),

	PART(T=Destination Reference,L=4,D=00000001),

	PART(T=Data,L=6,D=010003050416)

20180813031052145 DLSS7 <001d> osmo_ss7_hmrt.c:278 m3ua_hmdc_rx_from_l2(): dpc=2=0.0.2 not local, message is for routing

20180813031052145 DLSS7 <001d> osmo_ss7_hmrt.c:227 Found route for dpc=2=0.0.2: pc=0=0.0.0 mask=0x0=0.0.0 via AS as0 proto=m3ua

20180813031052145 DLSS7 <001d> osmo_ss7_hmrt.c:233 rt->dest.as proto is M3UA for dpc=2=0.0.2

20180813031052145 DLSS7 <001d> m3ua.c:507 XUA_AS(as0)[0x61200000baa0]{AS_ACTIVE}: Received Event AS-TRANSFER.req

20180813031052145 DVLR <000e> vlr_lu_fsm.c:736 vlr_lu_fsm(LU:3265951031)[0x6120000159a0]{VLR_ULA_S_WAIT_IMSI}: state_chg to VLR_ULA_S_DONE

20180813031052145 DMM <0002> vlr_lu_fsm.c:728 Subscr_Conn(LU:3265951031)[0x612000015b20]{SUBSCR_CONN_S_RELEASING}: Received Event SUBSCR_CONN_E_CN_CLOSE

20180813031052145 DMM <0002> vlr_lu_fsm.c:728 Subscr_Conn(LU:3265951031)[0x612000015b20]{SUBSCR_CONN_S_RELEASING}: Event SUBSCR_CONN_E_CN_CLOSE not permitted

20180813031052146 DBSSAP <0010> a_iface.c:419 (subscr TMSI:0xc2aa7537, conn_id 0) Tx BSSMAP CLEAR COMMAND to BSC

20180813031052146 DLSCCP <001e> sccp_scoc.c:1615 Received SCCP User Primitive N-DATA.request)

20180813031052146 DLSCCP <001e> sccp_scoc.c:1657 SCCP-SCOC(0)[0x612000015ca0]{ACTIVE}: Received Event N-DATA.req

20180813031052146 DLSS7 <001d> sccp_scrc.c:398 sccp_scrc_rx_scoc_conn_msg:  HDR=(CO:CODT,V=0,LEN=0),

	PART(T=Routing Context,L=4,D=00000000),

	PART(T=Destination Reference,L=4,D=00000001),

	PART(T=Data,L=6,D=000420040109)

20180813031052146 DLSS7 <001d> osmo_ss7_hmrt.c:278 m3ua_hmdc_rx_from_l2(): dpc=2=0.0.2 not local, message is for routing

20180813031052146 DLSS7 <001d> osmo_ss7_hmrt.c:227 Found route for dpc=2=0.0.2: pc=0=0.0.0 mask=0x0=0.0.0 via AS as0 proto=m3ua

20180813031052146 DLSS7 <001d> osmo_ss7_hmrt.c:233 rt->dest.as proto is M3UA for dpc=2=0.0.2

20180813031052146 DLSS7 <001d> m3ua.c:507 XUA_AS(as0)[0x61200000baa0]{AS_ACTIVE}: Received Event AS-TRANSFER.req

20180813031052146 DLINP <0013> stream.c:279 connected write

20180813031052146 DLINP <0013> stream.c:204 sending data

20180813031052147 DLINP <0013> stream.c:279 connected write

20180813031052147 DLINP <0013> stream.c:204 sending data

20180813031052147 DLINP <0013> stream.c:279 connected write

20180813031052147 DLINP <0013> stream.c:204 sending data

20180813031052154 DLINP <0013> stream.c:275 connected read

20180813031052154 DLINP <0013> stream.c:189 message received

20180813031052154 DLSS7 <001d> osmo_ss7.c:1549 asp-asp0: xua_cli_read_cb(): sctp_recvmsg() returned 44 (flags=0x80)

20180813031052154 DLM3UA <0020> m3ua.c:722 asp-asp0: Received M3UA Message (XFER:DATA)

20180813031052154 DLM3UA <0020> m3ua.c:541 asp-asp0: m3ua_rx_xfer

20180813031052154 DLM3UA <0020> m3ua.c:580 asp-asp0: m3ua_rx_xfer(): M3UA data header: opc=2=0.0.2 dpc=1=0.0.1

20180813031052154 DLSS7 <001d> osmo_ss7_hmrt.c:274 m3ua_hmdc_rx_from_l2(): found dpc=1=0.0.1 as local

20180813031052154 DLSS7 <001d> sccp_scrc.c:449 scrc_rx_mtp_xfer_ind_xua:  HDR=(CO:CODT,V=0,LEN=0),

	PART(T=Destination Reference,L=4,D=00000000),

	PART(T=Segmentation,L=4,D=00000000),

	PART(T=Data,L=3,D=000121)

20180813031052154 DLSCCP <001e> sccp_scoc.c:1548 Received CO:CODT for local reference 0

20180813031052155 DLSCCP <001e> sccp_scoc.c:1581 SCCP-SCOC(0)[0x612000015ca0]{ACTIVE}: Received Event RCOC-DT1.ind

20180813031052155 DLSCCP <001e> sccp_user.c:156 Delivering N-DATA.indication to SCCP User 'OsmoMSC-A'

20180813031052155 DBSSAP <0010> a_iface.c:564 N-DATA.ind(0, 00 01 21 )

20180813031052155 DMSC <0006> a_iface_bssap.c:80 Looking for A subscriber: conn_id 0

20180813031052155 DBSSAP <0010> a_iface_bssap.c:88 (subscr TMSI:0xc2aa7537, conn_id 0) Found A subscriber for conn_id 0

20180813031052155 DBSSAP <0010> a_iface_bssap.c:635 (subscr TMSI:0xc2aa7537, conn_id 0) Rx BSSMAP DT1 CLEAR COMPLETE

20180813031052155 DBSSAP <0010> a_iface_bssap.c:241 (subscr TMSI:0xc2aa7537, conn_id 0) Rx BSSMAP CLEAR COMPLETE, releasing SCCP connection

20180813031052155 DMM <0002> osmo_msc.c:315 Subscr_Conn(LU:3265951031)[0x612000015b20]{SUBSCR_CONN_S_RELEASING}: Received Event SUBSCR_CONN_E_UNUSED

20180813031052155 DMM <0002> subscr_conn.c:328 Subscr_Conn(LU:3265951031)[0x612000015b20]{SUBSCR_CONN_S_RELEASING}: state_chg to SUBSCR_CONN_S_RELEASED

20180813031052155 DMM <0002> subscr_conn.c:335 Subscr_Conn(LU:3265951031)[0x612000015b20]{SUBSCR_CONN_S_RELEASED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)

20180813031052155 DVLR <000e> subscr_conn.c:335 vlr_lu_fsm(LU:3265951031)[0x6120000159a0]{VLR_ULA_S_DONE}: Terminating (cause = OSMO_FSM_TERM_PARENT)

20180813031052155 DVLR <000e> subscr_conn.c:335 vlr_lu_fsm(LU:3265951031)[0x6120000159a0]{VLR_ULA_S_DONE}: Removing from parent Subscr_Conn(LU:3265951031)[0x612000015b20]

20180813031052155 DVLR <000e> vlr_lu_fsm.c:1382 vlr_lu_fsm(LU:3265951031)[0x6120000159a0]{VLR_ULA_S_DONE}: fsm_lu_cleanup called with cause OSMO_FSM_TERM_PARENT

20180813031052155 DVLR <000e> subscr_conn.c:335 vlr_lu_fsm(LU:3265951031)[0x6120000159a0]{VLR_ULA_S_DONE}: Freeing instance

20180813031052155 DVLR <000e> fsm.c:381 vlr_lu_fsm(LU:3265951031)[0x6120000159a0]{VLR_ULA_S_DONE}: Deallocated

20180813031052155 DRLL <0000> subscr_conn.c:449 TMSI:0xc2aa7537: Freeing subscriber connection

20180813031052155 DMM <0002> subscr_conn.c:335 Subscr_Conn(LU:3265951031)[0x612000015b20]{SUBSCR_CONN_S_RELEASED}: Freeing instance

20180813031052155 DMM <0002> fsm.c:381 Subscr_Conn(LU:3265951031)[0x612000015b20]{SUBSCR_CONN_S_RELEASED}: Deallocated

20180813031052155 DLSCCP <001e> sccp_scoc.c:1615 Received SCCP User Primitive N-DISCONNECT.request)

20180813031052156 DLSCCP <001e> sccp_scoc.c:1657 SCCP-SCOC(0)[0x612000015ca0]{ACTIVE}: Received Event N-DISCONNECT.req

20180813031052156 DLSS7 <001d> sccp_scrc.c:398 sccp_scrc_rx_scoc_conn_msg:  HDR=(CO:RELRE,V=0,LEN=0),

	PART(T=Routing Context,L=4,D=00000000),

	PART(T=Destination Reference,L=4,D=00000001),

	PART(T=Source Reference,L=4,D=00000000),

	PART(T=Cause,L=4,D=00000300)

20180813031052156 DLSS7 <001d> osmo_ss7_hmrt.c:278 m3ua_hmdc_rx_from_l2(): dpc=2=0.0.2 not local, message is for routing

20180813031052156 DLSS7 <001d> osmo_ss7_hmrt.c:227 Found route for dpc=2=0.0.2: pc=0=0.0.0 mask=0x0=0.0.0 via AS as0 proto=m3ua

20180813031052156 DLSS7 <001d> osmo_ss7_hmrt.c:233 rt->dest.as proto is M3UA for dpc=2=0.0.2

20180813031052156 DLSS7 <001d> m3ua.c:507 XUA_AS(as0)[0x61200000baa0]{AS_ACTIVE}: Received Event AS-TRANSFER.req

20180813031052156 DLSCCP <001e> sccp_scoc.c:956 SCCP-SCOC(0)[0x612000015ca0]{ACTIVE}: state_chg to DISCONN_PEND

20180813031052156 DBSSAP <0010> a_iface.c:90 (conn_id 0) Removing A-interface conn

20180813031052156 DLINP <0013> stream.c:279 connected write

20180813031052156 DLINP <0013> stream.c:204 sending data

20180813031052156 DLINP <0013> stream.c:279 connected write

20180813031052156 DLINP <0013> stream.c:204 sending data

20180813031052161 DLINP <0013> stream.c:275 connected read

20180813031052161 DLINP <0013> stream.c:189 message received

20180813031052161 DLSS7 <001d> osmo_ss7.c:1549 asp-asp0: xua_cli_read_cb(): sctp_recvmsg() returned 40 (flags=0x80)

20180813031052162 DLM3UA <0020> m3ua.c:722 asp-asp0: Received M3UA Message (XFER:DATA)

20180813031052162 DLM3UA <0020> m3ua.c:541 asp-asp0: m3ua_rx_xfer

20180813031052162 DLM3UA <0020> m3ua.c:580 asp-asp0: m3ua_rx_xfer(): M3UA data header: opc=2=0.0.2 dpc=1=0.0.1

20180813031052162 DLSS7 <001d> osmo_ss7_hmrt.c:274 m3ua_hmdc_rx_from_l2(): found dpc=1=0.0.1 as local

20180813031052162 DLSS7 <001d> sccp_scrc.c:449 scrc_rx_mtp_xfer_ind_xua:  HDR=(CO:RELCO,V=0,LEN=0),

	PART(T=Destination Reference,L=4,D=00000000),

	PART(T=Source Reference,L=4,D=00000001)

20180813031052162 DLSCCP <001e> sccp_scoc.c:1548 Received CO:RELCO for local reference 0

20180813031052162 DLSCCP <001e> sccp_scoc.c:1581 SCCP-SCOC(0)[0x612000015ca0]{DISCONN_PEND}: Received Event RCOC-RELEASE_COMPLETE.ind

20180813031052162 DLSCCP <001e> sccp_scoc.c:1060 SCCP-SCOC(0)[0x612000015ca0]{DISCONN_PEND}: state_chg to IDLE

20180813031052162 DLSCCP <001e> sccp_scoc.c:420 SCCP-SCOC(0)[0x612000015ca0]{IDLE}: Terminating (cause = OSMO_FSM_TERM_REQUEST)

20180813031052162 DLSCCP <001e> sccp_scoc.c:420 SCCP-SCOC(0)[0x612000015ca0]{IDLE}: Freeing instance

20180813031052162 DLSCCP <001e> fsm.c:381 SCCP-SCOC(0)[0x612000015ca0]{IDLE}: Deallocated

20180813031104901 DLGSUP <001b> gsup_client.c:241 GSUP ping callback (connected, got PONG)

20180813031104901 DLGSUP <001b> gsup_client.c:261 GSUP sending PING

20180813031104902 DLINP <0013> input/ipa.c:142 10.42.42.2:4222 connected write

20180813031104902 DLINP <0013> input/ipa.c:92 10.42.42.2:4222 sending data

20180813031104902 DLINP <0013> input/ipa.c:142 10.42.42.2:4222 connected write

20180813031104902 DLINP <0013> input/ipa.c:92 10.42.42.2:4222 sending data

20180813031104903 DLINP <0013> input/ipa.c:138 10.42.42.2:4222 connected read

20180813031104903 DLINP <0013> input/ipa.c:56 10.42.42.2:4222 message received

20180813031104903 DLGSUP <001b> gsup_client.c:198 GSUP receiving PONG

20180813031107680 DLINP <0013> stream.c:275 connected read

20180813031107680 DLINP <0013> stream.c:189 message received

20180813031107680 DLSS7 <001d> osmo_ss7.c:1549 asp-asp0: xua_cli_read_cb(): sctp_recvmsg() returned 88 (flags=0x80)

20180813031107681 DLM3UA <0020> m3ua.c:722 asp-asp0: Received M3UA Message (XFER:DATA)

20180813031107681 DLM3UA <0020> m3ua.c:541 asp-asp0: m3ua_rx_xfer

20180813031107681 DLM3UA <0020> m3ua.c:580 asp-asp0: m3ua_rx_xfer(): M3UA data header: opc=2=0.0.2 dpc=1=0.0.1

20180813031107681 DLSS7 <001d> osmo_ss7_hmrt.c:274 m3ua_hmdc_rx_from_l2(): found dpc=1=0.0.1 as local

20180813031107681 DLSUA <001f> sccp2sua.c:333 IEI 259: Parsed Addr: RI=2,PC=1,SSN=254

20180813031107681 DLSUA <001f> sccp2sua.c:333 IEI 258: Parsed Addr: RI=2,PC=2,SSN=254

20180813031107681 DLSS7 <001d> sccp_scrc.c:449 scrc_rx_mtp_xfer_ind_xua:  HDR=(CO:CORE,V=0,LEN=0),

	PART(T=Protocol Class,L=4,D=00000002),

	PART(T=Source Reference,L=4,D=00000002),

	PART(T=Destination Address,L=20,D=00020003800200080000000180030008000000fe),

	PART(T=Source Address,L=20,D=00020003800200080000000280030008000000fe),

	PART(T=Data,L=33,D=001f5705080009f10704ab04ab171205087009f107fffe50089910070000102525)

20180813031107681 DLSUA <001f> sua.c:385 sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 01 80 03 00 08 00 00 00 fe 

20180813031107681 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8

20180813031107681 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8

20180813031107681 DLSUA <001f> sua.c:385 sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 01 80 03 00 08 00 00 00 fe 

20180813031107681 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8

20180813031107681 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8

20180813031107682 DLSCCP <001e> fsm.c:299 SCCP-SCOC(1)[0x612000015820]{IDLE}: Allocated

20180813031107682 DLSCCP <001e> sccp_scoc.c:1548 Received CO:CORE for local reference 1

20180813031107682 DLSCCP <001e> sccp_scoc.c:1581 SCCP-SCOC(1)[0x612000015820]{IDLE}: Received Event RCOC-CONNECT.ind

20180813031107682 DLSUA <001f> sua.c:385 sua_addr_parse_part(IEI=0x0102) (20) 00 02 00 03 80 02 00 08 00 00 00 02 80 03 00 08 00 00 00 fe 

20180813031107682 DLSUA <001f> sua.c:439 SUA IEI 0x0102 pos 4/20: subpart tag 0x8002, len 8

20180813031107682 DLSUA <001f> sua.c:439 SUA IEI 0x0102 pos 12/20: subpart tag 0x8003, len 8

20180813031107682 DLSUA <001f> sua.c:385 sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 01 80 03 00 08 00 00 00 fe 

20180813031107682 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8

20180813031107682 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8

20180813031107682 DLSCCP <001e> sccp_scoc.c:775 SCCP-SCOC(1)[0x612000015820]{IDLE}: state_chg to CONN_PEND_IN

20180813031107682 DLSCCP <001e> sccp_user.c:156 Delivering N-CONNECT.indication to SCCP User 'OsmoMSC-A'

20180813031107682 DLSCCP <001e> sccp_scoc.c:1615 Received SCCP User Primitive N-CONNECT.response)

20180813031107682 DLSCCP <001e> sccp_scoc.c:1657 SCCP-SCOC(1)[0x612000015820]{CONN_PEND_IN}: Received Event N-CONNECT.resp

20180813031107682 DLSS7 <001d> sccp_scrc.c:398 sccp_scrc_rx_scoc_conn_msg:  HDR=(CO:COAK,V=0,LEN=0),

	PART(T=Routing Context,L=4,D=00000000),

	PART(T=Protocol Class,L=4,D=00000002),

	PART(T=Destination Reference,L=4,D=00000002),

	PART(T=Source Reference,L=4,D=00000001),

	PART(T=Sequence Control,L=4,D=00000000),

	PART(T=Source Address,L=20,D=00020003800200080000000180030008000000fe),

	PART(T=Destination Address,L=20,D=00020003800200080000000280030008000000fe)

20180813031107683 DLSUA <001f> sua.c:385 sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 02 80 03 00 08 00 00 00 fe 

20180813031107683 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8

20180813031107683 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8

20180813031107683 DLSUA <001f> sua.c:385 sua_addr_parse_part(IEI=0x0102) (20) 00 02 00 03 80 02 00 08 00 00 00 01 80 03 00 08 00 00 00 fe 

20180813031107683 DLSUA <001f> sua.c:439 SUA IEI 0x0102 pos 4/20: subpart tag 0x8002, len 8

20180813031107683 DLSUA <001f> sua.c:439 SUA IEI 0x0102 pos 12/20: subpart tag 0x8003, len 8

20180813031107683 DLSS7 <001d> osmo_ss7_hmrt.c:278 m3ua_hmdc_rx_from_l2(): dpc=2=0.0.2 not local, message is for routing

20180813031107683 DLSS7 <001d> osmo_ss7_hmrt.c:227 Found route for dpc=2=0.0.2: pc=0=0.0.0 mask=0x0=0.0.0 via AS as0 proto=m3ua

20180813031107683 DLSS7 <001d> osmo_ss7_hmrt.c:233 rt->dest.as proto is M3UA for dpc=2=0.0.2

20180813031107683 DLSS7 <001d> m3ua.c:507 XUA_AS(as0)[0x61200000baa0]{AS_ACTIVE}: Received Event AS-TRANSFER.req

20180813031107683 DLSCCP <001e> sccp_scoc.c:802 SCCP-SCOC(1)[0x612000015820]{CONN_PEND_IN}: state_chg to ACTIVE

20180813031107683 DBSSAP <0010> a_iface.c:535 N-CONNECT.ind(1, 00 1f 57 05 08 00 09 f1 07 04 ab 04 ab 17 12 05 08 70 09 f1 07 ff fe 50 08 99 10 07 00 00 10 25 25 )

20180813031107683 DBSSAP <0010> a_iface_bssap.c:268 Rx BSSMAP COMPLETE L3 INFO (conn_id=1)

20180813031107683 DMSC <0006> a_iface_bssap.c:55 Allocating A-Interface subscriber conn: lac 1195, conn_id 1

20180813031107683 DMM <0002> fsm.c:299 Subscr_Conn[0x6120000156a0]{SUBSCR_CONN_S_NEW}: Allocated

20180813031107684 DBSSAP <0010> a_iface_bssap.c:68 (subscr unknown, conn_id 1) A-Interface subscriber connection successfully allocated!

20180813031107684 DRLL <0000> gsm_04_08.c:1439 Dispatching 04.08 message GSM48_MT_MM_LOC_UPD_REQUEST (0x5:0x8)

20180813031107684 DMM <0002> subscr_conn.c:602 Subscr_Conn(LU:901700000015252)[0x6120000156a0]{SUBSCR_CONN_S_NEW}: Updated ID

20180813031107684 DMM <0002> gsm_04_08.c:353 LOCATION UPDATING REQUEST: MI(IMSI)=901700000015252 type=NORMAL

20180813031107684 DMM <0002> gsm_04_08.c:396 LU/new-LAC: 65534/1195

20180813031107684 DVLR <000e> fsm.c:299 vlr_lu_fsm(LU:901700000015252)[0x612000015520]{VLR_ULA_S_IDLE}: Allocated

20180813031107684 DVLR <000e> fsm.c:329 vlr_lu_fsm(LU:901700000015252)[0x612000015520]{VLR_ULA_S_IDLE}: is child of Subscr_Conn(LU:901700000015252)[0x6120000156a0]

20180813031107684 DVLR <000e> vlr_lu_fsm.c:1455 vlr_lu_fsm(LU:901700000015252)[0x612000015520]{VLR_ULA_S_IDLE}: rev=R99 net=GERAN (no Auth)

20180813031107684 DVLR <000e> vlr_lu_fsm.c:1461 vlr_lu_fsm(LU:901700000015252)[0x612000015520]{VLR_ULA_S_IDLE}: Received Event VLR_ULA_E_UPDATE_LA

20180813031107684 DVLR <000e> vlr.c:408 set IMSI on subscriber; IMSI=901700000015252 id=901700000015252

20180813031107684 DVLR <000e> vlr.c:361 New subscr, IMSI: 901700000015252

20180813031107684 DVLR <000e> vlr_lu_fsm.c:896 vlr_lu_fsm(LU:901700000015252)[0x612000015520]{VLR_ULA_S_IDLE}: vlr_loc_upd_node1()

20180813031107684 DVLR <000e> vlr_lu_fsm.c:850 vlr_lu_fsm(LU:901700000015252)[0x612000015520]{VLR_ULA_S_IDLE}: vlr_loc_upd_post_auth()

20180813031107684 DVLR <000e> vlr_lu_fsm.c:817 vlr_lu_fsm(LU:901700000015252)[0x612000015520]{VLR_ULA_S_IDLE}: vlr_loc_upd_post_ciph()

20180813031107684 DVLR <000e> vlr_lu_fsm.c:778 vlr_lu_fsm(LU:901700000015252)[0x612000015520]{VLR_ULA_S_IDLE}: vlr_loc_upd_node_4()

20180813031107685 DVLR <000e> vlr_lu_fsm.c:787 vlr_lu_fsm(LU:901700000015252)[0x612000015520]{VLR_ULA_S_IDLE}: state_chg to VLR_ULA_S_WAIT_HLR_UPD

20180813031107685 DVLR <000e> fsm.c:299 upd_hlr_vlr_fsm(LU:901700000015252)[0x6120000153a0]{UPD_HLR_VLR_S_INIT}: Allocated

20180813031107685 DVLR <000e> fsm.c:329 upd_hlr_vlr_fsm(LU:901700000015252)[0x6120000153a0]{UPD_HLR_VLR_S_INIT}: is child of vlr_lu_fsm(LU:901700000015252)[0x612000015520]

20180813031107685 DVLR <000e> vlr_lu_fsm.c:175 upd_hlr_vlr_fsm(LU:901700000015252)[0x6120000153a0]{UPD_HLR_VLR_S_INIT}: Received Event UPD_HLR_VLR_E_START

20180813031107685 DVLR <000e> vlr.c:175 GSUP tx: 04010809710000005152f2

20180813031107685 DVLR <000e> vlr_lu_fsm.c:86 upd_hlr_vlr_fsm(LU:901700000015252)[0x6120000153a0]{UPD_HLR_VLR_S_INIT}: state_chg to UPD_HLR_VLR_S_WAIT_FOR_DATA

20180813031107685 DMM <0002> subscr_conn.c:465 Subscr_Conn(LU:901700000015252)[0x6120000156a0]{SUBSCR_CONN_S_NEW}: Received Event SUBSCR_CONN_E_COMPLETE_LAYER_3

20180813031107685 DMM <0002> subscr_conn.c:117 Subscr_Conn(LU:901700000015252)[0x6120000156a0]{SUBSCR_CONN_S_NEW}: state_chg to SUBSCR_CONN_S_AUTH_CIPH

20180813031107685 DMM <0002> osmo_msc.c:315 Subscr_Conn(LU:901700000015252)[0x6120000156a0]{SUBSCR_CONN_S_AUTH_CIPH}: Received Event SUBSCR_CONN_E_UNUSED

20180813031107685 DMM <0002> subscr_conn.c:150 Subscr_Conn(LU:901700000015252)[0x6120000156a0]{SUBSCR_CONN_S_AUTH_CIPH}: Awaiting results for Auth+Ciph, overruling event SUBSCR_CONN_E_UNUSED

20180813031107685 DMSC <0006> a_iface_bssap.c:351 User has been accepted by MSC.

20180813031107685 DLINP <0013> input/ipa.c:142 10.42.42.2:4222 connected write

20180813031107685 DLINP <0013> input/ipa.c:92 10.42.42.2:4222 sending data

20180813031107686 DLINP <0013> stream.c:279 connected write

20180813031107686 DLINP <0013> stream.c:204 sending data

20180813031107686 DLINP <0013> input/ipa.c:142 10.42.42.2:4222 connected write

20180813031107686 DLINP <0013> input/ipa.c:92 10.42.42.2:4222 sending data

20180813031107686 DLINP <0013> stream.c:279 connected write

20180813031107686 DLINP <0013> stream.c:204 sending data

20180813031107688 DLINP <0013> input/ipa.c:138 10.42.42.2:4222 connected read

20180813031107688 DLINP <0013> input/ipa.c:56 10.42.42.2:4222 message received

20180813031107688 DVLR <000e> vlr.c:985 GSUP rx 28: 10010809710000005152f2080302526805071001011202012a280101

20180813031107688 DVLR <000e> vlr.c:739 IMSI:901700000015252 has MSISDN:2586

20180813031107688 DVLR <000e> vlr.c:782 SUBSCR(MSISDN:2586) Will set PDP info, context id = 1, APN = 01 2a 

vlr.c:795:3: runtime error: null pointer passed as argument 2, which is declared to never be null

20180813031107688 DVLR <000e> gsm_04_08.c:1686 SUBSCR(MSISDN:2586) VLR: update for IMSI=901700000015252 (MSISDN=2586, used=2)

20180813031107688 DVLR <000e> vlr.c:175 GSUP tx: 12010809710000005152f2

20180813031107688 DLINP <0013> input/ipa.c:142 10.42.42.2:4222 connected write

20180813031107689 DLINP <0013> input/ipa.c:92 10.42.42.2:4222 sending data

20180813031107689 DLINP <0013> input/ipa.c:138 10.42.42.2:4222 connected read

20180813031107690 DLINP <0013> input/ipa.c:56 10.42.42.2:4222 message received

20180813031107690 DVLR <000e> vlr.c:985 GSUP rx 11: 06010809710000005152f2

20180813031107690 DVLR <000e> vlr.c:829 vlr_lu_fsm(LU:901700000015252)[0x612000015520]{VLR_ULA_S_WAIT_HLR_UPD}: Received Event VLR_ULA_E_HLR_LU_RES

20180813031107690 DVLR <000e> vlr_lu_fsm.c:1178 upd_hlr_vlr_fsm(LU:901700000015252)[0x6120000153a0]{UPD_HLR_VLR_S_WAIT_FOR_DATA}: Received Event UPD_HLR_VLR_E_UPD_LOC_ACK

20180813031107690 DVLR <000e> vlr_lu_fsm.c:108 upd_hlr_vlr_fsm(LU:901700000015252)[0x6120000153a0]{UPD_HLR_VLR_S_WAIT_FOR_DATA}: state_chg to UPD_HLR_VLR_S_DONE

20180813031107690 DVLR <000e> vlr_lu_fsm.c:109 upd_hlr_vlr_fsm(LU:901700000015252)[0x6120000153a0]{UPD_HLR_VLR_S_DONE}: Terminating (cause = OSMO_FSM_TERM_REGULAR)

20180813031107690 DVLR <000e> vlr_lu_fsm.c:109 upd_hlr_vlr_fsm(LU:901700000015252)[0x6120000153a0]{UPD_HLR_VLR_S_DONE}: Removing from parent vlr_lu_fsm(LU:901700000015252)[0x612000015520]

20180813031107690 DVLR <000e> vlr_lu_fsm.c:109 upd_hlr_vlr_fsm(LU:901700000015252)[0x6120000153a0]{UPD_HLR_VLR_S_DONE}: Freeing instance

20180813031107690 DVLR <000e> fsm.c:381 upd_hlr_vlr_fsm(LU:901700000015252)[0x6120000153a0]{UPD_HLR_VLR_S_DONE}: Deallocated

20180813031107690 DVLR <000e> vlr_lu_fsm.c:109 vlr_lu_fsm(LU:901700000015252)[0x612000015520]{VLR_ULA_S_WAIT_HLR_UPD}: Received Event VLR_ULA_E_UPD_HLR_COMPL

20180813031107690 DVLR <000e> vlr_lu_fsm.c:1186 vlr_lu_fsm(LU:901700000015252)[0x612000015520]{VLR_ULA_S_WAIT_HLR_UPD}: state_chg to VLR_ULA_S_WAIT_LU_COMPL

20180813031107690 DVLR <000e> fsm.c:299 lu_compl_vlr_fsm(LU:901700000015252)[0x612000015220]{LU_COMPL_VLR_S_INIT}: Allocated

20180813031107690 DVLR <000e> fsm.c:329 lu_compl_vlr_fsm(LU:901700000015252)[0x612000015220]{LU_COMPL_VLR_S_INIT}: is child of vlr_lu_fsm(LU:901700000015252)[0x612000015520]

20180813031107690 DVLR <000e> vlr_lu_fsm.c:760 lu_compl_vlr_fsm(LU:901700000015252)[0x612000015220]{LU_COMPL_VLR_S_INIT}: Received Event LU_COMPL_VLR_E_START

20180813031107690 DVLR <000e> vlr_lu_fsm.c:414 lu_compl_vlr_fsm(LU:901700000015252)[0x612000015220]{LU_COMPL_VLR_S_INIT}: state_chg to LU_COMPL_VLR_S_WAIT_SUB_PRES

20180813031107690 DVLR <000e> fsm.c:299 sub_pres_vlr_fsm(LU:901700000015252)[0x6120000150a0]{SUB_PRES_VLR_S_INIT}: Allocated

20180813031107691 DVLR <000e> fsm.c:329 sub_pres_vlr_fsm(LU:901700000015252)[0x6120000150a0]{SUB_PRES_VLR_S_INIT}: is child of lu_compl_vlr_fsm(LU:901700000015252)[0x612000015220]

20180813031107691 DVLR <000e> vlr_lu_fsm.c:296 sub_pres_vlr_fsm(LU:901700000015252)[0x6120000150a0]{SUB_PRES_VLR_S_INIT}: Received Event SUB_PRES_VLR_E_START

20180813031107691 DVLR <000e> vlr_lu_fsm.c:213 sub_pres_vlr_fsm(LU:901700000015252)[0x6120000150a0]{SUB_PRES_VLR_S_INIT}: state_chg to SUB_PRES_VLR_S_DONE

20180813031107691 DVLR <000e> vlr_lu_fsm.c:214 sub_pres_vlr_fsm(LU:901700000015252)[0x6120000150a0]{SUB_PRES_VLR_S_DONE}: Terminating (cause = OSMO_FSM_TERM_REGULAR)

20180813031107691 DVLR <000e> vlr_lu_fsm.c:214 sub_pres_vlr_fsm(LU:901700000015252)[0x6120000150a0]{SUB_PRES_VLR_S_DONE}: Removing from parent lu_compl_vlr_fsm(LU:901700000015252)[0x612000015220]

20180813031107691 DVLR <000e> vlr_lu_fsm.c:214 sub_pres_vlr_fsm(LU:901700000015252)[0x6120000150a0]{SUB_PRES_VLR_S_DONE}: Freeing instance

20180813031107691 DVLR <000e> fsm.c:381 sub_pres_vlr_fsm(LU:901700000015252)[0x6120000150a0]{SUB_PRES_VLR_S_DONE}: Deallocated

20180813031107691 DVLR <000e> vlr_lu_fsm.c:214 lu_compl_vlr_fsm(LU:901700000015252)[0x612000015220]{LU_COMPL_VLR_S_WAIT_SUB_PRES}: Received Event LU_COMPL_VLR_E_SUB_PRES_COMPL

20180813031107691 DVLR <000e> vlr_lu_fsm.c:425 lu_compl_vlr_fsm(LU:901700000015252)[0x612000015220]{LU_COMPL_VLR_S_WAIT_SUB_PRES}: lu_compl_vlr_new_tmsi()

20180813031107691 DVLR <000e> vlr_lu_fsm.c:434 lu_compl_vlr_fsm(LU:901700000015252)[0x612000015220]{LU_COMPL_VLR_S_WAIT_SUB_PRES}: state_chg to LU_COMPL_VLR_S_WAIT_TMSI_CNF

20180813031107691 DMM <0002> gsm_04_08.c:252 -> MSISDN:2586 LOCATION UPDATE ACCEPT (TMSI = 0x512d6e01)

20180813031107691 DMSC <0006> msc_ifaces.c:60 msc_tx 14 bytes to MSISDN:2586 via RAN_GERAN_A

20180813031107691 DBSSAP <0010> a_iface.c:154 (subscr MSISDN:2586, conn_id 1) Passing DTAP message (DLCI=0x00) from MSC to BSC

20180813031107691 DBSSAP <0010> a_iface.c:168 (subscr MSISDN:2586, conn_id 1) N-DATA.req([])

20180813031107691 DLSCCP <001e> sccp_scoc.c:1615 Received SCCP User Primitive N-DATA.request)

20180813031107691 DLSCCP <001e> sccp_scoc.c:1657 SCCP-SCOC(1)[0x612000015820]{ACTIVE}: Received Event N-DATA.req

20180813031107692 DLSS7 <001d> sccp_scrc.c:398 sccp_scrc_rx_scoc_conn_msg:  HDR=(CO:CODT,V=0,LEN=0),

	PART(T=Routing Context,L=4,D=00000000),

	PART(T=Destination Reference,L=4,D=00000002),

	PART(T=Data,L=17,D=01000e050209f10704ab1705f4512d6e01)

20180813031107692 DLSS7 <001d> osmo_ss7_hmrt.c:278 m3ua_hmdc_rx_from_l2(): dpc=2=0.0.2 not local, message is for routing

20180813031107692 DLSS7 <001d> osmo_ss7_hmrt.c:227 Found route for dpc=2=0.0.2: pc=0=0.0.0 mask=0x0=0.0.0 via AS as0 proto=m3ua

20180813031107692 DLSS7 <001d> osmo_ss7_hmrt.c:233 rt->dest.as proto is M3UA for dpc=2=0.0.2

20180813031107692 DLSS7 <001d> m3ua.c:507 XUA_AS(as0)[0x61200000baa0]{AS_ACTIVE}: Received Event AS-TRANSFER.req

20180813031107692 DLINP <0013> input/ipa.c:142 10.42.42.2:4222 connected write

20180813031107692 DLINP <0013> input/ipa.c:92 10.42.42.2:4222 sending data

20180813031107692 DLINP <0013> stream.c:279 connected write

20180813031107692 DLINP <0013> stream.c:204 sending data

20180813031107693 DLINP <0013> stream.c:279 connected write

20180813031107693 DLINP <0013> stream.c:204 sending data

20180813031108149 DLINP <0013> stream.c:275 connected read

20180813031108149 DLINP <0013> stream.c:189 message received

20180813031108150 DLSS7 <001d> osmo_ss7.c:1549 asp-asp0: xua_cli_read_cb(): sctp_recvmsg() returned 44 (flags=0x80)

20180813031108150 DLM3UA <0020> m3ua.c:722 asp-asp0: Received M3UA Message (XFER:DATA)

20180813031108150 DLM3UA <0020> m3ua.c:541 asp-asp0: m3ua_rx_xfer

20180813031108150 DLM3UA <0020> m3ua.c:580 asp-asp0: m3ua_rx_xfer(): M3UA data header: opc=2=0.0.2 dpc=1=0.0.1

20180813031108150 DLSS7 <001d> osmo_ss7_hmrt.c:274 m3ua_hmdc_rx_from_l2(): found dpc=1=0.0.1 as local

20180813031108150 DLSS7 <001d> sccp_scrc.c:449 scrc_rx_mtp_xfer_ind_xua:  HDR=(CO:CODT,V=0,LEN=0),

	PART(T=Destination Reference,L=4,D=00000001),

	PART(T=Segmentation,L=4,D=00000000),

	PART(T=Data,L=5,D=010002055b)

20180813031108150 DLSCCP <001e> sccp_scoc.c:1548 Received CO:CODT for local reference 1

20180813031108150 DLSCCP <001e> sccp_scoc.c:1581 SCCP-SCOC(1)[0x612000015820]{ACTIVE}: Received Event RCOC-DT1.ind

20180813031108150 DLSCCP <001e> sccp_user.c:156 Delivering N-DATA.indication to SCCP User 'OsmoMSC-A'

20180813031108150 DBSSAP <0010> a_iface.c:564 N-DATA.ind(1, 01 00 02 05 5b )

20180813031108150 DMSC <0006> a_iface_bssap.c:80 Looking for A subscriber: conn_id 1

20180813031108150 DBSSAP <0010> a_iface_bssap.c:88 (subscr MSISDN:2586, conn_id 1) Found A subscriber for conn_id 1

20180813031108150 DBSSAP <0010> a_iface_bssap.c:674 (subscr MSISDN:2586, conn_id 1) Rx DTAP 01 00 02 05 5b 

20180813031108151 DRLL <0000> gsm_04_08.c:1439 Dispatching 04.08 message GSM48_MT_MM_TMSI_REALL_COMPL (0x5:0x1b)

20180813031108151 DMM <0002> gsm_04_08.c:1079 TMSI Reallocation Completed. Subscriber: MSISDN:2586

20180813031108151 DVLR <000e> vlr.c:1127 vlr_lu_fsm(LU:901700000015252)[0x612000015520]{VLR_ULA_S_WAIT_LU_COMPL}: Received Event VLR_ULA_E_NEW_TMSI_ACK

20180813031108151 DVLR <000e> vlr_lu_fsm.c:1221 lu_compl_vlr_fsm(LU:901700000015252)[0x612000015220]{LU_COMPL_VLR_S_WAIT_TMSI_CNF}: Received Event LU_COMPL_VLR_E_NEW_TMSI_ACK

20180813031108151 DVLR <000e> vlr_lu_fsm.c:350 lu_compl_vlr_fsm(LU:901700000015252)[0x612000015220]{LU_COMPL_VLR_S_WAIT_TMSI_CNF}: state_chg to LU_COMPL_VLR_S_DONE

20180813031108151 DVLR <000e> vlr_lu_fsm.c:384 vlr_lu_fsm(LU:901700000015252)[0x612000015520]{VLR_ULA_S_WAIT_LU_COMPL}: Received Event VLR_ULA_E_LU_COMPL_SUCCESS

20180813031108151 DVLR <000e> vlr_lu_fsm.c:768 lu_compl_vlr_fsm(LU:901700000015252)[0x612000015220]{LU_COMPL_VLR_S_DONE}: Terminating (cause = OSMO_FSM_TERM_PARENT)

20180813031108151 DVLR <000e> vlr_lu_fsm.c:768 lu_compl_vlr_fsm(LU:901700000015252)[0x612000015220]{LU_COMPL_VLR_S_DONE}: Removing from parent vlr_lu_fsm(LU:901700000015252)[0x612000015520]

20180813031108151 DVLR <000e> vlr_lu_fsm.c:768 lu_compl_vlr_fsm(LU:901700000015252)[0x612000015220]{LU_COMPL_VLR_S_DONE}: Freeing instance

20180813031108151 DVLR <000e> fsm.c:381 lu_compl_vlr_fsm(LU:901700000015252)[0x612000015220]{LU_COMPL_VLR_S_DONE}: Deallocated

20180813031108151 DVLR <000e> vlr_lu_fsm.c:736 vlr_lu_fsm(LU:901700000015252)[0x612000015520]{VLR_ULA_S_WAIT_LU_COMPL}: state_chg to VLR_ULA_S_DONE

20180813031108151 DMM <0002> vlr_lu_fsm.c:728 Subscr_Conn(LU:901700000015252)[0x6120000156a0]{SUBSCR_CONN_S_AUTH_CIPH}: Received Event SUBSCR_CONN_E_ACCEPTED

20180813031108167 DMM <0002> subscr_conn.c:145 Subscr_Conn(LU:901700000015252)[0x6120000156a0]{SUBSCR_CONN_S_AUTH_CIPH}: state_chg to SUBSCR_CONN_S_ACCEPTED

20180813031108168 DMM <0002> subscr_conn.c:215 Subscr_Conn(LU:901700000015252)[0x6120000156a0]{SUBSCR_CONN_S_ACCEPTED}: Received Event SUBSCR_CONN_E_UNUSED

20180813031108168 DMM <0002> subscr_conn.c:230 Subscr_Conn(LU:901700000015252)[0x6120000156a0]{SUBSCR_CONN_S_ACCEPTED}: state_chg to SUBSCR_CONN_S_RELEASING

20180813031108168 DBSSAP <0010> a_iface.c:419 (subscr MSISDN:2586, conn_id 1) Tx BSSMAP CLEAR COMMAND to BSC

20180813031108168 DLSCCP <001e> sccp_scoc.c:1615 Received SCCP User Primitive N-DATA.request)

20180813031108168 DLSCCP <001e> sccp_scoc.c:1657 SCCP-SCOC(1)[0x612000015820]{ACTIVE}: Received Event N-DATA.req

20180813031108168 DLSS7 <001d> sccp_scrc.c:398 sccp_scrc_rx_scoc_conn_msg:  HDR=(CO:CODT,V=0,LEN=0),

	PART(T=Routing Context,L=4,D=00000000),

	PART(T=Destination Reference,L=4,D=00000002),

	PART(T=Data,L=6,D=000420040109)

20180813031108168 DLSS7 <001d> osmo_ss7_hmrt.c:278 m3ua_hmdc_rx_from_l2(): dpc=2=0.0.2 not local, message is for routing

20180813031108168 DLSS7 <001d> osmo_ss7_hmrt.c:227 Found route for dpc=2=0.0.2: pc=0=0.0.0 mask=0x0=0.0.0 via AS as0 proto=m3ua

20180813031108168 DLSS7 <001d> osmo_ss7_hmrt.c:233 rt->dest.as proto is M3UA for dpc=2=0.0.2

20180813031108168 DLSS7 <001d> m3ua.c:507 XUA_AS(as0)[0x61200000baa0]{AS_ACTIVE}: Received Event AS-TRANSFER.req

20180813031108168 DLINP <0013> stream.c:279 connected write

20180813031108168 DLINP <0013> stream.c:204 sending data

20180813031108169 DLINP <0013> stream.c:279 connected write

20180813031108169 DLINP <0013> stream.c:204 sending data

20180813031108175 DLINP <0013> stream.c:275 connected read

20180813031108175 DLINP <0013> stream.c:189 message received

20180813031108175 DLSS7 <001d> osmo_ss7.c:1549 asp-asp0: xua_cli_read_cb(): sctp_recvmsg() returned 44 (flags=0x80)

20180813031108175 DLM3UA <0020> m3ua.c:722 asp-asp0: Received M3UA Message (XFER:DATA)

20180813031108175 DLM3UA <0020> m3ua.c:541 asp-asp0: m3ua_rx_xfer

20180813031108175 DLM3UA <0020> m3ua.c:580 asp-asp0: m3ua_rx_xfer(): M3UA data header: opc=2=0.0.2 dpc=1=0.0.1

20180813031108175 DLSS7 <001d> osmo_ss7_hmrt.c:274 m3ua_hmdc_rx_from_l2(): found dpc=1=0.0.1 as local

20180813031108175 DLSS7 <001d> sccp_scrc.c:449 scrc_rx_mtp_xfer_ind_xua:  HDR=(CO:CODT,V=0,LEN=0),

	PART(T=Destination Reference,L=4,D=00000001),

	PART(T=Segmentation,L=4,D=00000000),

	PART(T=Data,L=3,D=000121)

20180813031108175 DLSCCP <001e> sccp_scoc.c:1548 Received CO:CODT for local reference 1

20180813031108175 DLSCCP <001e> sccp_scoc.c:1581 SCCP-SCOC(1)[0x612000015820]{ACTIVE}: Received Event RCOC-DT1.ind

20180813031108175 DLSCCP <001e> sccp_user.c:156 Delivering N-DATA.indication to SCCP User 'OsmoMSC-A'

20180813031108176 DBSSAP <0010> a_iface.c:564 N-DATA.ind(1, 00 01 21 )

20180813031108176 DMSC <0006> a_iface_bssap.c:80 Looking for A subscriber: conn_id 1

20180813031108176 DBSSAP <0010> a_iface_bssap.c:88 (subscr MSISDN:2586, conn_id 1) Found A subscriber for conn_id 1

20180813031108176 DBSSAP <0010> a_iface_bssap.c:635 (subscr MSISDN:2586, conn_id 1) Rx BSSMAP DT1 CLEAR COMPLETE

20180813031108176 DBSSAP <0010> a_iface_bssap.c:241 (subscr MSISDN:2586, conn_id 1) Rx BSSMAP CLEAR COMPLETE, releasing SCCP connection

20180813031108176 DMM <0002> osmo_msc.c:315 Subscr_Conn(LU:901700000015252)[0x6120000156a0]{SUBSCR_CONN_S_RELEASING}: Received Event SUBSCR_CONN_E_UNUSED

20180813031108176 DMM <0002> subscr_conn.c:328 Subscr_Conn(LU:901700000015252)[0x6120000156a0]{SUBSCR_CONN_S_RELEASING}: state_chg to SUBSCR_CONN_S_RELEASED

20180813031108176 DMM <0002> subscr_conn.c:335 Subscr_Conn(LU:901700000015252)[0x6120000156a0]{SUBSCR_CONN_S_RELEASED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)

20180813031108176 DVLR <000e> subscr_conn.c:335 vlr_lu_fsm(LU:901700000015252)[0x612000015520]{VLR_ULA_S_DONE}: Terminating (cause = OSMO_FSM_TERM_PARENT)

20180813031108176 DVLR <000e> subscr_conn.c:335 vlr_lu_fsm(LU:901700000015252)[0x612000015520]{VLR_ULA_S_DONE}: Removing from parent Subscr_Conn(LU:901700000015252)[0x6120000156a0]

20180813031108176 DVLR <000e> vlr_lu_fsm.c:1382 vlr_lu_fsm(LU:901700000015252)[0x612000015520]{VLR_ULA_S_DONE}: fsm_lu_cleanup called with cause OSMO_FSM_TERM_PARENT

20180813031108176 DVLR <000e> subscr_conn.c:335 vlr_lu_fsm(LU:901700000015252)[0x612000015520]{VLR_ULA_S_DONE}: Freeing instance

20180813031108176 DVLR <000e> fsm.c:381 vlr_lu_fsm(LU:901700000015252)[0x612000015520]{VLR_ULA_S_DONE}: Deallocated

20180813031108176 DRLL <0000> subscr_conn.c:449 MSISDN:2586: Freeing subscriber connection

20180813031108176 DMM <0002> subscr_conn.c:335 Subscr_Conn(LU:901700000015252)[0x6120000156a0]{SUBSCR_CONN_S_RELEASED}: Freeing instance

20180813031108176 DMM <0002> fsm.c:381 Subscr_Conn(LU:901700000015252)[0x6120000156a0]{SUBSCR_CONN_S_RELEASED}: Deallocated

20180813031108176 DLSCCP <001e> sccp_scoc.c:1615 Received SCCP User Primitive N-DISCONNECT.request)

20180813031108176 DLSCCP <001e> sccp_scoc.c:1657 SCCP-SCOC(1)[0x612000015820]{ACTIVE}: Received Event N-DISCONNECT.req

20180813031108176 DLSS7 <001d> sccp_scrc.c:398 sccp_scrc_rx_scoc_conn_msg:  HDR=(CO:RELRE,V=0,LEN=0),

	PART(T=Routing Context,L=4,D=00000000),

	PART(T=Destination Reference,L=4,D=00000002),

	PART(T=Source Reference,L=4,D=00000001),

	PART(T=Cause,L=4,D=00000300)

20180813031108176 DLSS7 <001d> osmo_ss7_hmrt.c:278 m3ua_hmdc_rx_from_l2(): dpc=2=0.0.2 not local, message is for routing

20180813031108177 DLSS7 <001d> osmo_ss7_hmrt.c:227 Found route for dpc=2=0.0.2: pc=0=0.0.0 mask=0x0=0.0.0 via AS as0 proto=m3ua

20180813031108177 DLSS7 <001d> osmo_ss7_hmrt.c:233 rt->dest.as proto is M3UA for dpc=2=0.0.2

20180813031108177 DLSS7 <001d> m3ua.c:507 XUA_AS(as0)[0x61200000baa0]{AS_ACTIVE}: Received Event AS-TRANSFER.req

20180813031108177 DLSCCP <001e> sccp_scoc.c:956 SCCP-SCOC(1)[0x612000015820]{ACTIVE}: state_chg to DISCONN_PEND

20180813031108177 DBSSAP <0010> a_iface.c:90 (conn_id 1) Removing A-interface conn

20180813031108177 DLINP <0013> stream.c:279 connected write

20180813031108177 DLINP <0013> stream.c:204 sending data

20180813031108177 DLINP <0013> stream.c:279 connected write

20180813031108177 DLINP <0013> stream.c:204 sending data

20180813031108183 DLINP <0013> stream.c:275 connected read

20180813031108183 DLINP <0013> stream.c:189 message received

20180813031108183 DLSS7 <001d> osmo_ss7.c:1549 asp-asp0: xua_cli_read_cb(): sctp_recvmsg() returned 40 (flags=0x80)

20180813031108183 DLM3UA <0020> m3ua.c:722 asp-asp0: Received M3UA Message (XFER:DATA)

20180813031108183 DLM3UA <0020> m3ua.c:541 asp-asp0: m3ua_rx_xfer

20180813031108183 DLM3UA <0020> m3ua.c:580 asp-asp0: m3ua_rx_xfer(): M3UA data header: opc=2=0.0.2 dpc=1=0.0.1

20180813031108183 DLSS7 <001d> osmo_ss7_hmrt.c:274 m3ua_hmdc_rx_from_l2(): found dpc=1=0.0.1 as local

20180813031108183 DLSS7 <001d> sccp_scrc.c:449 scrc_rx_mtp_xfer_ind_xua:  HDR=(CO:RELCO,V=0,LEN=0),

	PART(T=Destination Reference,L=4,D=00000001),

	PART(T=Source Reference,L=4,D=00000002)

20180813031108184 DLSCCP <001e> sccp_scoc.c:1548 Received CO:RELCO for local reference 1

20180813031108184 DLSCCP <001e> sccp_scoc.c:1581 SCCP-SCOC(1)[0x612000015820]{DISCONN_PEND}: Received Event RCOC-RELEASE_COMPLETE.ind

20180813031108184 DLSCCP <001e> sccp_scoc.c:1060 SCCP-SCOC(1)[0x612000015820]{DISCONN_PEND}: state_chg to IDLE

20180813031108184 DLSCCP <001e> sccp_scoc.c:420 SCCP-SCOC(1)[0x612000015820]{IDLE}: Terminating (cause = OSMO_FSM_TERM_REQUEST)

20180813031108184 DLSCCP <001e> sccp_scoc.c:420 SCCP-SCOC(1)[0x612000015820]{IDLE}: Freeing instance

20180813031108184 DLSCCP <001e> fsm.c:381 SCCP-SCOC(1)[0x612000015820]{IDLE}: Deallocated

20180813031110265 DLCTRL <0018> control_if.c:530 accept()ed new CTRL connection from (r=10.42.42.1:40130<->l=10.42.42.6:4255)

20180813031110266 DLCTRL <0018> control_cmd.c:421 Command: GET subscriber-list-active-v1

20180813031110268 DLCTRL <0018> control_if.c:188 close()d CTRL connection (r=10.42.42.1:40130<->l=10.42.42.6:4255)

20180813031120861 DLINP <0013> stream.c:275 connected read

20180813031120862 DLINP <0013> stream.c:189 message received

20180813031120862 DLSS7 <001d> osmo_ss7.c:1549 asp-asp0: xua_cli_read_cb(): sctp_recvmsg() returned 84 (flags=0x80)

20180813031120862 DLM3UA <0020> m3ua.c:722 asp-asp0: Received M3UA Message (XFER:DATA)

20180813031120862 DLM3UA <0020> m3ua.c:541 asp-asp0: m3ua_rx_xfer

20180813031120862 DLM3UA <0020> m3ua.c:580 asp-asp0: m3ua_rx_xfer(): M3UA data header: opc=2=0.0.2 dpc=1=0.0.1

20180813031120862 DLSS7 <001d> osmo_ss7_hmrt.c:274 m3ua_hmdc_rx_from_l2(): found dpc=1=0.0.1 as local

20180813031120862 DLSUA <001f> sccp2sua.c:333 IEI 259: Parsed Addr: RI=2,PC=1,SSN=254

20180813031120862 DLSUA <001f> sccp2sua.c:333 IEI 258: Parsed Addr: RI=2,PC=2,SSN=254

20180813031120862 DLSS7 <001d> sccp_scrc.c:449 scrc_rx_mtp_xfer_ind_xua:  HDR=(CO:CORE,V=0,LEN=0),

	PART(T=Protocol Class,L=4,D=00000002),

	PART(T=Source Reference,L=4,D=00000003),

	PART(T=Destination Address,L=20,D=00020003800200080000000180030008000000fe),

	PART(T=Source Address,L=20,D=00020003800200080000000280030008000000fe),

	PART(T=Data,L=28,D=001a5705080009f10704ab04ab170d052478035058a605f4512d6e01)

20180813031120862 DLSUA <001f> sua.c:385 sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 01 80 03 00 08 00 00 00 fe 

20180813031120862 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8

20180813031120863 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8

20180813031120863 DLSUA <001f> sua.c:385 sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 01 80 03 00 08 00 00 00 fe 

20180813031120863 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8

20180813031120863 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8

20180813031120863 DLSCCP <001e> fsm.c:299 SCCP-SCOC(2)[0x612000012b20]{IDLE}: Allocated

20180813031120863 DLSCCP <001e> sccp_scoc.c:1548 Received CO:CORE for local reference 2

20180813031120863 DLSCCP <001e> sccp_scoc.c:1581 SCCP-SCOC(2)[0x612000012b20]{IDLE}: Received Event RCOC-CONNECT.ind

20180813031120863 DLSUA <001f> sua.c:385 sua_addr_parse_part(IEI=0x0102) (20) 00 02 00 03 80 02 00 08 00 00 00 02 80 03 00 08 00 00 00 fe 

20180813031120863 DLSUA <001f> sua.c:439 SUA IEI 0x0102 pos 4/20: subpart tag 0x8002, len 8

20180813031120863 DLSUA <001f> sua.c:439 SUA IEI 0x0102 pos 12/20: subpart tag 0x8003, len 8

20180813031120863 DLSUA <001f> sua.c:385 sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 01 80 03 00 08 00 00 00 fe 

20180813031120863 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8

20180813031120863 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8

20180813031120863 DLSCCP <001e> sccp_scoc.c:775 SCCP-SCOC(2)[0x612000012b20]{IDLE}: state_chg to CONN_PEND_IN

20180813031120863 DLSCCP <001e> sccp_user.c:156 Delivering N-CONNECT.indication to SCCP User 'OsmoMSC-A'

20180813031120863 DLSCCP <001e> sccp_scoc.c:1615 Received SCCP User Primitive N-CONNECT.response)

20180813031120863 DLSCCP <001e> sccp_scoc.c:1657 SCCP-SCOC(2)[0x612000012b20]{CONN_PEND_IN}: Received Event N-CONNECT.resp

20180813031120864 DLSS7 <001d> sccp_scrc.c:398 sccp_scrc_rx_scoc_conn_msg:  HDR=(CO:COAK,V=0,LEN=0),

	PART(T=Routing Context,L=4,D=00000000),

	PART(T=Protocol Class,L=4,D=00000002),

	PART(T=Destination Reference,L=4,D=00000003),

	PART(T=Source Reference,L=4,D=00000002),

	PART(T=Sequence Control,L=4,D=00000000),

	PART(T=Source Address,L=20,D=00020003800200080000000180030008000000fe),

	PART(T=Destination Address,L=20,D=00020003800200080000000280030008000000fe)

20180813031120864 DLSUA <001f> sua.c:385 sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 02 80 03 00 08 00 00 00 fe 

20180813031120864 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8

20180813031120864 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8

20180813031120864 DLSUA <001f> sua.c:385 sua_addr_parse_part(IEI=0x0102) (20) 00 02 00 03 80 02 00 08 00 00 00 01 80 03 00 08 00 00 00 fe 

20180813031120864 DLSUA <001f> sua.c:439 SUA IEI 0x0102 pos 4/20: subpart tag 0x8002, len 8

20180813031120864 DLSUA <001f> sua.c:439 SUA IEI 0x0102 pos 12/20: subpart tag 0x8003, len 8

20180813031120864 DLSS7 <001d> osmo_ss7_hmrt.c:278 m3ua_hmdc_rx_from_l2(): dpc=2=0.0.2 not local, message is for routing

20180813031120864 DLSS7 <001d> osmo_ss7_hmrt.c:227 Found route for dpc=2=0.0.2: pc=0=0.0.0 mask=0x0=0.0.0 via AS as0 proto=m3ua

20180813031120864 DLSS7 <001d> osmo_ss7_hmrt.c:233 rt->dest.as proto is M3UA for dpc=2=0.0.2

20180813031120864 DLSS7 <001d> m3ua.c:507 XUA_AS(as0)[0x61200000baa0]{AS_ACTIVE}: Received Event AS-TRANSFER.req

20180813031120864 DLSCCP <001e> sccp_scoc.c:802 SCCP-SCOC(2)[0x612000012b20]{CONN_PEND_IN}: state_chg to ACTIVE

20180813031120864 DBSSAP <0010> a_iface.c:535 N-CONNECT.ind(2, 00 1a 57 05 08 00 09 f1 07 04 ab 04 ab 17 0d 05 24 78 03 50 58 a6 05 f4 51 2d 6e 01 )

20180813031120865 DBSSAP <0010> a_iface_bssap.c:268 Rx BSSMAP COMPLETE L3 INFO (conn_id=2)

20180813031120865 DMSC <0006> a_iface_bssap.c:55 Allocating A-Interface subscriber conn: lac 1195, conn_id 2

20180813031120865 DMM <0002> fsm.c:299 Subscr_Conn[0x6120000129a0]{SUBSCR_CONN_S_NEW}: Allocated

20180813031120865 DBSSAP <0010> a_iface_bssap.c:68 (subscr unknown, conn_id 2) A-Interface subscriber connection successfully allocated!

20180813031120865 DRLL <0000> gsm_04_08.c:1439 Dispatching 04.08 message GSM48_MT_MM_CM_SERV_REQ (0x5:0x24)

20180813031120865 DMM <0002> gsm_04_08.c:731 <- CM SERVICE REQUEST serv_type=0x08 MI(TMSI)=1361931777

20180813031120865 DMM <0002> subscr_conn.c:602 Subscr_Conn(CM_SERVICE_REQ:1361931777)[0x6120000129a0]{SUBSCR_CONN_S_NEW}: Updated ID

20180813031120865 DVLR <000e> fsm.c:299 Process_Access_Request_VLR(CM_SERVICE_REQ:1361931777)[0x612000012820]{PR_ARQ_S_INIT}: Allocated

20180813031120865 DVLR <000e> fsm.c:329 Process_Access_Request_VLR(CM_SERVICE_REQ:1361931777)[0x612000012820]{PR_ARQ_S_INIT}: is child of Subscr_Conn(CM_SERVICE_REQ:1361931777)[0x6120000129a0]

20180813031120865 DVLR <000e> vlr_access_req_fsm.c:669 Process_Access_Request_VLR(CM_SERVICE_REQ:1361931777)[0x612000012820]{PR_ARQ_S_INIT}: rev=R99 net=GERAN (no Auth)

20180813031120865 DVLR <000e> vlr_access_req_fsm.c:693 Process_Access_Request_VLR(CM_SERVICE_REQ:1361931777)[0x612000012820]{PR_ARQ_S_INIT}: Received Event PR_ARQ_E_START

20180813031120865 DVLR <000e> vlr_access_req_fsm.c:328 Process_Access_Request_VLR(CM_SERVICE_REQ:1361931777)[0x612000012820]{PR_ARQ_S_INIT}: proc_arq_vlr_fn_post_imsi()

20180813031120865 DVLR <000e> vlr_access_req_fsm.c:280 Process_Access_Request_VLR(CM_SERVICE_REQ:1361931777)[0x612000012820]{PR_ARQ_S_INIT}: _proc_arq_vlr_node2()

20180813031120865 DVLR <000e> vlr_access_req_fsm.c:246 Process_Access_Request_VLR(CM_SERVICE_REQ:1361931777)[0x612000012820]{PR_ARQ_S_INIT}: _proc_arq_vlr_node2_post_ciph()

20180813031120865 DVLR <000e> vlr_access_req_fsm.c:218 Process_Access_Request_VLR(CM_SERVICE_REQ:1361931777)[0x612000012820]{PR_ARQ_S_INIT}: _proc_arq_vlr_node2_post_vlr()

20180813031120865 DVLR <000e> vlr_access_req_fsm.c:203 Process_Access_Request_VLR(CM_SERVICE_REQ:1361931777)[0x612000012820]{PR_ARQ_S_INIT}: _proc_arq_vlr_post_pres()

20180813031120865 DVLR <000e> vlr_access_req_fsm.c:187 Process_Access_Request_VLR(CM_SERVICE_REQ:1361931777)[0x612000012820]{PR_ARQ_S_INIT}: _proc_arq_vlr_post_trace()

20180813031120866 DVLR <000e> vlr_access_req_fsm.c:165 Process_Access_Request_VLR(CM_SERVICE_REQ:1361931777)[0x612000012820]{PR_ARQ_S_INIT}: _proc_arq_vlr_post_imei()

20180813031120866 DVLR <000e> vlr_access_req_fsm.c:178 Process_Access_Request_VLR(CM_SERVICE_REQ:1361931777)[0x612000012820]{PR_ARQ_S_INIT}: proc_arq_fsm_done(PASSED)

20180813031120866 DVLR <000e> vlr_access_req_fsm.c:101 Process_Access_Request_VLR(CM_SERVICE_REQ:1361931777)[0x612000012820]{PR_ARQ_S_INIT}: state_chg to PR_ARQ_S_DONE

20180813031120866 DVLR <000e> vlr_access_req_fsm.c:110 Process_Access_Request_VLR(CM_SERVICE_REQ:1361931777)[0x612000012820]{PR_ARQ_S_DONE}: Process Access Request result: PASSED

20180813031120866 DMM <0002> msc_ifaces.c:103 -> CM SERVICE ACCEPT MSISDN:2586

20180813031120866 DMSC <0006> msc_ifaces.c:60 msc_tx 2 bytes to MSISDN:2586 via RAN_GERAN_A

20180813031120866 DBSSAP <0010> a_iface.c:154 (subscr MSISDN:2586, conn_id 2) Passing DTAP message (DLCI=0x00) from MSC to BSC

20180813031120866 DBSSAP <0010> a_iface.c:168 (subscr MSISDN:2586, conn_id 2) N-DATA.req([])

20180813031120866 DLSCCP <001e> sccp_scoc.c:1615 Received SCCP User Primitive N-DATA.request)

20180813031120866 DLSCCP <001e> sccp_scoc.c:1657 SCCP-SCOC(2)[0x612000012b20]{ACTIVE}: Received Event N-DATA.req

20180813031120866 DLSS7 <001d> sccp_scrc.c:398 sccp_scrc_rx_scoc_conn_msg:  HDR=(CO:CODT,V=0,LEN=0),

	PART(T=Routing Context,L=4,D=00000000),

	PART(T=Destination Reference,L=4,D=00000003),

	PART(T=Data,L=5,D=0100020521)

20180813031120866 DLSS7 <001d> osmo_ss7_hmrt.c:278 m3ua_hmdc_rx_from_l2(): dpc=2=0.0.2 not local, message is for routing

20180813031120866 DLSS7 <001d> osmo_ss7_hmrt.c:227 Found route for dpc=2=0.0.2: pc=0=0.0.0 mask=0x0=0.0.0 via AS as0 proto=m3ua

20180813031120866 DLSS7 <001d> osmo_ss7_hmrt.c:233 rt->dest.as proto is M3UA for dpc=2=0.0.2

20180813031120867 DLSS7 <001d> m3ua.c:507 XUA_AS(as0)[0x61200000baa0]{AS_ACTIVE}: Received Event AS-TRANSFER.req

20180813031120867 DMM <0002> vlr_access_req_fsm.c:149 Subscr_Conn(CM_SERVICE_REQ:1361931777)[0x6120000129a0]{SUBSCR_CONN_S_NEW}: Received Event SUBSCR_CONN_E_ACCEPTED

20180813031120881 DMM <0002> subscr_conn.c:122 Subscr_Conn(CM_SERVICE_REQ:1361931777)[0x6120000129a0]{SUBSCR_CONN_S_NEW}: state_chg to SUBSCR_CONN_S_ACCEPTED

20180813031120881 DMM <0002> subscr_conn.c:178 Subscr_Conn(CM_SERVICE_REQ:1361931777)[0x6120000129a0]{SUBSCR_CONN_S_ACCEPTED}: subscr_conn_fsm_has_active_transactions: still awaiting first request after a CM Service Request

20180813031120882 DMM <0002> subscr_conn.c:465 Subscr_Conn(CM_SERVICE_REQ:1361931777)[0x6120000129a0]{SUBSCR_CONN_S_ACCEPTED}: Received Event SUBSCR_CONN_E_COMPLETE_LAYER_3

20180813031120882 DMM <0002> subscr_conn.c:465 Subscr_Conn(CM_SERVICE_REQ:1361931777)[0x6120000129a0]{SUBSCR_CONN_S_ACCEPTED}: Event SUBSCR_CONN_E_COMPLETE_LAYER_3 not permitted

20180813031120882 DMSC <0006> a_iface_bssap.c:351 User has been accepted by MSC.

20180813031120882 DLINP <0013> stream.c:279 connected write

20180813031120882 DLINP <0013> stream.c:204 sending data

20180813031120882 DLINP <0013> stream.c:279 connected write

20180813031120882 DLINP <0013> stream.c:204 sending data

20180813031120882 DLINP <0013> stream.c:279 connected write

20180813031120882 DLINP <0013> stream.c:204 sending data

20180813031121566 DLINP <0013> stream.c:275 connected read

20180813031121567 DLINP <0013> stream.c:189 message received

20180813031121567 DLSS7 <001d> osmo_ss7.c:1549 asp-asp0: xua_cli_read_cb(): sctp_recvmsg() returned 72 (flags=0x80)

20180813031121567 DLM3UA <0020> m3ua.c:722 asp-asp0: Received M3UA Message (XFER:DATA)

20180813031121567 DLM3UA <0020> m3ua.c:541 asp-asp0: m3ua_rx_xfer

20180813031121567 DLM3UA <0020> m3ua.c:580 asp-asp0: m3ua_rx_xfer(): M3UA data header: opc=2=0.0.2 dpc=1=0.0.1

20180813031121567 DLSS7 <001d> osmo_ss7_hmrt.c:274 m3ua_hmdc_rx_from_l2(): found dpc=1=0.0.1 as local

20180813031121567 DLSS7 <001d> sccp_scrc.c:449 scrc_rx_mtp_xfer_ind_xua:  HDR=(CO:CODT,V=0,LEN=0),

	PART(T=Destination Reference,L=4,D=00000002),

	PART(T=Segmentation,L=4,D=00000000),

	PART(T=Data,L=31,D=01001c0b7b1c15a11302010002013b300b04010f0406aa510c061b017f0100)

20180813031121567 DLSCCP <001e> sccp_scoc.c:1548 Received CO:CODT for local reference 2

20180813031121567 DLSCCP <001e> sccp_scoc.c:1581 SCCP-SCOC(2)[0x612000012b20]{ACTIVE}: Received Event RCOC-DT1.ind

20180813031121568 DLSCCP <001e> sccp_user.c:156 Delivering N-DATA.indication to SCCP User 'OsmoMSC-A'

20180813031121568 DBSSAP <0010> a_iface.c:564 N-DATA.ind(2, 01 00 1c 0b 7b 1c 15 a1 13 02 01 00 02 01 3b 30 0b 04 01 0f 04 06 aa 51 0c 06 1b 01 7f 01 00 )

20180813031121568 DMSC <0006> a_iface_bssap.c:80 Looking for A subscriber: conn_id 2

20180813031121568 DBSSAP <0010> a_iface_bssap.c:88 (subscr MSISDN:2586, conn_id 2) Found A subscriber for conn_id 2

20180813031121568 DBSSAP <0010> a_iface_bssap.c:674 (subscr MSISDN:2586, conn_id 2) Rx DTAP 01 00 1c 0b 7b 1c 15 a1 13 02 01 00 02 01 3b 30 0b 04 01 0f 04 06 aa 51 0c 06 1b 01 7f 01 00 

20180813031121568 DRLL <0000> gsm_04_08.c:1439 Dispatching 04.08 message GSM0480_MTYPE_REGISTER (0xb:0x3b)

20180813031121568 DMM <0002> gsm_09_11.c:76 Received SS/USSD data (trans_id=8, msg_type=GSM0480_MTYPE_REGISTER)

20180813031121568 DMM <0002> gsm_09_11.c:102  -> (new transaction)

20180813031121568 DCC <0001> transaction.c:97 (ti 08 sub MSISDN:2586 callref 20000001) New transaction

20180813031121568 DMM <0002> gsm_04_08.c:1320 MSISDN:2586: rx msg GSM0480_MTYPE_REGISTER: received_cm_service_request changes to false

20180813031121568 DMM <0002> subscr_conn.c:538 Subscr_Conn(CM_SERVICE_REQ:1361931777)[0x6120000129a0]{SUBSCR_CONN_S_ACCEPTED}: Received Event SUBSCR_CONN_E_COMMUNICATING

20180813031121568 DMM <0002> subscr_conn.c:222 Subscr_Conn(CM_SERVICE_REQ:1361931777)[0x6120000129a0]{SUBSCR_CONN_S_ACCEPTED}: state_chg to SUBSCR_CONN_S_COMMUNICATING

20180813031121568 DLINP <0013> input/ipa.c:142 10.42.42.2:4222 connected write

20180813031121568 DLINP <0013> input/ipa.c:92 10.42.42.2:4222 sending data

20180813031121569 DLINP <0013> input/ipa.c:142 10.42.42.2:4222 connected write

20180813031121569 DLINP <0013> input/ipa.c:92 10.42.42.2:4222 sending data

20180813031121570 DLINP <0013> input/ipa.c:138 10.42.42.2:4222 connected read

20180813031121570 DLINP <0013> input/ipa.c:56 10.42.42.2:4222 message received

20180813031121570 DVLR <000e> vlr.c:985 GSUP rx 30: 22010809710000005152f23004200000013101033508a306020100020112

20180813031121570 DMSC <0006> gsm_04_08.c:1709 Routed to GSM 09.11 SS/USSD handler

20180813031121571 DMSC <0006> msc_ifaces.c:60 msc_tx 12 bytes to MSISDN:2586 via RAN_GERAN_A

20180813031121571 DBSSAP <0010> a_iface.c:154 (subscr MSISDN:2586, conn_id 2) Passing DTAP message (DLCI=0x00) from MSC to BSC

20180813031121571 DBSSAP <0010> a_iface.c:168 (subscr MSISDN:2586, conn_id 2) N-DATA.req([])

20180813031121571 DLSCCP <001e> sccp_scoc.c:1615 Received SCCP User Primitive N-DATA.request)

20180813031121571 DLSCCP <001e> sccp_scoc.c:1657 SCCP-SCOC(2)[0x612000012b20]{ACTIVE}: Received Event N-DATA.req

20180813031121571 DLSS7 <001d> sccp_scrc.c:398 sccp_scrc_rx_scoc_conn_msg:  HDR=(CO:CODT,V=0,LEN=0),

	PART(T=Routing Context,L=4,D=00000000),

	PART(T=Destination Reference,L=4,D=00000003),

	PART(T=Data,L=15,D=01000c8b2a1c08a306020100020112)

20180813031121571 DLSS7 <001d> osmo_ss7_hmrt.c:278 m3ua_hmdc_rx_from_l2(): dpc=2=0.0.2 not local, message is for routing

20180813031121571 DLSS7 <001d> osmo_ss7_hmrt.c:227 Found route for dpc=2=0.0.2: pc=0=0.0.0 mask=0x0=0.0.0 via AS as0 proto=m3ua

20180813031121571 DLSS7 <001d> osmo_ss7_hmrt.c:233 rt->dest.as proto is M3UA for dpc=2=0.0.2

20180813031121571 DLSS7 <001d> m3ua.c:507 XUA_AS(as0)[0x61200000baa0]{AS_ACTIVE}: Received Event AS-TRANSFER.req

20180813031121572 DMM <0002> osmo_msc.c:315 Subscr_Conn(CM_SERVICE_REQ:1361931777)[0x6120000129a0]{SUBSCR_CONN_S_COMMUNICATING}: Received Event SUBSCR_CONN_E_UNUSED

20180813031121572 DMM <0002> subscr_conn.c:250 Subscr_Conn(CM_SERVICE_REQ:1361931777)[0x6120000129a0]{SUBSCR_CONN_S_COMMUNICATING}: state_chg to SUBSCR_CONN_S_RELEASING

20180813031121572 DBSSAP <0010> a_iface.c:419 (subscr MSISDN:2586, conn_id 2) Tx BSSMAP CLEAR COMMAND to BSC

20180813031121572 DLSCCP <001e> sccp_scoc.c:1615 Received SCCP User Primitive N-DATA.request)

20180813031121572 DLSCCP <001e> sccp_scoc.c:1657 SCCP-SCOC(2)[0x612000012b20]{ACTIVE}: Received Event N-DATA.req

20180813031121572 DLSS7 <001d> sccp_scrc.c:398 sccp_scrc_rx_scoc_conn_msg:  HDR=(CO:CODT,V=0,LEN=0),

	PART(T=Routing Context,L=4,D=00000000),

	PART(T=Destination Reference,L=4,D=00000003),

	PART(T=Data,L=6,D=000420040109)

20180813031121572 DLSS7 <001d> osmo_ss7_hmrt.c:278 m3ua_hmdc_rx_from_l2(): dpc=2=0.0.2 not local, message is for routing

20180813031121572 DLSS7 <001d> osmo_ss7_hmrt.c:227 Found route for dpc=2=0.0.2: pc=0=0.0.0 mask=0x0=0.0.0 via AS as0 proto=m3ua

20180813031121572 DLSS7 <001d> osmo_ss7_hmrt.c:233 rt->dest.as proto is M3UA for dpc=2=0.0.2

20180813031121572 DLSS7 <001d> m3ua.c:507 XUA_AS(as0)[0x61200000baa0]{AS_ACTIVE}: Received Event AS-TRANSFER.req

20180813031121572 DLINP <0013> stream.c:279 connected write

20180813031121572 DLINP <0013> stream.c:204 sending data

20180813031121573 DLINP <0013> stream.c:279 connected write

20180813031121573 DLINP <0013> stream.c:204 sending data

20180813031121573 DLINP <0013> stream.c:279 connected write

20180813031121573 DLINP <0013> stream.c:204 sending data

20180813031121580 DLINP <0013> stream.c:275 connected read

20180813031121581 DLINP <0013> stream.c:189 message received

20180813031121581 DLSS7 <001d> osmo_ss7.c:1549 asp-asp0: xua_cli_read_cb(): sctp_recvmsg() returned 44 (flags=0x80)

20180813031121581 DLM3UA <0020> m3ua.c:722 asp-asp0: Received M3UA Message (XFER:DATA)

20180813031121581 DLM3UA <0020> m3ua.c:541 asp-asp0: m3ua_rx_xfer

20180813031121581 DLM3UA <0020> m3ua.c:580 asp-asp0: m3ua_rx_xfer(): M3UA data header: opc=2=0.0.2 dpc=1=0.0.1

20180813031121581 DLSS7 <001d> osmo_ss7_hmrt.c:274 m3ua_hmdc_rx_from_l2(): found dpc=1=0.0.1 as local

20180813031121581 DLSS7 <001d> sccp_scrc.c:449 scrc_rx_mtp_xfer_ind_xua:  HDR=(CO:CODT,V=0,LEN=0),

	PART(T=Destination Reference,L=4,D=00000002),

	PART(T=Segmentation,L=4,D=00000000),

	PART(T=Data,L=3,D=000121)

20180813031121581 DLSCCP <001e> sccp_scoc.c:1548 Received CO:CODT for local reference 2

20180813031121581 DLSCCP <001e> sccp_scoc.c:1581 SCCP-SCOC(2)[0x612000012b20]{ACTIVE}: Received Event RCOC-DT1.ind

20180813031121581 DLSCCP <001e> sccp_user.c:156 Delivering N-DATA.indication to SCCP User 'OsmoMSC-A'

20180813031121581 DBSSAP <0010> a_iface.c:564 N-DATA.ind(2, 00 01 21 )

20180813031121581 DMSC <0006> a_iface_bssap.c:80 Looking for A subscriber: conn_id 2

20180813031121581 DBSSAP <0010> a_iface_bssap.c:88 (subscr MSISDN:2586, conn_id 2) Found A subscriber for conn_id 2

20180813031121581 DBSSAP <0010> a_iface_bssap.c:635 (subscr MSISDN:2586, conn_id 2) Rx BSSMAP DT1 CLEAR COMPLETE

20180813031121582 DBSSAP <0010> a_iface_bssap.c:241 (subscr MSISDN:2586, conn_id 2) Rx BSSMAP CLEAR COMPLETE, releasing SCCP connection

20180813031121582 DMM <0002> osmo_msc.c:315 Subscr_Conn(CM_SERVICE_REQ:1361931777)[0x6120000129a0]{SUBSCR_CONN_S_RELEASING}: Received Event SUBSCR_CONN_E_UNUSED

20180813031121582 DMM <0002> subscr_conn.c:328 Subscr_Conn(CM_SERVICE_REQ:1361931777)[0x6120000129a0]{SUBSCR_CONN_S_RELEASING}: state_chg to SUBSCR_CONN_S_RELEASED

20180813031121582 DMM <0002> subscr_conn.c:335 Subscr_Conn(CM_SERVICE_REQ:1361931777)[0x6120000129a0]{SUBSCR_CONN_S_RELEASED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)

20180813031121582 DVLR <000e> subscr_conn.c:335 Process_Access_Request_VLR(CM_SERVICE_REQ:1361931777)[0x612000012820]{PR_ARQ_S_DONE}: Terminating (cause = OSMO_FSM_TERM_PARENT)

20180813031121582 DVLR <000e> subscr_conn.c:335 Process_Access_Request_VLR(CM_SERVICE_REQ:1361931777)[0x612000012820]{PR_ARQ_S_DONE}: Removing from parent Subscr_Conn(CM_SERVICE_REQ:1361931777)[0x6120000129a0]

20180813031121582 DVLR <000e> subscr_conn.c:335 Process_Access_Request_VLR(CM_SERVICE_REQ:1361931777)[0x612000012820]{PR_ARQ_S_DONE}: Freeing instance

20180813031121582 DVLR <000e> fsm.c:381 Process_Access_Request_VLR(CM_SERVICE_REQ:1361931777)[0x612000012820]{PR_ARQ_S_DONE}: Deallocated

20180813031121582 DRLL <0000> subscr_conn.c:449 MSISDN:2586: Freeing subscriber connection

20180813031121582 DMM <0002> subscr_conn.c:335 Subscr_Conn(CM_SERVICE_REQ:1361931777)[0x6120000129a0]{SUBSCR_CONN_S_RELEASED}: Freeing instance

20180813031121582 DMM <0002> fsm.c:381 Subscr_Conn(CM_SERVICE_REQ:1361931777)[0x6120000129a0]{SUBSCR_CONN_S_RELEASED}: Deallocated

20180813031121582 DLSCCP <001e> sccp_scoc.c:1615 Received SCCP User Primitive N-DISCONNECT.request)

20180813031121582 DLSCCP <001e> sccp_scoc.c:1657 SCCP-SCOC(2)[0x612000012b20]{ACTIVE}: Received Event N-DISCONNECT.req

20180813031121582 DLSS7 <001d> sccp_scrc.c:398 sccp_scrc_rx_scoc_conn_msg:  HDR=(CO:RELRE,V=0,LEN=0),

	PART(T=Routing Context,L=4,D=00000000),

	PART(T=Destination Reference,L=4,D=00000003),

	PART(T=Source Reference,L=4,D=00000002),

	PART(T=Cause,L=4,D=00000300)

20180813031121582 DLSS7 <001d> osmo_ss7_hmrt.c:278 m3ua_hmdc_rx_from_l2(): dpc=2=0.0.2 not local, message is for routing

20180813031121582 DLSS7 <001d> osmo_ss7_hmrt.c:227 Found route for dpc=2=0.0.2: pc=0=0.0.0 mask=0x0=0.0.0 via AS as0 proto=m3ua

20180813031121582 DLSS7 <001d> osmo_ss7_hmrt.c:233 rt->dest.as proto is M3UA for dpc=2=0.0.2

20180813031121582 DLSS7 <001d> m3ua.c:507 XUA_AS(as0)[0x61200000baa0]{AS_ACTIVE}: Received Event AS-TRANSFER.req

20180813031121582 DLSCCP <001e> sccp_scoc.c:956 SCCP-SCOC(2)[0x612000012b20]{ACTIVE}: state_chg to DISCONN_PEND

20180813031121583 DBSSAP <0010> a_iface.c:90 (conn_id 2) Removing A-interface conn

20180813031121583 DLINP <0013> stream.c:279 connected write

20180813031121583 DLINP <0013> stream.c:204 sending data

20180813031121583 DLINP <0013> stream.c:279 connected write

20180813031121583 DLINP <0013> stream.c:204 sending data

20180813031121587 DLINP <0013> stream.c:275 connected read

20180813031121588 DLINP <0013> stream.c:189 message received

20180813031121588 DLSS7 <001d> osmo_ss7.c:1549 asp-asp0: xua_cli_read_cb(): sctp_recvmsg() returned 40 (flags=0x80)

20180813031121588 DLM3UA <0020> m3ua.c:722 asp-asp0: Received M3UA Message (XFER:DATA)

20180813031121588 DLM3UA <0020> m3ua.c:541 asp-asp0: m3ua_rx_xfer

20180813031121588 DLM3UA <0020> m3ua.c:580 asp-asp0: m3ua_rx_xfer(): M3UA data header: opc=2=0.0.2 dpc=1=0.0.1

20180813031121588 DLSS7 <001d> osmo_ss7_hmrt.c:274 m3ua_hmdc_rx_from_l2(): found dpc=1=0.0.1 as local

20180813031121588 DLSS7 <001d> sccp_scrc.c:449 scrc_rx_mtp_xfer_ind_xua:  HDR=(CO:RELCO,V=0,LEN=0),

	PART(T=Destination Reference,L=4,D=00000002),

	PART(T=Source Reference,L=4,D=00000003)

20180813031121588 DLSCCP <001e> sccp_scoc.c:1548 Received CO:RELCO for local reference 2

20180813031121588 DLSCCP <001e> sccp_scoc.c:1581 SCCP-SCOC(2)[0x612000012b20]{DISCONN_PEND}: Received Event RCOC-RELEASE_COMPLETE.ind

20180813031121588 DLSCCP <001e> sccp_scoc.c:1060 SCCP-SCOC(2)[0x612000012b20]{DISCONN_PEND}: state_chg to IDLE

20180813031121588 DLSCCP <001e> sccp_scoc.c:420 SCCP-SCOC(2)[0x612000012b20]{IDLE}: Terminating (cause = OSMO_FSM_TERM_REQUEST)

20180813031121588 DLSCCP <001e> sccp_scoc.c:420 SCCP-SCOC(2)[0x612000012b20]{IDLE}: Freeing instance

20180813031121588 DLSCCP <001e> fsm.c:381 SCCP-SCOC(2)[0x612000012b20]{IDLE}: Deallocated

20180813031122268 DLINP <0013> input/ipa.c:138 10.42.42.2:4222 connected read

20180813031122268 DLINP <0013> input/ipa.c:56 10.42.42.2:4222 message received

20180813031122268 DLINP <0013> input/ipa.c:69 10.42.42.2:4222 connection closed with server

20180813031122268 DLGSUP <001b> gsup_client.c:131 GSUP link to 10.42.42.2:4222 DOWN

20180813031122599 DLINP <0013> stream.c:275 connected read

20180813031122599 DLINP <0013> stream.c:189 message received

20180813031122599 DLSS7 <001d> osmo_ss7.c:1549 asp-asp0: xua_cli_read_cb(): sctp_recvmsg() returned 12 (flags=0x8080)

20180813031122599 DLSS7 <001d> osmo_ss7.c:1345 asp-asp0: xUA CLNT SCTP NOTIFICATION 32773 flags=0x0

20180813031122599 DLSS7 <001d> osmo_ss7.c:1358 asp-asp0: xUA CLNT SHUTDOWN_EVENT

20180813031122599 DLSS7 <001d> osmo_ss7.c:1491 XUA_ASP(asp0)[0x612000016720]{ASP_ACTIVE}: Received Event SCTP-COMM_DOWN.ind

20180813031122599 DLSS7 <001d> xua_asp_fsm.c:591 XUA_ASP(asp0)[0x612000016720]{ASP_ACTIVE}: state_chg to ASP_DOWN

20180813031122599 DLSS7 <001d> xua_asp_fsm.c:404 XUA_AS(as0)[0x61200000baa0]{AS_ACTIVE}: Received Event ASPAS-ASP_DOWN.ind

20180813031122599 DLSS7 <001d> xua_as_fsm.c:263 XUA_AS(as0)[0x61200000baa0]{AS_ACTIVE}: state_chg to AS_PENDING

20180813031122599 DLSS7 <001d> xua_default_lm_fsm.c:350 xua_default_lm(asp0)[0x612000016a20]{ACTIVE}: Received primitive M-ASP_DOWN.indication

20180813031122599 DLSS7 <001d> xua_default_lm_fsm.c:353 xua_default_lm(asp0)[0x612000016a20]{ACTIVE}: Ignoring primitive M-ASP_DOWN.indication

20180813031122599 DLSS7 <001d> xua_default_lm_fsm.c:350 xua_default_lm(asp0)[0x612000016a20]{ACTIVE}: Received primitive M-SCTP_RELEASE.indication

20180813031122600 DLSS7 <001d> xua_default_lm_fsm.c:357 xua_default_lm(asp0)[0x612000016a20]{ACTIVE}: Received Event SCTP-RELEASE.ind

20180813031122600 DLSS7 <001d> osmo_ss7.c:1221 0: Restarting ASP asp0

20180813031122601 DLSS7 <001d> osmo_ss7.c:1277 XUA_ASP(asp0)[0x612000016720]{ASP_DOWN}: Terminating (cause = OSMO_FSM_TERM_REQUEST)

20180813031122601 DLSS7 <001d> osmo_ss7.c:1277 XUA_ASP(asp0)[0x612000016720]{ASP_DOWN}: Freeing instance

20180813031122601 DLSS7 <001d> fsm.c:381 XUA_ASP(asp0)[0x612000016720]{ASP_DOWN}: Deallocated

20180813031122601 DLSS7 <001d> fsm.c:299 XUA_ASP(asp0)[0x6120000102a0]{ASP_DOWN}: Allocated

20180813031122601 DLSS7 <001d> xua_default_lm_fsm.c:372 xua_default_lm(asp0)[0x612000016a20]{ACTIVE}: Terminating (cause = OSMO_FSM_TERM_ERROR)

20180813031122601 DLSS7 <001d> xua_default_lm_fsm.c:372 xua_default_lm(asp0)[0x612000016a20]{ACTIVE}: Freeing instance

20180813031122601 DLSS7 <001d> fsm.c:381 xua_default_lm(asp0)[0x612000016a20]{ACTIVE}: Deallocated

20180813031122601 DLSS7 <001d> fsm.c:299 xua_default_lm(asp0)[0x612000010120]{IDLE}: Allocated

20180813031122601 DLINP <0013> stream.c:166 connection closed

20180813031122601 DLINP <0013> stream.c:169 retrying in 5 seconds...



=================================================================

==2050==ERROR: LeakSanitizer: detected memory leaks

Indirect leak of 5888 byte(s) in 46 object(s) allocated from:

    #0 0x7f97456c3d28 in malloc (/usr/lib/x86_64-linux-gnu/libasan.so.3+0xc1d28)

    #1 0x7f97450f2acd in _talloc_zero (/usr/lib/x86_64-linux-gnu/libtalloc.so.2+0x6acd)

Indirect leak of 4836 byte(s) in 46 object(s) allocated from:

    #0 0x7f97456c3d28 in malloc (/usr/lib/x86_64-linux-gnu/libasan.so.3+0xc1d28)

    #1 0x7f97450f2e89 in _talloc_memdup (/usr/lib/x86_64-linux-gnu/libtalloc.so.2+0x6e89)

Indirect leak of 1904 byte(s) in 14 object(s) allocated from:

    #0 0x7f97456c3d28 in malloc (/usr/lib/x86_64-linux-gnu/libasan.so.3+0xc1d28)

    #1 0x7f97450f29fa in _talloc_zero (/usr/lib/x86_64-linux-gnu/libtalloc.so.2+0x69fa)

SUMMARY: AddressSanitizer: 12628 byte(s) leaked in 106 allocation(s).