Project

General

Profile

Bug #3412 » osmo-sgsn.log

neels, 08/20/2018 03:57 PM

 
1
20180723171857787 DLGLOBAL NOTICE telnet_interface.c:104 telnet at 127.0.0.1 4245
2
20180723171857787 DLCTRL NOTICE control_if.c:887 CTRL at 127.0.0.1 4251
3
20180723171857787 DLGTP NOTICE gtp.c:757 GTP: gtp_newgsn() started at 192.168.0.6
4
20180723171857796 DGPRS NOTICE sgsn_libgtp.c:857 Created GTP on 192.168.0.6
5
20180723171857796 DGPRS NOTICE sgsn_main.c:478 libGTP v1.2.2.7-8e8c initialized
6
20180723171857796 DLGSUP NOTICE gsup_client.c:76 GSUP connecting to 127.0.0.1:4222
7
20180723171857796 DNS NOTICE gprs_ns.c:1628 Listening for nsip packets on 192.168.0.6:23000
8
20180723171857796 DNS NOTICE gprs_ns.c:1641 NS UDP socket at 192.168.0.6:23000
9
20180723171857797 DLSCCP NOTICE sccp_user.c:370 OsmoSGSN: Creating SS7 instance
10
20180723171857797 DLSS7 INFO osmo_ss7.c:363 1: Creating SS7 Instance
11
20180723171857797 DLSS7 INFO osmo_ss7.c:687 1: Creating Route Table system
12
20180723171857797 DLSCCP NOTICE sccp_user.c:397 OsmoSGSN: Using SS7 instance 1, pc:0.23.4
13
20180723171857797 DLSCCP NOTICE sccp_user.c:411 OsmoSGSN: Creating AS instance
14
20180723171857797 DLSS7 INFO osmo_ss7.c:936 1: Creating AS as-clnt-OsmoSGSN
15
20180723171857797 DLSS7 DEBUG fsm.c:299 XUA_AS(as-clnt-OsmoSGSN)[0x612000000820]{AS_DOWN}: Allocated
16
20180723171857797 DLSCCP NOTICE sccp_user.c:421 OsmoSGSN: Using AS instance as-clnt-OsmoSGSN
17
20180723171857797 DLSCCP NOTICE sccp_user.c:426 OsmoSGSN: Creating default route
18
20180723171857797 DLSS7 INFO osmo_ss7.c:811 1: Creating route: pc=0=0.0.0 mask=0x0 via AS 'as-clnt-OsmoSGSN'
19
20180723171857797 DLSCCP NOTICE sccp_user.c:446 OsmoSGSN: Creating ASP instance
20
20180723171857797 DLSS7 INFO osmo_ss7.c:968 1: Adding ASP asp-clnt-OsmoSGSN to AS as-clnt-OsmoSGSN
21
20180723171857797 DLSS7 DEBUG fsm.c:299 xua_default_lm(asp-clnt-OsmoSGSN)[0x612000000b20]{IDLE}: Allocated
22
20180723171857797 DLSS7 INFO osmo_ss7.c:1221 1: Restarting ASP asp-clnt-OsmoSGSN
23
20180723171857797 DLSS7 DEBUG fsm.c:299 XUA_ASP(asp-clnt-OsmoSGSN)[0x612000000e20]{ASP_DOWN}: Allocated
24
20180723171857797 DLSCCP NOTICE sccp_user.c:481 OsmoSGSN: Using ASP instance asp-clnt-OsmoSGSN
25
20180723171857798 DLSCCP NOTICE sccp_user.c:484 OsmoSGSN: Creating SCCP instance
26
20180723171857798 DLSS7 DEBUG osmo_ss7.c:482 registering user=SCCP for SI 3 with priv 0x611000003160
27
20180723171857798 DLSCCP INFO sccp_user.c:88 Binding user 'OsmoSGSN-IuPS' to SSN=142 PC=(no PC)
28
20180723171857798 DLGSUP INFO gsup_client.c:134 GSUP link to 127.0.0.1:4222 DOWN
29
20180723171857798 DLINP DEBUG stream.c:258 connection done.
30
20180723171857798 DLSS7 INFO osmo_ss7.c:1472 asp-asp-clnt-OsmoSGSN: Client connected (r=127.0.0.1:2905<->l=127.0.0.1:35352)
31
20180723171857798 DLSS7 DEBUG xua_default_lm_fsm.c:350 xua_default_lm(asp-clnt-OsmoSGSN)[0x612000000b20]{IDLE}: Received primitive M-SCTP_ESTABLISH.indication
32
20180723171857798 DLSS7 DEBUG xua_default_lm_fsm.c:357 xua_default_lm(asp-clnt-OsmoSGSN)[0x612000000b20]{IDLE}: Received Event SCTP-ESTABLISH.ind
33
20180723171857798 DLSS7 DEBUG xua_default_lm_fsm.c:142 xua_default_lm(asp-clnt-OsmoSGSN)[0x612000000b20]{IDLE}: State change to WAIT_ASP_UP (T0, 20s)
34
20180723171857798 DLSS7 DEBUG xua_default_lm_fsm.c:143 XUA_ASP(asp-clnt-OsmoSGSN)[0x612000000e20]{ASP_DOWN}: Received Event M-ASP_UP.req
35
20180723171857798 DLINP DEBUG stream.c:279 connected write
36
20180723171857798 DLINP DEBUG stream.c:204 sending data
37
20180723171857799 DLINP DEBUG stream.c:275 connected read
38
20180723171857799 DLINP DEBUG stream.c:189 message received
39
20180723171857799 DLSS7 DEBUG osmo_ss7.c:1549 asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 8 (flags=0x80)
40
20180723171857799 DLM3UA DEBUG m3ua.c:722 asp-asp-clnt-OsmoSGSN: Received M3UA Message (ASPSM:UP-ACK)
41
20180723171857799 DLSS7 DEBUG m3ua.c:686 XUA_ASP(asp-clnt-OsmoSGSN)[0x612000000e20]{ASP_DOWN}: Received Event ASPSM-ASP_UP_ACK
42
20180723171857799 DLSS7 DEBUG xua_asp_fsm.c:306 XUA_ASP(asp-clnt-OsmoSGSN)[0x612000000e20]{ASP_DOWN}: T(ack) stopped
43
20180723171857799 DLSS7 DEBUG xua_asp_fsm.c:355 XUA_ASP(asp-clnt-OsmoSGSN)[0x612000000e20]{ASP_DOWN}: State change to ASP_INACTIVE (no timeout)
44
20180723171857799 DLSS7 DEBUG xua_asp_fsm.c:404 XUA_AS(as-clnt-OsmoSGSN)[0x612000000820]{AS_DOWN}: Received Event ASPAS-ASP_INACTIVE.ind
45
20180723171857799 DLSS7 DEBUG xua_as_fsm.c:183 XUA_AS(as-clnt-OsmoSGSN)[0x612000000820]{AS_DOWN}: State change to AS_INACTIVE (no timeout)
46
20180723171857799 DLSS7 DEBUG xua_default_lm_fsm.c:350 xua_default_lm(asp-clnt-OsmoSGSN)[0x612000000b20]{WAIT_ASP_UP}: Received primitive M-ASP_UP.confirm
47
20180723171857799 DLSS7 DEBUG xua_default_lm_fsm.c:357 xua_default_lm(asp-clnt-OsmoSGSN)[0x612000000b20]{WAIT_ASP_UP}: Received Event ASP-UP.conf
48
20180723171857799 DLSS7 DEBUG xua_default_lm_fsm.c:154 xua_default_lm(asp-clnt-OsmoSGSN)[0x612000000b20]{WAIT_ASP_UP}: State change to WAIT_NOTIFY (T1, 2s)
49
20180723171857799 DLINP DEBUG stream.c:279 connected write
50
20180723171857799 DLINP DEBUG stream.c:204 sending data
51
20180723171857799 DLINP DEBUG stream.c:279 connected write
52
20180723171857799 DLINP DEBUG stream.c:204 sending data
53
20180723171857984 DNS INFO gprs_ns.c:266 NSVCI=65535 Creating NS-VC
54
20180723171857984 DNS DEBUG gprs_ns.c:272 NSEI 0 (NS-VCI=65535) setting state [DEAD,UNBLOCKED,UNRESET] -> [DEAD,BLOCKED,UNRESET]
55
20180723171857984 DLGLOBAL DEBUG rate_ctr.c:88 validating counter group 0x7fbbe4168460(ns:nsvc) with 12 counters
56
20180723171857984 DNS INFO gprs_ns.c:1330 Creating NS-VC for BSS at 192.168.0.6:7777
57
20180723171857984 DNS INFO gprs_ns.c:890 NSVCI=65535(invalid) Rx NS RESET (NSEI=101, NSVCI=101, cause=O&M intervention)
58
20180723171857984 DNS DEBUG gprs_ns.c:939 NSEI 0 (NS-VCI=65535) setting state [DEAD,BLOCKED,UNRESET] -> [ALIVE,BLOCKED,UNRESET]
59
20180723171857984 DNS INFO gprs_ns.c:710 NSEI=101 Tx NS RESET ACK (NSVCI=101)
60
20180723171857984 DNS DEBUG gprs_ns.c:610 NSEI=101 Starting timer in mode tns-test (30 seconds)
61
20180723171857985 DNS DEBUG gprs_ns.c:584 NSEI=101 Tx NS ALIVE_ACK (NSVCI=101)
62
20180723171857985 DNS DEBUG gprs_ns.c:610 NSEI=101 Starting timer in mode tns-test (30 seconds)
63
20180723171857985 DNS INFO gprs_ns.c:1414 NSEI=101 Rx NS UNBLOCK
64
20180723171857985 DNS DEBUG gprs_ns.c:1415 NSEI 101 (NS-VCI=101) setting state [ALIVE,BLOCKED,UNRESET] -> [ALIVE,UNBLOCKED,UNRESET]
65
20180723171859799 DLSS7 DEBUG fsm.c:189 xua_default_lm(asp-clnt-OsmoSGSN)[0x612000000b20]{WAIT_NOTIFY}: Timeout of T1
66
20180723171859799 DLSS7 DEBUG xua_default_lm_fsm.c:176 xua_default_lm(asp-clnt-OsmoSGSN)[0x612000000b20]{WAIT_NOTIFY}: State change to RKM_REG (T3, 10s)
67
20180723171859800 DLSS7 DEBUG xua_rkm.c:531 asp-asp-clnt-OsmoSGSN: Received XUA Layer Manager Primitive: M-RK_REG.request)
68
20180723171859800 DLINP DEBUG stream.c:279 connected write
69
20180723171859800 DLINP DEBUG stream.c:204 sending data
70
20180723171859800 DLINP DEBUG stream.c:279 connected write
71
20180723171859800 DLINP DEBUG stream.c:204 sending data
72
20180723171859802 DLINP DEBUG stream.c:275 connected read
73
20180723171859802 DLINP DEBUG stream.c:189 message received
74
20180723171859802 DLSS7 DEBUG osmo_ss7.c:1549 asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 36 (flags=0x80)
75
20180723171859802 DLM3UA DEBUG m3ua.c:722 asp-asp-clnt-OsmoSGSN: Received M3UA Message (RKM:REG-RESP)
76
20180723171859802 DLSS7 INFO xua_rkm.c:409 asp-asp-clnt-OsmoSGSN: Received RKM REG RES rctx=1 status=SUCCESS
77
20180723171859802 DLSS7 DEBUG xua_default_lm_fsm.c:350 xua_default_lm(asp-clnt-OsmoSGSN)[0x612000000b20]{RKM_REG}: Received primitive M-RK_REG.confirm
78
20180723171859802 DLSS7 DEBUG xua_default_lm_fsm.c:357 xua_default_lm(asp-clnt-OsmoSGSN)[0x612000000b20]{RKM_REG}: Received Event RKM_REG.conf
79
20180723171859802 DLSS7 DEBUG xua_default_lm_fsm.c:248 xua_default_lm(asp-clnt-OsmoSGSN)[0x612000000b20]{RKM_REG}: State change to WAIT_NOTIFY (T2, 20s)
80
20180723171859802 DLINP DEBUG stream.c:275 connected read
81
20180723171859803 DLINP DEBUG stream.c:189 message received
82
20180723171859803 DLSS7 DEBUG osmo_ss7.c:1549 asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 24 (flags=0x80)
83
20180723171859803 DLM3UA DEBUG m3ua.c:722 asp-asp-clnt-OsmoSGSN: Received M3UA Message (MGMT:NOTIFY)
84
20180723171859803 DLM3UA NOTICE m3ua.c:634 asp-asp-clnt-OsmoSGSN: Received NOTIFY Type State Change:AS Inactive ()
85
20180723171859803 DLSS7 DEBUG xua_default_lm_fsm.c:350 xua_default_lm(asp-clnt-OsmoSGSN)[0x612000000b20]{WAIT_NOTIFY}: Received primitive M-NOTIFY.indication
86
20180723171859803 DLSS7 DEBUG xua_default_lm_fsm.c:357 xua_default_lm(asp-clnt-OsmoSGSN)[0x612000000b20]{WAIT_NOTIFY}: Received Event NOTIFY.ind
87
20180723171859803 DLSS7 DEBUG xua_default_lm_fsm.c:214 xua_default_lm(asp-clnt-OsmoSGSN)[0x612000000b20]{WAIT_NOTIFY}: State change to ACTIVE (no timeout)
88
20180723171859803 DLSS7 DEBUG xua_default_lm_fsm.c:215 XUA_ASP(asp-clnt-OsmoSGSN)[0x612000000e20]{ASP_INACTIVE}: Received Event M-ASP_ACTIVE.req
89
20180723171859803 DLINP DEBUG stream.c:279 connected write
90
20180723171859803 DLINP DEBUG stream.c:204 sending data
91
20180723171859804 DLINP DEBUG stream.c:279 connected write
92
20180723171859804 DLINP DEBUG stream.c:204 sending data
93
20180723171859805 DLINP DEBUG stream.c:275 connected read
94
20180723171859805 DLINP DEBUG stream.c:189 message received
95
20180723171859805 DLSS7 DEBUG osmo_ss7.c:1549 asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 8 (flags=0x80)
96
20180723171859805 DLM3UA DEBUG m3ua.c:722 asp-asp-clnt-OsmoSGSN: Received M3UA Message (ASPTM:ACTIVE-ACK)
97
20180723171859805 DLSS7 DEBUG m3ua.c:686 XUA_ASP(asp-clnt-OsmoSGSN)[0x612000000e20]{ASP_INACTIVE}: Received Event ASPTM-ASP_AC_ACK
98
20180723171859805 DLSS7 DEBUG xua_asp_fsm.c:306 XUA_ASP(asp-clnt-OsmoSGSN)[0x612000000e20]{ASP_INACTIVE}: T(ack) stopped
99
20180723171859805 DLSS7 DEBUG xua_asp_fsm.c:434 XUA_ASP(asp-clnt-OsmoSGSN)[0x612000000e20]{ASP_INACTIVE}: State change to ASP_ACTIVE (no timeout)
100
20180723171859805 DLSS7 DEBUG xua_asp_fsm.c:404 XUA_AS(as-clnt-OsmoSGSN)[0x612000000820]{AS_INACTIVE}: Received Event ASPAS-ASP_ACTIVE.ind
101
20180723171859806 DLSS7 DEBUG xua_as_fsm.c:241 XUA_AS(as-clnt-OsmoSGSN)[0x612000000820]{AS_INACTIVE}: State change to AS_ACTIVE (no timeout)
102
20180723171859806 DLSS7 DEBUG xua_default_lm_fsm.c:350 xua_default_lm(asp-clnt-OsmoSGSN)[0x612000000b20]{ACTIVE}: Received primitive M-ASP_ACTIVE.confirm
103
20180723171859806 DLSS7 NOTICE xua_default_lm_fsm.c:353 xua_default_lm(asp-clnt-OsmoSGSN)[0x612000000b20]{ACTIVE}: Ignoring primitive M-ASP_ACTIVE.confirm
104
20180723171859806 DLINP DEBUG stream.c:275 connected read
105
20180723171859806 DLINP DEBUG stream.c:189 message received
106
20180723171859806 DLSS7 DEBUG osmo_ss7.c:1549 asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 24 (flags=0x80)
107
20180723171859806 DLM3UA DEBUG m3ua.c:722 asp-asp-clnt-OsmoSGSN: Received M3UA Message (MGMT:NOTIFY)
108
20180723171859806 DLM3UA NOTICE m3ua.c:634 asp-asp-clnt-OsmoSGSN: Received NOTIFY Type State Change:AS Active ()
109
20180723171859806 DLSS7 DEBUG xua_default_lm_fsm.c:350 xua_default_lm(asp-clnt-OsmoSGSN)[0x612000000b20]{ACTIVE}: Received primitive M-NOTIFY.indication
110
20180723171859806 DLSS7 DEBUG xua_default_lm_fsm.c:357 xua_default_lm(asp-clnt-OsmoSGSN)[0x612000000b20]{ACTIVE}: Received Event NOTIFY.ind
111
20180723171859806 DLINP DEBUG stream.c:279 connected write
112
20180723171859807 DLINP DEBUG stream.c:204 sending data
113
20180723171859807 DLINP DEBUG stream.c:279 connected write
114
20180723171859807 DLINP DEBUG stream.c:204 sending data
115
20180723171859960 DLINP DEBUG stream.c:275 connected read
116
20180723171859961 DLINP DEBUG stream.c:189 message received
117
20180723171859961 DLSS7 DEBUG osmo_ss7.c:1549 asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 148 (flags=0x8080)
118
20180723171859961 DLSS7 INFO osmo_ss7.c:1345 asp-asp-clnt-OsmoSGSN: xUA CLNT SCTP NOTIFICATION 32770 flags=0x0
119
20180723171859961 DLSS7 INFO osmo_ss7.c:1358 asp-asp-clnt-OsmoSGSN: xUA CLNT PEER_ADDR_CHANGE
120
20180723171900984 DLINP DEBUG stream.c:275 connected read
121
20180723171900984 DLINP DEBUG stream.c:189 message received
122
20180723171900984 DLSS7 DEBUG osmo_ss7.c:1549 asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 148 (flags=0x8080)
123
20180723171900984 DLSS7 INFO osmo_ss7.c:1345 asp-asp-clnt-OsmoSGSN: xUA CLNT SCTP NOTIFICATION 32770 flags=0x0
124
20180723171900984 DLSS7 INFO osmo_ss7.c:1358 asp-asp-clnt-OsmoSGSN: xUA CLNT PEER_ADDR_CHANGE
125
20180723171901752 DLINP DEBUG stream.c:275 connected read
126
20180723171901752 DLINP DEBUG stream.c:189 message received
127
20180723171901753 DLSS7 DEBUG osmo_ss7.c:1549 asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 148 (flags=0x8080)
128
20180723171901753 DLSS7 INFO osmo_ss7.c:1345 asp-asp-clnt-OsmoSGSN: xUA CLNT SCTP NOTIFICATION 32770 flags=0x0
129
20180723171901753 DLSS7 INFO osmo_ss7.c:1358 asp-asp-clnt-OsmoSGSN: xUA CLNT PEER_ADDR_CHANGE
130
20180723171907798 DLGSUP NOTICE gsup_client.c:76 GSUP connecting to 127.0.0.1:4222
131
20180723171907798 DLINP NOTICE ipa.c:131 127.0.0.1:4222 connection done
132
20180723171907798 DLGSUP INFO gsup_client.c:134 GSUP link to 127.0.0.1:4222 UP
133
20180723171907799 DLGSUP DEBUG gsup_client.c:264 GSUP sending PING
134
20180723171907799 DLINP DEBUG ipa.c:138 127.0.0.1:4222 connected read
135
20180723171907799 DLINP DEBUG ipa.c:56 127.0.0.1:4222 message received
136
20180723171907799 DLINP NOTICE ipaccess.c:705 received ID get from 0/0/0
137
20180723171907799 DLINP INFO ipaccess.c:639  tag 8: 0/0/0
138
20180723171907799 DLINP INFO ipaccess.c:639  tag 7: 00:00:00:00:00:00
139
20180723171907799 DLINP INFO ipaccess.c:639  tag 2: 00:00:00:00:00:00
140
20180723171907799 DLINP INFO ipaccess.c:639  tag 3: 00:00:00:00:00:00
141
20180723171907799 DLINP INFO ipaccess.c:639  tag 4: 00:00:00:00:00:00
142
20180723171907800 DLINP INFO ipaccess.c:639  tag 5: 00:00:00:00:00:00
143
20180723171907800 DLINP INFO ipaccess.c:639  tag 1: SGSN-00-00-00-00-00-00
144
20180723171907800 DLINP INFO ipaccess.c:639  tag 0: SGSN-00-00-00-00-00-00
145
20180723171907800 DLINP DEBUG ipa.c:142 127.0.0.1:4222 connected write
146
20180723171907800 DLINP DEBUG ipa.c:92 127.0.0.1:4222 sending data
147
20180723171907800 DLINP DEBUG ipa.c:142 127.0.0.1:4222 connected write
148
20180723171907800 DLINP DEBUG ipa.c:92 127.0.0.1:4222 sending data
149
20180723171907802 DLINP DEBUG ipa.c:138 127.0.0.1:4222 connected read
150
20180723171907803 DLINP DEBUG ipa.c:56 127.0.0.1:4222 message received
151
20180723171907844 DLINP DEBUG ipa.c:138 127.0.0.1:4222 connected read
152
20180723171907844 DLINP DEBUG ipa.c:56 127.0.0.1:4222 message received
153
20180723171907844 DLGSUP DEBUG gsup_client.c:201 GSUP receiving PONG
154
20180723171927787 DGPRS DEBUG gprs_sgsn.c:940 Checking for inactive LLMEs, time = 1483
155
20180723171927799 DLGSUP INFO gsup_client.c:244 GSUP ping callback (connected, got PONG)
156
20180723171927799 DLGSUP DEBUG gsup_client.c:264 GSUP sending PING
157
20180723171927799 DLINP DEBUG ipa.c:142 127.0.0.1:4222 connected write
158
20180723171927799 DLINP DEBUG ipa.c:92 127.0.0.1:4222 sending data
159
20180723171927799 DLINP DEBUG ipa.c:142 127.0.0.1:4222 connected write
160
20180723171927799 DLINP DEBUG ipa.c:92 127.0.0.1:4222 sending data
161
20180723171927800 DLINP DEBUG ipa.c:138 127.0.0.1:4222 connected read
162
20180723171927800 DLINP DEBUG ipa.c:56 127.0.0.1:4222 message received
163
20180723171927800 DLGSUP DEBUG gsup_client.c:201 GSUP receiving PONG
164
20180723171927985 DNS DEBUG gprs_ns.c:638 NSEI=101 Timer expired in mode tns-test (30 seconds)
165
20180723171927985 DNS DEBUG gprs_ns.c:571 NSEI=101 Tx NS ALIVE (NSVCI=101)
166
20180723171927986 DNS DEBUG gprs_ns.c:610 NSEI=101 Starting timer in mode tns-alive (3 seconds)
167
20180723171927986 DNS DEBUG gprs_ns.c:584 NSEI=101 Tx NS ALIVE_ACK (NSVCI=101)
168
20180723171927986 DNS DEBUG gprs_ns.c:610 NSEI=101 Starting timer in mode tns-test (30 seconds)
169
20180723171947800 DLGSUP INFO gsup_client.c:244 GSUP ping callback (connected, got PONG)
170
20180723171947800 DLGSUP DEBUG gsup_client.c:264 GSUP sending PING
171
20180723171947800 DLINP DEBUG ipa.c:142 127.0.0.1:4222 connected write
172
20180723171947800 DLINP DEBUG ipa.c:92 127.0.0.1:4222 sending data
173
20180723171947800 DLINP DEBUG ipa.c:142 127.0.0.1:4222 connected write
174
20180723171947800 DLINP DEBUG ipa.c:92 127.0.0.1:4222 sending data
175
20180723171947801 DLINP DEBUG ipa.c:138 127.0.0.1:4222 connected read
176
20180723171947801 DLINP DEBUG ipa.c:56 127.0.0.1:4222 message received
177
20180723171947801 DLGSUP DEBUG gsup_client.c:201 GSUP receiving PONG
178
20180723171957788 DGPRS DEBUG gprs_sgsn.c:940 Checking for inactive LLMEs, time = 1513
179
20180723171957987 DNS DEBUG gprs_ns.c:638 NSEI=101 Timer expired in mode tns-test (30 seconds)
180
20180723171957987 DNS DEBUG gprs_ns.c:571 NSEI=101 Tx NS ALIVE (NSVCI=101)
181
20180723171957987 DNS DEBUG gprs_ns.c:610 NSEI=101 Starting timer in mode tns-alive (3 seconds)
182
20180723171957988 DNS DEBUG gprs_ns.c:584 NSEI=101 Tx NS ALIVE_ACK (NSVCI=101)
183
20180723171957988 DNS DEBUG gprs_ns.c:610 NSEI=101 Starting timer in mode tns-test (30 seconds)
184
20180723172007801 DLGSUP INFO gsup_client.c:244 GSUP ping callback (connected, got PONG)
185
20180723172007801 DLGSUP DEBUG gsup_client.c:264 GSUP sending PING
186
20180723172007801 DLINP DEBUG ipa.c:142 127.0.0.1:4222 connected write
187
20180723172007801 DLINP DEBUG ipa.c:92 127.0.0.1:4222 sending data
188
20180723172007802 DLINP DEBUG ipa.c:142 127.0.0.1:4222 connected write
189
20180723172007802 DLINP DEBUG ipa.c:92 127.0.0.1:4222 sending data
190
20180723172007802 DLINP DEBUG ipa.c:138 127.0.0.1:4222 connected read
191
20180723172007802 DLINP DEBUG ipa.c:56 127.0.0.1:4222 message received
192
20180723172007802 DLGSUP DEBUG gsup_client.c:201 GSUP receiving PONG
193
20180723172027789 DGPRS DEBUG gprs_sgsn.c:940 Checking for inactive LLMEs, time = 1543
194
20180723172027801 DLGSUP INFO gsup_client.c:244 GSUP ping callback (connected, got PONG)
195
20180723172027801 DLGSUP DEBUG gsup_client.c:264 GSUP sending PING
196
20180723172027801 DLINP DEBUG ipa.c:142 127.0.0.1:4222 connected write
197
20180723172027802 DLINP DEBUG ipa.c:92 127.0.0.1:4222 sending data
198
20180723172027802 DLINP DEBUG ipa.c:142 127.0.0.1:4222 connected write
199
20180723172027802 DLINP DEBUG ipa.c:92 127.0.0.1:4222 sending data
200
20180723172027802 DLINP DEBUG ipa.c:138 127.0.0.1:4222 connected read
201
20180723172027802 DLINP DEBUG ipa.c:56 127.0.0.1:4222 message received
202
20180723172027803 DLGSUP DEBUG gsup_client.c:201 GSUP receiving PONG
203
20180723172027988 DNS DEBUG gprs_ns.c:638 NSEI=101 Timer expired in mode tns-test (30 seconds)
204
20180723172027989 DNS DEBUG gprs_ns.c:571 NSEI=101 Tx NS ALIVE (NSVCI=101)
205
20180723172027989 DNS DEBUG gprs_ns.c:610 NSEI=101 Starting timer in mode tns-alive (3 seconds)
206
20180723172027989 DNS DEBUG gprs_ns.c:584 NSEI=101 Tx NS ALIVE_ACK (NSVCI=101)
207
20180723172027989 DNS DEBUG gprs_ns.c:610 NSEI=101 Starting timer in mode tns-test (30 seconds)
208
20180723172047802 DLGSUP INFO gsup_client.c:244 GSUP ping callback (connected, got PONG)
209
20180723172047803 DLGSUP DEBUG gsup_client.c:264 GSUP sending PING
210
20180723172047803 DLINP DEBUG ipa.c:142 127.0.0.1:4222 connected write
211
20180723172047803 DLINP DEBUG ipa.c:92 127.0.0.1:4222 sending data
212
20180723172047803 DLINP DEBUG ipa.c:142 127.0.0.1:4222 connected write
213
20180723172047803 DLINP DEBUG ipa.c:92 127.0.0.1:4222 sending data
214
20180723172047804 DLINP DEBUG ipa.c:138 127.0.0.1:4222 connected read
215
20180723172047804 DLINP DEBUG ipa.c:56 127.0.0.1:4222 message received
216
20180723172047804 DLGSUP DEBUG gsup_client.c:201 GSUP receiving PONG
217
20180723172057790 DGPRS DEBUG gprs_sgsn.c:940 Checking for inactive LLMEs, time = 1573
218
20180723172057990 DNS DEBUG gprs_ns.c:638 NSEI=101 Timer expired in mode tns-test (30 seconds)
219
20180723172057990 DNS DEBUG gprs_ns.c:571 NSEI=101 Tx NS ALIVE (NSVCI=101)
220
20180723172057990 DNS DEBUG gprs_ns.c:610 NSEI=101 Starting timer in mode tns-alive (3 seconds)
221
20180723172057991 DNS DEBUG gprs_ns.c:584 NSEI=101 Tx NS ALIVE_ACK (NSVCI=101)
222
20180723172057991 DNS DEBUG gprs_ns.c:610 NSEI=101 Starting timer in mode tns-test (30 seconds)
223
20180723172107804 DLGSUP INFO gsup_client.c:244 GSUP ping callback (connected, got PONG)
224
20180723172107804 DLGSUP DEBUG gsup_client.c:264 GSUP sending PING
225
20180723172107804 DLINP DEBUG ipa.c:142 127.0.0.1:4222 connected write
226
20180723172107804 DLINP DEBUG ipa.c:92 127.0.0.1:4222 sending data
227
20180723172107804 DLINP DEBUG ipa.c:142 127.0.0.1:4222 connected write
228
20180723172107804 DLINP DEBUG ipa.c:92 127.0.0.1:4222 sending data
229
20180723172107805 DLINP DEBUG ipa.c:138 127.0.0.1:4222 connected read
230
20180723172107805 DLINP DEBUG ipa.c:56 127.0.0.1:4222 message received
231
20180723172107805 DLGSUP DEBUG gsup_client.c:201 GSUP receiving PONG
232
20180723172127488 DLINP DEBUG stream.c:275 connected read
233
20180723172127488 DLINP DEBUG stream.c:189 message received
234
20180723172127489 DLSS7 DEBUG osmo_ss7.c:1549 asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 156 (flags=0x80)
235
20180723172127489 DLM3UA DEBUG m3ua.c:722 asp-asp-clnt-OsmoSGSN: Received M3UA Message (XFER:DATA)
236
20180723172127489 DLM3UA DEBUG m3ua.c:541 asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer
237
20180723172127489 DLM3UA DEBUG m3ua.c:580 asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=188=0.23.4
238
20180723172127489 DLSS7 DEBUG osmo_ss7_hmrt.c:274 m3ua_hmdc_rx_from_l2(): found dpc=188=0.23.4 as local
239
20180723172127489 DLSUA DEBUG sccp2sua.c:333 IEI 259: Parsed Addr: RI=2,PC=188,SSN=142
240
20180723172127489 DLSUA DEBUG sccp2sua.c:333 IEI 258: Parsed Addr: RI=2,PC=189,SSN=142
241
20180723172127489 DLSS7 DEBUG sccp_scrc.c:449 scrc_rx_mtp_xfer_ind_xua:  HDR=(CO:CORE,V=0,LEN=0),
242
	PART(T=Protocol Class,L=4,D=00000002),
243
	PART(T=Source Reference,L=4,D=000003e9),
244
	PART(T=Destination Address,L=20,D=0002000380020008000000bc800300080000008e),
245
	PART(T=Source Address,L=20,D=0002000380020008000000bd800300080000008e),
246
	PART(T=Data,L=100,D=001340600000070003400180000f40060009f1073815003740010b003a40080009f107000100010010402928080103e5e000710a0008991007000010742009f107fffeff0d0b53432b37159ef989007000081705004f40030000170056400509f1070017)
247
20180723172127489 DLSUA DEBUG sua.c:385 sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 bc 80 03 00 08 00 00 00 8e 
248
20180723172127489 DLSUA DEBUG sua.c:439 SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8
249
20180723172127490 DLSUA DEBUG sua.c:439 SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8
250
20180723172127490 DLSUA DEBUG sua.c:385 sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 bc 80 03 00 08 00 00 00 8e 
251
20180723172127490 DLSUA DEBUG sua.c:439 SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8
252
20180723172127490 DLSUA DEBUG sua.c:439 SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8
253
20180723172127490 DLSCCP DEBUG fsm.c:299 SCCP-SCOC(0)[0x612000001120]{IDLE}: Allocated
254
20180723172127490 DLSCCP DEBUG sccp_scoc.c:1548 Received CO:CORE for local reference 0
255
20180723172127490 DLSCCP DEBUG sccp_scoc.c:1581 SCCP-SCOC(0)[0x612000001120]{IDLE}: Received Event RCOC-CONNECT.ind
256
20180723172127490 DLSUA DEBUG sua.c:385 sua_addr_parse_part(IEI=0x0102) (20) 00 02 00 03 80 02 00 08 00 00 00 bd 80 03 00 08 00 00 00 8e 
257
20180723172127490 DLSUA DEBUG sua.c:439 SUA IEI 0x0102 pos 4/20: subpart tag 0x8002, len 8
258
20180723172127490 DLSUA DEBUG sua.c:439 SUA IEI 0x0102 pos 12/20: subpart tag 0x8003, len 8
259
20180723172127491 DLSUA DEBUG sua.c:385 sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 bc 80 03 00 08 00 00 00 8e 
260
20180723172127491 DLSUA DEBUG sua.c:439 SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8
261
20180723172127491 DLSUA DEBUG sua.c:439 SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8
262
20180723172127491 DLSCCP DEBUG sccp_scoc.c:775 SCCP-SCOC(0)[0x612000001120]{IDLE}: State change to CONN_PEND_IN (no timeout)
263
20180723172127491 DLSCCP DEBUG sccp_user.c:156 Delivering N-CONNECT.indication to SCCP User 'OsmoSGSN-IuPS'
264
20180723172127491 DRANAP DEBUG iu_client.c:755 sccp_sap_up(N-CONNECT.indication)
265
20180723172127491 DRANAP DEBUG iu_client.c:764 N-CONNECT.ind(X->0)
266
20180723172127491 DLSCCP DEBUG sccp_scoc.c:1615 Received SCCP User Primitive N-CONNECT.response)
267
20180723172127491 DLSCCP DEBUG sccp_scoc.c:1657 SCCP-SCOC(0)[0x612000001120]{CONN_PEND_IN}: Received Event N-CONNECT.resp
268
20180723172127491 DLSS7 DEBUG sccp_scrc.c:398 sccp_scrc_rx_scoc_conn_msg:  HDR=(CO:COAK,V=0,LEN=0),
269
	PART(T=Routing Context,L=4,D=00000000),
270
	PART(T=Protocol Class,L=4,D=00000002),
271
	PART(T=Destination Reference,L=4,D=000003e9),
272
	PART(T=Source Reference,L=4,D=00000000),
273
	PART(T=Sequence Control,L=4,D=00000000),
274
	PART(T=Source Address,L=20,D=0002000380020008000000bc800300080000008e),
275
	PART(T=Destination Address,L=20,D=0002000380020008000000bd800300080000008e)
276
20180723172127491 DLSUA DEBUG sua.c:385 sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 bd 80 03 00 08 00 00 00 8e 
277
20180723172127492 DLSUA DEBUG sua.c:439 SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8
278
20180723172127492 DLSUA DEBUG sua.c:439 SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8
279
20180723172127492 DLSUA DEBUG sua.c:385 sua_addr_parse_part(IEI=0x0102) (20) 00 02 00 03 80 02 00 08 00 00 00 bc 80 03 00 08 00 00 00 8e 
280
20180723172127492 DLSUA DEBUG sua.c:439 SUA IEI 0x0102 pos 4/20: subpart tag 0x8002, len 8
281
20180723172127492 DLSUA DEBUG sua.c:439 SUA IEI 0x0102 pos 12/20: subpart tag 0x8003, len 8
282
20180723172127492 DLSS7 DEBUG osmo_ss7_hmrt.c:278 m3ua_hmdc_rx_from_l2(): dpc=189=0.23.5 not local, message is for routing
283
20180723172127492 DLSS7 DEBUG osmo_ss7_hmrt.c:227 Found route for dpc=189=0.23.5: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoSGSN proto=m3ua
284
20180723172127492 DLSS7 DEBUG osmo_ss7_hmrt.c:233 rt->dest.as proto is M3UA for dpc=189=0.23.5
285
20180723172127492 DLSS7 DEBUG m3ua.c:507 XUA_AS(as-clnt-OsmoSGSN)[0x612000000820]{AS_ACTIVE}: Received Event AS-TRANSFER.req
286
20180723172127492 DLSCCP DEBUG sccp_scoc.c:802 SCCP-SCOC(0)[0x612000001120]{CONN_PEND_IN}: State change to ACTIVE (no timeout)
287
20180723172127493 DRANAP NOTICE iu_client.c:507 handle_co_initial(dir=1, proc=19)
288
20180723172127493 DRANAP NOTICE iu_client.c:145 New RNC 23 at RI=2,PC=189,SSN=142
289
20180723172127493 DRANAP NOTICE iu_client.c:228 RNC 23: new LAC 14357 RAC 11
290
20180723172127493 DMM INFO gprs_gmm.c:1272 MM(---/ffffffff) -> GMM ATTACH REQUEST MI(901700000014702) type="GPRS attach" 
291
20180723172127493 DLGLOBAL DEBUG rate_ctr.c:88 validating counter group 0x5632aea921e0(sgsn:mmctx) with 11 counters
292
20180723172127493 DMM DEBUG gprs_gmm.c:556 MM(901700000014702/c43a74bc) <- GPRS IDENTITY REQUEST: mi_type=IMEI
293
20180723172127493 DRANAP INFO iu_client.c:431 Transmitting L3 Message as RANAP DT (SCCP conn_id 0)
294
20180723172127493 DLSCCP DEBUG sccp_scoc.c:1615 Received SCCP User Primitive N-DATA.request)
295
20180723172127493 DLSCCP DEBUG sccp_scoc.c:1657 SCCP-SCOC(0)[0x612000001120]{ACTIVE}: Received Event N-DATA.req
296
20180723172127493 DLSS7 DEBUG sccp_scrc.c:398 sccp_scrc_rx_scoc_conn_msg:  HDR=(CO:CODT,V=0,LEN=0),
297
	PART(T=Routing Context,L=4,D=00000000),
298
	PART(T=Destination Reference,L=4,D=000003e9),
299
	PART(T=Data,L=20,D=001400100000020010400403081502003b400100)
300
20180723172127494 DLSS7 DEBUG osmo_ss7_hmrt.c:278 m3ua_hmdc_rx_from_l2(): dpc=189=0.23.5 not local, message is for routing
301
20180723172127494 DLSS7 DEBUG osmo_ss7_hmrt.c:227 Found route for dpc=189=0.23.5: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoSGSN proto=m3ua
302
20180723172127494 DLSS7 DEBUG osmo_ss7_hmrt.c:233 rt->dest.as proto is M3UA for dpc=189=0.23.5
303
20180723172127494 DLSS7 DEBUG m3ua.c:507 XUA_AS(as-clnt-OsmoSGSN)[0x612000000820]{AS_ACTIVE}: Received Event AS-TRANSFER.req
304
20180723172127494 DLINP DEBUG stream.c:279 connected write
305
20180723172127494 DLINP DEBUG stream.c:204 sending data
306
20180723172127494 DLINP DEBUG stream.c:279 connected write
307
20180723172127494 DLINP DEBUG stream.c:204 sending data
308
20180723172127494 DLINP DEBUG stream.c:279 connected write
309
20180723172127494 DLINP DEBUG stream.c:204 sending data
310
20180723172127791 DGPRS DEBUG gprs_sgsn.c:940 Checking for inactive LLMEs, time = 1603
311
20180723172127804 DLGSUP INFO gsup_client.c:244 GSUP ping callback (connected, got PONG)
312
20180723172127804 DLGSUP DEBUG gsup_client.c:264 GSUP sending PING
313
20180723172127804 DLINP DEBUG ipa.c:142 127.0.0.1:4222 connected write
314
20180723172127804 DLINP DEBUG ipa.c:92 127.0.0.1:4222 sending data
315
20180723172127804 DLINP DEBUG ipa.c:142 127.0.0.1:4222 connected write
316
20180723172127805 DLINP DEBUG ipa.c:92 127.0.0.1:4222 sending data
317
20180723172127805 DLINP DEBUG ipa.c:138 127.0.0.1:4222 connected read
318
20180723172127805 DLINP DEBUG ipa.c:56 127.0.0.1:4222 message received
319
20180723172127805 DLGSUP DEBUG gsup_client.c:201 GSUP receiving PONG
320
20180723172127991 DNS DEBUG gprs_ns.c:638 NSEI=101 Timer expired in mode tns-test (30 seconds)
321
20180723172127991 DNS DEBUG gprs_ns.c:571 NSEI=101 Tx NS ALIVE (NSVCI=101)
322
20180723172127991 DNS DEBUG gprs_ns.c:610 NSEI=101 Starting timer in mode tns-alive (3 seconds)
323
20180723172127992 DNS DEBUG gprs_ns.c:584 NSEI=101 Tx NS ALIVE_ACK (NSVCI=101)
324
20180723172127992 DNS DEBUG gprs_ns.c:610 NSEI=101 Starting timer in mode tns-test (30 seconds)
325
20180723172128089 DLINP DEBUG stream.c:275 connected read
326
20180723172128089 DLINP DEBUG stream.c:189 message received
327
20180723172128089 DLSS7 DEBUG osmo_ss7.c:1549 asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 92 (flags=0x80)
328
20180723172128089 DLM3UA DEBUG m3ua.c:722 asp-asp-clnt-OsmoSGSN: Received M3UA Message (XFER:DATA)
329
20180723172128089 DLM3UA DEBUG m3ua.c:541 asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer
330
20180723172128090 DLM3UA DEBUG m3ua.c:580 asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=188=0.23.4
331
20180723172128090 DLSS7 DEBUG osmo_ss7_hmrt.c:274 m3ua_hmdc_rx_from_l2(): found dpc=188=0.23.4 as local
332
20180723172128090 DLSS7 DEBUG sccp_scrc.c:449 scrc_rx_mtp_xfer_ind_xua:  HDR=(CO:CODT,V=0,LEN=0),
333
	PART(T=Destination Reference,L=4,D=00000000),
334
	PART(T=Segmentation,L=4,D=00000000),
335
	PART(T=Data,L=50,D=0014402e0000040010400c0b0816083a75370795767201000f40060009f1073815003740010b003a40080009f10700010001)
336
20180723172128090 DLSCCP DEBUG sccp_scoc.c:1548 Received CO:CODT for local reference 0
337
20180723172128090 DLSCCP DEBUG sccp_scoc.c:1581 SCCP-SCOC(0)[0x612000001120]{ACTIVE}: Received Event RCOC-DT1.ind
338
20180723172128090 DLSCCP DEBUG sccp_user.c:156 Delivering N-DATA.indication to SCCP User 'OsmoSGSN-IuPS'
339
20180723172128090 DRANAP DEBUG iu_client.c:755 sccp_sap_up(N-DATA.indication)
340
20180723172128090 DRANAP DEBUG iu_client.c:789 N-DATA.ind(0, 00 14 40 2e 00 00 04 00 10 40 0c 0b 08 16 08 3a 75 37 07 95 76 72 01 00 0f 40 06 00 09 f1 07 38 15 00 37 40 01 0b 00 3a 40 08 00 09 f1 07 00 01 00 01 )
341
20180723172128090 DRANAP NOTICE iu_client.c:530 handle_co(dir=1, proc=20)
342
20180723172128091 DMM DEBUG gprs_gmm.c:1195 MM(901700000014702/c43a74bc) -> GMM IDENTITY RESPONSE: MI(IMEI)=357737059672710
343
20180723172128091 DMM DEBUG sgsn_auth.c:161 MM(901700000014702/c43a74bc) Requesting authorization
344
20180723172128091 DMM INFO sgsn_auth.c:186 MM(901700000014702/c43a74bc) Requesting authentication tuples
345
20180723172128091 DMM DEBUG gprs_subscriber.c:899 MM(901700000014702/c43a74bc) Requesting subscriber authentication info
346
20180723172128091 DGPRS INFO gprs_subscriber.c:800 SUBSCR(901700000014702) requesting auth info
347
20180723172128091 DGPRS INFO gprs_subscriber.c:206 SUBSCR(901700000014702) Sending GSUP, will send: 08 01 08 09 71 00 00 00 41 07 f2 28 01 01 
348
20180723172128091 DLINP DEBUG ipa.c:142 127.0.0.1:4222 connected write
349
20180723172128091 DLINP DEBUG ipa.c:92 127.0.0.1:4222 sending data
350
20180723172128092 DLINP DEBUG ipa.c:142 127.0.0.1:4222 connected write
351
20180723172128092 DLINP DEBUG ipa.c:92 127.0.0.1:4222 sending data
352
20180723172128100 DLINP DEBUG ipa.c:138 127.0.0.1:4222 connected read
353
20180723172128100 DLINP DEBUG ipa.c:56 127.0.0.1:4222 message received
354
20180723172128100 DGPRS INFO gprs_subscriber.c:719 SUBSCR(901700000014702) Received GSUP message OSMO_GSUP_MSGT_SEND_AUTH_INFO_RESULT
355
20180723172128100 DGPRS INFO gprs_subscriber.c:239 SUBSCR(901700000014702) Got SendAuthenticationInfoResult, num_auth_vectors = 5
356
20180723172128100 DGPRS DEBUG gprs_subscriber.c:251 SUBSCR(901700000014702) Adding auth tuple, cksn = 0
357
20180723172128100 DGPRS DEBUG gprs_subscriber.c:251 SUBSCR(901700000014702) Adding auth tuple, cksn = 1
358
20180723172128101 DGPRS DEBUG gprs_subscriber.c:251 SUBSCR(901700000014702) Adding auth tuple, cksn = 2
359
20180723172128101 DGPRS DEBUG gprs_subscriber.c:251 SUBSCR(901700000014702) Adding auth tuple, cksn = 3
360
20180723172128101 DGPRS DEBUG gprs_subscriber.c:251 SUBSCR(901700000014702) Adding auth tuple, cksn = 4
361
20180723172128101 DGPRS DEBUG gprs_subscriber.c:833 SUBSCR(901700000014702) Updating subscriber authentication info
362
20180723172128101 DMM INFO gprs_sgsn.c:754 MM(901700000014702/c43a74bc) Subscriber data update
363
20180723172128101 DMM DEBUG sgsn_auth.c:220 MM(901700000014702/c43a74bc) Updating authorization (unknown -> authenticate)
364
20180723172128101 DMM INFO sgsn_auth.c:249 MM(901700000014702/c43a74bc) Got authorization update: state unknown -> authenticate
365
20180723172128101 DMM INFO gprs_gmm.c:593 MM(901700000014702/c43a74bc) <- GPRS AUTH AND CIPHERING REQ (rand = f1 8e 3e 00 06 f6 db 3c 35 3e e1 ab 3a 2c 61 3f , mmctx_is_r99=1, vec->auth_types=0x3, autn = c1 af 21 71 7c 78 00 00 93 b2 b0 71 24 a6 35 39 )
366
20180723172128101 DRANAP INFO iu_client.c:431 Transmitting L3 Message as RANAP DT (SCCP conn_id 0)
367
20180723172128102 DLSCCP DEBUG sccp_scoc.c:1615 Received SCCP User Primitive N-DATA.request)
368
20180723172128102 DLSCCP DEBUG sccp_scoc.c:1657 SCCP-SCOC(0)[0x612000001120]{ACTIVE}: Received Event N-DATA.req
369
20180723172128102 DLSS7 DEBUG sccp_scrc.c:398 sccp_scrc_rx_scoc_conn_msg:  HDR=(CO:CODT,V=0,LEN=0),
370
	PART(T=Routing Context,L=4,D=00000000),
371
	PART(T=Destination Reference,L=4,D=000003e9),
372
	PART(T=Data,L=57,D=0014003500000200104029280812107021f18e3e0006f6db3c353ee1ab3a2c613f802810c1af21717c78000093b2b07124a63539003b400100)
373
20180723172128102 DLSS7 DEBUG osmo_ss7_hmrt.c:278 m3ua_hmdc_rx_from_l2(): dpc=189=0.23.5 not local, message is for routing
374
20180723172128102 DLSS7 DEBUG osmo_ss7_hmrt.c:227 Found route for dpc=189=0.23.5: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoSGSN proto=m3ua
375
20180723172128102 DLSS7 DEBUG osmo_ss7_hmrt.c:233 rt->dest.as proto is M3UA for dpc=189=0.23.5
376
20180723172128103 DLSS7 DEBUG m3ua.c:507 XUA_AS(as-clnt-OsmoSGSN)[0x612000000820]{AS_ACTIVE}: Received Event AS-TRANSFER.req
377
20180723172128103 DLINP DEBUG stream.c:279 connected write
378
20180723172128103 DLINP DEBUG stream.c:204 sending data
379
20180723172128103 DLINP DEBUG stream.c:279 connected write
380
20180723172128103 DLINP DEBUG stream.c:204 sending data
381
20180723172129609 DLINP DEBUG stream.c:275 connected read
382
20180723172129610 DLINP DEBUG stream.c:189 message received
383
20180723172129610 DLSS7 DEBUG osmo_ss7.c:1549 asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 104 (flags=0x80)
384
20180723172129610 DLM3UA DEBUG m3ua.c:722 asp-asp-clnt-OsmoSGSN: Received M3UA Message (XFER:DATA)
385
20180723172129610 DLM3UA DEBUG m3ua.c:541 asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer
386
20180723172129610 DLM3UA DEBUG m3ua.c:580 asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=188=0.23.4
387
20180723172129610 DLSS7 DEBUG osmo_ss7_hmrt.c:274 m3ua_hmdc_rx_from_l2(): found dpc=188=0.23.4 as local
388
20180723172129610 DLSS7 DEBUG sccp_scrc.c:449 scrc_rx_mtp_xfer_ind_xua:  HDR=(CO:CODT,V=0,LEN=0),
389
	PART(T=Destination Reference,L=4,D=00000000),
390
	PART(T=Segmentation,L=4,D=00000000),
391
	PART(T=Data,L=64,D=0014403c0000040010401a19081307229464793423093375370795767271f52904ac0e9aa0000f40060009f1073815003740010b003a40080009f10700010001)
392
20180723172129610 DLSCCP DEBUG sccp_scoc.c:1548 Received CO:CODT for local reference 0
393
20180723172129611 DLSCCP DEBUG sccp_scoc.c:1581 SCCP-SCOC(0)[0x612000001120]{ACTIVE}: Received Event RCOC-DT1.ind
394
20180723172129611 DLSCCP DEBUG sccp_user.c:156 Delivering N-DATA.indication to SCCP User 'OsmoSGSN-IuPS'
395
20180723172129611 DRANAP DEBUG iu_client.c:755 sccp_sap_up(N-DATA.indication)
396
20180723172129611 DRANAP DEBUG iu_client.c:789 N-DATA.ind(0, 00 14 40 3c 00 00 04 00 10 40 1a 19 08 13 07 22 94 64 79 34 23 09 33 75 37 07 95 76 72 71 f5 29 04 ac 0e 9a a0 00 0f 40 06 00 09 f1 07 38 15 00 37 40 01 0b 00 3a 40 08 00 09 f1 07 00 01 00 01 )
397
20180723172129611 DRANAP NOTICE iu_client.c:530 handle_co(dir=1, proc=20)
398
20180723172129611 DMM INFO gprs_gmm.c:733 MM(901700000014702/c43a74bc) -> GPRS AUTH AND CIPH RESPONSE
399
20180723172129611 DMM DEBUG gprs_gmm.c:780 MM(901700000014702/c43a74bc) checking auth: received UMTS RES = 94 64 79 34 ac 0e 9a a0 
400
20180723172129612 DMM DEBUG sgsn_auth.c:161 MM(901700000014702/c43a74bc) Requesting authorization
401
20180723172129612 DMM INFO sgsn_auth.c:197 MM(901700000014702/c43a74bc) Missing information, requesting subscriber data
402
20180723172129612 DMM DEBUG gprs_subscriber.c:874 MM(901700000014702/c43a74bc) Requesting subscriber data update
403
20180723172129612 DGPRS INFO gprs_subscriber.c:813 SUBSCR(901700000014702) subscriber data is not available
404
20180723172129612 DGPRS INFO gprs_subscriber.c:206 SUBSCR(901700000014702) Sending GSUP, will send: 04 01 08 09 71 00 00 00 41 07 f2 28 01 01 
405
20180723172129612 DLINP DEBUG ipa.c:142 127.0.0.1:4222 connected write
406
20180723172129612 DLINP DEBUG ipa.c:92 127.0.0.1:4222 sending data
407
20180723172129612 DLINP DEBUG ipa.c:142 127.0.0.1:4222 connected write
408
20180723172129613 DLINP DEBUG ipa.c:92 127.0.0.1:4222 sending data
409
20180723172129614 DLINP DEBUG ipa.c:138 127.0.0.1:4222 connected read
410
20180723172129614 DLINP DEBUG ipa.c:56 127.0.0.1:4222 message received
411
20180723172129614 DGPRS INFO gprs_subscriber.c:719 SUBSCR(901700000014702) Received GSUP message OSMO_GSUP_MSGT_INSERT_DATA_REQUEST
412
20180723172129614 DGPRS INFO gprs_subscriber.c:364 SUBSCR(901700000014702) Will set PDP info, context id = 1, APN = 01 2a 
413
20180723172129614 DGPRS DEBUG gprs_subscriber.c:821 SUBSCR(901700000014702) Updating subscriber data
414
20180723172129614 DMM INFO gprs_sgsn.c:754 MM(901700000014702/c43a74bc) Subscriber data update
415
20180723172129614 DMM DEBUG sgsn_auth.c:220 MM(901700000014702/c43a74bc) Updating authorization (authenticate -> accepted)
416
20180723172129614 DMM INFO sgsn_auth.c:249 MM(901700000014702/c43a74bc) Got authorization update: state authenticate -> accepted
417
20180723172129615 DMM NOTICE gprs_gmm.c:1119 MM(901700000014702/c43a74bc) Authorized, continuing procedure, IMSI=901700000014702
418
20180723172129615 DLSCCP DEBUG sccp_scoc.c:1615 Received SCCP User Primitive N-DATA.request)
419
20180723172129615 DLSCCP DEBUG sccp_scoc.c:1657 SCCP-SCOC(0)[0x612000001120]{ACTIVE}: Received Event N-DATA.req
420
20180723172129615 DLSS7 DEBUG sccp_scrc.c:398 sccp_scrc_rx_scoc_conn_msg:  HDR=(CO:CODT,V=0,LEN=0),
421
	PART(T=Routing Context,L=4,D=00000000),
422
	PART(T=Destination Reference,L=4,D=000003e9),
423
	PART(T=Data,L=34,D=0006001e000002000c00120808b79c8449282db0bad784861b1b821a28004b000140)
424
20180723172129615 DLSS7 DEBUG osmo_ss7_hmrt.c:278 m3ua_hmdc_rx_from_l2(): dpc=189=0.23.5 not local, message is for routing
425
20180723172129615 DLSS7 DEBUG osmo_ss7_hmrt.c:227 Found route for dpc=189=0.23.5: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoSGSN proto=m3ua
426
20180723172129615 DLSS7 DEBUG osmo_ss7_hmrt.c:233 rt->dest.as proto is M3UA for dpc=189=0.23.5
427
20180723172129615 DLSS7 DEBUG m3ua.c:507 XUA_AS(as-clnt-OsmoSGSN)[0x612000000820]{AS_ACTIVE}: Received Event AS-TRANSFER.req
428
20180723172129615 DGPRS INFO gprs_subscriber.c:206 SUBSCR(901700000014702) Sending GSUP, will send: 12 01 08 09 71 00 00 00 41 07 f2 28 01 01 
429
20180723172129616 DLINP DEBUG stream.c:279 connected write
430
20180723172129616 DLINP DEBUG stream.c:204 sending data
431
20180723172129616 DLINP DEBUG ipa.c:142 127.0.0.1:4222 connected write
432
20180723172129616 DLINP DEBUG ipa.c:92 127.0.0.1:4222 sending data
433
20180723172129616 DLINP DEBUG stream.c:279 connected write
434
20180723172129616 DLINP DEBUG stream.c:204 sending data
435
20180723172129616 DLINP DEBUG ipa.c:142 127.0.0.1:4222 connected write
436
20180723172129616 DLINP DEBUG ipa.c:92 127.0.0.1:4222 sending data
437
20180723172129617 DLINP DEBUG ipa.c:138 127.0.0.1:4222 connected read
438
20180723172129620 DLINP DEBUG ipa.c:56 127.0.0.1:4222 message received
439
20180723172129620 DGPRS INFO gprs_subscriber.c:719 SUBSCR(901700000014702) Received GSUP message OSMO_GSUP_MSGT_UPDATE_LOCATION_RESULT
440
20180723172129620 DGPRS DEBUG gprs_subscriber.c:821 SUBSCR(901700000014702) Updating subscriber data
441
20180723172129620 DMM INFO gprs_sgsn.c:754 MM(901700000014702/c43a74bc) Subscriber data update
442
20180723172129620 DMM DEBUG sgsn_auth.c:220 MM(901700000014702/c43a74bc) Updating authorization (accepted -> accepted)
443
20180723172130000 DLINP DEBUG stream.c:275 connected read
444
20180723172130000 DLINP DEBUG stream.c:189 message received
445
20180723172130001 DLSS7 DEBUG osmo_ss7.c:1549 asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 52 (flags=0x80)
446
20180723172130001 DLM3UA DEBUG m3ua.c:722 asp-asp-clnt-OsmoSGSN: Received M3UA Message (XFER:DATA)
447
20180723172130001 DLM3UA DEBUG m3ua.c:541 asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer
448
20180723172130001 DLM3UA DEBUG m3ua.c:580 asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=188=0.23.4
449
20180723172130001 DLSS7 DEBUG osmo_ss7_hmrt.c:274 m3ua_hmdc_rx_from_l2(): found dpc=188=0.23.4 as local
450
20180723172130001 DLSS7 DEBUG sccp_scrc.c:449 scrc_rx_mtp_xfer_ind_xua:  HDR=(CO:CODT,V=0,LEN=0),
451
	PART(T=Destination Reference,L=4,D=00000000),
452
	PART(T=Segmentation,L=4,D=00000000),
453
	PART(T=Data,L=12,D=200600080000010006000100)
454
20180723172130001 DLSCCP DEBUG sccp_scoc.c:1548 Received CO:CODT for local reference 0
455
20180723172130001 DLSCCP DEBUG sccp_scoc.c:1581 SCCP-SCOC(0)[0x612000001120]{ACTIVE}: Received Event RCOC-DT1.ind
456
20180723172130002 DLSCCP DEBUG sccp_user.c:156 Delivering N-DATA.indication to SCCP User 'OsmoSGSN-IuPS'
457
20180723172130002 DRANAP DEBUG iu_client.c:755 sccp_sap_up(N-DATA.indication)
458
20180723172130002 DRANAP DEBUG iu_client.c:789 N-DATA.ind(0, 20 06 00 08 00 00 01 00 06 00 01 00 )
459
20180723172130002 DRANAP NOTICE iu_client.c:530 handle_co(dir=2, proc=6)
460
20180723172130002 DMM INFO gprs_gmm.c:437 MM(901700000014702/c43a74bc) <- GPRS ATTACH ACCEPT (new P-TMSI=0xc43a74bc)
461
20180723172130002 DRANAP INFO iu_client.c:431 Transmitting L3 Message as RANAP DT (SCCP conn_id 0)
462
20180723172130002 DLSCCP DEBUG sccp_scoc.c:1615 Received SCCP User Primitive N-DATA.request)
463
20180723172130002 DLSCCP DEBUG sccp_scoc.c:1657 SCCP-SCOC(0)[0x612000001120]{ACTIVE}: Received Event N-DATA.req
464
20180723172130003 DLSS7 DEBUG sccp_scrc.c:398 sccp_scrc_rx_scoc_conn_msg:  HDR=(CO:CODT,V=0,LEN=0),
465
	PART(T=Routing Context,L=4,D=00000000),
466
	PART(T=Destination Reference,L=4,D=000003e9),
467
	PART(T=Data,L=37,D=0014002100000200104015140802012a0409f10738150b17161805f4c43a74bc003b400100)
468
20180723172130003 DLSS7 DEBUG osmo_ss7_hmrt.c:278 m3ua_hmdc_rx_from_l2(): dpc=189=0.23.5 not local, message is for routing
469
20180723172130003 DLSS7 DEBUG osmo_ss7_hmrt.c:227 Found route for dpc=189=0.23.5: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoSGSN proto=m3ua
470
20180723172130003 DLSS7 DEBUG osmo_ss7_hmrt.c:233 rt->dest.as proto is M3UA for dpc=189=0.23.5
471
20180723172130003 DLSS7 DEBUG m3ua.c:507 XUA_AS(as-clnt-OsmoSGSN)[0x612000000820]{AS_ACTIVE}: Received Event AS-TRANSFER.req
472
20180723172130003 DLINP DEBUG stream.c:279 connected write
473
20180723172130003 DLINP DEBUG stream.c:204 sending data
474
20180723172130003 DLINP DEBUG stream.c:279 connected write
475
20180723172130003 DLINP DEBUG stream.c:204 sending data
476
20180723172130268 DLINP DEBUG stream.c:275 connected read
477
20180723172130268 DLINP DEBUG stream.c:189 message received
478
20180723172130268 DLSS7 DEBUG osmo_ss7.c:1549 asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 80 (flags=0x80)
479
20180723172130268 DLM3UA DEBUG m3ua.c:722 asp-asp-clnt-OsmoSGSN: Received M3UA Message (XFER:DATA)
480
20180723172130268 DLM3UA DEBUG m3ua.c:541 asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer
481
20180723172130269 DLM3UA DEBUG m3ua.c:580 asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=188=0.23.4
482
20180723172130269 DLSS7 DEBUG osmo_ss7_hmrt.c:274 m3ua_hmdc_rx_from_l2(): found dpc=188=0.23.4 as local
483
20180723172130269 DLSS7 DEBUG sccp_scrc.c:449 scrc_rx_mtp_xfer_ind_xua:  HDR=(CO:CODT,V=0,LEN=0),
484
	PART(T=Destination Reference,L=4,D=00000000),
485
	PART(T=Segmentation,L=4,D=00000000),
486
	PART(T=Data,L=41,D=0014402500000400104003020803000f40060009f1073815003740010b003a40080009f10700010001)
487
20180723172130269 DLSCCP DEBUG sccp_scoc.c:1548 Received CO:CODT for local reference 0
488
20180723172130269 DLSCCP DEBUG sccp_scoc.c:1581 SCCP-SCOC(0)[0x612000001120]{ACTIVE}: Received Event RCOC-DT1.ind
489
20180723172130269 DLSCCP DEBUG sccp_user.c:156 Delivering N-DATA.indication to SCCP User 'OsmoSGSN-IuPS'
490
20180723172130269 DRANAP DEBUG iu_client.c:755 sccp_sap_up(N-DATA.indication)
491
20180723172130269 DRANAP DEBUG iu_client.c:789 N-DATA.ind(0, 00 14 40 25 00 00 04 00 10 40 03 02 08 03 00 0f 40 06 00 09 f1 07 38 15 00 37 40 01 0b 00 3a 40 08 00 09 f1 07 00 01 00 01 )
492
20180723172130270 DRANAP NOTICE iu_client.c:530 handle_co(dir=1, proc=20)
493
20180723172130270 DMM INFO gprs_gmm.c:2009 MM(901700000014702/c43a74bc) -> ATTACH COMPLETE
494
20180723172130270 DMM INFO gprs_gmm.c:139 MM(901700000014702/c43a74bc) Changing PMM state from PMM DETACH to PMM CONNECTED
495
20180723172134928 DLINP DEBUG stream.c:275 connected read
496
20180723172134928 DLINP DEBUG stream.c:189 message received
497
20180723172134928 DLSS7 DEBUG osmo_ss7.c:1549 asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 140 (flags=0x80)
498
20180723172134928 DLM3UA DEBUG m3ua.c:722 asp-asp-clnt-OsmoSGSN: Received M3UA Message (XFER:DATA)
499
20180723172134928 DLM3UA DEBUG m3ua.c:541 asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer
500
20180723172134928 DLM3UA DEBUG m3ua.c:580 asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=188=0.23.4
501
20180723172134928 DLSS7 DEBUG osmo_ss7_hmrt.c:274 m3ua_hmdc_rx_from_l2(): found dpc=188=0.23.4 as local
502
20180723172134928 DLSS7 DEBUG sccp_scrc.c:449 scrc_rx_mtp_xfer_ind_xua:  HDR=(CO:CODT,V=0,LEN=0),
503
	PART(T=Destination Reference,L=4,D=00000000),
504
	PART(T=Segmentation,L=4,D=00000000),
505
	PART(T=Data,L=100,D=001440600000040010403e3d0a4105030e0000000000000000000000000000020121280908696e7465726e6574271a8080211001000010810600000000830600000000000d00000a00000f40060009f1073815003740010b003a40080009f10700010001)
506
20180723172134928 DLSCCP DEBUG sccp_scoc.c:1548 Received CO:CODT for local reference 0
507
20180723172134929 DLSCCP DEBUG sccp_scoc.c:1581 SCCP-SCOC(0)[0x612000001120]{ACTIVE}: Received Event RCOC-DT1.ind
508
20180723172134929 DLSCCP DEBUG sccp_user.c:156 Delivering N-DATA.indication to SCCP User 'OsmoSGSN-IuPS'
509
20180723172134929 DRANAP DEBUG iu_client.c:755 sccp_sap_up(N-DATA.indication)
510
20180723172134929 DRANAP DEBUG iu_client.c:789 N-DATA.ind(0, 00 14 40 60 00 00 04 00 10 40 3e 3d 0a 41 05 03 0e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 02 01 21 28 09 08 69 6e 74 65 72 6e 65 74 27 1a 80 80 21 10 01 00 00 10 81 06 00 00 00 00 83 06 00 00 00 00 00 0d 00 00 0a 00 00 0f 40 06 00 09 f1 07 38 15 00 37 40 01 0b 00 3a 40 08 00 09 f1 07 00 01 00 01 )
511
20180723172134929 DRANAP NOTICE iu_client.c:530 handle_co(dir=1, proc=20)
512
20180723172134929 DMM INFO gprs_gmm.c:2477 MM(901700000014702/c43a74bc) -> ACTIVATE PDP CONTEXT REQ: SAPI=3 NSAPI=5 IETF IPv4 
513
20180723172134930 DMM INFO gprs_sgsn.c:904 MM(901700000014702/c43a74bc) Found GGSN 0 for APN 'internet' (requested 'internet')
514
20180723172134930 DMM DEBUG gprs_gmm.c:2364 MM(901700000014702/c43a74bc) Using GGSN 0
515
20180723172134930 DLGLOBAL DEBUG rate_ctr.c:88 validating counter group 0x5632aea92140(sgsn:pdpctx) with 4 counters
516
20180723172134930 DLGTP DEBUG pdp.c:237 Begin pdp_tidset tid = 5207410000007109
517
20180723172134930 DLGTP DEBUG pdp.c:246 End pdp_tidset
518
20180723172134930 DGPRS NOTICE sgsn_libgtp.c:175 PDP(901700000014702/0) Create PDP Context
519
20180723172134932 DGPRS DEBUG sgsn_libgtp.c:626 libgtp cb_conf(type=16, cause=128, pdp=0x7fbbe36b4760, cbp=0x6140000008a0)
520
20180723172134932 DGPRS INFO sgsn_libgtp.c:396 PDP(901700000014702/0) Received CREATE PDP CTX CONF, cause=128(Request accepted)
521
20180723172134932 DRANAP DEBUG gprs_gmm.c:2977 Assigning RAB: rab_id=5, ggsn_ip=c0a8002a, teid_gn=1, use_x213_nsap=1
522
20180723172134932 DLSCCP DEBUG sccp_scoc.c:1615 Received SCCP User Primitive N-DATA.request)
523
20180723172134932 DLSCCP DEBUG sccp_scoc.c:1657 SCCP-SCOC(0)[0x612000001120]{ACTIVE}: Received Event N-DATA.req
524
20180723172134933 DLSS7 DEBUG sccp_scrc.c:398 sccp_scrc_rx_scoc_conn_msg:  HDR=(CO:CODT,V=0,LEN=0),
525
	PART(T=Routing Context,L=4,D=00000000),
526
	PART(T=Destination Reference,L=4,D=000003e9),
527
	PART(T=Data,L=85,D=000000510000010036404a0000010035003b380a12de1869ff800c34ff001f400806089e00000000b1400560018cba7f0000113e350001c0a8002a0000000000000000000000000000000000014006781c00000000)
528
20180723172134933 DLSS7 DEBUG osmo_ss7_hmrt.c:278 m3ua_hmdc_rx_from_l2(): dpc=189=0.23.5 not local, message is for routing
529
20180723172134933 DLSS7 DEBUG osmo_ss7_hmrt.c:227 Found route for dpc=189=0.23.5: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoSGSN proto=m3ua
530
20180723172134933 DLSS7 DEBUG osmo_ss7_hmrt.c:233 rt->dest.as proto is M3UA for dpc=189=0.23.5
531
20180723172134933 DLSS7 DEBUG m3ua.c:507 XUA_AS(as-clnt-OsmoSGSN)[0x612000000820]{AS_ACTIVE}: Received Event AS-TRANSFER.req
532
20180723172134933 DLINP DEBUG stream.c:279 connected write
533
20180723172134933 DLINP DEBUG stream.c:204 sending data
534
20180723172134933 DLINP DEBUG stream.c:279 connected write
535
20180723172134934 DLINP DEBUG stream.c:204 sending data
536
20180723172135713 DLINP DEBUG stream.c:275 connected read
537
20180723172135713 DLINP DEBUG stream.c:189 message received
538
20180723172135713 DLSS7 DEBUG osmo_ss7.c:1549 asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 72 (flags=0x80)
539
20180723172135713 DLM3UA DEBUG m3ua.c:722 asp-asp-clnt-OsmoSGSN: Received M3UA Message (XFER:DATA)
540
20180723172135713 DLM3UA DEBUG m3ua.c:541 asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer
541
20180723172135714 DLM3UA DEBUG m3ua.c:580 asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=188=0.23.4
542
20180723172135714 DLSS7 DEBUG osmo_ss7_hmrt.c:274 m3ua_hmdc_rx_from_l2(): found dpc=188=0.23.4 as local
543
20180723172135714 DLSS7 DEBUG sccp_scrc.c:449 scrc_rx_mtp_xfer_ind_xua:  HDR=(CO:CODT,V=0,LEN=0),
544
	PART(T=Destination Reference,L=4,D=00000000),
545
	PART(T=Segmentation,L=4,D=00000000),
546
	PART(T=Data,L=30,D=6000001a000001003440130000010033400c60287cc0a8007c0000000005)
547
20180723172135714 DLSCCP DEBUG sccp_scoc.c:1548 Received CO:CODT for local reference 0
548
20180723172135714 DLSCCP DEBUG sccp_scoc.c:1581 SCCP-SCOC(0)[0x612000001120]{ACTIVE}: Received Event RCOC-DT1.ind
549
20180723172135714 DLSCCP DEBUG sccp_user.c:156 Delivering N-DATA.indication to SCCP User 'OsmoSGSN-IuPS'
550
20180723172135714 DRANAP DEBUG iu_client.c:755 sccp_sap_up(N-DATA.indication)
551
20180723172135714 DRANAP DEBUG iu_client.c:789 N-DATA.ind(0, 60 00 00 1a 00 00 01 00 34 40 13 00 00 01 00 33 40 0c 60 28 7c c0 a8 00 7c 00 00 00 00 05 )
552
20180723172135715 DRANAP NOTICE iu_client.c:530 handle_co(dir=4, proc=0)
553
20180723172135715 DRANAP INFO iu_client.c:482 Rx RAB Assignment Response for UE conn_id 0
554
 Setup: (5/c0 a8 00 7c )20180723172135715 DRANAP DEBUG sgsn_libgtp.c:524 Updating TEID on RNC side from 0x00000001 to 0x00000005
555
20180723172135715 DGPRS INFO gprs_gmm.c:2243 PDP(901700000014702/0) <- ACTIVATE PDP CONTEXT ACK
556
20180723172135715 DRANAP INFO iu_client.c:431 Transmitting L3 Message as RANAP DT (SCCP conn_id 0)
557
20180723172135715 DLSCCP DEBUG sccp_scoc.c:1615 Received SCCP User Primitive N-DATA.request)
558
20180723172135716 DLSCCP DEBUG sccp_scoc.c:1657 SCCP-SCOC(0)[0x612000001120]{ACTIVE}: Received Event N-DATA.req
559
20180723172135716 DLSS7 DEBUG sccp_scrc.c:398 sccp_scrc_rx_scoc_conn_msg:  HDR=(CO:CODT,V=0,LEN=0),
560
	PART(T=Routing Context,L=4,D=00000000),
561
	PART(T=Destination Reference,L=4,D=000003e9),
562
	PART(T=Data,L=80,D=0014004c000002001040403f8a42030e23621f72993f3f1143ffff000000002b060121c0a82a01272280802110020000108106c0a80001830609090909000d04c0a80001000d0409090909003b400100)
563
20180723172135716 DLSS7 DEBUG osmo_ss7_hmrt.c:278 m3ua_hmdc_rx_from_l2(): dpc=189=0.23.5 not local, message is for routing
564
20180723172135716 DLSS7 DEBUG osmo_ss7_hmrt.c:227 Found route for dpc=189=0.23.5: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoSGSN proto=m3ua
565
20180723172135716 DLSS7 DEBUG osmo_ss7_hmrt.c:233 rt->dest.as proto is M3UA for dpc=189=0.23.5
566
20180723172135716 DLSS7 DEBUG m3ua.c:507 XUA_AS(as-clnt-OsmoSGSN)[0x612000000820]{AS_ACTIVE}: Received Event AS-TRANSFER.req
567
20180723172135716 DGPRS DEBUG sgsn_libgtp.c:626 libgtp cb_conf(type=18, cause=128, pdp=0x7fbbe36b4760, cbp=0x6140000008a0)
568
20180723172135716 DLINP DEBUG stream.c:279 connected write
569
20180723172135716 DLINP DEBUG stream.c:204 sending data
570
20180723172135717 DLINP DEBUG stream.c:279 connected write
571
20180723172135717 DLINP DEBUG stream.c:204 sending data
572
20180723172144656 DLINP DEBUG stream.c:275 connected read
573
20180723172144656 DLINP DEBUG stream.c:189 message received
574
20180723172144656 DLSS7 DEBUG osmo_ss7.c:1549 asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 52 (flags=0x80)
575
20180723172144656 DLM3UA DEBUG m3ua.c:722 asp-asp-clnt-OsmoSGSN: Received M3UA Message (XFER:DATA)
576
20180723172144656 DLM3UA DEBUG m3ua.c:541 asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer
577
20180723172144656 DLM3UA DEBUG m3ua.c:580 asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=188=0.23.4
578
20180723172144656 DLSS7 DEBUG osmo_ss7_hmrt.c:274 m3ua_hmdc_rx_from_l2(): found dpc=188=0.23.4 as local
579
20180723172144657 DLSS7 DEBUG sccp_scrc.c:449 scrc_rx_mtp_xfer_ind_xua:  HDR=(CO:CODT,V=0,LEN=0),
580
	PART(T=Destination Reference,L=4,D=00000000),
581
	PART(T=Segmentation,L=4,D=00000000),
582
	PART(T=Data,L=13,D=000b40090000010004400209c0)
583
20180723172144657 DLSCCP DEBUG sccp_scoc.c:1548 Received CO:CODT for local reference 0
584
20180723172144657 DLSCCP DEBUG sccp_scoc.c:1581 SCCP-SCOC(0)[0x612000001120]{ACTIVE}: Received Event RCOC-DT1.ind
585
20180723172144657 DLSCCP DEBUG sccp_user.c:156 Delivering N-DATA.indication to SCCP User 'OsmoSGSN-IuPS'
586
20180723172144657 DRANAP DEBUG iu_client.c:755 sccp_sap_up(N-DATA.indication)
587
20180723172144657 DRANAP DEBUG iu_client.c:789 N-DATA.ind(0, 00 0b 40 09 00 00 01 00 04 40 02 09 c0 )
588
20180723172144657 DRANAP NOTICE iu_client.c:530 handle_co(dir=1, proc=11)
589
20180723172144657 DRANAP INFO iu_client.c:472 Received Iu Release Request, Sending Release Command
590
20180723172144658 DLSCCP DEBUG sccp_scoc.c:1615 Received SCCP User Primitive N-DATA.request)
591
20180723172144658 DLSCCP DEBUG sccp_scoc.c:1657 SCCP-SCOC(0)[0x612000001120]{ACTIVE}: Received Event N-DATA.req
592
20180723172144658 DLSS7 DEBUG sccp_scrc.c:398 sccp_scrc_rx_scoc_conn_msg:  HDR=(CO:CODT,V=0,LEN=0),
593
	PART(T=Routing Context,L=4,D=00000000),
594
	PART(T=Destination Reference,L=4,D=000003e9),
595
	PART(T=Data,L=13,D=000100090000010004400209c0)
596
20180723172144658 DLSS7 DEBUG osmo_ss7_hmrt.c:278 m3ua_hmdc_rx_from_l2(): dpc=189=0.23.5 not local, message is for routing
597
20180723172144658 DLSS7 DEBUG osmo_ss7_hmrt.c:227 Found route for dpc=189=0.23.5: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoSGSN proto=m3ua
598
20180723172144658 DLSS7 DEBUG osmo_ss7_hmrt.c:233 rt->dest.as proto is M3UA for dpc=189=0.23.5
599
20180723172144658 DLSS7 DEBUG m3ua.c:507 XUA_AS(as-clnt-OsmoSGSN)[0x612000000820]{AS_ACTIVE}: Received Event AS-TRANSFER.req
600
20180723172144659 DLINP DEBUG stream.c:279 connected write
601
20180723172144659 DLINP DEBUG stream.c:204 sending data
602
20180723172144659 DLINP DEBUG stream.c:279 connected write
603
20180723172144659 DLINP DEBUG stream.c:204 sending data
604
20180723172144670 DLINP DEBUG stream.c:275 connected read
605
20180723172144670 DLINP DEBUG stream.c:189 message received
606
20180723172144670 DLSS7 DEBUG osmo_ss7.c:1549 asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 52 (flags=0x80)
607
20180723172144670 DLM3UA DEBUG m3ua.c:722 asp-asp-clnt-OsmoSGSN: Received M3UA Message (XFER:DATA)
608
20180723172144670 DLM3UA DEBUG m3ua.c:541 asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer
609
20180723172144670 DLM3UA DEBUG m3ua.c:580 asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=188=0.23.4
610
20180723172144670 DLSS7 DEBUG osmo_ss7_hmrt.c:274 m3ua_hmdc_rx_from_l2(): found dpc=188=0.23.4 as local
611
20180723172144671 DLSS7 DEBUG sccp_scrc.c:449 scrc_rx_mtp_xfer_ind_xua:  HDR=(CO:RELRE,V=0,LEN=0),
612
	PART(T=Destination Reference,L=4,D=00000000),
613
	PART(T=Source Reference,L=4,D=000003e9),
614
	PART(T=Cause,L=4,D=00000300),
615
	PART(T=Data,L=7,D=20010003000000)
616
20180723172144671 DLSCCP DEBUG sccp_scoc.c:1548 Received CO:RELRE for local reference 0
617
20180723172144671 DLSCCP DEBUG sccp_scoc.c:1581 SCCP-SCOC(0)[0x612000001120]{ACTIVE}: Received Event RCOC-RELEASED.ind
618
20180723172144671 DLSCCP DEBUG sccp_user.c:156 Delivering N-DISCONNECT.indication to SCCP User 'OsmoSGSN-IuPS'
619
20180723172144671 DRANAP DEBUG iu_client.c:755 sccp_sap_up(N-DISCONNECT.indication)
620
20180723172144671 DRANAP DEBUG iu_client.c:782 N-DISCONNECT.ind(0)
621
20180723172144671 DRANAP NOTICE iu_client.c:530 handle_co(dir=2, proc=1)
622
20180723172144671 DMM INFO gprs_gmm.c:195 MM(901700000014702/c43a74bc) IU release for imsi 901700000014702
623
20180723172144671 DMM INFO gprs_gmm.c:139 MM(901700000014702/c43a74bc) Changing PMM state from PMM CONNECTED to PMM IDLE
624
20180723172144671 DMM INFO gprs_gmm.c:122 MM(901700000014702/c43a74bc) Changing GTP-U endpoints 192.168.0.124 -> 192.168.0.124
625
20180723172144671 DLSS7 DEBUG sccp_scrc.c:398 sccp_scrc_rx_scoc_conn_msg:  HDR=(CO:RELCO,V=0,LEN=0),
626
	PART(T=Routing Context,L=4,D=00000000),
627
	PART(T=Destination Reference,L=4,D=000003e9),
628
	PART(T=Source Reference,L=4,D=00000000)
629
20180723172144672 DLSS7 DEBUG osmo_ss7_hmrt.c:278 m3ua_hmdc_rx_from_l2(): dpc=189=0.23.5 not local, message is for routing
630
20180723172144672 DLSS7 DEBUG osmo_ss7_hmrt.c:227 Found route for dpc=189=0.23.5: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoSGSN proto=m3ua
631
20180723172144672 DLSS7 DEBUG osmo_ss7_hmrt.c:233 rt->dest.as proto is M3UA for dpc=189=0.23.5
632
20180723172144672 DLSS7 DEBUG m3ua.c:507 XUA_AS(as-clnt-OsmoSGSN)[0x612000000820]{AS_ACTIVE}: Received Event AS-TRANSFER.req
633
20180723172144672 DLSCCP DEBUG sccp_scoc.c:972 SCCP-SCOC(0)[0x612000001120]{ACTIVE}: State change to IDLE (no timeout)
634
20180723172144672 DLSCCP DEBUG sccp_scoc.c:420 SCCP-SCOC(0)[0x612000001120]{IDLE}: Terminating (cause = OSMO_FSM_TERM_REQUEST)
635
20180723172144672 DLSCCP DEBUG sccp_scoc.c:420 SCCP-SCOC(0)[0x612000001120]{IDLE}: Freeing instance
636
20180723172144672 DLSCCP DEBUG fsm.c:381 SCCP-SCOC(0)[0x612000001120]{IDLE}: Deallocated
637
20180723172144672 DGPRS DEBUG sgsn_libgtp.c:626 libgtp cb_conf(type=18, cause=128, pdp=0x7fbbe36b4760, cbp=0x6140000008a0)
638
20180723172144672 DLINP DEBUG stream.c:279 connected write
639
20180723172144672 DLINP DEBUG stream.c:204 sending data
640
20180723172144672 DLINP DEBUG stream.c:279 connected write
641
20180723172144673 DLINP DEBUG stream.c:204 sending data
642
20180723172145201 DLINP DEBUG stream.c:275 connected read
643
20180723172145201 DLINP DEBUG stream.c:189 message received
644
20180723172145201 DLSS7 DEBUG osmo_ss7.c:1549 asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 156 (flags=0x80)
645
20180723172145201 DLM3UA DEBUG m3ua.c:722 asp-asp-clnt-OsmoSGSN: Received M3UA Message (XFER:DATA)
646
20180723172145201 DLM3UA DEBUG m3ua.c:541 asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer
647
20180723172145201 DLM3UA DEBUG m3ua.c:580 asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=188=0.23.4
648
20180723172145201 DLSS7 DEBUG osmo_ss7_hmrt.c:274 m3ua_hmdc_rx_from_l2(): found dpc=188=0.23.4 as local
649
20180723172145201 DLSUA DEBUG sccp2sua.c:333 IEI 259: Parsed Addr: RI=2,PC=188,SSN=142
650
20180723172145201 DLSUA DEBUG sccp2sua.c:333 IEI 258: Parsed Addr: RI=2,PC=189,SSN=142
651
20180723172145201 DLSS7 DEBUG sccp_scrc.c:449 scrc_rx_mtp_xfer_ind_xua:  HDR=(CO:CORE,V=0,LEN=0),
652
	PART(T=Protocol Class,L=4,D=00000002),
653
	PART(T=Source Reference,L=4,D=000003eb),
654
	PART(T=Destination Address,L=20,D=0002000380020008000000bc800300080000008e),
655
	PART(T=Source Address,L=20,D=0002000380020008000000bd800300080000008e),
656
	PART(T=Data,L=100,D=001340600000070003400180000f40060009f1073815003740010b003a40080009f107000100010010402928080103e5e000710a0008991007000010741009f107fffeff0d0b53432b37159ef989007000081705004f40030000180056400509f1070017)
657
20180723172145202 DLSUA DEBUG sua.c:385 sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 bc 80 03 00 08 00 00 00 8e 
658
20180723172145202 DLSUA DEBUG sua.c:439 SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8
659
20180723172145202 DLSUA DEBUG sua.c:439 SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8
660
20180723172145202 DLSUA DEBUG sua.c:385 sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 bc 80 03 00 08 00 00 00 8e 
661
20180723172145202 DLSUA DEBUG sua.c:439 SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8
662
20180723172145202 DLSUA DEBUG sua.c:439 SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8
663
20180723172145202 DLSCCP DEBUG fsm.c:299 SCCP-SCOC(1)[0x612000001420]{IDLE}: Allocated
664
20180723172145202 DLSCCP DEBUG sccp_scoc.c:1548 Received CO:CORE for local reference 1
665
20180723172145202 DLSCCP DEBUG sccp_scoc.c:1581 SCCP-SCOC(1)[0x612000001420]{IDLE}: Received Event RCOC-CONNECT.ind
666
20180723172145202 DLSUA DEBUG sua.c:385 sua_addr_parse_part(IEI=0x0102) (20) 00 02 00 03 80 02 00 08 00 00 00 bd 80 03 00 08 00 00 00 8e 
667
20180723172145202 DLSUA DEBUG sua.c:439 SUA IEI 0x0102 pos 4/20: subpart tag 0x8002, len 8
668
20180723172145202 DLSUA DEBUG sua.c:439 SUA IEI 0x0102 pos 12/20: subpart tag 0x8003, len 8
669
20180723172145203 DLSUA DEBUG sua.c:385 sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 bc 80 03 00 08 00 00 00 8e 
670
20180723172145203 DLSUA DEBUG sua.c:439 SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8
671
20180723172145203 DLSUA DEBUG sua.c:439 SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8
672
20180723172145203 DLSCCP DEBUG sccp_scoc.c:775 SCCP-SCOC(1)[0x612000001420]{IDLE}: State change to CONN_PEND_IN (no timeout)
673
20180723172145203 DLSCCP DEBUG sccp_user.c:156 Delivering N-CONNECT.indication to SCCP User 'OsmoSGSN-IuPS'
674
20180723172145203 DRANAP DEBUG iu_client.c:755 sccp_sap_up(N-CONNECT.indication)
675
20180723172145203 DRANAP DEBUG iu_client.c:764 N-CONNECT.ind(X->1)
676
20180723172145203 DLSCCP DEBUG sccp_scoc.c:1615 Received SCCP User Primitive N-CONNECT.response)
677
20180723172145203 DLSCCP DEBUG sccp_scoc.c:1657 SCCP-SCOC(1)[0x612000001420]{CONN_PEND_IN}: Received Event N-CONNECT.resp
678
20180723172145204 DLSS7 DEBUG sccp_scrc.c:398 sccp_scrc_rx_scoc_conn_msg:  HDR=(CO:COAK,V=0,LEN=0),
679
	PART(T=Routing Context,L=4,D=00000000),
680
	PART(T=Protocol Class,L=4,D=00000002),
681
	PART(T=Destination Reference,L=4,D=000003eb),
682
	PART(T=Source Reference,L=4,D=00000001),
683
	PART(T=Sequence Control,L=4,D=00000000),
684
	PART(T=Source Address,L=20,D=0002000380020008000000bc800300080000008e),
685
	PART(T=Destination Address,L=20,D=0002000380020008000000bd800300080000008e)
686
20180723172145204 DLSUA DEBUG sua.c:385 sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 bd 80 03 00 08 00 00 00 8e 
687
20180723172145204 DLSUA DEBUG sua.c:439 SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8
688
20180723172145204 DLSUA DEBUG sua.c:439 SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8
689
20180723172145204 DLSUA DEBUG sua.c:385 sua_addr_parse_part(IEI=0x0102) (20) 00 02 00 03 80 02 00 08 00 00 00 bc 80 03 00 08 00 00 00 8e 
690
20180723172145204 DLSUA DEBUG sua.c:439 SUA IEI 0x0102 pos 4/20: subpart tag 0x8002, len 8
691
20180723172145204 DLSUA DEBUG sua.c:439 SUA IEI 0x0102 pos 12/20: subpart tag 0x8003, len 8
692
20180723172145204 DLSS7 DEBUG osmo_ss7_hmrt.c:278 m3ua_hmdc_rx_from_l2(): dpc=189=0.23.5 not local, message is for routing
693
20180723172145204 DLSS7 DEBUG osmo_ss7_hmrt.c:227 Found route for dpc=189=0.23.5: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoSGSN proto=m3ua
694
20180723172145204 DLSS7 DEBUG osmo_ss7_hmrt.c:233 rt->dest.as proto is M3UA for dpc=189=0.23.5
695
20180723172145204 DLSS7 DEBUG m3ua.c:507 XUA_AS(as-clnt-OsmoSGSN)[0x612000000820]{AS_ACTIVE}: Received Event AS-TRANSFER.req
696
20180723172145204 DLSCCP DEBUG sccp_scoc.c:802 SCCP-SCOC(1)[0x612000001420]{CONN_PEND_IN}: State change to ACTIVE (no timeout)
697
20180723172145205 DRANAP NOTICE iu_client.c:507 handle_co_initial(dir=1, proc=19)
698
20180723172145205 DMM INFO gprs_gmm.c:1272 MM(---/ffffffff) -> GMM ATTACH REQUEST MI(901700000014701) type="GPRS attach" 
699
20180723172145205 DLGLOBAL DEBUG rate_ctr.c:88 validating counter group 0x5632aea921e0(sgsn:mmctx) with 11 counters
700
20180723172145205 DMM DEBUG gprs_gmm.c:556 MM(901700000014701/ef665d56) <- GPRS IDENTITY REQUEST: mi_type=IMEI
701
20180723172145205 DRANAP INFO iu_client.c:431 Transmitting L3 Message as RANAP DT (SCCP conn_id 1)
702
20180723172145205 DLSCCP DEBUG sccp_scoc.c:1615 Received SCCP User Primitive N-DATA.request)
703
20180723172145205 DLSCCP DEBUG sccp_scoc.c:1657 SCCP-SCOC(1)[0x612000001420]{ACTIVE}: Received Event N-DATA.req
704
20180723172145205 DLSS7 DEBUG sccp_scrc.c:398 sccp_scrc_rx_scoc_conn_msg:  HDR=(CO:CODT,V=0,LEN=0),
705
	PART(T=Routing Context,L=4,D=00000000),
706
	PART(T=Destination Reference,L=4,D=000003eb),
707
	PART(T=Data,L=20,D=001400100000020010400403081502003b400100)
708
20180723172145205 DLSS7 DEBUG osmo_ss7_hmrt.c:278 m3ua_hmdc_rx_from_l2(): dpc=189=0.23.5 not local, message is for routing
709
20180723172145205 DLSS7 DEBUG osmo_ss7_hmrt.c:227 Found route for dpc=189=0.23.5: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoSGSN proto=m3ua
710
20180723172145205 DLSS7 DEBUG osmo_ss7_hmrt.c:233 rt->dest.as proto is M3UA for dpc=189=0.23.5
711
20180723172145205 DLSS7 DEBUG m3ua.c:507 XUA_AS(as-clnt-OsmoSGSN)[0x612000000820]{AS_ACTIVE}: Received Event AS-TRANSFER.req
712
20180723172145206 DLINP DEBUG stream.c:279 connected write
713
20180723172145206 DLINP DEBUG stream.c:204 sending data
714
20180723172145206 DLINP DEBUG stream.c:279 connected write
715
20180723172145206 DLINP DEBUG stream.c:204 sending data
716
20180723172145206 DLINP DEBUG stream.c:279 connected write
717
20180723172145206 DLINP DEBUG stream.c:204 sending data
718
20180723172145726 DLINP DEBUG stream.c:275 connected read
719
20180723172145727 DLINP DEBUG stream.c:189 message received
720
20180723172145727 DLSS7 DEBUG osmo_ss7.c:1549 asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 92 (flags=0x80)
721
20180723172145727 DLM3UA DEBUG m3ua.c:722 asp-asp-clnt-OsmoSGSN: Received M3UA Message (XFER:DATA)
722
20180723172145727 DLM3UA DEBUG m3ua.c:541 asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer
723
20180723172145727 DLM3UA DEBUG m3ua.c:580 asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=188=0.23.4
724
20180723172145727 DLSS7 DEBUG osmo_ss7_hmrt.c:274 m3ua_hmdc_rx_from_l2(): found dpc=188=0.23.4 as local
725
20180723172145727 DLSS7 DEBUG sccp_scrc.c:449 scrc_rx_mtp_xfer_ind_xua:  HDR=(CO:CODT,V=0,LEN=0),
726
	PART(T=Destination Reference,L=4,D=00000001),
727
	PART(T=Segmentation,L=4,D=00000000),
728
	PART(T=Data,L=50,D=0014402e0000040010400c0b0816083a65240535317608000f40060009f1073815003740010b003a40080009f10700010001)
729
20180723172145727 DLSCCP DEBUG sccp_scoc.c:1548 Received CO:CODT for local reference 1
730
20180723172145728 DLSCCP DEBUG sccp_scoc.c:1581 SCCP-SCOC(1)[0x612000001420]{ACTIVE}: Received Event RCOC-DT1.ind
731
20180723172145728 DLSCCP DEBUG sccp_user.c:156 Delivering N-DATA.indication to SCCP User 'OsmoSGSN-IuPS'
732
20180723172145728 DRANAP DEBUG iu_client.c:755 sccp_sap_up(N-DATA.indication)
733
20180723172145728 DRANAP DEBUG iu_client.c:789 N-DATA.ind(1, 00 14 40 2e 00 00 04 00 10 40 0c 0b 08 16 08 3a 65 24 05 35 31 76 08 00 0f 40 06 00 09 f1 07 38 15 00 37 40 01 0b 00 3a 40 08 00 09 f1 07 00 01 00 01 )
734
20180723172145728 DRANAP NOTICE iu_client.c:530 handle_co(dir=1, proc=20)
735
20180723172145728 DMM DEBUG gprs_gmm.c:1195 MM(901700000014701/ef665d56) -> GMM IDENTITY RESPONSE: MI(IMEI)=356425053136780
736
20180723172145728 DMM DEBUG sgsn_auth.c:161 MM(901700000014701/ef665d56) Requesting authorization
737
20180723172145728 DMM INFO sgsn_auth.c:186 MM(901700000014701/ef665d56) Requesting authentication tuples
738
20180723172145728 DMM DEBUG gprs_subscriber.c:899 MM(901700000014701/ef665d56) Requesting subscriber authentication info
739
20180723172145729 DGPRS INFO gprs_subscriber.c:800 SUBSCR(901700000014701) requesting auth info
740
20180723172145729 DGPRS INFO gprs_subscriber.c:206 SUBSCR(901700000014701) Sending GSUP, will send: 08 01 08 09 71 00 00 00 41 07 f1 28 01 01 
741
20180723172145729 DLINP DEBUG ipa.c:142 127.0.0.1:4222 connected write
742
20180723172145729 DLINP DEBUG ipa.c:92 127.0.0.1:4222 sending data
743
20180723172145729 DLINP DEBUG ipa.c:142 127.0.0.1:4222 connected write
744
20180723172145729 DLINP DEBUG ipa.c:92 127.0.0.1:4222 sending data
745
20180723172145739 DLINP DEBUG ipa.c:138 127.0.0.1:4222 connected read
746
20180723172145739 DLINP DEBUG ipa.c:56 127.0.0.1:4222 message received
747
20180723172145739 DGPRS INFO gprs_subscriber.c:719 SUBSCR(901700000014701) Received GSUP message OSMO_GSUP_MSGT_SEND_AUTH_INFO_RESULT
748
20180723172145739 DGPRS INFO gprs_subscriber.c:239 SUBSCR(901700000014701) Got SendAuthenticationInfoResult, num_auth_vectors = 5
749
20180723172145739 DGPRS DEBUG gprs_subscriber.c:251 SUBSCR(901700000014701) Adding auth tuple, cksn = 0
750
20180723172145739 DGPRS DEBUG gprs_subscriber.c:251 SUBSCR(901700000014701) Adding auth tuple, cksn = 1
751
20180723172145739 DGPRS DEBUG gprs_subscriber.c:251 SUBSCR(901700000014701) Adding auth tuple, cksn = 2
752
20180723172145739 DGPRS DEBUG gprs_subscriber.c:251 SUBSCR(901700000014701) Adding auth tuple, cksn = 3
753
20180723172145739 DGPRS DEBUG gprs_subscriber.c:251 SUBSCR(901700000014701) Adding auth tuple, cksn = 4
754
20180723172145739 DGPRS DEBUG gprs_subscriber.c:833 SUBSCR(901700000014701) Updating subscriber authentication info
755
20180723172145739 DMM INFO gprs_sgsn.c:754 MM(901700000014701/ef665d56) Subscriber data update
756
20180723172145739 DMM DEBUG sgsn_auth.c:220 MM(901700000014701/ef665d56) Updating authorization (unknown -> authenticate)
757
20180723172145739 DMM INFO sgsn_auth.c:249 MM(901700000014701/ef665d56) Got authorization update: state unknown -> authenticate
758
20180723172145740 DMM INFO gprs_gmm.c:593 MM(901700000014701/ef665d56) <- GPRS AUTH AND CIPHERING REQ (rand = 8e 76 1c 75 6a 1b 1b c1 51 24 89 10 15 89 0e 3d , mmctx_is_r99=1, vec->auth_types=0x3, autn = 7c 5d 9b 5e ce 2f 00 00 3c 78 75 cc 2f 5f 04 85 )
759
20180723172145740 DRANAP INFO iu_client.c:431 Transmitting L3 Message as RANAP DT (SCCP conn_id 1)
760
20180723172145740 DLSCCP DEBUG sccp_scoc.c:1615 Received SCCP User Primitive N-DATA.request)
761
20180723172145740 DLSCCP DEBUG sccp_scoc.c:1657 SCCP-SCOC(1)[0x612000001420]{ACTIVE}: Received Event N-DATA.req
762
20180723172145740 DLSS7 DEBUG sccp_scrc.c:398 sccp_scrc_rx_scoc_conn_msg:  HDR=(CO:CODT,V=0,LEN=0),
763
	PART(T=Routing Context,L=4,D=00000000),
764
	PART(T=Destination Reference,L=4,D=000003eb),
765
	PART(T=Data,L=57,D=00140035000002001040292808121030218e761c756a1b1bc15124891015890e3d8028107c5d9b5ece2f00003c7875cc2f5f0485003b400100)
766
20180723172145740 DLSS7 DEBUG osmo_ss7_hmrt.c:278 m3ua_hmdc_rx_from_l2(): dpc=189=0.23.5 not local, message is for routing
767
20180723172145740 DLSS7 DEBUG osmo_ss7_hmrt.c:227 Found route for dpc=189=0.23.5: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoSGSN proto=m3ua
768
20180723172145740 DLSS7 DEBUG osmo_ss7_hmrt.c:233 rt->dest.as proto is M3UA for dpc=189=0.23.5
769
20180723172145740 DLSS7 DEBUG m3ua.c:507 XUA_AS(as-clnt-OsmoSGSN)[0x612000000820]{AS_ACTIVE}: Received Event AS-TRANSFER.req
770
20180723172145740 DLINP DEBUG stream.c:279 connected write
771
20180723172145740 DLINP DEBUG stream.c:204 sending data
772
20180723172145740 DLINP DEBUG stream.c:279 connected write
773
20180723172145740 DLINP DEBUG stream.c:204 sending data
774
20180723172145931 DLGTP ERROR gtp.c:2755 Packet from 192.168.0.42:2152, length: 64 content: 32 ff 00 38 00 00 00 05 00 15 00 00 45 00 00 34 9c 19 40 00 35 06 34 31 94 fd f5 d2 c0 a8 2a 01 00 50 df ab 9d 1c 38 70 d1 84 7f 35 80 11 01 5c a8 2e 00 00 01 01 08 0a a5 fa c2 a2 00 1d e8 ba : Unknown PDP context, GTPv1
775
20180723172146948 DLINP DEBUG stream.c:275 connected read
776
20180723172146948 DLINP DEBUG stream.c:189 message received
777
20180723172146948 DLSS7 DEBUG osmo_ss7.c:1549 asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 104 (flags=0x80)
778
20180723172146948 DLM3UA DEBUG m3ua.c:722 asp-asp-clnt-OsmoSGSN: Received M3UA Message (XFER:DATA)
779
20180723172146948 DLM3UA DEBUG m3ua.c:541 asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer
780
20180723172146948 DLM3UA DEBUG m3ua.c:580 asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=188=0.23.4
781
20180723172146948 DLSS7 DEBUG osmo_ss7_hmrt.c:274 m3ua_hmdc_rx_from_l2(): found dpc=188=0.23.4 as local
782
20180723172146949 DLSS7 DEBUG sccp_scrc.c:449 scrc_rx_mtp_xfer_ind_xua:  HDR=(CO:CODT,V=0,LEN=0),
783
	PART(T=Destination Reference,L=4,D=00000001),
784
	PART(T=Segmentation,L=4,D=00000000),
785
	PART(T=Data,L=64,D=0014403c0000040010401a190813032277aa8a7c23093365240535317608f129041d467837000f40060009f1073815003740010b003a40080009f10700010001)
786
20180723172146949 DLSCCP DEBUG sccp_scoc.c:1548 Received CO:CODT for local reference 1
787
20180723172146949 DLSCCP DEBUG sccp_scoc.c:1581 SCCP-SCOC(1)[0x612000001420]{ACTIVE}: Received Event RCOC-DT1.ind
788
20180723172146949 DLSCCP DEBUG sccp_user.c:156 Delivering N-DATA.indication to SCCP User 'OsmoSGSN-IuPS'
789
20180723172146949 DRANAP DEBUG iu_client.c:755 sccp_sap_up(N-DATA.indication)
790
20180723172146949 DRANAP DEBUG iu_client.c:789 N-DATA.ind(1, 00 14 40 3c 00 00 04 00 10 40 1a 19 08 13 03 22 77 aa 8a 7c 23 09 33 65 24 05 35 31 76 08 f1 29 04 1d 46 78 37 00 0f 40 06 00 09 f1 07 38 15 00 37 40 01 0b 00 3a 40 08 00 09 f1 07 00 01 00 01 )
791
20180723172146949 DRANAP NOTICE iu_client.c:530 handle_co(dir=1, proc=20)
792
20180723172146950 DMM INFO gprs_gmm.c:733 MM(901700000014701/ef665d56) -> GPRS AUTH AND CIPH RESPONSE
793
20180723172146950 DMM DEBUG gprs_gmm.c:780 MM(901700000014701/ef665d56) checking auth: received UMTS RES = 77 aa 8a 7c 1d 46 78 37 
794
20180723172146950 DMM DEBUG sgsn_auth.c:161 MM(901700000014701/ef665d56) Requesting authorization
795
20180723172146950 DMM INFO sgsn_auth.c:197 MM(901700000014701/ef665d56) Missing information, requesting subscriber data
796
20180723172146950 DMM DEBUG gprs_subscriber.c:874 MM(901700000014701/ef665d56) Requesting subscriber data update
797
20180723172146950 DGPRS INFO gprs_subscriber.c:813 SUBSCR(901700000014701) subscriber data is not available
798
20180723172146950 DGPRS INFO gprs_subscriber.c:206 SUBSCR(901700000014701) Sending GSUP, will send: 04 01 08 09 71 00 00 00 41 07 f1 28 01 01 
799
20180723172146950 DLINP DEBUG ipa.c:142 127.0.0.1:4222 connected write
800
20180723172146950 DLINP DEBUG ipa.c:92 127.0.0.1:4222 sending data
801
20180723172146951 DLINP DEBUG ipa.c:142 127.0.0.1:4222 connected write
802
20180723172146951 DLINP DEBUG ipa.c:92 127.0.0.1:4222 sending data
803
20180723172146952 DLINP DEBUG ipa.c:138 127.0.0.1:4222 connected read
804
20180723172146952 DLINP DEBUG ipa.c:56 127.0.0.1:4222 message received
805
20180723172146952 DGPRS INFO gprs_subscriber.c:719 SUBSCR(901700000014701) Received GSUP message OSMO_GSUP_MSGT_INSERT_DATA_REQUEST
806
20180723172146952 DGPRS INFO gprs_subscriber.c:364 SUBSCR(901700000014701) Will set PDP info, context id = 1, APN = 01 2a 
807
20180723172146952 DGPRS DEBUG gprs_subscriber.c:821 SUBSCR(901700000014701) Updating subscriber data
808
20180723172146952 DMM INFO gprs_sgsn.c:754 MM(901700000014701/ef665d56) Subscriber data update
809
20180723172146952 DMM DEBUG sgsn_auth.c:220 MM(901700000014701/ef665d56) Updating authorization (authenticate -> accepted)
810
20180723172146953 DMM INFO sgsn_auth.c:249 MM(901700000014701/ef665d56) Got authorization update: state authenticate -> accepted
811
20180723172146953 DMM NOTICE gprs_gmm.c:1119 MM(901700000014701/ef665d56) Authorized, continuing procedure, IMSI=901700000014701
812
20180723172146953 DLSCCP DEBUG sccp_scoc.c:1615 Received SCCP User Primitive N-DATA.request)
813
20180723172146953 DLSCCP DEBUG sccp_scoc.c:1657 SCCP-SCOC(1)[0x612000001420]{ACTIVE}: Received Event N-DATA.req
814
20180723172146953 DLSS7 DEBUG sccp_scrc.c:398 sccp_scrc_rx_scoc_conn_msg:  HDR=(CO:CODT,V=0,LEN=0),
815
	PART(T=Routing Context,L=4,D=00000000),
816
	PART(T=Destination Reference,L=4,D=000003eb),
817
	PART(T=Data,L=34,D=0006001e000002000c00120808d725eb5a29094124962fb9b0b9fbde3f004b000140)
818
20180723172146953 DLSS7 DEBUG osmo_ss7_hmrt.c:278 m3ua_hmdc_rx_from_l2(): dpc=189=0.23.5 not local, message is for routing
819
20180723172146953 DLSS7 DEBUG osmo_ss7_hmrt.c:227 Found route for dpc=189=0.23.5: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoSGSN proto=m3ua
820
20180723172146953 DLSS7 DEBUG osmo_ss7_hmrt.c:233 rt->dest.as proto is M3UA for dpc=189=0.23.5
821
20180723172146953 DLSS7 DEBUG m3ua.c:507 XUA_AS(as-clnt-OsmoSGSN)[0x612000000820]{AS_ACTIVE}: Received Event AS-TRANSFER.req
822
20180723172146954 DGPRS INFO gprs_subscriber.c:206 SUBSCR(901700000014701) Sending GSUP, will send: 12 01 08 09 71 00 00 00 41 07 f1 28 01 01 
823
20180723172146954 DLINP DEBUG stream.c:279 connected write
824
20180723172146954 DLINP DEBUG stream.c:204 sending data
825
20180723172146954 DLINP DEBUG ipa.c:142 127.0.0.1:4222 connected write
826
20180723172146954 DLINP DEBUG ipa.c:92 127.0.0.1:4222 sending data
827
20180723172146955 DLINP DEBUG stream.c:279 connected write
828
20180723172146955 DLINP DEBUG stream.c:204 sending data
829
20180723172146955 DLINP DEBUG ipa.c:138 127.0.0.1:4222 connected read
830
20180723172146955 DLINP DEBUG ipa.c:56 127.0.0.1:4222 message received
831
20180723172146956 DGPRS INFO gprs_subscriber.c:719 SUBSCR(901700000014701) Received GSUP message OSMO_GSUP_MSGT_UPDATE_LOCATION_RESULT
832
20180723172146956 DGPRS DEBUG gprs_subscriber.c:821 SUBSCR(901700000014701) Updating subscriber data
833
20180723172146956 DMM INFO gprs_sgsn.c:754 MM(901700000014701/ef665d56) Subscriber data update
834
20180723172146956 DMM DEBUG sgsn_auth.c:220 MM(901700000014701/ef665d56) Updating authorization (accepted -> accepted)
835
20180723172146956 DLINP DEBUG ipa.c:142 127.0.0.1:4222 connected write
836
20180723172146956 DLINP DEBUG ipa.c:92 127.0.0.1:4222 sending data
837
20180723172147308 DLINP DEBUG stream.c:275 connected read
838
20180723172147308 DLINP DEBUG stream.c:189 message received
839
20180723172147309 DLSS7 DEBUG osmo_ss7.c:1549 asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 52 (flags=0x80)
840
20180723172147309 DLM3UA DEBUG m3ua.c:722 asp-asp-clnt-OsmoSGSN: Received M3UA Message (XFER:DATA)
841
20180723172147309 DLM3UA DEBUG m3ua.c:541 asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer
842
20180723172147309 DLM3UA DEBUG m3ua.c:580 asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=188=0.23.4
843
20180723172147309 DLSS7 DEBUG osmo_ss7_hmrt.c:274 m3ua_hmdc_rx_from_l2(): found dpc=188=0.23.4 as local
844
20180723172147309 DLSS7 DEBUG sccp_scrc.c:449 scrc_rx_mtp_xfer_ind_xua:  HDR=(CO:CODT,V=0,LEN=0),
845
	PART(T=Destination Reference,L=4,D=00000001),
846
	PART(T=Segmentation,L=4,D=00000000),
847
	PART(T=Data,L=12,D=200600080000010006000100)
848
20180723172147309 DLSCCP DEBUG sccp_scoc.c:1548 Received CO:CODT for local reference 1
849
20180723172147309 DLSCCP DEBUG sccp_scoc.c:1581 SCCP-SCOC(1)[0x612000001420]{ACTIVE}: Received Event RCOC-DT1.ind
850
20180723172147309 DLSCCP DEBUG sccp_user.c:156 Delivering N-DATA.indication to SCCP User 'OsmoSGSN-IuPS'
851
20180723172147310 DRANAP DEBUG iu_client.c:755 sccp_sap_up(N-DATA.indication)
852
20180723172147310 DRANAP DEBUG iu_client.c:789 N-DATA.ind(1, 20 06 00 08 00 00 01 00 06 00 01 00 )
853
20180723172147310 DRANAP NOTICE iu_client.c:530 handle_co(dir=2, proc=6)
854
20180723172147310 DMM INFO gprs_gmm.c:437 MM(901700000014701/ef665d56) <- GPRS ATTACH ACCEPT (new P-TMSI=0xef665d56)
855
20180723172147310 DRANAP INFO iu_client.c:431 Transmitting L3 Message as RANAP DT (SCCP conn_id 1)
856
20180723172147310 DLSCCP DEBUG sccp_scoc.c:1615 Received SCCP User Primitive N-DATA.request)
857
20180723172147310 DLSCCP DEBUG sccp_scoc.c:1657 SCCP-SCOC(1)[0x612000001420]{ACTIVE}: Received Event N-DATA.req
858
20180723172147310 DLSS7 DEBUG sccp_scrc.c:398 sccp_scrc_rx_scoc_conn_msg:  HDR=(CO:CODT,V=0,LEN=0),
859
	PART(T=Routing Context,L=4,D=00000000),
860
	PART(T=Destination Reference,L=4,D=000003eb),
861
	PART(T=Data,L=37,D=0014002100000200104015140802012a0409f10738150b17161805f4ef665d56003b400100)
862
20180723172147311 DLSS7 DEBUG osmo_ss7_hmrt.c:278 m3ua_hmdc_rx_from_l2(): dpc=189=0.23.5 not local, message is for routing
863
20180723172147311 DLSS7 DEBUG osmo_ss7_hmrt.c:227 Found route for dpc=189=0.23.5: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoSGSN proto=m3ua
864
20180723172147311 DLSS7 DEBUG osmo_ss7_hmrt.c:233 rt->dest.as proto is M3UA for dpc=189=0.23.5
865
20180723172147311 DLSS7 DEBUG m3ua.c:507 XUA_AS(as-clnt-OsmoSGSN)[0x612000000820]{AS_ACTIVE}: Received Event AS-TRANSFER.req
866
20180723172147311 DLINP DEBUG stream.c:279 connected write
867
20180723172147311 DLINP DEBUG stream.c:204 sending data
868
20180723172147311 DLINP DEBUG stream.c:279 connected write
869
20180723172147311 DLINP DEBUG stream.c:204 sending data
870
20180723172147566 DLINP DEBUG stream.c:275 connected read
871
20180723172147566 DLINP DEBUG stream.c:189 message received
872
20180723172147566 DLSS7 DEBUG osmo_ss7.c:1549 asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 80 (flags=0x80)
873
20180723172147567 DLM3UA DEBUG m3ua.c:722 asp-asp-clnt-OsmoSGSN: Received M3UA Message (XFER:DATA)
874
20180723172147567 DLM3UA DEBUG m3ua.c:541 asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer
875
20180723172147567 DLM3UA DEBUG m3ua.c:580 asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=188=0.23.4
876
20180723172147567 DLSS7 DEBUG osmo_ss7_hmrt.c:274 m3ua_hmdc_rx_from_l2(): found dpc=188=0.23.4 as local
877
20180723172147567 DLSS7 DEBUG sccp_scrc.c:449 scrc_rx_mtp_xfer_ind_xua:  HDR=(CO:CODT,V=0,LEN=0),
878
	PART(T=Destination Reference,L=4,D=00000001),
879
	PART(T=Segmentation,L=4,D=00000000),
880
	PART(T=Data,L=41,D=0014402500000400104003020803000f40060009f1073815003740010b003a40080009f10700010001)
881
20180723172147567 DLSCCP DEBUG sccp_scoc.c:1548 Received CO:CODT for local reference 1
882
20180723172147567 DLSCCP DEBUG sccp_scoc.c:1581 SCCP-SCOC(1)[0x612000001420]{ACTIVE}: Received Event RCOC-DT1.ind
883
20180723172147567 DLSCCP DEBUG sccp_user.c:156 Delivering N-DATA.indication to SCCP User 'OsmoSGSN-IuPS'
884
20180723172147567 DRANAP DEBUG iu_client.c:755 sccp_sap_up(N-DATA.indication)
885
20180723172147567 DRANAP DEBUG iu_client.c:789 N-DATA.ind(1, 00 14 40 25 00 00 04 00 10 40 03 02 08 03 00 0f 40 06 00 09 f1 07 38 15 00 37 40 01 0b 00 3a 40 08 00 09 f1 07 00 01 00 01 )
886
20180723172147568 DRANAP NOTICE iu_client.c:530 handle_co(dir=1, proc=20)
887
20180723172147568 DMM INFO gprs_gmm.c:2009 MM(901700000014701/ef665d56) -> ATTACH COMPLETE
888
20180723172147568 DMM INFO gprs_gmm.c:139 MM(901700000014701/ef665d56) Changing PMM state from PMM DETACH to PMM CONNECTED
889
20180723172147804 DLGSUP INFO gsup_client.c:244 GSUP ping callback (connected, got PONG)
890
20180723172147805 DLGSUP DEBUG gsup_client.c:264 GSUP sending PING
891
20180723172147805 DLINP DEBUG ipa.c:142 127.0.0.1:4222 connected write
892
20180723172147805 DLINP DEBUG ipa.c:92 127.0.0.1:4222 sending data
893
20180723172147805 DLINP DEBUG ipa.c:142 127.0.0.1:4222 connected write
894
20180723172147805 DLINP DEBUG ipa.c:92 127.0.0.1:4222 sending data
895
20180723172147805 DLINP DEBUG ipa.c:138 127.0.0.1:4222 connected read
896
20180723172147805 DLINP DEBUG ipa.c:56 127.0.0.1:4222 message received
897
20180723172147806 DLGSUP DEBUG gsup_client.c:201 GSUP receiving PONG
898
20180723172153227 DLINP DEBUG stream.c:275 connected read
899
20180723172153227 DLINP DEBUG stream.c:189 message received
900
20180723172153227 DLSS7 DEBUG osmo_ss7.c:1549 asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 140 (flags=0x80)
901
20180723172153227 DLM3UA DEBUG m3ua.c:722 asp-asp-clnt-OsmoSGSN: Received M3UA Message (XFER:DATA)
902
20180723172153227 DLM3UA DEBUG m3ua.c:541 asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer
903
20180723172153227 DLM3UA DEBUG m3ua.c:580 asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=188=0.23.4
904
20180723172153227 DLSS7 DEBUG osmo_ss7_hmrt.c:274 m3ua_hmdc_rx_from_l2(): found dpc=188=0.23.4 as local
905
20180723172153227 DLSS7 DEBUG sccp_scrc.c:449 scrc_rx_mtp_xfer_ind_xua:  HDR=(CO:CODT,V=0,LEN=0),
906
	PART(T=Destination Reference,L=4,D=00000001),
907
	PART(T=Segmentation,L=4,D=00000000),
908
	PART(T=Data,L=100,D=001440600000040010403e3d0a4105030e0000000000000000000000000000020121280908696e7465726e6574271a8080211001000010810600000000830600000000000d00000a00000f40060009f1073815003740010b003a40080009f10700010001)
909
20180723172153228 DLSCCP DEBUG sccp_scoc.c:1548 Received CO:CODT for local reference 1
910
20180723172153228 DLSCCP DEBUG sccp_scoc.c:1581 SCCP-SCOC(1)[0x612000001420]{ACTIVE}: Received Event RCOC-DT1.ind
911
20180723172153228 DLSCCP DEBUG sccp_user.c:156 Delivering N-DATA.indication to SCCP User 'OsmoSGSN-IuPS'
912
20180723172153228 DRANAP DEBUG iu_client.c:755 sccp_sap_up(N-DATA.indication)
913
20180723172153228 DRANAP DEBUG iu_client.c:789 N-DATA.ind(1, 00 14 40 60 00 00 04 00 10 40 3e 3d 0a 41 05 03 0e 00 00 00 00 00 00 00 00 00 00 00 00 00 00 02 01 21 28 09 08 69 6e 74 65 72 6e 65 74 27 1a 80 80 21 10 01 00 00 10 81 06 00 00 00 00 83 06 00 00 00 00 00 0d 00 00 0a 00 00 0f 40 06 00 09 f1 07 38 15 00 37 40 01 0b 00 3a 40 08 00 09 f1 07 00 01 00 01 )
914
20180723172153228 DRANAP NOTICE iu_client.c:530 handle_co(dir=1, proc=20)
915
20180723172153228 DMM INFO gprs_gmm.c:2477 MM(901700000014701/ef665d56) -> ACTIVATE PDP CONTEXT REQ: SAPI=3 NSAPI=5 IETF IPv4 
916
20180723172153228 DMM INFO gprs_sgsn.c:904 MM(901700000014701/ef665d56) Found GGSN 0 for APN 'internet' (requested 'internet')
917
20180723172153229 DMM DEBUG gprs_gmm.c:2364 MM(901700000014701/ef665d56) Using GGSN 0
918
20180723172153229 DLGLOBAL ERROR rate_ctr.c:219 counter group 'sgsn:pdpctx' already exists for index 5, instead using index 6. This is a software bug that needs fixing.
919
20180723172153229 DLGLOBAL DEBUG rate_ctr.c:88 validating counter group 0x5632aea92140(sgsn:pdpctx) with 4 counters
920
20180723172153229 DLGTP DEBUG pdp.c:237 Begin pdp_tidset tid = 5107410000007109
921
20180723172153229 DLGTP DEBUG pdp.c:246 End pdp_tidset
922
20180723172153229 DGPRS NOTICE sgsn_libgtp.c:175 PDP(901700000014701/0) Create PDP Context
923
20180723172153230 DGPRS DEBUG sgsn_libgtp.c:626 libgtp cb_conf(type=16, cause=128, pdp=0x7fbbe36b57c0, cbp=0x614000000ca0)
924
20180723172153230 DGPRS INFO sgsn_libgtp.c:396 PDP(901700000014701/0) Received CREATE PDP CTX CONF, cause=128(Request accepted)
925
20180723172153230 DRANAP DEBUG gprs_gmm.c:2977 Assigning RAB: rab_id=5, ggsn_ip=c0a8002a, teid_gn=1, use_x213_nsap=1
926
20180723172153231 DLSCCP DEBUG sccp_scoc.c:1615 Received SCCP User Primitive N-DATA.request)
927
20180723172153231 DLSCCP DEBUG sccp_scoc.c:1657 SCCP-SCOC(1)[0x612000001420]{ACTIVE}: Received Event N-DATA.req
928
20180723172153231 DLSS7 DEBUG sccp_scrc.c:398 sccp_scrc_rx_scoc_conn_msg:  HDR=(CO:CODT,V=0,LEN=0),
929
	PART(T=Routing Context,L=4,D=00000000),
930
	PART(T=Destination Reference,L=4,D=000003eb),
931
	PART(T=Data,L=85,D=000000510000010036404a0000010035003b380a12de1869ff800c34ff001f400806089e00000000b1400560018cba7f0000113e350001c0a8002a0000000000000000000000000000000000014006781c00000000)
932
20180723172153231 DLSS7 DEBUG osmo_ss7_hmrt.c:278 m3ua_hmdc_rx_from_l2(): dpc=189=0.23.5 not local, message is for routing
933
20180723172153231 DLSS7 DEBUG osmo_ss7_hmrt.c:227 Found route for dpc=189=0.23.5: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoSGSN proto=m3ua
934
20180723172153231 DLSS7 DEBUG osmo_ss7_hmrt.c:233 rt->dest.as proto is M3UA for dpc=189=0.23.5
935
20180723172153231 DLSS7 DEBUG m3ua.c:507 XUA_AS(as-clnt-OsmoSGSN)[0x612000000820]{AS_ACTIVE}: Received Event AS-TRANSFER.req
936
20180723172153232 DLINP DEBUG stream.c:279 connected write
937
20180723172153232 DLINP DEBUG stream.c:204 sending data
938
20180723172153232 DLINP DEBUG stream.c:279 connected write
939
20180723172153232 DLINP DEBUG stream.c:204 sending data
940
20180723172153841 DLINP DEBUG stream.c:275 connected read
941
20180723172153841 DLINP DEBUG stream.c:189 message received
942
20180723172153841 DLSS7 DEBUG osmo_ss7.c:1549 asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 72 (flags=0x80)
943
20180723172153841 DLM3UA DEBUG m3ua.c:722 asp-asp-clnt-OsmoSGSN: Received M3UA Message (XFER:DATA)
944
20180723172153841 DLM3UA DEBUG m3ua.c:541 asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer
945
20180723172153841 DLM3UA DEBUG m3ua.c:580 asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=188=0.23.4
946
20180723172153841 DLSS7 DEBUG osmo_ss7_hmrt.c:274 m3ua_hmdc_rx_from_l2(): found dpc=188=0.23.4 as local
947
20180723172153842 DLSS7 DEBUG sccp_scrc.c:449 scrc_rx_mtp_xfer_ind_xua:  HDR=(CO:CODT,V=0,LEN=0),
948
	PART(T=Destination Reference,L=4,D=00000001),
949
	PART(T=Segmentation,L=4,D=00000000),
950
	PART(T=Data,L=30,D=6000001a000001003440130000010033400c60287cc0a8007c0000000015)
951
20180723172153842 DLSCCP DEBUG sccp_scoc.c:1548 Received CO:CODT for local reference 1
952
20180723172153842 DLSCCP DEBUG sccp_scoc.c:1581 SCCP-SCOC(1)[0x612000001420]{ACTIVE}: Received Event RCOC-DT1.ind
953
20180723172153842 DLSCCP DEBUG sccp_user.c:156 Delivering N-DATA.indication to SCCP User 'OsmoSGSN-IuPS'
954
20180723172153842 DRANAP DEBUG iu_client.c:755 sccp_sap_up(N-DATA.indication)
955
20180723172153842 DRANAP DEBUG iu_client.c:789 N-DATA.ind(1, 60 00 00 1a 00 00 01 00 34 40 13 00 00 01 00 33 40 0c 60 28 7c c0 a8 00 7c 00 00 00 00 15 )
956
20180723172153842 DRANAP NOTICE iu_client.c:530 handle_co(dir=4, proc=0)
957
20180723172153842 DRANAP INFO iu_client.c:482 Rx RAB Assignment Response for UE conn_id 1
958
 Setup: (5/c0 a8 00 7c )20180723172153843 DRANAP DEBUG sgsn_libgtp.c:524 Updating TEID on RNC side from 0x00000002 to 0x00000015
959
20180723172153843 DGPRS INFO gprs_gmm.c:2243 PDP(901700000014701/0) <- ACTIVATE PDP CONTEXT ACK
960
20180723172153843 DRANAP INFO iu_client.c:431 Transmitting L3 Message as RANAP DT (SCCP conn_id 1)
961
20180723172153843 DLSCCP DEBUG sccp_scoc.c:1615 Received SCCP User Primitive N-DATA.request)
962
20180723172153843 DLSCCP DEBUG sccp_scoc.c:1657 SCCP-SCOC(1)[0x612000001420]{ACTIVE}: Received Event N-DATA.req
963
20180723172153843 DLSS7 DEBUG sccp_scrc.c:398 sccp_scrc_rx_scoc_conn_msg:  HDR=(CO:CODT,V=0,LEN=0),
964
	PART(T=Routing Context,L=4,D=00000000),
965
	PART(T=Destination Reference,L=4,D=000003eb),
966
	PART(T=Data,L=80,D=0014004c000002001040403f8a42030e23621f72993f3f1143ffff000000002b060121c0a82a02272280802110020000108106c0a80001830609090909000d04c0a80001000d0409090909003b400100)
967
20180723172153844 DLSS7 DEBUG osmo_ss7_hmrt.c:278 m3ua_hmdc_rx_from_l2(): dpc=189=0.23.5 not local, message is for routing
968
20180723172153844 DLSS7 DEBUG osmo_ss7_hmrt.c:227 Found route for dpc=189=0.23.5: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoSGSN proto=m3ua
969
20180723172153844 DLSS7 DEBUG osmo_ss7_hmrt.c:233 rt->dest.as proto is M3UA for dpc=189=0.23.5
970
20180723172153844 DLSS7 DEBUG m3ua.c:507 XUA_AS(as-clnt-OsmoSGSN)[0x612000000820]{AS_ACTIVE}: Received Event AS-TRANSFER.req
971
20180723172153844 DGPRS DEBUG sgsn_libgtp.c:626 libgtp cb_conf(type=18, cause=128, pdp=0x7fbbe36b57c0, cbp=0x614000000ca0)
972
20180723172153844 DLINP DEBUG stream.c:279 connected write
973
20180723172153844 DLINP DEBUG stream.c:204 sending data
974
20180723172153845 DLINP DEBUG stream.c:279 connected write
975
20180723172153845 DLINP DEBUG stream.c:204 sending data
976
20180723172154554 DLINP DEBUG stream.c:275 connected read
977
20180723172154554 DLINP DEBUG stream.c:189 message received
978
20180723172154554 DLSS7 DEBUG osmo_ss7.c:1549 asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 84 (flags=0x80)
979
20180723172154554 DLM3UA DEBUG m3ua.c:722 asp-asp-clnt-OsmoSGSN: Received M3UA Message (XFER:DATA)
980
20180723172154554 DLM3UA DEBUG m3ua.c:541 asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer
981
20180723172154555 DLM3UA DEBUG m3ua.c:580 asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=188=0.23.4
982
20180723172154555 DLSS7 DEBUG osmo_ss7_hmrt.c:274 m3ua_hmdc_rx_from_l2(): found dpc=188=0.23.4 as local
983
20180723172154555 DLSS7 DEBUG sccp_scrc.c:449 scrc_rx_mtp_xfer_ind_xua:  HDR=(CO:CODT,V=0,LEN=0),
984
	PART(T=Destination Reference,L=4,D=00000001),
985
	PART(T=Segmentation,L=4,D=00000000),
986
	PART(T=Data,L=43,D=0014402700000400104005040a462491000f40060009f1073815003740010b003a40080009f10700010001)
987
20180723172154555 DLSCCP DEBUG sccp_scoc.c:1548 Received CO:CODT for local reference 1
988
20180723172154555 DLSCCP DEBUG sccp_scoc.c:1581 SCCP-SCOC(1)[0x612000001420]{ACTIVE}: Received Event RCOC-DT1.ind
989
20180723172154555 DLSCCP DEBUG sccp_user.c:156 Delivering N-DATA.indication to SCCP User 'OsmoSGSN-IuPS'
990
20180723172154555 DRANAP DEBUG iu_client.c:755 sccp_sap_up(N-DATA.indication)
991
20180723172154555 DRANAP DEBUG iu_client.c:789 N-DATA.ind(1, 00 14 40 27 00 00 04 00 10 40 05 04 0a 46 24 91 00 0f 40 06 00 09 f1 07 38 15 00 37 40 01 0b 00 3a 40 08 00 09 f1 07 00 01 00 01 )
992
20180723172154556 DRANAP NOTICE iu_client.c:530 handle_co(dir=1, proc=20)
993
20180723172154556 DMM INFO gprs_gmm.c:2677 MM(901700000014701/ef665d56) -> DEACTIVATE PDP CONTEXT REQ (cause: Regular deactivation)
994
20180723172154556 DGPRS ERROR sgsn_libgtp.c:317 PDP(901700000014701/0) Delete PDP Context
995
20180723172154556 DGPRS INFO sgsn_libgtp.c:651 PDP(901700000014701:5): Context 0x7fbbe36b57c0 was deleted
996
20180723172154556 DMM INFO gprs_gmm.c:2313 MM(901700000014701/ef665d56) <- DEACTIVATE PDP CONTEXT REQ
997
20180723172154556 DRANAP INFO iu_client.c:431 Transmitting L3 Message as RANAP DT (SCCP conn_id 1)
998
20180723172154557 DLSCCP DEBUG sccp_scoc.c:1615 Received SCCP User Primitive N-DATA.request)
999
20180723172154557 DLSCCP DEBUG sccp_scoc.c:1657 SCCP-SCOC(1)[0x612000001420]{ACTIVE}: Received Event N-DATA.req
1000
20180723172154557 DLSS7 DEBUG sccp_scrc.c:398 sccp_scrc_rx_scoc_conn_msg:  HDR=(CO:CODT,V=0,LEN=0),
1001
	PART(T=Routing Context,L=4,D=00000000),
1002
	PART(T=Destination Reference,L=4,D=000003eb),
1003
	PART(T=Data,L=21,D=0014001100000200104005048a462691003b400100)
1004
20180723172154557 DLSS7 DEBUG osmo_ss7_hmrt.c:278 m3ua_hmdc_rx_from_l2(): dpc=189=0.23.5 not local, message is for routing
1005
20180723172154557 DLSS7 DEBUG osmo_ss7_hmrt.c:227 Found route for dpc=189=0.23.5: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoSGSN proto=m3ua
1006
20180723172154557 DLSS7 DEBUG osmo_ss7_hmrt.c:233 rt->dest.as proto is M3UA for dpc=189=0.23.5
1007
20180723172154557 DLSS7 DEBUG m3ua.c:507 XUA_AS(as-clnt-OsmoSGSN)[0x612000000820]{AS_ACTIVE}: Received Event AS-TRANSFER.req
1008
20180723172154558 DLGTP DEBUG pdp.c:255 Begin pdp_tiddel tid = 5107410000007109
1009
20180723172154558 DLGTP DEBUG pdp.c:262 End pdp_tiddel: PDP found
1010
20180723172154558 DLGTP NOTICE gtp.c:2608 Packet from 192.168.0.42:2123, length: 14 content: 32 15 00 06 00 00 00 02 78 05 00 00 01 80 : Unknown PDP context: 2 (expected if gtp_delete_context_req is used)
1011
20180723172154558 DGPRS DEBUG sgsn_libgtp.c:626 libgtp cb_conf(type=20, cause=-1, pdp=(nil), cbp=0x614000000ca0)
1012
20180723172154558 DGPRS ERROR sgsn_libgtp.c:630 libgtp EOF (type=20, pdp=(nil), cbp=0x614000000ca0)
1013
20180723172154558 DGPRS INFO sgsn_libgtp.c:549 PDP(901700000014701/0) Received DELETE PDP CTX CONF, cause=-1(unknown 0xffffffff)
1014
20180723172154558 DMM INFO gprs_gmm.c:2341 MM(901700000014701/ef665d56) <- DEACTIVATE PDP CONTEXT ACK
1015
20180723172154558 DRANAP INFO iu_client.c:431 Transmitting L3 Message as RANAP DT (SCCP conn_id 1)
1016
20180723172154559 DLSCCP DEBUG sccp_scoc.c:1615 Received SCCP User Primitive N-DATA.request)
1017
20180723172154559 DLSCCP DEBUG sccp_scoc.c:1657 SCCP-SCOC(1)[0x612000001420]{ACTIVE}: Received Event N-DATA.req
1018
20180723172154559 DLSS7 DEBUG sccp_scrc.c:398 sccp_scrc_rx_scoc_conn_msg:  HDR=(CO:CODT,V=0,LEN=0),
1019
	PART(T=Routing Context,L=4,D=00000000),
1020
	PART(T=Destination Reference,L=4,D=000003eb),
1021
	PART(T=Data,L=19,D=0014000f00000200104003028a47003b400100)
1022
20180723172154559 DLSS7 DEBUG osmo_ss7_hmrt.c:278 m3ua_hmdc_rx_from_l2(): dpc=189=0.23.5 not local, message is for routing
1023
20180723172154559 DLSS7 DEBUG osmo_ss7_hmrt.c:227 Found route for dpc=189=0.23.5: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoSGSN proto=m3ua
1024
20180723172154559 DLSS7 DEBUG osmo_ss7_hmrt.c:233 rt->dest.as proto is M3UA for dpc=189=0.23.5
1025
20180723172154559 DLSS7 DEBUG m3ua.c:507 XUA_AS(as-clnt-OsmoSGSN)[0x612000000820]{AS_ACTIVE}: Received Event AS-TRANSFER.req
1026
20180723172154560 DLINP DEBUG stream.c:279 connected write
1027
20180723172154560 DLINP DEBUG stream.c:204 sending data
1028
20180723172154560 DLINP DEBUG stream.c:279 connected write
1029
20180723172154560 DLINP DEBUG stream.c:204 sending data
1030
20180723172154560 DLINP DEBUG stream.c:279 connected write
1031
20180723172154560 DLINP DEBUG stream.c:204 sending data
1032
20180723172154815 DLINP DEBUG stream.c:275 connected read
1033
20180723172154815 DLINP DEBUG stream.c:189 message received
1034
20180723172154815 DLSS7 DEBUG osmo_ss7.c:1549 asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 80 (flags=0x80)
1035
20180723172154815 DLM3UA DEBUG m3ua.c:722 asp-asp-clnt-OsmoSGSN: Received M3UA Message (XFER:DATA)
1036
20180723172154815 DLM3UA DEBUG m3ua.c:541 asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer
1037
20180723172154816 DLM3UA DEBUG m3ua.c:580 asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=188=0.23.4
1038
20180723172154816 DLSS7 DEBUG osmo_ss7_hmrt.c:274 m3ua_hmdc_rx_from_l2(): found dpc=188=0.23.4 as local
1039
20180723172154816 DLSS7 DEBUG sccp_scrc.c:449 scrc_rx_mtp_xfer_ind_xua:  HDR=(CO:CODT,V=0,LEN=0),
1040
	PART(T=Destination Reference,L=4,D=00000001),
1041
	PART(T=Segmentation,L=4,D=00000000),
1042
	PART(T=Data,L=41,D=0014402500000400104003020a47000f40060009f1073815003740010b003a40080009f10700010001)
1043
20180723172154816 DLSCCP DEBUG sccp_scoc.c:1548 Received CO:CODT for local reference 1
1044
20180723172154816 DLSCCP DEBUG sccp_scoc.c:1581 SCCP-SCOC(1)[0x612000001420]{ACTIVE}: Received Event RCOC-DT1.ind
1045

(3-3/3)
Add picture from clipboard (Maximum size: 48.8 MB)