Project

General

Profile

yate-with-yatebts-debug-cli.txt

yate -vvvvvv -CDoa - roox, 02/22/2018 08:53 PM

 
1
yate -vvvvvv -CDoa
2
Yate (8914) is starting Thu Feb 22 21:21:32 2018
3
2018-02-22_21:21:32.571169 <ALL> Plugin::Plugin("sip",false) [0x7f57f147c800]
4
Loaded module SIP Channel
5
2018-02-22_21:21:32.571389 <ALL> Plugin::Plugin("extmodule",false) [0x7f57f1212940]
6
Loaded module ExtModule
7
2018-02-22_21:21:32.571542 <ALL> Plugin::Plugin("cdrbuild",false) [0x7f57f0ffd1e0]
8
Loaded module CdrBuild
9
2018-02-22_21:21:32.571702 <ALL> Plugin::Plugin("cdrfile",true) [0x7f57f0df0240]
10
Loaded module CdrFile
11
2018-02-22_21:21:32.572785 <ALL> Plugin::Plugin("fax",false) [0x7f57f0beb4c0]
12
Loaded module Fax
13
2018-02-22_21:21:32.573063 <ALL> Plugin::Plugin("speexcodec",false) [0x7f57efbd51e0]
14
Loaded module Speex - based on libspeex-1.1.16
15
2018-02-22_21:21:32.573301 <ALL> Plugin::Plugin("callfork",false) [0x7f57ef7b4420]
16
Loaded module Call Forker
17
2018-02-22_21:21:32.573517 <ALL> Plugin::Plugin("gsmcodec",false) [0x7f57ef5a8140]
18
Loaded module GSM - based on libgsm-1.0.10
19
2018-02-22_21:21:32.573733 <ALL> Plugin::Plugin("conf",false) [0x7f57ef197480]
20
Loaded module Conference
21
2018-02-22_21:21:32.573894 <ALL> Plugin::Plugin("regexroute",false) [0x7f57eef86f00]
22
Loaded module RegexRoute
23
2018-02-22_21:21:32.574128 <ALL> Plugin::Plugin("enumroute",false) [0x7f57eed79200]
24
2018-02-22_21:21:32.574321 <ALL> Plugin::Plugin("ilbccodec",false) [0x7f57eeb72000]
25
Loaded module iLBC - based on iLBC reference library
26
2018-02-22_21:21:32.574732 <ALL> Plugin::Plugin("isaccodec",false) [0x7f57ee94f6e0]
27
Loaded module iSAC floating point - based on WebRTC iSAC library version 4.3.0 (SPL version 1.2.0)
28
2018-02-22_21:21:32.575233 <ALL> Plugin::Plugin("iax",false) [0x7f57ee7134c0]
29
Loaded module YIAX
30
2018-02-22_21:21:32.575376 <ALL> Plugin::Plugin("gvoice",false) [0x7f57ee4d7460]
31
Loaded module GVoice
32
2018-02-22_21:21:32.575507 <ALL> Plugin::Plugin("msgsniff",false) [0x7f57ee2d1220]
33
Loaded module MsgSniffer
34
2018-02-22_21:21:32.575698 <ALL> Plugin::Plugin("mux",true) [0x7f57ee0cb600]
35
Loaded module MUX
36
2018-02-22_21:21:32.576874 <ALL> Plugin::Plugin("jingle",false) [0x7f57edebfa40]
37
Loaded module YJingle
38
2018-02-22_21:21:32.577073 <ALL> Plugin::Plugin("filetransfer",false) [0x7f57eda2a820]
39
Loaded module File Transfer
40
2018-02-22_21:21:32.577324 <ALL> Plugin::Plugin("callgen",false) [0x7f57ed81a360]
41
Loaded module Call Generator
42
2018-02-22_21:21:32.577493 <ALL> Plugin::Plugin("moh",false) [0x7f57ed60f400]
43
Loaded module MOH
44
2018-02-22_21:21:32.578396 <ALL> Plugin::Plugin("javascript",true) [0x7f57ed4068a0]
45
Loaded module Javascript
46
2018-02-22_21:21:32.578832 <ALL> Plugin::Plugin("yrtp",false) [0x7f57ecf88f60]
47
Loaded module YRTP
48
2018-02-22_21:21:32.579085 <ALL> Plugin::Plugin("tonedetect",false) [0x7f57ecd612e0]
49
Loaded module ToneDetector
50
2018-02-22_21:21:32.579287 <ALL> Plugin::Plugin("rmanager",false) [0x7f57ecb585c0]
51
Loaded module RManager
52
2018-02-22_21:21:32.579444 <ALL> Plugin::Plugin("dumb",false) [0x7f57ec9471a0]
53
Loaded module DumbChannel
54
2018-02-22_21:21:32.579621 <ALL> Plugin::Plugin("wave",false) [0x7f57ec741900]
55
Loaded module WaveFile
56
2018-02-22_21:21:32.579848 <ALL> Plugin::Plugin("tone",false) [0x7f57ec5315e0]
57
Loaded module ToneGen
58
2018-02-22_21:21:32.579883 <tone:ALL> Building comfort noise at level -10
59
2018-02-22_21:21:32.580035 <tone:ALL> Building tone of 1336 + 941 Hz
60
2018-02-22_21:21:32.581599 <tone:ALL> Building tone of 1209 + 697 Hz
61
2018-02-22_21:21:32.583035 <tone:ALL> Building tone of 1336 + 697 Hz
62
2018-02-22_21:21:32.584456 <tone:ALL> Building tone of 1477 + 697 Hz
63
2018-02-22_21:21:32.585936 <tone:ALL> Building tone of 1209 + 770 Hz
64
2018-02-22_21:21:32.587376 <tone:ALL> Building tone of 1336 + 770 Hz
65
2018-02-22_21:21:32.588825 <tone:ALL> Building tone of 1477 + 770 Hz
66
2018-02-22_21:21:32.590273 <tone:ALL> Building tone of 1209 + 852 Hz
67
2018-02-22_21:21:32.591716 <tone:ALL> Building tone of 1336 + 852 Hz
68
2018-02-22_21:21:32.593150 <tone:ALL> Building tone of 1477 + 852 Hz
69
2018-02-22_21:21:32.594594 <tone:ALL> Building tone of 1209 + 941 Hz
70
2018-02-22_21:21:32.596027 <tone:ALL> Building tone of 1477 + 941 Hz
71
2018-02-22_21:21:32.597515 <tone:ALL> Building tone of 1633 + 697 Hz
72
2018-02-22_21:21:32.598942 <tone:ALL> Building tone of 1633 + 770 Hz
73
2018-02-22_21:21:32.600371 <tone:ALL> Building tone of 1633 + 852 Hz
74
2018-02-22_21:21:32.601807 <tone:ALL> Building tone of 1633 + 941 Hz
75
2018-02-22_21:21:32.603229 <tone:ALL> Building tone of 2000 + 125 Hz
76
2018-02-22_21:21:32.606940 <tone:ALL> Building tone of 2000 modulated by 1000 Hz
77
2018-02-22_21:21:32.611636 <tone:ALL> Building tone of 2010 Hz
78
2018-02-22_21:21:32.611991 <tone:ALL> Building tone of 1780 Hz
79
2018-02-22_21:21:32.612566 <ALL> Plugin::Plugin("zlibcompress",true) [0x7f57ec320220]
80
Loaded module ZLib - using zlib library version 1.2.11
81
2018-02-22_21:21:32.612766 <ALL> Plugin::Plugin("socks",true) [0x7f57ec119960]
82
Loaded module YSOCKS
83
2018-02-22_21:21:32.657402 <ALL> Plugin::Plugin("openssl",true) [0x7f57ebf025a0]
84
Loaded module OpenSSL - based on OpenSSL 1.1.0g-fips  2 Nov 2017
85
2018-02-22_21:21:32.657618 <ALL> Plugin::Plugin("stun",false) [0x7f57eb6023a0]
86
Loaded module YSTUN
87
2018-02-22_21:21:32.657960 <ALL> Plugin::Plugin("ilbcwebrtc",false) [0x7f57eb3f8580]
88
Loaded module iLBC - based on WebRTC iLBC library version 1.1.1
89
2018-02-22_21:21:32.658110 <ALL> Plugin::Plugin("pbx",false) [0x7f57eb1e0480]
90
Loaded module PBX
91
2018-02-22_21:21:32.658285 <ALL> Plugin::Plugin("cdrcombine",false) [0x7f57eafd9900]
92
Loaded module CdrCombine
93
2018-02-22_21:21:32.658445 <ALL> Plugin::Plugin("fileinfo",false) [0x7f57eadd3b20]
94
Loaded module FileInfo
95
2018-02-22_21:21:32.658659 <ALL> Plugin::Plugin("analyzer",false) [0x7f57eabc8420]
96
Loaded module Analyzer
97
2018-02-22_21:21:32.658941 <ALL> Plugin::Plugin("presence",false) [0x7f57ea9bd2a0]
98
Loaded module Presence
99
2018-02-22_21:21:32.659133 <ALL> Plugin::Plugin("regfile",false) [0x7f57ea7b4380]
100
Loaded module Registration from file
101
2018-02-22_21:21:32.659309 <ALL> Plugin::Plugin("eventlogs",true) [0x7f57ea5ab2e0]
102
Loaded module Event Logs
103
2018-02-22_21:21:32.659536 <ALL> Plugin::Plugin("register",false) [0x7f57ea3a6600]
104
Loaded module Register for database
105
2018-02-22_21:21:32.660544 <ALL> Plugin::Plugin("sqlitedb",true) [0x7f57ea198380]
106
Loaded module SQLite based on 3.22.0
107
2018-02-22_21:21:32.660763 <ALL> Plugin::Plugin("yradius",false) [0x7f57e9c86a40]
108
Loaded module Radius client
109
2018-02-22_21:21:32.660903 <ALL> Plugin::Plugin("clustering",true) [0x7f57e9a751e0]
110
Loaded module Clustering
111
2018-02-22_21:21:32.665303 <ALL> Plugin::Plugin("ciscosm",true) [0x7f57e986f540]
112
Loaded module Cisco SM
113
2018-02-22_21:21:32.665487 <ALL> Plugin::Plugin("park",false) [0x7f57e910a240]
114
Loaded module Call Parking
115
2018-02-22_21:21:32.665643 <ALL> Plugin::Plugin("queues",false) [0x7f57e8f04580]
116
Loaded module Queues
117
2018-02-22_21:21:32.665832 <ALL> Plugin::Plugin("queuesnotify",false) [0x7f57e8cfb480]
118
Loaded module Queues Notify
119
2018-02-22_21:21:32.666038 <ALL> Plugin::Plugin("monitoring",false) [0x7f57e8af1280]
120
Loaded module Monitoring
121
2018-02-22_21:21:32.666214 <ALL> Plugin::Plugin("heartbeat",false) [0x7f57e88d5260]
122
Loaded module Heartbeat
123
2018-02-22_21:21:32.666394 <ALL> Plugin::Plugin("ccongestion",false) [0x7f57e86ce2c0]
124
Loaded module CCongestion
125
2018-02-22_21:21:32.666626 <ALL> Plugin::Plugin("accfile",false) [0x7f57e84c8240]
126
Loaded module Accounts from file
127
2018-02-22_21:21:32.666770 <ALL> Plugin::Plugin("lateroute",false) [0x7f57e82c2240]
128
Loaded module Late Router
129
2018-02-22_21:21:32.667038 <ALL> Plugin::Plugin("sigtransport",true) [0x7f57e80bd700]
130
Loaded module SigTransport
131
2018-02-22_21:21:32.667800 <ALL> Plugin::Plugin("mgcpca",true) [0x7f57e7ead240]
132
Loaded module MGCP-CA
133
2018-02-22_21:21:32.668124 <ALL> Plugin::Plugin("mgcpgw",false) [0x7f57e7a740e0]
134
Loaded module MGCP-GW
135
2018-02-22_21:21:32.668304 <ALL> Plugin::Plugin("dbwave",true) [0x7f57e7857260]
136
Loaded module DbWave
137
2018-02-22_21:21:32.668468 <ALL> Plugin::Plugin("sipfeatures",false) [0x7f57e7651380]
138
Loaded module SIP Features
139
2018-02-22_21:21:32.668764 <ALL> Plugin::Plugin("dbpbx",false) [0x7f57e7448280]
140
Loaded module PBX for database
141
2018-02-22_21:21:32.668963 <ALL> Plugin::Plugin("pbxassist",false) [0x7f57e723f3c0]
142
2018-02-22_21:21:32.669131 <ALL> Plugin::Plugin("callcounters",false) [0x7f57e702f1c0]
143
Loaded module CallCounters
144
2018-02-22_21:21:32.669355 <ALL> Plugin::Plugin("subscription",true) [0x7f57e6e297a0]
145
Loaded module Subscriptions
146
2018-02-22_21:21:32.669715 <ALL> Plugin::Plugin("analog",false) [0x7f57e6c15680]
147
Loaded module Analog Channel
148
2018-02-22_21:21:32.670454 <ALL> Plugin::Plugin("ybts",false) [0x7f57e69fb440]
149
Loaded module YBTS
150
2018-02-22_21:21:32.670682 <ALL> Plugin::Plugin("cache",false) [0x7f57e6570920]
151
Loaded module Cache
152
2018-02-22_21:21:32.671036 <ALL> Plugin::Plugin("analogdetect",true) [0x7f57e63609c0]
153
Loaded module Analog Detector
154
2018-02-22_21:21:32.671501 <libyatemodem:INFO> Initialized filter tables for type 'etsi' headerlen=6400
155
2018-02-22_21:21:32.673913 <ALL> Plugin::Plugin("snmpagent",false) [0x7f57e6150f40]
156
Loaded module SNMP Agent
157
2018-02-22_21:21:32.674081 <ALL> Plugin::Plugin("mrcp",false) [0x7f57e5f0d280]
158
Loaded module MRCP
159
2018-02-22_21:21:32.674235 <ALL> Plugin::Plugin("users",false) [0x7f57e5d05260]
160
Loaded module Users Management
161
2018-02-22_21:21:32.675547 <ALL> Plugin::Plugin("gsmtrx",false) [0x7f57e5afe060]
162
Loaded module GSM Transceiver
163
2018-02-22_21:21:32.675727 <ALL> Plugin::Plugin("cpuload",true) [0x7f57e58c63e0]
164
Loaded module Cpu
165
2018-02-22_21:21:32.676504 <ALL> Plugin::Plugin("sig",false) [0x7f57e56bd000]
166
Loaded module Signalling Channel
167
2018-02-22_21:21:32.677085 <ALL> Plugin::Plugin("jabber",true) [0x7f57e548fd20]
168
Loaded module Jabber Server
169
2018-02-22_21:21:32.677494 <ALL> Plugin::Plugin("jbfeatures",true) [0x7f57e5264420]
170
Loaded module Jabber Server Features
171
2018-02-22_21:21:32.679872 <ALL> Plugin::Plugin("bladerf",true) [0x7f57e5058280]
172
Loaded module BladeRF using libusb 1.0.21.11156 desc='http://libusb.info'
173
2018-02-22_21:21:32.680226 <ALL> Plugin::Plugin("radiotest",false) [0x7f57e3ef3ce0]
174
Loaded module Radio Test
175
2018-02-22_21:21:32.680602 <ALL> Plugin::Plugin("dummyradio",true) [0x7f57e3ce2700]
176
Loaded module DummyRadio
177
2018-02-22_21:21:32.680651 <ALL> Loaded 77 plugins
178
Initializing plugins
179
Initializing module DummyRadio
180
Initializing module BladeRF
181
Initializing module Jabber Server Features
182
Initializing module Jabber Server
183
2018-02-22_21:21:32.681418 <jabber:ALL> JBPendingWorker(0) start running [0x564a868e7f50]
184
2018-02-22_21:21:32.681501 <jbserverengine:NOTE> Failed to load entity caps from '/etc/yate/jabberentitycaps.xml': I/O error 2 'No such file or directory'
185
2018-02-22_21:21:32.681542 <jbserverengine:NOTE> No domains configured
186
Initializing module Cpu
187
2018-02-22_21:21:32.681979 <cpuload:NOTE> Updating CPU core number from 1 to 2
188
Initializing module Analog Detector
189
Initializing module Subscriptions
190
Initializing module DbWave
191
Initializing module MGCP Call Agent
192
2018-02-22_21:21:32.682418 <mgcpca:ALL> No gateways defined so module not initialized.
193
2018-02-22_21:21:32.687145 <mgcpca:ALL> Initialized audio codecs: mulaw,alaw,gsm,slin,ilbc20,ilbc30,speex,speex/16000,speex/32000,isac/16000,isac/32000
194
Initializing module SigTransport
195
Initializing module Cisco SM
196
Initializing module Clustering
197
Initializing module SQLite
198
Initializing module Event Logs
199
Initializing module OpenSSL
200
Initializing module YSOCKS
201
2018-02-22_21:21:32.688451 <socks:INFO> Initialized auth-timeout=10000ms reply-timeout=30000ms print-msg=false print-extended=false
202
Initializing module ZLib
203
2018-02-22_21:21:32.688526 <zlibcompress:ALL> Initialized compressor_buflen=256 decompressor_buflen=1024 compress_level=default
204
Initializing module Javascript
205
2018-02-22_21:21:32.689007 <javascript:INFO> Parsed routing script: /usr/share/yate/scripts/welcome.js
206
2018-02-22_21:21:32.715458 <javascript:INFO> Parsed 'nib' script: /usr/share/yate/scripts/nipc.js
207
2018-02-22_21:21:32.719098 <nipc:INFO> Finished reading saved registered subscribers. Found 1 registered_subscribers.
208
2018-02-22_21:21:32.719317 <nipc:INFO> Checked that only NiPC is enabled.
209
Initializing module MUX
210
Initializing module CdrFile
211
Initializing module SIP Channel
212
2018-02-22_21:21:32.719818 <sip:ALL> Initialized audio codecs: mulaw,alaw,gsm,slin,ilbc20,ilbc30,speex,speex/16000,speex/32000,isac/16000,isac/32000
213
2018-02-22_21:21:32.719999 <sip:ALL> YateSIPEndPoint::YateSIPEndPoint(normal) [0x564a86abf880]
214
2018-02-22_21:21:32.720122 <sip:ALL> Transport(general) created [0x564a86aba500]
215
2018-02-22_21:21:32.720165 <sip:ALL> Listener(UDP,'general') initialized addr=':5060' default=true maxpkt=1500 rtp_localip=(null) nat_address=(null) [0x564a86aba500]
216
2018-02-22_21:21:32.720230 <sip:INFO> Default UDP transport is 'general'
217
Initializing module ExtModule
218
Initializing module CdrBuild
219
Initializing module Fax
220
Initializing module Call Forker
221
2018-02-22_21:21:32.720554 <callfork:INFO> Default fork for 'chan.dtmf' matching 'peerid' priority 100
222
Initializing module Conference
223
Initializing module RegexRoute
224
Initializing module iSAC
225
Initializing module YIAX
226
2018-02-22_21:21:32.721022 <sip:ALL> Listener(UDP,'general') initializing socket addr='(null)' port=5060
227
2018-02-22_21:21:32.721320 <iaxengine:INFO> Bound on '0.0.0.0:4569' [0x564a86ac49c0]
228
2018-02-22_21:21:32.721355 <iaxengine:ALL> Adjust ts out set to thres=120 over=120 under=60 [0x564a86ac49c0]
229
2018-02-22_21:21:32.721393 <iaxengine:INFO> Status changed Idle -> Listening [0x564a86ac49c0]
230
2018-02-22_21:21:32.721428 <iax:INFO> Added listener (0x564a86ac49c0) 'iaxengine' status='Listening'
231
2018-02-22_21:21:32.721604 <sip:INFO> Listener(UDP,'general') started on ':5060' (IPv4)
232
2018-02-22_21:21:32.721644 <iaxengine:ALL> Created 3 'YIAXListener' threads [0x564a86ac49c0]
233
2018-02-22_21:21:32.721768 <iaxengine:ALL> Created 3 'YIAXGetEvent' threads [0x564a86ac49c0]
234
2018-02-22_21:21:32.722043 <iaxengine:ALL> Created 1 'YIAXTrunking' threads [0x564a86ac49c0]
235
2018-02-22_21:21:32.722072 <iax:INFO> Default listener is 'iaxengine'
236
Initializing module GVoice
237
Initializing module MsgSniffer
238
2018-02-22_21:21:32.722215 <INFO> Registered broadcast message handler 0x564a86abbff0
239
Initializing module YJingle
240
2018-02-22_21:21:32.722613 <jgengine:ALL> Jingle engine initialized: jingle_flags=0 stanza_timeout=20000 ping_interval=300000 [0x564a86abc200]
241
2018-02-22_21:21:32.722652 <jingle:ALL> YJGEngineWorker start running
242
Initializing module File Transfer
243
2018-02-22_21:21:32.723147 <filetransfer:INFO> Initialized send_chunk_size=4096 send_interval=50ms send_linger_intervals=20 notify_progress=false
244
Initializing module Call Generator
245
Initializing module MOH
246
2018-02-22_21:21:32.723326 <CallGen:INFO> CleanThread::run() [0x564a86a4b8f0]
247
Initializing module YRTP
248
Initializing module ToneDetector
249
Initializing module RManager
250
2018-02-22_21:21:32.723676 <RManager:INFO> Starting listener 'general' on 127.0.0.1:5038
251
Initializing module DumbChannel
252
DumbChannel initialized
253
Initializing module WaveFile
254
Initializing module ToneGen
255
2018-02-22_21:21:32.724766 <tone:ALL> ToneDesc(at/dial) initializing from '420' [0x564a86adde60]
256
2018-02-22_21:21:32.724792 <tone:ALL> Building tone of 420 Hz
257
2018-02-22_21:21:32.725156 <tone:ALL> ToneDesc(at/busy) initializing from '420/400,0/400' [0x564a86ad6490]
258
2018-02-22_21:21:32.725179 <tone:ALL> ToneDesc(at/ring) initializing from '420/1000,0/5000' [0x564a86ad66a0]
259
2018-02-22_21:21:32.725232 <tone:ALL> ToneDesc(at/congestion) initializing from '420/200,0/200' [0x564a86ad6840]
260
2018-02-22_21:21:32.725255 <tone:ALL> ToneDesc(at/callwaiting) initializing from '420/40,0/1960' [0x564a86ad6a60]
261
2018-02-22_21:21:32.725279 <tone:ALL> ToneDesc(at/dialrecall) initializing from '420' [0x564a86ad6c80]
262
2018-02-22_21:21:32.725301 <tone:ALL> ToneDesc(at/record) initializing from '1400/80,0/14920' [0x564a86ad6de0]
263
2018-02-22_21:21:32.725323 <tone:ALL> Building tone of 1400 Hz
264
2018-02-22_21:21:32.725795 <tone:ALL> ToneDesc(at/info) initializing from '950/330,1450/330,1850/330,0/1000' [0x564a86ad7180]
265
2018-02-22_21:21:32.725816 <tone:ALL> Building tone of 950 Hz
266
2018-02-22_21:21:32.726218 <tone:ALL> Building tone of 1450 Hz
267
2018-02-22_21:21:32.726617 <tone:ALL> Building tone of 1850 Hz
268
2018-02-22_21:21:32.727035 <tone:ALL> ToneDesc(at/stutter) initializing from '380+420' [0x564a86aecdf0]
269
2018-02-22_21:21:32.727056 <tone:ALL> Building tone of 420 + 380 Hz
270
2018-02-22_21:21:32.728567 <tone:ALL> ToneDesc(au/dial) initializing from '413+438' [0x564a86af0e70]
271
2018-02-22_21:21:32.728592 <tone:ALL> Building tone of 438 + 413 Hz
272
2018-02-22_21:21:32.730091 <tone:ALL> ToneDesc(au/busy) initializing from '425/375,0/375' [0x564a86af4fb0]
273
2018-02-22_21:21:32.730116 <tone:ALL> Building tone of 425 Hz
274
2018-02-22_21:21:32.730907 <tone:ALL> ToneDesc(au/ring) initializing from '413+438/400,0/200,413+438/400,0/2000' [0x564a86af9200]
275
2018-02-22_21:21:32.730940 <tone:ALL> ToneDesc(au/congestion) initializing from '425/375,0/375,420/375,0/375' [0x564a86af94d0]
276
2018-02-22_21:21:32.730970 <tone:ALL> ToneDesc(au/callwaiting) initializing from '425/200,0/200,425/200,0/4400' [0x564a86af9730]
277
2018-02-22_21:21:32.730994 <tone:ALL> ToneDesc(au/dialrecall) initializing from '413+438' [0x564a86af9990]
278
2018-02-22_21:21:32.731017 <tone:ALL> ToneDesc(au/record) initializing from '!425/1000,!0/15000,425/360,0/15000' [0x564a86af9b00]
279
2018-02-22_21:21:32.731047 <tone:ALL> ToneDesc(au/info) initializing from '425/2500,0/500' [0x564a86af9dc0]
280
2018-02-22_21:21:32.731072 <tone:ALL> ToneDesc(au/std) initializing from '!525/100,!0/100,!525/100,!0/100,!525/100,!0/100,!525/100,!0/100,!525/100' [0x564a86af9f40]
281
2018-02-22_21:21:32.731101 <tone:ALL> Building tone of 525 Hz
282
2018-02-22_21:21:32.731906 <tone:ALL> ToneDesc(au/facility) initializing from '425' [0x564a86afe340]
283
2018-02-22_21:21:32.731933 <tone:ALL> ToneDesc(au/stutter) initializing from '413+438/100,0/40' [0x564a86afe4a0]
284
2018-02-22_21:21:32.731981 <tone:ALL> ToneDesc(au/ringmobile) initializing from '400+450/400,0/200,400+450/400,0/2000' [0x564a86afe620]
285
2018-02-22_21:21:32.732015 <tone:ALL> Building tone of 450 + 400 Hz
286
2018-02-22_21:21:32.734072 <tone:ALL> ToneDesc(bg/dial) initializing from '425' [0x564a86b02700]
287
2018-02-22_21:21:32.734099 <tone:ALL> ToneDesc(bg/busy) initializing from '425/500,0/500' [0x564a86b02860]
288
2018-02-22_21:21:32.734126 <tone:ALL> ToneDesc(bg/ring) initializing from '425/1000,0/4000' [0x564a86b029e0]
289
2018-02-22_21:21:32.734151 <tone:ALL> ToneDesc(bg/congestion) initializing from '425/250,0/250' [0x564a86b02bd0]
290
2018-02-22_21:21:32.734178 <tone:ALL> ToneDesc(bg/callwaiting) initializing from '425/150,0/150,425/150,0/4000' [0x564a86b02df0]
291
2018-02-22_21:21:32.734210 <tone:ALL> ToneDesc(bg/dialrecall) initializing from '!425/100,!0/100,!425/100,!0/100,!425/100,!0/100,425' [0x564a86b03150]
292
2018-02-22_21:21:32.734240 <tone:ALL> ToneDesc(bg/record) initializing from '1400/425,0/15000' [0x564a86b034d0]
293
2018-02-22_21:21:32.734264 <tone:ALL> ToneDesc(bg/info) initializing from '950/330,1400/330,1800/330,0/1000' [0x564a86b03650]
294
2018-02-22_21:21:32.734291 <tone:ALL> Building tone of 1800 Hz
295
2018-02-22_21:21:32.734817 <tone:ALL> ToneDesc(bg/stutter) initializing from '425/1500,0/100' [0x564a86b05820]
296
2018-02-22_21:21:32.734849 <tone:ALL> ToneDesc(br/dial) initializing from '425' [0x564a86b059a0]
297
2018-02-22_21:21:32.734877 <tone:ALL> ToneDesc(br/busy) initializing from '425/250,0/250' [0x564a86b05b00]
298
2018-02-22_21:21:32.734902 <tone:ALL> ToneDesc(br/ring) initializing from '425/1000,0/4000' [0x564a86b05d40]
299
2018-02-22_21:21:32.734924 <tone:ALL> ToneDesc(br/congestion) initializing from '425/250,0/250,425/750,0/250' [0x564a86b05f60]
300
2018-02-22_21:21:32.734957 <tone:ALL> ToneDesc(br/callwaiting) initializing from '425/50,0/1000' [0x564a86b062c0]
301
2018-02-22_21:21:32.735005 <tone:ALL> ToneDesc(br/dialrecall) initializing from '350+440' [0x564a86b06440]
302
2018-02-22_21:21:32.735027 <tone:ALL> Building tone of 440 + 350 Hz
303
2018-02-22_21:21:32.736566 <tone:ALL> ToneDesc(br/record) initializing from '425/250,0/250' [0x564a86b0a500]
304
2018-02-22_21:21:32.736598 <tone:ALL> ToneDesc(br/info) initializing from '950/330,1400/330,1800/330' [0x564a86b0a780]
305
2018-02-22_21:21:32.736628 <tone:ALL> ToneDesc(br/stutter) initializing from '350+440' [0x564a86b0aa40]
306
2018-02-22_21:21:32.736660 <tone:ALL> ToneDesc(be/dial) initializing from '425' [0x564a86b0aba0]
307
2018-02-22_21:21:32.736684 <tone:ALL> ToneDesc(be/busy) initializing from '425/500,0/500' [0x564a86b0ad00]
308
2018-02-22_21:21:32.736714 <tone:ALL> ToneDesc(be/ring) initializing from '425/1000,0/3000' [0x564a86b0afc0]
309
2018-02-22_21:21:32.736739 <tone:ALL> ToneDesc(be/congestion) initializing from '425/167,0/167' [0x564a86b0b1e0]
310
2018-02-22_21:21:32.736763 <tone:ALL> ToneDesc(be/callwaiting) initializing from '1400/175,0/175,1400/175,0/3500' [0x564a86b0b400]
311
2018-02-22_21:21:32.736791 <tone:ALL> ToneDesc(be/dialrecall) initializing from '!350+440/100,!0/100,!350+440/100,!0/100,!350+440/100,!0/100,350+440"' [0x564a86b0b760]
312
2018-02-22_21:21:32.736826 <tone:ALL> ToneDesc(be/record) initializing from '1400/500,0/15000' [0x564a86b0bb90]
313
2018-02-22_21:21:32.736854 <tone:ALL> ToneDesc(be/info) initializing from '900/330,1400/330,1800/330,0/1000' [0x564a86b0bd10]
314
2018-02-22_21:21:32.736877 <tone:ALL> Building tone of 900 Hz
315
2018-02-22_21:21:32.737227 <CallGen:INFO> GenThread::run() [0x564a869f1720]
316
2018-02-22_21:21:32.737457 <tone:ALL> ToneDesc(be/stutter) initializing from '425/1000,0/250' [0x564a86b0dee0]
317
2018-02-22_21:21:32.737491 <tone:ALL> ToneDesc(ch/dial) initializing from '425' [0x564a86b0e060]
318
2018-02-22_21:21:32.737516 <tone:ALL> ToneDesc(ch/busy) initializing from '425/500,0/500' [0x564a86b0e1c0]
319
2018-02-22_21:21:32.737540 <tone:ALL> ToneDesc(ch/ring) initializing from '425/1000,0/4000' [0x564a86b0e400]
320
2018-02-22_21:21:32.737565 <tone:ALL> ToneDesc(ch/congestion) initializing from '425/200,0/200' [0x564a86b0e620]
321
2018-02-22_21:21:32.737590 <tone:ALL> ToneDesc(ch/callwaiting) initializing from '425/200,0/200,425/200,0/4000' [0x564a86b0e840]
322
2018-02-22_21:21:32.737618 <tone:ALL> ToneDesc(ch/dialrecall) initializing from '!425/100,!0/100,!425/100,!0/100,!425/100,!0/100,425' [0x564a86b0eba0]
323
2018-02-22_21:21:32.737651 <tone:ALL> ToneDesc(ch/record) initializing from '1400/80,0/15000' [0x564a86b0efc0]
324
2018-02-22_21:21:32.737678 <tone:ALL> ToneDesc(ch/info) initializing from '950/330,1400/330,1800/330,0/1000' [0x564a86b0f140]
325
2018-02-22_21:21:32.737710 <tone:ALL> ToneDesc(ch/stutter) initializing from '425+340/1100,0/1100' [0x564a86b0f300]
326
2018-02-22_21:21:32.737733 <tone:ALL> Building tone of 425 + 340 Hz
327
2018-02-22_21:21:32.739325 <tone:ALL> ToneDesc(cl/dial) initializing from '400' [0x564a86b133a0]
328
2018-02-22_21:21:32.739349 <tone:ALL> Building tone of 400 Hz
329
2018-02-22_21:21:32.739969 <tone:ALL> ToneDesc(cl/busy) initializing from '400/500,0/500' [0x564a86b15540]
330
2018-02-22_21:21:32.740001 <tone:ALL> ToneDesc(cl/ring) initializing from '400/1000,0/3000' [0x564a86b157e0]
331
2018-02-22_21:21:32.740030 <tone:ALL> ToneDesc(cl/congestion) initializing from '400/200,0/200' [0x564a86b15a00]
332
2018-02-22_21:21:32.740055 <tone:ALL> ToneDesc(cl/callwaiting) initializing from '400/250,0/8750' [0x564a86b15c20]
333
2018-02-22_21:21:32.740085 <tone:ALL> ToneDesc(cl/dialrecall) initializing from '!400/100,!0/100,!400/100,!0/100,!400/100,!0/100,400' [0x564a86b15e40]
334
2018-02-22_21:21:32.740121 <tone:ALL> ToneDesc(cl/record) initializing from '1400/500,0/15000' [0x564a86b16360]
335
2018-02-22_21:21:32.740146 <tone:ALL> ToneDesc(cl/info) initializing from '950/333,1400/333,1800/333,0/1000' [0x564a86b164e0]
336
2018-02-22_21:21:32.740175 <tone:ALL> ToneDesc(cl/stutter) initializing from '!400/100,!0/100,!400/100,!0/100,!400/100,!0/100,!400/100,!0/100,!400/100,!0/100,!400/100,!0/100,400' [0x564a86b166a0]
337
2018-02-22_21:21:32.740215 <tone:ALL> ToneDesc(cn/dial) initializing from '450' [0x564a86b16e20]
338
2018-02-22_21:21:32.740242 <tone:ALL> Building tone of 450 Hz
339
2018-02-22_21:21:32.740670 <tone:ALL> ToneDesc(cn/busy) initializing from '450/350,0/350' [0x564a86b16880]
340
2018-02-22_21:21:32.740698 <tone:ALL> ToneDesc(cn/ring) initializing from '450/1000,0/4000' [0x564a86b16a30]
341
2018-02-22_21:21:32.740727 <tone:ALL> ToneDesc(cn/congestion) initializing from '450/700,0/700' [0x564a86b18f80]
342
2018-02-22_21:21:32.740765 <tone:ALL> ToneDesc(cn/callwaiting) initializing from '450/400,0/4000' [0x564a86b19170]
343
2018-02-22_21:21:32.740800 <tone:ALL> ToneDesc(cn/dialrecall) initializing from '450' [0x564a86b19390]
344
2018-02-22_21:21:32.740848 <tone:ALL> ToneDesc(cn/record) initializing from '950/400,0/10000' [0x564a86b194f0]
345
2018-02-22_21:21:32.740874 <tone:ALL> ToneDesc(cn/info) initializing from '450/100,0/100,450/100,0/100,450/100,0/100,450/400,0/400' [0x564a86b19770]
346
2018-02-22_21:21:32.740908 <tone:ALL> ToneDesc(cn/stutter) initializing from '450+425' [0x564a86b19d40]
347
2018-02-22_21:21:32.740931 <tone:ALL> Building tone of 450 + 425 Hz
348
2018-02-22_21:21:32.742494 <tone:ALL> ToneDesc(cz/dial) initializing from '425/330,0/330,425/660,0/660' [0x564a86b19a80]
349
2018-02-22_21:21:32.742523 <tone:ALL> ToneDesc(cz/busy) initializing from '425/330,0/330' [0x564a86b1df40]
350
2018-02-22_21:21:32.742554 <tone:ALL> ToneDesc(cz/ring) initializing from '425/1000,0/4000' [0x564a86b1e080]
351
2018-02-22_21:21:32.742577 <tone:ALL> ToneDesc(cz/congestion) initializing from '425/165,0/165' [0x564a86b1e270]
352
2018-02-22_21:21:32.742615 <tone:ALL> ToneDesc(cz/callwaiting) initializing from '425/330,0/9000' [0x564a86b1e490]
353
2018-02-22_21:21:32.742639 <tone:ALL> ToneDesc(cz/dialrecall) initializing from '!425/100,!0/100,!425/100,!0/100,!425/100,!0/100,425/330,0/330,425/660,0/660' [0x564a86b1e6b0]
354
2018-02-22_21:21:32.742672 <tone:ALL> ToneDesc(cz/record) initializing from '1400/500,0/14000' [0x564a86b1ecf0]
355
2018-02-22_21:21:32.742699 <tone:ALL> ToneDesc(cz/info) initializing from '950/330,0/30,1400/330,0/30,1800/330,0/1000' [0x564a86b1ee70]
356
2018-02-22_21:21:32.742733 <tone:ALL> ToneDesc(cz/stutter) initializing from '425/450,0/50' [0x564a86b1f060]
357
2018-02-22_21:21:32.742760 <tone:ALL> ToneDesc(de/dial) initializing from '425' [0x564a86b1f1e0]
358
2018-02-22_21:21:32.742783 <tone:ALL> ToneDesc(de/busy) initializing from '425/480,0/480' [0x564a86b1f340]
359
2018-02-22_21:21:32.742806 <tone:ALL> ToneDesc(de/ring) initializing from '425/1000,0/4000' [0x564a86b1f4c0]
360
2018-02-22_21:21:32.742830 <tone:ALL> ToneDesc(de/congestion) initializing from '425/240,0/240' [0x564a86b1f680]
361
2018-02-22_21:21:32.742854 <tone:ALL> ToneDesc(de/callwaiting) initializing from '!425/200,!0/200,!425/200,!0/5000,!425/200,!0/200,!425/200,!0/5000,!425/200,!0/200,!425/200,!0/5000,!425/200,!0/200,!425/200,!0/5000,!425/200,!0/200,!425/200,0' [0x564a86b1f840]
362
2018-02-22_21:21:32.742929 <tone:ALL> ToneDesc(de/dialrecall) initializing from '!425/100,!0/100,!425/100,!0/100,!425/100,!0/100,425' [0x564a86b20540]
363
2018-02-22_21:21:32.742973 <tone:ALL> ToneDesc(de/record) initializing from '1400/80,0/15000' [0x564a86b20760]
364
2018-02-22_21:21:32.743017 <tone:ALL> ToneDesc(de/info) initializing from '950/330,1400/330,1800/330,0/1000' [0x564a86b208e0]
365
2018-02-22_21:21:32.743060 <tone:ALL> ToneDesc(de/stutter) initializing from '425+400' [0x564a86b20aa0]
366
2018-02-22_21:21:32.743091 <tone:ALL> Building tone of 425 + 400 Hz
367
2018-02-22_21:21:32.745050 <tone:ALL> ToneDesc(dk/dial) initializing from '425' [0x564a86b201e0]
368
2018-02-22_21:21:32.745085 <tone:ALL> ToneDesc(dk/busy) initializing from '425/500,0/500' [0x564a86b1fb60]
369
2018-02-22_21:21:32.745114 <tone:ALL> ToneDesc(dk/ring) initializing from '425/1000,0/4000' [0x564a86b1fd60]
370
2018-02-22_21:21:32.745137 <tone:ALL> ToneDesc(dk/congestion) initializing from '425/200,0/200' [0x564a86b24b60]
371
2018-02-22_21:21:32.745169 <tone:ALL> ToneDesc(dk/callwaiting) initializing from '!425/200,!0/600,!425/200,!0/3000,!425/200,!0/200,!425/200,0' [0x564a86b24d80]
372
2018-02-22_21:21:32.745227 <tone:ALL> ToneDesc(dk/dialrecall) initializing from '!425/100,!0/100,!425/100,!0/100,!425/100,!0/100,425' [0x564a86b25360]
373
2018-02-22_21:21:32.745259 <tone:ALL> ToneDesc(dk/record) initializing from '1400/80,0/15000' [0x564a86b25580]
374
2018-02-22_21:21:32.745288 <tone:ALL> ToneDesc(dk/info) initializing from '950/330,1400/330,1800/330,0/1000' [0x564a86b25700]
375
2018-02-22_21:21:32.745324 <tone:ALL> ToneDesc(dk/stutter) initializing from '425/450,0/50' [0x564a86b258c0]
376
2018-02-22_21:21:32.745353 <tone:ALL> ToneDesc(ee/dial) initializing from '425' [0x564a86b25a40]
377
2018-02-22_21:21:32.745378 <tone:ALL> ToneDesc(ee/busy) initializing from '425/300,0/300' [0x564a86b25ba0]
378
2018-02-22_21:21:32.745404 <tone:ALL> ToneDesc(ee/ring) initializing from '425/1000,0/4000' [0x564a86b25d80]
379
2018-02-22_21:21:32.745435 <tone:ALL> ToneDesc(ee/congestion) initializing from '425/200,0/200' [0x564a86b25fa0]
380
2018-02-22_21:21:32.745481 <tone:ALL> ToneDesc(ee/callwaiting) initializing from '950/650,0/325,950/325,0/30,1400/1300,0/2600' [0x564a86b261c0]
381
2018-02-22_21:21:32.745517 <tone:ALL> ToneDesc(ee/dialrecall) initializing from '425/650,0/25' [0x564a86b26660]
382
2018-02-22_21:21:32.745544 <tone:ALL> ToneDesc(ee/record) initializing from '1400/500,0/15000' [0x564a86b267e0]
383
2018-02-22_21:21:32.745572 <tone:ALL> ToneDesc(ee/info) initializing from '950/650,0/325,950/325,0/30,1400/1300,0/2600' [0x564a86b26960]
384
2018-02-22_21:21:32.745609 <tone:ALL> ToneDesc(ee/stutter) initializing from '!425/100,!0/100,!425/100,!0/100,!425/100,!0/100,!425/100,!0/100,!425/100,!0/100,!425/100,!0/100,425' [0x564a86b26d00]
385
2018-02-22_21:21:32.745662 <tone:ALL> ToneDesc(es/dial) initializing from '425' [0x564a86b273e0]
386
2018-02-22_21:21:32.745694 <tone:ALL> ToneDesc(es/busy) initializing from '425/200,0/200' [0x564a86b27540]
387
2018-02-22_21:21:32.745727 <tone:ALL> ToneDesc(es/ring) initializing from '425/1500,0/3000' [0x564a86b276c0]
388
2018-02-22_21:21:32.745759 <tone:ALL> ToneDesc(es/congestion) initializing from '425/200,0/200,425/200,0/200,425/200,0/600' [0x564a86b27840]
389
2018-02-22_21:21:32.745796 <tone:ALL> ToneDesc(es/callwaiting) initializing from '425/175,0/175,425/175,0/3500' [0x564a86b27a60]
390
2018-02-22_21:21:32.745831 <tone:ALL> ToneDesc(es/dialrecall) initializing from '!425/200,!0/200,!425/200,!0/200,!425/200,!0/200,425' [0x564a86b27c20]
391
2018-02-22_21:21:32.745869 <tone:ALL> ToneDesc(es/record) initializing from '1400/500,0/15000' [0x564a86b27ec0]
392
2018-02-22_21:21:32.745906 <tone:ALL> ToneDesc(es/info) initializing from '950/330,0/1000' [0x564a86b28040]
393
2018-02-22_21:21:32.745932 <tone:ALL> ToneDesc(es/dialout) initializing from '500' [0x564a86b281c0]
394
2018-02-22_21:21:32.745957 <tone:ALL> Building tone of 500 Hz
395
2018-02-22_21:21:32.746645 <tone:ALL> ToneDesc(fi/dial) initializing from '425' [0x564a86b2a300]
396
2018-02-22_21:21:32.746672 <tone:ALL> ToneDesc(fi/busy) initializing from '425/300,0/300' [0x564a86b2a460]
397
2018-02-22_21:21:32.746705 <tone:ALL> ToneDesc(fi/ring) initializing from '425/1000,0/4000' [0x564a86b2a6d0]
398
2018-02-22_21:21:32.746736 <tone:ALL> ToneDesc(fi/congestion) initializing from '425/200,0/200' [0x564a86b2a8f0]
399
2018-02-22_21:21:32.746766 <tone:ALL> ToneDesc(fi/callwaiting) initializing from '425/150,0/150,425/150,0/8000' [0x564a86b2ab10]
400
2018-02-22_21:21:32.746799 <tone:ALL> ToneDesc(fi/dialrecall) initializing from '425/650,0/25' [0x564a86b2ae70]
401
2018-02-22_21:21:32.746836 <tone:ALL> ToneDesc(fi/record) initializing from '1400/500,0/15000' [0x564a86b2aff0]
402
2018-02-22_21:21:32.746903 <tone:ALL> ToneDesc(fi/info) initializing from '950/650,0/325,950/325,0/30,1400/1300,0/2600' [0x564a86b2b170]
403
2018-02-22_21:21:32.746938 <tone:ALL> ToneDesc(fi/stutter) initializing from '425/650,0/25' [0x564a86b2b610]
404
2018-02-22_21:21:32.746973 <tone:ALL> ToneDesc(fr/dial) initializing from '440' [0x564a86b2b790]
405
2018-02-22_21:21:32.747004 <tone:ALL> Building tone of 440 Hz
406
2018-02-22_21:21:32.747432 <tone:ALL> ToneDesc(fr/busy) initializing from '440/500,0/500' [0x564a86b2d8d0]
407
2018-02-22_21:21:32.747462 <tone:ALL> ToneDesc(fr/ring) initializing from '440/1500,0/3500' [0x564a86b2daa0]
408
2018-02-22_21:21:32.747491 <tone:ALL> ToneDesc(fr/congestion) initializing from '440/250,0/250' [0x564a86b2dcc0]
409
2018-02-22_21:21:32.747531 <tone:ALL> ToneDesc(fr/callwait) initializing from '440/300,0/10000' [0x564a86b2dee0]
410
2018-02-22_21:21:32.747573 <tone:ALL> ToneDesc(fr/dialrecall) initializing from '!350+440/100,!0/100,!350+440/100,!0/100,!350+440/100,!0/100,350+440' [0x564a86b2e0e0]
411
2018-02-22_21:21:32.747611 <tone:ALL> ToneDesc(fr/record) initializing from '1400/500,0/15000' [0x564a86b2e610]
412
2018-02-22_21:21:32.747645 <tone:ALL> ToneDesc(fr/info) initializing from '!950/330,!1400/330,!1800/330' [0x564a86b2e790]
413
2018-02-22_21:21:32.747713 <tone:ALL> ToneDesc(fr/stutter) initializing from '!440/100,!0/100,!440/100,!0/100,!440/100,!0/100,!440/100,!0/100,!440/100,!0/100,!440/100,!0/100,440' [0x564a86b2e940]
414
2018-02-22_21:21:32.747766 <tone:ALL> ToneDesc(gr/dial) initializing from '425/200,0/300,425/700,0/800' [0x564a86b2f0c0]
415
2018-02-22_21:21:32.747798 <tone:ALL> ToneDesc(gr/busy) initializing from '425/300,0/300' [0x564a86b2f280]
416
2018-02-22_21:21:32.747826 <tone:ALL> ToneDesc(gr/ring) initializing from '425/1000,0/4000' [0x564a86b2f400]
417
2018-02-22_21:21:32.747851 <tone:ALL> ToneDesc(gr/congestion) initializing from '425/200,0/200' [0x564a86b2f580]
418
2018-02-22_21:21:32.747884 <tone:ALL> ToneDesc(gr/callwaiting) initializing from '425/150,0/150,425/150,0/8000' [0x564a86b2f700]
419
2018-02-22_21:21:32.747913 <tone:ALL> ToneDesc(gr/dialrecall) initializing from '425/650,0/25' [0x564a86b2f8e0]
420
2018-02-22_21:21:32.747945 <tone:ALL> ToneDesc(gr/record) initializing from '1400/400,0/15000' [0x564a86b2fa60]
421
2018-02-22_21:21:32.747974 <tone:ALL> ToneDesc(gr/info) initializing from '!950/330,!1400/330,!1800/330,!0/1000,!950/330,!1400/330,!1800/330,!0/1000,!950/330,!1400/330,!1800/330,!0/1000,0' [0x564a86b2fbe0]
422
2018-02-22_21:21:32.748027 <tone:ALL> ToneDesc(gr/stutter) initializing from '425/650,0/25' [0x564a86b30370]
423
2018-02-22_21:21:32.748061 <tone:ALL> ToneDesc(hu/dial) initializing from '425' [0x564a86b304f0]
424
2018-02-22_21:21:32.748093 <tone:ALL> ToneDesc(hu/busy) initializing from '425/300,0/300' [0x564a86b30650]
425
2018-02-22_21:21:32.748127 <tone:ALL> ToneDesc(hu/ring) initializing from '425/1250,0/3750' [0x564a86b307d0]
426
2018-02-22_21:21:32.748160 <tone:ALL> ToneDesc(hu/congestion) initializing from '425/300,0/300' [0x564a86b30950]
427
2018-02-22_21:21:32.748196 <tone:ALL> ToneDesc(hu/callwaiting) initializing from '425/40,0/1960' [0x564a86b30ad0]
428
2018-02-22_21:21:32.748230 <tone:ALL> ToneDesc(hu/dialrecall) initializing from '425+450' [0x564a86b30c50]
429
2018-02-22_21:21:32.748263 <tone:ALL> ToneDesc(hu/record) initializing from '1400/400,0/15000' [0x564a86b30dc0]
430
2018-02-22_21:21:32.748297 <tone:ALL> ToneDesc(hu/info) initializing from '!950/330,!1400/330,!1800/330,!0/1000,!950/330,!1400/330,!1800/330,!0/1000,!950/330,!1400/330,!1800/330,!0/1000,0' [0x564a86b30f80]
431
2018-02-22_21:21:32.748348 <tone:ALL> ToneDesc(hu/stutter) initializing from '350+375+400' [0x564a86b31770]
432
2018-02-22_21:21:32.748378 <tone:ALL> Building tone of 375 + 350 Hz
433
2018-02-22_21:21:32.750130 <tone:ALL> ToneDesc(il/dial) initializing from '414' [0x564a86b311b0]
434
2018-02-22_21:21:32.750164 <tone:ALL> Building tone of 414 Hz
435
2018-02-22_21:21:32.750556 <tone:ALL> ToneDesc(il/busy) initializing from '414/500,0/500' [0x564a86b313e0]
436
2018-02-22_21:21:32.750587 <tone:ALL> ToneDesc(il/ring) initializing from '414/1000,0/3000' [0x564a86b37830]
437
2018-02-22_21:21:32.750622 <tone:ALL> ToneDesc(il/congestion) initializing from '414/250,0/250' [0x564a86b37a40]
438
2018-02-22_21:21:32.750656 <tone:ALL> ToneDesc(il/callwaiting) initializing from '414/100,0/100,414/100,0/100,414/600,0/3000' [0x564a86b37c60]
439
2018-02-22_21:21:32.750695 <tone:ALL> ToneDesc(il/dialrecall) initializing from '!414/100,!0/100,!414/100,!0/100,!414/100,!0/100,414' [0x564a86b38100]
440
2018-02-22_21:21:32.750756 <tone:ALL> ToneDesc(il/record) initializing from '1400/500,0/15000' [0x564a86b38420]
441
2018-02-22_21:21:32.750791 <tone:ALL> ToneDesc(il/info) initializing from '1000/330,1400/330,1800/330,0/1000' [0x564a86b385a0]
442
2018-02-22_21:21:32.750820 <tone:ALL> Building tone of 1000 Hz
443
2018-02-22_21:21:32.751853 <tone:ALL> ToneDesc(il/stutter) initializing from '!414/160,!0/160,!414/160,!0/160,!414/160,!0/160,!414/160,!0/160,!414/160,!0/160,!414/160,!0/160,!414/160,!0/160,!414/160,!0/160,!414/160,!0/160,!414/160,!0/160,414' [0x564a86b3a770]
444
2018-02-22_21:21:32.751919 <tone:ALL> ToneDesc(in/dial) initializing from '400*25' [0x564a86b3b230]
445
2018-02-22_21:21:32.751949 <tone:ALL> Building tone of 400 modulated by 25 Hz
446
2018-02-22_21:21:32.753994 <tone:ALL> ToneDesc(in/busy) initializing from '400/750,0/750' [0x564a86b3a9f0]
447
2018-02-22_21:21:32.754031 <tone:ALL> ToneDesc(in/ring) initializing from '400*25/400,0/200,400*25/400,0/2000' [0x564a86b3aba0]
448
2018-02-22_21:21:32.754071 <tone:ALL> ToneDesc(in/congestion) initializing from '400/250,0/250' [0x564a86b3af00]
449
2018-02-22_21:21:32.754105 <tone:ALL> ToneDesc(in/callwaiting) initializing from '400/200,0/100,400/200,0/7500' [0x564a86b3b080]
450
2018-02-22_21:21:32.754136 <tone:ALL> ToneDesc(in/dialrecall) initializing from '!350+440/100,!0/100,!350+440/100,!0/100,!350+440/100,!0/100,350+440' [0x564a86b3f480]
451
2018-02-22_21:21:32.754173 <tone:ALL> ToneDesc(in/record) initializing from '1400/500,0/15000' [0x564a86b3f8b0]
452
2018-02-22_21:21:32.754207 <tone:ALL> ToneDesc(in/info) initializing from '!950/330,!1400/330,!1800/330,0/1000' [0x564a86b3fa30]
453
2018-02-22_21:21:32.754244 <tone:ALL> ToneDesc(in/stutter) initializing from '!350+440/100,!0/100,!350+440/100,!0/100,!350+440/100,!0/100,!350+440/100,!0/100,!350+440/100,!0/100,!350+440/100,!0/100,350+440' [0x564a86b3fbf0]
454
2018-02-22_21:21:32.754299 <tone:ALL> ToneDesc(it/dial) initializing from '425/200,0/200,425/600,0/1000' [0x564a86b402a0]
455
2018-02-22_21:21:32.754338 <tone:ALL> ToneDesc(it/busy) initializing from '425/500,0/500' [0x564a86b40460]
456
2018-02-22_21:21:32.754370 <tone:ALL> ToneDesc(it/ring) initializing from '425/1000,0/4000' [0x564a86b405e0]
457
2018-02-22_21:21:32.754400 <tone:ALL> ToneDesc(it/congestion) initializing from '425/200,0/200' [0x564a86b40760]
458
2018-02-22_21:21:32.754429 <tone:ALL> ToneDesc(it/callwaiting) initializing from '425/400,0/100,425/250,0/100,425/150,0/14000' [0x564a86b408e0]
459
2018-02-22_21:21:32.754465 <tone:ALL> ToneDesc(it/dialrecall) initializing from '470/400,425/400' [0x564a86b40b40]
460
2018-02-22_21:21:32.754492 <tone:ALL> Building tone of 470 Hz
461
2018-02-22_21:21:32.754887 <tone:ALL> ToneDesc(it/record) initializing from '1400/400,0/15000' [0x564a86b42ca0]
462
2018-02-22_21:21:32.754921 <tone:ALL> ToneDesc(it/info) initializing from '!950/330,!1400/330,!1800/330,!0/1000,!950/330,!1400/330,!1800/330,!0/1000,!950/330,!1400/330,!1800/330,!0/1000,0' [0x564a86b42e20]
463
2018-02-22_21:21:32.754970 <tone:ALL> ToneDesc(it/stutter) initializing from '470/400,425/400' [0x564a86b43600]
464
2018-02-22_21:21:32.755004 <tone:ALL> ToneDesc(lt/dial) initializing from '425' [0x564a86b43780]
465
2018-02-22_21:21:32.755033 <tone:ALL> ToneDesc(lt/busy) initializing from '425/350,0/350' [0x564a86b438e0]
466
2018-02-22_21:21:32.755065 <tone:ALL> ToneDesc(lt/ring) initializing from '425/1000,0/4000' [0x564a86b43a60]
467
2018-02-22_21:21:32.755095 <tone:ALL> ToneDesc(lt/congestion) initializing from '425/200,0/200' [0x564a86b43be0]
468
2018-02-22_21:21:32.755125 <tone:ALL> ToneDesc(lt/callwaiting) initializing from '425/150,0/150,425/150,0/4000' [0x564a86b43d60]
469
2018-02-22_21:21:32.755190 <tone:ALL> ToneDesc(lt/dialrecall) initializing from '425/500,0/50' [0x564a86b43f60]
470
2018-02-22_21:21:32.755225 <tone:ALL> ToneDesc(lt/record) initializing from '1400/500,0/15000' [0x564a86b440e0]
471
2018-02-22_21:21:32.755255 <tone:ALL> ToneDesc(lt/info) initializing from '!950/330,!1400/330,!1800/330,!0/1000,!950/330,!1400/330,!1800/330,!0/1000,!950/330,!1400/330,!1800/330,!0/1000,0' [0x564a86b44260]
472
2018-02-22_21:21:32.755303 <tone:ALL> ToneDesc(lt/stutter) initializing from '!425/100,!0/100,!425/100,!0/100,!425/100,!0/100,!425/100,!0/100,!425/100,!0/100,!425/100,!0/100,425' [0x564a86b44a50]
473
2018-02-22_21:21:32.755350 <tone:ALL> ToneDesc(jp/dial) initializing from '400' [0x564a86b44db0]
474
2018-02-22_21:21:32.755386 <tone:ALL> ToneDesc(jp/busy) initializing from '400/500,0/500' [0x564a86b44f10]
475
2018-02-22_21:21:32.755426 <tone:ALL> ToneDesc(jp/ring) initializing from '400+15/1000,0/2000' [0x564a86b45090]
476
2018-02-22_21:21:32.755452 <tone:ALL> Building tone of 400 + 15 Hz
477
2018-02-22_21:21:32.757371 <tone:ALL> ToneDesc(jp/congestion) initializing from '400/500,0/500' [0x564a86b49130]
478
2018-02-22_21:21:32.757414 <tone:ALL> ToneDesc(jp/callwaiting) initializing from '400+16/500,0/8000' [0x564a86b49220]
479
2018-02-22_21:21:32.757445 <tone:ALL> Building tone of 400 + 16 Hz
480
2018-02-22_21:21:32.759348 <tone:ALL> ToneDesc(jp/dialrecall) initializing from '!400/200,!0/200,!400/200,!0/200,!400/200,!0/200,400' [0x564a86b4d280]
481
2018-02-22_21:21:32.759392 <tone:ALL> ToneDesc(jp/record) initializing from '1400/500,0/15000' [0x564a86b4d750]
482
2018-02-22_21:21:32.759428 <tone:ALL> ToneDesc(jp/info) initializing from '!950/330,!1400/330,!1800/330,0' [0x564a86b4d8d0]
483
2018-02-22_21:21:32.759464 <tone:ALL> ToneDesc(jp/stutter) initializing from '!400/100,!0/100,!400/100,!0/100,!400/100,!0/100,!400/100,!0/100,!400/100,!0/100,!400/100,!0/100,400' [0x564a86b4da90]
484
2018-02-22_21:21:32.759520 <tone:ALL> ToneDesc(mx/dial) initializing from '425' [0x564a86b4e210]
485
2018-02-22_21:21:32.759550 <tone:ALL> ToneDesc(mx/busy) initializing from '425/250,0/250' [0x564a86b4e370]
486
2018-02-22_21:21:32.759586 <tone:ALL> ToneDesc(mx/ring) initializing from '425/1000,0/4000' [0x564a86b4e4f0]
487
2018-02-22_21:21:32.759623 <tone:ALL> ToneDesc(mx/congestion) initializing from '425/250,0/250' [0x564a86b4e670]
488
2018-02-22_21:21:32.759659 <tone:ALL> ToneDesc(mx/callwaiting) initializing from '425/200,0/600,425/200,0/10000' [0x564a86b4e7f0]
489
2018-02-22_21:21:32.759697 <tone:ALL> ToneDesc(mx/dialrecall) initializing from '!350+440/100,!0/100,!350+440/100,!0/100,!350+440/100,!0/100,350+440' [0x564a86b4e9d0]
490
2018-02-22_21:21:32.759757 <tone:ALL> ToneDesc(mx/record) initializing from '1400/500,0/15000' [0x564a86b4ec80]
491
2018-02-22_21:21:32.759793 <tone:ALL> ToneDesc(mx/info) initializing from '950/330,0/30,1400/330,0/30,1800/330,0/1000' [0x564a86b4ee00]
492
2018-02-22_21:21:32.759831 <tone:ALL> ToneDesc(mx/stutter) initializing from '!350+440/100,!0/100,!350+440/100,!0/100,!350+440/100,!0/100,!350+440/100,!0/100,!350+440/100,!0/100,!350+440/100,!0/100,350+440' [0x564a86b4f090]
493
2018-02-22_21:21:32.759880 <tone:ALL> ToneDesc(my/dial) initializing from '425' [0x564a86b4f790]
494
2018-02-22_21:21:32.759909 <tone:ALL> ToneDesc(my/busy) initializing from '425/500,0/500' [0x564a86b4f8f0]
495
2018-02-22_21:21:32.759942 <tone:ALL> ToneDesc(my/ring) initializing from '425/400,0/200' [0x564a86b4fa70]
496
2018-02-22_21:21:32.759978 <tone:ALL> ToneDesc(my/congestion) initializing from '425/500,0/500' [0x564a86b4fbf0]
497
2018-02-22_21:21:32.760018 <tone:ALL> ToneDesc(nl/dial) initializing from '425' [0x564a86b4fd70]
498
2018-02-22_21:21:32.760052 <tone:ALL> ToneDesc(nl/busy) initializing from '425/500,0/500' [0x564a86b4fed0]
499
2018-02-22_21:21:32.760092 <tone:ALL> ToneDesc(nl/ring) initializing from '425/1000,0/4000' [0x564a86b50070]
500
2018-02-22_21:21:32.760128 <tone:ALL> ToneDesc(nl/congestion) initializing from '425/250,0/250' [0x564a86b50230]
501
2018-02-22_21:21:32.760163 <tone:ALL> ToneDesc(nl/callwaiting) initializing from '425/500,0/9500' [0x564a86b503f0]
502
2018-02-22_21:21:32.760199 <tone:ALL> ToneDesc(nl/dialrecall) initializing from '425/500,0/50' [0x564a86b505b0]
503
2018-02-22_21:21:32.760235 <tone:ALL> ToneDesc(nl/record) initializing from '1400/500,0/15000' [0x564a86b50750]
504
2018-02-22_21:21:32.760270 <tone:ALL> ToneDesc(nl/info) initializing from '950/330,1400/330,1800/330,0/1000' [0x564a86b508f0]
505
2018-02-22_21:21:32.760309 <tone:ALL> ToneDesc(nl/stutter) initializing from '425/500,0/50' [0x564a86b50c50]
506
2018-02-22_21:21:32.760347 <tone:ALL> ToneDesc(no/dial) initializing from '425' [0x564a86b50dd0]
507
2018-02-22_21:21:32.760378 <tone:ALL> ToneDesc(no/busy) initializing from '425/500,0/500' [0x564a86b50f30]
508
2018-02-22_21:21:32.760419 <tone:ALL> ToneDesc(no/ring) initializing from '425/1000,0/4000' [0x564a86b51170]
509
2018-02-22_21:21:32.760453 <tone:ALL> ToneDesc(no/congestion) initializing from '425/200,0/200' [0x564a86b51390]
510
2018-02-22_21:21:32.760484 <tone:ALL> ToneDesc(no/callwaiting) initializing from '425/200,0/600,425/200,0/10000' [0x564a86b515b0]
511
2018-02-22_21:21:32.760522 <tone:ALL> ToneDesc(no/dialrecall) initializing from '470/400,425/400' [0x564a86b51910]
512
2018-02-22_21:21:32.760549 <tone:ALL> ToneDesc(no/record) initializing from '1400/400,0/15000' [0x564a86b51a90]
513
2018-02-22_21:21:32.760584 <tone:ALL> ToneDesc(no/info) initializing from '!950/330,!1400/330,!1800/330,!0/1000,!950/330,!1400/330,!1800/330,!0/1000,!950/330,!1400/330,!1800/330,!0/1000,0' [0x564a86b51c10]
514
2018-02-22_21:21:32.760632 <tone:ALL> ToneDesc(no/stutter) initializing from '470/400,425/400' [0x564a86b52520]
515
2018-02-22_21:21:32.760668 <tone:ALL> ToneDesc(nz/dial) initializing from '400' [0x564a86b526a0]
516
2018-02-22_21:21:32.760728 <tone:ALL> ToneDesc(nz/busy) initializing from '400/250,0/250' [0x564a86b52800]
517
2018-02-22_21:21:32.760763 <tone:ALL> ToneDesc(nz/ring) initializing from '400+450/400,0/200,400+450/400,0/2000' [0x564a86b52980]
518
2018-02-22_21:21:32.760797 <tone:ALL> ToneDesc(nz/congestion) initializing from '400/375,0/375' [0x564a86b52b40]
519
2018-02-22_21:21:32.760833 <tone:ALL> ToneDesc(nz/callwaiting) initializing from '!400/200,!0/3000,!400/200,!0/3000,!400/200,!0/3000,!400/200' [0x564a86b52cc0]
520
2018-02-22_21:21:32.760875 <tone:ALL> ToneDesc(nz/dialrecall) initializing from '!400/100!0/100,!400/100,!0/100,!400/100,!0/100,400' [0x564a86b52f90]
521
2018-02-22_21:21:32.760923 <tone:ALL> ToneDesc(nz/record) initializing from '1400/425,0/15000' [0x564a86b53190]
522
2018-02-22_21:21:32.760961 <tone:ALL> ToneDesc(nz/info) initializing from '400/750,0/100,400/750,0/100,400/750,0/100,400/750,0/400' [0x564a86b53310]
523
2018-02-22_21:21:32.761005 <tone:ALL> ToneDesc(nz/stutter) initializing from '!400/100!0/100,!400/100,!0/100,!400/100,!0/100,!400/100!0/100,!400/100,!0/100,!400/100,!0/100,400' [0x564a86b53760]
524
2018-02-22_21:21:32.761052 <tone:ALL> ToneDesc(nz/unobtainable) initializing from '400/75,0/100,400/75,0/100,400/75,0/100,400/75,0/400' [0x564a86b53c10]
525
2018-02-22_21:21:32.761097 <tone:ALL> ToneDesc(ph/dial) initializing from '425' [0x564a86b53e50]
526
2018-02-22_21:21:32.761138 <tone:ALL> ToneDesc(ph/busy) initializing from '480+620/500,0/500' [0x564a86b53fb0]
527
2018-02-22_21:21:32.761170 <tone:ALL> Building tone of 620 + 480 Hz
528
2018-02-22_21:21:32.762991 <tone:ALL> ToneDesc(ph/ring) initializing from '425+480/1000,0/4000' [0x564a86b58050]
529
2018-02-22_21:21:32.763019 <tone:ALL> Building tone of 480 + 425 Hz
530
2018-02-22_21:21:32.764774 <tone:ALL> ToneDesc(ph/congestion) initializing from '480+620/250,0/250' [0x564a86b5c0b0]
531
2018-02-22_21:21:32.764815 <tone:ALL> ToneDesc(ph/callwaiting) initializing from '440/300,0/10000' [0x564a86b5c230]
532
2018-02-22_21:21:32.764859 <tone:ALL> ToneDesc(ph/dialrecall) initializing from '!350+440/100,!0/100,!350+440/100,!0/100,!350+440/100,!0/100,350+440' [0x564a86b5c430]
533
2018-02-22_21:21:32.764902 <tone:ALL> ToneDesc(ph/record) initializing from '1400/500,0/15000' [0x564a86b5c960]
534
2018-02-22_21:21:32.764935 <tone:ALL> ToneDesc(ph/info) initializing from '!950/330,!1400/330,!1800/330,0' [0x564a86b5cae0]
535
2018-02-22_21:21:32.764967 <tone:ALL> ToneDesc(ph/stutter) initializing from '!350+440/100,!0/100,!350+440/100,!0/100,!350+440/100,!0/100,!350+440/100,!0/100,!350+440/100,!0/100,!350+440/100,!0/100,350+440' [0x564a86b5cca0]
536
2018-02-22_21:21:32.765025 <tone:ALL> ToneDesc(pl/dial) initializing from '425' [0x564a86b5d440]
537
2018-02-22_21:21:32.765060 <tone:ALL> ToneDesc(pl/busy) initializing from '425/500,0/500' [0x564a86b5d5a0]
538
2018-02-22_21:21:32.765097 <tone:ALL> ToneDesc(pl/ring) initializing from '425/1000,0/4000' [0x564a86b5d720]
539
2018-02-22_21:21:32.765134 <tone:ALL> ToneDesc(pl/congestion) initializing from '425/500,0/500' [0x564a86b5d8a0]
540
2018-02-22_21:21:32.765169 <tone:ALL> ToneDesc(pl/callwaiting) initializing from '425/150,0/150,425/150,0/4000' [0x564a86b5da20]
541
2018-02-22_21:21:32.765234 <tone:ALL> ToneDesc(pl/dialrecall) initializing from '425/500,0/50' [0x564a86b5dc00]
542
2018-02-22_21:21:32.765268 <tone:ALL> ToneDesc(pl/record) initializing from '1400/500,0/15000' [0x564a86b5dd80]
543
2018-02-22_21:21:32.765302 <tone:ALL> ToneDesc(pl/info) initializing from '!950/330,!1400/330,!1800/330,!0/1000,!950/330,!1400/330,!1800/330,!0/1000,!950/330,!1400/330,!1800/330,!0/1000' [0x564a86b5df00]
544
2018-02-22_21:21:32.765355 <tone:ALL> ToneDesc(pl/stutter) initializing from '!425/100,!0/100,!425/100,!0/100,!425/100,!0/100,!425/100,!0/100,!425/100,!0/100,!425/100,!0/100,425' [0x564a86b5e5f0]
545
2018-02-22_21:21:32.765405 <tone:ALL> ToneDesc(pt/dial) initializing from '425' [0x564a86b5e9d0]
546
2018-02-22_21:21:32.765440 <tone:ALL> ToneDesc(pt/busy) initializing from '425/500,0/500' [0x564a86b5eb30]
547
2018-02-22_21:21:32.765478 <tone:ALL> ToneDesc(pt/ring) initializing from '425/1000,0/5000' [0x564a86b5ecb0]
548
2018-02-22_21:21:32.765515 <tone:ALL> ToneDesc(pt/congestion) initializing from '425/200,0/200' [0x564a86b5ee30]
549
2018-02-22_21:21:32.765558 <tone:ALL> ToneDesc(pt/callwaiting) initializing from '440/300,0/10000' [0x564a86b5efb0]
550
2018-02-22_21:21:32.765596 <tone:ALL> ToneDesc(pt/dialrecall) initializing from '425/1000,0/200' [0x564a86b5f130]
551
2018-02-22_21:21:32.765634 <tone:ALL> ToneDesc(pt/record) initializing from '1400/500,0/15000' [0x564a86b5f2b0]
552
2018-02-22_21:21:32.765671 <tone:ALL> ToneDesc(pt/info) initializing from '950/330,1400/330,1800/330,0/1000' [0x564a86b5f450]
553
2018-02-22_21:21:32.765711 <tone:ALL> ToneDesc(pt/stutter) initializing from '!425/100,!0/100,!425/100,!0/100,!425/100,!0/100,!425/100,!0/100,!425/100,!0/100,!425/100,!0/100,425' [0x564a86b5f690]
554
2018-02-22_21:21:32.765757 <tone:ALL> ToneDesc(ru/dial) initializing from '425' [0x564a86b5fe10]
555
2018-02-22_21:21:32.765788 <tone:ALL> ToneDesc(ru/busy) initializing from '425/350,0/350' [0x564a86b5ff70]
556
2018-02-22_21:21:32.765828 <tone:ALL> ToneDesc(ru/ring) initializing from '425/800,0/3200' [0x564a86b600f0]
557
2018-02-22_21:21:32.765862 <tone:ALL> ToneDesc(ru/congestion) initializing from '425/350,0/350' [0x564a86b60270]
558
2018-02-22_21:21:32.765897 <tone:ALL> ToneDesc(ru/callwaiting) initializing from '425/200,0/5000' [0x564a86b603f0]
559
2018-02-22_21:21:32.765929 <tone:ALL> ToneDesc(ru/dialrecall) initializing from '!350+440/100,!0/100,!350+440/100,!0/100,!350+440/100,!0/100,350+440' [0x564a86b60570]
560
2018-02-22_21:21:32.765991 <tone:ALL> ToneDesc(ru/record) initializing from '1400/500,0/15000' [0x564a86b60840]
561
2018-02-22_21:21:32.766029 <tone:ALL> ToneDesc(ru/info) initializing from '!950/330,!1400/330,!1800/330,0' [0x564a86b609c0]
562
2018-02-22_21:21:32.766067 <tone:ALL> ToneDesc(se/dial) initializing from '425' [0x564a86b60b80]
563
2018-02-22_21:21:32.766129 <tone:ALL> ToneDesc(se/busy) initializing from '425/250,0/250' [0x564a86b60ce0]
564
2018-02-22_21:21:32.766162 <tone:ALL> ToneDesc(se/ring) initializing from '425/1000,0/5000' [0x564a86b60ea0]
565
2018-02-22_21:21:32.766205 <tone:ALL> ToneDesc(se/congestion) initializing from '425/250,0/750' [0x564a86b61060]
566
2018-02-22_21:21:32.766243 <tone:ALL> ToneDesc(se/callwaiting) initializing from '425/200,0/500,425/200,0/9100' [0x564a86b61280]
567
2018-02-22_21:21:32.766284 <tone:ALL> ToneDesc(se/dialrecall) initializing from '!425/100,!0/100,!425/100,!0/100,!425/100,!0/100,425' [0x564a86b615e0]
568
2018-02-22_21:21:32.766328 <tone:ALL> ToneDesc(se/record) initializing from '1400/500,0/15000' [0x564a86b61a00]
569
2018-02-22_21:21:32.766366 <tone:ALL> ToneDesc(se/info) initializing from '!950/332,!0/24,!1400/332,!0/24,!1800/332,!0/2024,!950/332,!0/24,!1400/332,!0/24,!1800/332,!0/2024,!950/332,!0/24,!1400/332,!0/24,!1800/332,!0/2024,!950/332,!0/24,!1400/332,!0/24,!1800/332,!0/2024,!950/332,!0/24,!1400/332,!0/24,!1800/332,0' [0x564a86b61b80]
570
2018-02-22_21:21:32.766437 <tone:ALL> ToneDesc(se/stutter) initializing from '!425/100,!0/100,!425/100,!0/100,!425/100,!0/100,!425/100,!0/100,!425/100,!0/100,!425/100,!0/100,425' [0x564a86b62d20]
571
2018-02-22_21:21:32.766520 <tone:ALL> ToneDesc(sg/dial) initializing from '425' [0x564a86b63000]
572
2018-02-22_21:21:32.766559 <tone:ALL> ToneDesc(sg/ring) initializing from '425*24/400,0/200,425*24/400,0/2000' [0x564a86b63160]
573
2018-02-22_21:21:32.766591 <tone:ALL> Building tone of 425 modulated by 24 Hz
574
2018-02-22_21:21:32.768135 <tone:ALL> ToneDesc(sg/busy) initializing from '425/750,0/750' [0x564a86b628a0]
575
2018-02-22_21:21:32.768169 <tone:ALL> ToneDesc(sg/congestion) initializing from '425/250,0/250' [0x564a86b62910]
576
2018-02-22_21:21:32.768203 <tone:ALL> ToneDesc(sg/callwaiting) initializing from '425*24/300,0/200,425*24/300,0/3200' [0x564a86b61f70]
577
2018-02-22_21:21:32.768244 <tone:ALL> ToneDesc(sg/stutter) initializing from '!425/200,!0/200,!425/600,!0/200,!425/200,!0/200,!425/600,!0/200,!425/200,!0/200,!425/600,!0/200,!425/200,!0/200,!425/600,!0/200,425' [0x564a86b62170]
578
2018-02-22_21:21:32.768296 <tone:ALL> ToneDesc(sg/info) initializing from '950/330,1400/330,1800/330,0/1000' [0x564a86b678e0]
579
2018-02-22_21:21:32.768332 <tone:ALL> ToneDesc(sg/dialrecall) initializing from '425*24/500,0/500,425/500,0/2500' [0x564a86b67a60]
580
2018-02-22_21:21:32.768372 <tone:ALL> ToneDesc(sg/record) initializing from '1400/500,0/15000' [0x564a86b67be0]
581
2018-02-22_21:21:32.768406 <tone:ALL> ToneDesc(sg/nutone) initializing from '425/2500,0/500' [0x564a86b67d60]
582
2018-02-22_21:21:32.768440 <tone:ALL> ToneDesc(sg/intrusion) initializing from '425/250,0/2000' [0x564a86b67ee0]
583
2018-02-22_21:21:32.768508 <tone:ALL> ToneDesc(sg/warning) initializing from '425/624,0/4376' [0x564a86b68060]
584
2018-02-22_21:21:32.768543 <tone:ALL> ToneDesc(sg/acceptance) initializing from '425/125,0/125' [0x564a86b681e0]
585
2018-02-22_21:21:32.768577 <tone:ALL> ToneDesc(sg/holdinga) initializing from '!425*24/500,!0/500' [0x564a86b68360]
586
2018-02-22_21:21:32.768616 <tone:ALL> ToneDesc(sg/holdingb) initializing from '!425/500,!0/2500' [0x564a86b684e0]
587
2018-02-22_21:21:32.768656 <tone:ALL> ToneDesc(th/dial) initializing from '400*50' [0x564a86b68660]
588
2018-02-22_21:21:32.768689 <tone:ALL> Building tone of 400 modulated by 50 Hz
589
2018-02-22_21:21:32.770706 <tone:ALL> ToneDesc(th/busy) initializing from '400/500,0/500' [0x564a86b6c6f0]
590
2018-02-22_21:21:32.770746 <tone:ALL> ToneDesc(th/ring) initializing from '420/1000,0/5000' [0x564a86b6c870]
591
2018-02-22_21:21:32.770788 <tone:ALL> ToneDesc(th/congestion) initializing from '400/300,0/300' [0x564a86b6ca50]
592
2018-02-22_21:21:32.770825 <tone:ALL> ToneDesc(th/callwaiting) initializing from '1000/400,10000/400,1000/400' [0x564a86b6cc10]
593
2018-02-22_21:21:32.770857 <tone:ALL> Building tone of 10000 Hz
594
2018-02-22_21:21:32.772454 <tone:ALL> ToneDesc(th/dialrecall) initializing from '400*50/400,0/100,400*50/400,0/100' [0x564a86b6ef20]
595
2018-02-22_21:21:32.772504 <tone:ALL> ToneDesc(th/record) initializing from '1400/500,0/15000' [0x564a86b6f1e0]
596
2018-02-22_21:21:32.772543 <tone:ALL> ToneDesc(th/info) initializing from '950/330,1400/330,1800/330' [0x564a86b6f360]
597
2018-02-22_21:21:32.772590 <tone:ALL> ToneDesc(th/stutter) initializing from '!400/200,!0/200,!400/600,!0/200,!400/200,!0/200,!400/600,!0/200,!400/200,!0/200,!400/600,!0/200,!400/200,!0/200,!400/600,!0/200,400' [0x564a86b6f5a0]
598
2018-02-22_21:21:32.772652 <tone:ALL> ToneDesc(uk/dial) initializing from '350+440' [0x564a86b70080]
599
2018-02-22_21:21:32.772686 <tone:ALL> ToneDesc(uk/specdial) initializing from '350+440/750,440/750' [0x564a86b701e0]
600
2018-02-22_21:21:32.772726 <tone:ALL> ToneDesc(uk/busy) initializing from '400/375,0/375' [0x564a86b70360]
601
2018-02-22_21:21:32.772765 <tone:ALL> ToneDesc(uk/congestion) initializing from '400/400,0/350,400/225,0/525' [0x564a86b704e0]
602
2018-02-22_21:21:32.772806 <tone:ALL> ToneDesc(uk/speccongestion) initializing from '400/200,1004/300' [0x564a86b706a0]
603
2018-02-22_21:21:32.772836 <tone:ALL> Building tone of 1004 Hz
604
2018-02-22_21:21:32.773260 <tone:ALL> ToneDesc(uk/unobtainable) initializing from '400' [0x564a86b72800]
605
2018-02-22_21:21:32.773297 <tone:ALL> ToneDesc(uk/ring) initializing from '400+450/400,0/200,400+450/400,0/2000' [0x564a86b728f0]
606
2018-02-22_21:21:32.773338 <tone:ALL> ToneDesc(uk/callwaiting) initializing from '400/100,0/4000' [0x564a86b72a70]
607
2018-02-22_21:21:32.773372 <tone:ALL> ToneDesc(uk/speccallwaiting) initializing from '400/250,0/250,400/250,0/250,400/250,0/5000' [0x564a86b72bf0]
608
2018-02-22_21:21:32.773418 <tone:ALL> ToneDesc(uk/creditexpired) initializing from '400/125,0/125' [0x564a86b72f00]
609
2018-02-22_21:21:32.773458 <tone:ALL> ToneDesc(uk/confirm) initializing from '1400' [0x564a86b73080]
610
2018-02-22_21:21:32.773498 <tone:ALL> ToneDesc(uk/switching) initializing from '400/200,0/400,400/2000,0/400' [0x564a86b731e0]
611
2018-02-22_21:21:32.773537 <tone:ALL> ToneDesc(uk/info) initializing from '950/330,0/15,1400/330,0/15,1800/330,0/1000' [0x564a86b73420]
612
2018-02-22_21:21:32.773581 <tone:ALL> ToneDesc(uk/record) initializing from '1400/500,0/60000' [0x564a86b737b0]
613
2018-02-22_21:21:32.773620 <tone:ALL> ToneDesc(uk/stutter) initializing from '350+440/750,440/750' [0x564a86b73930]
614
2018-02-22_21:21:32.773664 <tone:ALL> ToneDesc(us/dial) initializing from '350+440' [0x564a86b73ab0]
615
2018-02-22_21:21:32.773705 <tone:ALL> ToneDesc(us/busy) initializing from '480+620/500,0/500' [0x564a86b73c10]
616
2018-02-22_21:21:32.773774 <tone:ALL> ToneDesc(us/ring) initializing from '440+480/2000,0/4000' [0x564a86b73dd0]
617
2018-02-22_21:21:32.773807 <tone:ALL> Building tone of 480 + 440 Hz
618
2018-02-22_21:21:32.775564 <tone:ALL> ToneDesc(us/congestion) initializing from '480+620/250,0/250' [0x564a86b77fa0]
619
2018-02-22_21:21:32.775609 <tone:ALL> ToneDesc(us/callwaiting) initializing from '440/300,0/10000' [0x564a86b781c0]
620
2018-02-22_21:21:32.775651 <tone:ALL> ToneDesc(us/dialrecall) initializing from '!350+440/100,!0/100,!350+440/100,!0/100,!350+440/100,!0/100,350+440' [0x564a86b783e0]
621
2018-02-22_21:21:32.775698 <tone:ALL> ToneDesc(us/record) initializing from '1400/500,0/15000' [0x564a86b78910]
622
2018-02-22_21:21:32.775735 <tone:ALL> ToneDesc(us/info) initializing from '!950/330,!1400/330,!1800/330,0' [0x564a86b78a90]
623
2018-02-22_21:21:32.775777 <tone:ALL> ToneDesc(us/stutter) initializing from '!350+440/100,!0/100,!350+440/100,!0/100,!350+440/100,!0/100,!350+440/100,!0/100,!350+440/100,!0/100,!350+440/100,!0/100,350+440' [0x564a86b78c50]
624
2018-02-22_21:21:32.775842 <tone:ALL> ToneDesc(us-old/dial) initializing from '600*120' [0x564a86b793f0]
625
2018-02-22_21:21:32.775873 <tone:ALL> Building tone of 600 modulated by 120 Hz
626
2018-02-22_21:21:32.777690 <tone:ALL> ToneDesc(us-old/busy) initializing from '500*100/500,0/500' [0x564a86b78e50]
627
2018-02-22_21:21:32.777718 <tone:ALL> Building tone of 500 modulated by 100 Hz
628
2018-02-22_21:21:32.779927 <tone:ALL> ToneDesc(us-old/ring) initializing from '420*40/2000,0/4000' [0x564a86b79130]
629
2018-02-22_21:21:32.779956 <tone:ALL> Building tone of 420 modulated by 40 Hz
630
2018-02-22_21:21:32.781514 <tone:ALL> ToneDesc(us-old/congestion) initializing from '500*100/250,0/250' [0x564a86b85400]
631
2018-02-22_21:21:32.781556 <tone:ALL> ToneDesc(us-old/callwaiting) initializing from '440/300,0/10000' [0x564a86b85630]
632
2018-02-22_21:21:32.781600 <tone:ALL> ToneDesc(us-old/dialrecall) initializing from '!600*120/100,!0/100,!600*120/100,!0/100,!600*120/100,!0/100,600*120' [0x564a86b85850]
633
2018-02-22_21:21:32.781648 <tone:ALL> ToneDesc(us-old/record) initializing from '1400/500,0/15000' [0x564a86b85d90]
634
2018-02-22_21:21:32.781685 <tone:ALL> ToneDesc(us-old/info) initializing from '!950/330,!1400/330,!1800/330,0' [0x564a86b85f10]
635
2018-02-22_21:21:32.781733 <tone:ALL> ToneDesc(us-old/stutter) initializing from '!600*120/100,!0/100,!600*120/100,!0/100,!600*120/100,!0/100,!600*120/100,!0/100,!600*120/100,!0/100,!600*120/100,!0/100,600*120' [0x564a86b860d0]
636
2018-02-22_21:21:32.781791 <tone:ALL> ToneDesc(tw/dial) initializing from '350+440' [0x564a86b86870]
637
2018-02-22_21:21:32.781830 <tone:ALL> ToneDesc(tw/busy) initializing from '480+620/500,0/500' [0x564a86b869d0]
638
2018-02-22_21:21:32.781868 <tone:ALL> ToneDesc(tw/ring) initializing from '440+480/1000,0/2000' [0x564a86b86b50]
639
2018-02-22_21:21:32.781902 <tone:ALL> ToneDesc(tw/congestion) initializing from '480+620/250,0/250' [0x564a86b86cd0]
640
2018-02-22_21:21:32.781963 <tone:ALL> ToneDesc(tw/callwaiting) initializing from '350+440/250,0/250,350+440/250,0/3250' [0x564a86b86e50]
641
2018-02-22_21:21:32.782010 <tone:ALL> ToneDesc(tw/dialrecall) initializing from '300/1500,0/500' [0x564a86b87040]
642
2018-02-22_21:21:32.782037 <tone:ALL> Building tone of 300 Hz
643
2018-02-22_21:21:32.782516 <tone:ALL> ToneDesc(tw/record) initializing from '1400/500,0/15000' [0x564a86b891a0]
644
2018-02-22_21:21:32.782551 <tone:ALL> ToneDesc(tw/info) initializing from '!950/330,!1400/330,!1800/330,0' [0x564a86b89320]
645
2018-02-22_21:21:32.782594 <tone:ALL> ToneDesc(tw/stutter) initializing from '!350+440/100,!0/100,!350+440/100,!0/100,!350+440/100,!0/100,!350+440/100,!0/100,!350+440/100,!0/100,!350+440/100,!0/100,350+440' [0x564a86b89590]
646
2018-02-22_21:21:32.782646 <tone:ALL> ToneDesc(ve/dial) initializing from '425' [0x564a86b89d90]
647
2018-02-22_21:21:32.782680 <tone:ALL> ToneDesc(ve/busy) initializing from '425/500,0/500' [0x564a86b89ef0]
648
2018-02-22_21:21:32.782724 <tone:ALL> ToneDesc(ve/ring) initializing from '425/1000,0/4000' [0x564a86b8a070]
649
2018-02-22_21:21:32.782761 <tone:ALL> ToneDesc(ve/congestion) initializing from '425/250,0/250' [0x564a86b8a1f0]
650
2018-02-22_21:21:32.782802 <tone:ALL> ToneDesc(ve/callwaiting) initializing from '400+450/300,0/6000' [0x564a86b8a370]
651
2018-02-22_21:21:32.782844 <tone:ALL> ToneDesc(ve/dialrecall) initializing from '425' [0x564a86b8a4f0]
652
2018-02-22_21:21:32.782884 <tone:ALL> ToneDesc(ve/record) initializing from '1400/500,0/15000' [0x564a86b8a650]
653
2018-02-22_21:21:32.782925 <tone:ALL> ToneDesc(ve/info) initializing from '!950/330,!1440/330,!1800/330,0/1000' [0x564a86b8a7f0]
654
2018-02-22_21:21:32.782958 <tone:ALL> Building tone of 1440 Hz
655
2018-02-22_21:21:32.783489 <tone:ALL> ToneDesc(za/dial) initializing from '400*33' [0x564a86b8ca40]
656
2018-02-22_21:21:32.783521 <tone:ALL> Building tone of 400 modulated by 33 Hz
657
2018-02-22_21:21:32.785534 <tone:ALL> ToneDesc(za/ring) initializing from '400*33/400,0/200,400*33/400,0/2000' [0x564a86b90ad0]
658
2018-02-22_21:21:32.785582 <tone:ALL> ToneDesc(za/callwaiting) initializing from '400*33/250,0/250,400*33/250,0/250,400*33/250,0/250,400*33/250,0/250' [0x564a86b90e60]
659
2018-02-22_21:21:32.785637 <tone:ALL> ToneDesc(za/congestion) initializing from '400/250,0/250' [0x564a86b91340]
660
2018-02-22_21:21:32.785675 <tone:ALL> ToneDesc(za/busy) initializing from '400/500,0/500' [0x564a86b914c0]
661
2018-02-22_21:21:32.785712 <tone:ALL> ToneDesc(za/dialrecall) initializing from '350+440' [0x564a86b91640]
662
2018-02-22_21:21:32.785752 <tone:ALL> ToneDesc(za/record) initializing from '1400/500,0/10000' [0x564a86b917b0]
663
2018-02-22_21:21:32.785793 <tone:ALL> ToneDesc(za/info) initializing from '950/330,1400/330,1800/330,0/330' [0x564a86b91930]
664
2018-02-22_21:21:32.785836 <tone:ALL> ToneDesc(za/stutter) initializing from '!400*33/100,!0/100,!400*33/100,!0/100,!400*33/100,!0/100,!400*33/100,!0/100,!400*33/100,!0/100,!400*33/100,!0/100,400*33' [0x564a86b91bd0]
665
Initializing module YSTUN
666
2018-02-22_21:21:32.786177 <stun:ALL> Bind request interval set to 15000 msec.
667
Initializing module iLBC webrtc
668
Initializing module PBX
669
Initializing module CdrCombine
670
Initializing module FileInfo
671
Initializing module Analyzer
672
Initializing module Presence
673
2018-02-22_21:21:32.786713 <presence:ALL> Initialized lists=16 expirecheck=0 expiretime=0 account=(null)
674
Initializing module Register from file
675
Initializing module Register for database
676
Initializing module Radius client
677
2018-02-22_21:21:32.787010 <yradius:NOTE> Local address not set or invalid. Radius functions disabled
678
Initializing module Call Parking
679
Initializing module Queues for database
680
Initializing module Queues Notify
681
2018-02-22_21:21:32.787276 <queuesnotify:INFO> Query 'callinfo' not configured
682
2018-02-22_21:21:32.787308 <queuesnotify:INFO> Query 'cdrinfo' not configured
683
2018-02-22_21:21:32.787364 <queuesnotify:ALL> Initialized: account= notifyhanguponunload=true
684
Initializing module Monitoring
685
2018-02-22_21:21:32.787764 <monitoring:ALL> RTPTable created [0x564a86add550]
686
2018-02-22_21:21:32.787802 <monitoring:ALL> RTPTable [0x564a86add550] configured with directions='(null)',resetTime=3600
687
Initializing module CCongestion
688
Initializing module Accounts from file
689
Initializing module Late Router
690
Initializing module MGCP Gateway
691
2018-02-22_21:21:32.788563 <mgcp_gw:INFO> Initialized:

692
type:              Gateway

693
bind address:      :2427

694
allow_unknown_cmd: false

695
retrans_interval:  250000

696
retrans_count:     3

697
lower_case_params: true

698
max_recv_packet:   1500

699
send_provisional:  true
700
2018-02-22_21:21:32.788670 <mgcpgw:ALL> Initialized audio codecs: mulaw,alaw,gsm,slin,ilbc20,ilbc30,speex,speex/16000,speex/32000,isac/16000,isac/32000
701
Initializing module SIP Features
702
2018-02-22_21:21:32.788752 <sipfeatures:ALL> Allowed subscriptions: dialog,message-summary
703
Initializing module PBX for database
704
Initializing module Analog Channel
705
Initializing module YBTS
706
2018-02-22_21:21:32.789698 <ybts:INFO> LAI changed (null) -> 00101_0001
707
Initializing module Cache
708
Initializing module SNMP Agent
709
2018-02-22_21:21:32.797733 <snmpagent:ALL> SnmpMsgQueue created for 0.0.0.0:161 with priority 'normal'
710
2018-02-22_21:21:32.797856 <snmpagent:INFO> SNMP UDP Listener initialized on port 161
711
Initializing module MrcpSpeech
712
Initializing module Users Management
713
Initializing module GSM Transceiver
714
2018-02-22_21:21:32.799046 <gsmtrx:INFO> Socket(control) bound on 127.0.0.1:5700 (remote: 127.0.0.1:5701) [0x564a86b97ce0]
715
2018-02-22_21:21:32.799125 <gsmtrx:INFO> Module state changed Idle -> Waiting
716
Initializing module Signalling Channel
717
2018-02-22_21:21:32.799925 <sig/isup.decode:INFO> ISUP Call Controller pointcode-type=ITU format=alaw plan/type/pres/screen=unknown/unknown/allowed/user-provided caller-category=ordinary remote-pointcode=1-1-1 SIF/SSF=5/128 lockcircuits= userpartavail=false lockgroup=true mediareq=no outboundsls=cic [0x564a86b9cc10]
718
2018-02-22_21:21:32.799997 <sig/isup.encode:INFO> ISUP Call Controller pointcode-type=ITU format=alaw plan/type/pres/screen=unknown/unknown/allowed/user-provided caller-category=ordinary remote-pointcode=1-1-1 SIF/SSF=5/128 lockcircuits= userpartavail=false lockgroup=true mediareq=no outboundsls=cic [0x564a86b9e670]
719
2018-02-22_21:21:32.800090 <sig:NOTE> Section 'tcapuser_test'. Unknown/missing type '(null)'
720
Initializing module Radio Test
721
Initialization complete
722
2018-02-22_21:21:32.800386 <ALL> Engine dispatching start message
723
2018-02-22_21:21:32.800670 <ybts:NOTE> State changed Idle -> Starting restart counter 1/10
724
2018-02-22_21:21:32.800766 <transceiver:ALL> Started worker thread [0x564a86b941e0]
725
2018-02-22_21:21:32.800803 <transceiver:INFO> Started [0x564a86b941e0]
726
2018-02-22_21:21:32.800970 <mbts:ALL> Started worker thread [0x564a86b943f0]
727
2018-02-22_21:21:32.801005 <mbts:INFO> Started [0x564a86b943f0]
728
2018-02-22_21:21:32.801122 <ybts-command:INFO> Started [0x564a86b94650]
729
2018-02-22_21:21:32.801384 <ybts-signalling:ALL> Started worker thread [0x564a86b94a90]
730
2018-02-22_21:21:32.801435 <ybts-signalling:INFO> Started [0x564a86b94a90]
731
2018-02-22_21:21:32.801505 <ybts-media:ALL> Started worker thread [0x564a86b947b0]
732
2018-02-22_21:21:32.801590 <ybts-media:INFO> Started [0x564a86b947b0]
733
2018-02-22_21:21:32.801689 <ybts:ALL> Starting peer '/usr/lib64/yate/server/bts/mbts' '(null)'
734
2018-02-22_21:21:32.802720 <ybts:INFO> Started peer pid=8944
735
2018-02-22_21:21:32.802867 <ybts:NOTE> State changed Starting -> WaitHandshake
736
2018-02-22_21:21:32.802924 <ybts:ALL> Restart scheduled in 120000ms [0x7f57e69fb440]
737
MBTS connected to YBTS
738
Yate-BTS MBTS Component

739
Copyright 2008, 2009, 2010 Free Software Foundation, Inc.

740
Copyright 2010 Kestrel Signal Processing, Inc.

741
Copyright 2011, 2012, 2013 Range Networks, Inc.

742
Copyright 2013, 2014 Null Team Impex SRL

743
Copyright 2014 Legba, Inc.

744
"OpenBTS" is a registered trademark of Range Networks, Inc.

745

746
Contributors:

747
  SC Null Team Impex SRL:

748
    Paul Chitescu

749
  Legba, Inc.

750
    David Burgess

751
  Range Networks, Inc.:

752
    David Burgess, Harvind Samra, Donald Kirker, Doug Brown,

753
    Pat Thompson, Kurtis Heimerl

754
  Kestrel Signal Processing, Inc.:

755
    David Burgess, Harvind Samra, Raffi Sevlian, Roshan Baliga

756
  GNU Radio:

757
    Johnathan Corgan

758
  Others:

759
    Anne Kwong, Jacob Appelbaum, Joshua Lackey, Alon Levy

760
    Alexander Chemeris, Alberto Escudero-Pascual

761
Incorporated L/GPL libraries and components:

762
  libusb, LGPL 2.1, various copyright holders, www.libusb.org

763
Incorporated BSD/MIT-style libraries and components:

764
  A5/1 Pedagogical Implementation, Simplified BSD License,

765
    Copyright 1998-1999 Marc Briceno, Ian Goldberg, and David Wagner

766
Incorporated public domain libraries and components:

767
  sqlite3, released to public domain 15 Sept 2001, www.sqlite.org

768

769

770
This program comes with ABSOLUTELY NO WARRANTY.

771

772
Use of this software may be subject to other legal restrictions,

773
including patent licensing and radio spectrum licensing.

774
All users of this software are expected to comply with applicable

775
regulations and laws.  See the LEGAL file in the source code for

776
more information.

777

778
Release 6.0.0 formal build date Jan 19 2018 rev

779
2018-02-22_21:21:32.816947 <mbts:NOTE> GSMConfig.cpp:80:regenerateBeacon: regenerating system information messages, changemark 1
780
Starting MBTS...
781
2018-02-22_21:21:32.817782 <bladerf/1:ALL> Interface created [0x7f57bc000e40]
782
2018-02-22_21:21:32.823367 <bladerf:ALL> Initialized libusb context
783
2018-02-22_21:21:32.823478 <bladerf/1:ALL> Created device (0x7f57bc0208b0) [0x7f57bc000e40]
784
2018-02-22_21:21:32.824231 <ybts-signalling:INFO> Received [0x564a86b94a90]

785
-----

786
Primitive: Handshake

787
Info: 0

788
-----
789
2018-02-22_21:21:32.824284 <ybts:NOTE> State changed WaitHandshake -> Running
790
2018-02-22_21:21:32.824316 <ybts-signalling:INFO> Sending [0x564a86b94a90]

791
-----

792
Primitive: Handshake

793
Info: 0

794
-----
795
2018-02-22_21:21:32.834775 <javascript:INFO> Parsed 'nipc' script: /usr/share/yate/scripts/nipc.js
796
2018-02-22_21:21:32.838604 <nipc:INFO> Finished reading saved registered subscribers. Found 1 registered_subscribers.
797
2018-02-22_21:21:32.839207 <nipc:INFO> Checked that only NiPC is enabled.
798
2018-02-22_21:21:32.839297 <ALL> Rescanning handler list for 'engine.start' [0x7ffc6f717670] at priority 100
799
Yate engine is initialized and starting up on lifebook.home.lan
800
2018-02-22_21:21:32.839572 <INFO> Creating first 1 message dispatching threads
801
2018-02-22_21:21:32.890655 <bladerf/1:ALL> Opened device bus=4 addr=2 [0x7f57bc000e40]
802
2018-02-22_21:21:33.190661 <bladerf/1:ALL> Reset USB device bus=4 addr=2 [0x7f57bc000e40]
803
2018-02-22_21:21:33.190834 <bladerf/1:ALL> Device closed [0x7f57bc000e40]
804
2018-02-22_21:21:33.191823 <bladerf/1:ALL> Opened device bus=4 addr=2 [0x7f57bc000e40]
805
2018-02-22_21:21:33.191908 <bladerf/1:ALL> Initialized I/O TX buffers=8 samples/buffer=252 total_bytes=8192 tx_min_buffers=8 [0x7f57bc000e40]
806
2018-02-22_21:21:33.192002 <bladerf/1:ALL> Initialized I/O RX buffers=8 samples/buffer=252 total_bytes=8192 [0x7f57bc000e40]
807
2018-02-22_21:21:33.193221 <bladerf/1:ALL> Updating FPGA [0x7f57bc000e40]
808
2018-02-22_21:21:33.199882 <bladerf/1:ALL> Loading FPGA from '/usr/share/yate/data/hostedx40.rbf' len=1191788 [0x7f57bc000e40]
809
2018-02-22_21:21:33.404053 <bladerf/1:ALL> Loaded FPGA from '/usr/share/yate/data/hostedx40.rbf' [0x7f57bc000e40]
810
2018-02-22_21:21:33.418452 <bladerf/1:ALL> Writing '4740592964367937' to LMS [0x7f57bc000e40]
811
2018-02-22_21:21:33.421032 <bladerf/1:ALL> RX DC offset I set to 0 (from 0) reg=0x80 [0x7f57bc000e40]
812
2018-02-22_21:21:33.421942 <bladerf/1:ALL> RX DC offset Q set to 0 (from 0) reg=0x80 [0x7f57bc000e40]
813
2018-02-22_21:21:33.424431 <bladerf/1:ALL> TX DC offset I set to 0 (from 0) reg=0x80 [0x7f57bc000e40]
814
2018-02-22_21:21:33.425168 <bladerf/1:ALL> TX DC offset Q set to 0 (from 0) reg=0x80 [0x7f57bc000e40]
815
2018-02-22_21:21:33.426014 <bladerf/1:INFO> RX VGA1 set to 30dB 0x78 (from 30) [0x7f57bc000e40]
816
2018-02-22_21:21:33.431912 <bladerf/1:ALL> Opened device [0x7f57bc000e40]

817
-----

818
Address=USB/4/2

819
Serial=a412decab55aafd046a0a568604e786b

820
Speed=HIGH

821
Firmware=1.9.1

822
FPGA=0.1.2 - /usr/share/yate/data/hostedx40.rbf - MD5: ff681baa275d3f01af3d864e737a23ae

823
LMS_Ver=0x22 (2.2)

824
-----
825
2018-02-22_21:21:33.432314 <gsmtrx:ALL> Adjusting parameter arfcns='1' -> 4 [0x7f57bc01f570]
826
2018-02-22_21:21:33.433008 <gsmtrx:ALL> Initialized [0x7f57bc01f570]

827
-----

828
ARFCNs=4

829
oversampling=8

830
-----
831
2018-02-22_21:21:33.438121 <bladerf/1:INFO> TX samplerate set to 2166667 [0x7f57bc000e40]
832
2018-02-22_21:21:33.443285 <bladerf/1:INFO> RX samplerate set to 2166667 [0x7f57bc000e40]
833
2018-02-22_21:21:33.456893 <bladerf/1:INFO> TX LPF bandwidth set to 1500000 (from 1500000, reg=0x3e) [0x7f57bc000e40]
834
2018-02-22_21:21:33.457661 <bladerf/1:INFO> RX LPF bandwidth set to 1500000 (from 1500000, reg=0x3e) [0x7f57bc000e40]
835
2018-02-22_21:21:33.458552 <gsmtrx:INFO> radio_latency_slots changed 0 -> 5 [0x7f57bc01f570]
836
2018-02-22_21:21:33.458602 <gsmtrx:INFO> tx_slots changed 1 -> 16 [0x7f57bc01f570]
837
2018-02-22_21:21:33.458648 <gsmtrx:INFO> Transceiver initialized radio=(0x7f57bc000e40) 'bladerf/1' [0x7f57bc01f570]
838
2018-02-22_21:21:33.458687 <gsmtrx:NOTE> State changed Invalid -> Idle [0x7f57bc01f570]
839
2018-02-22_21:21:33.458829 <gsmtrx:ALL> Starting [0x7f57bc01f570]
840
2018-02-22_21:21:33.458929 <gsmtrx:INFO> Socket(clock) bound on 127.0.0.1:5702 (remote: 127.0.0.1:5703) [0x7f57bc01f570]
841
2018-02-22_21:21:33.458979 <gsmtrx:INFO> ARFCN[0]: Socket(data) bound on 127.0.0.1:5704 (remote: 127.0.0.1:5705) [0x7f57bc00f350]
842
2018-02-22_21:21:33.459044 <gsmtrx:INFO> ARFCN[1]: Socket(data) bound on 127.0.0.1:5706 (remote: 127.0.0.1:5707) [0x7f57bc0317f0]
843
2018-02-22_21:21:33.459082 <gsmtrx:INFO> ARFCN[2]: Socket(data) bound on 127.0.0.1:5708 (remote: 127.0.0.1:5709) [0x7f57bc0380a0]
844
2018-02-22_21:21:33.459158 <gsmtrx:INFO> ARFCN[3]: Socket(data) bound on 127.0.0.1:5710 (remote: 127.0.0.1:5711) [0x7f57bc03bf80]
845
2018-02-22_21:21:33.459181 <gsmtrx:NOTE> State changed Idle -> PowerOff [0x7f57bc01f570]
846
2018-02-22_21:21:33.459231 <gsmtrx:INFO> Module state changed Waiting -> Running
847
2018-02-22_21:21:33.459525 <gsmtrx:ALL> Handling command 'CMD 0 READFACTORY sdrsn' arfcn=0 [0x7f57bc01f570]
848
2018-02-22_21:21:33.459553 <gsmtrx:ALL> Command 'CMD 0 READFACTORY sdrsn' (ARFCN=0) RSP '0'
849
2018-02-22_21:21:33.459850 <gsmtrx:ALL> Handling command 'CMD 0 RXTUNE 1781800' arfcn=0 [0x7f57bc01f570]
850
2018-02-22_21:21:33.469390 <bladerf/1:INFO> LNA 2 selected [0x7f57bc000e40]
851
2018-02-22_21:21:33.470536 <bladerf/1:ALL> FrequencyOffset set to 128 [0x7f57bc000e40]
852
2018-02-22_21:21:33.470591 <bladerf/1:INFO> RX frequency set to 1782.4MHz offset=128 [0x7f57bc000e40]
853
2018-02-22_21:21:33.472163 <gsmtrx:ALL> Command 'CMD 0 RXTUNE 1781800' (ARFCN=0) RSP '1782400000'
854
2018-02-22_21:21:33.472389 <gsmtrx:ALL> Handling command 'CMD 0 TXTUNE 1876800' arfcn=0 [0x7f57bc01f570]
855
2018-02-22_21:21:33.482150 <bladerf/1:INFO> PA2 enabled [0x7f57bc000e40]
856
2018-02-22_21:21:33.483393 <bladerf/1:ALL> FrequencyOffset set to 128 [0x7f57bc000e40]
857
2018-02-22_21:21:33.483622 <bladerf/1:INFO> TX frequency set to 1877.4MHz offset=128 [0x7f57bc000e40]
858
2018-02-22_21:21:33.485271 <gsmtrx:ALL> Command 'CMD 0 TXTUNE 1876800' (ARFCN=0) RSP '1877400000'
859
2018-02-22_21:21:33.485598 <gsmtrx:ALL> Handling command 'CMD 0 SETTSC 2' arfcn=0 [0x7f57bc01f570]
860
2018-02-22_21:21:33.485832 <gsmtrx:INFO> TSC changed 0 -> 2 [0x7f57bc01f570]
861
2018-02-22_21:21:33.486012 <gsmtrx:ALL> Command 'CMD 0 SETTSC 2' (ARFCN=0) RSP '2'
862
2018-02-22_21:21:33.486289 <gsmtrx:ALL> Handling command 'CMD 0 SETMAXDLY 2' arfcn=0 [0x7f57bc01f570]
863
2018-02-22_21:21:33.486484 <gsmtrx:ALL> Command 'CMD 0 SETMAXDLY 2' (ARFCN=0) RSP '2'
864
2018-02-22_21:21:33.486728 <gsmtrx:ALL> Handling command 'CMD 0 SETRXGAIN 0' arfcn=0 [0x7f57bc01f570]
865
2018-02-22_21:21:33.488407 <gsmtrx:ALL> Command 'CMD 0 SETRXGAIN 0' (ARFCN=0) RSP '0'
866
2018-02-22_21:21:33.488656 <gsmtrx:ALL> Handling command 'CMD 0 POWERON' arfcn=0 [0x7f57bc01f570]
867
2018-02-22_21:21:33.488880 <gsmtrx:INFO> Starting radio [0x7f57bc01f570]
868
2018-02-22_21:21:33.489061 <bladerf/1:ALL> Initializing ... [0x7f57bc000e40]
869
2018-02-22_21:21:33.490377 <bladerf/1:ALL> Enabled timestamps [0x7f57bc000e40]
870
2018-02-22_21:21:33.511704 <bladerf/1:ALL> Enabled RF TX [0x7f57bc000e40]
871
2018-02-22_21:21:33.523687 <bladerf/1:ALL> Enabled RF RX [0x7f57bc000e40]
872
2018-02-22_21:21:33.523817 <bladerf/1:NOTE> Powered ON the radio [0x7f57bc000e40]
873
2018-02-22_21:21:33.524128 <bladerf/1:ALL> Initialized [0x7f57bc000e40]
874
2018-02-22_21:21:33.528409 <gsmtrx:NOTE> State changed PowerOff -> PowerOn [0x7f57bc01f570]
875
2018-02-22_21:21:33.528508 <gsmtrx:ALL> Command 'CMD 0 POWERON' (ARFCN=0) RSP '(null)'
876
2018-02-22_21:21:33.528728 <gsmtrx:ALL> Handling command 'CMD 0 SETPOWER 0' arfcn=0 [0x7f57bc01f570]
877
2018-02-22_21:21:33.529759 <bladerf/1:INFO> TX VGA2 set to 25dB (0xc8) [0x7f57bc000e40]
878
2018-02-22_21:21:33.529812 <gsmtrx:INFO> Set Tx gain=0 power_scaling=1 [0x7f57bc01f570]
879
2018-02-22_21:21:33.529843 <gsmtrx:ALL> Command 'CMD 0 SETPOWER 0' (ARFCN=0) RSP '0'
880
2018-02-22_21:21:33.530004 <gsmtrx:ALL> Handling command 'CMD 0 SETSLOT 0 5' arfcn=0 [0x7f57bc01f570]
881
2018-02-22_21:21:33.530034 <gsmtrx:ALL> ARFCN[0]: Slot 0 type set to 5 'V' [0x7f57bc00f350]
882
2018-02-22_21:21:33.530058 <gsmtrx:ALL> ARFCN[0]: Slot 0 burst type changed None -> Check [0x7f57bc00f350]
883
2018-02-22_21:21:33.530082 <gsmtrx:ALL> Command 'CMD 0 SETSLOT 0 5' (ARFCN=0) RSP '0 5'
884
2018-02-22_21:21:33.530401 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f57bc01f570]
885
2018-02-22_21:21:33.530437 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0) RSP '0'
886
2018-02-22_21:21:33.530671 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f57bc01f570]
887
2018-02-22_21:21:33.530703 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0) RSP '0'
888
2018-02-22_21:21:33.530887 <bladerf/1:ALL> RX: timestamp jumped by 45735 to 0 in buffer 1/8 [0x7f57bc000e40]
889
2018-02-22_21:21:33.530930 <bladerf/1:ALL> RX: timestamp adjusted by 45735 to 45987 [0x7f57bc000e40]
890
2018-02-22_21:21:33.530959 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f57bc01f570]
891
2018-02-22_21:21:33.530991 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0) RSP '0'
892
2018-02-22_21:21:33.531367 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f57bc01f570]
893
2018-02-22_21:21:33.531407 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0) RSP '0'
894
2018-02-22_21:21:33.531593 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f57bc01f570]
895
2018-02-22_21:21:33.531623 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0) RSP '0'
896
2018-02-22_21:21:33.531824 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f57bc01f570]
897
2018-02-22_21:21:33.531857 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0) RSP '0'
898
2018-02-22_21:21:33.532011 <bladerf/1:ALL> RX: timestamp jumped by 29783 to 55059 in buffer 6/8 [0x7f57bc000e40]
899
2018-02-22_21:21:33.532043 <bladerf/1:ALL> RX: timestamp adjusted by 29783 to 85094 [0x7f57bc000e40]
900
2018-02-22_21:21:33.532985 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f57bc01f570]
901
2018-02-22_21:21:33.533061 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0) RSP '0'
902
2018-02-22_21:21:33.533423 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f57bc01f570]
903
2018-02-22_21:21:33.533459 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0) RSP '0'
904
2018-02-22_21:21:33.534125 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f57bc01f570]
905
2018-02-22_21:21:33.534196 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0) RSP '0'
906
2018-02-22_21:21:33.534512 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f57bc01f570]
907
2018-02-22_21:21:33.534547 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0) RSP '0'
908
2018-02-22_21:21:33.535948 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f57bc01f570]
909
2018-02-22_21:21:33.536010 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0) RSP '0'
910
2018-02-22_21:21:33.536652 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f57bc01f570]
911
2018-02-22_21:21:33.536722 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0) RSP '0'
912
2018-02-22_21:21:33.537098 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f57bc01f570]
913
2018-02-22_21:21:33.537139 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0) RSP '0'
914
2018-02-22_21:21:33.537752 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f57bc01f570]
915
2018-02-22_21:21:33.537791 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0) RSP '0'
916
2018-02-22_21:21:33.538123 <mbts:NOTE> GSMConfig.cpp:532:createCombinationI: Configuring combination I on C0T1
917
2018-02-22_21:21:33.538277 <gsmtrx:ALL> ARFCN[0]: Slot 1 type set to 1 'I' [0x7f57bc00f350]
918
2018-02-22_21:21:33.538307 <gsmtrx:ALL> ARFCN[0]: Slot 1 burst type changed None -> Normal [0x7f57bc00f350]
919
2018-02-22_21:21:33.538333 <gsmtrx:ALL> Command 'CMD 0 SETSLOT 1 1' (ARFCN=0) RSP '1 1'
920
2018-02-22_21:21:33.539835 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 1' arfcn=0 [0x7f57bc01f570]
921
2018-02-22_21:21:33.539885 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 1' (ARFCN=0) RSP '1'
922
2018-02-22_21:21:33.540252 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 1' arfcn=0 [0x7f57bc01f570]
923
2018-02-22_21:21:33.540284 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 1' (ARFCN=0) RSP '1'
924
2018-02-22_21:21:33.540992 <mbts:NOTE> GSMConfig.cpp:532:createCombinationI: Configuring combination I on C0T2
925
2018-02-22_21:21:33.541055 <gsmtrx:ALL> Handling command 'CMD 0 SETSLOT 2 1' arfcn=0 [0x7f57bc01f570]
926
2018-02-22_21:21:33.541085 <gsmtrx:ALL> ARFCN[0]: Slot 2 type set to 1 'I' [0x7f57bc00f350]
927
2018-02-22_21:21:33.541109 <gsmtrx:ALL> ARFCN[0]: Slot 2 burst type changed None -> Normal [0x7f57bc00f350]
928
2018-02-22_21:21:33.541137 <gsmtrx:ALL> Command 'CMD 0 SETSLOT 2 1' (ARFCN=0) RSP '2 1'
929
2018-02-22_21:21:33.541830 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 2' arfcn=0 [0x7f57bc01f570]
930
2018-02-22_21:21:33.541872 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 2' (ARFCN=0) RSP '2'
931
2018-02-22_21:21:33.542358 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 2' arfcn=0 [0x7f57bc01f570]
932
2018-02-22_21:21:33.542407 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 2' (ARFCN=0) RSP '2'
933
2018-02-22_21:21:33.542671 <mbts:NOTE> GSMConfig.cpp:532:createCombinationI: Configuring combination I on C0T3
934
2018-02-22_21:21:33.542768 <gsmtrx:ALL> Handling command 'CMD 0 SETSLOT 3 1' arfcn=0 [0x7f57bc01f570]
935
2018-02-22_21:21:33.542797 <gsmtrx:ALL> ARFCN[0]: Slot 3 type set to 1 'I' [0x7f57bc00f350]
936
2018-02-22_21:21:33.542823 <gsmtrx:ALL> ARFCN[0]: Slot 3 burst type changed None -> Normal [0x7f57bc00f350]
937
2018-02-22_21:21:33.542851 <gsmtrx:ALL> Command 'CMD 0 SETSLOT 3 1' (ARFCN=0) RSP '3 1'
938
2018-02-22_21:21:33.543655 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 3' arfcn=0 [0x7f57bc01f570]
939
2018-02-22_21:21:33.543697 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 3' (ARFCN=0) RSP '3'
940
2018-02-22_21:21:33.543982 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 3' arfcn=0 [0x7f57bc01f570]
941
2018-02-22_21:21:33.544016 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 3' (ARFCN=0) RSP '3'
942
2018-02-22_21:21:33.544641 <mbts:NOTE> GSMConfig.cpp:532:createCombinationI: Configuring combination I on C0T4
943
2018-02-22_21:21:33.544695 <gsmtrx:ALL> Handling command 'CMD 0 SETSLOT 4 1' arfcn=0 [0x7f57bc01f570]
944
2018-02-22_21:21:33.544722 <gsmtrx:ALL> ARFCN[0]: Slot 4 type set to 1 'I' [0x7f57bc00f350]
945
2018-02-22_21:21:33.544748 <gsmtrx:ALL> ARFCN[0]: Slot 4 burst type changed None -> Normal [0x7f57bc00f350]
946
2018-02-22_21:21:33.544775 <gsmtrx:ALL> Command 'CMD 0 SETSLOT 4 1' (ARFCN=0) RSP '4 1'
947
2018-02-22_21:21:33.545315 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 4' arfcn=0 [0x7f57bc01f570]
948
2018-02-22_21:21:33.545365 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 4' (ARFCN=0) RSP '4'
949
2018-02-22_21:21:33.545650 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 4' arfcn=0 [0x7f57bc01f570]
950
2018-02-22_21:21:33.545684 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 4' (ARFCN=0) RSP '4'
951
2018-02-22_21:21:33.545953 <mbts:NOTE> GSMConfig.cpp:532:createCombinationI: Configuring combination I on C0T5
952
2018-02-22_21:21:33.546313 <gsmtrx:ALL> Handling command 'CMD 0 SETSLOT 5 1' arfcn=0 [0x7f57bc01f570]
953
2018-02-22_21:21:33.546347 <gsmtrx:ALL> ARFCN[0]: Slot 5 type set to 1 'I' [0x7f57bc00f350]
954
2018-02-22_21:21:33.546380 <gsmtrx:ALL> ARFCN[0]: Slot 5 burst type changed None -> Normal [0x7f57bc00f350]
955
2018-02-22_21:21:33.546405 <gsmtrx:ALL> Command 'CMD 0 SETSLOT 5 1' (ARFCN=0) RSP '5 1'
956
2018-02-22_21:21:33.547236 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 5' arfcn=0 [0x7f57bc01f570]
957
2018-02-22_21:21:33.547278 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 5' (ARFCN=0) RSP '5'
958
2018-02-22_21:21:33.547728 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 5' arfcn=0 [0x7f57bc01f570]
959
2018-02-22_21:21:33.547765 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 5' (ARFCN=0) RSP '5'
960
2018-02-22_21:21:33.548375 <mbts:NOTE> GSMConfig.cpp:532:createCombinationI: Configuring combination I on C0T6
961
2018-02-22_21:21:33.548546 <gsmtrx:ALL> Handling command 'CMD 0 SETSLOT 6 1' arfcn=0 [0x7f57bc01f570]
962
2018-02-22_21:21:33.548580 <gsmtrx:ALL> ARFCN[0]: Slot 6 type set to 1 'I' [0x7f57bc00f350]
963
2018-02-22_21:21:33.548602 <gsmtrx:ALL> ARFCN[0]: Slot 6 burst type changed None -> Normal [0x7f57bc00f350]
964
2018-02-22_21:21:33.548629 <gsmtrx:ALL> Command 'CMD 0 SETSLOT 6 1' (ARFCN=0) RSP '6 1'
965
2018-02-22_21:21:33.549419 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 6' arfcn=0 [0x7f57bc01f570]
966
2018-02-22_21:21:33.549471 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 6' (ARFCN=0) RSP '6'
967
2018-02-22_21:21:33.549989 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 6' arfcn=0 [0x7f57bc01f570]
968
2018-02-22_21:21:33.550026 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 6' (ARFCN=0) RSP '6'
969
2018-02-22_21:21:33.550434 <mbts:NOTE> GSMConfig.cpp:532:createCombinationI: Configuring combination I on C0T7
970
2018-02-22_21:21:33.550759 <gsmtrx:ALL> Handling command 'CMD 0 SETSLOT 7 1' arfcn=0 [0x7f57bc01f570]
971
2018-02-22_21:21:33.550792 <gsmtrx:ALL> ARFCN[0]: Slot 7 type set to 1 'I' [0x7f57bc00f350]
972
2018-02-22_21:21:33.550816 <gsmtrx:ALL> ARFCN[0]: Slot 7 burst type changed None -> Normal [0x7f57bc00f350]
973
2018-02-22_21:21:33.550842 <gsmtrx:ALL> Command 'CMD 0 SETSLOT 7 1' (ARFCN=0) RSP '7 1'
974
2018-02-22_21:21:33.551429 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 7' arfcn=0 [0x7f57bc01f570]
975
2018-02-22_21:21:33.551468 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 7' (ARFCN=0) RSP '7'
976
2018-02-22_21:21:33.552118 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 7' arfcn=0 [0x7f57bc01f570]
977
2018-02-22_21:21:33.552157 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 7' (ARFCN=0) RSP '7'
978
2018-02-22_21:21:33.552847 <gsmtrx:ALL> Handling command 'CMD 0 SETPOWER 10' arfcn=0 [0x7f57bc01f570]
979
2018-02-22_21:21:33.579911 <bladerf/1:INFO> TX VGA2 set to 15dB (0x78) [0x7f57bc000e40]
980
2018-02-22_21:21:33.579999 <gsmtrx:INFO> Set Tx gain=-10 power_scaling=1 [0x7f57bc01f570]
981
2018-02-22_21:21:33.580045 <gsmtrx:ALL> Command 'CMD 0 SETPOWER 10' (ARFCN=0) RSP '10'
982
2018-02-22_21:21:33.656402 <bladerf/1:ALL> RX DC offset Q set to 1 (from 1) reg=0x81 [0x7f57bc000e40]
983
2018-02-22_21:21:33.685002 <cpuload:NOTE> Updating CPU core number from 1 to 2
984
2018-02-22_21:21:33.796016 <bladerf/1:ALL> RX DC offset Q set to 2 (from 2) reg=0x82 [0x7f57bc000e40]
985
2018-02-22_21:21:33.832324 <mbts:MILD> iputils.cpp:456:ip_finddns: GGSN: DNS servers: 192.168.200.254 0.0.0.0
986
2018-02-22_21:21:33.940412 <bladerf/1:ALL> RX DC offset Q set to 3 (from 3) reg=0x83 [0x7f57bc000e40]
987
RTNETLINK answers: File exists
988
2018-02-22_21:21:34.087281 <bladerf/1:ALL> RX DC offset Q set to 4 (from 4) reg=0x84 [0x7f57bc000e40]
989
2018-02-22_21:21:34.242648 <bladerf/1:ALL> RX DC offset Q set to 5 (from 5) reg=0x85 [0x7f57bc000e40]
990
MBTS ready
991
2018-02-22_21:21:34.333949 <ybts-signalling:INFO> Received [0x564a86b94a90]

992
-----

993
Primitive: RadioReady

994
Info: 0

995
-----
996
2018-02-22_21:21:34.333975 <ybts:NOTE> State changed Running -> RadioUp
997
2018-02-22_21:21:34.399925 <bladerf/1:ALL> RX DC offset Q set to 6 (from 6) reg=0x86 [0x7f57bc000e40]
998
2018-02-22_21:21:34.562670 <bladerf/1:ALL> RX DC offset Q set to 7 (from 7) reg=0x87 [0x7f57bc000e40]
999
2018-02-22_21:21:34.731329 <bladerf/1:ALL> RX DC offset Q set to 8 (from 8) reg=0x88 [0x7f57bc000e40]
1000
2018-02-22_21:21:34.911827 <bladerf/1:ALL> RX DC offset Q set to 9 (from 9) reg=0x89 [0x7f57bc000e40]
1001
2018-02-22_21:21:35.097677 <bladerf/1:ALL> RX DC offset Q set to 10 (from 10) reg=0x8a [0x7f57bc000e40]
1002
2018-02-22_21:21:35.295069 <bladerf/1:ALL> RX DC offset Q set to 11 (from 11) reg=0x8b [0x7f57bc000e40]
1003
2018-02-22_21:21:35.495063 <bladerf/1:ALL> RX DC offset Q set to 12 (from 12) reg=0x8c [0x7f57bc000e40]
1004
2018-02-22_21:21:35.708059 <bladerf/1:ALL> RX DC offset Q set to 13 (from 13) reg=0x8d [0x7f57bc000e40]
1005
2018-02-22_21:21:35.930476 <bladerf/1:ALL> RX DC offset Q set to 14 (from 14) reg=0x8e [0x7f57bc000e40]
1006
2018-02-22_21:21:36.165989 <bladerf/1:ALL> RX DC offset Q set to 15 (from 15) reg=0x8f [0x7f57bc000e40]
1007
2018-02-22_21:21:36.412604 <bladerf/1:ALL> RX DC offset Q set to 16 (from 16) reg=0x90 [0x7f57bc000e40]
1008
2018-02-22_21:21:36.684390 <bladerf/1:ALL> RX DC offset Q set to 17 (from 17) reg=0x91 [0x7f57bc000e40]
1009
2018-02-22_21:21:36.969869 <bladerf/1:ALL> RX DC offset Q set to 18 (from 18) reg=0x92 [0x7f57bc000e40]
1010
2018-02-22_21:21:37.273284 <bladerf/1:ALL> RX DC offset Q set to 19 (from 19) reg=0x93 [0x7f57bc000e40]
1011
2018-02-22_21:21:37.562945 <gsmtrx:ALL> Handling command 'CMD 0 SETSLOT 1 10' arfcn=0 [0x7f57bc01f570]
1012
2018-02-22_21:21:37.562986 <gsmtrx:ALL> ARFCN[0]: Slot 1 type set to 10 'IGPRS' [0x7f57bc00f350]
1013
2018-02-22_21:21:37.563004 <gsmtrx:ALL> Command 'CMD 0 SETSLOT 1 10' (ARFCN=0) RSP '1 10'
1014
2018-02-22_21:21:37.564282 <gsmtrx:ALL> Handling command 'CMD 0 SETSLOT 2 10' arfcn=0 [0x7f57bc01f570]
1015
2018-02-22_21:21:37.564303 <gsmtrx:ALL> ARFCN[0]: Slot 2 type set to 10 'IGPRS' [0x7f57bc00f350]
1016
2018-02-22_21:21:37.564317 <gsmtrx:ALL> Command 'CMD 0 SETSLOT 2 10' (ARFCN=0) RSP '2 10'
1017
2018-02-22_21:21:37.565552 <gsmtrx:ALL> Handling command 'CMD 0 SETSLOT 3 10' arfcn=0 [0x7f57bc01f570]
1018
2018-02-22_21:21:37.565573 <gsmtrx:ALL> ARFCN[0]: Slot 3 type set to 10 'IGPRS' [0x7f57bc00f350]
1019
2018-02-22_21:21:37.565587 <gsmtrx:ALL> Command 'CMD 0 SETSLOT 3 10' (ARFCN=0) RSP '3 10'
1020
2018-02-22_21:21:37.596906 <bladerf/1:ALL> RX DC offset Q set to 20 (from 20) reg=0x94 [0x7f57bc000e40]
1021
2018-02-22_21:21:37.947912 <bladerf/1:ALL> RX DC offset Q set to 21 (from 21) reg=0x95 [0x7f57bc000e40]
1022
2018-02-22_21:21:38.323681 <bladerf/1:ALL> RX DC offset Q set to 22 (from 22) reg=0x96 [0x7f57bc000e40]
1023
2018-02-22_21:21:38.731173 <bladerf/1:ALL> RX DC offset Q set to 23 (from 23) reg=0x97 [0x7f57bc000e40]
1024
2018-02-22_21:21:39.176818 <bladerf/1:ALL> RX DC offset Q set to 24 (from 24) reg=0x98 [0x7f57bc000e40]
1025
2018-02-22_21:21:39.580869 <gsmtrx:ALL> Handling command 'CMD 0 SETPOWER 9' arfcn=0 [0x7f57bc01f570]
1026
2018-02-22_21:21:39.586208 <bladerf/1:INFO> TX VGA2 set to 16dB (0x80) [0x7f57bc000e40]
1027
2018-02-22_21:21:39.586248 <gsmtrx:INFO> Set Tx gain=-9 power_scaling=1 [0x7f57bc01f570]
1028
2018-02-22_21:21:39.586291 <gsmtrx:ALL> Command 'CMD 0 SETPOWER 9' (ARFCN=0) RSP '9'
1029
2018-02-22_21:21:39.700830 <bladerf/1:ALL> RX DC offset Q set to 25 (from 25) reg=0x99 [0x7f57bc000e40]
1030
2018-02-22_21:21:40.265616 <bladerf/1:ALL> RX DC offset Q set to 26 (from 26) reg=0x9a [0x7f57bc000e40]
1031
2018-02-22_21:21:40.913261 <bladerf/1:ALL> RX DC offset Q set to 27 (from 27) reg=0x9b [0x7f57bc000e40]
1032
2018-02-22_21:21:41.584524 <gsmtrx:ALL> ARFCN[0]: Slot 6. Excessive TOA error=4 peak/mean=3.13645 count=1 [0x7f57bc00f350]
1033
2018-02-22_21:21:41.644672 <bladerf/1:ALL> RX DC offset Q set to 28 (from 28) reg=0x9c [0x7f57bc000e40]
1034
2018-02-22_21:21:42.522810 <bladerf/1:ALL> RX DC offset Q set to 29 (from 29) reg=0x9d [0x7f57bc000e40]
1035
2018-02-22_21:21:43.617075 <bladerf/1:ALL> RX DC offset Q set to 30 (from 30) reg=0x9e [0x7f57bc000e40]
1036
2018-02-22_21:21:45.102001 <bladerf/1:ALL> RX DC offset Q set to 31 (from 31) reg=0x9f [0x7f57bc000e40]
1037
2018-02-22_21:21:45.586914 <gsmtrx:ALL> Handling command 'CMD 0 SETPOWER 8' arfcn=0 [0x7f57bc01f570]
1038
2018-02-22_21:21:45.590439 <bladerf/1:INFO> TX VGA2 set to 17dB (0x88) [0x7f57bc000e40]
1039
2018-02-22_21:21:45.590478 <gsmtrx:INFO> Set Tx gain=-8 power_scaling=1 [0x7f57bc01f570]
1040
2018-02-22_21:21:45.590509 <gsmtrx:ALL> Command 'CMD 0 SETPOWER 8' (ARFCN=0) RSP '8'
1041
2018-02-22_21:21:47.253869 <gsmtrx:ALL> ARFCN[0]: Slot 2. Excessive TOA error=-4 peak/mean=3.76476 count=1 [0x7f57bc00f350]
1042
2018-02-22_21:21:47.700246 <bladerf/1:ALL> RX DC offset Q set to 32 (from 32) reg=0xa0 [0x7f57bc000e40]
1043
2018-02-22_21:21:51.591062 <gsmtrx:ALL> Handling command 'CMD 0 SETPOWER 7' arfcn=0 [0x7f57bc01f570]
1044
2018-02-22_21:21:51.852536 <bladerf/1:INFO> TX VGA2 set to 18dB (0x90) [0x7f57bc000e40]
1045
2018-02-22_21:21:51.852589 <gsmtrx:INFO> Set Tx gain=-7 power_scaling=1 [0x7f57bc01f570]
1046
2018-02-22_21:21:51.852621 <gsmtrx:ALL> Command 'CMD 0 SETPOWER 7' (ARFCN=0) RSP '7'
1047
2018-02-22_21:21:53.224611 <gsmtrx:ALL> ARFCN[0]: Slot 6. Excessive TOA error=-4 peak/mean=3.21378 count=1 [0x7f57bc00f350]
1048
2018-02-22_21:21:54.964631 <gsmtrx:ALL> ARFCN[0]: Slot 6. Excessive TOA error=-4 peak/mean=3.1045 count=1 [0x7f57bc00f350]
1049
2018-02-22_21:21:57.356329 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f57bc01f570]
1050
2018-02-22_21:21:57.356383 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0) RSP '0'
1051
2018-02-22_21:21:57.356874 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f57bc01f570]
1052
2018-02-22_21:21:57.356909 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0) RSP '0'
1053
2018-02-22_21:21:57.689605 <ybts-signalling:INFO> Received [0x564a86b94a90]

1054
-----

1055
Primitive: PhysicalInfo

1056
Info: 0

1057
Connection: 1

1058

1059
<PhysicalInfo>TA=0 TE=2.000 UpRSSI=-4 TxPwr=33 DnRSSIdBm=-111 time=1519330917.370</PhysicalInfo>

1060
-----
1061
2018-02-22_21:21:57.690401 <ybts-signalling:INFO> Received [0x564a86b94a90]

1062
-----

1063
Primitive: L3Message

1064
Info: 0

1065
Connection: 1

1066

1067
<MM>

1068
  <SkipIndicator>0</SkipIndicator>

1069
  <NSD>0</NSD>

1070
  <Message type="LocationUpdatingRequest">

1071
    <LocationUpdatingType>

1072
      <FOR>false</FOR>

1073
      <LUT>IMSI-attach</LUT>

1074
    </LocationUpdatingType>

1075
    <CKSN>no-key/reserved</CKSN>

1076
    <LAI>

1077
      <PLMNidentity>00101</PLMNidentity>

1078
      <LAC>0001</LAC>

1079
    </LAI>

1080
    <MSClassmark1>

1081
      <RFPowerCapability>class1</RFPowerCapability>

1082
      <RevisionLevel>GSM-phase2</RevisionLevel>

1083
      <Flags>ES-IND</Flags>

1084
    </MSClassmark1>

1085
    <MobileIdentity>

1086
      <TMSI>007b0002</TMSI>

1087
    </MobileIdentity>

1088
  </Message>

1089
</MM>

1090
-----
1091
2018-02-22_21:21:57.690465 <ybts-signalling:ALL> Added connection (0x7f57c0005550,1) [0x564a86b94a90]
1092
2018-02-22_21:21:57.690509 <ybts-mm:ALL> Handling LocationUpdatingRequest conn=1: ident=TMSI/007b0002 LAI=00101_0001 [0x564a86b94e70]
1093
2018-02-22_21:21:57.690549 <ybts-mm:ALL> Added UE (0x7f57c0005690) TMSI=007b0002 IMSI= [0x564a86b94e70]
1094
2018-02-22_21:21:57.691313 <ybts-signalling:ALL> Connection 1 set UE (0x7f57c0005690) TMSI=007b0002 IMSI= [0x7f57c0005550]
1095
2018-02-22_21:21:57.691353 <ybts-signalling:INFO> Sending [0x564a86b94a90]

1096
-----

1097
Primitive: L3Message

1098
Info: 0

1099
Connection: 1

1100

1101
<MM>

1102
  <Message type="IdentityRequest">

1103
    <IdentityType>IMEI</IdentityType>

1104
  </Message>

1105
</MM>

1106
-----
1107
2018-02-22_21:21:57.780672 <bladerf/1:ALL> RX DC offset Q set to 33 (from 33) reg=0xa1 [0x7f57bc000e40]
1108
2018-02-22_21:21:57.853385 <gsmtrx:ALL> Handling command 'CMD 0 SETPOWER 6' arfcn=0 [0x7f57bc01f570]
1109
2018-02-22_21:21:57.856788 <bladerf/1:INFO> TX VGA2 set to 19dB (0x98) [0x7f57bc000e40]
1110
2018-02-22_21:21:57.856838 <gsmtrx:INFO> Set Tx gain=-6 power_scaling=1 [0x7f57bc01f570]
1111
2018-02-22_21:21:57.856877 <gsmtrx:ALL> Command 'CMD 0 SETPOWER 6' (ARFCN=0) RSP '6'
1112
2018-02-22_21:21:58.630156 <ybts-signalling:INFO> Received [0x564a86b94a90]

1113
-----

1114
Primitive: PhysicalInfo

1115
Info: 0

1116
Connection: 1

1117

1118
<PhysicalInfo>TA=2 TE=0.000 UpRSSI=-8 TxPwr=30 DnRSSIdBm=-111 time=1519330917.857</PhysicalInfo>

1119
-----
1120
2018-02-22_21:21:58.630359 <ybts-signalling:INFO> Received [0x564a86b94a90]

1121
-----

1122
Primitive: L3Message

1123
Info: 0

1124
Connection: 1

1125

1126
<MM>

1127
  <SkipIndicator>0</SkipIndicator>

1128
  <NSD>1</NSD>

1129
  <Message type="IdentityResponse">

1130
    <MobileIdentity>

1131
      <IMEI>359810014587110</IMEI>

1132
    </MobileIdentity>

1133
  </Message>

1134
</MM>

1135
-----
1136
2018-02-22_21:21:58.631154 <ybts:ALL> Started location updating thread for (0x7f57c0005690) TMSI=007b0002 IMSI= [0x7f57c0005ab0]
1137
2018-02-22_21:21:58.631900 <nipc:INFO> Got user.register for imsi='001010000000001', tmsi='007b0002'
1138
2018-02-22_21:21:58.633136 <nipc:INFO> Registered imsi 001010000000001 with number 10000001
1139
2018-02-22_21:21:58.633206 <ybts:ALL> Location updating thread for (0x7f57c0005690) TMSI=007b0002 IMSI= terminated [0x7f57c0005ab0]
1140
2018-02-22_21:21:58.633359 <ybts-mm:ALL> UE (0x7f57c0005690) registered TMSI '007b0002' -> '007b0002', IMSI '' -> '001010000000001' conn=1 [0x564a86b94e70]
1141
2018-02-22_21:21:58.633376 <ybts-mm:ALL> UE (0x7f57c0005690) TMSI=007b0002 IMSI=001010000000001 register succeeded [0x564a86b94e70]
1142
2018-02-22_21:21:58.633437 <ybts-signalling:INFO> Sending [0x564a86b94a90]

1143
-----

1144
Primitive: L3Message

1145
Info: 0

1146
Connection: 1

1147

1148
<MM>

1149
  <Message type="LocationUpdatingAccept">

1150
    <LAI>

1151
      <PLMNidentity>00101</PLMNidentity>

1152
      <LAC>0001</LAC>

1153
    </LAI>

1154
    <MobileIdentity>

1155
      <TMSI>007b0002</TMSI>

1156
    </MobileIdentity>

1157
  </Message>

1158
</MM>

1159
-----
1160
2018-02-22_21:21:59.725942 <ybts-signalling:INFO> Received [0x564a86b94a90]

1161
-----

1162
Primitive: PhysicalInfo

1163
Info: 0

1164
Connection: 1

1165

1166
<PhysicalInfo>TA=2 TE=0.000 UpRSSI=-19 TxPwr=6 DnRSSIdBm=-74 time=1519330918.858</PhysicalInfo>

1167
-----
1168
2018-02-22_21:21:59.726054 <ybts-signalling:INFO> Received [0x564a86b94a90]

1169
-----

1170
Primitive: L3Message

1171
Info: 0

1172
Connection: 1

1173

1174
<MM>

1175
  <SkipIndicator>0</SkipIndicator>

1176
  <NSD>0</NSD>

1177
  <Message type="TMSIReallocationComplete"/>

1178
</MM>

1179
-----
1180
2018-02-22_21:21:59.726095 <ybts-signalling:ALL> Releasing connection (0x7f57c0005550,1) [0x564a86b94a90]
1181
2018-02-22_21:21:59.726134 <ybts-signalling:INFO> Sending [0x564a86b94a90]

1182
-----

1183
Primitive: ConnRelease

1184
Info: 0

1185
Connection: 1

1186
-----
1187
2018-02-22_21:22:01.294515 <gsmtrx:ALL> ARFCN[0]: Slot 2. Excessive TOA error=3 peak/mean=3.22482 count=1 [0x7f57bc00f350]
1188
2018-02-22_21:22:01.412836 <gsmtrx:INFO> ARFCN[0]: Slot 0. Receiver clipping 1.87392 dB (FN=6049) count=1 [0x7f57bc00f350]
1189
2018-02-22_21:22:01.863441 <ybts-signalling:INFO> Received [0x564a86b94a90]

1190
-----

1191
Primitive: GprsAttachReq

1192
Info: 0

1193
Connection: 8193

1194

1195
<GprsAttach>

1196
  <tlli>80006001</tlli>

1197
  <ptmsi>00006001</ptmsi>

1198
  <authenticated>false</authenticated>

1199
</GprsAttach>

1200
-----
1201
2018-02-22_21:22:01.863499 <ybts-signalling:ALL> Added GPRS connection (0x7f57c0002860,8193) [0x564a86b94a90]
1202
2018-02-22_21:22:01.863576 <ybts/ps/1:ALL> Created ConnID=8193 [0x7f57c0005d50]
1203
2018-02-22_21:22:01.866242 <INFO> Could not route gprs to 'sgsn' in context 'default', wasted 327 usec
1204
2018-02-22_21:22:01.866363 <ybts/ps/1:MILD> Call rejected error='noroute' reason='No route to call target' [0x7f57c0005d50]
1205
2018-02-22_21:22:01.866446 <ybts/ps/1:INFO> Connection continuing locally in mbts
1206
2018-02-22_21:22:01.866502 <ybts-signalling:INFO> Sending [0x564a86b94a90]

1207
-----

1208
Primitive: GprsAttachLBO

1209
Info: 0

1210
Connection: 8193

1211
-----
1212
2018-02-22_21:22:01.866687 <ybts/ps/1:ALL> Destroyed ConnID=8193 [0x7f57c0005d50]
1213
2018-02-22_21:22:01.867081 <javascript:NOTE> Channel 'ybts/ps/1' already assisted!
1214
2018-02-22_21:22:01.867255 <javascript:ALL> Assistant for 'ybts/ps/1' deleted
1215
2018-02-22_21:22:03.857541 <gsmtrx:ALL> Handling command 'CMD 0 SETPOWER 5' arfcn=0 [0x7f57bc01f570]
1216
2018-02-22_21:22:03.861414 <bladerf/1:INFO> TX VGA2 set to 20dB (0xa0) [0x7f57bc000e40]
1217
2018-02-22_21:22:03.861452 <gsmtrx:INFO> Set Tx gain=-5 power_scaling=1 [0x7f57bc01f570]
1218
2018-02-22_21:22:03.861496 <gsmtrx:ALL> Command 'CMD 0 SETPOWER 5' (ARFCN=0) RSP '5'
1219
2018-02-22_21:22:03.874725 <gsmtrx:ALL> ARFCN[0]: Slot 2. Excessive TOA error=4 peak/mean=4.55786 count=1 [0x7f57bc00f350]
1220
2018-02-22_21:22:09.862129 <gsmtrx:ALL> Handling command 'CMD 0 SETPOWER 4' arfcn=0 [0x7f57bc01f570]
1221
2018-02-22_21:22:09.867180 <bladerf/1:INFO> TX VGA2 set to 21dB (0xa8) [0x7f57bc000e40]
1222
2018-02-22_21:22:09.867219 <gsmtrx:INFO> Set Tx gain=-4 power_scaling=1 [0x7f57bc01f570]
1223
2018-02-22_21:22:09.867251 <gsmtrx:ALL> Command 'CMD 0 SETPOWER 4' (ARFCN=0) RSP '4'
1224
2018-02-22_21:22:15.867902 <gsmtrx:ALL> Handling command 'CMD 0 SETPOWER 3' arfcn=0 [0x7f57bc01f570]
1225
2018-02-22_21:22:16.218670 <bladerf/1:INFO> TX VGA2 set to 22dB (0xb0) [0x7f57bc000e40]
1226
2018-02-22_21:22:16.218725 <gsmtrx:INFO> Set Tx gain=-3 power_scaling=1 [0x7f57bc01f570]
1227
2018-02-22_21:22:16.218757 <gsmtrx:ALL> Command 'CMD 0 SETPOWER 3' (ARFCN=0) RSP '3'
1228
2018-02-22_21:22:16.413650 <gsmtrx:INFO> ARFCN[0]: Slot 0. Receiver clipping 0.428256 dB (FN=9299) count=1 [0x7f57bc00f350]
1229
2018-02-22_21:22:16.922872 <ybts-signalling:INFO> Received [0x564a86b94a90]

1230
-----

1231
Primitive: GprsAttachReq

1232
Info: 0

1233
Connection: 8193

1234

1235
<GprsAttach>

1236
  <tlli>80006001</tlli>

1237
  <ptmsi>00006001</ptmsi>

1238
  <authenticated>false</authenticated>

1239
</GprsAttach>

1240
-----
1241
2018-02-22_21:22:16.922928 <ybts-signalling:ALL> Added GPRS connection (0x7f57c0002040,8193) [0x564a86b94a90]
1242
2018-02-22_21:22:16.922969 <ybts/ps/2:ALL> Created ConnID=8193 [0x7f57c0005d50]
1243
2018-02-22_21:22:16.925424 <INFO> Could not route gprs to 'sgsn' in context 'default', wasted 413 usec
1244
2018-02-22_21:22:16.925518 <ybts/ps/2:MILD> Call rejected error='noroute' reason='No route to call target' [0x7f57c0005d50]
1245
2018-02-22_21:22:16.925558 <ybts/ps/2:INFO> Connection continuing locally in mbts
1246
2018-02-22_21:22:16.925588 <ybts-signalling:INFO> Sending [0x564a86b94a90]

1247
-----

1248
Primitive: GprsAttachLBO

1249
Info: 0

1250
Connection: 8193

1251
-----
1252
2018-02-22_21:22:16.925652 <ybts/ps/2:ALL> Destroyed ConnID=8193 [0x7f57c0005d50]
1253
2018-02-22_21:22:16.930148 <javascript:ALL> Assistant for 'ybts/ps/2' deleted
1254
2018-02-22_21:22:17.224139 <gsmtrx:ALL> ARFCN[0]: Slot 6. Excessive TOA error=-3 peak/mean=3.14377 count=1 [0x7f57bc00f350]
1255
2018-02-22_21:22:17.493957 <gsmtrx:ALL> ARFCN[0]: Slot 2. Excessive TOA error=-5 peak/mean=3.30102 count=1 [0x7f57bc00f350]
1256
2018-02-22_21:22:20.974761 <gsmtrx:ALL> ARFCN[0]: Slot 2. Excessive TOA error=-3 peak/mean=4.05798 count=1 [0x7f57bc00f350]
1257
2018-02-22_21:22:21.604809 <mbts:MILD> SigConnection.cpp:603:process: primitive 2 length 0 took 21879 ms
1258
2018-02-22_21:22:21.604863 <mbts:MILD> SigConnection.cpp:417:process: received GPRS Local Breakout for unmapped id 8193
1259
2018-02-22_21:22:22.219814 <gsmtrx:ALL> Handling command 'CMD 0 SETPOWER 2' arfcn=0 [0x7f57bc01f570]
1260
2018-02-22_21:22:22.225776 <bladerf/1:INFO> TX VGA2 set to 23dB (0xb8) [0x7f57bc000e40]
1261
2018-02-22_21:22:22.225830 <gsmtrx:INFO> Set Tx gain=-2 power_scaling=1 [0x7f57bc01f570]
1262
2018-02-22_21:22:22.225861 <gsmtrx:ALL> Command 'CMD 0 SETPOWER 2' (ARFCN=0) RSP '2'
1263
2018-02-22_21:22:22.773378 <gsmtrx:INFO> ARFCN[0]: Slot 0. Receiver clipping 0.240848 dB (FN=10677) count=1 [0x7f57bc00f350]
1264
2018-02-22_21:22:23.944859 <gsmtrx:ALL> ARFCN[0]: Slot 6. Excessive TOA errors 1 [0x7f57bc00f350]
1265
2018-02-22_21:22:24.452932 <gsmtrx:INFO> ARFCN[0]: Slot 0. Receiver clipping 0.137578 dB (FN=11041) count=1 [0x7f57bc00f350]
1266
2018-02-22_21:22:24.784206 <gsmtrx:ALL> ARFCN[0]: Slot 6. Excessive TOA error=-4 peak/mean=3.10825 count=1 [0x7f57bc00f350]
1267
2018-02-22_21:22:25.594551 <gsmtrx:ALL> ARFCN[0]: Slot 2. Excessive TOA error=-3 peak/mean=4.16589 count=1 [0x7f57bc00f350]
1268
2018-02-22_21:22:27.214456 <gsmtrx:ALL> ARFCN[0]: Slot 2. Excessive TOA error=-5 peak/mean=3.22103 count=1 [0x7f57bc00f350]
1269
2018-02-22_21:22:28.024036 <gsmtrx:ALL> ARFCN[0]: Slot 6. Excessive TOA errors 1 [0x7f57bc00f350]
1270
2018-02-22_21:22:28.226651 <gsmtrx:ALL> Handling command 'CMD 0 SETPOWER 1' arfcn=0 [0x7f57bc01f570]
1271
2018-02-22_21:22:28.235408 <bladerf/1:INFO> TX VGA2 set to 24dB (0xc0) [0x7f57bc000e40]
1272
2018-02-22_21:22:28.235450 <gsmtrx:INFO> Set Tx gain=-1 power_scaling=1 [0x7f57bc01f570]
1273
2018-02-22_21:22:28.235480 <gsmtrx:ALL> Command 'CMD 0 SETPOWER 1' (ARFCN=0) RSP '1'
1274
2018-02-22_21:22:28.930244 <gsmtrx:INFO> ARFCN[0]: Slot 0. Receiver clipping 0.467326 dB (FN=12011) count=1 [0x7f57bc00f350]
1275
2018-02-22_21:22:30.000445 <ybts-signalling:ALL> Removing released connection 1 [0x564a86b94a90]
1276
2018-02-22_21:22:30.000496 <ybts-mm:ALL> Removed UE (0x7f57c0005690): destroyed [0x564a86b94e70]
1277
2018-02-22_21:22:30.000519 <ybts:ALL> UE destroyed [0x7f57c0005690]
1278
2018-02-22_21:22:31.623963 <gsmtrx:ALL> ARFCN[0]: Slot 6. Excessive TOA error=-5 peak/mean=4.98407 count=1 [0x7f57bc00f350]
1279
2018-02-22_21:22:34.236107 <gsmtrx:ALL> Handling command 'CMD 0 SETPOWER 0' arfcn=0 [0x7f57bc01f570]
1280
2018-02-22_21:22:34.237856 <bladerf/1:INFO> TX VGA2 set to 25dB (0xc8) [0x7f57bc000e40]
1281
2018-02-22_21:22:34.237879 <gsmtrx:INFO> Set Tx gain=0 power_scaling=1 [0x7f57bc01f570]
1282
2018-02-22_21:22:34.237898 <gsmtrx:ALL> Command 'CMD 0 SETPOWER 0' (ARFCN=0) RSP '0'
1283
2018-02-22_21:22:34.774336 <gsmtrx:ALL> ARFCN[0]: Slot 2. Excessive TOA error=-4 peak/mean=4.03747 count=1 [0x7f57bc00f350]
1284
2018-02-22_21:22:36.634434 <gsmtrx:ALL> ARFCN[0]: Slot 2. Excessive TOA error=-5 peak/mean=3.64994 count=1 [0x7f57bc00f350]
1285
2018-02-22_21:22:37.204819 <gsmtrx:ALL> ARFCN[0]: Slot 6. Excessive TOA error=-5 peak/mean=3.57665 count=1 [0x7f57bc00f350]
1286
2018-02-22_21:22:53.305539 <gsmtrx:ALL> ARFCN[0]: Slot 3. Excessive TOA error=-3 peak/mean=3.73011 count=1 [0x7f57bc00f350]
1287
2018-02-22_21:23:18.602958 <gsmtrx:ALL> ARFCN[0]: Slot 3. Excessive TOA error=-4 peak/mean=3.72903 count=1 [0x7f57bc00f350]
1288
2018-02-22_21:23:23.294332 <gsmtrx:INFO> ARFCN[0]: Slot 0. Receiver clipping 0.418075 dB (FN=23790) count=1 [0x7f57bc00f350]
1289
2018-02-22_21:23:23.294957 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 4' arfcn=0 [0x7f57bc01f570]
1290
2018-02-22_21:23:23.294983 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 4' (ARFCN=0) RSP '4'
1291
2018-02-22_21:23:23.295267 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 4' arfcn=0 [0x7f57bc01f570]
1292
2018-02-22_21:23:23.295286 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 4' (ARFCN=0) RSP '4'
1293
2018-02-22_21:23:23.495328 <gsmtrx:INFO> ARFCN[0]: Slot 4. Receiver clipping -1.86277 dB (FN=23833) count=1 [0x7f57bc00f350]
1294
2018-02-22_21:23:23.528629 <ybts-signalling:INFO> Received [0x564a86b94a90]

1295
-----

1296
Primitive: PhysicalInfo

1297
Info: 0

1298
Connection: 2

1299

1300
<PhysicalInfo>TA=0 TE=2.000 UpRSSI=0 TxPwr=33 DnRSSIdBm=-111 time=1519331003.308</PhysicalInfo>

1301
-----
1302
2018-02-22_21:23:23.529162 <ybts-signalling:INFO> Received [0x564a86b94a90]

1303
-----

1304
Primitive: L3Message

1305
Info: 0

1306
Connection: 2

1307

1308
<MM>

1309
  <SkipIndicator>0</SkipIndicator>

1310
  <NSD>0</NSD>

1311
  <Message type="CMServiceRequest">

1312
    <CMServiceType>MO-call-establishment-or-PM-connection-establishment</CMServiceType>

1313
    <CKSN>no-key/reserved</CKSN>

1314
    <MSClassmark2>

1315
      <RFPowerCapability>class1</RFPowerCapability>

1316
      <RevisionLevel>GSM-phase2</RevisionLevel>

1317
      <SSScreeningIndicator>ellipsis-notation-and-phase2-error-handling</SSScreeningIndicator>

1318
      <Flags>ES-IND,MT-sms-point-to-point-capability,LCS-VA-support,CM3-support</Flags>

1319
    </MSClassmark2>

1320
    <MobileIdentity>

1321
      <TMSI>007b0002</TMSI>

1322
    </MobileIdentity>

1323
  </Message>

1324
</MM>

1325
-----
1326
2018-02-22_21:23:23.529238 <ybts-signalling:ALL> Added connection (0x7f57c0006f60,2) [0x564a86b94a90]
1327
2018-02-22_21:23:23.529271 <ybts-mm:ALL> Handling CMServiceRequest conn=2: ident=TMSI/007b0002 type=MO-call-establishment-or-PM-connection-establishment [0x564a86b94e70]
1328
2018-02-22_21:23:23.529297 <ybts-mm:ALL> Added UE (0x7f57c00089e0) TMSI=007b0002 IMSI= [0x564a86b94e70]
1329
2018-02-22_21:23:23.529320 <ybts-signalling:ALL> Connection 2 set UE (0x7f57c00089e0) TMSI=007b0002 IMSI= [0x7f57c0006f60]
1330
2018-02-22_21:23:23.529368 <ybts-signalling:INFO> Sending [0x564a86b94a90]

1331
-----

1332
Primitive: L3Message

1333
Info: 0

1334
Connection: 2

1335

1336
<MM>

1337
  <Message type="CMServiceAccept"/>

1338
</MM>

1339
-----
1340
2018-02-22_21:23:23.768473 <ybts-signalling:INFO> Received [0x564a86b94a90]

1341
-----

1342
Primitive: PhysicalInfo

1343
Info: 0

1344
Connection: 2

1345

1346
<PhysicalInfo>TA=0 TE=0.000 UpRSSI=-2 TxPwr=33 DnRSSIdBm=-111 time=1519331003.295</PhysicalInfo>

1347
-----
1348
2018-02-22_21:23:23.769133 <ybts-signalling:INFO> Received [0x564a86b94a90]

1349
-----

1350
Primitive: L3Message

1351
Info: 0

1352
Connection: 2

1353

1354
<CC>

1355
  <TID TIFlag="false">0</TID>

1356
  <NSD>1</NSD>

1357
  <Message type="Setup">

1358
    <BearerCapability1>

1359
      <ITC>speech</ITC>

1360
      <TransferMode>circuit-mode</TransferMode>

1361
      <CodingStandard>GSM</CodingStandard>

1362
      <RadioChannelRequirement>FR-and-HR-speech-version1-supported/FR-speech-preferred</RadioChannelRequirement>

1363
      <SpeechVersions>GSM-FR-speech-version3,GSM-FR-speech-version2,GSM-FR-speech-version1,GSM-HR-speech-version3,GSM-HR-speech-version1</SpeechVersions>

1364
    </BearerCapability1>

1365
    <CalledPartyBCDNumber nature="unknown" plan="isdn">32843</CalledPartyBCDNumber>

1366
    <CCCapabilities>

1367
      <Flags>DTMF</Flags>

1368
      <MaxSupportedBearers>0</MaxSupportedBearers>

1369
      <MaxSpeechBearers>1</MaxSpeechBearers>

1370
    </CCCapabilities>

1371
  </Message>

1372
</CC>

1373
-----
1374
2018-02-22_21:23:23.769202 <ybts/3:CALL> Incoming address=TMSI007b0002 conn=2 [0x7f57c000b510]
1375
2018-02-22_21:23:23.769227 <ybts-signalling:INFO> Sending [0x564a86b94a90]

1376
-----

1377
Primitive: AllocMedia

1378
Info: 0

1379
Connection: 2

1380
-----
1381
2018-02-22_21:23:23.769256 <ybts/3:INFO> Call 'i0' changed state Null -> CallProceeding [0x7f57c000b510]
1382
2018-02-22_21:23:23.769293 <ybts-signalling:INFO> Sending [0x564a86b94a90]

1383
-----

1384
Primitive: L3Message

1385
Info: 0

1386
Connection: 2

1387

1388
<CC>

1389
  <TID TIFlag="true">0</TID>

1390
  <Message type="CallProceeding"/>

1391
</CC>

1392
-----
1393
2018-02-22_21:23:23.769318 <ybts/3:INFO> Added call 'i0' [0x7f57c000b510]
1394
2018-02-22_21:23:23.769334 <ybts-signalling:INFO> Sending [0x564a86b94a90]

1395
-----

1396
Primitive: StartMedia

1397
Info: 1

1398
Connection: 2

1399
-----
1400
2018-02-22_21:23:23.769353 <ybts-signalling:ALL> Connection 2 waiting for traffic channel allocation mode=1 ... [0x564a86b94a90]
1401
2018-02-22_21:23:24.067934 <ybts-signalling:INFO> Received [0x564a86b94a90]

1402
-----

1403
Primitive: PhysicalInfo

1404
Info: 0

1405
Connection: 2

1406

1407
<PhysicalInfo>TA=0 TE=0.000 UpRSSI=-3 TxPwr=33 DnRSSIdBm=-111 time=1519331003.295</PhysicalInfo>

1408
-----
1409
2018-02-22_21:23:24.067989 <ybts-signalling:INFO> Received [0x564a86b94a90]

1410
-----

1411
Primitive: MediaStarted

1412
Info: 0

1413
Connection: 2

1414
-----
1415
2018-02-22_21:23:24.068007 <ybts-signalling:ALL> Connection 2 traffic channel set succeeded mode=1 [0x564a86b94a90]
1416
2018-02-22_21:23:24.068030 <ybts/3:ALL> Got media started notification [0x7f57c000b510]
1417
2018-02-22_21:23:24.068695 <INFO> Could not classify call from 'TMSI007b0002', wasted 9 usec
1418
2018-02-22_21:23:24.069116 <welcome:INFO> Got call to welcome IVR.
1419
2018-02-22_21:23:24.070317 <wave:INFO> Play from wave file '/usr/share/yate/sounds/welcome.au'
1420
2018-02-22_21:23:24.070612 <wave/1:ALL> WaveChan::WaveChan(play) [0x7f57cc00d3f0]
1421
2018-02-22_21:23:24.070899 <wave:ALL> WaveSource::WaveSource("/usr/share/yate/sounds/welcome.au",0x7f57cc00d3f0) [0x7f57cc006440]
1422
2018-02-22_21:23:24.071533 <MILD> Invalid .au file header, assuming raw signed linear
1423
2018-02-22_21:23:24.072115 <ybts/3:NOTE> Answering now call ybts/3 because we have no targetid [0x7f57c000b510]
1424
2018-02-22_21:23:24.072720 <ybts/3:INFO> Call 'i0' changed state CallProceeding -> ConnectReq [0x7f57c000b510]
1425
2018-02-22_21:23:24.073203 <ybts-signalling:INFO> Sending [0x564a86b94a90]

1426
-----

1427
Primitive: L3Message

1428
Info: 0

1429
Connection: 2

1430

1431
<CC>

1432
  <TID TIFlag="true">0</TID>

1433
  <Message type="Connect"/>

1434
</CC>

1435
-----
1436
2018-02-22_21:23:24.073628 <ALL> GsmCodec::GsmCodec("slin","gsm",encoding) [0x7f57cc00e930]
1437
2018-02-22_21:23:24.073922 <ALL> Created DataTranslator 0x7f57cc00e930 for 'slin' -> 'gsm' by factory 0x7f57ef5a81a0 (len=1)
1438
2018-02-22_21:23:24.074276 <ALL> DataTranslator::attachChain [0x7f57cc006440] 'slin' -> [0x7f57cc001cb0] 'gsm' succeeded
1439
2018-02-22_21:23:24.127552 <bladerf/1:ALL> RX DC offset Q set to 34 (from 34) reg=0xa2 [0x7f57bc000e40]
1440
2018-02-22_21:23:24.229760 <ybts-signalling:INFO> Received [0x564a86b94a90]

1441
-----

1442
Primitive: L3Message

1443
Info: 0

1444
Connection: 2

1445

1446
<CC>

1447
  <TID TIFlag="false">0</TID>

1448
  <NSD>0</NSD>

1449
  <Message type="ConnectAcknowledge"/>

1450
</CC>

1451
-----
1452
2018-02-22_21:23:24.229808 <ybts/3:INFO> Call 'i0' changed state ConnectReq -> Active [0x7f57c000b510]
1453
2018-02-22_21:23:34.034755 <gsmtrx:ALL> ARFCN[0]: Slot 7. Excessive TOA error=4 peak/mean=3.2997 count=1 [0x7f57bc00f350]
1454
2018-02-22_21:23:35.623990 <gsmtrx:ALL> ARFCN[0]: Slot 3. Excessive TOA error=3 peak/mean=3.32901 count=1 [0x7f57bc00f350]
1455
2018-02-22_21:23:36.998026 <wave:ALL> WaveSource '(null)' end of data (206918 played) chan=0x7f57cc00d3f0 [0x7f57cc006440]
1456
2018-02-22_21:23:36.998253 <wave:ALL> WaveSource cleanup, total=206918, chan=(nil) [0x7f57cc006440]
1457
2018-02-22_21:23:36.998640 >>> DataTranslator::detachChain(0x7f57cc006440,0x7f57cc001cb0)
1458
2018-02-22_21:23:36.999243   >>> DataTranslator::detachChain(0x7f57cc006440,0x7f57cc00e930)
1459
2018-02-22_21:23:36.999699   <<< DataTranslator::detachChain
1460
2018-02-22_21:23:37.000332   <ALL> GsmCodec::~GsmCodec() [0x7f57cc00e930]
1461
2018-02-22_21:23:37.000772 <<< DataTranslator::detachChain
1462
2018-02-22_21:23:37.001302 <ybts/3:ALL> Disconnected '(null)' [0x7f57c000b510]
1463
2018-02-22_21:23:37.002142 <wave:INFO> Record to wave file '-'
1464
2018-02-22_21:23:37.002169 <wave/2:ALL> WaveChan::WaveChan(record) [0x7f57b401d120]
1465
2018-02-22_21:23:37.002185 <wave:ALL> WaveConsumer::WaveConsumer("-",0x7f57b401d120,180000,"(null)",false,0x7f57b401b790) [0x7f57b4000eb0]
1466
2018-02-22_21:23:37.002215 <ALL> GsmCodec::GsmCodec("gsm","slin",decoding) [0x7f57b4001000]
1467
2018-02-22_21:23:37.002229 <ALL> Created DataTranslator 0x7f57b4001000 for 'gsm' -> 'slin' by factory 0x7f57ef5a81a0 (len=1)
1468
2018-02-22_21:23:37.002244 <ALL> DataTranslator::attachChain [0x7f57cc00e850] 'gsm' -> [0x7f57b4000eb0] 'slin' succeeded
1469
2018-02-22_21:23:37.002762 <wave:ALL> WaveSource::~WaveSource() [0x7f57cc006440] total=206918 stamp=103360
1470
2018-02-22_21:23:37.003060 <wave:INFO> WaveSource rate=16009 b/s
1471
2018-02-22_21:23:37.003347 <wave/1:ALL> WaveChan::~WaveChan() wave/1 [0x7f57cc00d3f0]
1472
2018-02-22_21:23:39.601578 <bladerf/1:MILD> RX buf_samples=252: 8 buffers: invalid timestamps (buf=ts/delta) 6=273233453/5055 [0x7f57bc000e40]
1473
2018-02-22_21:23:39.601650 <bladerf/1:WARN> RX: timestamp jumped by 4803 to 273228650 in buffer 6/8 [0x7f57bc000e40]
1474
2018-02-22_21:23:39.601681 <bladerf/1:NOTE> RX: timestamp adjusted by 4803 to 273233705 [0x7f57bc000e40]
1475
2018-02-22_21:23:39.603981 <gsmtrx:MILD> Transmit underrun by 2 timeslots [0x7f57bc01f570]
1476
2018-02-22_21:23:39.604014 <gsmtrx:NOTE> ARFCN[0]: 1 burst(s) expired at 218598 27324/6 [0x7f57bc00f350]
1477
2018-02-22_21:23:39.604041 <bladerf/1:ALL> TX: timestamps don't match (our=273245000 requested=273247500), dropping previous data 1878 samples [0x7f57bc000e40]
1478
2018-02-22_21:23:39.814718 <bladerf/1:MILD> RX buf_samples=252: 8 buffers: invalid timestamps (buf=ts/delta) 6=273695354/22665 [0x7f57bc000e40]
1479
2018-02-22_21:23:39.814763 <bladerf/1:WARN> RX: timestamp jumped by 22413 to 273672941 in buffer 6/8 [0x7f57bc000e40]
1480
2018-02-22_21:23:39.814778 <bladerf/1:NOTE> RX: timestamp adjusted by 22413 to 273695606 [0x7f57bc000e40]
1481
2018-02-22_21:23:39.815807 <gsmtrx:MILD> Transmit underrun by 7 timeslots [0x7f57bc01f570]
1482
2018-02-22_21:23:39.815855 <gsmtrx:NOTE> ARFCN[0]: 1 burst(s) expired at 218965 27370/5 [0x7f57bc00f350]
1483
2018-02-22_21:23:39.815895 <bladerf/1:ALL> TX: timestamps don't match (our=273697500 requested=273706250), dropping previous data 432 samples [0x7f57bc000e40]
1484
2018-02-22_21:23:39.913962 <gsmtrx:ALL> ARFCN[0]: Slot 7. Excessive TOA error=5 peak/mean=3.174 count=1 [0x7f57bc00f350]
1485
2018-02-22_21:23:40.244113 <gsmtrx:ALL> ARFCN[0]: Slot 3. Excessive TOA error=-4 peak/mean=3.2315 count=2 [0x7f57bc00f350]
1486
2018-02-22_21:23:40.490114 <ybts-signalling:INFO> Received [0x564a86b94a90]

1487
-----

1488
Primitive: PhysicalInfo

1489
Info: 0

1490
Connection: 2

1491

1492
<PhysicalInfo>TA=2 TE=0.000 UpRSSI=-24 TxPwr=6 DnRSSIdBm=-53 time=1519331020.352</PhysicalInfo>

1493
-----
1494
2018-02-22_21:23:40.490198 <ybts-signalling:INFO> Received [0x564a86b94a90]

1495
-----

1496
Primitive: L3Message

1497
Info: 0

1498
Connection: 2

1499

1500
<CC>

1501
  <TID TIFlag="false">0</TID>

1502
  <NSD>1</NSD>

1503
  <Message type="StartDTMF">

1504
    <KeypadFacility>1</KeypadFacility>

1505
  </Message>

1506
</CC>

1507
-----
1508
2018-02-22_21:23:40.490261 <ybts-signalling:INFO> Sending [0x564a86b94a90]

1509
-----

1510
Primitive: L3Message

1511
Info: 0

1512
Connection: 2

1513

1514
<CC>

1515
  <TID TIFlag="true">0</TID>

1516
  <Message type="StartDTMFAck">

1517
    <KeypadFacility>1</KeypadFacility>

1518
  </Message>

1519
</CC>

1520
-----
1521
2018-02-22_21:23:40.492267 <wave:INFO> Play from wave file '/usr/share/yate/sounds/echo.au'
1522
2018-02-22_21:23:40.492297 <wave/3:ALL> WaveChan::WaveChan(play) [0x7f57b401e1f0]
1523
2018-02-22_21:23:40.492313 <wave:ALL> WaveSource::WaveSource("/usr/share/yate/sounds/echo.au",0x7f57b401e1f0) [0x7f57b4011a60]
1524
2018-02-22_21:23:40.492836 >>> DataTranslator::detachChain(0x7f57cc00e850,0x7f57b4000eb0)
1525
2018-02-22_21:23:40.492856   >>> DataTranslator::detachChain(0x7f57cc00e850,0x7f57b4001000)
1526
2018-02-22_21:23:40.492873   <<< DataTranslator::detachChain
1527
2018-02-22_21:23:40.492887   <ALL> GsmCodec::~GsmCodec() [0x7f57b4001000]
1528
2018-02-22_21:23:40.492902 <<< DataTranslator::detachChain
1529
2018-02-22_21:23:40.492953 <ALL> GsmCodec::GsmCodec("slin","gsm",encoding) [0x7f57b4001000]
1530
2018-02-22_21:23:40.492978 <ALL> Created DataTranslator 0x7f57b4001000 for 'mulaw' -> 'gsm' by factory 0x564a868eda00 (len=2)
1531
2018-02-22_21:23:40.493004 <ALL> DataTranslator::attachChain [0x7f57b4011a60] 'mulaw' -> [0x7f57cc001cb0] 'gsm' succeeded
1532
2018-02-22_21:23:40.493111 <wave:ALL> WaveConsumer::~WaveConsumer() [0x7f57b4000eb0] total=55680 stamp=131200
1533
2018-02-22_21:23:40.493126 <wave:INFO> WaveConsumer rate=15993 b/s
1534
2018-02-22_21:23:40.493150 <wave/2:ALL> WaveChan::~WaveChan() wave/2 [0x7f57b401d120]
1535
2018-02-22_21:23:40.711503 <ybts-signalling:INFO> Received [0x564a86b94a90]

1536
-----

1537
Primitive: PhysicalInfo

1538
Info: 0

1539
Connection: 2

1540

1541
<PhysicalInfo>TA=2 TE=0.000 UpRSSI=-24 TxPwr=6 DnRSSIdBm=-53 time=1519331020.352</PhysicalInfo>

1542
-----
1543
2018-02-22_21:23:40.711585 <ybts-signalling:INFO> Received [0x564a86b94a90]

1544
-----

1545
Primitive: L3Message

1546
Info: 0

1547
Connection: 2

1548

1549
<CC>

1550
  <TID TIFlag="false">0</TID>

1551
  <NSD>0</NSD>

1552
  <Message type="StopDTMF"/>

1553
</CC>

1554
-----
1555
2018-02-22_21:23:40.711633 <ybts-signalling:INFO> Sending [0x564a86b94a90]

1556
-----

1557
Primitive: L3Message

1558
Info: 0

1559
Connection: 2

1560

1561
<CC>

1562
  <TID TIFlag="true">0</TID>

1563
  <Message type="StopDTMFAck"/>

1564
</CC>

1565
-----
1566
2018-02-22_21:23:41.324338 <gsmtrx:ALL> ARFCN[0]: Slot 3. Excessive TOA error=5 peak/mean=4.71805 count=1 [0x7f57bc00f350]
1567
2018-02-22_21:23:43.214281 <gsmtrx:ALL> ARFCN[0]: Slot 7. Excessive TOA error=5 peak/mean=5.19542 count=2 [0x7f57bc00f350]
1568
2018-02-22_21:23:44.414251 <wave:ALL> WaveSource '(null)' end of data (31520 played) chan=0x7f57b401e1f0 [0x7f57b4011a60]
1569
2018-02-22_21:23:44.414642 <wave:ALL> WaveSource cleanup, total=31520, chan=(nil) [0x7f57b4011a60]
1570
2018-02-22_21:23:44.414817 >>> DataTranslator::detachChain(0x7f57b4011a60,0x7f57cc001cb0)
1571
2018-02-22_21:23:44.415087   >>> DataTranslator::detachChain(0x7f57b4011a60,0x7f57b4001000)
1572
2018-02-22_21:23:44.415460     >>> DataTranslator::detachChain(0x7f57b4011a60,0x7f57b401c420)
1573
2018-02-22_21:23:44.415734     <<< DataTranslator::detachChain
1574
2018-02-22_21:23:44.416004   <<< DataTranslator::detachChain
1575
2018-02-22_21:23:44.416331   <ALL> GsmCodec::~GsmCodec() [0x7f57b4001000]
1576
2018-02-22_21:23:44.416608 <<< DataTranslator::detachChain
1577
2018-02-22_21:23:44.416842 <ybts/3:ALL> Disconnected '(null)' [0x7f57c000b510]
1578
2018-02-22_21:23:44.417325 <wave:ALL> WaveSource::~WaveSource() [0x7f57b4011a60] total=31520 stamp=31360
1579
2018-02-22_21:23:44.417621 <wave:INFO> WaveSource rate=8033 b/s
1580
2018-02-22_21:23:44.417907 <wave/3:ALL> WaveChan::~WaveChan() wave/3 [0x7f57b401e1f0]
1581
2018-02-22_21:23:44.422410 <welcome:INFO> Returned to main function in state 'echoTest'
1582
2018-02-22_21:23:44.422861 <ALL> ExtModChan::ExtModChan(4) [0x7f57b4026ba0]
1583
2018-02-22_21:23:44.422895 <ALL> ExtModConsumer::ExtModConsumer(0x7f57b40297f0) [0x7f57b40103b0]
1584
2018-02-22_21:23:44.422915 <ALL> ExtModSource::ExtModSource(0x7f57b4029870) [0x7f57c0005690]
1585
2018-02-22_21:23:44.422998 <ALL> ExtModReceiver::ExtModReceiver("echo.sh","(null)") [0x7f57b4012060]
1586
Execing '/usr/share/yate/scripts/echo.sh' ''
1587
2018-02-22_21:23:44.430916 <INFO> Launched External Script '/usr/share/yate/scripts/echo.sh' ''
1588
2018-02-22_21:23:44.433132 <bladerf/1:MILD> RX buf_samples=252: 8 buffers: invalid timestamps (buf=ts/delta) 6=283701428/6966 [0x7f57bc000e40]
1589
2018-02-22_21:23:44.433218 <bladerf/1:WARN> RX: timestamp jumped by 6714 to 283694714 in buffer 6/8 [0x7f57bc000e40]
1590
2018-02-22_21:23:44.433233 <bladerf/1:NOTE> RX: timestamp adjusted by 6714 to 283701680 [0x7f57bc000e40]
1591
2018-02-22_21:23:44.434625 <gsmtrx:MILD> Transmit underrun by 4 timeslots [0x7f57bc01f570]
1592
2018-02-22_21:23:44.434677 <bladerf/1:ALL> TX: timestamps don't match (our=283708750 requested=283713750), dropping previous data 1124 samples [0x7f57bc000e40]
1593
=================== play ====================
1594
2018-02-22_21:23:44.441354 <ALL> GsmCodec::GsmCodec("gsm","slin",decoding) [0x7f57b401d780]
1595
2018-02-22_21:23:44.441390 <ALL> Created DataTranslator 0x7f57b401d780 for 'gsm' -> 'slin' by factory 0x7f57ef5a81a0 (len=1)
1596
2018-02-22_21:23:44.441416 <ALL> DataTranslator::attachChain [0x7f57cc00e850] 'gsm' -> [0x7f57b40103b0] 'slin' succeeded
1597
2018-02-22_21:23:44.441439 <ALL> GsmCodec::GsmCodec("slin","gsm",encoding) [0x7f57b4010d90]
1598
2018-02-22_21:23:44.441455 <ALL> Created DataTranslator 0x7f57b4010d90 for 'slin' -> 'gsm' by factory 0x7f57ef5a81a0 (len=1)
1599
2018-02-22_21:23:44.441482 <ALL> DataTranslator::attachChain [0x7f57c0005690] 'slin' -> [0x7f57cc001cb0] 'gsm' succeeded
1600
2018-02-22_21:23:45.104363 <gsmtrx:ALL> ARFCN[0]: Slot 3. Excessive TOA error=5 peak/mean=5.04826 count=1 [0x7f57bc00f350]
1601
2018-02-22_21:23:48.014845 <gsmtrx:ALL> ARFCN[0]: Slot 7. Excessive TOA error=-4 peak/mean=3.32295 count=1 [0x7f57bc00f350]
1602
2018-02-22_21:23:49.154570 <gsmtrx:ALL> ARFCN[0]: Slot 7. Excessive TOA errors 1 [0x7f57bc00f350]
1603
2018-02-22_21:23:49.231248 <ybts-signalling:INFO> Received [0x564a86b94a90]

1604
-----

1605
Primitive: PhysicalInfo

1606
Info: 0

1607
Connection: 2

1608

1609
<PhysicalInfo>TA=2 TE=0.000 UpRSSI=-10 TxPwr=6 DnRSSIdBm=-49 time=1519331028.390</PhysicalInfo>

1610
-----
1611
2018-02-22_21:23:49.231340 <ybts-signalling:INFO> Received [0x564a86b94a90]

1612
-----

1613
Primitive: L3Message

1614
Info: 0

1615
Connection: 2

1616

1617
<CC>

1618
  <TID TIFlag="false">0</TID>

1619
  <NSD>1</NSD>

1620
  <Message type="Disconnect">

1621
    <Cause coding="GSM-PLMN" location="U">normal-clearing</Cause>

1622
  </Message>

1623
</CC>

1624
-----
1625
2018-02-22_21:23:49.231368 <ybts/3:INFO> Removing call 'i0' [0x7f57c000b510]
1626
2018-02-22_21:23:49.231386 <ybts/3:INFO> Call 'i0' changed state Active -> Release [0x7f57c000b510]
1627
2018-02-22_21:23:49.231440 <ybts-signalling:INFO> Sending [0x564a86b94a90]

1628
-----

1629
Primitive: L3Message

1630
Info: 0

1631
Connection: 2

1632

1633
<CC>

1634
  <TID TIFlag="true">0</TID>

1635
  <Message type="Release">

1636
    <Cause coding="GSM-PLMN" location="LPN">normal-clearing</Cause>

1637
  </Message>

1638
</CC>

1639
-----
1640
2018-02-22_21:23:49.231497 <ybts/3:CALL> Hangup reason='normal-clearing' [0x7f57c000b510]
1641
2018-02-22_21:23:49.231532 >>> DataTranslator::detachChain(0x7f57cc00e850,0x7f57b40103b0)
1642
2018-02-22_21:23:49.231548   >>> DataTranslator::detachChain(0x7f57cc00e850,0x7f57b401d780)
1643
2018-02-22_21:23:49.231560   <<< DataTranslator::detachChain
1644
2018-02-22_21:23:49.232556   <ALL> GsmCodec::~GsmCodec() [0x7f57b401d780]
1645
2018-02-22_21:23:49.232573 <<< DataTranslator::detachChain
1646
2018-02-22_21:23:49.232584 >>> DataTranslator::detachChain(0x7f57c0005690,0x7f57cc001cb0)
1647
2018-02-22_21:23:49.232653   >>> DataTranslator::detachChain(0x7f57c0005690,0x7f57b4010d90)
1648
2018-02-22_21:23:49.232668   <<< DataTranslator::detachChain
1649
2018-02-22_21:23:49.232679   <ALL> GsmCodec::~GsmCodec() [0x7f57b4010d90]
1650
2018-02-22_21:23:49.232696 <<< DataTranslator::detachChain
1651
2018-02-22_21:23:49.232708 <ALL> ExtModChan::disconnected() 'normal-clearing' [0x7f57b4026ba0]
1652
2018-02-22_21:23:49.232722 <ALL> ExtModConsumer::~ExtModConsumer() [0x7f57b40103b0] total=76800
1653
2018-02-22_21:23:49.232750 >>> ExtModChan::~ExtModChan() [0x7f57b4026ba0]
1654
2018-02-22_21:23:49.232764   <ALL> ExtModReceiver::die() pid=9070 dead=no [0x7f57b4012060]
1655
2018-02-22_21:23:49.232782   <ALL> ExtModReceiver::die() waiting for pid=9070 to die [0x7f57b4012060]
1656
================= play done =================
1657
2018-02-22_21:23:49.233858   <ALL> ExtModSource [0x7f57c0005690] end of data total=76800
1658
2018-02-22_21:23:49.233887   <ALL> ExtModSource::~ExtModSource() [0x7f57c0005690] total=76800
1659
2018-02-22_21:23:49.233958   <ExtModule:INFO> Read EOF on 0x7f57a4001010 [0x7f57b4012060]
1660
2018-02-22_21:23:49.234202   <ALL> ExtModReceiver::destruct() pid=0 [0x7f57b4012060]
1661
2018-02-22_21:23:49.234219   <ALL> ExtModReceiver::die() pid=0 dead=yes [0x7f57b4012060]
1662
2018-02-22_21:23:49.234233 <<< ExtModChan::~ExtModChan()
1663
2018-02-22_21:23:49.234261 <ybts/3:CALL> Destroyed [0x7f57c000b510]
1664
2018-02-22_21:23:49.236580 <javascript:ALL> Assistant for 'ybts/3' deleted
1665
2018-02-22_21:23:49.236607 <ALL> Rescanning handler list for 'chan.hangup' [0x7f57cc00ee20] at priority 15
1666
2018-02-22_21:23:49.369786 <ybts-signalling:INFO> Received [0x564a86b94a90]

1667
-----

1668
Primitive: L3Message

1669
Info: 0

1670
Connection: 2

1671

1672
<CC>

1673
  <TID TIFlag="false">0</TID>

1674
  <NSD>0</NSD>

1675
  <Message type="ReleaseComplete"/>

1676
</CC>

1677
-----
1678
2018-02-22_21:23:49.369831 <ybts:NOTE> Removing terminated call 'i0' conn=2
1679
2018-02-22_21:23:52.000528 <ybts-signalling:ALL> Connection 2 idle timeout [0x564a86b94a90]
1680
2018-02-22_21:23:52.000575 <ybts-signalling:ALL> Releasing connection (0x7f57c0006f60,2) [0x564a86b94a90]
1681
2018-02-22_21:23:52.000607 <ybts-signalling:INFO> Sending [0x564a86b94a90]

1682
-----

1683
Primitive: ConnRelease

1684
Info: 0

1685
Connection: 2

1686
-----
1687
2018-02-22_21:23:54.126140 <gsmtrx:INFO> ARFCN[0]: Slot 0. Receiver clipping 1.37174 dB (FN=30470) count=1 [0x7f57bc00f350]
1688
2018-02-22_21:23:56.714759 <gsmtrx:ALL> ARFCN[0]: Slot 7. Excessive TOA error=4 peak/mean=3.19168 count=1 [0x7f57bc00f350]
1689
2018-02-22_21:23:58.131776 <gsmtrx:INFO> ARFCN[0]: Slot 0. Receiver clipping 0.974068 dB (FN=31338) count=1 [0x7f57bc00f350]
1690
2018-02-22_21:23:58.132771 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f57bc01f570]
1691
2018-02-22_21:23:58.132818 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0) RSP '0'
1692
2018-02-22_21:23:58.133052 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f57bc01f570]
1693
2018-02-22_21:23:58.133081 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0) RSP '0'
1694
2018-02-22_21:23:58.226764 <mbts:NOTE> TBF.cpp:1150:mtCancel:   21:23:58.2,7238: 21:23:58.2@@@failed tbf cause=3101 TBF#7 TFI=7 TBF_TLLI=0xc007f001 mtMS= MS#1,TLLI=c007f001,80006001 mtDir=RLCDir::Up

1695
	 channels: down=( 0:1 0:2 0:3) up=( 0:2,usf=1 0:3,usf=1)

1696
	 mtState==TBFState::DataTransmit mtAttached=1 mtTFI=7 mtTlli=0xc007f001

1697
	 mtMsgExpectedBits=0x20 mtMsgAckBits=0x2 mtExpectedAckBSN[5]=7238

1698
	 mtUnAckMode=0 OnCCCH=0 mtAssignCounter=1 N3101=21

1699
	 msNumDataUSFGrants=356 msAckNackUSFGrant=352 msOldTlli=0x80006001 Pacch= idle=0

1700
	 TA=2 TE=(0.00) RSSI=(-26 min=-44 max=-17 avg=-27.45 N=371) CV=(57 min=44 max=57 avg=50.75 N=8) ILev=(0) RXQual=(7) SigVar=(0) ChCoding=(3) RXLev=(-59) mLastAlpha=10 mLastGamma=28 mGamma=28

1701
	 VR=1 VQ=1 stalled=0 mNumUpBlocksSinceAckNack=0 mtUpState=1

1702
	 blocks: total=17 unique=2 grants=356
1703
2018-02-22_21:23:58.441612 <ybts-signalling:INFO> Received [0x564a86b94a90]

1704
-----

1705
Primitive: PhysicalInfo

1706
Info: 0

1707
Connection: 3

1708

1709
<PhysicalInfo>TA=0 TE=2.000 UpRSSI=0 TxPwr=33 DnRSSIdBm=-67 time=1519331038.146</PhysicalInfo>

1710
-----
1711
2018-02-22_21:23:58.441771 <ybts-signalling:INFO> Received [0x564a86b94a90]

1712
-----

1713
Primitive: L3Message

1714
Info: 0

1715
Connection: 3

1716

1717
<MM>

1718
  <SkipIndicator>0</SkipIndicator>

1719
  <NSD>0</NSD>

1720
  <Message type="IMSIDetachIndication">

1721
    <MSClassmark1>

1722
      <RFPowerCapability>class1</RFPowerCapability>

1723
      <RevisionLevel>GSM-phase2</RevisionLevel>

1724
      <Flags>ES-IND</Flags>

1725
    </MSClassmark1>

1726
    <MobileIdentity>

1727
      <TMSI>007b0002</TMSI>

1728
    </MobileIdentity>

1729
  </Message>

1730
</MM>

1731
-----
1732
2018-02-22_21:23:58.441828 <ybts-signalling:ALL> Added connection (0x7f57c000c0f0,3) [0x564a86b94a90]
1733
2018-02-22_21:23:58.441857 <ybts-signalling:ALL> Releasing connection (0x7f57c000c0f0,3) [0x564a86b94a90]
1734
2018-02-22_21:23:58.441887 <ybts-signalling:INFO> Sending [0x564a86b94a90]

1735
-----

1736
Primitive: ConnRelease

1737
Info: 0

1738
Connection: 3

1739
-----
1740
2018-02-22_21:23:58.441932 <ybts-mm:INFO> Detached UE (0x7f57c00089e0) TMSI=007b0002 IMSI=(null) [0x564a86b94e70]
1741
2018-02-22_21:23:58.444392 <nipc:INFO> Finished onUnregister imsi 001010000000001
1742
2018-02-22_21:23:59.137596 <gsmtrx:INFO> ARFCN[0]: Slot 0. Receiver clipping -0.334817 dB (FN=31556) count=18 [0x7f57bc00f350]
1743
^C2018-02-22_21:23:59.558378 <sip:ALL> Returning false from engine.stop handler
1744
2018-02-22_21:23:59.558448 <iaxengine:INFO> Status changed Listening -> Exiting [0x564a86ac49c0]
1745
2018-02-22_21:23:59.558478 <iaxengine:ALL> Set terminate timeout=16500ms status=Exiting [0x564a86ac49c0]
1746
2018-02-22_21:23:59.558523 <iax:INFO> Removed listener (0x564a86ac49c0) 'iaxengine' status='Exiting'
1747
2018-02-22_21:23:59.559507 <iaxengine:ALL> Thread (0x564a86a0ce60) 'YIAXTrunking' terminated [0x564a86ac49c0]
1748
2018-02-22_21:23:59.559953 <iaxengine:ALL> Thread (0x564a86a18ce0) 'YIAXListener' terminated [0x564a86ac49c0]
1749
2018-02-22_21:23:59.560557 <iaxengine:ALL> Thread (0x564a86a0f9b0) 'YIAXGetEvent' terminated [0x564a86ac49c0]
1750
2018-02-22_21:23:59.560666 <iaxengine:ALL> Thread (0x564a86a17250) 'YIAXListener' terminated [0x564a86ac49c0]
1751
2018-02-22_21:23:59.560900 <iaxengine:ALL> Thread (0x564a86a10ad0) 'YIAXGetEvent' terminated [0x564a86ac49c0]
1752
2018-02-22_21:23:59.561311 <iaxengine:ALL> Thread (0x564a86a136a0) 'YIAXGetEvent' terminated [0x564a86ac49c0]
1753
2018-02-22_21:23:59.561777 <jingle:ALL> YJGEngineWorker stop running
1754
2018-02-22_21:23:59.562967 <iaxengine:ALL> Thread (0x564a86a17c70) 'YIAXListener' terminated [0x564a86ac49c0]
1755
2018-02-22_21:23:59.563662 <iaxengine:ALL> Destroyed [0x564a86ac49c0]
1756
2018-02-22_21:23:59.564038 <mbts:MILD> CLI.cpp:228:exit_function: dropping 1 control transactions on exit
1757
2018-02-22_21:23:59.564072 <gsmtrx:INFO> Module state changed Running -> Waiting
1758
2018-02-22_21:23:59.564089 <gsmtrx:NOTE> Stopping transceiver: received STOP command
1759
2018-02-22_21:23:59.564110 <gsmtrx:ALL> Stopping [0x7f57bc01f570]
1760
2018-02-22_21:23:59.564125 <gsmtrx:ALL> Sending 'EXITING' on clock interface [0x7f57bc01f570]
1761
2018-02-22_21:23:59.564158 <gsmtrx:NOTE> State changed PowerOn -> Idle [0x7f57bc01f570]
1762
2018-02-22_21:23:59.564240 <mbts:NOTE> TRXManager.cpp:281:clockHandler: TRX clock 'EXITING' indication
1763
2018-02-22_21:23:59.564256 <ybts-signalling:INFO> Received [0x564a86b94a90]

1764
-----

1765
Primitive: Stop

1766
Info: 3

1767
-----
1768
2018-02-22_21:23:59.564497 <gsmtrx:ALL> Radio device read thread (0x7f57bc042530) terminated [0x7f57bc01f570]
1769
2018-02-22_21:23:59.564521 <gsmtrx:ALL> Radio device send thread (0x7f57bc043490) terminated [0x7f57bc01f570]
1770
2018-02-22_21:23:59.565944 <gsmtrx:ALL> ARFCN[1]: Data socket read thread (0x7f57bc043cc0) terminated [0x7f57bc0317f0]
1771
2018-02-22_21:23:59.566719 <gsmtrx:ALL> ARFCN[2]: Radio input process thread (0x7f57bc043f20) terminated [0x7f57bc0380a0]
1772
2018-02-22_21:23:59.566769 <gsmtrx:ALL> ARFCN[1]: Radio input process thread (0x7f57bc043a60) terminated [0x7f57bc0317f0]
1773
2018-02-22_21:23:59.566814 <gsmtrx:ALL> ARFCN[3]: Radio input process thread (0x7f57bc042e20) terminated [0x7f57bc03bf80]
1774
2018-02-22_21:23:59.567040 <gsmtrx:ALL> ARFCN[0]: Radio input process thread (0x7f57bc043660) terminated [0x7f57bc00f350]
1775
2018-02-22_21:23:59.567626 <gsmtrx:ALL> ARFCN[3]: Data socket read thread (0x7f57bc044480) terminated [0x7f57bc03bf80]
1776
2018-02-22_21:23:59.568083 <gsmtrx:ALL> ARFCN[2]: Data socket read thread (0x7f57bc042db0) terminated [0x7f57bc0380a0]
1777
2018-02-22_21:23:59.568675 <gsmtrx:ALL> Radio read process thread (0x7f57bc042120) terminated [0x7f57bc01f570]
1778
2018-02-22_21:23:59.569012 <gsmtrx:ALL> ARFCN[0]: Data socket read thread (0x7f57bc043830) terminated [0x7f57bc00f350]
1779
2018-02-22_21:23:59.569278 <gsmtrx:NOTE> ARFCN[0]: Radio power off. RX: bursts=35186 passed=7667 (21.79%) dropped=27519 (78.21%) (LowSNR=23433 LowPeakMin=83 LowPower=4003) ellapsed=146sec avg=241.00 bursts/sec [0x7f57bc00f350]
1780
2018-02-22_21:23:59.569754 <bladerf/1:ALL> Destroying device=(0x7f57bc0208b0) [0x7f57bc000e40]
1781
2018-02-22_21:23:59.589982 <bladerf/1:ALL> Disabled RF TX [0x7f57bc000e40]
1782
2018-02-22_21:23:59.611014 <bladerf/1:ALL> Disabled RF RX [0x7f57bc000e40]
1783
2018-02-22_21:23:59.611057 <bladerf/1:NOTE> Powered OFF the radio [0x7f57bc000e40]
1784
2018-02-22_21:23:59.612188 <bladerf/1:ALL> Device closed [0x7f57bc000e40]
1785
2018-02-22_21:23:59.612291 <bladerf/1:ALL> Interface destroyed [0x7f57bc000e40]
1786
MBTS exiting
1787
2018-02-22_21:23:59.612824 <ybts:ALL> 'shutdown' command result:

1788
-----

1789
exiting with loads:

1790
== GSM ==

1791
SDCCH load: 1/4

1792
TCH/F load: 0/4

1793
AGCH/PCH load: 0,0

1794
Paging table size: 0

1795
T3122: 2000 ms (target 5000 ms)

1796
== GPRS ==

1797
current PDCHs: 3

1798
utilization: 0%

1799

1800
exiting...

1801
-----
1802
2018-02-22_21:23:59.612891 <ybts:ALL> Stopping ...
1803
2018-02-22_21:23:59.615401 <ybts-signalling:ALL> Worker thread terminated [0x564a86b94a90]
1804
2018-02-22_21:23:59.618032 <ybts-mm:ALL> Removed UE (0x7f57c00089e0): destroyed [0x564a86b94e70]
1805
2018-02-22_21:23:59.618063 <ybts:ALL> UE destroyed [0x7f57c00089e0]
1806
2018-02-22_21:23:59.618105 <ybts-signalling:INFO> Stopped [0x564a86b94a90]
1807
2018-02-22_21:23:59.619481 <ybts-media:ALL> Worker thread terminated [0x564a86b947b0]
1808
2018-02-22_21:23:59.623271 <ybts-media:INFO> Stopped [0x564a86b947b0]
1809
2018-02-22_21:23:59.623307 <ybts:NOTE> Peer pid 8944 has not exited - we'll kill it
1810
2018-02-22_21:23:59.723435 <ybts:INFO> Peer pid 8944 terminated
1811
2018-02-22_21:23:59.723514 <ybts-command:INFO> Stopped [0x564a86b94650]
1812
2018-02-22_21:23:59.727225 <transceiver:ALL> Worker thread terminated [0x564a86b941e0]
1813
2018-02-22_21:23:59.728699 <transceiver:INFO> Stopped [0x564a86b941e0]
1814
2018-02-22_21:23:59.730595 <mbts:ALL> Worker thread terminated [0x564a86b943f0]
1815
2018-02-22_21:23:59.733893 <mbts:INFO> Stopped [0x564a86b943f0]
1816
2018-02-22_21:23:59.733936 <ybts:NOTE> State changed RadioUp -> Idle
1817
Yate engine is shutting down with code 0
1818
2018-02-22_21:23:59.734245 <ALL> Rescanning handler list for 'engine.halt' [0x7ffc6f718740] at priority 100
1819
2018-02-22_21:23:59.734288 <ALL> Handler list for 'engine.halt' [0x7ffc6f718740] changed, skipping from 0x564a868e87f0 (100) to 0x564a868e90f0 (100)
1820
2018-02-22_21:23:59.735638 <jabber:ALL> JBPendingWorker(0) terminated [0x564a868e7f50]
1821
2018-02-22_21:23:59.735709 <jabber:ALL> Terminated 1 pending worker threads
1822
2018-02-22_21:23:59.735829 <ALL> Rescanning handler list for 'engine.halt' [0x7ffc6f718740] at priority 100
1823
2018-02-22_21:23:59.735921 <ALL> Rescanning handler list for 'engine.halt' [0x7ffc6f718740] at priority 100
1824
2018-02-22_21:23:59.736059 <ALL> Rescanning handler list for 'engine.halt' [0x7ffc6f718740] at priority 100
1825
2018-02-22_21:23:59.736099 <ALL> Handler list for 'engine.halt' [0x7ffc6f718740] changed, skipping from 0x564a8692fc20 (100) to 0x564a86930900 (100)
1826
2018-02-22_21:23:59.736143 <sip:ALL> Clearing udp transports reason=Exiting
1827
2018-02-22_21:23:59.736201 <sip:INFO> Listener(UDP,'general') stop listening reason='Exiting' [0x564a86aba500]
1828
2018-02-22_21:23:59.736237 <sip:ALL> Removed transport (0x564a86aba500,'general')
1829
2018-02-22_21:23:59.736267 <sip:INFO> Reset default UDP transport
1830
2018-02-22_21:23:59.745043 <sip:ALL> Transport(general) destroyed [0x564a86aba500]
1831
2018-02-22_21:23:59.745093 <sip:ALL> Endpoint stopped
1832
2018-02-22_21:23:59.745138 <sip:ALL> YateSIPEndPoint::~YateSIPEndPoint() [0x564a86abf880]
1833
2018-02-22_21:23:59.745174 <ALL> Rescanning handler list for 'engine.halt' [0x7ffc6f718740] at priority 100
1834
2018-02-22_21:23:59.745332 <ALL> Rescanning handler list for 'engine.halt' [0x7ffc6f718740] at priority 100
1835
2018-02-22_21:23:59.745359 <ALL> Handler list for 'engine.halt' [0x7ffc6f718740] changed, skipping from 0x564a86ad2cf0 (100) to 0x564a86ad2f40 (100)
1836
2018-02-22_21:23:59.745421 <queuesnotify:ALL> Cancelling worker(s)
1837
2018-02-22_21:23:59.750601 <queuesnotify:ALL> Worker(s) terminated
1838
2018-02-22_21:23:59.750637 <ALL> Rescanning handler list for 'engine.halt' [0x7ffc6f718740] at priority 100
1839
2018-02-22_21:23:59.750656 <ALL> Handler list for 'engine.halt' [0x7ffc6f718740] changed, skipping from 0x564a86adf4e0 (100) to 0x564a86ae0d90 (100)
1840
2018-02-22_21:23:59.750716 <ALL> Rescanning handler list for 'engine.halt' [0x7ffc6f718740] at priority 100
1841
2018-02-22_21:23:59.750733 <ALL> Handler list for 'engine.halt' [0x7ffc6f718740] changed, skipping from 0x564a86ae1e60 (100) to 0x564a86ae2590 (100)
1842
2018-02-22_21:23:59.750787 <ALL> Rescanning handler list for 'engine.halt' [0x7ffc6f718740] at priority 100
1843
2018-02-22_21:23:59.750804 <ALL> Handler list for 'engine.halt' [0x7ffc6f718740] changed, skipping from 0x564a86ae29f0 (100) to 0x564a86b91f00 (100)
1844
2018-02-22_21:23:59.750841 <ALL> Rescanning handler list for 'engine.halt' [0x7ffc6f718740] at priority 100
1845
2018-02-22_21:23:59.750861 <snmpagent:ALL> ::unload()
1846
2018-02-22_21:23:59.766486 <ALL> Rescanning handler list for 'engine.halt' [0x7ffc6f718740] at priority 100
1847
2018-02-22_21:23:59.766555 <ALL> Handler list for 'engine.halt' [0x7ffc6f718740] changed, skipping from 0x564a86b96590 (100) to 0x564a86b97000 (100)
1848
2018-02-22_21:23:59.766710 <ALL> Rescanning handler list for 'engine.halt' [0x7ffc6f718740] at priority 100
1849
2018-02-22_21:23:59.766761 <ALL> Handler list for 'engine.halt' [0x7ffc6f718740] changed, skipping from 0x564a86b97910 (100) to 0x564a86b9ae40 (100)
1850
2018-02-22_21:23:59.766803 <gsmtrx:INFO> Module state changed Waiting -> Idle
1851
2018-02-22_21:23:59.766865 <gsmtrx:ALL> 'control' thread terminated
1852
2018-02-22_21:23:59.776823 <signalling:ALL> Engine stopped worker thread [0x564a86b9de10]
1853
2018-02-22_21:23:59.981222 >>> ThreadPrivate::killall()
1854
2018-02-22_21:23:59.981301   <NOTE> Soft cancelling 4 running threads
1855
2018-02-22_21:23:59.981361   <INFO> Stopping ThreadPrivate 'RManager Listener' [0x564a869ee420]
1856
2018-02-22_21:23:59.981418   <INFO> Stopping ThreadPrivate 'MGCP Receive' [0x564a86963a60]
1857
2018-02-22_21:23:59.981473   <INFO> Stopping ThreadPrivate 'MGCP Process' [0x564a86a5c2b0]
1858
2018-02-22_21:23:59.981507   <INFO> Stopping ThreadPrivate 'Engine Worker' [0x564a86c56d10]
1859
2018-02-22_21:23:59.991813 <<< ThreadPrivate::killall()
1860
2018-02-22_21:23:59.993346 <sig/isup.decode:INFO> ISUP Call Controller destroyed [0x564a86b9cc10]
1861
2018-02-22_21:23:59.993388 <sig/isup.encode:INFO> ISUP Call Controller destroyed [0x564a86b9e670]
1862
Unloading module Radio Test
1863
2018-02-22_21:23:59.998034 >>> Plugin::~Plugin() "radiotest" [0x7f57e3ef3ce0]
1864
2018-02-22_21:23:59.998084 <<< Plugin::~Plugin()
1865
Unloading module Signalling Channel
1866
2018-02-22_21:23:59.998289 >>> Plugin::~Plugin() "sig" [0x7f57e56bd000]
1867
2018-02-22_21:23:59.998320 <<< Plugin::~Plugin()
1868
2018-02-22_21:23:59.998390 <WARN> Unloading 'gsmtrx' removed 0 out of 1 plugins
1869
Unloaded module Users Management
1870
2018-02-22_21:23:59.998497 >>> Plugin::~Plugin() "users" [0x7f57e5d05260]
1871
2018-02-22_21:23:59.998530 <<< Plugin::~Plugin()
1872
Unloading module MRCP
1873
2018-02-22_21:23:59.998703 >>> Plugin::~Plugin() "mrcp" [0x7f57e5f0d280]
1874
2018-02-22_21:23:59.998732 <<< Plugin::~Plugin()
1875
2018-02-22_21:23:59.998793 <WARN> Unloading 'ysnmpagent' removed 0 out of 1 plugins
1876
Unloading module Cache
1877
2018-02-22_21:23:59.998917 >>> Plugin::~Plugin() "cache" [0x7f57e6570920]
1878
2018-02-22_21:23:59.998942 <<< Plugin::~Plugin()
1879
2018-02-22_21:23:59.999004 <WARN> Unloading 'ybts' removed 0 out of 1 plugins
1880
Unloading module Analog Channel
1881
2018-02-22_21:23:59.999173 >>> Plugin::~Plugin() "analog" [0x7f57e6c15680]
1882
2018-02-22_21:23:59.999210 <<< Plugin::~Plugin()
1883
Unloading module CallCounters
1884
2018-02-22_21:23:59.999365 >>> Plugin::~Plugin() "callcounters" [0x7f57e702f1c0]
1885
2018-02-22_21:23:59.999398 <<< Plugin::~Plugin()
1886
2018-02-22_21:23:59.999525 >>> Plugin::~Plugin() "pbxassist" [0x7f57e723f3c0]
1887
2018-02-22_21:23:59.999561 <<< Plugin::~Plugin()
1888
Unloading module PBX for database
1889
2018-02-22_21:23:59.999727 >>> Plugin::~Plugin() "dbpbx" [0x7f57e7448280]
1890
2018-02-22_21:23:59.999756 <<< Plugin::~Plugin()
1891
Unloading module SIP Features
1892
2018-02-22_21:23:59.999935 >>> Plugin::~Plugin() "sipfeatures" [0x7f57e7651380]
1893
2018-02-22_21:23:59.999971 <<< Plugin::~Plugin()
1894
Unloading module MGCP-GW
1895
2018-02-22_21:24:00.000136 >>> Plugin::~Plugin() "mgcpgw" [0x7f57e7a740e0]
1896
2018-02-22_21:24:00.000170 <<< Plugin::~Plugin()
1897
Unloading module Late Router
1898
2018-02-22_21:24:00.000346 >>> Plugin::~Plugin() "lateroute" [0x7f57e82c2240]
1899
2018-02-22_21:24:00.000379 <<< Plugin::~Plugin()
1900
2018-02-22_21:24:00.000512 >>> Plugin::~Plugin() "accfile" [0x7f57e84c8240]
1901
2018-02-22_21:24:00.000542 <<< Plugin::~Plugin()
1902
Unloading module CCongestion
1903
2018-02-22_21:24:00.000662 >>> Plugin::~Plugin() "ccongestion" [0x7f57e86ce2c0]
1904
2018-02-22_21:24:00.000690 <<< Plugin::~Plugin()
1905
Unloading module Heartbeat
1906
2018-02-22_21:24:00.000846 >>> Plugin::~Plugin() "heartbeat" [0x7f57e88d5260]
1907
2018-02-22_21:24:00.000879 <<< Plugin::~Plugin()
1908
Unloaded module Monitoring
1909
2018-02-22_21:24:00.001014 >>> Plugin::~Plugin() "monitoring" [0x7f57e8af1280]
1910
2018-02-22_21:24:00.001045 <<< Plugin::~Plugin()
1911
Unloading module Queues Notify
1912
2018-02-22_21:24:00.001163 >>> Plugin::~Plugin() "queuesnotify" [0x7f57e8cfb480]
1913
2018-02-22_21:24:00.001242 <<< Plugin::~Plugin()
1914
Unloading module Queues
1915
2018-02-22_21:24:00.001366 >>> Plugin::~Plugin() "queues" [0x7f57e8f04580]
1916
2018-02-22_21:24:00.001401 <<< Plugin::~Plugin()
1917
Unloading module Call Parking
1918
2018-02-22_21:24:00.001520 >>> Plugin::~Plugin() "park" [0x7f57e910a240]
1919
2018-02-22_21:24:00.001553 <<< Plugin::~Plugin()
1920
Unloaded module Radius client
1921
2018-02-22_21:24:00.001691 >>> Plugin::~Plugin() "yradius" [0x7f57e9c86a40]
1922
2018-02-22_21:24:00.001727 <<< Plugin::~Plugin()
1923
Unloading module Register for database
1924
2018-02-22_21:24:00.001864 >>> Plugin::~Plugin() "register" [0x7f57ea3a6600]
1925
2018-02-22_21:24:00.001900 <<< Plugin::~Plugin()
1926
Unload module Registration from file
1927
2018-02-22_21:24:00.002021 >>> Plugin::~Plugin() "regfile" [0x7f57ea7b4380]
1928
2018-02-22_21:24:00.002054 <<< Plugin::~Plugin()
1929
Unloaded module Presence
1930
2018-02-22_21:24:00.002179 >>> Plugin::~Plugin() "presence" [0x7f57ea9bd2a0]
1931
2018-02-22_21:24:00.002207 <<< Plugin::~Plugin()
1932
Unloading module Analyzer
1933
2018-02-22_21:24:00.002310 >>> Plugin::~Plugin() "analyzer" [0x7f57eabc8420]
1934
2018-02-22_21:24:00.002335 <<< Plugin::~Plugin()
1935
Unloading module FileInfo
1936
2018-02-22_21:24:00.002495 >>> Plugin::~Plugin() "fileinfo" [0x7f57eadd3b20]
1937
2018-02-22_21:24:00.002520 <<< Plugin::~Plugin()
1938
Unloading module CdrCombine
1939
2018-02-22_21:24:00.002624 >>> Plugin::~Plugin() "cdrcombine" [0x7f57eafd9900]
1940
2018-02-22_21:24:00.002653 <<< Plugin::~Plugin()
1941
Unloading module PBX
1942
2018-02-22_21:24:00.002757 >>> Plugin::~Plugin() "pbx" [0x7f57eb1e0480]
1943
2018-02-22_21:24:00.002779 <<< Plugin::~Plugin()
1944
Unloading module iLBC webrtc with 0 codecs still in use
1945
2018-02-22_21:24:00.003022 >>> Plugin::~Plugin() "ilbcwebrtc" [0x7f57eb3f8580]
1946
2018-02-22_21:24:00.003054 <<< Plugin::~Plugin()
1947
Unloading module YSTUN
1948
2018-02-22_21:24:00.003174 >>> Plugin::~Plugin() "stun" [0x7f57eb6023a0]
1949
2018-02-22_21:24:00.003207 <<< Plugin::~Plugin()
1950
Unloading module ToneGen
1951
2018-02-22_21:24:00.003648 >>> Plugin::~Plugin() "tone" [0x7f57ec5315e0]
1952
2018-02-22_21:24:00.003680 <<< Plugin::~Plugin()
1953
2018-02-22_21:24:00.003898 >>> Plugin::~Plugin() "wave" [0x7f57ec741900]
1954
2018-02-22_21:24:00.003935 <<< Plugin::~Plugin()
1955
Unloading module DumbChannel
1956
2018-02-22_21:24:00.004051 >>> Plugin::~Plugin() "dumb" [0x7f57ec9471a0]
1957
2018-02-22_21:24:00.004087 <<< Plugin::~Plugin()
1958
Unloading module RManager
1959
2018-02-22_21:24:00.004193 >>> Plugin::~Plugin() "rmanager" [0x7f57ecb585c0]
1960
2018-02-22_21:24:00.004225 <<< Plugin::~Plugin()
1961
2018-02-22_21:24:00.004307 <WARN> Unloading 'tonedetect' removed 0 out of 1 plugins
1962
Unloading module YRTP
1963
2018-02-22_21:24:00.004450 >>> Plugin::~Plugin() "yrtp" [0x7f57ecf88f60]
1964
2018-02-22_21:24:00.004473 <<< Plugin::~Plugin()
1965
Unloading module MOH
1966
2018-02-22_21:24:00.004641 >>> Plugin::~Plugin() "moh" [0x7f57ed60f400]
1967
2018-02-22_21:24:00.004674 <<< Plugin::~Plugin()
1968
Unloading module Call Generator, clearing 0 calls
1969
2018-02-22_21:24:00.004784 >>> Plugin::~Plugin() "callgen" [0x7f57ed81a360]
1970
2018-02-22_21:24:00.004812 <<< Plugin::~Plugin()
1971
2018-02-22_21:24:00.004870 <WARN> Unloading 'yjinglechan' removed 0 out of 1 plugins
1972
2018-02-22_21:24:00.004934 >>> Plugin::~Plugin() "msgsniff" [0x7f57ee2d1220]
1973
2018-02-22_21:24:00.004960 <<< Plugin::~Plugin()
1974
Unloading module GVoice
1975
2018-02-22_21:24:00.005122 >>> Plugin::~Plugin() "gvoice" [0x7f57ee4d7460]
1976
2018-02-22_21:24:00.005150 <<< Plugin::~Plugin()
1977
Unloading module YIAX
1978
2018-02-22_21:24:00.005291 >>> Plugin::~Plugin() "iax" [0x7f57ee7134c0]
1979
2018-02-22_21:24:00.005320 <<< Plugin::~Plugin()
1980
Unloading module iSAC with 0 codecs still in use
1981
2018-02-22_21:24:00.005986 >>> Plugin::~Plugin() "isaccodec" [0x7f57ee94f6e0]
1982
2018-02-22_21:24:00.006006 <<< Plugin::~Plugin()
1983
Unloading module iLBC with 0 codecs still in use
1984
2018-02-22_21:24:00.006322 >>> Plugin::~Plugin() "ilbccodec" [0x7f57eeb72000]
1985
2018-02-22_21:24:00.006340 <<< Plugin::~Plugin()
1986
2018-02-22_21:24:00.006399 >>> Plugin::~Plugin() "enumroute" [0x7f57eed79200]
1987
2018-02-22_21:24:00.006417 <<< Plugin::~Plugin()
1988
2018-02-22_21:24:00.006499 >>> Plugin::~Plugin() "regexroute" [0x7f57eef86f00]
1989
2018-02-22_21:24:00.006517 <<< Plugin::~Plugin()
1990
Unloading module Conference
1991
2018-02-22_21:24:00.006596 >>> Plugin::~Plugin() "conf" [0x7f57ef197480]
1992
2018-02-22_21:24:00.006613 <<< Plugin::~Plugin()
1993
Unloading module GSM with 0 codecs still in use
1994
2018-02-22_21:24:00.006783 >>> Plugin::~Plugin() "gsmcodec" [0x7f57ef5a8140]
1995
2018-02-22_21:24:00.006801 <<< Plugin::~Plugin()
1996
Unloading module Call Forker
1997
2018-02-22_21:24:00.006878 >>> Plugin::~Plugin() "callfork" [0x7f57ef7b4420]
1998
2018-02-22_21:24:00.006895 <<< Plugin::~Plugin()
1999
Unloading module Speex with 0 codecs still in use
2000
2018-02-22_21:24:00.007087 >>> Plugin::~Plugin() "speexcodec" [0x7f57efbd51e0]
2001
2018-02-22_21:24:00.007106 <<< Plugin::~Plugin()
2002
2018-02-22_21:24:00.007182 >>> Plugin::~Plugin() "fax" [0x7f57f0beb4c0]
2003
2018-02-22_21:24:00.007202 <<< Plugin::~Plugin()
2004
Unloading module CdrBuild
2005
2018-02-22_21:24:00.007408 >>> Plugin::~Plugin() "cdrbuild" [0x7f57f0ffd1e0]
2006
2018-02-22_21:24:00.007428 <<< Plugin::~Plugin()
2007
2018-02-22_21:24:00.007469 <WARN> Unloading 'extmodule' removed 0 out of 1 plugins
2008
2018-02-22_21:24:00.007498 <WARN> Unloading 'ysipchan' removed 0 out of 1 plugins
2009
Unloading module CdrFile
2010
2018-02-22_21:24:00.007542 >>> Plugin::~Plugin() "cdrfile" [0x7f57f0df0240]
2011
2018-02-22_21:24:00.007562 <<< Plugin::~Plugin()
2012
Unloading module MUX
2013
2018-02-22_21:24:00.007625 >>> Plugin::~Plugin() "mux" [0x7f57ee0cb600]
2014
2018-02-22_21:24:00.007644 <<< Plugin::~Plugin()
2015
Unloading module File Transfer
2016
2018-02-22_21:24:00.007703 >>> Plugin::~Plugin() "filetransfer" [0x7f57eda2a820]
2017
2018-02-22_21:24:00.007720 <<< Plugin::~Plugin()
2018
2018-02-22_21:24:00.007755 <WARN> Unloading 'javascript' removed 0 out of 1 plugins
2019
Unloading module ZLib
2020
2018-02-22_21:24:00.007797 >>> Plugin::~Plugin() "zlibcompress" [0x7f57ec320220]
2021
2018-02-22_21:24:00.007810 <<< Plugin::~Plugin()
2022
2018-02-22_21:24:00.007845 <WARN> Unloading 'ysockschan' removed 0 out of 1 plugins
2023
Unloading module OpenSSL
2024
2018-02-22_21:24:00.007912 >>> Plugin::~Plugin() "openssl" [0x7f57ebf025a0]
2025
2018-02-22_21:24:00.007930 <<< Plugin::~Plugin()
2026
Unloading module Event Logs
2027
2018-02-22_21:24:00.007986 >>> Plugin::~Plugin() "eventlogs" [0x7f57ea5ab2e0]
2028
2018-02-22_21:24:00.008003 <<< Plugin::~Plugin()
2029
Unloading module SQLite
2030
2018-02-22_21:24:00.008075 >>> Plugin::~Plugin() "sqlitedb" [0x7f57ea198380]
2031
2018-02-22_21:24:00.008095 <<< Plugin::~Plugin()
2032
Unloading module Clustering
2033
2018-02-22_21:24:00.008182 >>> Plugin::~Plugin() "clustering" [0x7f57e9a751e0]
2034
2018-02-22_21:24:00.008196 <<< Plugin::~Plugin()
2035
2018-02-22_21:24:00.008225 <WARN> Unloading 'ciscosm' removed 0 out of 1 plugins
2036
Unloading module SigTransport
2037
2018-02-22_21:24:00.008290 >>> Plugin::~Plugin() "sigtransport" [0x7f57e80bd700]
2038
2018-02-22_21:24:00.008319 <<< Plugin::~Plugin()
2039
Unloading module MGCP-CA
2040
2018-02-22_21:24:00.008389 >>> Plugin::~Plugin() "mgcpca" [0x7f57e7ead240]
2041
2018-02-22_21:24:00.008408 <<< Plugin::~Plugin()
2042
Unloading module ToneDetector
2043
2018-02-22_21:24:00.008448 >>> Plugin::~Plugin() "tonedetect" [0x7f57ecd612e0]
2044
2018-02-22_21:24:00.008467 <<< Plugin::~Plugin()
2045
Unloading module SIP Channel
2046
2018-02-22_21:24:00.008521 >>> Plugin::~Plugin() "sip" [0x7f57f147c800]
2047
2018-02-22_21:24:00.008542 <<< Plugin::~Plugin()
2048
2018-02-22_21:24:00.008641 <WARN> Unloading 'mgcpca' removed 3 out of 1 plugins
2049
2018-02-22_21:24:00.008674 >>> Plugin::~Plugin() "dbwave" [0x7f57e7857260]
2050
2018-02-22_21:24:00.008714 <<< Plugin::~Plugin()
2051
Unloading module Subscriptions
2052
2018-02-22_21:24:00.008781 >>> Plugin::~Plugin() "subscription" [0x7f57e6e297a0]
2053
2018-02-22_21:24:00.008800 <<< Plugin::~Plugin()
2054
Unloading module Analog Detector
2055
2018-02-22_21:24:00.008861 >>> Plugin::~Plugin() "analogdetect" [0x7f57e63609c0]
2056
2018-02-22_21:24:00.008881 <<< Plugin::~Plugin()
2057
Unloading module Cpu
2058
2018-02-22_21:24:00.008941 >>> Plugin::~Plugin() "cpuload" [0x7f57e58c63e0]
2059
2018-02-22_21:24:00.008960 <<< Plugin::~Plugin()
2060
Unloading module Jabber Server
2061
2018-02-22_21:24:00.009038 >>> Plugin::~Plugin() "jabber" [0x7f57e548fd20]
2062
2018-02-22_21:24:00.009060 <<< Plugin::~Plugin()
2063
Unloading module Jabber Server Features
2064
2018-02-22_21:24:00.009128 >>> Plugin::~Plugin() "jbfeatures" [0x7f57e5264420]
2065
2018-02-22_21:24:00.009147 <<< Plugin::~Plugin()
2066
Unloading module BladeRF
2067
2018-02-22_21:24:00.009388 <bladerf:ALL> Cleared libusb context
2068
2018-02-22_21:24:00.009408 >>> Plugin::~Plugin() "bladerf" [0x7f57e5058280]
2069
2018-02-22_21:24:00.009419 <<< Plugin::~Plugin()
2070
Unloading module DummyRadio
2071
2018-02-22_21:24:00.009668 >>> Plugin::~Plugin() "dummyradio" [0x7f57e3ce2700]
2072
2018-02-22_21:24:00.009683 <<< Plugin::~Plugin()
2073
2018-02-22_21:24:00.009725 <WARN> Exiting with 0 locked mutexes and 8 plugins loaded!
2074
Yate (8914) is stopping Thu Feb 22 21:24:00 2018
2075
Unloading module GSM Transceiver
2076
2018-02-22_21:24:00.009935 >>> Plugin::~Plugin() "gsmtrx" [0x7f57e5afe060]
2077
2018-02-22_21:24:00.009983 <<< Plugin::~Plugin()
2078
Unloaded module SNMP Agent
2079
2018-02-22_21:24:00.010213 >>> Plugin::~Plugin() "snmpagent" [0x7f57e6150f40]
2080
2018-02-22_21:24:00.010231 <<< Plugin::~Plugin()
2081
Unloading module YBTS
2082
2018-02-22_21:24:00.010292 >>> Plugin::~Plugin() "ybts" [0x7f57e69fb440]
2083
2018-02-22_21:24:00.010310 <<< Plugin::~Plugin()
2084
Unloading module Cisco SM
2085
2018-02-22_21:24:00.010444 >>> Plugin::~Plugin() "ciscosm" [0x7f57e986f540]
2086
2018-02-22_21:24:00.010465 <<< Plugin::~Plugin()
2087
Unloading module YSOCKS
2088
2018-02-22_21:24:00.010902 >>> Plugin::~Plugin() "socks" [0x7f57ec119960]
2089
2018-02-22_21:24:00.010921 <<< Plugin::~Plugin()
2090
Unloading module Javascript
2091
2018-02-22_21:24:00.010991 >>> Plugin::~Plugin() "javascript" [0x7f57ed4068a0]
2092
2018-02-22_21:24:00.011009 <<< Plugin::~Plugin()
2093
Unloading module YJingle
2094
2018-02-22_21:24:00.011070 >>> Plugin::~Plugin() "jingle" [0x7f57edebfa40]
2095
2018-02-22_21:24:00.011088 <<< Plugin::~Plugin()
2096
Unloading module ExtModule
2097
2018-02-22_21:24:00.011196 >>> Plugin::~Plugin() "extmodule" [0x7f57f1212940]
2098
2018-02-22_21:24:00.011207 <<< Plugin::~Plugin()
2099
lifebook:~ #(B exit
Add picture from clipboard (Maximum size: 48.8 MB)