Project

General

Profile

osmo-sgsn-debug-logs-br0ken.txt

roox, 11/12/2018 11:32 PM

 
1
osmocom-testing:~ # /usr/bin/osmo-sgsn -c /root/osmo-gsm-tester/src/osmo_gsm_tester/templates/osmo-sgsn.cfg.tmpl-cisco 
2
20181112235334961 DLGLOBAL <001b> telnet_interface.c:104 Available via telnet 127.0.0.1 4245
3
20181112235334961 DLCTRL <0022> control_if.c:911 CTRL at 127.0.0.1 4251
4
20181112235334961 DLGTP <0023> gtp.c:798 GTP: gtp_newgsn() started at 192.168.200.153
5
20181112235334963 DGPRS <000e> sgsn_libgtp.c:856 Created GTP on 192.168.200.153
6
20181112235334963 DGPRS <000e> sgsn_main.c:471 libGTP v1.2.2.15 initialized
7
20181112235334964 DLGSUP <0025> gsup_client.c:73 GSUP connecting to 192.168.200.155:4222
8
20181112235334964 DNS <000f> gprs_ns.c:1628 Listening for nsip packets on 192.168.200.153:23000
9
20181112235334964 DNS <000f> gprs_ns.c:1644 NS UDP socket at 192.168.200.153:23000
10
20181112235334964 DLSCCP <0028> sccp_user.c:370 OsmoSGSN: Creating SS7 instance
11
20181112235334964 DLSS7 <0027> osmo_ss7.c:363 1: Creating SS7 Instance
12
20181112235334964 DLSS7 <0027> osmo_ss7.c:699 1: Creating Route Table system
13
20181112235334964 DLSCCP <0028> sccp_user.c:397 OsmoSGSN: Using SS7 instance 1, pc:0.23.4
14
20181112235334964 DLSCCP <0028> sccp_user.c:404 OsmoSGSN: Creating AS instance
15
20181112235334964 DLSS7 <0027> osmo_ss7.c:948 1: Creating AS as-clnt-OsmoSGSN
16
20181112235334964 DLSS7 <0027> fsm.c:299 XUA_AS(as-clnt-OsmoSGSN)[0x55a732989bf0]{AS_DOWN}: Allocated
17
20181112235334965 DLSCCP <0028> sccp_user.c:415 OsmoSGSN: Using AS instance as-clnt-OsmoSGSN
18
20181112235334965 DLSCCP <0028> sccp_user.c:420 OsmoSGSN: Creating default route
19
20181112235334965 DLSS7 <0027> osmo_ss7.c:823 1: Creating route: pc=0=0.0.0 mask=0x0 via AS 'as-clnt-OsmoSGSN'
20
20181112235334965 DLSCCP <0028> sccp_user.c:440 OsmoSGSN: Creating ASP instance
21
20181112235334965 DLSS7 <0027> osmo_ss7.c:980 1: Adding ASP asp-clnt-OsmoSGSN to AS as-clnt-OsmoSGSN
22
20181112235334965 DLSS7 <0027> fsm.c:299 xua_default_lm(asp-clnt-OsmoSGSN)[0x55a73298a070]{IDLE}: Allocated
23
20181112235334965 DLSS7 <0027> osmo_ss7.c:1233 1: Restarting ASP asp-clnt-OsmoSGSN
24
20181112235334965 DLSS7 <0027> fsm.c:299 XUA_ASP(asp-clnt-OsmoSGSN)[0x55a73298a500]{ASP_DOWN}: Allocated
25
20181112235334965 DLSCCP <0028> sccp_user.c:476 OsmoSGSN: Using ASP instance asp-clnt-OsmoSGSN
26
20181112235334965 DLSS7 <0027> osmo_ss7.c:470 1: Creating SCCP instance
27
20181112235334965 DLSS7 <0027> osmo_ss7.c:494 registering user=SCCP for SI 3 with priv 0x55a73298a820
28
20181112235334965 DLSCCP <0028> sccp_user.c:88 Binding user 'OsmoSGSN-IuPS' to SSN=142 PC=(no PC)
29
20181112235334965 DLINP <001d> input/ipa.c:128 192.168.200.155:4222 connection done
30
20181112235334966 DLGSUP <0025> gsup_client.c:131 GSUP link to 192.168.200.155:4222 UP
31
20181112235334966 DLGSUP <0025> gsup_client.c:261 GSUP sending PING
32
20181112235334966 DLINP <001d> stream.c:166 connection closed
33
20181112235334966 DLINP <001d> stream.c:169 retrying in 5 seconds...
34
20181112235334966 DLINP <001d> input/ipa.c:135 192.168.200.155:4222 connected read
35
20181112235334966 DLINP <001d> input/ipa.c:56 192.168.200.155:4222 message received
36
20181112235334966 DLINP <001d> input/ipaccess.c:705 received ID get from 0/0/0
37
20181112235334966 DLINP <001d> input/ipaccess.c:639  tag 8: 0/0/0
38
20181112235334966 DLINP <001d> input/ipaccess.c:639  tag 7: 00:00:00:00:00:00
39
20181112235334966 DLINP <001d> input/ipaccess.c:639  tag 2: 00:00:00:00:00:00
40
20181112235334966 DLINP <001d> input/ipaccess.c:639  tag 3: 00:00:00:00:00:00
41
20181112235334966 DLINP <001d> input/ipaccess.c:639  tag 4: 00:00:00:00:00:00
42
20181112235334966 DLINP <001d> input/ipaccess.c:639  tag 5: 00:00:00:00:00:00
43
20181112235334966 DLINP <001d> input/ipaccess.c:639  tag 1: SGSN-00-00-00-00-00-00
44
20181112235334966 DLINP <001d> input/ipaccess.c:639  tag 0: SGSN-00-00-00-00-00-00
45
20181112235334967 DLINP <001d> input/ipa.c:139 192.168.200.155:4222 connected write
46
20181112235334967 DLINP <001d> input/ipa.c:89 192.168.200.155:4222 sending data
47
20181112235334967 DLINP <001d> input/ipa.c:135 192.168.200.155:4222 connected read
48
20181112235334967 DLINP <001d> input/ipa.c:56 192.168.200.155:4222 message received
49
20181112235334967 DLMI <001f> ipa.c:502 ID_ACK
50
20181112235334967 DLINP <001d> input/ipa.c:139 192.168.200.155:4222 connected write
51
20181112235334967 DLINP <001d> input/ipa.c:89 192.168.200.155:4222 sending data
52
20181112235334967 DLINP <001d> input/ipa.c:135 192.168.200.155:4222 connected read
53
20181112235334967 DLINP <001d> input/ipa.c:56 192.168.200.155:4222 message received
54
20181112235334967 DLMI <001f> ipa.c:499 PONG!
55
20181112235334968 DLGSUP <0025> gsup_client.c:198 GSUP receiving PONG
56
20181112235339323 DNS <000f> gprs_ns.c:1289 Rejecting NS PDU type NS-ALIVE from 192.168.200.150:23020 for non-existing NS-VC
57
20181112235339323 DNS <000f> gprs_ns.c:1292 NSEI 65534 (NS-VCI=65534) setting state [DEAD,BLOCKED,UNRESET] -> [ALIVE,UNBLOCKED,UNRESET]
58
20181112235339323 DNS <000f> gprs_ns.c:483 NSEI=65534 Tx NS STATUS (NSVCI=65534, cause=PDU not compatible with protocol state)
59
20181112235339966 DLINP <001d> stream.c:506 reconnecting.
60
20181112235339966 DLINP <001d> stream.c:166 connection closed
61
20181112235339966 DLINP <001d> stream.c:169 retrying in 5 seconds...
62
20181112235342323 DNS <000f> gprs_ns.c:1289 Rejecting NS PDU type NS-ALIVE from 192.168.200.150:23020 for non-existing NS-VC
63
20181112235342323 DNS <000f> gprs_ns.c:1292 NSEI 65534 (NS-VCI=65534) setting state [ALIVE,UNBLOCKED,UNRESET] -> [ALIVE,UNBLOCKED,UNRESET]
64
20181112235342323 DNS <000f> gprs_ns.c:483 NSEI=65534 Tx NS STATUS (NSVCI=65534, cause=PDU not compatible with protocol state)
65
20181112235344966 DLINP <001d> stream.c:506 reconnecting.
66
20181112235344966 DLINP <001d> stream.c:166 connection closed
67
20181112235344966 DLINP <001d> stream.c:169 retrying in 5 seconds...
68
20181112235345323 DNS <000f> gprs_ns.c:1289 Rejecting NS PDU type NS-ALIVE from 192.168.200.150:23020 for non-existing NS-VC
69
20181112235345323 DNS <000f> gprs_ns.c:1292 NSEI 65534 (NS-VCI=65534) setting state [ALIVE,UNBLOCKED,UNRESET] -> [ALIVE,UNBLOCKED,UNRESET]
70
20181112235345323 DNS <000f> gprs_ns.c:483 NSEI=65534 Tx NS STATUS (NSVCI=65534, cause=PDU not compatible with protocol state)
71
20181112235348323 DNS <000f> gprs_ns.c:1289 Rejecting NS PDU type NS-ALIVE from 192.168.200.150:23020 for non-existing NS-VC
72
20181112235348323 DNS <000f> gprs_ns.c:1292 NSEI 65534 (NS-VCI=65534) setting state [ALIVE,UNBLOCKED,UNRESET] -> [ALIVE,UNBLOCKED,UNRESET]
73
20181112235348323 DNS <000f> gprs_ns.c:483 NSEI=65534 Tx NS STATUS (NSVCI=65534, cause=PDU not compatible with protocol state)
74
20181112235349967 DLINP <001d> stream.c:506 reconnecting.
75
20181112235349967 DLINP <001d> stream.c:166 connection closed
76
20181112235349967 DLINP <001d> stream.c:169 retrying in 5 seconds...
77
20181112235351323 DNS <000f> gprs_ns.c:1289 Rejecting NS PDU type NS-ALIVE from 192.168.200.150:23020 for non-existing NS-VC
78
20181112235351323 DNS <000f> gprs_ns.c:1292 NSEI 65534 (NS-VCI=65534) setting state [ALIVE,UNBLOCKED,UNRESET] -> [ALIVE,UNBLOCKED,UNRESET]
79
20181112235351323 DNS <000f> gprs_ns.c:483 NSEI=65534 Tx NS STATUS (NSVCI=65534, cause=PDU not compatible with protocol state)
80
20181112235354323 DNS <000f> gprs_ns.c:1289 Rejecting NS PDU type NS-ALIVE from 192.168.200.150:23020 for non-existing NS-VC
81
20181112235354323 DNS <000f> gprs_ns.c:1292 NSEI 65534 (NS-VCI=65534) setting state [ALIVE,UNBLOCKED,UNRESET] -> [ALIVE,UNBLOCKED,UNRESET]
82
20181112235354323 DNS <000f> gprs_ns.c:483 NSEI=65534 Tx NS STATUS (NSVCI=65534, cause=PDU not compatible with protocol state)
83
20181112235354966 DLGSUP <0025> gsup_client.c:241 GSUP ping callback (connected, got PONG)
84
20181112235354966 DLGSUP <0025> gsup_client.c:261 GSUP sending PING
85
20181112235354966 DLINP <001d> input/ipa.c:139 192.168.200.155:4222 connected write
86
20181112235354966 DLINP <001d> input/ipa.c:89 192.168.200.155:4222 sending data
87
20181112235354966 DLINP <001d> input/ipa.c:139 192.168.200.155:4222 connected write
88
20181112235354966 DLINP <001d> input/ipa.c:89 192.168.200.155:4222 sending data
89
20181112235354966 DLINP <001d> input/ipa.c:135 192.168.200.155:4222 connected read
90
20181112235354966 DLINP <001d> input/ipa.c:56 192.168.200.155:4222 message received
91
20181112235354966 DLMI <001f> ipa.c:499 PONG!
92
20181112235354966 DLGSUP <0025> gsup_client.c:198 GSUP receiving PONG
93
20181112235354967 DLINP <001d> stream.c:506 reconnecting.
94
20181112235354967 DLINP <001d> stream.c:166 connection closed
95
20181112235354967 DLINP <001d> stream.c:169 retrying in 5 seconds...
96
20181112235357323 DNS <000f> gprs_ns.c:1289 Rejecting NS PDU type NS-ALIVE from 192.168.200.150:23020 for non-existing NS-VC
97
20181112235357323 DNS <000f> gprs_ns.c:1292 NSEI 65534 (NS-VCI=65534) setting state [ALIVE,UNBLOCKED,UNRESET] -> [ALIVE,UNBLOCKED,UNRESET]
98
20181112235357323 DNS <000f> gprs_ns.c:483 NSEI=65534 Tx NS STATUS (NSVCI=65534, cause=PDU not compatible with protocol state)
99
20181112235359968 DLINP <001d> stream.c:506 reconnecting.
100
20181112235359969 DLINP <001d> stream.c:166 connection closed
101
20181112235359969 DLINP <001d> stream.c:169 retrying in 5 seconds...
102
20181112235400323 DNS <000f> gprs_ns.c:1289 Rejecting NS PDU type NS-ALIVE from 192.168.200.150:23020 for non-existing NS-VC
103
20181112235400323 DNS <000f> gprs_ns.c:1292 NSEI 65534 (NS-VCI=65534) setting state [ALIVE,UNBLOCKED,UNRESET] -> [ALIVE,UNBLOCKED,UNRESET]
104
20181112235400323 DNS <000f> gprs_ns.c:483 NSEI=65534 Tx NS STATUS (NSVCI=65534, cause=PDU not compatible with protocol state)
105
20181112235403323 DNS <000f> gprs_ns.c:1289 Rejecting NS PDU type NS-ALIVE from 192.168.200.150:23020 for non-existing NS-VC
106
20181112235403323 DNS <000f> gprs_ns.c:1292 NSEI 65534 (NS-VCI=65534) setting state [ALIVE,UNBLOCKED,UNRESET] -> [ALIVE,UNBLOCKED,UNRESET]
107
20181112235403323 DNS <000f> gprs_ns.c:483 NSEI=65534 Tx NS STATUS (NSVCI=65534, cause=PDU not compatible with protocol state)
108
20181112235404961 DGPRS <000e> gprs_sgsn.c:963 Checking for inactive LLMEs, time = 1327416
109
20181112235404969 DLINP <001d> stream.c:506 reconnecting.
110
20181112235404969 DLINP <001d> stream.c:166 connection closed
111
20181112235404969 DLINP <001d> stream.c:169 retrying in 5 seconds...
112
20181112235406323 DNS <000f> gprs_ns.c:1289 Rejecting NS PDU type NS-ALIVE from 192.168.200.150:23020 for non-existing NS-VC
113
20181112235406323 DNS <000f> gprs_ns.c:1292 NSEI 65534 (NS-VCI=65534) setting state [ALIVE,UNBLOCKED,UNRESET] -> [ALIVE,UNBLOCKED,UNRESET]
114
20181112235406323 DNS <000f> gprs_ns.c:483 NSEI=65534 Tx NS STATUS (NSVCI=65534, cause=PDU not compatible with protocol state)
115
20181112235409323 DNS <000f> gprs_ns.c:1289 Rejecting NS PDU type NS-ALIVE from 192.168.200.150:23020 for non-existing NS-VC
116
20181112235409323 DNS <000f> gprs_ns.c:1292 NSEI 65534 (NS-VCI=65534) setting state [ALIVE,UNBLOCKED,UNRESET] -> [ALIVE,UNBLOCKED,UNRESET]
117
20181112235409323 DNS <000f> gprs_ns.c:483 NSEI=65534 Tx NS STATUS (NSVCI=65534, cause=PDU not compatible with protocol state)
118
20181112235409970 DLINP <001d> stream.c:506 reconnecting.
119
20181112235409970 DLINP <001d> stream.c:166 connection closed
120
20181112235409970 DLINP <001d> stream.c:169 retrying in 5 seconds...
121
20181112235412323 DNS <000f> gprs_ns.c:1289 Rejecting NS PDU type NS-BLOCK from 192.168.200.150:23020 for non-existing NS-VC
122
20181112235412323 DNS <000f> gprs_ns.c:1292 NSEI 65534 (NS-VCI=65534) setting state [ALIVE,UNBLOCKED,UNRESET] -> [ALIVE,UNBLOCKED,UNRESET]
123
20181112235412323 DNS <000f> gprs_ns.c:483 NSEI=65534 Tx NS STATUS (NSVCI=65534, cause=PDU not compatible with protocol state)
124
20181112235414967 DLGSUP <0025> gsup_client.c:241 GSUP ping callback (connected, got PONG)
125
20181112235414967 DLGSUP <0025> gsup_client.c:261 GSUP sending PING
126
20181112235414967 DLINP <001d> input/ipa.c:139 192.168.200.155:4222 connected write
127
20181112235414967 DLINP <001d> input/ipa.c:89 192.168.200.155:4222 sending data
128
20181112235414967 DLINP <001d> input/ipa.c:139 192.168.200.155:4222 connected write
129
20181112235414967 DLINP <001d> input/ipa.c:89 192.168.200.155:4222 sending data
130
20181112235414967 DLINP <001d> input/ipa.c:135 192.168.200.155:4222 connected read
131
20181112235414967 DLINP <001d> input/ipa.c:56 192.168.200.155:4222 message received
132
20181112235414967 DLMI <001f> ipa.c:499 PONG!
133
20181112235414967 DLGSUP <0025> gsup_client.c:198 GSUP receiving PONG
134
20181112235414970 DLINP <001d> stream.c:506 reconnecting.
135
20181112235414970 DLINP <001d> stream.c:166 connection closed
136
20181112235414970 DLINP <001d> stream.c:169 retrying in 5 seconds...
137
20181112235419971 DLINP <001d> stream.c:506 reconnecting.
138
20181112235419972 DLINP <001d> stream.c:166 connection closed
139
20181112235419972 DLINP <001d> stream.c:169 retrying in 5 seconds...
140
20181112235424972 DLINP <001d> stream.c:506 reconnecting.
141
20181112235424972 DLINP <001d> stream.c:166 connection closed
142
20181112235424972 DLINP <001d> stream.c:169 retrying in 5 seconds...
143
20181112235429973 DLINP <001d> stream.c:506 reconnecting.
144
20181112235429973 DLINP <001d> stream.c:166 connection closed
145
20181112235429973 DLINP <001d> stream.c:169 retrying in 5 seconds...
146
20181112235434961 DGPRS <000e> gprs_sgsn.c:963 Checking for inactive LLMEs, time = 1327446
147
20181112235434967 DLGSUP <0025> gsup_client.c:241 GSUP ping callback (connected, got PONG)
148
20181112235434967 DLGSUP <0025> gsup_client.c:261 GSUP sending PING
149
20181112235434967 DLINP <001d> input/ipa.c:139 192.168.200.155:4222 connected write
150
20181112235434967 DLINP <001d> input/ipa.c:89 192.168.200.155:4222 sending data
151
20181112235434967 DLINP <001d> input/ipa.c:139 192.168.200.155:4222 connected write
152
20181112235434967 DLINP <001d> input/ipa.c:89 192.168.200.155:4222 sending data
153
20181112235434967 DLINP <001d> input/ipa.c:135 192.168.200.155:4222 connected read
154
20181112235434967 DLINP <001d> input/ipa.c:56 192.168.200.155:4222 message received
155
20181112235434967 DLMI <001f> ipa.c:499 PONG!
156
20181112235434967 DLGSUP <0025> gsup_client.c:198 GSUP receiving PONG
157
20181112235434973 DLINP <001d> stream.c:506 reconnecting.
158
20181112235434974 DLINP <001d> stream.c:166 connection closed
159
20181112235434974 DLINP <001d> stream.c:169 retrying in 5 seconds...
160
20181112235439975 DLINP <001d> stream.c:506 reconnecting.
161
20181112235439975 DLINP <001d> stream.c:166 connection closed
162
20181112235439975 DLINP <001d> stream.c:169 retrying in 5 seconds...
163
20181112235442366 DNS <000f> gprs_ns.c:266 NSVCI=65535 Creating NS-VC
164
20181112235442366 DNS <000f> gprs_ns.c:272 NSEI 0 (NS-VCI=65535) setting state [DEAD,UNBLOCKED,UNRESET] -> [DEAD,BLOCKED,UNRESET]
165
20181112235442366 DLGLOBAL <001b> rate_ctr.c:88 validating counter group 0x7f131edbd560(ns:nsvc) with 12 counters
166
20181112235442366 DNS <000f> gprs_ns.c:1330 Creating NS-VC for BSS at 192.168.200.150:23020
167
20181112235442366 DNS <000f> gprs_ns.c:890 NSVCI=65535(invalid) Rx NS RESET (NSEI=1234, NSVCI=1234, cause=O&M intervention)
168
20181112235442366 DNS <000f> gprs_ns.c:939 NSEI 0 (NS-VCI=65535) setting state [DEAD,BLOCKED,UNRESET] -> [ALIVE,BLOCKED,UNRESET]
169
20181112235442366 DNS <000f> gprs_ns.c:710 NSEI=1234 Tx NS RESET ACK (NSVCI=1234)
170
20181112235442366 DNS <000f> gprs_ns.c:610 NSEI=1234 Starting timer in mode tns-test (30 seconds)
171
20181112235442368 DNS <000f> gprs_ns.c:610 NSEI=1234 Starting timer in mode tns-test (30 seconds)
172
20181112235442370 DNS <000f> gprs_ns.c:1414 NSEI=1234 Rx NS UNBLOCK
173
20181112235442370 DNS <000f> gprs_ns.c:1415 NSEI 1234 (NS-VCI=1234) setting state [ALIVE,BLOCKED,UNRESET] -> [ALIVE,UNBLOCKED,UNRESET]
174
20181112235442377 DBSSGP <0010> gprs_bssgp.c:266 BSSGP BVCI=0 Rx RESET cause=Transmission capacity modified
175
20181112235442377 DLGLOBAL <001b> rate_ctr.c:88 validating counter group 0x7f131edbd6c0(bssgp:bss_ctx) with 7 counters
176
20181112235442379 DBSSGP <0010> gprs_bssgp.c:266 BSSGP BVCI=1234 Rx RESET cause=Transmission capacity modified
177
20181112235442379 DLGLOBAL <001b> rate_ctr.c:88 validating counter group 0x7f131edbd6c0(bssgp:bss_ctx) with 7 counters
178
20181112235442379 DBSSGP <0010> gprs_bssgp.c:288 Cell 001-01-1-1 CI 0 on BVCI 1234
179
20181112235444975 DLINP <001d> stream.c:506 reconnecting.
180
20181112235444975 DLINP <001d> stream.c:166 connection closed
181
20181112235444975 DLINP <001d> stream.c:169 retrying in 5 seconds...
182
20181112235449976 DLINP <001d> stream.c:506 reconnecting.
183
20181112235449977 DLINP <001d> stream.c:166 connection closed
184
20181112235449977 DLINP <001d> stream.c:169 retrying in 5 seconds...
185
20181112235452408 DBSSGP <0010> gprs_bssgp.c:360 BSSGP BVCI=1234 Rx BVC-UNBLOCK
186
20181112235452416 DBSSGP <0010> gprs_bssgp.c:806 BSSGP BVCI=1234 Rx Flow Control BVC
187
20181112235452416 DBSSGP <0010> gprs_bssgp.c:839 BSS instructs us to MS default bucket leak rate != 0, restarting DL GPRS!
188
20181112235454968 DLGSUP <0025> gsup_client.c:241 GSUP ping callback (connected, got PONG)
189
20181112235454968 DLGSUP <0025> gsup_client.c:261 GSUP sending PING
190
20181112235454968 DLINP <001d> input/ipa.c:139 192.168.200.155:4222 connected write
191
20181112235454968 DLINP <001d> input/ipa.c:89 192.168.200.155:4222 sending data
192
20181112235454968 DLINP <001d> input/ipa.c:139 192.168.200.155:4222 connected write
193
20181112235454968 DLINP <001d> input/ipa.c:89 192.168.200.155:4222 sending data
194
20181112235454969 DLINP <001d> input/ipa.c:135 192.168.200.155:4222 connected read
195
20181112235454969 DLINP <001d> input/ipa.c:56 192.168.200.155:4222 message received
196
20181112235454969 DLMI <001f> ipa.c:499 PONG!
197
20181112235454969 DLGSUP <0025> gsup_client.c:198 GSUP receiving PONG
198
20181112235454977 DLINP <001d> stream.c:506 reconnecting.
199
20181112235454977 DLINP <001d> stream.c:166 connection closed
200
20181112235454977 DLINP <001d> stream.c:169 retrying in 5 seconds...
201
20181112235459978 DLINP <001d> stream.c:506 reconnecting.
202
20181112235459978 DLINP <001d> stream.c:166 connection closed
203
20181112235459978 DLINP <001d> stream.c:169 retrying in 5 seconds...
204
20181112235504962 DGPRS <000e> gprs_sgsn.c:963 Checking for inactive LLMEs, time = 1327476
205
20181112235504979 DLINP <001d> stream.c:506 reconnecting.
206
20181112235504979 DLINP <001d> stream.c:166 connection closed
207
20181112235504979 DLINP <001d> stream.c:169 retrying in 5 seconds...
208
20181112235509980 DLINP <001d> stream.c:506 reconnecting.
209
20181112235509980 DLINP <001d> stream.c:166 connection closed
210
20181112235509980 DLINP <001d> stream.c:169 retrying in 5 seconds...
211
20181112235512369 DNS <000f> gprs_ns.c:638 NSEI=1234 Timer expired in mode tns-test (30 seconds)
212
20181112235512369 DNS <000f> gprs_ns.c:571 NSEI=1234 Tx NS ALIVE (NSVCI=1234)
213
20181112235512369 DNS <000f> gprs_ns.c:610 NSEI=1234 Starting timer in mode tns-alive (3 seconds)
214
20181112235512373 DNS <000f> gprs_ns.c:584 NSEI=1234 Tx NS ALIVE_ACK (NSVCI=1234)
215
20181112235512377 DNS <000f> gprs_ns.c:610 NSEI=1234 Starting timer in mode tns-test (30 seconds)
216
20181112235514969 DLGSUP <0025> gsup_client.c:241 GSUP ping callback (connected, got PONG)
217
20181112235514969 DLGSUP <0025> gsup_client.c:261 GSUP sending PING
218
20181112235514969 DLINP <001d> input/ipa.c:139 192.168.200.155:4222 connected write
219
20181112235514969 DLINP <001d> input/ipa.c:89 192.168.200.155:4222 sending data
220
20181112235514969 DLINP <001d> input/ipa.c:139 192.168.200.155:4222 connected write
221
20181112235514969 DLINP <001d> input/ipa.c:89 192.168.200.155:4222 sending data
222
20181112235514970 DLINP <001d> input/ipa.c:135 192.168.200.155:4222 connected read
223
20181112235514970 DLINP <001d> input/ipa.c:56 192.168.200.155:4222 message received
224
20181112235514970 DLMI <001f> ipa.c:499 PONG!
225
20181112235514970 DLGSUP <0025> gsup_client.c:198 GSUP receiving PONG
226
20181112235514980 DLINP <001d> stream.c:506 reconnecting.
227
20181112235514981 DLINP <001d> stream.c:166 connection closed
228
20181112235514981 DLINP <001d> stream.c:169 retrying in 5 seconds...
229
20181112235519982 DLINP <001d> stream.c:506 reconnecting.
230
20181112235519982 DLINP <001d> stream.c:166 connection closed
231
20181112235519982 DLINP <001d> stream.c:169 retrying in 5 seconds...
232
20181112235524982 DLINP <001d> stream.c:506 reconnecting.
233
20181112235524982 DLINP <001d> stream.c:166 connection closed
234
20181112235524982 DLINP <001d> stream.c:169 retrying in 5 seconds...
235
20181112235526196 DBSSGP <0010> gprs_bssgp.c:392 BSSGP TLLI=0x8bc4d3af Rx UPLINK-UNITDATA
236
20181112235526196 DLLC <0011> gprs_llc.c:526 LLC RX: unknown TLLI 0x8bc4d3af, creating LLME on the fly
237
20181112235526196 DLLC <0011> gprs_llc_parse.c:81 LLC SAPI=1 C   U GEA0 IOV-UI=0x000000 FCS=0xde24a6 CMD=UI DATA 
238
20181112235526196 DMM <0002> gprs_gmm.c:1278 MM(---/ffffffff) -> GMM ATTACH REQUEST MI(3418674095) type="GPRS attach" 
239
20181112235526196 DMM <0002> gprs_sgsn.c:239 MM(/00000000) Allocated with GEA0 cipher.
240
20181112235526196 DLGLOBAL <001b> rate_ctr.c:88 validating counter group 0x55a7320533a0(sgsn:mmctx) with 11 counters
241
20181112235526196 DMM <0002> fsm.c:299 GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x55a73298e010]{Init}: Allocated
242
20181112235526196 DMM <0002> gprs_gmm.c:1434 GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x55a73298e010]{Init}: Received Event Received an attach request
243
20181112235526196 DMM <0002> gprs_gmm_attach.c:36 GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x55a73298e010]{Init}: state_chg to CheckIdentity
244
20181112235526196 DMM <0002> gprs_gmm.c:557 MM(/c23c73ac) <- GPRS IDENTITY REQUEST: mi_type=IMEI
245
20181112235526777 DBSSGP <0010> gprs_bssgp.c:392 BSSGP TLLI=0x8bc4d3af Rx UPLINK-UNITDATA
246
20181112235526777 DLLC <0011> gprs_llc_parse.c:81 LLC SAPI=1 C   U GEA0 IOV-UI=0x000000 FCS=0x4335ac CMD=UI DATA 
247
20181112235526777 DMM <0002> gprs_gmm.c:1201 MM(/c23c73ac) -> GMM IDENTITY RESPONSE: MI(IMEI)=356161072930990
248
20181112235526777 DMM <0002> gprs_gmm.c:242 MM(/c23c73ac) Stopping MM timer 3370 but 0 is running
249
20181112235526777 DMM <0002> gprs_gmm.c:1244 GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x55a73298e010]{CheckIdentity}: Received Event Identity Request received
250
20181112235526777 DMM <0002> gprs_gmm_attach.c:97 GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x55a73298e010]{CheckIdentity}: state_chg to CheckIdentity
251
20181112235526777 DMM <0002> gprs_gmm.c:557 MM(/c23c73ac) <- GPRS IDENTITY REQUEST: mi_type=IMSI
252
20181112235527018 DBSSGP <0010> gprs_bssgp.c:892 BSSGP BVCI=1234 Rx Flow Control MS
253
20181112235527216 DBSSGP <0010> gprs_bssgp.c:392 BSSGP TLLI=0x8bc4d3af Rx UPLINK-UNITDATA
254
20181112235527216 DLLC <0011> gprs_llc_parse.c:81 LLC SAPI=1 C   U GEA0 IOV-UI=0x000000 FCS=0x4ebb00 CMD=UI DATA 
255
20181112235527216 DMM <0002> gprs_gmm.c:1201 MM(/c23c73ac) -> GMM IDENTITY RESPONSE: MI(IMSI)=010000000001100
256
20181112235527216 DMM <0002> gprs_gmm.c:242 MM(/c23c73ac) Stopping MM timer 3370 but 0 is running
257
20181112235527216 DMM <0002> gprs_gmm.c:1244 GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x55a73298e010]{CheckIdentity}: Received Event Identity Request received
258
20181112235527216 DMM <0002> gprs_gmm_attach.c:99 GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x55a73298e010]{CheckIdentity}: state_chg to Authenticate
259
20181112235527216 DMM <0002> gprs_gmm_attach.c:134 GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x55a73298e010]{Authenticate}: state_chg to WaitAttachComplete
260
20181112235527216 DMM <0002> gprs_gmm.c:438 MM(010000000001100/c23c73ac) <- GPRS ATTACH ACCEPT (new P-TMSI=0xc23c73ac)
261
20181112235527657 DBSSGP <0010> gprs_bssgp.c:392 BSSGP TLLI=0xc23c73ac Rx UPLINK-UNITDATA
262
20181112235527657 DLLC <0011> gprs_llc_parse.c:81 LLC SAPI=1 C   U GEA0 IOV-UI=0x000000 FCS=0xbcf132 CMD=UI DATA 
263
20181112235527657 DMM <0002> gprs_gmm.c:2037 MM(010000000001100/c23c73ac) -> ATTACH COMPLETE
264
20181112235527657 DMM <0002> gprs_gmm.c:242 MM(010000000001100/c23c73ac) Stopping MM timer 3350 but 0 is running
265
20181112235527657 DMM <0002> gprs_gmm.c:166 MM(010000000001100/c23c73ac) Changing MM state from MM IDLE to MM READY
266
20181112235527657 DMM <0002> gprs_gmm.c:2054 GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x55a73298e010]{WaitAttachComplete}: Received Event Attach complete received.
267
20181112235527657 DMM <0002> gprs_gmm_attach.c:196 GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x55a73298e010]{WaitAttachComplete}: state_chg to Init
268
20181112235528098 DBSSGP <0010> gprs_bssgp.c:392 BSSGP TLLI=0xc23c73ac Rx UPLINK-UNITDATA
269
20181112235528098 DLLC <0011> gprs_llc_parse.c:81 LLC SAPI=1 C   U GEA0 IOV-UI=0x000000 FCS=0xa1eb62 CMD=UI DATA 
270
20181112235528098 DMM <0002> gprs_gmm.c:2506 MM(010000000001100/c23c73ac) -> ACTIVATE PDP CONTEXT REQ: SAPI=3 NSAPI=5 IETF IPv4 
271
20181112235528098 DMM <0002> gprs_sgsn.c:927 MM(010000000001100/c23c73ac) Found GGSN 0 for APN 'cisco' (requested 'cisco')
272
20181112235528098 DMM <0002> gprs_gmm.c:2393 MM(010000000001100/c23c73ac) Using GGSN 0
273
20181112235528098 DLGLOBAL <001b> rate_ctr.c:88 validating counter group 0x55a732053340(sgsn:pdpctx) with 4 counters
274
20181112235528098 DLGTP <0023> pdp.c:237 Begin pdp_tidset tid = 5001100000000010
275
20181112235528098 DLGTP <0023> pdp.c:246 End pdp_tidset
276
20181112235528098 DGPRS <000e> sgsn_libgtp.c:175 PDP(010000000001100/0) Create PDP Context
277
20181112235528115 DGPRS <000e> sgsn_libgtp.c:625 libgtp cb_conf(type=16, cause=193, pdp=0x7f131df2d2e0, cbp=0x55a7328b25d0)
278
20181112235528115 DGPRS <000e> sgsn_libgtp.c:396 PDP(010000000001100/0) Received CREATE PDP CTX CONF, cause=193(Invalid message format)
279
20181112235528115 DLGTP <0023> pdp.c:255 Begin pdp_tiddel tid = 5001100000000010
280
20181112235528115 DLGTP <0023> pdp.c:262 End pdp_tiddel: PDP found
281
20181112235528115 DMM <0002> gprs_gmm.c:2317 MM(010000000001100/c23c73ac) <- ACTIVATE PDP CONTEXT REJ(cause=30)
282
20181112235528393 DBSSGP <0010> gprs_bssgp.c:892 BSSGP BVCI=1234 Rx Flow Control MS
283
20181112235529983 DLINP <001d> stream.c:506 reconnecting.
284
20181112235529984 DLINP <001d> stream.c:166 connection closed
285
20181112235529984 DLINP <001d> stream.c:169 retrying in 5 seconds...
286
20181112235530955 DBSSGP <0010> gprs_bssgp.c:892 BSSGP BVCI=1234 Rx Flow Control MS
287
20181112235533511 DBSSGP <0010> gprs_bssgp.c:892 BSSGP BVCI=1234 Rx Flow Control MS
288
20181112235534964 DGPRS <000e> gprs_sgsn.c:963 Checking for inactive LLMEs, time = 1327506
289
20181112235534969 DLGSUP <0025> gsup_client.c:241 GSUP ping callback (connected, got PONG)
290
20181112235534969 DLGSUP <0025> gsup_client.c:261 GSUP sending PING
291
20181112235534969 DLINP <001d> input/ipa.c:139 192.168.200.155:4222 connected write
292
20181112235534969 DLINP <001d> input/ipa.c:89 192.168.200.155:4222 sending data
293
20181112235534970 DLINP <001d> input/ipa.c:139 192.168.200.155:4222 connected write
294
20181112235534970 DLINP <001d> input/ipa.c:89 192.168.200.155:4222 sending data
295
20181112235534970 DLINP <001d> input/ipa.c:135 192.168.200.155:4222 connected read
296
20181112235534970 DLINP <001d> input/ipa.c:56 192.168.200.155:4222 message received
297
20181112235534970 DLMI <001f> ipa.c:499 PONG!
298
20181112235534970 DLGSUP <0025> gsup_client.c:198 GSUP receiving PONG
299
20181112235534984 DLINP <001d> stream.c:506 reconnecting.
300
20181112235534984 DLINP <001d> stream.c:166 connection closed
301
20181112235534984 DLINP <001d> stream.c:169 retrying in 5 seconds...
302
20181112235536096 DBSSGP <0010> gprs_bssgp.c:392 BSSGP TLLI=0xc23c73ac Rx UPLINK-UNITDATA
303
20181112235536096 DLLC <0011> gprs_llc_parse.c:81 LLC SAPI=1 C   U GEA0 IOV-UI=0x000000 FCS=0x3be662 CMD=UI DATA 
304
20181112235536096 DMM <0002> gprs_gmm.c:2506 MM(010000000001100/c23c73ac) -> ACTIVATE PDP CONTEXT REQ: SAPI=3 NSAPI=5 IETF IPv4 
305
20181112235536096 DMM <0002> gprs_sgsn.c:927 MM(010000000001100/c23c73ac) Found GGSN 0 for APN 'cisco' (requested 'cisco')
306
20181112235536096 DMM <0002> gprs_gmm.c:2393 MM(010000000001100/c23c73ac) Using GGSN 0
307
20181112235536096 DLGLOBAL <001b> rate_ctr.c:88 validating counter group 0x55a732053340(sgsn:pdpctx) with 4 counters
308
20181112235536096 DLGTP <0023> pdp.c:237 Begin pdp_tidset tid = 5001100000000010
309
20181112235536096 DLGTP <0023> pdp.c:246 End pdp_tidset
310
20181112235536096 DGPRS <000e> sgsn_libgtp.c:175 PDP(010000000001100/0) Create PDP Context
311
20181112235536113 DGPRS <000e> sgsn_libgtp.c:625 libgtp cb_conf(type=16, cause=193, pdp=0x7f131df2d2e0, cbp=0x55a7328b25d0)
312
20181112235536113 DGPRS <000e> sgsn_libgtp.c:396 PDP(010000000001100/0) Received CREATE PDP CTX CONF, cause=193(Invalid message format)
313
20181112235536113 DLGTP <0023> pdp.c:255 Begin pdp_tiddel tid = 5001100000000010
314
20181112235536113 DLGTP <0023> pdp.c:262 End pdp_tiddel: PDP found
315
20181112235536113 DMM <0002> gprs_gmm.c:2317 MM(010000000001100/c23c73ac) <- ACTIVATE PDP CONTEXT REJ(cause=30)
316
20181112235536716 DBSSGP <0010> gprs_bssgp.c:892 BSSGP BVCI=1234 Rx Flow Control MS
317
20181112235538637 DBSSGP <0010> gprs_bssgp.c:892 BSSGP BVCI=1234 Rx Flow Control MS
318
20181112235539985 DLINP <001d> stream.c:506 reconnecting.
319
20181112235539986 DLINP <001d> stream.c:166 connection closed
320
20181112235539986 DLINP <001d> stream.c:169 retrying in 5 seconds...
321
20181112235541191 DBSSGP <0010> gprs_bssgp.c:892 BSSGP BVCI=1234 Rx Flow Control MS
322
20181112235542377 DNS <000f> gprs_ns.c:638 NSEI=1234 Timer expired in mode tns-test (30 seconds)
323
20181112235542377 DNS <000f> gprs_ns.c:571 NSEI=1234 Tx NS ALIVE (NSVCI=1234)
324
20181112235542377 DNS <000f> gprs_ns.c:610 NSEI=1234 Starting timer in mode tns-alive (3 seconds)
325
20181112235542379 DNS <000f> gprs_ns.c:584 NSEI=1234 Tx NS ALIVE_ACK (NSVCI=1234)
326
20181112235542380 DNS <000f> gprs_ns.c:610 NSEI=1234 Starting timer in mode tns-test (30 seconds)
327
20181112235543753 DBSSGP <0010> gprs_bssgp.c:892 BSSGP BVCI=1234 Rx Flow Control MS
328
20181112235544986 DLINP <001d> stream.c:506 reconnecting.
329
20181112235544986 DLINP <001d> stream.c:166 connection closed
330
20181112235544986 DLINP <001d> stream.c:169 retrying in 5 seconds...
331
^Csignal 2 received
332
osmocom-testing:~ # 
Add picture from clipboard (Maximum size: 48.8 MB)