Project

General

Profile

Bug #2885 » osmo-msc-log-osmo-mgw-not-running.txt

stsp, 09/28/2018 02:55 PM

 
1
20180928164633149 DMNCC <0004> mncc_sock.c:275 MNCC Socket has connection with external call control application
2
20180928164646566 DLGSUP <001b> gsup_client.c:241 GSUP ping callback (connected, got PONG)
3
20180928164646566 DLGSUP <001b> gsup_client.c:261 GSUP sending PING
4
20180928164646567 DLINP <0013> input/ipa.c:139 127.0.0.1:4222 connected write
5
20180928164646567 DLINP <0013> input/ipa.c:89 127.0.0.1:4222 sending data
6
20180928164646567 DLINP <0013> input/ipa.c:139 127.0.0.1:4222 connected write
7
20180928164646567 DLINP <0013> input/ipa.c:89 127.0.0.1:4222 sending data
8
20180928164646567 DLINP <0013> input/ipa.c:135 127.0.0.1:4222 connected read
9
20180928164646567 DLINP <0013> input/ipa.c:56 127.0.0.1:4222 message received
10
20180928164646567 DLGSUP <001b> gsup_client.c:198 GSUP receiving PONG
11
20180928164647313 DMNCC <0004> gsm_04_08_cc.c:1822 receive message MNCC_SETUP_REQ
12
20180928164647313 DCC <0001> transaction.c:97 (ti ff sub MSISDN:12345 callref 1) New transaction
13
20180928164647313 DMM <0002> gsm_subscriber.c:158 Subscriber MSISDN:12345 not paged yet, start paging.
14
20180928164647313 DBSSAP <0010> a_iface.c:216 Tx BSSMAP paging message from MSC RI=SSN_PC,PC=0.23.1,SSN=BSSAP to BSC RI=SSN_PC,PC=0.23.1,SSN=BSSAP (imsi=901700000023021, tmsi=0x9e1e6aa2, lac=23)
15
20180928164647313 DLSCCP <001e> sccp_scoc.c:1615 Received SCCP User Primitive N-UNITDATA.request)
16
20180928164647313 DLSS7 <001d> sccp_scrc.c:420 sccp_scrc_rx_sclc_msg:  HDR=(CL:CLDT,V=0,LEN=0),
17
        PART(T=Routing Context,L=4,D=00000000),
18
        PART(T=Protocol Class,L=4,D=00000000),
19
        PART(T=Source Address,L=20,D=0002000380020008000000b980030008000000fe),
20
        PART(T=Destination Address,L=20,D=0002000380020008000000bb80030008000000fe),
21
        PART(T=Sequence Control,L=4,D=00000000),
22
        PART(T=Data,L=24,D=0016520808991007000020031209049e1e6aa21a03050017)
23
20180928164647313 DLSUA <001f> sua.c:385 sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 bb 80 03 00 08 00 00 00 fe
24
20180928164647313 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8
25
20180928164647313 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8
26
20180928164647313 DLSUA <001f> sua.c:385 sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 bb 80 03 00 08 00 00 00 fe
27
20180928164647313 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8
28
20180928164647313 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8
29
20180928164647313 DLSUA <001f> sua.c:385 sua_addr_parse_part(IEI=0x0102) (20) 00 02 00 03 80 02 00 08 00 00 00 b9 80 03 00 08 00 00 00 fe
30
20180928164647313 DLSUA <001f> sua.c:439 SUA IEI 0x0102 pos 4/20: subpart tag 0x8002, len 8
31
20180928164647313 DLSUA <001f> sua.c:439 SUA IEI 0x0102 pos 12/20: subpart tag 0x8003, len 8
32
20180928164647313 DLSS7 <001d> osmo_ss7_hmrt.c:278 m3ua_hmdc_rx_from_l2(): dpc=187=0.23.3 not local, message is for routing
33
20180928164647313 DLSS7 <001d> osmo_ss7_hmrt.c:227 Found route for dpc=187=0.23.3: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoMSC-A proto=m3ua
34
20180928164647313 DLSS7 <001d> osmo_ss7_hmrt.c:233 rt->dest.as proto is M3UA for dpc=187=0.23.3
35
20180928164647313 DLSS7 <001d> m3ua.c:507 XUA_AS(as-clnt-OsmoMSC-A)[0x612000009220]{AS_ACTIVE}: Received Event AS-TRANSFER.req
36
20180928164647313 DLINP <0013> stream.c:279 connected write
37
20180928164647313 DLINP <0013> stream.c:204 sending data
38
20180928164647314 DLINP <0013> stream.c:279 connected write
39
20180928164647314 DLINP <0013> stream.c:204 sending data
40
20180928164647314 DMNCC <0004> gsm_04_08_cc.c:1822 receive message MNCC_SETUP_REQ
41
20180928164647314 DCC <0001> transaction.c:97 (ti ff sub MSISDN:12346 callref 2) New transaction
42
20180928164647314 DMM <0002> gsm_subscriber.c:158 Subscriber MSISDN:12346 not paged yet, start paging.
43
20180928164647314 DBSSAP <0010> a_iface.c:216 Tx BSSMAP paging message from MSC RI=SSN_PC,PC=0.23.1,SSN=BSSAP to BSC RI=SSN_PC,PC=0.23.1,SSN=BSSAP (imsi=901700000023022, tmsi=0x24fa487a, lac=23)
44
20180928164647314 DLSCCP <001e> sccp_scoc.c:1615 Received SCCP User Primitive N-UNITDATA.request)
45
20180928164647314 DLSS7 <001d> sccp_scrc.c:420 sccp_scrc_rx_sclc_msg:  HDR=(CL:CLDT,V=0,LEN=0),
46
        PART(T=Routing Context,L=4,D=00000000),
47
        PART(T=Protocol Class,L=4,D=00000000),
48
        PART(T=Source Address,L=20,D=0002000380020008000000b980030008000000fe),
49
        PART(T=Destination Address,L=20,D=0002000380020008000000bb80030008000000fe),
50
        PART(T=Sequence Control,L=4,D=00000000),
51
        PART(T=Data,L=24,D=00165208089910070000200322090424fa487a1a03050017)
52
20180928164647314 DLSUA <001f> sua.c:385 sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 bb 80 03 00 08 00 00 00 fe
53
20180928164647314 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8
54
20180928164647314 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8
55
20180928164647314 DLSUA <001f> sua.c:385 sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 bb 80 03 00 08 00 00 00 fe
56
20180928164647314 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8
57
20180928164647314 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8
58
20180928164647314 DLSUA <001f> sua.c:385 sua_addr_parse_part(IEI=0x0102) (20) 00 02 00 03 80 02 00 08 00 00 00 b9 80 03 00 08 00 00 00 fe
59
20180928164647314 DLSUA <001f> sua.c:439 SUA IEI 0x0102 pos 4/20: subpart tag 0x8002, len 8
60
20180928164647314 DLSUA <001f> sua.c:439 SUA IEI 0x0102 pos 12/20: subpart tag 0x8003, len 8
61
20180928164647314 DLSS7 <001d> osmo_ss7_hmrt.c:278 m3ua_hmdc_rx_from_l2(): dpc=187=0.23.3 not local, message is for routing
62
20180928164647314 DLSS7 <001d> osmo_ss7_hmrt.c:227 Found route for dpc=187=0.23.3: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoMSC-A proto=m3ua
63
20180928164647314 DLSS7 <001d> osmo_ss7_hmrt.c:233 rt->dest.as proto is M3UA for dpc=187=0.23.3
64
20180928164647314 DLSS7 <001d> m3ua.c:507 XUA_AS(as-clnt-OsmoMSC-A)[0x612000009220]{AS_ACTIVE}: Received Event AS-TRANSFER.req
65
20180928164647314 DLINP <0013> stream.c:279 connected write
66
20180928164647315 DLINP <0013> stream.c:204 sending data
67
20180928164647315 DLINP <0013> stream.c:279 connected write
68
20180928164647315 DLINP <0013> stream.c:204 sending data
69
20180928164649370 DLINP <0013> stream.c:275 connected read
70
20180928164649370 DLINP <0013> stream.c:189 message received
71
20180928164649370 DLSS7 <001d> osmo_ss7.c:1549 asp-asp-clnt-OsmoMSC-A: xua_cli_read_cb(): sctp_recvmsg() returned 88 (flags=0x80)
72
20180928164649370 DLM3UA <0020> m3ua.c:722 asp-asp-clnt-OsmoMSC-A: Received M3UA Message (XFER:DATA)
73
20180928164649370 DLM3UA <0020> m3ua.c:541 asp-asp-clnt-OsmoMSC-A: m3ua_rx_xfer
74
20180928164649370 DLM3UA <0020> m3ua.c:580 asp-asp-clnt-OsmoMSC-A: m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3 dpc=185=0.23.1
75
20180928164649370 DLSS7 <001d> osmo_ss7_hmrt.c:274 m3ua_hmdc_rx_from_l2(): found dpc=185=0.23.1 as local
76
20180928164649370 DLSUA <001f> sccp2sua.c:333 IEI 259: Parsed Addr: RI=2,PC=185,SSN=254
77
20180928164649371 DLSUA <001f> sccp2sua.c:333 IEI 258: Parsed Addr: RI=2,PC=187,SSN=254
78
20180928164649371 DLSS7 <001d> sccp_scrc.c:450 scrc_rx_mtp_xfer_ind_xua:  HDR=(CO:CORE,V=0,LEN=0),
79
        PART(T=Protocol Class,L=4,D=00000002),
80
        PART(T=Source Reference,L=4,D=00000064),
81
        PART(T=Destination Address,L=20,D=0002000380020008000000b980030008000000fe),
82
        PART(T=Source Address,L=20,D=0002000380020008000000bb80030008000000fe),
83
        PART(T=Data,L=33,D=001f5705080009f19900170000170d0627070350588605f49e1e6aa27d03840607)
84
20180928164649371 DLSUA <001f> sua.c:385 sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 b9 80 03 00 08 00 00 00 fe
85
20180928164649371 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8
86
20180928164649371 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8
87
20180928164649371 DLSUA <001f> sua.c:385 sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 b9 80 03 00 08 00 00 00 fe
88
20180928164649371 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8
89
20180928164649371 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8
90
20180928164649371 DLSCCP <001e> fsm.c:299 SCCP-SCOC(17)[0x612000032da0]{IDLE}: Allocated
91
20180928164649371 DLSCCP <001e> sccp_scoc.c:1548 Received CO:CORE for local reference 17
92
20180928164649371 DLSCCP <001e> sccp_scoc.c:1581 SCCP-SCOC(17)[0x612000032da0]{IDLE}: Received Event RCOC-CONNECT.ind
93
20180928164649371 DLSUA <001f> sua.c:385 sua_addr_parse_part(IEI=0x0102) (20) 00 02 00 03 80 02 00 08 00 00 00 bb 80 03 00 08 00 00 00 fe
94
20180928164649371 DLSUA <001f> sua.c:439 SUA IEI 0x0102 pos 4/20: subpart tag 0x8002, len 8
95
20180928164649371 DLSUA <001f> sua.c:439 SUA IEI 0x0102 pos 12/20: subpart tag 0x8003, len 8
96
20180928164649371 DLSUA <001f> sua.c:385 sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 b9 80 03 00 08 00 00 00 fe
97
20180928164649371 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8
98
20180928164649371 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8
99
20180928164649371 DLSCCP <001e> sccp_scoc.c:775 SCCP-SCOC(17)[0x612000032da0]{IDLE}: state_chg to CONN_PEND_IN
100
20180928164649371 DLSCCP <001e> sccp_user.c:156 Delivering N-CONNECT.indication to SCCP User 'OsmoMSC-A'
101
20180928164649371 DLSCCP <001e> sccp_scoc.c:1615 Received SCCP User Primitive N-CONNECT.response)
102
20180928164649371 DLSCCP <001e> sccp_scoc.c:1657 SCCP-SCOC(17)[0x612000032da0]{CONN_PEND_IN}: Received Event N-CONNECT.resp
103
20180928164649372 DLSS7 <001d> sccp_scrc.c:398 sccp_scrc_rx_scoc_conn_msg:  HDR=(CO:COAK,V=0,LEN=0),
104
        PART(T=Routing Context,L=4,D=00000000),
105
        PART(T=Protocol Class,L=4,D=00000002),
106
        PART(T=Destination Reference,L=4,D=00000064),
107
        PART(T=Source Reference,L=4,D=00000011),
108
        PART(T=Sequence Control,L=4,D=00000000),
109
        PART(T=Source Address,L=20,D=0002000380020008000000b980030008000000fe),
110
        PART(T=Destination Address,L=20,D=0002000380020008000000bb80030008000000fe)
111
20180928164649372 DLSUA <001f> sua.c:385 sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 bb 80 03 00 08 00 00 00 fe
112
20180928164649372 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8
113
20180928164649372 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8
114
20180928164649372 DLSUA <001f> sua.c:385 sua_addr_parse_part(IEI=0x0102) (20) 00 02 00 03 80 02 00 08 00 00 00 b9 80 03 00 08 00 00 00 fe
115
20180928164649372 DLSUA <001f> sua.c:439 SUA IEI 0x0102 pos 4/20: subpart tag 0x8002, len 8
116
20180928164649372 DLSUA <001f> sua.c:439 SUA IEI 0x0102 pos 12/20: subpart tag 0x8003, len 8
117
20180928164649372 DLSS7 <001d> osmo_ss7_hmrt.c:278 m3ua_hmdc_rx_from_l2(): dpc=187=0.23.3 not local, message is for routing
118
20180928164649372 DLSS7 <001d> osmo_ss7_hmrt.c:227 Found route for dpc=187=0.23.3: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoMSC-A proto=m3ua
119
20180928164649372 DLSS7 <001d> osmo_ss7_hmrt.c:233 rt->dest.as proto is M3UA for dpc=187=0.23.3
120
20180928164649372 DLSS7 <001d> m3ua.c:507 XUA_AS(as-clnt-OsmoMSC-A)[0x612000009220]{AS_ACTIVE}: Received Event AS-TRANSFER.req
121
20180928164649372 DLSCCP <001e> sccp_scoc.c:802 SCCP-SCOC(17)[0x612000032da0]{CONN_PEND_IN}: state_chg to ACTIVE
122
20180928164649372 DBSSAP <0010> a_iface.c:545 N-CONNECT.ind(17, 00 1f 57 05 08 00 09 f1 99 00 17 00 00 17 0d 06 27 07 03 50 58 86 05 f4 9e 1e 6a a2 7d 03 84 06 07 )
123
20180928164649372 DBSSAP <0010> a_iface_bssap.c:268 Rx BSSMAP COMPLETE L3 INFO (conn_id=17)
124
20180928164649372 DMSC <0006> a_iface_bssap.c:55 Allocating A-Interface subscriber conn: lac 23, conn_id 17
125
20180928164649372 DMM <0002> fsm.c:299 Subscr_Conn[0x612000032f20]{SUBSCR_CONN_S_NEW}: Allocated
126
20180928164649372 DBSSAP <0010> a_iface_bssap.c:68 (subscr unknown, conn_id 17) A-Interface subscriber connection successfully allocated!
127
20180928164649373 DRLL <0000> gsm_04_08.c:1451 Dispatching 04.08 message GSM48_MT_RR_PAG_RESP (0x6:0x27)
128
20180928164649373 DRR <0003> gsm_04_08.c:1185 PAGING RESPONSE: MI(TMSI)=2652793506
129
20180928164649373 DMM <0002> subscr_conn.c:681 Subscr_Conn(PAGING_RESP:2652793506)[0x612000032f20]{SUBSCR_CONN_S_NEW}: Updated ID
130
20180928164649373 DVLR <000e> fsm.c:299 Process_Access_Request_VLR(PAGING_RESP:2652793506)[0x6120000330a0]{PR_ARQ_S_INIT}: Allocated
131
20180928164649373 DVLR <000e> fsm.c:329 Process_Access_Request_VLR(PAGING_RESP:2652793506)[0x6120000330a0]{PR_ARQ_S_INIT}: is child of Subscr_Conn(PAGING_RESP:2652793506)[0x612000032f20]
132
20180928164649373 DVLR <000e> vlr_access_req_fsm.c:669 Process_Access_Request_VLR(PAGING_RESP:2652793506)[0x6120000330a0]{PR_ARQ_S_INIT}: rev=R99 net=GERAN (no Auth)
133
20180928164649373 DVLR <000e> vlr_access_req_fsm.c:693 Process_Access_Request_VLR(PAGING_RESP:2652793506)[0x6120000330a0]{PR_ARQ_S_INIT}: Received Event PR_ARQ_E_START
134
20180928164649373 DVLR <000e> vlr_access_req_fsm.c:328 Process_Access_Request_VLR(PAGING_RESP:2652793506)[0x6120000330a0]{PR_ARQ_S_INIT}: proc_arq_vlr_fn_post_imsi()
135
20180928164649373 DVLR <000e> vlr_access_req_fsm.c:280 Process_Access_Request_VLR(PAGING_RESP:2652793506)[0x6120000330a0]{PR_ARQ_S_INIT}: _proc_arq_vlr_node2()
136
20180928164649373 DVLR <000e> vlr_access_req_fsm.c:246 Process_Access_Request_VLR(PAGING_RESP:2652793506)[0x6120000330a0]{PR_ARQ_S_INIT}: _proc_arq_vlr_node2_post_ciph()
137
20180928164649374 DVLR <000e> vlr_access_req_fsm.c:218 Process_Access_Request_VLR(PAGING_RESP:2652793506)[0x6120000330a0]{PR_ARQ_S_INIT}: _proc_arq_vlr_node2_post_vlr()
138
20180928164649374 DVLR <000e> vlr_access_req_fsm.c:203 Process_Access_Request_VLR(PAGING_RESP:2652793506)[0x6120000330a0]{PR_ARQ_S_INIT}: _proc_arq_vlr_post_pres()
139
20180928164649374 DVLR <000e> vlr_access_req_fsm.c:187 Process_Access_Request_VLR(PAGING_RESP:2652793506)[0x6120000330a0]{PR_ARQ_S_INIT}: _proc_arq_vlr_post_trace()
140
20180928164649374 DVLR <000e> vlr_access_req_fsm.c:165 Process_Access_Request_VLR(PAGING_RESP:2652793506)[0x6120000330a0]{PR_ARQ_S_INIT}: _proc_arq_vlr_post_imei()
141
20180928164649374 DVLR <000e> vlr_access_req_fsm.c:178 Process_Access_Request_VLR(PAGING_RESP:2652793506)[0x6120000330a0]{PR_ARQ_S_INIT}: proc_arq_fsm_done(PASSED)
142
20180928164649374 DVLR <000e> vlr_access_req_fsm.c:101 Process_Access_Request_VLR(PAGING_RESP:2652793506)[0x6120000330a0]{PR_ARQ_S_INIT}: state_chg to PR_ARQ_S_DONE
143
20180928164649374 DVLR <000e> vlr_access_req_fsm.c:110 Process_Access_Request_VLR(PAGING_RESP:2652793506)[0x6120000330a0]{PR_ARQ_S_DONE}: Process Access Request result: PASSED
144
20180928164649374 DMM <0002> vlr_access_req_fsm.c:149 Subscr_Conn(PAGING_RESP:2652793506)[0x612000032f20]{SUBSCR_CONN_S_NEW}: Received Event SUBSCR_CONN_E_ACCEPTED
145
20180928164649374 DPAG <0005> gsm_subscriber.c:73 Paging success for MSISDN:12345 (event=0)
146
20180928164649374 DPAG <0005> osmo_msc.c:350 Paging can stop for MSISDN:12345
147
20180928164649374 DPAG <0005> gsm_subscriber.c:100 Calling paging cbfn.
148
20180928164649374 DCC <0001> gsm_04_08_cc.c:279 Paging subscr 12345 succeeded!
149
20180928164649374 DCC <0001> gsm_04_08_cc.c:463 starting timer T303 with 30 seconds
150
20180928164649374 DCC <0001> gsm_04_08_cc.c:148 (ti 00 sub MSISDN:12345) new state NULL -> CALL_PRESENT
151
20180928164649375 DMSC <0006> msc_ifaces.c:60 msc_tx 23 bytes to MSISDN:12345 via RAN_GERAN_A
152
20180928164649375 DBSSAP <0010> a_iface.c:154 (subscr MSISDN:12345, conn_id 17) Passing DTAP message (DLCI=0x00) from MSC to BSC
153
20180928164649375 DBSSAP <0010> a_iface.c:168 (subscr MSISDN:12345, conn_id 17) N-DATA.req([])
154
20180928164649375 DLSCCP <001e> sccp_scoc.c:1615 Received SCCP User Primitive N-DATA.request)
155
20180928164649375 DLSCCP <001e> sccp_scoc.c:1657 SCCP-SCOC(17)[0x612000032da0]{ACTIVE}: Received Event N-DATA.req
156
20180928164649375 DLSS7 <001d> sccp_scrc.c:398 sccp_scrc_rx_scoc_conn_msg:  HDR=(CO:CODT,V=0,LEN=0),
157
        PART(T=Routing Context,L=4,D=00000000),
158
        PART(T=Destination Reference,L=4,D=00000064),
159
        PART(T=Data,L=26,D=010017030504062000010204855c0500a02143f65e04802143f5)
160
20180928164649375 DLSS7 <001d> osmo_ss7_hmrt.c:278 m3ua_hmdc_rx_from_l2(): dpc=187=0.23.3 not local, message is for routing
161
20180928164649375 DLSS7 <001d> osmo_ss7_hmrt.c:227 Found route for dpc=187=0.23.3: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoMSC-A proto=m3ua
162
20180928164649375 DLSS7 <001d> osmo_ss7_hmrt.c:233 rt->dest.as proto is M3UA for dpc=187=0.23.3
163
20180928164649375 DLSS7 <001d> m3ua.c:507 XUA_AS(as-clnt-OsmoMSC-A)[0x612000009220]{AS_ACTIVE}: Received Event AS-TRANSFER.req
164
20180928164649381 DMM <0002> subscr_conn.c:123 Subscr_Conn(PAGING_RESP:2652793506)[0x612000032f20]{SUBSCR_CONN_S_NEW}: state_chg to SUBSCR_CONN_S_ACCEPTED
165
20180928164649381 DMM <0002> subscr_conn.c:257 Subscr_Conn(PAGING_RESP:2652793506)[0x612000032f20]{SUBSCR_CONN_S_ACCEPTED}: subscr_conn_fsm_has_active_transactions: connection still has active transaction: CC
166
20180928164649381 DMM <0002> subscr_conn.c:544 Subscr_Conn(PAGING_RESP:2652793506)[0x612000032f20]{SUBSCR_CONN_S_ACCEPTED}: Received Event SUBSCR_CONN_E_COMPLETE_LAYER_3
167
20180928164649381 DMSC <0006> a_iface_bssap.c:351 User has been accepted by MSC.
168
20180928164649381 DLINP <0013> stream.c:279 connected write
169
20180928164649381 DLINP <0013> stream.c:204 sending data
170
20180928164649381 DLINP <0013> stream.c:279 connected write
171
20180928164649381 DLINP <0013> stream.c:204 sending data
172
20180928164649381 DLINP <0013> stream.c:279 connected write
173
20180928164649381 DLINP <0013> stream.c:204 sending data
174
20180928164649657 DLINP <0013> stream.c:275 connected read
175
20180928164649657 DLINP <0013> stream.c:189 message received
176
20180928164649657 DLSS7 <001d> osmo_ss7.c:1549 asp-asp-clnt-OsmoMSC-A: xua_cli_read_cb(): sctp_recvmsg() returned 88 (flags=0x80)
177
20180928164649657 DLM3UA <0020> m3ua.c:722 asp-asp-clnt-OsmoMSC-A: Received M3UA Message (XFER:DATA)
178
20180928164649657 DLM3UA <0020> m3ua.c:541 asp-asp-clnt-OsmoMSC-A: m3ua_rx_xfer
179
20180928164649657 DLM3UA <0020> m3ua.c:580 asp-asp-clnt-OsmoMSC-A: m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3 dpc=185=0.23.1
180
20180928164649657 DLSS7 <001d> osmo_ss7_hmrt.c:274 m3ua_hmdc_rx_from_l2(): found dpc=185=0.23.1 as local
181
20180928164649657 DLSUA <001f> sccp2sua.c:333 IEI 259: Parsed Addr: RI=2,PC=185,SSN=254
182
20180928164649657 DLSUA <001f> sccp2sua.c:333 IEI 258: Parsed Addr: RI=2,PC=187,SSN=254
183
20180928164649657 DLSS7 <001d> sccp_scrc.c:450 scrc_rx_mtp_xfer_ind_xua:  HDR=(CO:CORE,V=0,LEN=0),
184
        PART(T=Protocol Class,L=4,D=00000002),
185
        PART(T=Source Reference,L=4,D=00000065),
186
        PART(T=Destination Address,L=20,D=0002000380020008000000b980030008000000fe),
187
        PART(T=Source Address,L=20,D=0002000380020008000000bb80030008000000fe),
188
        PART(T=Data,L=33,D=001f5705080009f19900170000170d0627070330199505f424fa487a7d03840607)
189
20180928164649657 DLSUA <001f> sua.c:385 sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 b9 80 03 00 08 00 00 00 fe
190
20180928164649657 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8
191
20180928164649657 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8
192
20180928164649658 DLSUA <001f> sua.c:385 sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 b9 80 03 00 08 00 00 00 fe
193
20180928164649658 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8
194
20180928164649658 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8
195
20180928164649658 DLSCCP <001e> fsm.c:299 SCCP-SCOC(18)[0x612000035620]{IDLE}: Allocated
196
20180928164649658 DLSCCP <001e> sccp_scoc.c:1548 Received CO:CORE for local reference 18
197
20180928164649658 DLSCCP <001e> sccp_scoc.c:1581 SCCP-SCOC(18)[0x612000035620]{IDLE}: Received Event RCOC-CONNECT.ind
198
20180928164649658 DLSUA <001f> sua.c:385 sua_addr_parse_part(IEI=0x0102) (20) 00 02 00 03 80 02 00 08 00 00 00 bb 80 03 00 08 00 00 00 fe
199
20180928164649658 DLSUA <001f> sua.c:439 SUA IEI 0x0102 pos 4/20: subpart tag 0x8002, len 8
200
20180928164649658 DLSUA <001f> sua.c:439 SUA IEI 0x0102 pos 12/20: subpart tag 0x8003, len 8
201
20180928164649658 DLSUA <001f> sua.c:385 sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 b9 80 03 00 08 00 00 00 fe
202
20180928164649658 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8
203
20180928164649658 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8
204
20180928164649658 DLSCCP <001e> sccp_scoc.c:775 SCCP-SCOC(18)[0x612000035620]{IDLE}: state_chg to CONN_PEND_IN
205
20180928164649658 DLSCCP <001e> sccp_user.c:156 Delivering N-CONNECT.indication to SCCP User 'OsmoMSC-A'
206
20180928164649658 DLSCCP <001e> sccp_scoc.c:1615 Received SCCP User Primitive N-CONNECT.response)
207
20180928164649658 DLSCCP <001e> sccp_scoc.c:1657 SCCP-SCOC(18)[0x612000035620]{CONN_PEND_IN}: Received Event N-CONNECT.resp
208
20180928164649658 DLSS7 <001d> sccp_scrc.c:398 sccp_scrc_rx_scoc_conn_msg:  HDR=(CO:COAK,V=0,LEN=0),
209
        PART(T=Routing Context,L=4,D=00000000),
210
        PART(T=Protocol Class,L=4,D=00000002),
211
        PART(T=Destination Reference,L=4,D=00000065),
212
        PART(T=Source Reference,L=4,D=00000012),
213
        PART(T=Sequence Control,L=4,D=00000000),
214
        PART(T=Source Address,L=20,D=0002000380020008000000b980030008000000fe),
215
        PART(T=Destination Address,L=20,D=0002000380020008000000bb80030008000000fe)
216
20180928164649659 DLSUA <001f> sua.c:385 sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 bb 80 03 00 08 00 00 00 fe
217
20180928164649659 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8
218
20180928164649659 DLSUA <001f> sua.c:439 SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8
219
20180928164649659 DLSUA <001f> sua.c:385 sua_addr_parse_part(IEI=0x0102) (20) 00 02 00 03 80 02 00 08 00 00 00 b9 80 03 00 08 00 00 00 fe
220
20180928164649659 DLSUA <001f> sua.c:439 SUA IEI 0x0102 pos 4/20: subpart tag 0x8002, len 8
221
20180928164649659 DLSUA <001f> sua.c:439 SUA IEI 0x0102 pos 12/20: subpart tag 0x8003, len 8
222
20180928164649659 DLSS7 <001d> osmo_ss7_hmrt.c:278 m3ua_hmdc_rx_from_l2(): dpc=187=0.23.3 not local, message is for routing
223
20180928164649659 DLSS7 <001d> osmo_ss7_hmrt.c:227 Found route for dpc=187=0.23.3: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoMSC-A proto=m3ua
224
20180928164649659 DLSS7 <001d> osmo_ss7_hmrt.c:233 rt->dest.as proto is M3UA for dpc=187=0.23.3
225
20180928164649659 DLSS7 <001d> m3ua.c:507 XUA_AS(as-clnt-OsmoMSC-A)[0x612000009220]{AS_ACTIVE}: Received Event AS-TRANSFER.req
226
20180928164649659 DLSCCP <001e> sccp_scoc.c:802 SCCP-SCOC(18)[0x612000035620]{CONN_PEND_IN}: state_chg to ACTIVE
227
20180928164649659 DBSSAP <0010> a_iface.c:545 N-CONNECT.ind(18, 00 1f 57 05 08 00 09 f1 99 00 17 00 00 17 0d 06 27 07 03 30 19 95 05 f4 24 fa 48 7a 7d 03 84 06 07 )
228
20180928164649659 DBSSAP <0010> a_iface_bssap.c:268 Rx BSSMAP COMPLETE L3 INFO (conn_id=18)
229
20180928164649659 DMSC <0006> a_iface_bssap.c:55 Allocating A-Interface subscriber conn: lac 23, conn_id 18
230
20180928164649659 DMM <0002> fsm.c:299 Subscr_Conn[0x6120000357a0]{SUBSCR_CONN_S_NEW}: Allocated
231
20180928164649659 DBSSAP <0010> a_iface_bssap.c:68 (subscr unknown, conn_id 18) A-Interface subscriber connection successfully allocated!
232
20180928164649659 DRLL <0000> gsm_04_08.c:1451 Dispatching 04.08 message GSM48_MT_RR_PAG_RESP (0x6:0x27)
233
20180928164649660 DRR <0003> gsm_04_08.c:1185 PAGING RESPONSE: MI(TMSI)=620382330
234
20180928164649660 DMM <0002> subscr_conn.c:681 Subscr_Conn(PAGING_RESP:620382330)[0x6120000357a0]{SUBSCR_CONN_S_NEW}: Updated ID
235
20180928164649660 DVLR <000e> fsm.c:299 Process_Access_Request_VLR(PAGING_RESP:620382330)[0x612000035920]{PR_ARQ_S_INIT}: Allocated
236
20180928164649660 DVLR <000e> fsm.c:329 Process_Access_Request_VLR(PAGING_RESP:620382330)[0x612000035920]{PR_ARQ_S_INIT}: is child of Subscr_Conn(PAGING_RESP:620382330)[0x6120000357a0]
237
20180928164649660 DVLR <000e> vlr_access_req_fsm.c:669 Process_Access_Request_VLR(PAGING_RESP:620382330)[0x612000035920]{PR_ARQ_S_INIT}: rev=GSM net=GERAN (no Auth)
238
20180928164649660 DVLR <000e> vlr_access_req_fsm.c:693 Process_Access_Request_VLR(PAGING_RESP:620382330)[0x612000035920]{PR_ARQ_S_INIT}: Received Event PR_ARQ_E_START
239
20180928164649660 DVLR <000e> vlr_access_req_fsm.c:328 Process_Access_Request_VLR(PAGING_RESP:620382330)[0x612000035920]{PR_ARQ_S_INIT}: proc_arq_vlr_fn_post_imsi()
240
20180928164649660 DVLR <000e> vlr_access_req_fsm.c:280 Process_Access_Request_VLR(PAGING_RESP:620382330)[0x612000035920]{PR_ARQ_S_INIT}: _proc_arq_vlr_node2()
241
20180928164649660 DVLR <000e> vlr_access_req_fsm.c:246 Process_Access_Request_VLR(PAGING_RESP:620382330)[0x612000035920]{PR_ARQ_S_INIT}: _proc_arq_vlr_node2_post_ciph()
242
20180928164649660 DVLR <000e> vlr_access_req_fsm.c:218 Process_Access_Request_VLR(PAGING_RESP:620382330)[0x612000035920]{PR_ARQ_S_INIT}: _proc_arq_vlr_node2_post_vlr()
243
20180928164649660 DVLR <000e> vlr_access_req_fsm.c:203 Process_Access_Request_VLR(PAGING_RESP:620382330)[0x612000035920]{PR_ARQ_S_INIT}: _proc_arq_vlr_post_pres()
244
20180928164649660 DVLR <000e> vlr_access_req_fsm.c:187 Process_Access_Request_VLR(PAGING_RESP:620382330)[0x612000035920]{PR_ARQ_S_INIT}: _proc_arq_vlr_post_trace()
245
20180928164649660 DVLR <000e> vlr_access_req_fsm.c:165 Process_Access_Request_VLR(PAGING_RESP:620382330)[0x612000035920]{PR_ARQ_S_INIT}: _proc_arq_vlr_post_imei()
246
20180928164649660 DVLR <000e> vlr_access_req_fsm.c:178 Process_Access_Request_VLR(PAGING_RESP:620382330)[0x612000035920]{PR_ARQ_S_INIT}: proc_arq_fsm_done(PASSED)
247
20180928164649660 DVLR <000e> vlr_access_req_fsm.c:101 Process_Access_Request_VLR(PAGING_RESP:620382330)[0x612000035920]{PR_ARQ_S_INIT}: state_chg to PR_ARQ_S_DONE
248
20180928164649660 DVLR <000e> vlr_access_req_fsm.c:110 Process_Access_Request_VLR(PAGING_RESP:620382330)[0x612000035920]{PR_ARQ_S_DONE}: Process Access Request result: PASSED
249
20180928164649660 DMM <0002> vlr_access_req_fsm.c:149 Subscr_Conn(PAGING_RESP:620382330)[0x6120000357a0]{SUBSCR_CONN_S_NEW}: Received Event SUBSCR_CONN_E_ACCEPTED
250
20180928164649660 DPAG <0005> gsm_subscriber.c:73 Paging success for MSISDN:12346 (event=0)
251
20180928164649661 DPAG <0005> osmo_msc.c:350 Paging can stop for MSISDN:12346
252
20180928164649661 DPAG <0005> gsm_subscriber.c:100 Calling paging cbfn.
253
20180928164649661 DCC <0001> gsm_04_08_cc.c:279 Paging subscr 12346 succeeded!
254
20180928164649661 DCC <0001> gsm_04_08_cc.c:463 starting timer T303 with 30 seconds
255
20180928164649661 DCC <0001> gsm_04_08_cc.c:148 (ti 00 sub MSISDN:12346) new state NULL -> CALL_PRESENT
256
20180928164649661 DMSC <0006> msc_ifaces.c:60 msc_tx 23 bytes to MSISDN:12346 via RAN_GERAN_A
257
20180928164649661 DBSSAP <0010> a_iface.c:154 (subscr MSISDN:12346, conn_id 18) Passing DTAP message (DLCI=0x00) from MSC to BSC
258
20180928164649661 DBSSAP <0010> a_iface.c:168 (subscr MSISDN:12346, conn_id 18) N-DATA.req([])
259
20180928164649661 DLSCCP <001e> sccp_scoc.c:1615 Received SCCP User Primitive N-DATA.request)
260
20180928164649661 DLSCCP <001e> sccp_scoc.c:1657 SCCP-SCOC(18)[0x612000035620]{ACTIVE}: Received Event N-DATA.req
261
20180928164649661 DLSS7 <001d> sccp_scrc.c:398 sccp_scrc_rx_scoc_conn_msg:  HDR=(CO:CODT,V=0,LEN=0),
262
        PART(T=Routing Context,L=4,D=00000000),
263
        PART(T=Destination Reference,L=4,D=00000065),
264
        PART(T=Data,L=26,D=010017030504062000010204855c0500a02143f55e04802143f6)
265
20180928164649661 DLSS7 <001d> osmo_ss7_hmrt.c:278 m3ua_hmdc_rx_from_l2(): dpc=187=0.23.3 not local, message is for routing
266
20180928164649662 DLSS7 <001d> osmo_ss7_hmrt.c:227 Found route for dpc=187=0.23.3: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoMSC-A proto=m3ua
267
20180928164649662 DLSS7 <001d> osmo_ss7_hmrt.c:233 rt->dest.as proto is M3UA for dpc=187=0.23.3
268
20180928164649662 DLSS7 <001d> m3ua.c:507 XUA_AS(as-clnt-OsmoMSC-A)[0x612000009220]{AS_ACTIVE}: Received Event AS-TRANSFER.req
269
20180928164649665 DMM <0002> subscr_conn.c:123 Subscr_Conn(PAGING_RESP:620382330)[0x6120000357a0]{SUBSCR_CONN_S_NEW}: state_chg to SUBSCR_CONN_S_ACCEPTED
270
20180928164649665 DMM <0002> subscr_conn.c:257 Subscr_Conn(PAGING_RESP:620382330)[0x6120000357a0]{SUBSCR_CONN_S_ACCEPTED}: subscr_conn_fsm_has_active_transactions: connection still has active transaction: CC
271
20180928164649666 DMM <0002> subscr_conn.c:544 Subscr_Conn(PAGING_RESP:620382330)[0x6120000357a0]{SUBSCR_CONN_S_ACCEPTED}: Received Event SUBSCR_CONN_E_COMPLETE_LAYER_3
272
20180928164649666 DMSC <0006> a_iface_bssap.c:351 User has been accepted by MSC.
273
20180928164649666 DLINP <0013> stream.c:279 connected write
274
20180928164649666 DLINP <0013> stream.c:204 sending data
275
20180928164649667 DLINP <0013> stream.c:279 connected write
276
20180928164649667 DLINP <0013> stream.c:204 sending data
277
20180928164649668 DLINP <0013> stream.c:279 connected write
278
20180928164649668 DLINP <0013> stream.c:204 sending data
279
20180928164650309 DLINP <0013> stream.c:275 connected read
280
20180928164650309 DLINP <0013> stream.c:189 message received
281
20180928164650309 DLSS7 <001d> osmo_ss7.c:1549 asp-asp-clnt-OsmoMSC-A: xua_cli_read_cb(): sctp_recvmsg() returned 68 (flags=0x80)
282
20180928164650309 DLM3UA <0020> m3ua.c:722 asp-asp-clnt-OsmoMSC-A: Received M3UA Message (XFER:DATA)
283
20180928164650309 DLM3UA <0020> m3ua.c:541 asp-asp-clnt-OsmoMSC-A: m3ua_rx_xfer
284
20180928164650309 DLM3UA <0020> m3ua.c:580 asp-asp-clnt-OsmoMSC-A: m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3 dpc=185=0.23.1
285
20180928164650309 DLSS7 <001d> osmo_ss7_hmrt.c:274 m3ua_hmdc_rx_from_l2(): found dpc=185=0.23.1 as local
286
20180928164650310 DLSS7 <001d> sccp_scrc.c:450 scrc_rx_mtp_xfer_ind_xua:  HDR=(CO:CODT,V=0,LEN=0),
287
        PART(T=Destination Reference,L=4,D=00000011),
288
        PART(T=Segmentation,L=4,D=00000000),
289
        PART(T=Data,L=27,D=010018830804066004020005811502010040080402600400021f00)
290
20180928164650310 DLSCCP <001e> sccp_scoc.c:1548 Received CO:CODT for local reference 17
291
20180928164650310 DLSCCP <001e> sccp_scoc.c:1581 SCCP-SCOC(17)[0x612000032da0]{ACTIVE}: Received Event RCOC-DT1.ind
292
20180928164650310 DLSCCP <001e> sccp_user.c:156 Delivering N-DATA.indication to SCCP User 'OsmoMSC-A'
293
20180928164650310 DBSSAP <0010> a_iface.c:574 N-DATA.ind(17, 01 00 18 83 08 04 06 60 04 02 00 05 81 15 02 01 00 40 08 04 02 60 04 00 02 1f 00 )
294
20180928164650310 DMSC <0006> a_iface_bssap.c:80 Looking for A subscriber: conn_id 17
295
20180928164650310 DBSSAP <0010> a_iface_bssap.c:88 (subscr MSISDN:12345, conn_id 17) Found A subscriber for conn_id 17
296
20180928164650310 DBSSAP <0010> a_iface_bssap.c:674 (subscr MSISDN:12345, conn_id 17) Rx DTAP 01 00 18 83 08 04 06 60 04 02 00 05 81 15 02 01 00 40 08 04 02 60 04 00 02 1f 00
297
20180928164650310 DRLL <0000> gsm_04_08.c:1451 Dispatching 04.08 message GSM48_MT_CC_CALL_CONF (0x3:0x8)
298
20180928164650311 DCC <0001> gsm_04_08_cc.c:176 stopping pending timer T303
299
20180928164650311 DCC <0001> gsm_04_08_cc.c:463 starting timer T310 with 30 seconds
300
20180928164650311 DCC <0001> gsm_04_08_cc.c:148 (ti 00 sub MSISDN:12345) new state CALL_PRESENT -> MO_TERM_CALL_CONF
301
20180928164650311 DMGCP <0007> fsm.c:299 MGW(MGW_0)[0x612000037ea0]{ST_CRCX_RAN}: Allocated
302
20180928164650311 DMGCP <0007> msc_mgcp.c:1016 MGW(MGW_0)[0x612000037ea0]{ST_CRCX_RAN}: Received Event EV_INIT
303
20180928164650311 DMGCP <0007> msc_mgcp.c:296 MGW(MGW_0)[0x612000037ea0]{ST_CRCX_RAN}: CRCX/RAN: creating connection for the RAN side on MGW endpoint:rtpbridge/*@mgw...
304
20180928164650311 DLMGCP <0021> mgcp_client.c:953 Queued 63 bytes for MGCP GW
305
20180928164650311 DMGCP <0007> msc_mgcp.c:330 MGW(MGW_0)[0x612000037ea0]{ST_CRCX_RAN}: state_chg to ST_CRCX_CN
306
20180928164650311 DMGCP <0007> msc_mgcp.c:1021 (subscriber:MSISDN:12345) call assignment initiated
307
20180928164650311 DMNCC <0004> gsm_04_08_cc.c:188 transmit message MNCC_CALL_CONF_IND
308
20180928164650311 DCC <0001> gsm_04_08_cc.c:211 Sending 'MNCC_CALL_CONF_IND' to MNCC.
309
20180928164650312 DMM <0002> subscr_conn.c:617 Subscr_Conn(PAGING_RESP:2652793506)[0x612000032f20]{SUBSCR_CONN_S_ACCEPTED}: Received Event SUBSCR_CONN_E_COMMUNICATING
310
20180928164650312 DMM <0002> subscr_conn.c:289 Subscr_Conn(PAGING_RESP:2652793506)[0x612000032f20]{SUBSCR_CONN_S_ACCEPTED}: state_chg to SUBSCR_CONN_S_COMMUNICATING
311
20180928164650312 DLMGCP <0021> mgcp_client.c:749 Tx MGCP msg to MGCP GW: 'CRCX 30 rtpbridge/*@mgw MGCP 1.0'
312
20180928164650312 DLMGCP <0021> mgcp_client.c:751 Sending msg to MGCP GW size: 63
313
20180928164650312 DLMGCP <0021> mgcp_client.c:720 Failed to read: 111/Connection refused
314
20180928164650313 DMNCC <0004> gsm_04_08_cc.c:1822 receive message MNCC_LCHAN_MODIFY
315
20180928164650313 DCC <0001> gsm_04_08_cc.c:1997 (ti 00 sub 12345) Received 'MNCC_LCHAN_MODIFY' from MNCC in state 9 (MO_TERM_CALL_CONF)
316
20180928164650313 DCC <0001> gsm_04_08_cc.c:2006 Message unhandled at this state.
317
20180928164650326 DLINP <0013> stream.c:275 connected read
318
20180928164650326 DLINP <0013> stream.c:189 message received
319
20180928164650326 DLSS7 <001d> osmo_ss7.c:1549 asp-asp-clnt-OsmoMSC-A: xua_cli_read_cb(): sctp_recvmsg() returned 44 (flags=0x80)
320
20180928164650326 DLM3UA <0020> m3ua.c:722 asp-asp-clnt-OsmoMSC-A: Received M3UA Message (XFER:DATA)
321
20180928164650326 DLM3UA <0020> m3ua.c:541 asp-asp-clnt-OsmoMSC-A: m3ua_rx_xfer
322
20180928164650326 DLM3UA <0020> m3ua.c:580 asp-asp-clnt-OsmoMSC-A: m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3 dpc=185=0.23.1
323
20180928164650326 DLSS7 <001d> osmo_ss7_hmrt.c:274 m3ua_hmdc_rx_from_l2(): found dpc=185=0.23.1 as local
324
20180928164650326 DLSS7 <001d> sccp_scrc.c:450 scrc_rx_mtp_xfer_ind_xua:  HDR=(CO:CODT,V=0,LEN=0),
325
        PART(T=Destination Reference,L=4,D=00000012),
326
        PART(T=Segmentation,L=4,D=00000000),
327
        PART(T=Data,L=5,D=0100028308)
328
20180928164650326 DLSCCP <001e> sccp_scoc.c:1548 Received CO:CODT for local reference 18
329
20180928164650326 DLSCCP <001e> sccp_scoc.c:1581 SCCP-SCOC(18)[0x612000035620]{ACTIVE}: Received Event RCOC-DT1.ind
330
20180928164650326 DLSCCP <001e> sccp_user.c:156 Delivering N-DATA.indication to SCCP User 'OsmoMSC-A'
331
20180928164650326 DBSSAP <0010> a_iface.c:574 N-DATA.ind(18, 01 00 02 83 08 )
332
20180928164650326 DMSC <0006> a_iface_bssap.c:80 Looking for A subscriber: conn_id 18
333
20180928164650326 DBSSAP <0010> a_iface_bssap.c:88 (subscr MSISDN:12346, conn_id 18) Found A subscriber for conn_id 18
334
20180928164650326 DBSSAP <0010> a_iface_bssap.c:674 (subscr MSISDN:12346, conn_id 18) Rx DTAP 01 00 02 83 08
335
20180928164650326 DRLL <0000> gsm_04_08.c:1451 Dispatching 04.08 message GSM48_MT_CC_CALL_CONF (0x3:0x8)
336
20180928164650326 DCC <0001> gsm_04_08_cc.c:176 stopping pending timer T303
337
20180928164650326 DCC <0001> gsm_04_08_cc.c:463 starting timer T310 with 30 seconds
338
20180928164650326 DCC <0001> gsm_04_08_cc.c:148 (ti 00 sub MSISDN:12346) new state CALL_PRESENT -> MO_TERM_CALL_CONF
339
20180928164650326 DMGCP <0007> fsm.c:299 MGW(MGW_0)[0x612000038020]{ST_CRCX_RAN}: Allocated
340
20180928164650326 DMGCP <0007> msc_mgcp.c:1016 MGW(MGW_0)[0x612000038020]{ST_CRCX_RAN}: Received Event EV_INIT
341
20180928164650326 DMGCP <0007> msc_mgcp.c:296 MGW(MGW_0)[0x612000038020]{ST_CRCX_RAN}: CRCX/RAN: creating connection for the RAN side on MGW endpoint:rtpbridge/*@mgw...
342
20180928164650326 DLMGCP <0021> mgcp_client.c:953 Queued 63 bytes for MGCP GW
343
20180928164650327 DMGCP <0007> msc_mgcp.c:330 MGW(MGW_0)[0x612000038020]{ST_CRCX_RAN}: state_chg to ST_CRCX_CN
344
20180928164650327 DMGCP <0007> msc_mgcp.c:1021 (subscriber:MSISDN:12346) call assignment initiated
345
20180928164650327 DMNCC <0004> gsm_04_08_cc.c:188 transmit message MNCC_CALL_CONF_IND
346
20180928164650327 DCC <0001> gsm_04_08_cc.c:211 Sending 'MNCC_CALL_CONF_IND' to MNCC.
347
20180928164650327 DMM <0002> subscr_conn.c:617 Subscr_Conn(PAGING_RESP:620382330)[0x6120000357a0]{SUBSCR_CONN_S_ACCEPTED}: Received Event SUBSCR_CONN_E_COMMUNICATING
348
20180928164650327 DMM <0002> subscr_conn.c:289 Subscr_Conn(PAGING_RESP:620382330)[0x6120000357a0]{SUBSCR_CONN_S_ACCEPTED}: state_chg to SUBSCR_CONN_S_COMMUNICATING
349
20180928164650327 DLMGCP <0021> mgcp_client.c:749 Tx MGCP msg to MGCP GW: 'CRCX 31 rtpbridge/*@mgw MGCP 1.0'
350
20180928164650327 DLMGCP <0021> mgcp_client.c:751 Sending msg to MGCP GW size: 63
351
20180928164650327 DLMGCP <0021> mgcp_client.c:720 Failed to read: 111/Connection refused
352
20180928164650327 DMNCC <0004> gsm_04_08_cc.c:1822 receive message MNCC_LCHAN_MODIFY
353
20180928164650328 DCC <0001> gsm_04_08_cc.c:1997 (ti 00 sub 12346) Received 'MNCC_LCHAN_MODIFY' from MNCC in state 9 (MO_TERM_CALL_CONF)
354
20180928164650328 DCC <0001> gsm_04_08_cc.c:2006 Message unhandled at this state.
355
20180928164654314 DMGCP <0007> fsm.c:189 MGW(MGW_0)[0x612000037ea0]{ST_CRCX_CN}: Timeout of T1
356
20180928164654314 DLMGCP <0021> mgcp_client.c:979 Canceled transaction 30
357
20180928164654314 DMGCP <0007> msc_mgcp.c:239 MGW(MGW_0)[0x612000037ea0]{ST_CRCX_CN}: request to MGW timed out -- graceful shutdown...
358
20180928164654314 DMNCC <0004> gsm_04_08_cc.c:1822 receive message MNCC_REL_REQ
359
20180928164654315 DCC <0001> gsm_04_08_cc.c:1997 (ti 00 sub 12345) Received 'MNCC_REL_REQ' from MNCC in state 9 (MO_TERM_CALL_CONF)
360
20180928164654315 DCC <0001> gsm_04_08_cc.c:176 stopping pending timer T310
361
20180928164654315 DCC <0001> gsm_04_08_cc.c:463 starting timer T308 with 10 seconds
362
20180928164654315 DCC <0001> gsm_04_08_cc.c:148 (ti 00 sub MSISDN:12345) new state MO_TERM_CALL_CONF -> RELEASE_REQ
363
20180928164654315 DMSC <0006> msc_ifaces.c:60 msc_tx 6 bytes to MSISDN:12345 via RAN_GERAN_A
364
20180928164654315 DBSSAP <0010> a_iface.c:154 (subscr MSISDN:12345, conn_id 17) Passing DTAP message (DLCI=0x00) from MSC to BSC
365
20180928164654315 DBSSAP <0010> a_iface.c:168 (subscr MSISDN:12345, conn_id 17) N-DATA.req([])
366
20180928164654315 DLSCCP <001e> sccp_scoc.c:1615 Received SCCP User Primitive N-DATA.request)
367
20180928164654315 DLSCCP <001e> sccp_scoc.c:1657 SCCP-SCOC(17)[0x612000032da0]{ACTIVE}: Received Event N-DATA.req
368
20180928164654316 DLSS7 <001d> sccp_scrc.c:398 sccp_scrc_rx_scoc_conn_msg:  HDR=(CO:CODT,V=0,LEN=0),
369
        PART(T=Routing Context,L=4,D=00000000),
370
        PART(T=Destination Reference,L=4,D=00000064),
371
        PART(T=Data,L=9,D=010006032d080283af)
372
20180928164654316 DLSS7 <001d> osmo_ss7_hmrt.c:278 m3ua_hmdc_rx_from_l2(): dpc=187=0.23.3 not local, message is for routing
373
20180928164654316 DLSS7 <001d> osmo_ss7_hmrt.c:227 Found route for dpc=187=0.23.3: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoMSC-A proto=m3ua
374
20180928164654316 DLSS7 <001d> osmo_ss7_hmrt.c:233 rt->dest.as proto is M3UA for dpc=187=0.23.3
375
20180928164654316 DLSS7 <001d> m3ua.c:507 XUA_AS(as-clnt-OsmoMSC-A)[0x612000009220]{AS_ACTIVE}: Received Event AS-TRANSFER.req
376
20180928164654316 DMGCP <0007> msc_mgcp.c:215 MGW(MGW_0)[0x612000037ea0]{ST_CRCX_CN}: state_chg to ST_HALT
377
20180928164654316 DMGCP <0007> msc_mgcp.c:216 MGW(MGW_0)[0x612000037ea0]{ST_HALT}: Received Event EV_TEARDOWN_ERROR
378
20180928164654316 DMGCP <0007> msc_mgcp.c:854 MGW(MGW_0)[0x612000037ea0]{ST_HALT}: state_chg to ST_HALT
379
20180928164654317 DLINP <0013> stream.c:279 connected write
380
20180928164654317 DLINP <0013> stream.c:204 sending data
381
20180928164654319 DLINP <0013> stream.c:279 connected write
382
20180928164654319 DLINP <0013> stream.c:204 sending data
383
20180928164654327 DMGCP <0007> fsm.c:189 MGW(MGW_0)[0x612000038020]{ST_CRCX_CN}: Timeout of T1
384
20180928164654327 DLMGCP <0021> mgcp_client.c:979 Canceled transaction 31
385
20180928164654327 DMGCP <0007> msc_mgcp.c:239 MGW(MGW_0)[0x612000038020]{ST_CRCX_CN}: request to MGW timed out -- graceful shutdown...
386
20180928164654327 DMNCC <0004> gsm_04_08_cc.c:1822 receive message MNCC_REL_REQ
387
20180928164654327 DCC <0001> gsm_04_08_cc.c:1997 (ti 00 sub 12346) Received 'MNCC_REL_REQ' from MNCC in state 9 (MO_TERM_CALL_CONF)
388
20180928164654327 DCC <0001> gsm_04_08_cc.c:176 stopping pending timer T310
389
20180928164654327 DCC <0001> gsm_04_08_cc.c:463 starting timer T308 with 10 seconds
390
20180928164654327 DCC <0001> gsm_04_08_cc.c:148 (ti 00 sub MSISDN:12346) new state MO_TERM_CALL_CONF -> RELEASE_REQ
391
20180928164654327 DMSC <0006> msc_ifaces.c:60 msc_tx 6 bytes to MSISDN:12346 via RAN_GERAN_A
392
20180928164654327 DBSSAP <0010> a_iface.c:154 (subscr MSISDN:12346, conn_id 18) Passing DTAP message (DLCI=0x00) from MSC to BSC
393
20180928164654327 DBSSAP <0010> a_iface.c:168 (subscr MSISDN:12346, conn_id 18) N-DATA.req([])
394
20180928164654327 DLSCCP <001e> sccp_scoc.c:1615 Received SCCP User Primitive N-DATA.request)
395
20180928164654327 DLSCCP <001e> sccp_scoc.c:1657 SCCP-SCOC(18)[0x612000035620]{ACTIVE}: Received Event N-DATA.req
396
20180928164654327 DLSS7 <001d> sccp_scrc.c:398 sccp_scrc_rx_scoc_conn_msg:  HDR=(CO:CODT,V=0,LEN=0),
397
        PART(T=Routing Context,L=4,D=00000000),
398
        PART(T=Destination Reference,L=4,D=00000065),
399
        PART(T=Data,L=9,D=010006032d080283af)
400
20180928164654327 DLSS7 <001d> osmo_ss7_hmrt.c:278 m3ua_hmdc_rx_from_l2(): dpc=187=0.23.3 not local, message is for routing
401
20180928164654327 DLSS7 <001d> osmo_ss7_hmrt.c:227 Found route for dpc=187=0.23.3: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoMSC-A proto=m3ua
402
20180928164654328 DLSS7 <001d> osmo_ss7_hmrt.c:233 rt->dest.as proto is M3UA for dpc=187=0.23.3
403
20180928164654328 DLSS7 <001d> m3ua.c:507 XUA_AS(as-clnt-OsmoMSC-A)[0x612000009220]{AS_ACTIVE}: Received Event AS-TRANSFER.req
404
20180928164654328 DMGCP <0007> msc_mgcp.c:215 MGW(MGW_0)[0x612000038020]{ST_CRCX_CN}: state_chg to ST_HALT
405
20180928164654328 DMGCP <0007> msc_mgcp.c:216 MGW(MGW_0)[0x612000038020]{ST_HALT}: Received Event EV_TEARDOWN_ERROR
406
20180928164654328 DMGCP <0007> msc_mgcp.c:854 MGW(MGW_0)[0x612000038020]{ST_HALT}: state_chg to ST_HALT
407
20180928164654328 DLINP <0013> stream.c:279 connected write
408
20180928164654328 DLINP <0013> stream.c:204 sending data
409
20180928164654329 DLINP <0013> stream.c:279 connected write
410
20180928164654329 DLINP <0013> stream.c:204 sending data
411
20180928164654541 DLINP <0013> stream.c:275 connected read
412
20180928164654541 DLINP <0013> stream.c:189 message received
413
20180928164654541 DLSS7 <001d> osmo_ss7.c:1549 asp-asp-clnt-OsmoMSC-A: xua_cli_read_cb(): sctp_recvmsg() returned 44 (flags=0x80)
414
20180928164654541 DLM3UA <0020> m3ua.c:722 asp-asp-clnt-OsmoMSC-A: Received M3UA Message (XFER:DATA)
415
20180928164654541 DLM3UA <0020> m3ua.c:541 asp-asp-clnt-OsmoMSC-A: m3ua_rx_xfer
416
20180928164654541 DLM3UA <0020> m3ua.c:580 asp-asp-clnt-OsmoMSC-A: m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3 dpc=185=0.23.1
417
20180928164654541 DLSS7 <001d> osmo_ss7_hmrt.c:274 m3ua_hmdc_rx_from_l2(): found dpc=185=0.23.1 as local
418
20180928164654541 DLSS7 <001d> sccp_scrc.c:450 scrc_rx_mtp_xfer_ind_xua:  HDR=(CO:CODT,V=0,LEN=0),
419
        PART(T=Destination Reference,L=4,D=00000011),
420
        PART(T=Segmentation,L=4,D=00000000),
421
        PART(T=Data,L=5,D=010002836a)
422
20180928164654541 DLSCCP <001e> sccp_scoc.c:1548 Received CO:CODT for local reference 17
423
20180928164654541 DLSCCP <001e> sccp_scoc.c:1581 SCCP-SCOC(17)[0x612000032da0]{ACTIVE}: Received Event RCOC-DT1.ind
424
20180928164654541 DLSCCP <001e> sccp_user.c:156 Delivering N-DATA.indication to SCCP User 'OsmoMSC-A'
425
20180928164654541 DBSSAP <0010> a_iface.c:574 N-DATA.ind(17, 01 00 02 83 6a )
426
20180928164654541 DMSC <0006> a_iface_bssap.c:80 Looking for A subscriber: conn_id 17
427
20180928164654541 DBSSAP <0010> a_iface_bssap.c:88 (subscr MSISDN:12345, conn_id 17) Found A subscriber for conn_id 17
428
20180928164654541 DBSSAP <0010> a_iface_bssap.c:674 (subscr MSISDN:12345, conn_id 17) Rx DTAP 01 00 02 83 6a
429
20180928164654542 DRLL <0000> gsm_04_08.c:1451 Dispatching 04.08 message GSM48_MT_CC_RELEASE_COMPL (0x3:0x2a)
430
20180928164654542 DCC <0001> gsm_04_08_cc.c:176 stopping pending timer T308
431
20180928164654542 DMNCC <0004> gsm_04_08_cc.c:188 transmit message MNCC_REL_CNF
432
20180928164654542 DCC <0001> gsm_04_08_cc.c:211 Sending 'MNCC_REL_CNF' to MNCC.
433
20180928164654542 DMGCP <0007> msc_mgcp.c:1169 MGW(MGW_0)[0x612000037ea0]{ST_HALT}: Received Event EV_TEARDOWN
434
20180928164654542 DLMGCP <0021> mgcp_client.c:976 Cannot cancel, no such transaction: 30
435
20180928164654542 DMGCP <0007> fsm.c:381 MGW(MGW_0)[0x612000037ea0]{ST_HALT}: Deallocated
436
20180928164654542 DMGCP <0007> msc_mgcp.c:1178 (subscriber:MSISDN:12345) call release initiated
437
20180928164654542 DCC <0001> gsm_04_08_cc.c:148 (ti 00 sub MSISDN:12345) new state RELEASE_REQ -> NULL
438
20180928164654542 DMM <0002> subscr_conn.c:617 Subscr_Conn(PAGING_RESP:2652793506)[0x612000032f20]{SUBSCR_CONN_S_COMMUNICATING}: Received Event SUBSCR_CONN_E_COMMUNICATING
439
20180928164654542 DMM <0002> osmo_msc.c:326 Subscr_Conn(PAGING_RESP:2652793506)[0x612000032f20]{SUBSCR_CONN_S_COMMUNICATING}: Received Event SUBSCR_CONN_E_UNUSED
440
20180928164654542 DMM <0002> subscr_conn.c:317 Subscr_Conn(PAGING_RESP:2652793506)[0x612000032f20]{SUBSCR_CONN_S_COMMUNICATING}: state_chg to SUBSCR_CONN_S_RELEASING
441
20180928164654542 DBSSAP <0010> a_iface.c:419 (subscr MSISDN:12345, conn_id 17) Tx BSSMAP CLEAR COMMAND to BSC
442
20180928164654542 DLSCCP <001e> sccp_scoc.c:1615 Received SCCP User Primitive N-DATA.request)
443
20180928164654543 DLSCCP <001e> sccp_scoc.c:1657 SCCP-SCOC(17)[0x612000032da0]{ACTIVE}: Received Event N-DATA.req
444
20180928164654543 DLSS7 <001d> sccp_scrc.c:398 sccp_scrc_rx_scoc_conn_msg:  HDR=(CO:CODT,V=0,LEN=0),
445
        PART(T=Routing Context,L=4,D=00000000),
446
        PART(T=Destination Reference,L=4,D=00000064),
447
        PART(T=Data,L=6,D=000420040109)
448
20180928164654543 DLSS7 <001d> osmo_ss7_hmrt.c:278 m3ua_hmdc_rx_from_l2(): dpc=187=0.23.3 not local, message is for routing
449
20180928164654543 DLSS7 <001d> osmo_ss7_hmrt.c:227 Found route for dpc=187=0.23.3: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoMSC-A proto=m3ua
450
20180928164654543 DLSS7 <001d> osmo_ss7_hmrt.c:233 rt->dest.as proto is M3UA for dpc=187=0.23.3
451
20180928164654543 DLSS7 <001d> m3ua.c:507 XUA_AS(as-clnt-OsmoMSC-A)[0x612000009220]{AS_ACTIVE}: Received Event AS-TRANSFER.req
452
20180928164654543 DLINP <0013> stream.c:279 connected write
453
20180928164654543 DLINP <0013> stream.c:204 sending data
454
20180928164654543 DLINP <0013> stream.c:279 connected write
455
20180928164654543 DLINP <0013> stream.c:204 sending data
456
20180928164654546 DLINP <0013> stream.c:275 connected read
457
20180928164654546 DLINP <0013> stream.c:189 message received
458
20180928164654546 DLSS7 <001d> osmo_ss7.c:1549 asp-asp-clnt-OsmoMSC-A: xua_cli_read_cb(): sctp_recvmsg() returned 44 (flags=0x80)
459
20180928164654546 DLM3UA <0020> m3ua.c:722 asp-asp-clnt-OsmoMSC-A: Received M3UA Message (XFER:DATA)
460
20180928164654546 DLM3UA <0020> m3ua.c:541 asp-asp-clnt-OsmoMSC-A: m3ua_rx_xfer
461
20180928164654546 DLM3UA <0020> m3ua.c:580 asp-asp-clnt-OsmoMSC-A: m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3 dpc=185=0.23.1
462
20180928164654546 DLSS7 <001d> osmo_ss7_hmrt.c:274 m3ua_hmdc_rx_from_l2(): found dpc=185=0.23.1 as local
463
20180928164654546 DLSS7 <001d> sccp_scrc.c:450 scrc_rx_mtp_xfer_ind_xua:  HDR=(CO:CODT,V=0,LEN=0),
464
        PART(T=Destination Reference,L=4,D=00000011),
465
        PART(T=Segmentation,L=4,D=00000000),
466
        PART(T=Data,L=3,D=000121)
467
20180928164654546 DLSCCP <001e> sccp_scoc.c:1548 Received CO:CODT for local reference 17
468
20180928164654547 DLSCCP <001e> sccp_scoc.c:1581 SCCP-SCOC(17)[0x612000032da0]{ACTIVE}: Received Event RCOC-DT1.ind
469
20180928164654547 DLSCCP <001e> sccp_user.c:156 Delivering N-DATA.indication to SCCP User 'OsmoMSC-A'
470
20180928164654547 DBSSAP <0010> a_iface.c:574 N-DATA.ind(17, 00 01 21 )
471
20180928164654547 DMSC <0006> a_iface_bssap.c:80 Looking for A subscriber: conn_id 17
472
20180928164654547 DBSSAP <0010> a_iface_bssap.c:88 (subscr MSISDN:12345, conn_id 17) Found A subscriber for conn_id 17
473
20180928164654547 DBSSAP <0010> a_iface_bssap.c:635 (subscr MSISDN:12345, conn_id 17) Rx BSSMAP DT1 CLEAR COMPLETE
474
20180928164654547 DBSSAP <0010> a_iface_bssap.c:241 (subscr MSISDN:12345, conn_id 17) Rx BSSMAP CLEAR COMPLETE, releasing SCCP connection
475
20180928164654547 DMM <0002> osmo_msc.c:326 Subscr_Conn(PAGING_RESP:2652793506)[0x612000032f20]{SUBSCR_CONN_S_RELEASING}: Received Event SUBSCR_CONN_E_UNUSED
476
20180928164654547 DMM <0002> subscr_conn.c:395 Subscr_Conn(PAGING_RESP:2652793506)[0x612000032f20]{SUBSCR_CONN_S_RELEASING}: state_chg to SUBSCR_CONN_S_RELEASED
477
20180928164654547 DMM <0002> subscr_conn.c:402 Subscr_Conn(PAGING_RESP:2652793506)[0x612000032f20]{SUBSCR_CONN_S_RELEASED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
478
20180928164654547 DVLR <000e> subscr_conn.c:402 Process_Access_Request_VLR(PAGING_RESP:2652793506)[0x6120000330a0]{PR_ARQ_S_DONE}: Terminating (cause = OSMO_FSM_TERM_PARENT)
479
20180928164654547 DVLR <000e> subscr_conn.c:402 Process_Access_Request_VLR(PAGING_RESP:2652793506)[0x6120000330a0]{PR_ARQ_S_DONE}: Removing from parent Subscr_Conn(PAGING_RESP:2652793506)[0x612000032f20]
480
20180928164654547 DVLR <000e> subscr_conn.c:402 Process_Access_Request_VLR(PAGING_RESP:2652793506)[0x6120000330a0]{PR_ARQ_S_DONE}: Freeing instance
481
20180928164654547 DVLR <000e> fsm.c:381 Process_Access_Request_VLR(PAGING_RESP:2652793506)[0x6120000330a0]{PR_ARQ_S_DONE}: Deallocated
482
20180928164654547 DRLL <0000> subscr_conn.c:528 MSISDN:12345: Freeing subscriber connection
483
20180928164654547 DMM <0002> subscr_conn.c:402 Subscr_Conn(PAGING_RESP:2652793506)[0x612000032f20]{SUBSCR_CONN_S_RELEASED}: Freeing instance
484
20180928164654547 DMM <0002> fsm.c:381 Subscr_Conn(PAGING_RESP:2652793506)[0x612000032f20]{SUBSCR_CONN_S_RELEASED}: Deallocated
485
20180928164654547 DLSCCP <001e> sccp_scoc.c:1615 Received SCCP User Primitive N-DISCONNECT.request)
486
20180928164654547 DLSCCP <001e> sccp_scoc.c:1657 SCCP-SCOC(17)[0x612000032da0]{ACTIVE}: Received Event N-DISCONNECT.req
487
20180928164654547 DLSS7 <001d> sccp_scrc.c:398 sccp_scrc_rx_scoc_conn_msg:  HDR=(CO:RELRE,V=0,LEN=0),
488
        PART(T=Routing Context,L=4,D=00000000),
489
        PART(T=Destination Reference,L=4,D=00000064),
490
        PART(T=Source Reference,L=4,D=00000011),
491
        PART(T=Cause,L=4,D=00000300)
492
20180928164654547 DLSS7 <001d> osmo_ss7_hmrt.c:278 m3ua_hmdc_rx_from_l2(): dpc=187=0.23.3 not local, message is for routing
493
20180928164654547 DLSS7 <001d> osmo_ss7_hmrt.c:227 Found route for dpc=187=0.23.3: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoMSC-A proto=m3ua
494
20180928164654547 DLSS7 <001d> osmo_ss7_hmrt.c:233 rt->dest.as proto is M3UA for dpc=187=0.23.3
495
20180928164654547 DLSS7 <001d> m3ua.c:507 XUA_AS(as-clnt-OsmoMSC-A)[0x612000009220]{AS_ACTIVE}: Received Event AS-TRANSFER.req
496
20180928164654547 DLSCCP <001e> sccp_scoc.c:956 SCCP-SCOC(17)[0x612000032da0]{ACTIVE}: state_chg to DISCONN_PEND
497
20180928164654547 DBSSAP <0010> a_iface.c:90 (conn_id 17) Removing A-interface conn
498
20180928164654547 DLINP <0013> stream.c:279 connected write
499
20180928164654547 DLINP <0013> stream.c:204 sending data
500
20180928164654547 DLINP <0013> stream.c:279 connected write
501
20180928164654547 DLINP <0013> stream.c:204 sending data
502
20180928164654549 DLINP <0013> stream.c:275 connected read
503
20180928164654549 DLINP <0013> stream.c:189 message received
504
20180928164654549 DLSS7 <001d> osmo_ss7.c:1549 asp-asp-clnt-OsmoMSC-A: xua_cli_read_cb(): sctp_recvmsg() returned 40 (flags=0x80)
505
20180928164654549 DLM3UA <0020> m3ua.c:722 asp-asp-clnt-OsmoMSC-A: Received M3UA Message (XFER:DATA)
506
20180928164654549 DLM3UA <0020> m3ua.c:541 asp-asp-clnt-OsmoMSC-A: m3ua_rx_xfer
507
20180928164654549 DLM3UA <0020> m3ua.c:580 asp-asp-clnt-OsmoMSC-A: m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3 dpc=185=0.23.1
508
20180928164654549 DLSS7 <001d> osmo_ss7_hmrt.c:274 m3ua_hmdc_rx_from_l2(): found dpc=185=0.23.1 as local
509
20180928164654549 DLSS7 <001d> sccp_scrc.c:450 scrc_rx_mtp_xfer_ind_xua:  HDR=(CO:RELCO,V=0,LEN=0),
510
        PART(T=Destination Reference,L=4,D=00000011),
511
        PART(T=Source Reference,L=4,D=00000064)
512
20180928164654549 DLSCCP <001e> sccp_scoc.c:1548 Received CO:RELCO for local reference 17
513
20180928164654549 DLSCCP <001e> sccp_scoc.c:1581 SCCP-SCOC(17)[0x612000032da0]{DISCONN_PEND}: Received Event RCOC-RELEASE_COMPLETE.ind
514
20180928164654549 DLSCCP <001e> sccp_scoc.c:1060 SCCP-SCOC(17)[0x612000032da0]{DISCONN_PEND}: state_chg to IDLE
515
20180928164654549 DLSCCP <001e> sccp_scoc.c:420 SCCP-SCOC(17)[0x612000032da0]{IDLE}: Terminating (cause = OSMO_FSM_TERM_REQUEST)
516
20180928164654549 DLSCCP <001e> sccp_scoc.c:420 SCCP-SCOC(17)[0x612000032da0]{IDLE}: Freeing instance
517
20180928164654549 DLSCCP <001e> fsm.c:381 SCCP-SCOC(17)[0x612000032da0]{IDLE}: Deallocated
518
20180928164654558 DLINP <0013> stream.c:275 connected read
519
20180928164654558 DLINP <0013> stream.c:189 message received
520
20180928164654558 DLSS7 <001d> osmo_ss7.c:1549 asp-asp-clnt-OsmoMSC-A: xua_cli_read_cb(): sctp_recvmsg() returned 44 (flags=0x80)
521
20180928164654558 DLM3UA <0020> m3ua.c:722 asp-asp-clnt-OsmoMSC-A: Received M3UA Message (XFER:DATA)
522
20180928164654558 DLM3UA <0020> m3ua.c:541 asp-asp-clnt-OsmoMSC-A: m3ua_rx_xfer
523
20180928164654558 DLM3UA <0020> m3ua.c:580 asp-asp-clnt-OsmoMSC-A: m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3 dpc=185=0.23.1
524
20180928164654559 DLSS7 <001d> osmo_ss7_hmrt.c:274 m3ua_hmdc_rx_from_l2(): found dpc=185=0.23.1 as local
525
20180928164654559 DLSS7 <001d> sccp_scrc.c:450 scrc_rx_mtp_xfer_ind_xua:  HDR=(CO:CODT,V=0,LEN=0),
526
        PART(T=Destination Reference,L=4,D=00000012),
527
        PART(T=Segmentation,L=4,D=00000000),
528
        PART(T=Data,L=5,D=010002836a)
529
20180928164654559 DLSCCP <001e> sccp_scoc.c:1548 Received CO:CODT for local reference 18
530
20180928164654559 DLSCCP <001e> sccp_scoc.c:1581 SCCP-SCOC(18)[0x612000035620]{ACTIVE}: Received Event RCOC-DT1.ind
531
20180928164654559 DLSCCP <001e> sccp_user.c:156 Delivering N-DATA.indication to SCCP User 'OsmoMSC-A'
532
20180928164654559 DBSSAP <0010> a_iface.c:574 N-DATA.ind(18, 01 00 02 83 6a )
533
20180928164654559 DMSC <0006> a_iface_bssap.c:80 Looking for A subscriber: conn_id 18
534
20180928164654559 DBSSAP <0010> a_iface_bssap.c:88 (subscr MSISDN:12346, conn_id 18) Found A subscriber for conn_id 18
535
20180928164654559 DBSSAP <0010> a_iface_bssap.c:674 (subscr MSISDN:12346, conn_id 18) Rx DTAP 01 00 02 83 6a
536
20180928164654559 DRLL <0000> gsm_04_08.c:1451 Dispatching 04.08 message GSM48_MT_CC_RELEASE_COMPL (0x3:0x2a)
537
20180928164654559 DCC <0001> gsm_04_08_cc.c:176 stopping pending timer T308
538
20180928164654559 DMNCC <0004> gsm_04_08_cc.c:188 transmit message MNCC_REL_CNF
539
20180928164654559 DCC <0001> gsm_04_08_cc.c:211 Sending 'MNCC_REL_CNF' to MNCC.
540
20180928164654559 DMGCP <0007> msc_mgcp.c:1169 MGW(MGW_0)[0x612000038020]{ST_HALT}: Received Event EV_TEARDOWN
541
20180928164654559 DLMGCP <0021> mgcp_client.c:976 Cannot cancel, no such transaction: 31
542
20180928164654559 DMGCP <0007> fsm.c:381 MGW(MGW_0)[0x612000038020]{ST_HALT}: Deallocated
543
20180928164654559 DMGCP <0007> msc_mgcp.c:1178 (subscriber:MSISDN:12346) call release initiated
544
20180928164654559 DCC <0001> gsm_04_08_cc.c:148 (ti 00 sub MSISDN:12346) new state RELEASE_REQ -> NULL
545
20180928164654559 DMM <0002> subscr_conn.c:617 Subscr_Conn(PAGING_RESP:620382330)[0x6120000357a0]{SUBSCR_CONN_S_COMMUNICATING}: Received Event SUBSCR_CONN_E_COMMUNICATING
546
20180928164654559 DMM <0002> osmo_msc.c:326 Subscr_Conn(PAGING_RESP:620382330)[0x6120000357a0]{SUBSCR_CONN_S_COMMUNICATING}: Received Event SUBSCR_CONN_E_UNUSED
547
20180928164654559 DMM <0002> subscr_conn.c:317 Subscr_Conn(PAGING_RESP:620382330)[0x6120000357a0]{SUBSCR_CONN_S_COMMUNICATING}: state_chg to SUBSCR_CONN_S_RELEASING
548
20180928164654560 DBSSAP <0010> a_iface.c:419 (subscr MSISDN:12346, conn_id 18) Tx BSSMAP CLEAR COMMAND to BSC
549
20180928164654560 DLSCCP <001e> sccp_scoc.c:1615 Received SCCP User Primitive N-DATA.request)
550
20180928164654560 DLSCCP <001e> sccp_scoc.c:1657 SCCP-SCOC(18)[0x612000035620]{ACTIVE}: Received Event N-DATA.req
551
20180928164654560 DLSS7 <001d> sccp_scrc.c:398 sccp_scrc_rx_scoc_conn_msg:  HDR=(CO:CODT,V=0,LEN=0),
552
        PART(T=Routing Context,L=4,D=00000000),
553
        PART(T=Destination Reference,L=4,D=00000065),
554
        PART(T=Data,L=6,D=000420040109)
555
20180928164654560 DLSS7 <001d> osmo_ss7_hmrt.c:278 m3ua_hmdc_rx_from_l2(): dpc=187=0.23.3 not local, message is for routing
556
20180928164654560 DLSS7 <001d> osmo_ss7_hmrt.c:227 Found route for dpc=187=0.23.3: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoMSC-A proto=m3ua
557
20180928164654560 DLSS7 <001d> osmo_ss7_hmrt.c:233 rt->dest.as proto is M3UA for dpc=187=0.23.3
558
20180928164654560 DLSS7 <001d> m3ua.c:507 XUA_AS(as-clnt-OsmoMSC-A)[0x612000009220]{AS_ACTIVE}: Received Event AS-TRANSFER.req
559
20180928164654560 DLINP <0013> stream.c:279 connected write
560
20180928164654560 DLINP <0013> stream.c:204 sending data
561
20180928164654561 DLINP <0013> stream.c:279 connected write
562
20180928164654561 DLINP <0013> stream.c:204 sending data
563
20180928164654562 DLINP <0013> stream.c:275 connected read
564
20180928164654562 DLINP <0013> stream.c:189 message received
565
20180928164654562 DLSS7 <001d> osmo_ss7.c:1549 asp-asp-clnt-OsmoMSC-A: xua_cli_read_cb(): sctp_recvmsg() returned 44 (flags=0x80)
566
20180928164654562 DLM3UA <0020> m3ua.c:722 asp-asp-clnt-OsmoMSC-A: Received M3UA Message (XFER:DATA)
567
20180928164654562 DLM3UA <0020> m3ua.c:541 asp-asp-clnt-OsmoMSC-A: m3ua_rx_xfer
568
20180928164654562 DLM3UA <0020> m3ua.c:580 asp-asp-clnt-OsmoMSC-A: m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3 dpc=185=0.23.1
569
20180928164654562 DLSS7 <001d> osmo_ss7_hmrt.c:274 m3ua_hmdc_rx_from_l2(): found dpc=185=0.23.1 as local
570
20180928164654562 DLSS7 <001d> sccp_scrc.c:450 scrc_rx_mtp_xfer_ind_xua:  HDR=(CO:CODT,V=0,LEN=0),
571
        PART(T=Destination Reference,L=4,D=00000012),
572
        PART(T=Segmentation,L=4,D=00000000),
573
        PART(T=Data,L=3,D=000121)
574
20180928164654562 DLSCCP <001e> sccp_scoc.c:1548 Received CO:CODT for local reference 18
575
20180928164654562 DLSCCP <001e> sccp_scoc.c:1581 SCCP-SCOC(18)[0x612000035620]{ACTIVE}: Received Event RCOC-DT1.ind
576
20180928164654562 DLSCCP <001e> sccp_user.c:156 Delivering N-DATA.indication to SCCP User 'OsmoMSC-A'
577
20180928164654562 DBSSAP <0010> a_iface.c:574 N-DATA.ind(18, 00 01 21 )
578
20180928164654562 DMSC <0006> a_iface_bssap.c:80 Looking for A subscriber: conn_id 18
579
20180928164654563 DBSSAP <0010> a_iface_bssap.c:88 (subscr MSISDN:12346, conn_id 18) Found A subscriber for conn_id 18
580
20180928164654563 DBSSAP <0010> a_iface_bssap.c:635 (subscr MSISDN:12346, conn_id 18) Rx BSSMAP DT1 CLEAR COMPLETE
581
20180928164654563 DBSSAP <0010> a_iface_bssap.c:241 (subscr MSISDN:12346, conn_id 18) Rx BSSMAP CLEAR COMPLETE, releasing SCCP connection
582
20180928164654563 DMM <0002> osmo_msc.c:326 Subscr_Conn(PAGING_RESP:620382330)[0x6120000357a0]{SUBSCR_CONN_S_RELEASING}: Received Event SUBSCR_CONN_E_UNUSED
583
20180928164654563 DMM <0002> subscr_conn.c:395 Subscr_Conn(PAGING_RESP:620382330)[0x6120000357a0]{SUBSCR_CONN_S_RELEASING}: state_chg to SUBSCR_CONN_S_RELEASED
584
20180928164654563 DMM <0002> subscr_conn.c:402 Subscr_Conn(PAGING_RESP:620382330)[0x6120000357a0]{SUBSCR_CONN_S_RELEASED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
585
20180928164654563 DVLR <000e> subscr_conn.c:402 Process_Access_Request_VLR(PAGING_RESP:620382330)[0x612000035920]{PR_ARQ_S_DONE}: Terminating (cause = OSMO_FSM_TERM_PARENT)
586
20180928164654563 DVLR <000e> subscr_conn.c:402 Process_Access_Request_VLR(PAGING_RESP:620382330)[0x612000035920]{PR_ARQ_S_DONE}: Removing from parent Subscr_Conn(PAGING_RESP:620382330)[0x6120000357a0]
587
20180928164654563 DVLR <000e> subscr_conn.c:402 Process_Access_Request_VLR(PAGING_RESP:620382330)[0x612000035920]{PR_ARQ_S_DONE}: Freeing instance
588
20180928164654563 DVLR <000e> fsm.c:381 Process_Access_Request_VLR(PAGING_RESP:620382330)[0x612000035920]{PR_ARQ_S_DONE}: Deallocated
589
20180928164654563 DRLL <0000> subscr_conn.c:528 MSISDN:12346: Freeing subscriber connection
590
20180928164654563 DMM <0002> subscr_conn.c:402 Subscr_Conn(PAGING_RESP:620382330)[0x6120000357a0]{SUBSCR_CONN_S_RELEASED}: Freeing instance
591
20180928164654563 DMM <0002> fsm.c:381 Subscr_Conn(PAGING_RESP:620382330)[0x6120000357a0]{SUBSCR_CONN_S_RELEASED}: Deallocated
592
20180928164654563 DLSCCP <001e> sccp_scoc.c:1615 Received SCCP User Primitive N-DISCONNECT.request)
593
20180928164654563 DLSCCP <001e> sccp_scoc.c:1657 SCCP-SCOC(18)[0x612000035620]{ACTIVE}: Received Event N-DISCONNECT.req
594
20180928164654563 DLSS7 <001d> sccp_scrc.c:398 sccp_scrc_rx_scoc_conn_msg:  HDR=(CO:RELRE,V=0,LEN=0),
595
        PART(T=Routing Context,L=4,D=00000000),
596
        PART(T=Destination Reference,L=4,D=00000065),
597
        PART(T=Source Reference,L=4,D=00000012),
598
        PART(T=Cause,L=4,D=00000300)
599
20180928164654563 DLSS7 <001d> osmo_ss7_hmrt.c:278 m3ua_hmdc_rx_from_l2(): dpc=187=0.23.3 not local, message is for routing
600
20180928164654563 DLSS7 <001d> osmo_ss7_hmrt.c:227 Found route for dpc=187=0.23.3: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoMSC-A proto=m3ua
601
20180928164654563 DLSS7 <001d> osmo_ss7_hmrt.c:233 rt->dest.as proto is M3UA for dpc=187=0.23.3
602
20180928164654563 DLSS7 <001d> m3ua.c:507 XUA_AS(as-clnt-OsmoMSC-A)[0x612000009220]{AS_ACTIVE}: Received Event AS-TRANSFER.req
603
20180928164654563 DLSCCP <001e> sccp_scoc.c:956 SCCP-SCOC(18)[0x612000035620]{ACTIVE}: state_chg to DISCONN_PEND
604
20180928164654563 DBSSAP <0010> a_iface.c:90 (conn_id 18) Removing A-interface conn
605
20180928164654563 DLINP <0013> stream.c:279 connected write
606
20180928164654564 DLINP <0013> stream.c:204 sending data
607
20180928164654565 DLINP <0013> stream.c:275 connected read
608
20180928164654565 DLINP <0013> stream.c:189 message received
609
20180928164654565 DLSS7 <001d> osmo_ss7.c:1549 asp-asp-clnt-OsmoMSC-A: xua_cli_read_cb(): sctp_recvmsg() returned 40 (flags=0x80)
610
20180928164654565 DLM3UA <0020> m3ua.c:722 asp-asp-clnt-OsmoMSC-A: Received M3UA Message (XFER:DATA)
611
20180928164654565 DLM3UA <0020> m3ua.c:541 asp-asp-clnt-OsmoMSC-A: m3ua_rx_xfer
612
20180928164654565 DLM3UA <0020> m3ua.c:580 asp-asp-clnt-OsmoMSC-A: m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3 dpc=185=0.23.1
613
20180928164654565 DLSS7 <001d> osmo_ss7_hmrt.c:274 m3ua_hmdc_rx_from_l2(): found dpc=185=0.23.1 as local
614
20180928164654565 DLSS7 <001d> sccp_scrc.c:450 scrc_rx_mtp_xfer_ind_xua:  HDR=(CO:RELCO,V=0,LEN=0),
615
        PART(T=Destination Reference,L=4,D=00000012),
616
        PART(T=Source Reference,L=4,D=00000065)
617
20180928164654566 DLSCCP <001e> sccp_scoc.c:1548 Received CO:RELCO for local reference 18
618
20180928164654566 DLSCCP <001e> sccp_scoc.c:1581 SCCP-SCOC(18)[0x612000035620]{DISCONN_PEND}: Received Event RCOC-RELEASE_COMPLETE.ind
619
20180928164654566 DLSCCP <001e> sccp_scoc.c:1060 SCCP-SCOC(18)[0x612000035620]{DISCONN_PEND}: state_chg to IDLE
620
20180928164654566 DLSCCP <001e> sccp_scoc.c:420 SCCP-SCOC(18)[0x612000035620]{IDLE}: Terminating (cause = OSMO_FSM_TERM_REQUEST)
621
20180928164654566 DLSCCP <001e> sccp_scoc.c:420 SCCP-SCOC(18)[0x612000035620]{IDLE}: Freeing instance
622
20180928164654566 DLSCCP <001e> fsm.c:381 SCCP-SCOC(18)[0x612000035620]{IDLE}: Deallocated
623
20180928164654566 DLINP <0013> stream.c:279 connected write
624
20180928164654566 DLINP <0013> stream.c:204 sending data
625
20180928164700278 DMNCC <0004> mncc_sock.c:85 MNCC Socket has LOST connection
626
20180928164700278 DCC <0001> gsm_04_08.c:198 Clearing all currently active transactions!!!
(3-3/3)
Add picture from clipboard (Maximum size: 48.8 MB)