Project

General

Profile

Download (117 KB)

Bug #5491 ยป osmo-msc-csfb.log

keith, 03/19/2022 02:37 AM

 
1 
20220319023541665 DREF DEBUG VLR subscr IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780 + mncc_tx_to_gsm_cc: now used by 2 (attached,mncc_tx_to_gsm_cc) (gsm_04_08_cc.c:1917)
2 
20220319023541665 DREF DEBUG VLR subscr IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780 + CC: now used by 3 (attached,mncc_tx_to_gsm_cc,CC) (transaction.c:215)
3 
20220319023541665 DCC DEBUG trans(CC:NULL IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780 callref-0x13ad tid-255) New transaction (transaction.c:218)
4 
20220319023541665 DMNCC DEBUG trans(CC:NULL IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780 callref-0x13ad tid-255) rx MNCC_SETUP_REQ (gsm_04_08_cc.c:1979)
5 
20220319023541665 DPAG DEBUG Paging: IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780 for MNCC: establish call: Starting paging (paging.c:101)
6 
20220319023541665 DSGS INFO mmec01.mmegi0002.mme.epc.mnc007.mcc334.3gppnetwork.org: Paging on SGs: IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780 for CS Call (conf_by_radio_contact_ind=1) (sgs_iface.c:497)
7 
20220319023541665 DSGS INFO SGs-UE(imsi:334070000000968)[0x55b8e9139430]{SGs-ASSOCIATED}: Received Event TX_PAGING (vlr_sgs.c:351)
8 
20220319023541665 DREF DEBUG VLR subscr IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780 + SGs-paging-req: now used by 4 (attached,mncc_tx_to_gsm_cc,CC,SGs-paging-req) (vlr_sgs.c:360)
9 
20220319023541665 DREF DEBUG VLR subscr IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780 + Paging: now used by 5 (attached,mncc_tx_to_gsm_cc,CC,SGs-paging-req,Paging) (paging.c:111)
10 
20220319023541665 DREF DEBUG VLR subscr IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780 - mncc_tx_to_gsm_cc: now used by 4 (attached,CC,SGs-paging-req,Paging) (gsm_04_08_cc.c:1992)
11 
20220319023541665 DLINP DEBUG connected read/write (stream.c:1343)
12 
20220319023541665 DLINP DEBUG sending data (stream.c:1293)
13 
20220319023541665 DLINP DEBUG connected read/write (stream.c:1343)
14 
20220319023541665 DLINP DEBUG sending data (stream.c:1293)
15 
20220319023542200 DLINP DEBUG connected read/write (stream.c:1343)
16 
20220319023542200 DLINP DEBUG message received (stream.c:1271)
17 
20220319023542200 DREF DEBUG VLR subscr IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780 + check_sgs_association: now used by 5 (attached,CC,SGs-paging-req,Paging,check_sgs_association) (sgs_iface.c:274)
18 
20220319023542200 DREF DEBUG VLR subscr IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780 - check_sgs_association: now used by 4 (attached,CC,SGs-paging-req,Paging) (sgs_iface.c:293)
19 
20220319023542200 DREF DEBUG VLR subscr IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780 + sgs_rx_service_req: now used by 5 (attached,CC,SGs-paging-req,Paging,sgs_rx_service_req) (sgs_iface.c:816)
20 
20220319023542200 DREF DEBUG VLR subscr IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780 + vlr_sgs_pag_ack: now used by 6 (attached,CC,SGs-paging-req,Paging,sgs_rx_service_req,vlr_sgs_pag_ack) (vlr_sgs.c:272)
21 
20220319023542200 DREF DEBUG VLR subscr IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780 - SGs-paging-req: now used by 5 (attached,CC,Paging,sgs_rx_service_req,vlr_sgs_pag_ack) (vlr_sgs.c:279)
22 
20220319023542200 DREF DEBUG VLR subscr IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780 - vlr_sgs_pag_ack: now used by 4 (attached,CC,Paging,sgs_rx_service_req) (vlr_sgs.c:281)
23 
20220319023542200 DREF DEBUG VLR subscr IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780 - sgs_rx_service_req: now used by 3 (attached,CC,Paging) (sgs_iface.c:853)
24 
20220319023544637 DLINP DEBUG [CONNECTED] osmo_stream_cli_fd_cb(): connected read (stream.c:445)
25 
20220319023544637 DLINP DEBUG [CONNECTED] osmo_stream_cli_read(): message received (stream.c:324)
26 
20220319023544638 DLSS7 DEBUG 0: asp-asp-clnt-OsmoMSC-A-Iu: xua_cli_read_cb(): sctp_recvmsg() returned 92 (flags=0x80) (osmo_ss7.c:1907)
27 
20220319023544638 DLM3UA DEBUG 0: asp-asp-clnt-OsmoMSC-A-Iu: Received M3UA Message (XFER:DATA) (m3ua.c:715)
28 
20220319023544638 DLM3UA DEBUG 0: asp-asp-clnt-OsmoMSC-A-Iu: m3ua_rx_xfer (m3ua.c:543)
29 
20220319023544638 DLM3UA DEBUG 0: asp-asp-clnt-OsmoMSC-A-Iu: m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3 dpc=185=0.23.1 (m3ua.c:569)
30 
20220319023544638 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): found dpc=185=0.23.1 as local (osmo_ss7_hmrt.c:277)
31 
20220319023544638 DLSUA DEBUG IEI 259: Parsed Addr: RI=2,PC=185,SSN=254 (sccp2sua.c:333)
32 
20220319023544638 DLSUA DEBUG IEI 258: Parsed Addr: RI=2,PC=187,SSN=254 (sccp2sua.c:333)
33 
20220319023544638 DLSS7 DEBUG 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=00000031), PART(T=Destination Address,L=20,D=0002000380020008000000b980030008000000fe), PART(T=Source Address,L=20,D=0002000380020008000000bb80030008000000fe), PART(T=Data,L=37,D=00235705080033f47000650064170e062704035358b205f42f655780c17d06841004831044) (sccp_scrc.c:472)
34 
20220319023544638 DLSUA DEBUG sua_addr_parse_part(IEI=0x0102) (20) 00 02 00 03 80 02 00 08 00 00 00 bb 80 03 00 08 00 00 00 fe  (sua.c:386)
35 
20220319023544638 DLSUA DEBUG SUA IEI 0x0102 pos 4/20: subpart tag 0x8002, len 8 (sua.c:440)
36 
20220319023544638 DLSUA DEBUG SUA IEI 0x0102 pos 12/20: subpart tag 0x8003, len 8 (sua.c:440)
37 
20220319023544638 DLSUA DEBUG sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 b9 80 03 00 08 00 00 00 fe  (sua.c:386)
38 
20220319023544638 DLSUA DEBUG SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8 (sua.c:440)
39 
20220319023544638 DLSUA DEBUG SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8 (sua.c:440)
40 
20220319023544638 DLSUA DEBUG sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 b9 80 03 00 08 00 00 00 fe  (sua.c:386)
41 
20220319023544638 DLSUA DEBUG SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8 (sua.c:440)
42 
20220319023544638 DLSUA DEBUG SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8 (sua.c:440)
43 
20220319023544638 DLSCCP DEBUG SCCP-SCOC(49)[0x55b8e913e300]{IDLE}: Allocated (fsm.c:456)
44 
20220319023544638 DLSCCP DEBUG Received CO:CORE for local reference 49 (sccp_scoc.c:1665)
45 
20220319023544638 DLSCCP DEBUG SCCP-SCOC(49)[0x55b8e913e300]{IDLE}: Received Event RCOC-CONNECT.ind (sccp_scoc.c:1698)
46 
20220319023544638 DLSUA DEBUG sua_addr_parse_part(IEI=0x0102) (20) 00 02 00 03 80 02 00 08 00 00 00 bb 80 03 00 08 00 00 00 fe  (sua.c:386)
47 
20220319023544638 DLSUA DEBUG SUA IEI 0x0102 pos 4/20: subpart tag 0x8002, len 8 (sua.c:440)
48 
20220319023544638 DLSUA DEBUG SUA IEI 0x0102 pos 12/20: subpart tag 0x8003, len 8 (sua.c:440)
49 
20220319023544638 DLSUA DEBUG sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 b9 80 03 00 08 00 00 00 fe  (sua.c:386)
50 
20220319023544638 DLSUA DEBUG SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8 (sua.c:440)
51 
20220319023544638 DLSUA DEBUG SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8 (sua.c:440)
52 
20220319023544638 DLSCCP DEBUG SCCP-SCOC(49)[0x55b8e913e300]{IDLE}: State change to CONN_PEND_IN (no timeout) (sccp_scoc.c:876)
53 
20220319023544638 DLSCCP DEBUG Delivering N-CONNECT.indication to SCCP User 'OsmoMSC-A' (sccp_user.c:176)
54 
20220319023544638 DBSSAP DEBUG (GERAN-A-49 from RI=SSN_PC,PC=0.23.3,SSN=BSSAP) sccp_ran_sap_up(N-CONNECT.indication) (sccp_ran.c:84)
55 
20220319023544638 DLSCCP DEBUG Received SCCP User Primitive (N-CONNECT.response) (sccp_scoc.c:1732)
56 
20220319023544638 DLSCCP DEBUG SCCP-SCOC(49)[0x55b8e913e300]{CONN_PEND_IN}: Received Event N-CONNECT.resp (sccp_scoc.c:1772)
57 
20220319023544638 DLSS7 DEBUG 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=00000031), PART(T=Source Reference,L=4,D=00000031), PART(T=Sequence Control,L=4,D=00000000), PART(T=Source Address,L=20,D=0002000380020008000000b980030008000000fe), PART(T=Destination Address,L=20,D=0002000380020008000000bb80030008000000fe) (sccp_scrc.c:401)
58 
20220319023544638 DLSUA DEBUG sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 bb 80 03 00 08 00 00 00 fe  (sua.c:386)
59 
20220319023544638 DLSUA DEBUG SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8 (sua.c:440)
60 
20220319023544638 DLSUA DEBUG SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8 (sua.c:440)
61 
20220319023544638 DLSUA DEBUG sua_addr_parse_part(IEI=0x0102) (20) 00 02 00 03 80 02 00 08 00 00 00 b9 80 03 00 08 00 00 00 fe  (sua.c:386)
62 
20220319023544638 DLSUA DEBUG SUA IEI 0x0102 pos 4/20: subpart tag 0x8002, len 8 (sua.c:440)
63 
20220319023544638 DLSUA DEBUG SUA IEI 0x0102 pos 12/20: subpart tag 0x8003, len 8 (sua.c:440)
64 
20220319023544638 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=187=0.23.3 not local, message is for routing (osmo_ss7_hmrt.c:281)
65 
20220319023544638 DLSS7 DEBUG Found route for dpc=187=0.23.3: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoMSC-A-Iu proto=m3ua (osmo_ss7_hmrt.c:228)
66 
20220319023544638 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=187=0.23.3 (osmo_ss7_hmrt.c:236)
67 
20220319023544638 DLSS7 DEBUG XUA_AS(as-clnt-OsmoMSC-A-Iu)[0x55b8e912c0c0]{AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:508)
68 
20220319023544638 DLSCCP DEBUG SCCP-SCOC(49)[0x55b8e913e300]{CONN_PEND_IN}: State change to ACTIVE (no timeout) (sccp_scoc.c:903)
69 
20220319023544638 DRR DEBUG ran_peer(GERAN-A:RI-SSN_PC:PC-0-23-3:SSN-BSSAP)[0x55b8e9134540]{READY}: Received Event RAN_PEER_EV_MSG_UP_CO_INITIAL (ran_peer.c:591)
70 
20220319023544638 DMSC DEBUG msub_fsm[0x55b8e91378c0]{active}: Allocated (fsm.c:456)
71 
20220319023544638 DMSC DEBUG msc_i[0x55b8e91359b0]{READY}: Allocated (fsm.c:456)
72 
20220319023544638 DMSC DEBUG msc_i[0x55b8e91359b0]{READY}: is child of msub_fsm[0x55b8e91378c0] (fsm.c:486)
73 
20220319023544638 DMSC DEBUG msc_a[0x55b8e91324c0]{MSC_A_ST_VALIDATE_L3}: Allocated (fsm.c:456)
74 
20220319023544638 DMSC DEBUG msc_a[0x55b8e91324c0]{MSC_A_ST_VALIDATE_L3}: is child of msub_fsm[0x55b8e91378c0] (fsm.c:486)
75 
20220319023544638 DMSC DEBUG msc_a(unknown:GERAN-A-49:NONE)[0x55b8e91324c0]{MSC_A_ST_VALIDATE_L3}: State change to MSC_A_ST_VALIDATE_L3 (X1, 5s) (msc_a.c:1107)
76 
20220319023544638 DMSC DEBUG msc_i(unknown:GERAN-A-49:NONE)[0x55b8e91359b0]{READY}: Received Event MSC_EV_FROM_RAN_COMPLETE_LAYER_3 (ran_peer.c:387)
77 
20220319023544638 DMSC DEBUG msc_a(unknown:GERAN-A-49:NONE)[0x55b8e91324c0]{MSC_A_ST_VALIDATE_L3}: Received Event MSC_A_EV_FROM_I_COMPLETE_LAYER_3 (msc_i.c:103)
78 
20220319023544638 DREF INFO msc_a(unknown:GERAN-A-49:NONE)[0x55b8e91324c0]{MSC_A_ST_VALIDATE_L3}: + msc_a_ran_dec: now used by 1 (msc_a_ran_dec) (msc_a.c:206)
79 
20220319023544638 DBSSAP DEBUG msc_a(unknown:GERAN-A-49:NONE)[0x55b8e91324c0]{MSC_A_ST_VALIDATE_L3}: RAN decode: BSSMAP: COMPLETE LAYER 3 (ran_msg_a.c:786)
80 
20220319023544638 DBSSAP DEBUG msc_a(unknown:GERAN-A-49:NONE)[0x55b8e91324c0]{MSC_A_ST_VALIDATE_L3}: RAN decode: BSSMAP Complete Layer 3 Information (msc_a.c:1625)
81 
20220319023544638 DRLL DEBUG msc_a(unknown:GERAN-A-49:NONE)[0x55b8e91324c0]{MSC_A_ST_VALIDATE_L3}: Dispatching 04.08 message: RR GSM48_MT_RR_PAG_RESP (msc_a.c:1229)
82 
20220319023544638 DRR DEBUG msc_a(TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_VALIDATE_L3}: Rx PAGING RESPONSE TMSI-0x2F655780 (gsm_04_08.c:1287)
83 
20220319023544638 DREF DEBUG msc_a(TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_VALIDATE_L3}: + paging-response: now used by 2 (msc_a_ran_dec,paging-response) (gsm_04_08.c:1289)
84 
20220319023544638 DVLR DEBUG Process_Access_Request_VLR(TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91404a0]{PR_ARQ_S_INIT}: Allocated (fsm.c:456)
85 
20220319023544638 DVLR DEBUG Process_Access_Request_VLR(TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91404a0]{PR_ARQ_S_INIT}: is child of msc_a(TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0] (fsm.c:486)
86 
20220319023544638 DVLR DEBUG Process_Access_Request_VLR(TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91404a0]{PR_ARQ_S_INIT}: rev=R99 net=GERAN Auth+Ciph (vlr_access_req_fsm.c:672)
87 
20220319023544638 DVLR DEBUG Process_Access_Request_VLR(TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91404a0]{PR_ARQ_S_INIT}: Received Event PR_ARQ_E_START (vlr_access_req_fsm.c:694)
88 
20220319023544638 DREF DEBUG VLR subscr IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780 + proc_arq_vlr_fn_init: now used by 4 (attached,CC,Paging,proc_arq_vlr_fn_init) (vlr_access_req_fsm.c:364)
89 
20220319023544638 DREF DEBUG VLR subscr IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780 + active-conn: now used by 5 (attached,CC,Paging,proc_arq_vlr_fn_init,active-conn) (msub.c:378)
90 
20220319023544638 DMSC DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_VALIDATE_L3}: Received Event MSC_A_EV_COMPLETE_LAYER_3_OK (gsm_04_08.c:1555)
91 
20220319023544638 DMSC DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_VALIDATE_L3}: State change to MSC_A_ST_AUTH_CIPH (keeping X1, 4.999s remaining) (msc_a.c:226)
92 
20220319023544638 DVLR DEBUG Process_Access_Request_VLR(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91404a0]{PR_ARQ_S_INIT}: proc_arq_vlr_fn_post_imsi() (vlr_access_req_fsm.c:332)
93 
20220319023544638 DVLR DEBUG Process_Access_Request_VLR(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91404a0]{PR_ARQ_S_INIT}: State change to PR_ARQ_S_WAIT_AUTH (no timeout) (vlr_access_req_fsm.c:339)
94 
20220319023544638 DVLR DEBUG VLR_Authenticate(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e913d250]{VLR_SUB_AS_NEEDS_AUTH}: Allocated (fsm.c:456)
95 
20220319023544638 DVLR DEBUG VLR_Authenticate(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e913d250]{VLR_SUB_AS_NEEDS_AUTH}: is child of Process_Access_Request_VLR(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91404a0] (fsm.c:486)
96 
20220319023544638 DVLR DEBUG VLR_Authenticate(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e913d250]{VLR_SUB_AS_NEEDS_AUTH}: Received Event VLR_AUTH_E_START (vlr_auth_fsm.c:624)
97 
20220319023544638 DVLR DEBUG VLR_Authenticate(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e913d250]{VLR_SUB_AS_NEEDS_AUTH}: State change to VLR_SUB_AS_NEEDS_AUTH_WAIT_AI (T0, 30s) (vlr_auth_fsm.c:318)
98 
20220319023544638 DREF DEBUG VLR subscr IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780 - proc_arq_vlr_fn_init: now used by 4 (attached,CC,Paging,active-conn) (vlr_access_req_fsm.c:382)
99 
20220319023544638 DREF DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_AUTH_CIPH}: - msc_a_ran_dec: now used by 1 (paging-response) (msc_a.c:208)
100 
20220319023544638 DLINP DEBUG 10.23.100.60:4222 connected write (ipa.c:139)
101 
20220319023544638 DLINP DEBUG 10.23.100.60:4222 sending data (ipa.c:89)
102 
20220319023544639 DLINP DEBUG [CONNECTED] osmo_stream_cli_fd_cb(): connected write (stream.c:449)
103 
20220319023544639 DLINP DEBUG [CONNECTED] osmo_stream_cli_write(): sending 48 bytes of data (stream.c:352)
104 
20220319023544639 DLINP DEBUG 10.23.100.60:4222 connected write (ipa.c:139)
105 
20220319023544639 DLINP DEBUG 10.23.100.60:4222 sending data (ipa.c:89)
106 
20220319023544639 DLINP DEBUG [CONNECTED] osmo_stream_cli_fd_cb(): connected write (stream.c:449)
107 
20220319023544647 DLINP DEBUG 10.23.100.60:4222 connected read (ipa.c:135)
108 
20220319023544647 DLINP DEBUG 10.23.100.60:4222 message received (ipa.c:56)
109 
20220319023544647 DREF DEBUG VLR subscr IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780 + vlr_gsup_rx: now used by 5 (attached,CC,Paging,active-conn,vlr_gsup_rx) (vlr.c:1111)
110 
20220319023544647 DVLR DEBUG VLR_Authenticate(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e913d250]{VLR_SUB_AS_NEEDS_AUTH_WAIT_AI}: Received Event VLR_AUTH_E_HLR_SAI_ACK (vlr.c:806)
111 
20220319023544647 DVLR DEBUG SUBSCR(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780) Received 5 auth tuples (vlr.c:780)
112 
20220319023544647 DVLR DEBUG VLR_Authenticate(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e913d250]{VLR_SUB_AS_NEEDS_AUTH_WAIT_AI}: State change to VLR_SUB_AS_WAIT_RESP (T3260, 12s) (vlr_auth_fsm.c:375)
113 
20220319023544647 DVLR DEBUG VLR_Authenticate(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e913d250]{VLR_SUB_AS_WAIT_RESP}: got auth tuple: use_count=1 key_seq=0 -- will use UMTS AKA (is_r99=yes, at->vec.auth_types=0x3) (vlr_auth_fsm.c:286)
114 
20220319023544647 DMM DEBUG Tx AUTH REQ (rand = e3518bbd0ddfe0c062d9984558a1386a) (gsm_04_08.c:642)
115 
20220319023544647 DMM DEBUG    AUTH REQ (autn = 9b56a3f8f8210000e2a6e7296bac9301) (gsm_04_08.c:644)
116 
20220319023544647 DBSSAP DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_AUTH_CIPH}: Sending DTAP: MM GSM48_MT_MM_AUTH_REQ (msc_a.c:1689)
117 
20220319023544647 DBSSAP DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_AUTH_CIPH}: RAN encode: BSSMAP: DTAP (ran_msg_a.c:1237)
118 
20220319023544647 DMSC DEBUG msc_i(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91359b0]{READY}: Received Event MSC_I_EV_FROM_A_FORWARD_ACCESS_SIGNALLING_REQUEST (msc_a.c:1695)
119 
20220319023544647 DRR DEBUG ran_peer(GERAN-A:RI-SSN_PC:PC-0-23-3:SSN-BSSAP)[0x55b8e9134540]{READY}: Received Event RAN_PEER_EV_MSG_DOWN_CO (ran_conn.c:119)
120 
20220319023544647 DLSCCP DEBUG Received SCCP User Primitive (N-DATA.request) (sccp_scoc.c:1732)
121 
20220319023544647 DLSCCP DEBUG SCCP-SCOC(49)[0x55b8e913e300]{ACTIVE}: Received Event N-DATA.req (sccp_scoc.c:1772)
122 
20220319023544647 DLSS7 DEBUG 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=00000031), PART(T=Data,L=40,D=010025051200e3518bbd0ddfe0c062d9984558a1386a20109b56a3f8f8210000e2a6e7296bac9301) (sccp_scrc.c:401)
123 
20220319023544647 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=187=0.23.3 not local, message is for routing (osmo_ss7_hmrt.c:281)
124 
20220319023544647 DLSS7 DEBUG Found route for dpc=187=0.23.3: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoMSC-A-Iu proto=m3ua (osmo_ss7_hmrt.c:228)
125 
20220319023544647 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=187=0.23.3 (osmo_ss7_hmrt.c:236)
126 
20220319023544647 DLSS7 DEBUG XUA_AS(as-clnt-OsmoMSC-A-Iu)[0x55b8e912c0c0]{AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:508)
127 
20220319023544647 DREF DEBUG VLR subscr IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780 - vlr_gsup_rx: now used by 4 (attached,CC,Paging,active-conn) (vlr.c:1157)
128 
20220319023544647 DLINP DEBUG [CONNECTED] osmo_stream_cli_fd_cb(): connected write (stream.c:449)
129 
20220319023544647 DLINP DEBUG [CONNECTED] osmo_stream_cli_write(): sending 80 bytes of data (stream.c:352)
130 
20220319023544647 DLINP DEBUG [CONNECTED] osmo_stream_cli_fd_cb(): connected write (stream.c:449)
131 
20220319023545578 DLINP DEBUG [CONNECTED] osmo_stream_cli_fd_cb(): connected read (stream.c:445)
132 
20220319023545578 DLINP DEBUG [CONNECTED] osmo_stream_cli_read(): message received (stream.c:324)
133 
20220319023545578 DLSS7 DEBUG 0: asp-asp-clnt-OsmoMSC-A-Iu: xua_cli_read_cb(): sctp_recvmsg() returned 56 (flags=0x80) (osmo_ss7.c:1907)
134 
20220319023545578 DLM3UA DEBUG 0: asp-asp-clnt-OsmoMSC-A-Iu: Received M3UA Message (XFER:DATA) (m3ua.c:715)
135 
20220319023545578 DLM3UA DEBUG 0: asp-asp-clnt-OsmoMSC-A-Iu: m3ua_rx_xfer (m3ua.c:543)
136 
20220319023545578 DLM3UA DEBUG 0: asp-asp-clnt-OsmoMSC-A-Iu: m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3 dpc=185=0.23.1 (m3ua.c:569)
137 
20220319023545578 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): found dpc=185=0.23.1 as local (osmo_ss7_hmrt.c:277)
138 
20220319023545578 DLSS7 DEBUG scrc_rx_mtp_xfer_ind_xua: HDR=(CO:CODT,V=0,LEN=0), PART(T=Destination Reference,L=4,D=00000031), PART(T=Segmentation,L=4,D=00000000), PART(T=Data,L=15,D=01800c05147775c3482104d576c560) (sccp_scrc.c:472)
139 
20220319023545578 DLSCCP DEBUG Received CO:CODT for local reference 49 (sccp_scoc.c:1665)
140 
20220319023545578 DLSCCP DEBUG SCCP-SCOC(49)[0x55b8e913e300]{ACTIVE}: Received Event RCOC-DT1.ind (sccp_scoc.c:1698)
141 
20220319023545578 DLSCCP DEBUG Delivering N-DATA.indication to SCCP User 'OsmoMSC-A' (sccp_user.c:176)
142 
20220319023545578 DBSSAP DEBUG (GERAN-A-49) sccp_ran_sap_up(N-DATA.indication) (sccp_ran.c:108)
143 
20220319023545578 DRR DEBUG ran_peer(GERAN-A:RI-SSN_PC:PC-0-23-3:SSN-BSSAP)[0x55b8e9134540]{READY}: Received Event RAN_PEER_EV_MSG_UP_CO (ran_peer.c:591)
144 
20220319023545578 DMSC DEBUG msc_i(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91359b0]{READY}: Received Event MSC_EV_FROM_RAN_UP_L2 (ran_peer.c:407)
145 
20220319023545578 DMSC DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_AUTH_CIPH}: Received Event MSC_A_EV_FROM_I_PROCESS_ACCESS_SIGNALLING_REQUEST (msc_i.c:85)
146 
20220319023545578 DREF DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_AUTH_CIPH}: + msc_a_ran_dec: now used by 2 (paging-response,msc_a_ran_dec) (msc_a.c:206)
147 
20220319023545578 DBSSAP DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_AUTH_CIPH}: RAN decode: BSSAP DTAP (msc_a.c:1625)
148 
20220319023545578 DRLL DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_AUTH_CIPH}: Dispatching 04.08 message: MM GSM48_MT_MM_AUTH_RESP (msc_a.c:1229)
149 
20220319023545578 DMM DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_AUTH_CIPH}: MM UMTS AUTHENTICATION RESPONSE (res = 7775c348d576c560) (gsm_04_08.c:1119)
150 
20220319023545578 DVLR DEBUG VLR_Authenticate(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e913d250]{VLR_SUB_AS_WAIT_RESP}: Received Event VLR_AUTH_E_MS_AUTH_RESP (vlr.c:1358)
151 
20220319023545578 DVLR DEBUG SUBSCR(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780) AUTH on GERAN received SRES/RES: 7775c348d576c560 (8 bytes) (vlr_auth_fsm.c:136)
152 
20220319023545578 DVLR INFO SUBSCR(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780) AUTH established UMTS security context (vlr_auth_fsm.c:195)
153 
20220319023545578 DVLR DEBUG VLR_Authenticate(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e913d250]{VLR_SUB_AS_WAIT_RESP}: Authentication terminating with result PASSED (vlr_auth_fsm.c:244)
154 
20220319023545578 DVLR DEBUG VLR_Authenticate(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e913d250]{VLR_SUB_AS_WAIT_RESP}: State change to VLR_SUB_AS_AUTHENTICATED (no timeout) (vlr_auth_fsm.c:248)
155 
20220319023545578 DVLR DEBUG VLR_Authenticate(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e913d250]{VLR_SUB_AS_AUTHENTICATED}: Terminating (cause = OSMO_FSM_TERM_REGULAR) (vlr_auth_fsm.c:253)
156 
20220319023545578 DVLR DEBUG VLR_Authenticate(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e913d250]{VLR_SUB_AS_AUTHENTICATED}: Removing from parent Process_Access_Request_VLR(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91404a0] (vlr_auth_fsm.c:253)
157 
20220319023545578 DVLR DEBUG VLR_Authenticate(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e913d250]{VLR_SUB_AS_AUTHENTICATED}: Freeing instance (vlr_auth_fsm.c:253)
158 
20220319023545578 DVLR DEBUG VLR_Authenticate(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e913d250]{VLR_SUB_AS_AUTHENTICATED}: Deallocated (fsm.c:568)
159 
20220319023545578 DVLR DEBUG Process_Access_Request_VLR(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91404a0]{PR_ARQ_S_WAIT_AUTH}: Received Event PR_ARQ_E_AUTH_RES (vlr_auth_fsm.c:253)
160 
20220319023545578 DVLR DEBUG Process_Access_Request_VLR(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91404a0]{PR_ARQ_S_WAIT_AUTH}: _proc_arq_vlr_node2() (vlr_access_req_fsm.c:284)
161 
20220319023545578 DVLR DEBUG Process_Access_Request_VLR(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91404a0]{PR_ARQ_S_WAIT_AUTH}: Set Ciphering Mode (vlr.c:1388)
162 
20220319023545578 DBSSAP DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_AUTH_CIPH}: For A5/1, we still need Classmark 1 (msc_a.c:264)
163 
20220319023545578 DBSSAP DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_AUTH_CIPH}: RAN encode: BSSMAP: CLASSMARK_REQUEST (ran_msg_a.c:1237)
164 
20220319023545578 DMSC DEBUG msc_i(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91359b0]{READY}: Received Event MSC_I_EV_FROM_A_FORWARD_ACCESS_SIGNALLING_REQUEST (msc_a.c:298)
165 
20220319023545578 DRR DEBUG ran_peer(GERAN-A:RI-SSN_PC:PC-0-23-3:SSN-BSSAP)[0x55b8e9134540]{READY}: Received Event RAN_PEER_EV_MSG_DOWN_CO (ran_conn.c:119)
166 
20220319023545578 DLSCCP DEBUG Received SCCP User Primitive (N-DATA.request) (sccp_scoc.c:1732)
167 
20220319023545578 DLSCCP DEBUG SCCP-SCOC(49)[0x55b8e913e300]{ACTIVE}: Received Event N-DATA.req (sccp_scoc.c:1772)
168 
20220319023545578 DLSS7 DEBUG 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=00000031), PART(T=Data,L=3,D=000158) (sccp_scrc.c:401)
169 
20220319023545578 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=187=0.23.3 not local, message is for routing (osmo_ss7_hmrt.c:281)
170 
20220319023545578 DLSS7 DEBUG Found route for dpc=187=0.23.3: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoMSC-A-Iu proto=m3ua (osmo_ss7_hmrt.c:228)
171 
20220319023545578 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=187=0.23.3 (osmo_ss7_hmrt.c:236)
172 
20220319023545578 DLSS7 DEBUG XUA_AS(as-clnt-OsmoMSC-A-Iu)[0x55b8e912c0c0]{AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:508)
173 
20220319023545578 DMSC DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_AUTH_CIPH}: State change to MSC_A_ST_WAIT_CLASSMARK_UPDATE (keeping X1, 4.060s remaining) (msc_a.c:312)
174 
20220319023545578 DVLR DEBUG Process_Access_Request_VLR(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91404a0]{PR_ARQ_S_WAIT_AUTH}: State change to PR_ARQ_S_WAIT_CIPH (no timeout) (vlr_access_req_fsm.c:314)
175 
20220319023545578 DREF DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_WAIT_CLASSMARK_UPDATE}: - msc_a_ran_dec: now used by 1 (paging-response) (msc_a.c:208)
176 
20220319023545578 DLINP DEBUG [CONNECTED] osmo_stream_cli_fd_cb(): connected write (stream.c:449)
177 
20220319023545578 DLINP DEBUG [CONNECTED] osmo_stream_cli_write(): sending 44 bytes of data (stream.c:352)
178 
20220319023545578 DLINP DEBUG [CONNECTED] osmo_stream_cli_fd_cb(): connected write (stream.c:449)
179 
20220319023546284 DLINP DEBUG [CONNECTED] osmo_stream_cli_fd_cb(): connected read (stream.c:445)
180 
20220319023546284 DLINP DEBUG [CONNECTED] osmo_stream_cli_read(): message received (stream.c:324)
181 
20220319023546284 DLSS7 DEBUG 0: asp-asp-clnt-OsmoMSC-A-Iu: xua_cli_read_cb(): sctp_recvmsg() returned 60 (flags=0x80) (osmo_ss7.c:1907)
182 
20220319023546285 DLM3UA DEBUG 0: asp-asp-clnt-OsmoMSC-A-Iu: Received M3UA Message (XFER:DATA) (m3ua.c:715)
183 
20220319023546285 DLM3UA DEBUG 0: asp-asp-clnt-OsmoMSC-A-Iu: m3ua_rx_xfer (m3ua.c:543)
184 
20220319023546285 DLM3UA DEBUG 0: asp-asp-clnt-OsmoMSC-A-Iu: m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3 dpc=185=0.23.1 (m3ua.c:569)
185 
20220319023546285 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): found dpc=185=0.23.1 as local (osmo_ss7_hmrt.c:277)
186 
20220319023546285 DLSS7 DEBUG scrc_rx_mtp_xfer_ind_xua: HDR=(CO:CODT,V=0,LEN=0), PART(T=Destination Reference,L=4,D=00000031), PART(T=Segmentation,L=4,D=00000000), PART(T=Data,L=20,D=00125412035358b2130a601434e2918100123e80) (sccp_scrc.c:472)
187 
20220319023546285 DLSCCP DEBUG Received CO:CODT for local reference 49 (sccp_scoc.c:1665)
188 
20220319023546285 DLSCCP DEBUG SCCP-SCOC(49)[0x55b8e913e300]{ACTIVE}: Received Event RCOC-DT1.ind (sccp_scoc.c:1698)
189 
20220319023546285 DLSCCP DEBUG Delivering N-DATA.indication to SCCP User 'OsmoMSC-A' (sccp_user.c:176)
190 
20220319023546285 DBSSAP DEBUG (GERAN-A-49) sccp_ran_sap_up(N-DATA.indication) (sccp_ran.c:108)
191 
20220319023546285 DRR DEBUG ran_peer(GERAN-A:RI-SSN_PC:PC-0-23-3:SSN-BSSAP)[0x55b8e9134540]{READY}: Received Event RAN_PEER_EV_MSG_UP_CO (ran_peer.c:591)
192 
20220319023546285 DMSC DEBUG msc_i(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91359b0]{READY}: Received Event MSC_EV_FROM_RAN_UP_L2 (ran_peer.c:407)
193 
20220319023546285 DBSSAP DEBUG msc_i(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91359b0]{READY}: RAN decode: BSSMAP: CLASSMARK UPDATE (ran_msg_a.c:786)
194 
20220319023546285 DMSC DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_WAIT_CLASSMARK_UPDATE}: Received Event MSC_A_EV_FROM_I_PROCESS_ACCESS_SIGNALLING_REQUEST (msc_i.c:85)
195 
20220319023546285 DREF DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_WAIT_CLASSMARK_UPDATE}: + msc_a_ran_dec: now used by 2 (paging-response,msc_a_ran_dec) (msc_a.c:206)
196 
20220319023546285 DBSSAP DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_WAIT_CLASSMARK_UPDATE}: RAN decode: BSSMAP: CLASSMARK UPDATE (ran_msg_a.c:786)
197 
20220319023546285 DBSSAP DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_WAIT_CLASSMARK_UPDATE}: RAN decode: BSSMAP Classmark Update (msc_a.c:1625)
198 
20220319023546285 DBSSAP DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_WAIT_CLASSMARK_UPDATE}: A5 capabilities received from Classmark Update: no-cm1 cm2{0x2= A5/3} cm3{0x60=} (msc_a.c:1393)
199 
20220319023546285 DMSC DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_WAIT_CLASSMARK_UPDATE}: Received Event MSC_A_EV_CLASSMARK_UPDATE (msc_a.c:1398)
200 
20220319023546285 DMSC DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_WAIT_CLASSMARK_UPDATE}: State change to MSC_A_ST_AUTH_CIPH (keeping X1, 3.353s remaining) (msc_a.c:428)
201 
20220319023546285 DBSSAP DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_AUTH_CIPH}: RAN encode: BSSMAP: CIPHER_MODE_COMMAND (ran_msg_a.c:1237)
202 
20220319023546285 DBSSAP ERROR msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_AUTH_CIPH}: RAN encode: BSSMAP: cannot start ciphering, no intersection between MSC-configured and MS-supported A5 algorithms. MSC: 0x02  MS: no-cm1 cm2{0x2= A5/3} cm3{0x60=} (ran_msg_a.c:1081)
203 
20220319023546285 DMSC ERROR msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_AUTH_CIPH}: Failed to encode CIPHER_MODE_COMMAND (msub.c:560)
204 
20220319023546285 DBSSAP ERROR msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_AUTH_CIPH}: Sending Cipher Mode Command failed (msc_a.c:368)
205 
20220319023546285 DBSSAP ERROR msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_AUTH_CIPH}: After Classmark Update, still failed to send Cipher Mode Command (msc_a.c:433)
206 
20220319023546285 DMSC DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_AUTH_CIPH}: State change to MSC_A_ST_RELEASING (X2, 30s) (msc_a.c:434)
207 
20220319023546285 DBSSAP DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_RELEASING}: Releasing: msc_a use is 2 (paging-response,msc_a_ran_dec) (msc_a.c:780)
208 
20220319023546285 DREF DEBUG VLR subscr IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780 + msc_a_fsm_releasing_onenter: now used by 5 (attached,CC,Paging,active-conn,msc_a_fsm_releasing_onenter) (msc_a.c:783)
209 
20220319023546285 DREF DEBUG VLR subscr IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780 + vlr_subscr_cancel_attach_fsm: now used by 6 (attached,CC,Paging,active-conn,msc_a_fsm_releasing_onenter,vlr_subscr_cancel_attach_fsm) (vlr.c:309)
210 
20220319023546285 DVLR DEBUG Process_Access_Request_VLR(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91404a0]{PR_ARQ_S_WAIT_CIPH}: Cancel: OSMO_FSM_TERM_ERROR (vlr_access_req_fsm.c:705)
211 
20220319023546285 DVLR DEBUG Process_Access_Request_VLR(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91404a0]{PR_ARQ_S_WAIT_CIPH}: proc_arq_fsm_done(CONGESTION) (vlr_access_req_fsm.c:706)
212 
20220319023546285 DVLR DEBUG Process_Access_Request_VLR(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91404a0]{PR_ARQ_S_WAIT_CIPH}: State change to PR_ARQ_S_DONE (no timeout) (vlr_access_req_fsm.c:103)
213 
20220319023546285 DVLR DEBUG Process_Access_Request_VLR(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91404a0]{PR_ARQ_S_DONE}: Process Access Request result: CONGESTION (vlr_access_req_fsm.c:112)
214 
20220319023546285 DMSC DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_RELEASING}: Received Event MSC_A_EV_CN_CLOSE (vlr_access_req_fsm.c:153)
215 
20220319023546285 DREF DEBUG VLR subscr IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780 - vlr_subscr_cancel_attach_fsm: now used by 5 (attached,CC,Paging,active-conn,msc_a_fsm_releasing_onenter) (vlr.c:314)
216 
20220319023546285 DBSSAP DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_RELEASING}: Releasing: canceling still pending use: paging-response (1) (msc_a.c:804)
217 
20220319023546285 DREF DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_RELEASING}: - paging-response: now used by 1 (msc_a_ran_dec) (msc_a.c:805)
218 
20220319023546285 DREF DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_RELEASING}: + wait-Clear-Complete: now used by 2 (msc_a_ran_dec,wait-Clear-Complete) (msc_a.c:822)
219 
20220319023546285 DBSSAP DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_RELEASING}: RAN encode: BSSMAP: CLEAR_COMMAND (ran_msg_a.c:1237)
220 
20220319023546285 DMSC DEBUG msc_i(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91359b0]{READY}: Received Event MSC_I_EV_FROM_A_FORWARD_ACCESS_SIGNALLING_REQUEST (msc_a.c:823)
221 
20220319023546285 DRR DEBUG ran_peer(GERAN-A:RI-SSN_PC:PC-0-23-3:SSN-BSSAP)[0x55b8e9134540]{READY}: Received Event RAN_PEER_EV_MSG_DOWN_CO (ran_conn.c:119)
222 
20220319023546285 DLSCCP DEBUG Received SCCP User Primitive (N-DATA.request) (sccp_scoc.c:1732)
223 
20220319023546285 DLSCCP DEBUG SCCP-SCOC(49)[0x55b8e913e300]{ACTIVE}: Received Event N-DATA.req (sccp_scoc.c:1772)
224 
20220319023546285 DLSS7 DEBUG 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=00000031), PART(T=Data,L=7,D=0005200401098f) (sccp_scrc.c:401)
225 
20220319023546285 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=187=0.23.3 not local, message is for routing (osmo_ss7_hmrt.c:281)
226 
20220319023546285 DLSS7 DEBUG Found route for dpc=187=0.23.3: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoMSC-A-Iu proto=m3ua (osmo_ss7_hmrt.c:228)
227 
20220319023546285 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=187=0.23.3 (osmo_ss7_hmrt.c:236)
228 
20220319023546285 DLSS7 DEBUG XUA_AS(as-clnt-OsmoMSC-A-Iu)[0x55b8e912c0c0]{AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:508)
229 
20220319023546285 DREF DEBUG VLR subscr IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780 - msc_a_fsm_releasing_onenter: now used by 4 (attached,CC,Paging,active-conn) (msc_a.c:832)
230 
20220319023546285 DREF DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_RELEASING}: - msc_a_ran_dec: now used by 1 (wait-Clear-Complete) (msc_a.c:208)
231 
20220319023546285 DLINP DEBUG [CONNECTED] osmo_stream_cli_fd_cb(): connected write (stream.c:449)
232 
20220319023546285 DLINP DEBUG [CONNECTED] osmo_stream_cli_write(): sending 48 bytes of data (stream.c:352)
233 
20220319023546285 DLINP DEBUG [CONNECTED] osmo_stream_cli_fd_cb(): connected write (stream.c:449)
234 
20220319023546286 DLINP DEBUG [CONNECTED] osmo_stream_cli_fd_cb(): connected read (stream.c:445)
235 
20220319023546286 DLINP DEBUG [CONNECTED] osmo_stream_cli_read(): message received (stream.c:324)
236 
20220319023546286 DLSS7 DEBUG 0: asp-asp-clnt-OsmoMSC-A-Iu: xua_cli_read_cb(): sctp_recvmsg() returned 44 (flags=0x80) (osmo_ss7.c:1907)
237 
20220319023546286 DLM3UA DEBUG 0: asp-asp-clnt-OsmoMSC-A-Iu: Received M3UA Message (XFER:DATA) (m3ua.c:715)
238 
20220319023546286 DLM3UA DEBUG 0: asp-asp-clnt-OsmoMSC-A-Iu: m3ua_rx_xfer (m3ua.c:543)
239 
20220319023546286 DLM3UA DEBUG 0: asp-asp-clnt-OsmoMSC-A-Iu: m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3 dpc=185=0.23.1 (m3ua.c:569)
240 
20220319023546286 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): found dpc=185=0.23.1 as local (osmo_ss7_hmrt.c:277)
241 
20220319023546286 DLSS7 DEBUG scrc_rx_mtp_xfer_ind_xua: HDR=(CO:CODT,V=0,LEN=0), PART(T=Destination Reference,L=4,D=00000031), PART(T=Segmentation,L=4,D=00000000), PART(T=Data,L=3,D=000121) (sccp_scrc.c:472)
242 
20220319023546286 DLSCCP DEBUG Received CO:CODT for local reference 49 (sccp_scoc.c:1665)
243 
20220319023546286 DLSCCP DEBUG SCCP-SCOC(49)[0x55b8e913e300]{ACTIVE}: Received Event RCOC-DT1.ind (sccp_scoc.c:1698)
244 
20220319023546286 DLSCCP DEBUG Delivering N-DATA.indication to SCCP User 'OsmoMSC-A' (sccp_user.c:176)
245 
20220319023546286 DBSSAP DEBUG (GERAN-A-49) sccp_ran_sap_up(N-DATA.indication) (sccp_ran.c:108)
246 
20220319023546286 DRR DEBUG ran_peer(GERAN-A:RI-SSN_PC:PC-0-23-3:SSN-BSSAP)[0x55b8e9134540]{READY}: Received Event RAN_PEER_EV_MSG_UP_CO (ran_peer.c:591)
247 
20220319023546286 DMSC DEBUG msc_i(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91359b0]{READY}: Received Event MSC_EV_FROM_RAN_UP_L2 (ran_peer.c:407)
248 
20220319023546286 DBSSAP DEBUG msc_i(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91359b0]{READY}: RAN decode: BSSMAP: CLEAR COMPLETE (ran_msg_a.c:786)
249 
20220319023546286 DMSC DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_RELEASING}: Received Event MSC_A_EV_FROM_I_PROCESS_ACCESS_SIGNALLING_REQUEST (msc_i.c:85)
250 
20220319023546286 DREF DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_RELEASING}: + msc_a_ran_dec: now used by 2 (wait-Clear-Complete,msc_a_ran_dec) (msc_a.c:206)
251 
20220319023546286 DBSSAP DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_RELEASING}: RAN decode: BSSMAP: CLEAR COMPLETE (ran_msg_a.c:786)
252 
20220319023546286 DBSSAP DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_RELEASING}: RAN decode: BSSMAP Clear Complete (msc_a.c:1625)
253 
20220319023546287 DREF DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_RELEASING}: - wait-Clear-Complete: now used by 1 (msc_a_ran_dec) (msc_a.c:1452)
254 
20220319023546287 DMSC DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_RELEASING}: State change to MSC_A_ST_RELEASED (X2, 30s) (msc_a.c:1453)
255 
20220319023546287 DBSSAP DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_RELEASED}: Released: msc_a use is 1 (msc_a_ran_dec) (msc_a.c:873)
256 
20220319023546287 DREF INFO msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_RELEASED}: - msc_a_ran_dec: now used by 0 (-) (msc_a.c:208)
257 
20220319023546287 DMSC DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_RELEASED}: Received Event MSC_A_EV_UNUSED (msc_a.c:1094)
258 
20220319023546287 DMSC DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_RELEASED}: Terminating (cause = OSMO_FSM_TERM_REGULAR) (msc_a.c:881)
259 
20220319023546287 DVLR DEBUG Process_Access_Request_VLR(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91404a0]{PR_ARQ_S_DONE}: Terminating (cause = OSMO_FSM_TERM_PARENT) (msc_a.c:881)
260 
20220319023546287 DVLR DEBUG Process_Access_Request_VLR(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91404a0]{PR_ARQ_S_DONE}: Removing from parent msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0] (msc_a.c:881)
261 
20220319023546287 DVLR DEBUG Process_Access_Request_VLR(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91404a0]{PR_ARQ_S_DONE}: Freeing instance (msc_a.c:881)
262 
20220319023546287 DVLR DEBUG Process_Access_Request_VLR(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91404a0]{PR_ARQ_S_DONE}: Deallocated (fsm.c:568)
263 
20220319023546287 DMSC DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_RELEASED}: Removing from parent msub_fsm[0x55b8e91378c0] (msc_a.c:881)
264 
20220319023546287 DBSSAP DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_RELEASED}: msc_a_fsm_has_active_transactions: still active: MNCC: establish call (msc_a.c:491)
265 
20220319023546287 DBSSAP ERROR msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_RELEASED}: Deallocating active transactions failed (msc_a.c:891)
266 
20220319023546287 DREF DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_RELEASED}: max total use count was 2 (msc_a.c:893)
267 
20220319023546287 DMSC DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_RELEASED}: Freeing instance (msc_a.c:881)
268 
20220319023546287 DMSC DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91324c0]{MSC_A_ST_RELEASED}: Deallocated (fsm.c:568)
269 
20220319023546287 DMSC DEBUG msub_fsm[0x55b8e91378c0]{active}: Received Event MSUB_EV_ROLE_TERMINATED (msc_a.c:881)
270 
20220319023546287 DMSC DEBUG msub(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780) MSC-A terminated (msub.c:260)
271 
20220319023546287 DMSC DEBUG msub(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780) 1 MSC-I still active (msub.c:78)
272 
20220319023546287 DMSC DEBUG msub_fsm[0x55b8e91378c0]{active}: State change to terminating (no timeout) (msub.c:90)
273 
20220319023546287 DMSC DEBUG msub_fsm[0x55b8e91378c0]{terminating}: Terminating (cause = OSMO_FSM_TERM_REGULAR) (msub.c:112)
274 
20220319023546287 DMSC DEBUG msc_i(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91359b0]{READY}: Terminating (cause = OSMO_FSM_TERM_PARENT) (msub.c:112)
275 
20220319023546287 DMSC DEBUG msc_i(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91359b0]{READY}: Removing from parent msub_fsm[0x55b8e91378c0] (msub.c:112)
276 
20220319023546287 DBSSAP DEBUG ran_peer(GERAN-A:RI-SSN_PC:PC-0-23-3:SSN-BSSAP)[0x55b8e9134540]{READY}: Closing GERAN-A-49 (ran_conn.c:142)
277 
20220319023546287 DLSCCP DEBUG Received SCCP User Primitive (N-DISCONNECT.request) (sccp_scoc.c:1732)
278 
20220319023546287 DLSCCP DEBUG SCCP-SCOC(49)[0x55b8e913e300]{ACTIVE}: Received Event N-DISCONNECT.req (sccp_scoc.c:1772)
279 
20220319023546287 DLSS7 DEBUG 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=00000031), PART(T=Source Reference,L=4,D=00000031), PART(T=Cause,L=4,D=00000300) (sccp_scrc.c:401)
280 
20220319023546287 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=187=0.23.3 not local, message is for routing (osmo_ss7_hmrt.c:281)
281 
20220319023546287 DLSS7 DEBUG Found route for dpc=187=0.23.3: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoMSC-A-Iu proto=m3ua (osmo_ss7_hmrt.c:228)
282 
20220319023546287 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=187=0.23.3 (osmo_ss7_hmrt.c:236)
283 
20220319023546287 DLSS7 DEBUG XUA_AS(as-clnt-OsmoMSC-A-Iu)[0x55b8e912c0c0]{AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:508)
284 
20220319023546287 DLSCCP DEBUG SCCP-SCOC(49)[0x55b8e913e300]{ACTIVE}: State change to DISCONN_PEND (no timeout) (sccp_scoc.c:1057)
285 
20220319023546287 DMSC DEBUG NULL{fi=NULL}: Deallocating no-RAN-peer-49 (ran_conn.c:155)
286 
20220319023546287 DMSC DEBUG msc_i(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91359b0]{READY}: Freeing instance (msub.c:112)
287 
20220319023546287 DMSC DEBUG msc_i(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-49:PAGING_RESP)[0x55b8e91359b0]{READY}: Deallocated (fsm.c:568)
288 
20220319023546287 DMSC DEBUG msub(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780) Free (msub.c:118)
289 
20220319023546287 DREF DEBUG VLR subscr IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780 - active-conn: now used by 3 (attached,CC,Paging) (msub.c:374)
290 
20220319023546287 DMSC DEBUG msub_fsm[0x55b8e91378c0]{terminating}: Freeing instance (msub.c:112)
291 
20220319023546287 DMSC DEBUG msub_fsm[0x55b8e91378c0]{terminating}: Deallocated (fsm.c:568)
292 
20220319023546287 DLINP DEBUG [CONNECTED] osmo_stream_cli_fd_cb(): connected write (stream.c:449)
293 
20220319023546287 DLINP DEBUG [CONNECTED] osmo_stream_cli_write(): sending 44 bytes of data (stream.c:352)
294 
20220319023546288 DLINP DEBUG [CONNECTED] osmo_stream_cli_fd_cb(): connected write (stream.c:449)
295 
20220319023546289 DLINP DEBUG [CONNECTED] osmo_stream_cli_fd_cb(): connected read (stream.c:445)
296 
20220319023546289 DLINP DEBUG [CONNECTED] osmo_stream_cli_read(): message received (stream.c:324)
297 
20220319023546289 DLSS7 DEBUG 0: asp-asp-clnt-OsmoMSC-A-Iu: xua_cli_read_cb(): sctp_recvmsg() returned 40 (flags=0x80) (osmo_ss7.c:1907)
298 
20220319023546289 DLM3UA DEBUG 0: asp-asp-clnt-OsmoMSC-A-Iu: Received M3UA Message (XFER:DATA) (m3ua.c:715)
299 
20220319023546289 DLM3UA DEBUG 0: asp-asp-clnt-OsmoMSC-A-Iu: m3ua_rx_xfer (m3ua.c:543)
300 
20220319023546289 DLM3UA DEBUG 0: asp-asp-clnt-OsmoMSC-A-Iu: m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3 dpc=185=0.23.1 (m3ua.c:569)
301 
20220319023546289 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): found dpc=185=0.23.1 as local (osmo_ss7_hmrt.c:277)
302 
20220319023546289 DLSS7 DEBUG scrc_rx_mtp_xfer_ind_xua: HDR=(CO:RELCO,V=0,LEN=0), PART(T=Destination Reference,L=4,D=00000031), PART(T=Source Reference,L=4,D=00000031) (sccp_scrc.c:472)
303 
20220319023546289 DLSCCP DEBUG Received CO:RELCO for local reference 49 (sccp_scoc.c:1665)
304 
20220319023546289 DLSCCP DEBUG SCCP-SCOC(49)[0x55b8e913e300]{DISCONN_PEND}: Received Event RCOC-RELEASE_COMPLETE.ind (sccp_scoc.c:1698)
305 
20220319023546289 DLSCCP DEBUG SCCP-SCOC(49)[0x55b8e913e300]{DISCONN_PEND}: State change to IDLE (no timeout) (sccp_scoc.c:1175)
306 
20220319023546289 DLSCCP DEBUG SCCP-SCOC(49)[0x55b8e913e300]{IDLE}: Terminating (cause = OSMO_FSM_TERM_REQUEST) (sccp_scoc.c:520)
307 
20220319023546289 DLSCCP DEBUG SCCP-SCOC(49)[0x55b8e913e300]{IDLE}: Freeing instance (sccp_scoc.c:520)
308 
20220319023546289 DLSCCP DEBUG SCCP-SCOC(49)[0x55b8e913e300]{IDLE}: Deallocated (fsm.c:568)
309 
20220319023547479 DLINP DEBUG [CONNECTED] osmo_stream_cli_fd_cb(): connected read (stream.c:445)
310 
20220319023547479 DLINP DEBUG [CONNECTED] osmo_stream_cli_read(): message received (stream.c:324)
311 
20220319023547479 DLSS7 DEBUG 0: asp-asp-clnt-OsmoMSC-A-Iu: xua_cli_read_cb(): sctp_recvmsg() returned 92 (flags=0x80) (osmo_ss7.c:1907)
312 
20220319023547479 DLM3UA DEBUG 0: asp-asp-clnt-OsmoMSC-A-Iu: Received M3UA Message (XFER:DATA) (m3ua.c:715)
313 
20220319023547479 DLM3UA DEBUG 0: asp-asp-clnt-OsmoMSC-A-Iu: m3ua_rx_xfer (m3ua.c:543)
314 
20220319023547479 DLM3UA DEBUG 0: asp-asp-clnt-OsmoMSC-A-Iu: m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3 dpc=185=0.23.1 (m3ua.c:569)
315 
20220319023547479 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): found dpc=185=0.23.1 as local (osmo_ss7_hmrt.c:277)
316 
20220319023547479 DLSUA DEBUG IEI 259: Parsed Addr: RI=2,PC=185,SSN=254 (sccp2sua.c:333)
317 
20220319023547479 DLSUA DEBUG IEI 258: Parsed Addr: RI=2,PC=187,SSN=254 (sccp2sua.c:333)
318 
20220319023547479 DLSS7 DEBUG 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=00000032), PART(T=Destination Address,L=20,D=0002000380020008000000b980030008000000fe), PART(T=Source Address,L=20,D=0002000380020008000000bb80030008000000fe), PART(T=Data,L=39,D=00255705080033f47000650064171005080033f47000655305f42f655780c17d06841004831044) (sccp_scrc.c:472)
319 
20220319023547479 DLSUA DEBUG sua_addr_parse_part(IEI=0x0102) (20) 00 02 00 03 80 02 00 08 00 00 00 bb 80 03 00 08 00 00 00 fe  (sua.c:386)
320 
20220319023547479 DLSUA DEBUG SUA IEI 0x0102 pos 4/20: subpart tag 0x8002, len 8 (sua.c:440)
321 
20220319023547479 DLSUA DEBUG SUA IEI 0x0102 pos 12/20: subpart tag 0x8003, len 8 (sua.c:440)
322 
20220319023547479 DLSUA DEBUG sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 b9 80 03 00 08 00 00 00 fe  (sua.c:386)
323 
20220319023547479 DLSUA DEBUG SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8 (sua.c:440)
324 
20220319023547479 DLSUA DEBUG SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8 (sua.c:440)
325 
20220319023547479 DLSUA DEBUG sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 b9 80 03 00 08 00 00 00 fe  (sua.c:386)
326 
20220319023547479 DLSUA DEBUG SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8 (sua.c:440)
327 
20220319023547479 DLSUA DEBUG SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8 (sua.c:440)
328 
20220319023547479 DLSCCP DEBUG SCCP-SCOC(50)[0x55b8e913e300]{IDLE}: Allocated (fsm.c:456)
329 
20220319023547479 DLSCCP DEBUG Received CO:CORE for local reference 50 (sccp_scoc.c:1665)
330 
20220319023547479 DLSCCP DEBUG SCCP-SCOC(50)[0x55b8e913e300]{IDLE}: Received Event RCOC-CONNECT.ind (sccp_scoc.c:1698)
331 
20220319023547479 DLSUA DEBUG sua_addr_parse_part(IEI=0x0102) (20) 00 02 00 03 80 02 00 08 00 00 00 bb 80 03 00 08 00 00 00 fe  (sua.c:386)
332 
20220319023547479 DLSUA DEBUG SUA IEI 0x0102 pos 4/20: subpart tag 0x8002, len 8 (sua.c:440)
333 
20220319023547479 DLSUA DEBUG SUA IEI 0x0102 pos 12/20: subpart tag 0x8003, len 8 (sua.c:440)
334 
20220319023547479 DLSUA DEBUG sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 b9 80 03 00 08 00 00 00 fe  (sua.c:386)
335 
20220319023547479 DLSUA DEBUG SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8 (sua.c:440)
336 
20220319023547479 DLSUA DEBUG SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8 (sua.c:440)
337 
20220319023547479 DLSCCP DEBUG SCCP-SCOC(50)[0x55b8e913e300]{IDLE}: State change to CONN_PEND_IN (no timeout) (sccp_scoc.c:876)
338 
20220319023547479 DLSCCP DEBUG Delivering N-CONNECT.indication to SCCP User 'OsmoMSC-A' (sccp_user.c:176)
339 
20220319023547479 DBSSAP DEBUG (GERAN-A-50 from RI=SSN_PC,PC=0.23.3,SSN=BSSAP) sccp_ran_sap_up(N-CONNECT.indication) (sccp_ran.c:84)
340 
20220319023547479 DLSCCP DEBUG Received SCCP User Primitive (N-CONNECT.response) (sccp_scoc.c:1732)
341 
20220319023547479 DLSCCP DEBUG SCCP-SCOC(50)[0x55b8e913e300]{CONN_PEND_IN}: Received Event N-CONNECT.resp (sccp_scoc.c:1772)
342 
20220319023547479 DLSS7 DEBUG 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=00000032), PART(T=Source Reference,L=4,D=00000032), PART(T=Sequence Control,L=4,D=00000000), PART(T=Source Address,L=20,D=0002000380020008000000b980030008000000fe), PART(T=Destination Address,L=20,D=0002000380020008000000bb80030008000000fe) (sccp_scrc.c:401)
343 
20220319023547479 DLSUA DEBUG sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 bb 80 03 00 08 00 00 00 fe  (sua.c:386)
344 
20220319023547479 DLSUA DEBUG SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8 (sua.c:440)
345 
20220319023547479 DLSUA DEBUG SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8 (sua.c:440)
346 
20220319023547479 DLSUA DEBUG sua_addr_parse_part(IEI=0x0102) (20) 00 02 00 03 80 02 00 08 00 00 00 b9 80 03 00 08 00 00 00 fe  (sua.c:386)
347 
20220319023547479 DLSUA DEBUG SUA IEI 0x0102 pos 4/20: subpart tag 0x8002, len 8 (sua.c:440)
348 
20220319023547479 DLSUA DEBUG SUA IEI 0x0102 pos 12/20: subpart tag 0x8003, len 8 (sua.c:440)
349 
20220319023547479 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=187=0.23.3 not local, message is for routing (osmo_ss7_hmrt.c:281)
350 
20220319023547479 DLSS7 DEBUG Found route for dpc=187=0.23.3: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoMSC-A-Iu proto=m3ua (osmo_ss7_hmrt.c:228)
351 
20220319023547479 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=187=0.23.3 (osmo_ss7_hmrt.c:236)
352 
20220319023547479 DLSS7 DEBUG XUA_AS(as-clnt-OsmoMSC-A-Iu)[0x55b8e912c0c0]{AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:508)
353 
20220319023547480 DLSCCP DEBUG SCCP-SCOC(50)[0x55b8e913e300]{CONN_PEND_IN}: State change to ACTIVE (no timeout) (sccp_scoc.c:903)
354 
20220319023547480 DRR DEBUG ran_peer(GERAN-A:RI-SSN_PC:PC-0-23-3:SSN-BSSAP)[0x55b8e9134540]{READY}: Received Event RAN_PEER_EV_MSG_UP_CO_INITIAL (ran_peer.c:591)
355 
20220319023547480 DMSC DEBUG msub_fsm[0x55b8e91404a0]{active}: Allocated (fsm.c:456)
356 
20220319023547480 DMSC DEBUG msc_i[0x55b8e91324c0]{READY}: Allocated (fsm.c:456)
357 
20220319023547480 DMSC DEBUG msc_i[0x55b8e91324c0]{READY}: is child of msub_fsm[0x55b8e91404a0] (fsm.c:486)
358 
20220319023547480 DMSC DEBUG msc_a[0x55b8e91359b0]{MSC_A_ST_VALIDATE_L3}: Allocated (fsm.c:456)
359 
20220319023547480 DMSC DEBUG msc_a[0x55b8e91359b0]{MSC_A_ST_VALIDATE_L3}: is child of msub_fsm[0x55b8e91404a0] (fsm.c:486)
360 
20220319023547480 DMSC DEBUG msc_a(unknown:GERAN-A-50:NONE)[0x55b8e91359b0]{MSC_A_ST_VALIDATE_L3}: State change to MSC_A_ST_VALIDATE_L3 (X1, 5s) (msc_a.c:1107)
361 
20220319023547480 DMSC DEBUG msc_i(unknown:GERAN-A-50:NONE)[0x55b8e91324c0]{READY}: Received Event MSC_EV_FROM_RAN_COMPLETE_LAYER_3 (ran_peer.c:387)
362 
20220319023547480 DMSC DEBUG msc_a(unknown:GERAN-A-50:NONE)[0x55b8e91359b0]{MSC_A_ST_VALIDATE_L3}: Received Event MSC_A_EV_FROM_I_COMPLETE_LAYER_3 (msc_i.c:103)
363 
20220319023547480 DREF INFO msc_a(unknown:GERAN-A-50:NONE)[0x55b8e91359b0]{MSC_A_ST_VALIDATE_L3}: + msc_a_ran_dec: now used by 1 (msc_a_ran_dec) (msc_a.c:206)
364 
20220319023547480 DBSSAP DEBUG msc_a(unknown:GERAN-A-50:NONE)[0x55b8e91359b0]{MSC_A_ST_VALIDATE_L3}: RAN decode: BSSMAP: COMPLETE LAYER 3 (ran_msg_a.c:786)
365 
20220319023547480 DBSSAP DEBUG msc_a(unknown:GERAN-A-50:NONE)[0x55b8e91359b0]{MSC_A_ST_VALIDATE_L3}: RAN decode: BSSMAP Complete Layer 3 Information (msc_a.c:1625)
366 
20220319023547480 DRLL DEBUG msc_a(unknown:GERAN-A-50:NONE)[0x55b8e91359b0]{MSC_A_ST_VALIDATE_L3}: Dispatching 04.08 message: MM GSM48_MT_MM_LOC_UPD_REQUEST (msc_a.c:1229)
367 
20220319023547480 DMM DEBUG msc_a(TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_VALIDATE_L3}: LOCATION UPDATING REQUEST: MI=TMSI-0x2F655780 LU-type=NORMAL (gsm_04_08.c:366)
368 
20220319023547480 DMM DEBUG msc_a(TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_VALIDATE_L3}: USIM: old LAI: 334-07-101 (gsm_04_08.c:407)
369 
20220319023547480 DREF DEBUG msc_a(TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_VALIDATE_L3}: + mm_rx_loc_upd_req: now used by 2 (msc_a_ran_dec,mm_rx_loc_upd_req) (gsm_04_08.c:409)
370 
20220319023547480 DREF DEBUG msc_a(TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_VALIDATE_L3}: + lu: now used by 3 (msc_a_ran_dec,mm_rx_loc_upd_req,lu) (gsm_04_08.c:410)
371 
20220319023547480 DVLR DEBUG vlr_lu_fsm(TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91378c0]{VLR_ULA_S_IDLE}: Allocated (fsm.c:456)
372 
20220319023547480 DVLR DEBUG vlr_lu_fsm(TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91378c0]{VLR_ULA_S_IDLE}: is child of msc_a(TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91359b0] (fsm.c:486)
373 
20220319023547480 DVLR DEBUG vlr_lu_fsm(TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91378c0]{VLR_ULA_S_IDLE}: rev=R99 net=GERAN Auth+Ciph (vlr_lu_fsm.c:1521)
374 
20220319023547480 DVLR DEBUG vlr_lu_fsm(TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91378c0]{VLR_ULA_S_IDLE}: Received Event VLR_ULA_E_UPDATE_LA (vlr_lu_fsm.c:1527)
375 
20220319023547480 DREF DEBUG VLR subscr IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780 + _lu_fsm_associate_vsub: now used by 4 (attached,CC,Paging,_lu_fsm_associate_vsub) (vlr_lu_fsm.c:1008)
376 
20220319023547480 DREF DEBUG VLR subscr IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780 + active-conn: now used by 5 (attached,CC,Paging,_lu_fsm_associate_vsub,active-conn) (msub.c:378)
377 
20220319023547480 DMSC DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_VALIDATE_L3}: Received Event MSC_A_EV_COMPLETE_LAYER_3_OK (gsm_04_08.c:1555)
378 
20220319023547480 DMSC DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_VALIDATE_L3}: State change to MSC_A_ST_AUTH_CIPH (keeping X1, 4.999s remaining) (msc_a.c:226)
379 
20220319023547480 DREF DEBUG VLR subscr IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780 - _lu_fsm_associate_vsub: now used by 4 (attached,CC,Paging,active-conn) (vlr_lu_fsm.c:1021)
380 
20220319023547480 DSGS INFO SGs-UE(imsi:334070000000968)[0x55b8e9139430]{SGs-ASSOCIATED}: Received Event RX_LU_FROM_A_Iu_Gs (vlr_lu_fsm.c:1088)
381 
20220319023547480 DSGS INFO SGs-UE(imsi:334070000000968)[0x55b8e9139430]{SGs-ASSOCIATED}: State change to SGs-NULL (no timeout) (vlr_sgs_fsm.c:55)
382 
20220319023547480 DVLR DEBUG set Last E-UTRAN PLMN ID on subscriber: (none) (vlr.c:508)
383 
20220319023547480 DPAG DEBUG Paging: IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780 for MNCC: establish call: Paging Response action (expired) (paging.c:154)
384 
20220319023547480 DPAG DEBUG Paging: IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780 for MNCC: establish call: Removing Paging Request (paging.c:127)
385 
20220319023547480 DCC DEBUG trans(CC:NULL IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780 callref-0x13ad tid-255) Paging expired (gsm_04_08_cc.c:339)
386 
20220319023547480 DMNCC DEBUG trans(CC:NULL IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780 callref-0x13ad tid-255) tx MNCC_REL_IND (gsm_04_08_cc.c:237)
387 
20220319023547480 DCC DEBUG trans(CC:NULL IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780 callref-0x0 tid-255) Freeing transaction (transaction.c:230)
388 
20220319023547480 DREF DEBUG VLR subscr IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780 - CC: now used by 3 (attached,Paging,active-conn) (transaction.c:260)
389 
20220319023547480 DREF DEBUG VLR subscr IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780 - Paging: now used by 2 (attached,active-conn) (paging.c:174)
390 
20220319023547480 DBSSAP DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_AUTH_CIPH}: Sending DTAP: MM GSM48_MT_MM_ID_REQ (msc_a.c:1689)
391 
20220319023547480 DBSSAP DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_AUTH_CIPH}: RAN encode: BSSMAP: DTAP (ran_msg_a.c:1237)
392 
20220319023547480 DMSC DEBUG msc_i(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91324c0]{READY}: Received Event MSC_I_EV_FROM_A_FORWARD_ACCESS_SIGNALLING_REQUEST (msc_a.c:1695)
393 
20220319023547480 DRR DEBUG ran_peer(GERAN-A:RI-SSN_PC:PC-0-23-3:SSN-BSSAP)[0x55b8e9134540]{READY}: Received Event RAN_PEER_EV_MSG_DOWN_CO (ran_conn.c:119)
394 
20220319023547480 DLSCCP DEBUG Received SCCP User Primitive (N-DATA.request) (sccp_scoc.c:1732)
395 
20220319023547480 DLSCCP DEBUG SCCP-SCOC(50)[0x55b8e913e300]{ACTIVE}: Received Event N-DATA.req (sccp_scoc.c:1772)
396 
20220319023547480 DLSS7 DEBUG 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=00000032), PART(T=Data,L=6,D=010003051803) (sccp_scrc.c:401)
397 
20220319023547480 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=187=0.23.3 not local, message is for routing (osmo_ss7_hmrt.c:281)
398 
20220319023547480 DLSS7 DEBUG Found route for dpc=187=0.23.3: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoMSC-A-Iu proto=m3ua (osmo_ss7_hmrt.c:228)
399 
20220319023547480 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=187=0.23.3 (osmo_ss7_hmrt.c:236)
400 
20220319023547480 DLSS7 DEBUG XUA_AS(as-clnt-OsmoMSC-A-Iu)[0x55b8e912c0c0]{AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:508)
401 
20220319023547480 DVLR DEBUG vlr_lu_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91378c0]{VLR_ULA_S_IDLE}: State change to VLR_ULA_S_WAIT_IMEISV (T3270, 12s) (vlr_lu_fsm.c:1098)
402 
20220319023547480 DREF DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_AUTH_CIPH}: - mm_rx_loc_upd_req: now used by 2 (msc_a_ran_dec,lu) (gsm_04_08.c:449)
403 
20220319023547480 DREF DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_AUTH_CIPH}: - msc_a_ran_dec: now used by 1 (lu) (msc_a.c:208)
404 
20220319023547480 DLINP DEBUG [CONNECTED] osmo_stream_cli_fd_cb(): connected write (stream.c:449)
405 
20220319023547480 DLINP DEBUG [CONNECTED] osmo_stream_cli_write(): sending 48 bytes of data (stream.c:352)
406 
20220319023547480 DLINP DEBUG [CONNECTED] osmo_stream_cli_fd_cb(): connected write (stream.c:449)
407 
20220319023547480 DLINP DEBUG [CONNECTED] osmo_stream_cli_write(): sending 48 bytes of data (stream.c:352)
408 
20220319023547480 DLINP DEBUG [CONNECTED] osmo_stream_cli_fd_cb(): connected write (stream.c:449)
409 
20220319023548185 DLINP DEBUG [CONNECTED] osmo_stream_cli_fd_cb(): connected read (stream.c:445)
410 
20220319023548185 DLINP DEBUG [CONNECTED] osmo_stream_cli_read(): message received (stream.c:324)
411 
20220319023548185 DLSS7 DEBUG 0: asp-asp-clnt-OsmoMSC-A-Iu: xua_cli_read_cb(): sctp_recvmsg() returned 56 (flags=0x80) (osmo_ss7.c:1907)
412 
20220319023548185 DLM3UA DEBUG 0: asp-asp-clnt-OsmoMSC-A-Iu: Received M3UA Message (XFER:DATA) (m3ua.c:715)
413 
20220319023548185 DLM3UA DEBUG 0: asp-asp-clnt-OsmoMSC-A-Iu: m3ua_rx_xfer (m3ua.c:543)
414 
20220319023548185 DLM3UA DEBUG 0: asp-asp-clnt-OsmoMSC-A-Iu: m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3 dpc=185=0.23.1 (m3ua.c:569)
415 
20220319023548185 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): found dpc=185=0.23.1 as local (osmo_ss7_hmrt.c:277)
416 
20220319023548185 DLSS7 DEBUG scrc_rx_mtp_xfer_ind_xua: HDR=(CO:CODT,V=0,LEN=0), PART(T=Destination Reference,L=4,D=00000032), PART(T=Segmentation,L=4,D=00000000), PART(T=Data,L=15,D=01800c0559093375920257888000f1) (sccp_scrc.c:472)
417 
20220319023548185 DLSCCP DEBUG Received CO:CODT for local reference 50 (sccp_scoc.c:1665)
418 
20220319023548185 DLSCCP DEBUG SCCP-SCOC(50)[0x55b8e913e300]{ACTIVE}: Received Event RCOC-DT1.ind (sccp_scoc.c:1698)
419 
20220319023548185 DLSCCP DEBUG Delivering N-DATA.indication to SCCP User 'OsmoMSC-A' (sccp_user.c:176)
420 
20220319023548185 DBSSAP DEBUG (GERAN-A-50) sccp_ran_sap_up(N-DATA.indication) (sccp_ran.c:108)
421 
20220319023548185 DRR DEBUG ran_peer(GERAN-A:RI-SSN_PC:PC-0-23-3:SSN-BSSAP)[0x55b8e9134540]{READY}: Received Event RAN_PEER_EV_MSG_UP_CO (ran_peer.c:591)
422 
20220319023548185 DMSC DEBUG msc_i(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91324c0]{READY}: Received Event MSC_EV_FROM_RAN_UP_L2 (ran_peer.c:407)
423 
20220319023548185 DMSC DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_AUTH_CIPH}: Received Event MSC_A_EV_FROM_I_PROCESS_ACCESS_SIGNALLING_REQUEST (msc_i.c:85)
424 
20220319023548185 DREF DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_AUTH_CIPH}: + msc_a_ran_dec: now used by 2 (lu,msc_a_ran_dec) (msc_a.c:206)
425 
20220319023548185 DBSSAP DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_AUTH_CIPH}: RAN decode: BSSAP DTAP (msc_a.c:1625)
426 
20220319023548185 DRLL DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_AUTH_CIPH}: Dispatching 04.08 message: MM GSM48_MT_MM_ID_RESP (msc_a.c:1229)
427 
20220319023548185 DMM DEBUG IDENTITY RESPONSE: IMEI-SV-3572920758808001 (gsm_04_08.c:227)
428 
20220319023548185 DVLR DEBUG set IMEISV on subscriber; IMSI=334070000000968 IMEISV=3572920758808001 (vlr.c:478)
429 
20220319023548185 DVLR DEBUG set IMEI on subscriber; IMSI=334070000000968 IMEI=35729207588080 (vlr.c:469)
430 
20220319023548185 DVLR DEBUG vlr_lu_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91378c0]{VLR_ULA_S_WAIT_IMEISV}: Received Event VLR_ULA_E_ID_IMEISV (vlr.c:1201)
431 
20220319023548185 DVLR DEBUG vlr_lu_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91378c0]{VLR_ULA_S_WAIT_IMEISV}: vlr_loc_upd_node1_pre() (vlr_lu_fsm.c:922)
432 
20220319023548185 DVLR DEBUG vlr_lu_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91378c0]{VLR_ULA_S_WAIT_IMEISV}: State change to VLR_ULA_S_WAIT_HLR_CHECK_IMEI_EARLY (T3270, 12s) (vlr_lu_fsm.c:925)
433 
20220319023548185 DREF DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_AUTH_CIPH}: - msc_a_ran_dec: now used by 1 (lu) (msc_a.c:208)
434 
20220319023548185 DLINP DEBUG 10.23.100.60:4222 connected write (ipa.c:139)
435 
20220319023548185 DLINP DEBUG 10.23.100.60:4222 sending data (ipa.c:89)
436 
20220319023548185 DLINP DEBUG 10.23.100.60:4222 connected write (ipa.c:139)
437 
20220319023548185 DLINP DEBUG 10.23.100.60:4222 sending data (ipa.c:89)
438 
20220319023548185 DLINP DEBUG 10.23.100.60:4222 connected read (ipa.c:135)
439 
20220319023548185 DLINP DEBUG 10.23.100.60:4222 message received (ipa.c:56)
440 
20220319023548185 DREF DEBUG VLR subscr IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780 + vlr_gsup_rx: now used by 3 (attached,active-conn,vlr_gsup_rx) (vlr.c:1111)
441 
20220319023548185 DVLR DEBUG vlr_lu_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91378c0]{VLR_ULA_S_WAIT_HLR_CHECK_IMEI_EARLY}: Received Event VLR_ULA_E_HLR_IMEI_ACK (vlr.c:1091)
442 
20220319023548185 DVLR DEBUG vlr_lu_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91378c0]{VLR_ULA_S_WAIT_HLR_CHECK_IMEI_EARLY}: vlr_loc_upd_node1() (vlr_lu_fsm.c:899)
443 
20220319023548185 DVLR DEBUG vlr_lu_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91378c0]{VLR_ULA_S_WAIT_HLR_CHECK_IMEI_EARLY}: State change to VLR_ULA_S_WAIT_AUTH (T0, 30s) (vlr_lu_fsm.c:906)
444 
20220319023548185 DVLR DEBUG VLR_Authenticate(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e913d250]{VLR_SUB_AS_NEEDS_AUTH}: Allocated (fsm.c:456)
445 
20220319023548185 DVLR DEBUG VLR_Authenticate(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e913d250]{VLR_SUB_AS_NEEDS_AUTH}: is child of vlr_lu_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91378c0] (fsm.c:486)
446 
20220319023548185 DVLR DEBUG VLR_Authenticate(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e913d250]{VLR_SUB_AS_NEEDS_AUTH}: Received Event VLR_AUTH_E_START (vlr_auth_fsm.c:624)
447 
20220319023548185 DVLR DEBUG VLR_Authenticate(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e913d250]{VLR_SUB_AS_NEEDS_AUTH}: State change to VLR_SUB_AS_WAIT_RESP (T3260, 12s) (vlr_auth_fsm.c:322)
448 
20220319023548185 DVLR DEBUG VLR_Authenticate(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e913d250]{VLR_SUB_AS_WAIT_RESP}: got auth tuple: use_count=1 key_seq=1 -- will use UMTS AKA (is_r99=yes, at->vec.auth_types=0x3) (vlr_auth_fsm.c:286)
449 
20220319023548185 DMM DEBUG Tx AUTH REQ (rand = 5a3c81be6bc0bd83ef56381d13e209ec) (gsm_04_08.c:642)
450 
20220319023548185 DMM DEBUG    AUTH REQ (autn = 394314874a230000e17c70f3a1d63d89) (gsm_04_08.c:644)
451 
20220319023548185 DBSSAP DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_AUTH_CIPH}: Sending DTAP: MM GSM48_MT_MM_AUTH_REQ (msc_a.c:1689)
452 
20220319023548185 DBSSAP DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_AUTH_CIPH}: RAN encode: BSSMAP: DTAP (ran_msg_a.c:1237)
453 
20220319023548185 DMSC DEBUG msc_i(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91324c0]{READY}: Received Event MSC_I_EV_FROM_A_FORWARD_ACCESS_SIGNALLING_REQUEST (msc_a.c:1695)
454 
20220319023548185 DRR DEBUG ran_peer(GERAN-A:RI-SSN_PC:PC-0-23-3:SSN-BSSAP)[0x55b8e9134540]{READY}: Received Event RAN_PEER_EV_MSG_DOWN_CO (ran_conn.c:119)
455 
20220319023548185 DLSCCP DEBUG Received SCCP User Primitive (N-DATA.request) (sccp_scoc.c:1732)
456 
20220319023548185 DLSCCP DEBUG SCCP-SCOC(50)[0x55b8e913e300]{ACTIVE}: Received Event N-DATA.req (sccp_scoc.c:1772)
457 
20220319023548185 DLSS7 DEBUG 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=00000032), PART(T=Data,L=40,D=0100250512015a3c81be6bc0bd83ef56381d13e209ec2010394314874a230000e17c70f3a1d63d89) (sccp_scrc.c:401)
458 
20220319023548185 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=187=0.23.3 not local, message is for routing (osmo_ss7_hmrt.c:281)
459 
20220319023548185 DLSS7 DEBUG Found route for dpc=187=0.23.3: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoMSC-A-Iu proto=m3ua (osmo_ss7_hmrt.c:228)
460 
20220319023548185 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=187=0.23.3 (osmo_ss7_hmrt.c:236)
461 
20220319023548185 DLSS7 DEBUG XUA_AS(as-clnt-OsmoMSC-A-Iu)[0x55b8e912c0c0]{AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:508)
462 
20220319023548185 DREF DEBUG VLR subscr IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780 - vlr_gsup_rx: now used by 2 (attached,active-conn) (vlr.c:1157)
463 
20220319023548185 DLINP DEBUG [CONNECTED] osmo_stream_cli_fd_cb(): connected write (stream.c:449)
464 
20220319023548185 DLINP DEBUG [CONNECTED] osmo_stream_cli_write(): sending 80 bytes of data (stream.c:352)
465 
20220319023548185 DLINP DEBUG [CONNECTED] osmo_stream_cli_fd_cb(): connected write (stream.c:449)
466 
20220319023549127 DLINP DEBUG [CONNECTED] osmo_stream_cli_fd_cb(): connected read (stream.c:445)
467 
20220319023549127 DLINP DEBUG [CONNECTED] osmo_stream_cli_read(): message received (stream.c:324)
468 
20220319023549127 DLSS7 DEBUG 0: asp-asp-clnt-OsmoMSC-A-Iu: xua_cli_read_cb(): sctp_recvmsg() returned 56 (flags=0x80) (osmo_ss7.c:1907)
469 
20220319023549127 DLM3UA DEBUG 0: asp-asp-clnt-OsmoMSC-A-Iu: Received M3UA Message (XFER:DATA) (m3ua.c:715)
470 
20220319023549127 DLM3UA DEBUG 0: asp-asp-clnt-OsmoMSC-A-Iu: m3ua_rx_xfer (m3ua.c:543)
471 
20220319023549127 DLM3UA DEBUG 0: asp-asp-clnt-OsmoMSC-A-Iu: m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3 dpc=185=0.23.1 (m3ua.c:569)
472 
20220319023549127 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): found dpc=185=0.23.1 as local (osmo_ss7_hmrt.c:277)
473 
20220319023549127 DLSS7 DEBUG scrc_rx_mtp_xfer_ind_xua: HDR=(CO:CODT,V=0,LEN=0), PART(T=Destination Reference,L=4,D=00000032), PART(T=Segmentation,L=4,D=00000000), PART(T=Data,L=15,D=01800c0594ad8dbf97210415c674bb) (sccp_scrc.c:472)
474 
20220319023549127 DLSCCP DEBUG Received CO:CODT for local reference 50 (sccp_scoc.c:1665)
475 
20220319023549127 DLSCCP DEBUG SCCP-SCOC(50)[0x55b8e913e300]{ACTIVE}: Received Event RCOC-DT1.ind (sccp_scoc.c:1698)
476 
20220319023549127 DLSCCP DEBUG Delivering N-DATA.indication to SCCP User 'OsmoMSC-A' (sccp_user.c:176)
477 
20220319023549127 DBSSAP DEBUG (GERAN-A-50) sccp_ran_sap_up(N-DATA.indication) (sccp_ran.c:108)
478 
20220319023549127 DRR DEBUG ran_peer(GERAN-A:RI-SSN_PC:PC-0-23-3:SSN-BSSAP)[0x55b8e9134540]{READY}: Received Event RAN_PEER_EV_MSG_UP_CO (ran_peer.c:591)
479 
20220319023549127 DMSC DEBUG msc_i(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91324c0]{READY}: Received Event MSC_EV_FROM_RAN_UP_L2 (ran_peer.c:407)
480 
20220319023549127 DMSC DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_AUTH_CIPH}: Received Event MSC_A_EV_FROM_I_PROCESS_ACCESS_SIGNALLING_REQUEST (msc_i.c:85)
481 
20220319023549127 DREF DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_AUTH_CIPH}: + msc_a_ran_dec: now used by 2 (lu,msc_a_ran_dec) (msc_a.c:206)
482 
20220319023549127 DBSSAP DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_AUTH_CIPH}: RAN decode: BSSAP DTAP (msc_a.c:1625)
483 
20220319023549127 DRLL DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_AUTH_CIPH}: Dispatching 04.08 message: MM GSM48_MT_MM_AUTH_RESP (msc_a.c:1229)
484 
20220319023549127 DMM DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_AUTH_CIPH}: MM UMTS AUTHENTICATION RESPONSE (res = ad8dbf9715c674bb) (gsm_04_08.c:1119)
485 
20220319023549127 DVLR DEBUG VLR_Authenticate(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e913d250]{VLR_SUB_AS_WAIT_RESP}: Received Event VLR_AUTH_E_MS_AUTH_RESP (vlr.c:1358)
486 
20220319023549127 DVLR DEBUG SUBSCR(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780) AUTH on GERAN received SRES/RES: ad8dbf9715c674bb (8 bytes) (vlr_auth_fsm.c:136)
487 
20220319023549127 DVLR INFO SUBSCR(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780) AUTH established UMTS security context (vlr_auth_fsm.c:195)
488 
20220319023549127 DVLR DEBUG VLR_Authenticate(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e913d250]{VLR_SUB_AS_WAIT_RESP}: Authentication terminating with result PASSED (vlr_auth_fsm.c:244)
489 
20220319023549127 DVLR DEBUG VLR_Authenticate(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e913d250]{VLR_SUB_AS_WAIT_RESP}: State change to VLR_SUB_AS_AUTHENTICATED (no timeout) (vlr_auth_fsm.c:248)
490 
20220319023549127 DVLR DEBUG VLR_Authenticate(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e913d250]{VLR_SUB_AS_AUTHENTICATED}: Terminating (cause = OSMO_FSM_TERM_REGULAR) (vlr_auth_fsm.c:253)
491 
20220319023549127 DVLR DEBUG VLR_Authenticate(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e913d250]{VLR_SUB_AS_AUTHENTICATED}: Removing from parent vlr_lu_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91378c0] (vlr_auth_fsm.c:253)
492 
20220319023549127 DVLR DEBUG VLR_Authenticate(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e913d250]{VLR_SUB_AS_AUTHENTICATED}: Freeing instance (vlr_auth_fsm.c:253)
493 
20220319023549127 DVLR DEBUG VLR_Authenticate(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e913d250]{VLR_SUB_AS_AUTHENTICATED}: Deallocated (fsm.c:568)
494 
20220319023549127 DVLR DEBUG vlr_lu_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91378c0]{VLR_ULA_S_WAIT_AUTH}: Received Event VLR_ULA_E_AUTH_RES (vlr_auth_fsm.c:253)
495 
20220319023549127 DVLR DEBUG vlr_lu_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91378c0]{VLR_ULA_S_WAIT_AUTH}: vlr_loc_upd_post_auth() (vlr_lu_fsm.c:854)
496 
20220319023549127 DVLR DEBUG vlr_lu_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91378c0]{VLR_ULA_S_WAIT_AUTH}: Set Ciphering Mode (vlr.c:1388)
497 
20220319023549127 DBSSAP DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_AUTH_CIPH}: RAN encode: BSSMAP: CIPHER_MODE_COMMAND (ran_msg_a.c:1237)
498 
20220319023549127 DBSSAP DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_AUTH_CIPH}: RAN encode: BSSMAP: Tx BSSMAP CIPHER MODE COMMAND to BSC, 1 ciphers (02) key 4c8760219b73d9e8 (ran_msg_a.c:1125)
499 
20220319023549127 DMSC DEBUG msc_i(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91324c0]{READY}: Received Event MSC_I_EV_FROM_A_FORWARD_ACCESS_SIGNALLING_REQUEST (msc_a.c:367)
500 
20220319023549127 DRR DEBUG ran_peer(GERAN-A:RI-SSN_PC:PC-0-23-3:SSN-BSSAP)[0x55b8e9134540]{READY}: Received Event RAN_PEER_EV_MSG_DOWN_CO (ran_conn.c:119)
501 
20220319023549127 DLSCCP DEBUG Received SCCP User Primitive (N-DATA.request) (sccp_scoc.c:1732)
502 
20220319023549127 DLSCCP DEBUG SCCP-SCOC(50)[0x55b8e913e300]{ACTIVE}: Received Event N-DATA.req (sccp_scoc.c:1772)
503 
20220319023549127 DLSS7 DEBUG 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=00000032), PART(T=Data,L=16,D=000e530a09024c8760219b73d9e82301) (sccp_scrc.c:401)
504 
20220319023549127 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=187=0.23.3 not local, message is for routing (osmo_ss7_hmrt.c:281)
505 
20220319023549127 DLSS7 DEBUG Found route for dpc=187=0.23.3: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoMSC-A-Iu proto=m3ua (osmo_ss7_hmrt.c:228)
506 
20220319023549127 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=187=0.23.3 (osmo_ss7_hmrt.c:236)
507 
20220319023549127 DLSS7 DEBUG XUA_AS(as-clnt-OsmoMSC-A-Iu)[0x55b8e912c0c0]{AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:508)
508 
20220319023549128 DBSSAP DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_AUTH_CIPH}: RAN encoding chose ciphering: A5/-1 kc 4c8760219b73d9e8 kc128 - (msc_a.c:380)
509 
20220319023549128 DVLR DEBUG vlr_lu_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91378c0]{VLR_ULA_S_WAIT_AUTH}: State change to VLR_ULA_S_WAIT_CIPH (T0, 30s) (vlr_lu_fsm.c:891)
510 
20220319023549128 DREF DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_AUTH_CIPH}: - msc_a_ran_dec: now used by 1 (lu) (msc_a.c:208)
511 
20220319023549128 DLINP DEBUG [CONNECTED] osmo_stream_cli_fd_cb(): connected write (stream.c:449)
512 
20220319023549128 DLINP DEBUG [CONNECTED] osmo_stream_cli_write(): sending 56 bytes of data (stream.c:352)
513 
20220319023549128 DLINP DEBUG [CONNECTED] osmo_stream_cli_fd_cb(): connected write (stream.c:449)
514 
20220319023549835 DLINP DEBUG [CONNECTED] osmo_stream_cli_fd_cb(): connected read (stream.c:445)
515 
20220319023549835 DLINP DEBUG [CONNECTED] osmo_stream_cli_read(): message received (stream.c:324)
516 
20220319023549835 DLSS7 DEBUG 0: asp-asp-clnt-OsmoMSC-A-Iu: xua_cli_read_cb(): sctp_recvmsg() returned 60 (flags=0x80) (osmo_ss7.c:1907)
517 
20220319023549835 DLM3UA DEBUG 0: asp-asp-clnt-OsmoMSC-A-Iu: Received M3UA Message (XFER:DATA) (m3ua.c:715)
518 
20220319023549835 DLM3UA DEBUG 0: asp-asp-clnt-OsmoMSC-A-Iu: m3ua_rx_xfer (m3ua.c:543)
519 
20220319023549835 DLM3UA DEBUG 0: asp-asp-clnt-OsmoMSC-A-Iu: m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3 dpc=185=0.23.1 (m3ua.c:569)
520 
20220319023549835 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): found dpc=185=0.23.1 as local (osmo_ss7_hmrt.c:277)
521 
20220319023549835 DLSS7 DEBUG scrc_rx_mtp_xfer_ind_xua: HDR=(CO:CODT,V=0,LEN=0), PART(T=Destination Reference,L=4,D=00000032), PART(T=Segmentation,L=4,D=00000000), PART(T=Data,L=20,D=001255200d063217093375920257888000f12c02) (sccp_scrc.c:472)
522 
20220319023549835 DLSCCP DEBUG Received CO:CODT for local reference 50 (sccp_scoc.c:1665)
523 
20220319023549835 DLSCCP DEBUG SCCP-SCOC(50)[0x55b8e913e300]{ACTIVE}: Received Event RCOC-DT1.ind (sccp_scoc.c:1698)
524 
20220319023549835 DLSCCP DEBUG Delivering N-DATA.indication to SCCP User 'OsmoMSC-A' (sccp_user.c:176)
525 
20220319023549835 DBSSAP DEBUG (GERAN-A-50) sccp_ran_sap_up(N-DATA.indication) (sccp_ran.c:108)
526 
20220319023549835 DRR DEBUG ran_peer(GERAN-A:RI-SSN_PC:PC-0-23-3:SSN-BSSAP)[0x55b8e9134540]{READY}: Received Event RAN_PEER_EV_MSG_UP_CO (ran_peer.c:591)
527 
20220319023549835 DMSC DEBUG msc_i(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91324c0]{READY}: Received Event MSC_EV_FROM_RAN_UP_L2 (ran_peer.c:407)
528 
20220319023549835 DBSSAP DEBUG msc_i(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91324c0]{READY}: RAN decode: BSSMAP: CIPHER MODE COMPLETE (ran_msg_a.c:786)
529 
20220319023549835 DMSC DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_AUTH_CIPH}: Received Event MSC_A_EV_FROM_I_PROCESS_ACCESS_SIGNALLING_REQUEST (msc_i.c:85)
530 
20220319023549835 DREF DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_AUTH_CIPH}: + msc_a_ran_dec: now used by 2 (lu,msc_a_ran_dec) (msc_a.c:206)
531 
20220319023549835 DBSSAP DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_AUTH_CIPH}: RAN decode: BSSMAP: CIPHER MODE COMPLETE (ran_msg_a.c:786)
532 
20220319023549835 DBSSAP DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_AUTH_CIPH}: RAN decode: BSSMAP Ciphering Mode Complete (msc_a.c:1625)
533 
20220319023549835 DBSSAP DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_AUTH_CIPH}: Cipher Mode Complete: chosen encryption algorithm: A5/1 (msc_a.c:1475)
534 
20220319023549835 DVLR DEBUG vlr_lu_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91378c0]{VLR_ULA_S_WAIT_CIPH}: Received Event VLR_ULA_E_CIPH_RES (vlr.c:1367)
535 
20220319023549835 DVLR DEBUG vlr_lu_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91378c0]{VLR_ULA_S_WAIT_CIPH}: vlr_loc_upd_post_ciph() (vlr_lu_fsm.c:825)
536 
20220319023549835 DBSSAP DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_AUTH_CIPH}: RAN encode: BSSMAP: COMMON_ID (ran_msg_a.c:1237)
537 
20220319023549835 DMSC DEBUG msc_i(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91324c0]{READY}: Received Event MSC_I_EV_FROM_A_FORWARD_ACCESS_SIGNALLING_REQUEST (gsm_04_08.c:1498)
538 
20220319023549835 DRR DEBUG ran_peer(GERAN-A:RI-SSN_PC:PC-0-23-3:SSN-BSSAP)[0x55b8e9134540]{READY}: Received Event RAN_PEER_EV_MSG_DOWN_CO (ran_conn.c:119)
539 
20220319023549835 DLSCCP DEBUG Received SCCP User Primitive (N-DATA.request) (sccp_scoc.c:1732)
540 
20220319023549835 DLSCCP DEBUG SCCP-SCOC(50)[0x55b8e913e300]{ACTIVE}: Received Event N-DATA.req (sccp_scoc.c:1772)
541 
20220319023549835 DLSS7 DEBUG 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=00000032), PART(T=Data,L=13,D=000b2f08083943700000009086) (sccp_scrc.c:401)
542 
20220319023549835 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=187=0.23.3 not local, message is for routing (osmo_ss7_hmrt.c:281)
543 
20220319023549835 DLSS7 DEBUG Found route for dpc=187=0.23.3: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoMSC-A-Iu proto=m3ua (osmo_ss7_hmrt.c:228)
544 
20220319023549835 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=187=0.23.3 (osmo_ss7_hmrt.c:236)
545 
20220319023549835 DLSS7 DEBUG XUA_AS(as-clnt-OsmoMSC-A-Iu)[0x55b8e912c0c0]{AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:508)
546 
20220319023549835 DVLR DEBUG vlr_lu_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91378c0]{VLR_ULA_S_WAIT_CIPH}: vlr_loc_upd_node_4() (vlr_lu_fsm.c:785)
547 
20220319023549835 DVLR DEBUG vlr_lu_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91378c0]{VLR_ULA_S_WAIT_CIPH}: State change to VLR_ULA_S_WAIT_HLR_UPD (T0, 30s) (vlr_lu_fsm.c:794)
548 
20220319023549835 DVLR DEBUG upd_hlr_vlr_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e913d250]{UPD_HLR_VLR_S_INIT}: Allocated (fsm.c:456)
549 
20220319023549835 DVLR DEBUG upd_hlr_vlr_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e913d250]{UPD_HLR_VLR_S_INIT}: is child of vlr_lu_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91378c0] (fsm.c:486)
550 
20220319023549835 DVLR DEBUG upd_hlr_vlr_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e913d250]{UPD_HLR_VLR_S_INIT}: Received Event UPD_HLR_VLR_E_START (vlr_lu_fsm.c:176)
551 
20220319023549835 DVLR DEBUG upd_hlr_vlr_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e913d250]{UPD_HLR_VLR_S_INIT}: State change to UPD_HLR_VLR_S_WAIT_FOR_DATA (T0, 30s) (vlr_lu_fsm.c:87)
552 
20220319023549835 DRLL DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_AUTH_CIPH}: Dispatching 04.08 message: RR GSM48_MT_RR_CIPH_M_COMPL (msc_a.c:1229)
553 
20220319023549835 DBSSAP DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_AUTH_CIPH}: RR Ciphering Mode Complete contains Mobile Identity: IMEI-SV-3572920758808001 (gsm_04_08.c:1352)
554 
20220319023549835 DVLR DEBUG set IMEISV on subscriber; IMSI=334070000000968 IMEISV=3572920758808001 (vlr.c:478)
555 
20220319023549835 DVLR DEBUG set IMEI on subscriber; IMSI=334070000000968 IMEI=35729207588080 (vlr.c:469)
556 
20220319023549835 DVLR DEBUG vlr_lu_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91378c0]{VLR_ULA_S_WAIT_HLR_UPD}: Received Event VLR_ULA_E_ID_IMEISV (vlr.c:1201)
557 
20220319023549835 DREF DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_AUTH_CIPH}: - msc_a_ran_dec: now used by 1 (lu) (msc_a.c:208)
558 
20220319023549835 DLINP DEBUG 10.23.100.60:4222 connected write (ipa.c:139)
559 
20220319023549835 DLINP DEBUG 10.23.100.60:4222 sending data (ipa.c:89)
560 
20220319023549835 DLINP DEBUG [CONNECTED] osmo_stream_cli_fd_cb(): connected write (stream.c:449)
561 
20220319023549835 DLINP DEBUG [CONNECTED] osmo_stream_cli_write(): sending 52 bytes of data (stream.c:352)
562 
20220319023549836 DLINP DEBUG 10.23.100.60:4222 connected write (ipa.c:139)
563 
20220319023549836 DLINP DEBUG 10.23.100.60:4222 sending data (ipa.c:89)
564 
20220319023549836 DLINP DEBUG [CONNECTED] osmo_stream_cli_fd_cb(): connected write (stream.c:449)
565 
20220319023549851 DLINP DEBUG 10.23.100.60:4222 connected read (ipa.c:135)
566 
20220319023549851 DLINP DEBUG 10.23.100.60:4222 message received (ipa.c:56)
567 
20220319023549851 DREF DEBUG VLR subscr IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780 + vlr_gsup_rx: now used by 3 (attached,active-conn,vlr_gsup_rx) (vlr.c:1111)
568 
20220319023549851 DVLR DEBUG IMSI:334070000000968 has MSISDN:12345174747 (vlr.c:829)
569 
20220319023549851 DVLR NOTICE SUBSCR(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780) VLR: update for IMSI=334070000000968 (MSISDN=12345174747) (gsm_04_08.c:1526)
570 
20220319023549851 DREF DEBUG VLR subscr IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780 - vlr_gsup_rx: now used by 2 (attached,active-conn) (vlr.c:1157)
571 
20220319023549851 DLINP DEBUG 10.23.100.60:4222 connected write (ipa.c:139)
572 
20220319023549851 DLINP DEBUG 10.23.100.60:4222 sending data (ipa.c:89)
573 
20220319023549851 DLINP DEBUG 10.23.100.60:4222 connected write (ipa.c:139)
574 
20220319023549851 DLINP DEBUG 10.23.100.60:4222 sending data (ipa.c:89)
575 
20220319023549852 DLINP DEBUG 10.23.100.60:4222 connected read (ipa.c:135)
576 
20220319023549852 DLINP DEBUG 10.23.100.60:4222 message received (ipa.c:56)
577 
20220319023549852 DREF DEBUG VLR subscr IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780 + vlr_gsup_rx: now used by 3 (attached,active-conn,vlr_gsup_rx) (vlr.c:1111)
578 
20220319023549852 DVLR DEBUG vlr_lu_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91378c0]{VLR_ULA_S_WAIT_HLR_UPD}: Received Event VLR_ULA_E_HLR_LU_RES (vlr.c:930)
579 
20220319023549852 DVLR DEBUG upd_hlr_vlr_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e913d250]{UPD_HLR_VLR_S_WAIT_FOR_DATA}: Received Event UPD_HLR_VLR_E_UPD_LOC_ACK (vlr_lu_fsm.c:1209)
580 
20220319023549852 DVLR DEBUG upd_hlr_vlr_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e913d250]{UPD_HLR_VLR_S_WAIT_FOR_DATA}: State change to UPD_HLR_VLR_S_DONE (no timeout) (vlr_lu_fsm.c:109)
581 
20220319023549852 DVLR DEBUG upd_hlr_vlr_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e913d250]{UPD_HLR_VLR_S_DONE}: Terminating (cause = OSMO_FSM_TERM_REGULAR) (vlr_lu_fsm.c:110)
582 
20220319023549852 DVLR DEBUG upd_hlr_vlr_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e913d250]{UPD_HLR_VLR_S_DONE}: Removing from parent vlr_lu_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91378c0] (vlr_lu_fsm.c:110)
583 
20220319023549852 DVLR DEBUG upd_hlr_vlr_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e913d250]{UPD_HLR_VLR_S_DONE}: Freeing instance (vlr_lu_fsm.c:110)
584 
20220319023549852 DVLR DEBUG upd_hlr_vlr_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e913d250]{UPD_HLR_VLR_S_DONE}: Deallocated (fsm.c:568)
585 
20220319023549852 DVLR DEBUG vlr_lu_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91378c0]{VLR_ULA_S_WAIT_HLR_UPD}: Received Event VLR_ULA_E_UPD_HLR_COMPL (vlr_lu_fsm.c:110)
586 
20220319023549852 DVLR DEBUG vlr_lu_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91378c0]{VLR_ULA_S_WAIT_HLR_UPD}: State change to VLR_ULA_S_WAIT_LU_COMPL (T0, 30s) (vlr_lu_fsm.c:1217)
587 
20220319023549852 DVLR DEBUG lu_compl_vlr_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e913e030]{LU_COMPL_VLR_S_INIT}: Allocated (fsm.c:456)
588 
20220319023549852 DVLR DEBUG lu_compl_vlr_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e913e030]{LU_COMPL_VLR_S_INIT}: is child of vlr_lu_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e91378c0] (fsm.c:486)
589 
20220319023549852 DVLR DEBUG lu_compl_vlr_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e913e030]{LU_COMPL_VLR_S_INIT}: Received Event LU_COMPL_VLR_E_START (vlr_lu_fsm.c:767)
590 
20220319023549852 DVLR DEBUG lu_compl_vlr_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e913e030]{LU_COMPL_VLR_S_INIT}: State change to LU_COMPL_VLR_S_WAIT_SUB_PRES (T0, 30s) (vlr_lu_fsm.c:411)
591 
20220319023549852 DVLR DEBUG lu_compl_vlr_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e913e030]{LU_COMPL_VLR_S_WAIT_SUB_PRES}: Received Event LU_COMPL_VLR_E_SUB_PRES_COMPL (vlr_lu_fsm.c:418)
592 
20220319023549852 DVLR DEBUG lu_compl_vlr_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e913e030]{LU_COMPL_VLR_S_WAIT_SUB_PRES}: lu_compl_vlr_new_tmsi() (vlr_lu_fsm.c:427)
593 
20220319023549852 DVLR NOTICE SUBSCR(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:TMSInew-0x31E9FC86) VLR: update for IMSI=334070000000968 (MSISDN=12345174747) (gsm_04_08.c:1526)
594 
20220319023549852 DVLR DEBUG lu_compl_vlr_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e913e030]{LU_COMPL_VLR_S_WAIT_SUB_PRES}: State change to LU_COMPL_VLR_S_WAIT_TMSI_CNF (T3250, 12s) (vlr_lu_fsm.c:435)
595 
20220319023549852 DMM DEBUG -> IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:TMSInew-0x31E9FC86 LOCATION UPDATE ACCEPT (TMSI = 0x31e9fc86) (gsm_04_08.c:155)
596 
20220319023549852 DBSSAP DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:TMSInew-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_AUTH_CIPH}: Sending DTAP: MM GSM48_MT_MM_LOC_UPD_ACCEPT (msc_a.c:1689)
597 
20220319023549852 DBSSAP DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:TMSInew-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_AUTH_CIPH}: RAN encode: BSSMAP: DTAP (ran_msg_a.c:1237)
598 
20220319023549852 DMSC DEBUG msc_i(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:TMSInew-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91324c0]{READY}: Received Event MSC_I_EV_FROM_A_FORWARD_ACCESS_SIGNALLING_REQUEST (msc_a.c:1695)
599 
20220319023549852 DRR DEBUG ran_peer(GERAN-A:RI-SSN_PC:PC-0-23-3:SSN-BSSAP)[0x55b8e9134540]{READY}: Received Event RAN_PEER_EV_MSG_DOWN_CO (ran_conn.c:119)
600 
20220319023549852 DLSCCP DEBUG Received SCCP User Primitive (N-DATA.request) (sccp_scoc.c:1732)
601 
20220319023549852 DLSCCP DEBUG SCCP-SCOC(50)[0x55b8e913e300]{ACTIVE}: Received Event N-DATA.req (sccp_scoc.c:1772)
602 
20220319023549852 DLSS7 DEBUG 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=00000032), PART(T=Data,L=17,D=01000e050233f47000651705f431e9fc86) (sccp_scrc.c:401)
603 
20220319023549852 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=187=0.23.3 not local, message is for routing (osmo_ss7_hmrt.c:281)
604 
20220319023549852 DLSS7 DEBUG Found route for dpc=187=0.23.3: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoMSC-A-Iu proto=m3ua (osmo_ss7_hmrt.c:228)
605 
20220319023549852 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=187=0.23.3 (osmo_ss7_hmrt.c:236)
606 
20220319023549852 DLSS7 DEBUG XUA_AS(as-clnt-OsmoMSC-A-Iu)[0x55b8e912c0c0]{AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:508)
607 
20220319023549852 DREF DEBUG VLR subscr IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:TMSInew-0x31E9FC86 - vlr_gsup_rx: now used by 2 (attached,active-conn) (vlr.c:1157)
608 
20220319023549852 DLINP DEBUG [CONNECTED] osmo_stream_cli_fd_cb(): connected write (stream.c:449)
609 
20220319023549853 DLINP DEBUG [CONNECTED] osmo_stream_cli_write(): sending 56 bytes of data (stream.c:352)
610 
20220319023549853 DLINP DEBUG [CONNECTED] osmo_stream_cli_fd_cb(): connected write (stream.c:449)
611 
20220319023550539 DLINP DEBUG [CONNECTED] osmo_stream_cli_fd_cb(): connected read (stream.c:445)
612 
20220319023550539 DLINP DEBUG [CONNECTED] osmo_stream_cli_read(): message received (stream.c:324)
613 
20220319023550539 DLSS7 DEBUG 0: asp-asp-clnt-OsmoMSC-A-Iu: xua_cli_read_cb(): sctp_recvmsg() returned 44 (flags=0x80) (osmo_ss7.c:1907)
614 
20220319023550539 DLM3UA DEBUG 0: asp-asp-clnt-OsmoMSC-A-Iu: Received M3UA Message (XFER:DATA) (m3ua.c:715)
615 
20220319023550539 DLM3UA DEBUG 0: asp-asp-clnt-OsmoMSC-A-Iu: m3ua_rx_xfer (m3ua.c:543)
616 
20220319023550539 DLM3UA DEBUG 0: asp-asp-clnt-OsmoMSC-A-Iu: m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3 dpc=185=0.23.1 (m3ua.c:569)
617 
20220319023550539 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): found dpc=185=0.23.1 as local (osmo_ss7_hmrt.c:277)
618 
20220319023550539 DLSS7 DEBUG scrc_rx_mtp_xfer_ind_xua: HDR=(CO:CODT,V=0,LEN=0), PART(T=Destination Reference,L=4,D=00000032), PART(T=Segmentation,L=4,D=00000000), PART(T=Data,L=5,D=01800205db) (sccp_scrc.c:472)
619 
20220319023550539 DLSCCP DEBUG Received CO:CODT for local reference 50 (sccp_scoc.c:1665)
620 
20220319023550539 DLSCCP DEBUG SCCP-SCOC(50)[0x55b8e913e300]{ACTIVE}: Received Event RCOC-DT1.ind (sccp_scoc.c:1698)
621 
20220319023550539 DLSCCP DEBUG Delivering N-DATA.indication to SCCP User 'OsmoMSC-A' (sccp_user.c:176)
622 
20220319023550539 DBSSAP DEBUG (GERAN-A-50) sccp_ran_sap_up(N-DATA.indication) (sccp_ran.c:108)
623 
20220319023550539 DRR DEBUG ran_peer(GERAN-A:RI-SSN_PC:PC-0-23-3:SSN-BSSAP)[0x55b8e9134540]{READY}: Received Event RAN_PEER_EV_MSG_UP_CO (ran_peer.c:591)
624 
20220319023550539 DMSC DEBUG msc_i(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:TMSInew-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91324c0]{READY}: Received Event MSC_EV_FROM_RAN_UP_L2 (ran_peer.c:407)
625 
20220319023550539 DMSC DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:TMSInew-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_AUTH_CIPH}: Received Event MSC_A_EV_FROM_I_PROCESS_ACCESS_SIGNALLING_REQUEST (msc_i.c:85)
626 
20220319023550539 DREF DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:TMSInew-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_AUTH_CIPH}: + msc_a_ran_dec: now used by 2 (lu,msc_a_ran_dec) (msc_a.c:206)
627 
20220319023550539 DBSSAP DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:TMSInew-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_AUTH_CIPH}: RAN decode: BSSAP DTAP (msc_a.c:1625)
628 
20220319023550539 DRLL DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:TMSInew-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_AUTH_CIPH}: Dispatching 04.08 message: MM GSM48_MT_MM_TMSI_REALL_COMPL (msc_a.c:1229)
629 
20220319023550539 DMM DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:TMSInew-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_AUTH_CIPH}: TMSI Reallocation Completed (gsm_04_08.c:1206)
630 
20220319023550539 DVLR DEBUG vlr_lu_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:TMSInew-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91378c0]{VLR_ULA_S_WAIT_LU_COMPL}: Received Event VLR_ULA_E_NEW_TMSI_ACK (vlr.c:1215)
631 
20220319023550539 DVLR DEBUG lu_compl_vlr_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e913e030]{LU_COMPL_VLR_S_WAIT_TMSI_CNF}: Received Event LU_COMPL_VLR_E_NEW_TMSI_ACK (vlr_lu_fsm.c:1255)
632 
20220319023550539 DVLR NOTICE SUBSCR(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86) VLR: update for IMSI=334070000000968 (MSISDN=12345174747) (gsm_04_08.c:1526)
633 
20220319023550539 DVLR DEBUG lu_compl_vlr_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e913e030]{LU_COMPL_VLR_S_WAIT_TMSI_CNF}: State change to LU_COMPL_VLR_S_DONE (no timeout) (vlr_lu_fsm.c:353)
634 
20220319023550539 DVLR DEBUG vlr_lu_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91378c0]{VLR_ULA_S_WAIT_LU_COMPL}: Received Event VLR_ULA_E_LU_COMPL_SUCCESS (vlr_lu_fsm.c:381)
635 
20220319023550539 DVLR DEBUG lu_compl_vlr_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e913e030]{LU_COMPL_VLR_S_DONE}: Terminating (cause = OSMO_FSM_TERM_PARENT) (vlr_lu_fsm.c:775)
636 
20220319023550539 DVLR DEBUG lu_compl_vlr_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e913e030]{LU_COMPL_VLR_S_DONE}: Removing from parent vlr_lu_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91378c0] (vlr_lu_fsm.c:775)
637 
20220319023550539 DVLR DEBUG lu_compl_vlr_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e913e030]{LU_COMPL_VLR_S_DONE}: Freeing instance (vlr_lu_fsm.c:775)
638 
20220319023550539 DVLR DEBUG lu_compl_vlr_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x2F655780:GERAN-A-50:LU)[0x55b8e913e030]{LU_COMPL_VLR_S_DONE}: Deallocated (fsm.c:568)
639 
20220319023550539 DVLR DEBUG vlr_lu_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91378c0]{VLR_ULA_S_WAIT_LU_COMPL}: State change to VLR_ULA_S_DONE (no timeout) (vlr_lu_fsm.c:743)
640 
20220319023550539 DMSC DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_AUTH_CIPH}: Received Event MSC_A_EV_AUTHENTICATED (vlr_lu_fsm.c:735)
641 
20220319023550539 DMSC DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_AUTH_CIPH}: State change to MSC_A_ST_AUTHENTICATED (keeping X1, 1.940s remaining) (msc_a.c:396)
642 
20220319023550539 DSMPP DEBUG [NOTIFY] Tx ALERT_NOTIFICATION (12345174747/3/1): Available (smpp_smsc.c:657)
643 
20220319023550539 DSMPP DEBUG [OSMPP] is not set to receive Alert Notifications (smpp_openbsc.c:331)
644 
20220319023550540 DREF DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_AUTHENTICATED}: - lu: now used by 1 (msc_a_ran_dec) (msc_a.c:168)
645 
20220319023550540 DREF INFO msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_AUTHENTICATED}: - msc_a_ran_dec: now used by 0 (-) (msc_a.c:208)
646 
20220319023550540 DMSC DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_AUTHENTICATED}: Received Event MSC_A_EV_UNUSED (msc_a.c:1094)
647 
20220319023550540 DMSC DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_AUTHENTICATED}: State change to MSC_A_ST_RELEASING (X2, 30s) (msc_a.c:545)
648 
20220319023550540 DBSSAP DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_RELEASING}: Releasing: msc_a use is 0 (-) (msc_a.c:780)
649 
20220319023550540 DREF DEBUG VLR subscr IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86 + msc_a_fsm_releasing_onenter: now used by 3 (attached,active-conn,msc_a_fsm_releasing_onenter) (msc_a.c:783)
650 
20220319023550540 DREF DEBUG VLR subscr IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86 + vlr_subscr_cancel_attach_fsm: now used by 4 (attached,active-conn,msc_a_fsm_releasing_onenter,vlr_subscr_cancel_attach_fsm) (vlr.c:309)
651 
20220319023550540 DREF DEBUG VLR subscr IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86 - vlr_subscr_cancel_attach_fsm: now used by 3 (attached,active-conn,msc_a_fsm_releasing_onenter) (vlr.c:314)
652 
20220319023550540 DREF INFO msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_RELEASING}: + wait-Clear-Complete: now used by 1 (wait-Clear-Complete) (msc_a.c:822)
653 
20220319023550540 DBSSAP DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_RELEASING}: RAN encode: BSSMAP: CLEAR_COMMAND (ran_msg_a.c:1237)
654 
20220319023550540 DMSC DEBUG msc_i(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91324c0]{READY}: Received Event MSC_I_EV_FROM_A_FORWARD_ACCESS_SIGNALLING_REQUEST (msc_a.c:823)
655 
20220319023550540 DRR DEBUG ran_peer(GERAN-A:RI-SSN_PC:PC-0-23-3:SSN-BSSAP)[0x55b8e9134540]{READY}: Received Event RAN_PEER_EV_MSG_DOWN_CO (ran_conn.c:119)
656 
20220319023550540 DLSCCP DEBUG Received SCCP User Primitive (N-DATA.request) (sccp_scoc.c:1732)
657 
20220319023550540 DLSCCP DEBUG SCCP-SCOC(50)[0x55b8e913e300]{ACTIVE}: Received Event N-DATA.req (sccp_scoc.c:1772)
658 
20220319023550540 DLSS7 DEBUG 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=00000032), PART(T=Data,L=6,D=000420040109) (sccp_scrc.c:401)
659 
20220319023550540 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=187=0.23.3 not local, message is for routing (osmo_ss7_hmrt.c:281)
660 
20220319023550540 DLSS7 DEBUG Found route for dpc=187=0.23.3: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoMSC-A-Iu proto=m3ua (osmo_ss7_hmrt.c:228)
661 
20220319023550540 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=187=0.23.3 (osmo_ss7_hmrt.c:236)
662 
20220319023550540 DLSS7 DEBUG XUA_AS(as-clnt-OsmoMSC-A-Iu)[0x55b8e912c0c0]{AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:508)
663 
20220319023550540 DREF DEBUG VLR subscr IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86 - msc_a_fsm_releasing_onenter: now used by 2 (attached,active-conn) (msc_a.c:832)
664 
20220319023550540 DLINP DEBUG [CONNECTED] osmo_stream_cli_fd_cb(): connected write (stream.c:449)
665 
20220319023550540 DLINP DEBUG [CONNECTED] osmo_stream_cli_write(): sending 48 bytes of data (stream.c:352)
666 
20220319023550540 DLINP DEBUG [CONNECTED] osmo_stream_cli_fd_cb(): connected write (stream.c:449)
667 
20220319023550541 DLINP DEBUG [CONNECTED] osmo_stream_cli_fd_cb(): connected read (stream.c:445)
668 
20220319023550541 DLINP DEBUG [CONNECTED] osmo_stream_cli_read(): message received (stream.c:324)
669 
20220319023550541 DLSS7 DEBUG 0: asp-asp-clnt-OsmoMSC-A-Iu: xua_cli_read_cb(): sctp_recvmsg() returned 44 (flags=0x80) (osmo_ss7.c:1907)
670 
20220319023550541 DLM3UA DEBUG 0: asp-asp-clnt-OsmoMSC-A-Iu: Received M3UA Message (XFER:DATA) (m3ua.c:715)
671 
20220319023550541 DLM3UA DEBUG 0: asp-asp-clnt-OsmoMSC-A-Iu: m3ua_rx_xfer (m3ua.c:543)
672 
20220319023550541 DLM3UA DEBUG 0: asp-asp-clnt-OsmoMSC-A-Iu: m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3 dpc=185=0.23.1 (m3ua.c:569)
673 
20220319023550541 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): found dpc=185=0.23.1 as local (osmo_ss7_hmrt.c:277)
674 
20220319023550541 DLSS7 DEBUG scrc_rx_mtp_xfer_ind_xua: HDR=(CO:CODT,V=0,LEN=0), PART(T=Destination Reference,L=4,D=00000032), PART(T=Segmentation,L=4,D=00000000), PART(T=Data,L=3,D=000121) (sccp_scrc.c:472)
675 
20220319023550541 DLSCCP DEBUG Received CO:CODT for local reference 50 (sccp_scoc.c:1665)
676 
20220319023550541 DLSCCP DEBUG SCCP-SCOC(50)[0x55b8e913e300]{ACTIVE}: Received Event RCOC-DT1.ind (sccp_scoc.c:1698)
677 
20220319023550541 DLSCCP DEBUG Delivering N-DATA.indication to SCCP User 'OsmoMSC-A' (sccp_user.c:176)
678 
20220319023550541 DBSSAP DEBUG (GERAN-A-50) sccp_ran_sap_up(N-DATA.indication) (sccp_ran.c:108)
679 
20220319023550541 DRR DEBUG ran_peer(GERAN-A:RI-SSN_PC:PC-0-23-3:SSN-BSSAP)[0x55b8e9134540]{READY}: Received Event RAN_PEER_EV_MSG_UP_CO (ran_peer.c:591)
680 
20220319023550541 DMSC DEBUG msc_i(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91324c0]{READY}: Received Event MSC_EV_FROM_RAN_UP_L2 (ran_peer.c:407)
681 
20220319023550541 DBSSAP DEBUG msc_i(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91324c0]{READY}: RAN decode: BSSMAP: CLEAR COMPLETE (ran_msg_a.c:786)
682 
20220319023550541 DMSC DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_RELEASING}: Received Event MSC_A_EV_FROM_I_PROCESS_ACCESS_SIGNALLING_REQUEST (msc_i.c:85)
683 
20220319023550541 DREF DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_RELEASING}: + msc_a_ran_dec: now used by 2 (wait-Clear-Complete,msc_a_ran_dec) (msc_a.c:206)
684 
20220319023550541 DBSSAP DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_RELEASING}: RAN decode: BSSMAP: CLEAR COMPLETE (ran_msg_a.c:786)
685 
20220319023550541 DBSSAP DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_RELEASING}: RAN decode: BSSMAP Clear Complete (msc_a.c:1625)
686 
20220319023550541 DREF DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_RELEASING}: - wait-Clear-Complete: now used by 1 (msc_a_ran_dec) (msc_a.c:1452)
687 
20220319023550541 DMSC DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_RELEASING}: State change to MSC_A_ST_RELEASED (X2, 30s) (msc_a.c:1453)
688 
20220319023550541 DBSSAP DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_RELEASED}: Released: msc_a use is 1 (msc_a_ran_dec) (msc_a.c:873)
689 
20220319023550541 DREF INFO msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_RELEASED}: - msc_a_ran_dec: now used by 0 (-) (msc_a.c:208)
690 
20220319023550541 DMSC DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_RELEASED}: Received Event MSC_A_EV_UNUSED (msc_a.c:1094)
691 
20220319023550541 DMSC DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_RELEASED}: Terminating (cause = OSMO_FSM_TERM_REGULAR) (msc_a.c:881)
692 
20220319023550541 DVLR DEBUG vlr_lu_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91378c0]{VLR_ULA_S_DONE}: Terminating (cause = OSMO_FSM_TERM_PARENT) (msc_a.c:881)
693 
20220319023550541 DVLR DEBUG vlr_lu_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91378c0]{VLR_ULA_S_DONE}: Removing from parent msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91359b0] (msc_a.c:881)
694 
20220319023550541 DVLR DEBUG vlr_lu_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91378c0]{VLR_ULA_S_DONE}: fsm_lu_cleanup called with cause OSMO_FSM_TERM_PARENT (vlr_lu_fsm.c:1446)
695 
20220319023550541 DVLR DEBUG vlr_lu_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91378c0]{VLR_ULA_S_DONE}: Freeing instance (msc_a.c:881)
696 
20220319023550541 DVLR DEBUG vlr_lu_fsm(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91378c0]{VLR_ULA_S_DONE}: Deallocated (fsm.c:568)
697 
20220319023550541 DMSC DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_RELEASED}: Removing from parent msub_fsm[0x55b8e91404a0] (msc_a.c:881)
698 
20220319023550541 DREF DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_RELEASED}: max total use count was 3 (msc_a.c:893)
699 
20220319023550541 DMSC DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_RELEASED}: Freeing instance (msc_a.c:881)
700 
20220319023550541 DMSC DEBUG msc_a(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91359b0]{MSC_A_ST_RELEASED}: Deallocated (fsm.c:568)
701 
20220319023550541 DMSC DEBUG msub_fsm[0x55b8e91404a0]{active}: Received Event MSUB_EV_ROLE_TERMINATED (msc_a.c:881)
702 
20220319023550541 DMSC DEBUG msub(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86) MSC-A terminated (msub.c:260)
703 
20220319023550541 DMSC DEBUG msub(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86) 1 MSC-I still active (msub.c:78)
704 
20220319023550541 DMSC DEBUG msub_fsm[0x55b8e91404a0]{active}: State change to terminating (no timeout) (msub.c:90)
705 
20220319023550541 DMSC DEBUG msub_fsm[0x55b8e91404a0]{terminating}: Terminating (cause = OSMO_FSM_TERM_REGULAR) (msub.c:112)
706 
20220319023550541 DMSC DEBUG msc_i(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91324c0]{READY}: Terminating (cause = OSMO_FSM_TERM_PARENT) (msub.c:112)
707 
20220319023550541 DMSC DEBUG msc_i(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91324c0]{READY}: Removing from parent msub_fsm[0x55b8e91404a0] (msub.c:112)
708 
20220319023550541 DBSSAP DEBUG ran_peer(GERAN-A:RI-SSN_PC:PC-0-23-3:SSN-BSSAP)[0x55b8e9134540]{READY}: Closing GERAN-A-50 (ran_conn.c:142)
709 
20220319023550542 DLSCCP DEBUG Received SCCP User Primitive (N-DISCONNECT.request) (sccp_scoc.c:1732)
710 
20220319023550542 DLSCCP DEBUG SCCP-SCOC(50)[0x55b8e913e300]{ACTIVE}: Received Event N-DISCONNECT.req (sccp_scoc.c:1772)
711 
20220319023550542 DLSS7 DEBUG 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=00000032), PART(T=Source Reference,L=4,D=00000032), PART(T=Cause,L=4,D=00000300) (sccp_scrc.c:401)
712 
20220319023550542 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=187=0.23.3 not local, message is for routing (osmo_ss7_hmrt.c:281)
713 
20220319023550542 DLSS7 DEBUG Found route for dpc=187=0.23.3: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoMSC-A-Iu proto=m3ua (osmo_ss7_hmrt.c:228)
714 
20220319023550542 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=187=0.23.3 (osmo_ss7_hmrt.c:236)
715 
20220319023550542 DLSS7 DEBUG XUA_AS(as-clnt-OsmoMSC-A-Iu)[0x55b8e912c0c0]{AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:508)
716 
20220319023550542 DLSCCP DEBUG SCCP-SCOC(50)[0x55b8e913e300]{ACTIVE}: State change to DISCONN_PEND (no timeout) (sccp_scoc.c:1057)
717 
20220319023550542 DMSC DEBUG NULL{fi=NULL}: Deallocating no-RAN-peer-50 (ran_conn.c:155)
718 
20220319023550542 DMSC DEBUG msc_i(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91324c0]{READY}: Freeing instance (msub.c:112)
719 
20220319023550542 DMSC DEBUG msc_i(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86:GERAN-A-50:LU)[0x55b8e91324c0]{READY}: Deallocated (fsm.c:568)
720 
20220319023550542 DMSC DEBUG msub(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86) Free (msub.c:118)
721 
20220319023550542 DREF DEBUG VLR subscr IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86 - active-conn: now used by 1 (attached) (msub.c:374)
722 
20220319023550542 DMSC DEBUG msub_fsm[0x55b8e91404a0]{terminating}: Freeing instance (msub.c:112)
723 
20220319023550542 DMSC DEBUG msub_fsm[0x55b8e91404a0]{terminating}: Deallocated (fsm.c:568)
724 
20220319023550542 DLINP DEBUG [CONNECTED] osmo_stream_cli_fd_cb(): connected write (stream.c:449)
725 
20220319023550542 DLINP DEBUG [CONNECTED] osmo_stream_cli_write(): sending 44 bytes of data (stream.c:352)
726 
20220319023550542 DLINP DEBUG [CONNECTED] osmo_stream_cli_fd_cb(): connected write (stream.c:449)
727 
20220319023550542 DLINP DEBUG [CONNECTED] osmo_stream_cli_fd_cb(): connected read (stream.c:445)
728 
20220319023550542 DLINP DEBUG [CONNECTED] osmo_stream_cli_read(): message received (stream.c:324)
729 
20220319023550542 DLSS7 DEBUG 0: asp-asp-clnt-OsmoMSC-A-Iu: xua_cli_read_cb(): sctp_recvmsg() returned 40 (flags=0x80) (osmo_ss7.c:1907)
730 
20220319023550542 DLM3UA DEBUG 0: asp-asp-clnt-OsmoMSC-A-Iu: Received M3UA Message (XFER:DATA) (m3ua.c:715)
731 
20220319023550542 DLM3UA DEBUG 0: asp-asp-clnt-OsmoMSC-A-Iu: m3ua_rx_xfer (m3ua.c:543)
732 
20220319023550542 DLM3UA DEBUG 0: asp-asp-clnt-OsmoMSC-A-Iu: m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3 dpc=185=0.23.1 (m3ua.c:569)
733 
20220319023550542 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): found dpc=185=0.23.1 as local (osmo_ss7_hmrt.c:277)
734 
20220319023550542 DLSS7 DEBUG scrc_rx_mtp_xfer_ind_xua: HDR=(CO:RELCO,V=0,LEN=0), PART(T=Destination Reference,L=4,D=00000032), PART(T=Source Reference,L=4,D=00000032) (sccp_scrc.c:472)
735 
20220319023550542 DLSCCP DEBUG Received CO:RELCO for local reference 50 (sccp_scoc.c:1665)
736 
20220319023550542 DLSCCP DEBUG SCCP-SCOC(50)[0x55b8e913e300]{DISCONN_PEND}: Received Event RCOC-RELEASE_COMPLETE.ind (sccp_scoc.c:1698)
737 
20220319023550542 DLSCCP DEBUG SCCP-SCOC(50)[0x55b8e913e300]{DISCONN_PEND}: State change to IDLE (no timeout) (sccp_scoc.c:1175)
738 
20220319023550542 DLSCCP DEBUG SCCP-SCOC(50)[0x55b8e913e300]{IDLE}: Terminating (cause = OSMO_FSM_TERM_REQUEST) (sccp_scoc.c:520)
739 
20220319023550542 DLSCCP DEBUG SCCP-SCOC(50)[0x55b8e913e300]{IDLE}: Freeing instance (sccp_scoc.c:520)
740 
20220319023550542 DLSCCP DEBUG SCCP-SCOC(50)[0x55b8e913e300]{IDLE}: Deallocated (fsm.c:568)
741 
20220319023556232 DLGSUP INFO GSUP ping callback (connected, got PONG) (gsup_client.c:268)
742 
20220319023556232 DLGSUP DEBUG GSUP sending PING (gsup_client.c:288)
743 
20220319023556232 DLINP DEBUG 10.23.100.60:4222 connected write (ipa.c:139)
744 
20220319023556232 DLINP DEBUG 10.23.100.60:4222 sending data (ipa.c:89)
745 
20220319023556232 DLINP DEBUG 10.23.100.60:4222 connected write (ipa.c:139)
746 
20220319023556232 DLINP DEBUG 10.23.100.60:4222 sending data (ipa.c:89)
747 
20220319023556232 DLINP DEBUG 10.23.100.60:4222 connected read (ipa.c:135)
748 
20220319023556232 DLINP DEBUG 10.23.100.60:4222 message received (ipa.c:56)
749 
20220319023556232 DLMI DEBUG PONG! (ipa.c:524)
750 
20220319023556232 DLGSUP DEBUG GSUP receiving PONG (gsup_client.c:225)
751 
20220319023557720 DLINP DEBUG connected read/write (stream.c:1343)
752 
20220319023557720 DLINP DEBUG message received (stream.c:1271)
753 
20220319023557720 DREF DEBUG VLR subscr IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86 + sgs_rx_loc_upd_req: now used by 2 (attached,sgs_rx_loc_upd_req) (sgs_iface.c:614)
754 
20220319023557720 DREF DEBUG VLR subscr IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86 - sgs_rx_loc_upd_req: now used by 1 (attached) (sgs_iface.c:617)
755 
20220319023557720 DSGS INFO r=192.168.11.140:39319<->l=192.168.11.109:29118: Receiving SGsAP-LOCATION-UPDATE-REQUEST without TAI nor E-CGI IEs, fast fallback GERAN->EUTRAN won't be possible! (sgs_iface.c:659)
756 
20220319023557720 DREF DEBUG VLR subscr IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86 + SGs-lu: now used by 2 (attached,SGs-lu) (vlr_sgs.c:86)
757 
20220319023557720 DVLR DEBUG set IMSI on subscriber; IMSI=334070000000968 id=334070000000968 (vlr.c:460)
758 
20220319023557720 DVLR DEBUG set Last E-UTRAN PLMN ID on subscriber: (none) (vlr.c:508)
759 
20220319023557720 DSGS INFO SGs-UE(imsi:334070000000968)[0x55b8e9139430]{SGs-NULL}: Received Event RX_LU_FROM_MME (vlr_sgs.c:100)
760 
20220319023557720 DVLR NOTICE SUBSCR(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86:TMSInew-0xA221AAC0) VLR: update for IMSI=334070000000968 (MSISDN=12345174747) (NO CONN!) (gsm_04_08.c:1526)
761 
20220319023557720 DSGS INFO SGs-UE(imsi:334070000000968)[0x55b8e9139430]{SGs-NULL}: State change to SGs-LA-UPDATE-PRESENT (no timeout) (vlr_sgs_fsm.c:95)
762 
20220319023557720 DLINP DEBUG 10.23.100.60:4222 connected write (ipa.c:139)
763 
20220319023557720 DLINP DEBUG 10.23.100.60:4222 sending data (ipa.c:89)
764 
20220319023557720 DLINP DEBUG 10.23.100.60:4222 connected write (ipa.c:139)
765 
20220319023557720 DLINP DEBUG 10.23.100.60:4222 sending data (ipa.c:89)
766 
20220319023557737 DLINP DEBUG 10.23.100.60:4222 connected read (ipa.c:135)
767 
20220319023557737 DLINP DEBUG 10.23.100.60:4222 message received (ipa.c:56)
768 
20220319023557737 DREF DEBUG VLR subscr IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86:TMSInew-0xA221AAC0 + vlr_gsup_rx: now used by 3 (attached,SGs-lu,vlr_gsup_rx) (vlr.c:1111)
769 
20220319023557737 DVLR DEBUG IMSI:334070000000968 has MSISDN:12345174747 (vlr.c:829)
770 
20220319023557737 DVLR NOTICE SUBSCR(IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86:TMSInew-0xA221AAC0) VLR: update for IMSI=334070000000968 (MSISDN=12345174747) (NO CONN!) (gsm_04_08.c:1526)
771 
20220319023557737 DREF DEBUG VLR subscr IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86:TMSInew-0xA221AAC0 - vlr_gsup_rx: now used by 2 (attached,SGs-lu) (vlr.c:1157)
772 
20220319023557737 DLINP DEBUG 10.23.100.60:4222 connected write (ipa.c:139)
773 
20220319023557737 DLINP DEBUG 10.23.100.60:4222 sending data (ipa.c:89)
774 
20220319023557738 DLINP DEBUG 10.23.100.60:4222 connected write (ipa.c:139)
775 
20220319023557738 DLINP DEBUG 10.23.100.60:4222 sending data (ipa.c:89)
776 
20220319023557738 DLINP DEBUG 10.23.100.60:4222 connected read (ipa.c:135)
777 
20220319023557738 DLINP DEBUG 10.23.100.60:4222 message received (ipa.c:56)
778 
20220319023557738 DREF DEBUG VLR subscr IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86:TMSInew-0xA221AAC0 + vlr_gsup_rx: now used by 3 (attached,SGs-lu,vlr_gsup_rx) (vlr.c:1111)
779 
20220319023557738 DSGS INFO SGs-UE(imsi:334070000000968)[0x55b8e9139430]{SGs-LA-UPDATE-PRESENT}: Received Event TX_LU_ACCEPT (vlr_sgs.c:120)
780 
20220319023557738 DSGS INFO SGs-UE(imsi:334070000000968)[0x55b8e9139430]{SGs-LA-UPDATE-PRESENT}: State change to SGs-ASSOCIATED (T1, 40s) (vlr_sgs_fsm.c:166)
781 
20220319023557738 DREF DEBUG VLR subscr IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86:TMSInew-0xA221AAC0 - SGs-lu: now used by 2 (attached,vlr_gsup_rx) (vlr_sgs.c:123)
782 
20220319023557738 DREF DEBUG VLR subscr IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86:TMSInew-0xA221AAC0 - vlr_gsup_rx: now used by 1 (attached) (vlr.c:1157)
783 
20220319023557738 DLINP DEBUG connected read/write (stream.c:1343)
784 
20220319023557738 DLINP DEBUG sending data (stream.c:1293)
785 
20220319023557738 DLINP DEBUG connected read/write (stream.c:1343)
786 
20220319023557738 DLINP DEBUG sending data (stream.c:1293)
787 
20220319023557880 DLINP DEBUG connected read/write (stream.c:1343)
788 
20220319023557880 DLINP DEBUG message received (stream.c:1271)
789 
20220319023557880 DREF DEBUG VLR subscr IMSI-334070000000968:MSISDN-12345174747:TMSI-0x31E9FC86:TMSInew-0xA221AAC0 + vlr_sgs_tmsi_reall_compl: now used by 2 (attached,vlr_sgs_tmsi_reall_compl) (vlr_sgs.c:234)
790 
20220319023557880 DSGS INFO SGs-UE(imsi:334070000000968)[0x55b8e9139430]{SGs-ASSOCIATED}: Received Event RX_TMSI_REALLOC (vlr_sgs.c:238)
791 
20220319023557880 DSGS INFO SGs-UE(imsi:334070000000968)[0x55b8e9139430]{SGs-ASSOCIATED}: State change to SGs-ASSOCIATED (no timeout) (vlr_sgs_fsm.c:231)
792 
20220319023557881 DREF DEBUG VLR subscr IMSI-334070000000968:MSISDN-12345174747:TMSI-0xA221AAC0 - vlr_sgs_tmsi_reall_compl: now used by 1 (attached) (vlr_sgs.c:239)
    (1-1/1)
    Add picture from clipboard (Maximum size: 48.8 MB)