Project

General

Profile

Feature #2430 » 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

    
693
type:              Gateway
694

    
695
bind address:      :2427
696

    
697
allow_unknown_cmd: false
698

    
699
retrans_interval:  250000
700

    
701
retrans_count:     3
702

    
703
lower_case_params: true
704

    
705
max_recv_packet:   1500
706

    
707
send_provisional:  true
708
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
709
Initializing module SIP Features
710
2018-02-22_21:21:32.788752 <sipfeatures:ALL> Allowed subscriptions: dialog,message-summary
711
Initializing module PBX for database
712
Initializing module Analog Channel
713
Initializing module YBTS
714
2018-02-22_21:21:32.789698 <ybts:INFO> LAI changed (null) -> 00101_0001
715
Initializing module Cache
716
Initializing module SNMP Agent
717
2018-02-22_21:21:32.797733 <snmpagent:ALL> SnmpMsgQueue created for 0.0.0.0:161 with priority 'normal'
718
2018-02-22_21:21:32.797856 <snmpagent:INFO> SNMP UDP Listener initialized on port 161
719
Initializing module MrcpSpeech
720
Initializing module Users Management
721
Initializing module GSM Transceiver
722
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]
723
2018-02-22_21:21:32.799125 <gsmtrx:INFO> Module state changed Idle -> Waiting
724
Initializing module Signalling Channel
725
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]
726
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]
727
2018-02-22_21:21:32.800090 <sig:NOTE> Section 'tcapuser_test'. Unknown/missing type '(null)'
728
Initializing module Radio Test
729
Initialization complete
730
2018-02-22_21:21:32.800386 <ALL> Engine dispatching start message
731
2018-02-22_21:21:32.800670 <ybts:NOTE> State changed Idle -> Starting restart counter 1/10
732
2018-02-22_21:21:32.800766 <transceiver:ALL> Started worker thread [0x564a86b941e0]
733
2018-02-22_21:21:32.800803 <transceiver:INFO> Started [0x564a86b941e0]
734
2018-02-22_21:21:32.800970 <mbts:ALL> Started worker thread [0x564a86b943f0]
735
2018-02-22_21:21:32.801005 <mbts:INFO> Started [0x564a86b943f0]
736
2018-02-22_21:21:32.801122 <ybts-command:INFO> Started [0x564a86b94650]
737
2018-02-22_21:21:32.801384 <ybts-signalling:ALL> Started worker thread [0x564a86b94a90]
738
2018-02-22_21:21:32.801435 <ybts-signalling:INFO> Started [0x564a86b94a90]
739
2018-02-22_21:21:32.801505 <ybts-media:ALL> Started worker thread [0x564a86b947b0]
740
2018-02-22_21:21:32.801590 <ybts-media:INFO> Started [0x564a86b947b0]
741
2018-02-22_21:21:32.801689 <ybts:ALL> Starting peer '/usr/lib64/yate/server/bts/mbts' '(null)'
742
2018-02-22_21:21:32.802720 <ybts:INFO> Started peer pid=8944
743
2018-02-22_21:21:32.802867 <ybts:NOTE> State changed Starting -> WaitHandshake
744
2018-02-22_21:21:32.802924 <ybts:ALL> Restart scheduled in 120000ms [0x7f57e69fb440]
745
MBTS connected to YBTS
746
Yate-BTS MBTS Component
747

    
748
Copyright 2008, 2009, 2010 Free Software Foundation, Inc.
749

    
750
Copyright 2010 Kestrel Signal Processing, Inc.
751

    
752
Copyright 2011, 2012, 2013 Range Networks, Inc.
753

    
754
Copyright 2013, 2014 Null Team Impex SRL
755

    
756
Copyright 2014 Legba, Inc.
757

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

    
760

    
761

    
762
Contributors:
763

    
764
  SC Null Team Impex SRL:
765

    
766
    Paul Chitescu
767

    
768
  Legba, Inc.
769

    
770
    David Burgess
771

    
772
  Range Networks, Inc.:
773

    
774
    David Burgess, Harvind Samra, Donald Kirker, Doug Brown,
775

    
776
    Pat Thompson, Kurtis Heimerl
777

    
778
  Kestrel Signal Processing, Inc.:
779

    
780
    David Burgess, Harvind Samra, Raffi Sevlian, Roshan Baliga
781

    
782
  GNU Radio:
783

    
784
    Johnathan Corgan
785

    
786
  Others:
787

    
788
    Anne Kwong, Jacob Appelbaum, Joshua Lackey, Alon Levy
789

    
790
    Alexander Chemeris, Alberto Escudero-Pascual
791

    
792
Incorporated L/GPL libraries and components:
793

    
794
  libusb, LGPL 2.1, various copyright holders, www.libusb.org
795

    
796
Incorporated BSD/MIT-style libraries and components:
797

    
798
  A5/1 Pedagogical Implementation, Simplified BSD License,
799

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

    
802
Incorporated public domain libraries and components:
803

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

    
806

    
807

    
808

    
809

    
810
This program comes with ABSOLUTELY NO WARRANTY.
811

    
812

    
813

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

    
816
including patent licensing and radio spectrum licensing.
817

    
818
All users of this software are expected to comply with applicable
819

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

    
822
more information.
823

    
824

    
825

    
826
Release 6.0.0 formal build date Jan 19 2018 rev
827

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

    
835
-----
836

    
837
Primitive: Handshake
838

    
839
Info: 0
840

    
841
-----
842
2018-02-22_21:21:32.824284 <ybts:NOTE> State changed WaitHandshake -> Running
843
2018-02-22_21:21:32.824316 <ybts-signalling:INFO> Sending [0x564a86b94a90]
844

    
845
-----
846

    
847
Primitive: Handshake
848

    
849
Info: 0
850

    
851
-----
852
2018-02-22_21:21:32.834775 <javascript:INFO> Parsed 'nipc' script: /usr/share/yate/scripts/nipc.js
853
2018-02-22_21:21:32.838604 <nipc:INFO> Finished reading saved registered subscribers. Found 1 registered_subscribers.
854
2018-02-22_21:21:32.839207 <nipc:INFO> Checked that only NiPC is enabled.
855
2018-02-22_21:21:32.839297 <ALL> Rescanning handler list for 'engine.start' [0x7ffc6f717670] at priority 100
856
Yate engine is initialized and starting up on lifebook.home.lan
857
2018-02-22_21:21:32.839572 <INFO> Creating first 1 message dispatching threads
858
2018-02-22_21:21:32.890655 <bladerf/1:ALL> Opened device bus=4 addr=2 [0x7f57bc000e40]
859
2018-02-22_21:21:33.190661 <bladerf/1:ALL> Reset USB device bus=4 addr=2 [0x7f57bc000e40]
860
2018-02-22_21:21:33.190834 <bladerf/1:ALL> Device closed [0x7f57bc000e40]
861
2018-02-22_21:21:33.191823 <bladerf/1:ALL> Opened device bus=4 addr=2 [0x7f57bc000e40]
862
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]
863
2018-02-22_21:21:33.192002 <bladerf/1:ALL> Initialized I/O RX buffers=8 samples/buffer=252 total_bytes=8192 [0x7f57bc000e40]
864
2018-02-22_21:21:33.193221 <bladerf/1:ALL> Updating FPGA [0x7f57bc000e40]
865
2018-02-22_21:21:33.199882 <bladerf/1:ALL> Loading FPGA from '/usr/share/yate/data/hostedx40.rbf' len=1191788 [0x7f57bc000e40]
866
2018-02-22_21:21:33.404053 <bladerf/1:ALL> Loaded FPGA from '/usr/share/yate/data/hostedx40.rbf' [0x7f57bc000e40]
867
2018-02-22_21:21:33.418452 <bladerf/1:ALL> Writing '4740592964367937' to LMS [0x7f57bc000e40]
868
2018-02-22_21:21:33.421032 <bladerf/1:ALL> RX DC offset I set to 0 (from 0) reg=0x80 [0x7f57bc000e40]
869
2018-02-22_21:21:33.421942 <bladerf/1:ALL> RX DC offset Q set to 0 (from 0) reg=0x80 [0x7f57bc000e40]
870
2018-02-22_21:21:33.424431 <bladerf/1:ALL> TX DC offset I set to 0 (from 0) reg=0x80 [0x7f57bc000e40]
871
2018-02-22_21:21:33.425168 <bladerf/1:ALL> TX DC offset Q set to 0 (from 0) reg=0x80 [0x7f57bc000e40]
872
2018-02-22_21:21:33.426014 <bladerf/1:INFO> RX VGA1 set to 30dB 0x78 (from 30) [0x7f57bc000e40]
873
2018-02-22_21:21:33.431912 <bladerf/1:ALL> Opened device [0x7f57bc000e40]
874

    
875
-----
876

    
877
Address=USB/4/2
878

    
879
Serial=a412decab55aafd046a0a568604e786b
880

    
881
Speed=HIGH
882

    
883
Firmware=1.9.1
884

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

    
887
LMS_Ver=0x22 (2.2)
888

    
889
-----
890
2018-02-22_21:21:33.432314 <gsmtrx:ALL> Adjusting parameter arfcns='1' -> 4 [0x7f57bc01f570]
891
2018-02-22_21:21:33.433008 <gsmtrx:ALL> Initialized [0x7f57bc01f570]
892

    
893
-----
894

    
895
ARFCNs=4
896

    
897
oversampling=8
898

    
899
-----
900
2018-02-22_21:21:33.438121 <bladerf/1:INFO> TX samplerate set to 2166667 [0x7f57bc000e40]
901
2018-02-22_21:21:33.443285 <bladerf/1:INFO> RX samplerate set to 2166667 [0x7f57bc000e40]
902
2018-02-22_21:21:33.456893 <bladerf/1:INFO> TX LPF bandwidth set to 1500000 (from 1500000, reg=0x3e) [0x7f57bc000e40]
903
2018-02-22_21:21:33.457661 <bladerf/1:INFO> RX LPF bandwidth set to 1500000 (from 1500000, reg=0x3e) [0x7f57bc000e40]
904
2018-02-22_21:21:33.458552 <gsmtrx:INFO> radio_latency_slots changed 0 -> 5 [0x7f57bc01f570]
905
2018-02-22_21:21:33.458602 <gsmtrx:INFO> tx_slots changed 1 -> 16 [0x7f57bc01f570]
906
2018-02-22_21:21:33.458648 <gsmtrx:INFO> Transceiver initialized radio=(0x7f57bc000e40) 'bladerf/1' [0x7f57bc01f570]
907
2018-02-22_21:21:33.458687 <gsmtrx:NOTE> State changed Invalid -> Idle [0x7f57bc01f570]
908
2018-02-22_21:21:33.458829 <gsmtrx:ALL> Starting [0x7f57bc01f570]
909
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]
910
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]
911
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]
912
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]
913
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]
914
2018-02-22_21:21:33.459181 <gsmtrx:NOTE> State changed Idle -> PowerOff [0x7f57bc01f570]
915
2018-02-22_21:21:33.459231 <gsmtrx:INFO> Module state changed Waiting -> Running
916
2018-02-22_21:21:33.459525 <gsmtrx:ALL> Handling command 'CMD 0 READFACTORY sdrsn' arfcn=0 [0x7f57bc01f570]
917
2018-02-22_21:21:33.459553 <gsmtrx:ALL> Command 'CMD 0 READFACTORY sdrsn' (ARFCN=0) RSP '0'
918
2018-02-22_21:21:33.459850 <gsmtrx:ALL> Handling command 'CMD 0 RXTUNE 1781800' arfcn=0 [0x7f57bc01f570]
919
2018-02-22_21:21:33.469390 <bladerf/1:INFO> LNA 2 selected [0x7f57bc000e40]
920
2018-02-22_21:21:33.470536 <bladerf/1:ALL> FrequencyOffset set to 128 [0x7f57bc000e40]
921
2018-02-22_21:21:33.470591 <bladerf/1:INFO> RX frequency set to 1782.4MHz offset=128 [0x7f57bc000e40]
922
2018-02-22_21:21:33.472163 <gsmtrx:ALL> Command 'CMD 0 RXTUNE 1781800' (ARFCN=0) RSP '1782400000'
923
2018-02-22_21:21:33.472389 <gsmtrx:ALL> Handling command 'CMD 0 TXTUNE 1876800' arfcn=0 [0x7f57bc01f570]
924
2018-02-22_21:21:33.482150 <bladerf/1:INFO> PA2 enabled [0x7f57bc000e40]
925
2018-02-22_21:21:33.483393 <bladerf/1:ALL> FrequencyOffset set to 128 [0x7f57bc000e40]
926
2018-02-22_21:21:33.483622 <bladerf/1:INFO> TX frequency set to 1877.4MHz offset=128 [0x7f57bc000e40]
927
2018-02-22_21:21:33.485271 <gsmtrx:ALL> Command 'CMD 0 TXTUNE 1876800' (ARFCN=0) RSP '1877400000'
928
2018-02-22_21:21:33.485598 <gsmtrx:ALL> Handling command 'CMD 0 SETTSC 2' arfcn=0 [0x7f57bc01f570]
929
2018-02-22_21:21:33.485832 <gsmtrx:INFO> TSC changed 0 -> 2 [0x7f57bc01f570]
930
2018-02-22_21:21:33.486012 <gsmtrx:ALL> Command 'CMD 0 SETTSC 2' (ARFCN=0) RSP '2'
931
2018-02-22_21:21:33.486289 <gsmtrx:ALL> Handling command 'CMD 0 SETMAXDLY 2' arfcn=0 [0x7f57bc01f570]
932
2018-02-22_21:21:33.486484 <gsmtrx:ALL> Command 'CMD 0 SETMAXDLY 2' (ARFCN=0) RSP '2'
933
2018-02-22_21:21:33.486728 <gsmtrx:ALL> Handling command 'CMD 0 SETRXGAIN 0' arfcn=0 [0x7f57bc01f570]
934
2018-02-22_21:21:33.488407 <gsmtrx:ALL> Command 'CMD 0 SETRXGAIN 0' (ARFCN=0) RSP '0'
935
2018-02-22_21:21:33.488656 <gsmtrx:ALL> Handling command 'CMD 0 POWERON' arfcn=0 [0x7f57bc01f570]
936
2018-02-22_21:21:33.488880 <gsmtrx:INFO> Starting radio [0x7f57bc01f570]
937
2018-02-22_21:21:33.489061 <bladerf/1:ALL> Initializing ... [0x7f57bc000e40]
938
2018-02-22_21:21:33.490377 <bladerf/1:ALL> Enabled timestamps [0x7f57bc000e40]
939
2018-02-22_21:21:33.511704 <bladerf/1:ALL> Enabled RF TX [0x7f57bc000e40]
940
2018-02-22_21:21:33.523687 <bladerf/1:ALL> Enabled RF RX [0x7f57bc000e40]
941
2018-02-22_21:21:33.523817 <bladerf/1:NOTE> Powered ON the radio [0x7f57bc000e40]
942
2018-02-22_21:21:33.524128 <bladerf/1:ALL> Initialized [0x7f57bc000e40]
943
2018-02-22_21:21:33.528409 <gsmtrx:NOTE> State changed PowerOff -> PowerOn [0x7f57bc01f570]
944
2018-02-22_21:21:33.528508 <gsmtrx:ALL> Command 'CMD 0 POWERON' (ARFCN=0) RSP '(null)'
945
2018-02-22_21:21:33.528728 <gsmtrx:ALL> Handling command 'CMD 0 SETPOWER 0' arfcn=0 [0x7f57bc01f570]
946
2018-02-22_21:21:33.529759 <bladerf/1:INFO> TX VGA2 set to 25dB (0xc8) [0x7f57bc000e40]
947
2018-02-22_21:21:33.529812 <gsmtrx:INFO> Set Tx gain=0 power_scaling=1 [0x7f57bc01f570]
948
2018-02-22_21:21:33.529843 <gsmtrx:ALL> Command 'CMD 0 SETPOWER 0' (ARFCN=0) RSP '0'
949
2018-02-22_21:21:33.530004 <gsmtrx:ALL> Handling command 'CMD 0 SETSLOT 0 5' arfcn=0 [0x7f57bc01f570]
950
2018-02-22_21:21:33.530034 <gsmtrx:ALL> ARFCN[0]: Slot 0 type set to 5 'V' [0x7f57bc00f350]
951
2018-02-22_21:21:33.530058 <gsmtrx:ALL> ARFCN[0]: Slot 0 burst type changed None -> Check [0x7f57bc00f350]
952
2018-02-22_21:21:33.530082 <gsmtrx:ALL> Command 'CMD 0 SETSLOT 0 5' (ARFCN=0) RSP '0 5'
953
2018-02-22_21:21:33.530401 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f57bc01f570]
954
2018-02-22_21:21:33.530437 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0) RSP '0'
955
2018-02-22_21:21:33.530671 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f57bc01f570]
956
2018-02-22_21:21:33.530703 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0) RSP '0'
957
2018-02-22_21:21:33.530887 <bladerf/1:ALL> RX: timestamp jumped by 45735 to 0 in buffer 1/8 [0x7f57bc000e40]
958
2018-02-22_21:21:33.530930 <bladerf/1:ALL> RX: timestamp adjusted by 45735 to 45987 [0x7f57bc000e40]
959
2018-02-22_21:21:33.530959 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f57bc01f570]
960
2018-02-22_21:21:33.530991 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0) RSP '0'
961
2018-02-22_21:21:33.531367 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f57bc01f570]
962
2018-02-22_21:21:33.531407 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0) RSP '0'
963
2018-02-22_21:21:33.531593 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f57bc01f570]
964
2018-02-22_21:21:33.531623 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0) RSP '0'
965
2018-02-22_21:21:33.531824 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f57bc01f570]
966
2018-02-22_21:21:33.531857 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0) RSP '0'
967
2018-02-22_21:21:33.532011 <bladerf/1:ALL> RX: timestamp jumped by 29783 to 55059 in buffer 6/8 [0x7f57bc000e40]
968
2018-02-22_21:21:33.532043 <bladerf/1:ALL> RX: timestamp adjusted by 29783 to 85094 [0x7f57bc000e40]
969
2018-02-22_21:21:33.532985 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f57bc01f570]
970
2018-02-22_21:21:33.533061 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0) RSP '0'
971
2018-02-22_21:21:33.533423 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f57bc01f570]
972
2018-02-22_21:21:33.533459 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0) RSP '0'
973
2018-02-22_21:21:33.534125 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f57bc01f570]
974
2018-02-22_21:21:33.534196 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0) RSP '0'
975
2018-02-22_21:21:33.534512 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f57bc01f570]
976
2018-02-22_21:21:33.534547 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0) RSP '0'
977
2018-02-22_21:21:33.535948 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f57bc01f570]
978
2018-02-22_21:21:33.536010 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0) RSP '0'
979
2018-02-22_21:21:33.536652 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f57bc01f570]
980
2018-02-22_21:21:33.536722 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0) RSP '0'
981
2018-02-22_21:21:33.537098 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f57bc01f570]
982
2018-02-22_21:21:33.537139 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0) RSP '0'
983
2018-02-22_21:21:33.537752 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f57bc01f570]
984
2018-02-22_21:21:33.537791 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0) RSP '0'
985
2018-02-22_21:21:33.538123 <mbts:NOTE> GSMConfig.cpp:532:createCombinationI: Configuring combination I on C0T1
986
2018-02-22_21:21:33.538277 <gsmtrx:ALL> ARFCN[0]: Slot 1 type set to 1 'I' [0x7f57bc00f350]
987
2018-02-22_21:21:33.538307 <gsmtrx:ALL> ARFCN[0]: Slot 1 burst type changed None -> Normal [0x7f57bc00f350]
988
2018-02-22_21:21:33.538333 <gsmtrx:ALL> Command 'CMD 0 SETSLOT 1 1' (ARFCN=0) RSP '1 1'
989
2018-02-22_21:21:33.539835 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 1' arfcn=0 [0x7f57bc01f570]
990
2018-02-22_21:21:33.539885 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 1' (ARFCN=0) RSP '1'
991
2018-02-22_21:21:33.540252 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 1' arfcn=0 [0x7f57bc01f570]
992
2018-02-22_21:21:33.540284 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 1' (ARFCN=0) RSP '1'
993
2018-02-22_21:21:33.540992 <mbts:NOTE> GSMConfig.cpp:532:createCombinationI: Configuring combination I on C0T2
994
2018-02-22_21:21:33.541055 <gsmtrx:ALL> Handling command 'CMD 0 SETSLOT 2 1' arfcn=0 [0x7f57bc01f570]
995
2018-02-22_21:21:33.541085 <gsmtrx:ALL> ARFCN[0]: Slot 2 type set to 1 'I' [0x7f57bc00f350]
996
2018-02-22_21:21:33.541109 <gsmtrx:ALL> ARFCN[0]: Slot 2 burst type changed None -> Normal [0x7f57bc00f350]
997
2018-02-22_21:21:33.541137 <gsmtrx:ALL> Command 'CMD 0 SETSLOT 2 1' (ARFCN=0) RSP '2 1'
998
2018-02-22_21:21:33.541830 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 2' arfcn=0 [0x7f57bc01f570]
999
2018-02-22_21:21:33.541872 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 2' (ARFCN=0) RSP '2'
1000
2018-02-22_21:21:33.542358 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 2' arfcn=0 [0x7f57bc01f570]
1001
2018-02-22_21:21:33.542407 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 2' (ARFCN=0) RSP '2'
1002
2018-02-22_21:21:33.542671 <mbts:NOTE> GSMConfig.cpp:532:createCombinationI: Configuring combination I on C0T3
1003
2018-02-22_21:21:33.542768 <gsmtrx:ALL> Handling command 'CMD 0 SETSLOT 3 1' arfcn=0 [0x7f57bc01f570]
1004
2018-02-22_21:21:33.542797 <gsmtrx:ALL> ARFCN[0]: Slot 3 type set to 1 'I' [0x7f57bc00f350]
1005
2018-02-22_21:21:33.542823 <gsmtrx:ALL> ARFCN[0]: Slot 3 burst type changed None -> Normal [0x7f57bc00f350]
1006
2018-02-22_21:21:33.542851 <gsmtrx:ALL> Command 'CMD 0 SETSLOT 3 1' (ARFCN=0) RSP '3 1'
1007
2018-02-22_21:21:33.543655 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 3' arfcn=0 [0x7f57bc01f570]
1008
2018-02-22_21:21:33.543697 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 3' (ARFCN=0) RSP '3'
1009
2018-02-22_21:21:33.543982 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 3' arfcn=0 [0x7f57bc01f570]
1010
2018-02-22_21:21:33.544016 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 3' (ARFCN=0) RSP '3'
1011
2018-02-22_21:21:33.544641 <mbts:NOTE> GSMConfig.cpp:532:createCombinationI: Configuring combination I on C0T4
1012
2018-02-22_21:21:33.544695 <gsmtrx:ALL> Handling command 'CMD 0 SETSLOT 4 1' arfcn=0 [0x7f57bc01f570]
1013
2018-02-22_21:21:33.544722 <gsmtrx:ALL> ARFCN[0]: Slot 4 type set to 1 'I' [0x7f57bc00f350]
1014
2018-02-22_21:21:33.544748 <gsmtrx:ALL> ARFCN[0]: Slot 4 burst type changed None -> Normal [0x7f57bc00f350]
1015
2018-02-22_21:21:33.544775 <gsmtrx:ALL> Command 'CMD 0 SETSLOT 4 1' (ARFCN=0) RSP '4 1'
1016
2018-02-22_21:21:33.545315 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 4' arfcn=0 [0x7f57bc01f570]
1017
2018-02-22_21:21:33.545365 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 4' (ARFCN=0) RSP '4'
1018
2018-02-22_21:21:33.545650 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 4' arfcn=0 [0x7f57bc01f570]
1019
2018-02-22_21:21:33.545684 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 4' (ARFCN=0) RSP '4'
1020
2018-02-22_21:21:33.545953 <mbts:NOTE> GSMConfig.cpp:532:createCombinationI: Configuring combination I on C0T5
1021
2018-02-22_21:21:33.546313 <gsmtrx:ALL> Handling command 'CMD 0 SETSLOT 5 1' arfcn=0 [0x7f57bc01f570]
1022
2018-02-22_21:21:33.546347 <gsmtrx:ALL> ARFCN[0]: Slot 5 type set to 1 'I' [0x7f57bc00f350]
1023
2018-02-22_21:21:33.546380 <gsmtrx:ALL> ARFCN[0]: Slot 5 burst type changed None -> Normal [0x7f57bc00f350]
1024
2018-02-22_21:21:33.546405 <gsmtrx:ALL> Command 'CMD 0 SETSLOT 5 1' (ARFCN=0) RSP '5 1'
1025
2018-02-22_21:21:33.547236 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 5' arfcn=0 [0x7f57bc01f570]
1026
2018-02-22_21:21:33.547278 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 5' (ARFCN=0) RSP '5'
1027
2018-02-22_21:21:33.547728 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 5' arfcn=0 [0x7f57bc01f570]
1028
2018-02-22_21:21:33.547765 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 5' (ARFCN=0) RSP '5'
1029
2018-02-22_21:21:33.548375 <mbts:NOTE> GSMConfig.cpp:532:createCombinationI: Configuring combination I on C0T6
1030
2018-02-22_21:21:33.548546 <gsmtrx:ALL> Handling command 'CMD 0 SETSLOT 6 1' arfcn=0 [0x7f57bc01f570]
1031
2018-02-22_21:21:33.548580 <gsmtrx:ALL> ARFCN[0]: Slot 6 type set to 1 'I' [0x7f57bc00f350]
1032
2018-02-22_21:21:33.548602 <gsmtrx:ALL> ARFCN[0]: Slot 6 burst type changed None -> Normal [0x7f57bc00f350]
1033
2018-02-22_21:21:33.548629 <gsmtrx:ALL> Command 'CMD 0 SETSLOT 6 1' (ARFCN=0) RSP '6 1'
1034
2018-02-22_21:21:33.549419 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 6' arfcn=0 [0x7f57bc01f570]
1035
2018-02-22_21:21:33.549471 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 6' (ARFCN=0) RSP '6'
1036
2018-02-22_21:21:33.549989 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 6' arfcn=0 [0x7f57bc01f570]
1037
2018-02-22_21:21:33.550026 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 6' (ARFCN=0) RSP '6'
1038
2018-02-22_21:21:33.550434 <mbts:NOTE> GSMConfig.cpp:532:createCombinationI: Configuring combination I on C0T7
1039
2018-02-22_21:21:33.550759 <gsmtrx:ALL> Handling command 'CMD 0 SETSLOT 7 1' arfcn=0 [0x7f57bc01f570]
1040
2018-02-22_21:21:33.550792 <gsmtrx:ALL> ARFCN[0]: Slot 7 type set to 1 'I' [0x7f57bc00f350]
1041
2018-02-22_21:21:33.550816 <gsmtrx:ALL> ARFCN[0]: Slot 7 burst type changed None -> Normal [0x7f57bc00f350]
1042
2018-02-22_21:21:33.550842 <gsmtrx:ALL> Command 'CMD 0 SETSLOT 7 1' (ARFCN=0) RSP '7 1'
1043
2018-02-22_21:21:33.551429 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 7' arfcn=0 [0x7f57bc01f570]
1044
2018-02-22_21:21:33.551468 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 7' (ARFCN=0) RSP '7'
1045
2018-02-22_21:21:33.552118 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 7' arfcn=0 [0x7f57bc01f570]
1046
2018-02-22_21:21:33.552157 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 7' (ARFCN=0) RSP '7'
1047
2018-02-22_21:21:33.552847 <gsmtrx:ALL> Handling command 'CMD 0 SETPOWER 10' arfcn=0 [0x7f57bc01f570]
1048
2018-02-22_21:21:33.579911 <bladerf/1:INFO> TX VGA2 set to 15dB (0x78) [0x7f57bc000e40]
1049
2018-02-22_21:21:33.579999 <gsmtrx:INFO> Set Tx gain=-10 power_scaling=1 [0x7f57bc01f570]
1050
2018-02-22_21:21:33.580045 <gsmtrx:ALL> Command 'CMD 0 SETPOWER 10' (ARFCN=0) RSP '10'
1051
2018-02-22_21:21:33.656402 <bladerf/1:ALL> RX DC offset Q set to 1 (from 1) reg=0x81 [0x7f57bc000e40]
1052
2018-02-22_21:21:33.685002 <cpuload:NOTE> Updating CPU core number from 1 to 2
1053
2018-02-22_21:21:33.796016 <bladerf/1:ALL> RX DC offset Q set to 2 (from 2) reg=0x82 [0x7f57bc000e40]
1054
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
1055
2018-02-22_21:21:33.940412 <bladerf/1:ALL> RX DC offset Q set to 3 (from 3) reg=0x83 [0x7f57bc000e40]
1056
RTNETLINK answers: File exists
1057
2018-02-22_21:21:34.087281 <bladerf/1:ALL> RX DC offset Q set to 4 (from 4) reg=0x84 [0x7f57bc000e40]
1058
2018-02-22_21:21:34.242648 <bladerf/1:ALL> RX DC offset Q set to 5 (from 5) reg=0x85 [0x7f57bc000e40]
1059
MBTS ready
1060
2018-02-22_21:21:34.333949 <ybts-signalling:INFO> Received [0x564a86b94a90]
1061

    
1062
-----
1063

    
1064
Primitive: RadioReady
1065

    
1066
Info: 0
1067

    
1068
-----
1069
2018-02-22_21:21:34.333975 <ybts:NOTE> State changed Running -> RadioUp
1070
2018-02-22_21:21:34.399925 <bladerf/1:ALL> RX DC offset Q set to 6 (from 6) reg=0x86 [0x7f57bc000e40]
1071
2018-02-22_21:21:34.562670 <bladerf/1:ALL> RX DC offset Q set to 7 (from 7) reg=0x87 [0x7f57bc000e40]
1072
2018-02-22_21:21:34.731329 <bladerf/1:ALL> RX DC offset Q set to 8 (from 8) reg=0x88 [0x7f57bc000e40]
1073
2018-02-22_21:21:34.911827 <bladerf/1:ALL> RX DC offset Q set to 9 (from 9) reg=0x89 [0x7f57bc000e40]
1074
2018-02-22_21:21:35.097677 <bladerf/1:ALL> RX DC offset Q set to 10 (from 10) reg=0x8a [0x7f57bc000e40]
1075
2018-02-22_21:21:35.295069 <bladerf/1:ALL> RX DC offset Q set to 11 (from 11) reg=0x8b [0x7f57bc000e40]
1076
2018-02-22_21:21:35.495063 <bladerf/1:ALL> RX DC offset Q set to 12 (from 12) reg=0x8c [0x7f57bc000e40]
1077
2018-02-22_21:21:35.708059 <bladerf/1:ALL> RX DC offset Q set to 13 (from 13) reg=0x8d [0x7f57bc000e40]
1078
2018-02-22_21:21:35.930476 <bladerf/1:ALL> RX DC offset Q set to 14 (from 14) reg=0x8e [0x7f57bc000e40]
1079
2018-02-22_21:21:36.165989 <bladerf/1:ALL> RX DC offset Q set to 15 (from 15) reg=0x8f [0x7f57bc000e40]
1080
2018-02-22_21:21:36.412604 <bladerf/1:ALL> RX DC offset Q set to 16 (from 16) reg=0x90 [0x7f57bc000e40]
1081
2018-02-22_21:21:36.684390 <bladerf/1:ALL> RX DC offset Q set to 17 (from 17) reg=0x91 [0x7f57bc000e40]
1082
2018-02-22_21:21:36.969869 <bladerf/1:ALL> RX DC offset Q set to 18 (from 18) reg=0x92 [0x7f57bc000e40]
1083
2018-02-22_21:21:37.273284 <bladerf/1:ALL> RX DC offset Q set to 19 (from 19) reg=0x93 [0x7f57bc000e40]
1084
2018-02-22_21:21:37.562945 <gsmtrx:ALL> Handling command 'CMD 0 SETSLOT 1 10' arfcn=0 [0x7f57bc01f570]
1085
2018-02-22_21:21:37.562986 <gsmtrx:ALL> ARFCN[0]: Slot 1 type set to 10 'IGPRS' [0x7f57bc00f350]
1086
2018-02-22_21:21:37.563004 <gsmtrx:ALL> Command 'CMD 0 SETSLOT 1 10' (ARFCN=0) RSP '1 10'
1087
2018-02-22_21:21:37.564282 <gsmtrx:ALL> Handling command 'CMD 0 SETSLOT 2 10' arfcn=0 [0x7f57bc01f570]
1088
2018-02-22_21:21:37.564303 <gsmtrx:ALL> ARFCN[0]: Slot 2 type set to 10 'IGPRS' [0x7f57bc00f350]
1089
2018-02-22_21:21:37.564317 <gsmtrx:ALL> Command 'CMD 0 SETSLOT 2 10' (ARFCN=0) RSP '2 10'
1090
2018-02-22_21:21:37.565552 <gsmtrx:ALL> Handling command 'CMD 0 SETSLOT 3 10' arfcn=0 [0x7f57bc01f570]
1091
2018-02-22_21:21:37.565573 <gsmtrx:ALL> ARFCN[0]: Slot 3 type set to 10 'IGPRS' [0x7f57bc00f350]
1092
2018-02-22_21:21:37.565587 <gsmtrx:ALL> Command 'CMD 0 SETSLOT 3 10' (ARFCN=0) RSP '3 10'
1093
2018-02-22_21:21:37.596906 <bladerf/1:ALL> RX DC offset Q set to 20 (from 20) reg=0x94 [0x7f57bc000e40]
1094
2018-02-22_21:21:37.947912 <bladerf/1:ALL> RX DC offset Q set to 21 (from 21) reg=0x95 [0x7f57bc000e40]
1095
2018-02-22_21:21:38.323681 <bladerf/1:ALL> RX DC offset Q set to 22 (from 22) reg=0x96 [0x7f57bc000e40]
1096
2018-02-22_21:21:38.731173 <bladerf/1:ALL> RX DC offset Q set to 23 (from 23) reg=0x97 [0x7f57bc000e40]
1097
2018-02-22_21:21:39.176818 <bladerf/1:ALL> RX DC offset Q set to 24 (from 24) reg=0x98 [0x7f57bc000e40]
1098
2018-02-22_21:21:39.580869 <gsmtrx:ALL> Handling command 'CMD 0 SETPOWER 9' arfcn=0 [0x7f57bc01f570]
1099
2018-02-22_21:21:39.586208 <bladerf/1:INFO> TX VGA2 set to 16dB (0x80) [0x7f57bc000e40]
1100
2018-02-22_21:21:39.586248 <gsmtrx:INFO> Set Tx gain=-9 power_scaling=1 [0x7f57bc01f570]
1101
2018-02-22_21:21:39.586291 <gsmtrx:ALL> Command 'CMD 0 SETPOWER 9' (ARFCN=0) RSP '9'
1102
2018-02-22_21:21:39.700830 <bladerf/1:ALL> RX DC offset Q set to 25 (from 25) reg=0x99 [0x7f57bc000e40]
1103
2018-02-22_21:21:40.265616 <bladerf/1:ALL> RX DC offset Q set to 26 (from 26) reg=0x9a [0x7f57bc000e40]
1104
2018-02-22_21:21:40.913261 <bladerf/1:ALL> RX DC offset Q set to 27 (from 27) reg=0x9b [0x7f57bc000e40]
1105
2018-02-22_21:21:41.584524 <gsmtrx:ALL> ARFCN[0]: Slot 6. Excessive TOA error=4 peak/mean=3.13645 count=1 [0x7f57bc00f350]
1106
2018-02-22_21:21:41.644672 <bladerf/1:ALL> RX DC offset Q set to 28 (from 28) reg=0x9c [0x7f57bc000e40]
1107
2018-02-22_21:21:42.522810 <bladerf/1:ALL> RX DC offset Q set to 29 (from 29) reg=0x9d [0x7f57bc000e40]
1108
2018-02-22_21:21:43.617075 <bladerf/1:ALL> RX DC offset Q set to 30 (from 30) reg=0x9e [0x7f57bc000e40]
1109
2018-02-22_21:21:45.102001 <bladerf/1:ALL> RX DC offset Q set to 31 (from 31) reg=0x9f [0x7f57bc000e40]
1110
2018-02-22_21:21:45.586914 <gsmtrx:ALL> Handling command 'CMD 0 SETPOWER 8' arfcn=0 [0x7f57bc01f570]
1111
2018-02-22_21:21:45.590439 <bladerf/1:INFO> TX VGA2 set to 17dB (0x88) [0x7f57bc000e40]
1112
2018-02-22_21:21:45.590478 <gsmtrx:INFO> Set Tx gain=-8 power_scaling=1 [0x7f57bc01f570]
1113
2018-02-22_21:21:45.590509 <gsmtrx:ALL> Command 'CMD 0 SETPOWER 8' (ARFCN=0) RSP '8'
1114
2018-02-22_21:21:47.253869 <gsmtrx:ALL> ARFCN[0]: Slot 2. Excessive TOA error=-4 peak/mean=3.76476 count=1 [0x7f57bc00f350]
1115
2018-02-22_21:21:47.700246 <bladerf/1:ALL> RX DC offset Q set to 32 (from 32) reg=0xa0 [0x7f57bc000e40]
1116
2018-02-22_21:21:51.591062 <gsmtrx:ALL> Handling command 'CMD 0 SETPOWER 7' arfcn=0 [0x7f57bc01f570]
1117
2018-02-22_21:21:51.852536 <bladerf/1:INFO> TX VGA2 set to 18dB (0x90) [0x7f57bc000e40]
1118
2018-02-22_21:21:51.852589 <gsmtrx:INFO> Set Tx gain=-7 power_scaling=1 [0x7f57bc01f570]
1119
2018-02-22_21:21:51.852621 <gsmtrx:ALL> Command 'CMD 0 SETPOWER 7' (ARFCN=0) RSP '7'
1120
2018-02-22_21:21:53.224611 <gsmtrx:ALL> ARFCN[0]: Slot 6. Excessive TOA error=-4 peak/mean=3.21378 count=1 [0x7f57bc00f350]
1121
2018-02-22_21:21:54.964631 <gsmtrx:ALL> ARFCN[0]: Slot 6. Excessive TOA error=-4 peak/mean=3.1045 count=1 [0x7f57bc00f350]
1122
2018-02-22_21:21:57.356329 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f57bc01f570]
1123
2018-02-22_21:21:57.356383 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0) RSP '0'
1124
2018-02-22_21:21:57.356874 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f57bc01f570]
1125
2018-02-22_21:21:57.356909 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0) RSP '0'
1126
2018-02-22_21:21:57.689605 <ybts-signalling:INFO> Received [0x564a86b94a90]
1127

    
1128
-----
1129

    
1130
Primitive: PhysicalInfo
1131

    
1132
Info: 0
1133

    
1134
Connection: 1
1135

    
1136

    
1137

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

    
1140
-----
1141
2018-02-22_21:21:57.690401 <ybts-signalling:INFO> Received [0x564a86b94a90]
1142

    
1143
-----
1144

    
1145
Primitive: L3Message
1146

    
1147
Info: 0
1148

    
1149
Connection: 1
1150

    
1151

    
1152

    
1153
<MM>
1154

    
1155
  <SkipIndicator>0</SkipIndicator>
1156

    
1157
  <NSD>0</NSD>
1158

    
1159
  <Message type="LocationUpdatingRequest">
1160

    
1161
    <LocationUpdatingType>
1162

    
1163
      <FOR>false</FOR>
1164

    
1165
      <LUT>IMSI-attach</LUT>
1166

    
1167
    </LocationUpdatingType>
1168

    
1169
    <CKSN>no-key/reserved</CKSN>
1170

    
1171
    <LAI>
1172

    
1173
      <PLMNidentity>00101</PLMNidentity>
1174

    
1175
      <LAC>0001</LAC>
1176

    
1177
    </LAI>
1178

    
1179
    <MSClassmark1>
1180

    
1181
      <RFPowerCapability>class1</RFPowerCapability>
1182

    
1183
      <RevisionLevel>GSM-phase2</RevisionLevel>
1184

    
1185
      <Flags>ES-IND</Flags>
1186

    
1187
    </MSClassmark1>
1188

    
1189
    <MobileIdentity>
1190

    
1191
      <TMSI>007b0002</TMSI>
1192

    
1193
    </MobileIdentity>
1194

    
1195
  </Message>
1196

    
1197
</MM>
1198

    
1199
-----
1200
2018-02-22_21:21:57.690465 <ybts-signalling:ALL> Added connection (0x7f57c0005550,1) [0x564a86b94a90]
1201
2018-02-22_21:21:57.690509 <ybts-mm:ALL> Handling LocationUpdatingRequest conn=1: ident=TMSI/007b0002 LAI=00101_0001 [0x564a86b94e70]
1202
2018-02-22_21:21:57.690549 <ybts-mm:ALL> Added UE (0x7f57c0005690) TMSI=007b0002 IMSI= [0x564a86b94e70]
1203
2018-02-22_21:21:57.691313 <ybts-signalling:ALL> Connection 1 set UE (0x7f57c0005690) TMSI=007b0002 IMSI= [0x7f57c0005550]
1204
2018-02-22_21:21:57.691353 <ybts-signalling:INFO> Sending [0x564a86b94a90]
1205

    
1206
-----
1207

    
1208
Primitive: L3Message
1209

    
1210
Info: 0
1211

    
1212
Connection: 1
1213

    
1214

    
1215

    
1216
<MM>
1217

    
1218
  <Message type="IdentityRequest">
1219

    
1220
    <IdentityType>IMEI</IdentityType>
1221

    
1222
  </Message>
1223

    
1224
</MM>
1225

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

    
1234
-----
1235

    
1236
Primitive: PhysicalInfo
1237

    
1238
Info: 0
1239

    
1240
Connection: 1
1241

    
1242

    
1243

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

    
1246
-----
1247
2018-02-22_21:21:58.630359 <ybts-signalling:INFO> Received [0x564a86b94a90]
1248

    
1249
-----
1250

    
1251
Primitive: L3Message
1252

    
1253
Info: 0
1254

    
1255
Connection: 1
1256

    
1257

    
1258

    
1259
<MM>
1260

    
1261
  <SkipIndicator>0</SkipIndicator>
1262

    
1263
  <NSD>1</NSD>
1264

    
1265
  <Message type="IdentityResponse">
1266

    
1267
    <MobileIdentity>
1268

    
1269
      <IMEI>359810014587110</IMEI>
1270

    
1271
    </MobileIdentity>
1272

    
1273
  </Message>
1274

    
1275
</MM>
1276

    
1277
-----
1278
2018-02-22_21:21:58.631154 <ybts:ALL> Started location updating thread for (0x7f57c0005690) TMSI=007b0002 IMSI= [0x7f57c0005ab0]
1279
2018-02-22_21:21:58.631900 <nipc:INFO> Got user.register for imsi='001010000000001', tmsi='007b0002'
1280
2018-02-22_21:21:58.633136 <nipc:INFO> Registered imsi 001010000000001 with number 10000001
1281
2018-02-22_21:21:58.633206 <ybts:ALL> Location updating thread for (0x7f57c0005690) TMSI=007b0002 IMSI= terminated [0x7f57c0005ab0]
1282
2018-02-22_21:21:58.633359 <ybts-mm:ALL> UE (0x7f57c0005690) registered TMSI '007b0002' -> '007b0002', IMSI '' -> '001010000000001' conn=1 [0x564a86b94e70]
1283
2018-02-22_21:21:58.633376 <ybts-mm:ALL> UE (0x7f57c0005690) TMSI=007b0002 IMSI=001010000000001 register succeeded [0x564a86b94e70]
1284
2018-02-22_21:21:58.633437 <ybts-signalling:INFO> Sending [0x564a86b94a90]
1285

    
1286
-----
1287

    
1288
Primitive: L3Message
1289

    
1290
Info: 0
1291

    
1292
Connection: 1
1293

    
1294

    
1295

    
1296
<MM>
1297

    
1298
  <Message type="LocationUpdatingAccept">
1299

    
1300
    <LAI>
1301

    
1302
      <PLMNidentity>00101</PLMNidentity>
1303

    
1304
      <LAC>0001</LAC>
1305

    
1306
    </LAI>
1307

    
1308
    <MobileIdentity>
1309

    
1310
      <TMSI>007b0002</TMSI>
1311

    
1312
    </MobileIdentity>
1313

    
1314
  </Message>
1315

    
1316
</MM>
1317

    
1318
-----
1319
2018-02-22_21:21:59.725942 <ybts-signalling:INFO> Received [0x564a86b94a90]
1320

    
1321
-----
1322

    
1323
Primitive: PhysicalInfo
1324

    
1325
Info: 0
1326

    
1327
Connection: 1
1328

    
1329

    
1330

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

    
1333
-----
1334
2018-02-22_21:21:59.726054 <ybts-signalling:INFO> Received [0x564a86b94a90]
1335

    
1336
-----
1337

    
1338
Primitive: L3Message
1339

    
1340
Info: 0
1341

    
1342
Connection: 1
1343

    
1344

    
1345

    
1346
<MM>
1347

    
1348
  <SkipIndicator>0</SkipIndicator>
1349

    
1350
  <NSD>0</NSD>
1351

    
1352
  <Message type="TMSIReallocationComplete"/>
1353

    
1354
</MM>
1355

    
1356
-----
1357
2018-02-22_21:21:59.726095 <ybts-signalling:ALL> Releasing connection (0x7f57c0005550,1) [0x564a86b94a90]
1358
2018-02-22_21:21:59.726134 <ybts-signalling:INFO> Sending [0x564a86b94a90]
1359

    
1360
-----
1361

    
1362
Primitive: ConnRelease
1363

    
1364
Info: 0
1365

    
1366
Connection: 1
1367

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

    
1373
-----
1374

    
1375
Primitive: GprsAttachReq
1376

    
1377
Info: 0
1378

    
1379
Connection: 8193
1380

    
1381

    
1382

    
1383
<GprsAttach>
1384

    
1385
  <tlli>80006001</tlli>
1386

    
1387
  <ptmsi>00006001</ptmsi>
1388

    
1389
  <authenticated>false</authenticated>
1390

    
1391
</GprsAttach>
1392

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

    
1401
-----
1402

    
1403
Primitive: GprsAttachLBO
1404

    
1405
Info: 0
1406

    
1407
Connection: 8193
1408

    
1409
-----
1410
2018-02-22_21:22:01.866687 <ybts/ps/1:ALL> Destroyed ConnID=8193 [0x7f57c0005d50]
1411
2018-02-22_21:22:01.867081 <javascript:NOTE> Channel 'ybts/ps/1' already assisted!
1412
2018-02-22_21:22:01.867255 <javascript:ALL> Assistant for 'ybts/ps/1' deleted
1413
2018-02-22_21:22:03.857541 <gsmtrx:ALL> Handling command 'CMD 0 SETPOWER 5' arfcn=0 [0x7f57bc01f570]
1414
2018-02-22_21:22:03.861414 <bladerf/1:INFO> TX VGA2 set to 20dB (0xa0) [0x7f57bc000e40]
1415
2018-02-22_21:22:03.861452 <gsmtrx:INFO> Set Tx gain=-5 power_scaling=1 [0x7f57bc01f570]
1416
2018-02-22_21:22:03.861496 <gsmtrx:ALL> Command 'CMD 0 SETPOWER 5' (ARFCN=0) RSP '5'
1417
2018-02-22_21:22:03.874725 <gsmtrx:ALL> ARFCN[0]: Slot 2. Excessive TOA error=4 peak/mean=4.55786 count=1 [0x7f57bc00f350]
1418
2018-02-22_21:22:09.862129 <gsmtrx:ALL> Handling command 'CMD 0 SETPOWER 4' arfcn=0 [0x7f57bc01f570]
1419
2018-02-22_21:22:09.867180 <bladerf/1:INFO> TX VGA2 set to 21dB (0xa8) [0x7f57bc000e40]
1420
2018-02-22_21:22:09.867219 <gsmtrx:INFO> Set Tx gain=-4 power_scaling=1 [0x7f57bc01f570]
1421
2018-02-22_21:22:09.867251 <gsmtrx:ALL> Command 'CMD 0 SETPOWER 4' (ARFCN=0) RSP '4'
1422
2018-02-22_21:22:15.867902 <gsmtrx:ALL> Handling command 'CMD 0 SETPOWER 3' arfcn=0 [0x7f57bc01f570]
1423
2018-02-22_21:22:16.218670 <bladerf/1:INFO> TX VGA2 set to 22dB (0xb0) [0x7f57bc000e40]
1424
2018-02-22_21:22:16.218725 <gsmtrx:INFO> Set Tx gain=-3 power_scaling=1 [0x7f57bc01f570]
1425
2018-02-22_21:22:16.218757 <gsmtrx:ALL> Command 'CMD 0 SETPOWER 3' (ARFCN=0) RSP '3'
1426
2018-02-22_21:22:16.413650 <gsmtrx:INFO> ARFCN[0]: Slot 0. Receiver clipping 0.428256 dB (FN=9299) count=1 [0x7f57bc00f350]
1427
2018-02-22_21:22:16.922872 <ybts-signalling:INFO> Received [0x564a86b94a90]
1428

    
1429
-----
1430

    
1431
Primitive: GprsAttachReq
1432

    
1433
Info: 0
1434

    
1435
Connection: 8193
1436

    
1437

    
1438

    
1439
<GprsAttach>
1440

    
1441
  <tlli>80006001</tlli>
1442

    
1443
  <ptmsi>00006001</ptmsi>
1444

    
1445
  <authenticated>false</authenticated>
1446

    
1447
</GprsAttach>
1448

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

    
1457
-----
1458

    
1459
Primitive: GprsAttachLBO
1460

    
1461
Info: 0
1462

    
1463
Connection: 8193
1464

    
1465
-----
1466
2018-02-22_21:22:16.925652 <ybts/ps/2:ALL> Destroyed ConnID=8193 [0x7f57c0005d50]
1467
2018-02-22_21:22:16.930148 <javascript:ALL> Assistant for 'ybts/ps/2' deleted
1468
2018-02-22_21:22:17.224139 <gsmtrx:ALL> ARFCN[0]: Slot 6. Excessive TOA error=-3 peak/mean=3.14377 count=1 [0x7f57bc00f350]
1469
2018-02-22_21:22:17.493957 <gsmtrx:ALL> ARFCN[0]: Slot 2. Excessive TOA error=-5 peak/mean=3.30102 count=1 [0x7f57bc00f350]
1470
2018-02-22_21:22:20.974761 <gsmtrx:ALL> ARFCN[0]: Slot 2. Excessive TOA error=-3 peak/mean=4.05798 count=1 [0x7f57bc00f350]
1471
2018-02-22_21:22:21.604809 <mbts:MILD> SigConnection.cpp:603:process: primitive 2 length 0 took 21879 ms
1472
2018-02-22_21:22:21.604863 <mbts:MILD> SigConnection.cpp:417:process: received GPRS Local Breakout for unmapped id 8193
1473
2018-02-22_21:22:22.219814 <gsmtrx:ALL> Handling command 'CMD 0 SETPOWER 2' arfcn=0 [0x7f57bc01f570]
1474
2018-02-22_21:22:22.225776 <bladerf/1:INFO> TX VGA2 set to 23dB (0xb8) [0x7f57bc000e40]
1475
2018-02-22_21:22:22.225830 <gsmtrx:INFO> Set Tx gain=-2 power_scaling=1 [0x7f57bc01f570]
1476
2018-02-22_21:22:22.225861 <gsmtrx:ALL> Command 'CMD 0 SETPOWER 2' (ARFCN=0) RSP '2'
1477
2018-02-22_21:22:22.773378 <gsmtrx:INFO> ARFCN[0]: Slot 0. Receiver clipping 0.240848 dB (FN=10677) count=1 [0x7f57bc00f350]
1478
2018-02-22_21:22:23.944859 <gsmtrx:ALL> ARFCN[0]: Slot 6. Excessive TOA errors 1 [0x7f57bc00f350]
1479
2018-02-22_21:22:24.452932 <gsmtrx:INFO> ARFCN[0]: Slot 0. Receiver clipping 0.137578 dB (FN=11041) count=1 [0x7f57bc00f350]
1480
2018-02-22_21:22:24.784206 <gsmtrx:ALL> ARFCN[0]: Slot 6. Excessive TOA error=-4 peak/mean=3.10825 count=1 [0x7f57bc00f350]
1481
2018-02-22_21:22:25.594551 <gsmtrx:ALL> ARFCN[0]: Slot 2. Excessive TOA error=-3 peak/mean=4.16589 count=1 [0x7f57bc00f350]
1482
2018-02-22_21:22:27.214456 <gsmtrx:ALL> ARFCN[0]: Slot 2. Excessive TOA error=-5 peak/mean=3.22103 count=1 [0x7f57bc00f350]
1483
2018-02-22_21:22:28.024036 <gsmtrx:ALL> ARFCN[0]: Slot 6. Excessive TOA errors 1 [0x7f57bc00f350]
1484
2018-02-22_21:22:28.226651 <gsmtrx:ALL> Handling command 'CMD 0 SETPOWER 1' arfcn=0 [0x7f57bc01f570]
1485
2018-02-22_21:22:28.235408 <bladerf/1:INFO> TX VGA2 set to 24dB (0xc0) [0x7f57bc000e40]
1486
2018-02-22_21:22:28.235450 <gsmtrx:INFO> Set Tx gain=-1 power_scaling=1 [0x7f57bc01f570]
1487
2018-02-22_21:22:28.235480 <gsmtrx:ALL> Command 'CMD 0 SETPOWER 1' (ARFCN=0) RSP '1'
1488
2018-02-22_21:22:28.930244 <gsmtrx:INFO> ARFCN[0]: Slot 0. Receiver clipping 0.467326 dB (FN=12011) count=1 [0x7f57bc00f350]
1489
2018-02-22_21:22:30.000445 <ybts-signalling:ALL> Removing released connection 1 [0x564a86b94a90]
1490
2018-02-22_21:22:30.000496 <ybts-mm:ALL> Removed UE (0x7f57c0005690): destroyed [0x564a86b94e70]
1491
2018-02-22_21:22:30.000519 <ybts:ALL> UE destroyed [0x7f57c0005690]
1492
2018-02-22_21:22:31.623963 <gsmtrx:ALL> ARFCN[0]: Slot 6. Excessive TOA error=-5 peak/mean=4.98407 count=1 [0x7f57bc00f350]
1493
2018-02-22_21:22:34.236107 <gsmtrx:ALL> Handling command 'CMD 0 SETPOWER 0' arfcn=0 [0x7f57bc01f570]
1494
2018-02-22_21:22:34.237856 <bladerf/1:INFO> TX VGA2 set to 25dB (0xc8) [0x7f57bc000e40]
1495
2018-02-22_21:22:34.237879 <gsmtrx:INFO> Set Tx gain=0 power_scaling=1 [0x7f57bc01f570]
1496
2018-02-22_21:22:34.237898 <gsmtrx:ALL> Command 'CMD 0 SETPOWER 0' (ARFCN=0) RSP '0'
1497
2018-02-22_21:22:34.774336 <gsmtrx:ALL> ARFCN[0]: Slot 2. Excessive TOA error=-4 peak/mean=4.03747 count=1 [0x7f57bc00f350]
1498
2018-02-22_21:22:36.634434 <gsmtrx:ALL> ARFCN[0]: Slot 2. Excessive TOA error=-5 peak/mean=3.64994 count=1 [0x7f57bc00f350]
1499
2018-02-22_21:22:37.204819 <gsmtrx:ALL> ARFCN[0]: Slot 6. Excessive TOA error=-5 peak/mean=3.57665 count=1 [0x7f57bc00f350]
1500
2018-02-22_21:22:53.305539 <gsmtrx:ALL> ARFCN[0]: Slot 3. Excessive TOA error=-3 peak/mean=3.73011 count=1 [0x7f57bc00f350]
1501
2018-02-22_21:23:18.602958 <gsmtrx:ALL> ARFCN[0]: Slot 3. Excessive TOA error=-4 peak/mean=3.72903 count=1 [0x7f57bc00f350]
1502
2018-02-22_21:23:23.294332 <gsmtrx:INFO> ARFCN[0]: Slot 0. Receiver clipping 0.418075 dB (FN=23790) count=1 [0x7f57bc00f350]
1503
2018-02-22_21:23:23.294957 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 4' arfcn=0 [0x7f57bc01f570]
1504
2018-02-22_21:23:23.294983 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 4' (ARFCN=0) RSP '4'
1505
2018-02-22_21:23:23.295267 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 4' arfcn=0 [0x7f57bc01f570]
1506
2018-02-22_21:23:23.295286 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 4' (ARFCN=0) RSP '4'
1507
2018-02-22_21:23:23.495328 <gsmtrx:INFO> ARFCN[0]: Slot 4. Receiver clipping -1.86277 dB (FN=23833) count=1 [0x7f57bc00f350]
1508
2018-02-22_21:23:23.528629 <ybts-signalling:INFO> Received [0x564a86b94a90]
1509

    
1510
-----
1511

    
1512
Primitive: PhysicalInfo
1513

    
1514
Info: 0
1515

    
1516
Connection: 2
1517

    
1518

    
1519

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

    
1522
-----
1523
2018-02-22_21:23:23.529162 <ybts-signalling:INFO> Received [0x564a86b94a90]
1524

    
1525
-----
1526

    
1527
Primitive: L3Message
1528

    
1529
Info: 0
1530

    
1531
Connection: 2
1532

    
1533

    
1534

    
1535
<MM>
1536

    
1537
  <SkipIndicator>0</SkipIndicator>
1538

    
1539
  <NSD>0</NSD>
1540

    
1541
  <Message type="CMServiceRequest">
1542

    
1543
    <CMServiceType>MO-call-establishment-or-PM-connection-establishment</CMServiceType>
1544

    
1545
    <CKSN>no-key/reserved</CKSN>
1546

    
1547
    <MSClassmark2>
1548

    
1549
      <RFPowerCapability>class1</RFPowerCapability>
1550

    
1551
      <RevisionLevel>GSM-phase2</RevisionLevel>
1552

    
1553
      <SSScreeningIndicator>ellipsis-notation-and-phase2-error-handling</SSScreeningIndicator>
1554

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

    
1557
    </MSClassmark2>
1558

    
1559
    <MobileIdentity>
1560

    
1561
      <TMSI>007b0002</TMSI>
1562

    
1563
    </MobileIdentity>
1564

    
1565
  </Message>
1566

    
1567
</MM>
1568

    
1569
-----
1570
2018-02-22_21:23:23.529238 <ybts-signalling:ALL> Added connection (0x7f57c0006f60,2) [0x564a86b94a90]
1571
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]
1572
2018-02-22_21:23:23.529297 <ybts-mm:ALL> Added UE (0x7f57c00089e0) TMSI=007b0002 IMSI= [0x564a86b94e70]
1573
2018-02-22_21:23:23.529320 <ybts-signalling:ALL> Connection 2 set UE (0x7f57c00089e0) TMSI=007b0002 IMSI= [0x7f57c0006f60]
1574
2018-02-22_21:23:23.529368 <ybts-signalling:INFO> Sending [0x564a86b94a90]
1575

    
1576
-----
1577

    
1578
Primitive: L3Message
1579

    
1580
Info: 0
1581

    
1582
Connection: 2
1583

    
1584

    
1585

    
1586
<MM>
1587

    
1588
  <Message type="CMServiceAccept"/>
1589

    
1590
</MM>
1591

    
1592
-----
1593
2018-02-22_21:23:23.768473 <ybts-signalling:INFO> Received [0x564a86b94a90]
1594

    
1595
-----
1596

    
1597
Primitive: PhysicalInfo
1598

    
1599
Info: 0
1600

    
1601
Connection: 2
1602

    
1603

    
1604

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

    
1607
-----
1608
2018-02-22_21:23:23.769133 <ybts-signalling:INFO> Received [0x564a86b94a90]
1609

    
1610
-----
1611

    
1612
Primitive: L3Message
1613

    
1614
Info: 0
1615

    
1616
Connection: 2
1617

    
1618

    
1619

    
1620
<CC>
1621

    
1622
  <TID TIFlag="false">0</TID>
1623

    
1624
  <NSD>1</NSD>
1625

    
1626
  <Message type="Setup">
1627

    
1628
    <BearerCapability1>
1629

    
1630
      <ITC>speech</ITC>
1631

    
1632
      <TransferMode>circuit-mode</TransferMode>
1633

    
1634
      <CodingStandard>GSM</CodingStandard>
1635

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

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

    
1640
    </BearerCapability1>
1641

    
1642
    <CalledPartyBCDNumber nature="unknown" plan="isdn">32843</CalledPartyBCDNumber>
1643

    
1644
    <CCCapabilities>
1645

    
1646
      <Flags>DTMF</Flags>
1647

    
1648
      <MaxSupportedBearers>0</MaxSupportedBearers>
1649

    
1650
      <MaxSpeechBearers>1</MaxSpeechBearers>
1651

    
1652
    </CCCapabilities>
1653

    
1654
  </Message>
1655

    
1656
</CC>
1657

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

    
1662
-----
1663

    
1664
Primitive: AllocMedia
1665

    
1666
Info: 0
1667

    
1668
Connection: 2
1669

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

    
1674
-----
1675

    
1676
Primitive: L3Message
1677

    
1678
Info: 0
1679

    
1680
Connection: 2
1681

    
1682

    
1683

    
1684
<CC>
1685

    
1686
  <TID TIFlag="true">0</TID>
1687

    
1688
  <Message type="CallProceeding"/>
1689

    
1690
</CC>
1691

    
1692
-----
1693
2018-02-22_21:23:23.769318 <ybts/3:INFO> Added call 'i0' [0x7f57c000b510]
1694
2018-02-22_21:23:23.769334 <ybts-signalling:INFO> Sending [0x564a86b94a90]
1695

    
1696
-----
1697

    
1698
Primitive: StartMedia
1699

    
1700
Info: 1
1701

    
1702
Connection: 2
1703

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

    
1708
-----
1709

    
1710
Primitive: PhysicalInfo
1711

    
1712
Info: 0
1713

    
1714
Connection: 2
1715

    
1716

    
1717

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

    
1720
-----
1721
2018-02-22_21:23:24.067989 <ybts-signalling:INFO> Received [0x564a86b94a90]
1722

    
1723
-----
1724

    
1725
Primitive: MediaStarted
1726

    
1727
Info: 0
1728

    
1729
Connection: 2
1730

    
1731
-----
1732
2018-02-22_21:23:24.068007 <ybts-signalling:ALL> Connection 2 traffic channel set succeeded mode=1 [0x564a86b94a90]
1733
2018-02-22_21:23:24.068030 <ybts/3:ALL> Got media started notification [0x7f57c000b510]
1734
2018-02-22_21:23:24.068695 <INFO> Could not classify call from 'TMSI007b0002', wasted 9 usec
1735
2018-02-22_21:23:24.069116 <welcome:INFO> Got call to welcome IVR.
1736
2018-02-22_21:23:24.070317 <wave:INFO> Play from wave file '/usr/share/yate/sounds/welcome.au'
1737
2018-02-22_21:23:24.070612 <wave/1:ALL> WaveChan::WaveChan(play) [0x7f57cc00d3f0]
1738
2018-02-22_21:23:24.070899 <wave:ALL> WaveSource::WaveSource("/usr/share/yate/sounds/welcome.au",0x7f57cc00d3f0) [0x7f57cc006440]
1739
2018-02-22_21:23:24.071533 <MILD> Invalid .au file header, assuming raw signed linear
1740
2018-02-22_21:23:24.072115 <ybts/3:NOTE> Answering now call ybts/3 because we have no targetid [0x7f57c000b510]
1741
2018-02-22_21:23:24.072720 <ybts/3:INFO> Call 'i0' changed state CallProceeding -> ConnectReq [0x7f57c000b510]
1742
2018-02-22_21:23:24.073203 <ybts-signalling:INFO> Sending [0x564a86b94a90]
1743

    
1744
-----
1745

    
1746
Primitive: L3Message
1747

    
1748
Info: 0
1749

    
1750
Connection: 2
1751

    
1752

    
1753

    
1754
<CC>
1755

    
1756
  <TID TIFlag="true">0</TID>
1757

    
1758
  <Message type="Connect"/>
1759

    
1760
</CC>
1761

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

    
1769
-----
1770

    
1771
Primitive: L3Message
1772

    
1773
Info: 0
1774

    
1775
Connection: 2
1776

    
1777

    
1778

    
1779
<CC>
1780

    
1781
  <TID TIFlag="false">0</TID>
1782

    
1783
  <NSD>0</NSD>
1784

    
1785
  <Message type="ConnectAcknowledge"/>
1786

    
1787
</CC>
1788

    
1789
-----
1790
2018-02-22_21:23:24.229808 <ybts/3:INFO> Call 'i0' changed state ConnectReq -> Active [0x7f57c000b510]
1791
2018-02-22_21:23:34.034755 <gsmtrx:ALL> ARFCN[0]: Slot 7. Excessive TOA error=4 peak/mean=3.2997 count=1 [0x7f57bc00f350]
1792
2018-02-22_21:23:35.623990 <gsmtrx:ALL> ARFCN[0]: Slot 3. Excessive TOA error=3 peak/mean=3.32901 count=1 [0x7f57bc00f350]
1793
2018-02-22_21:23:36.998026 <wave:ALL> WaveSource '(null)' end of data (206918 played) chan=0x7f57cc00d3f0 [0x7f57cc006440]
1794
2018-02-22_21:23:36.998253 <wave:ALL> WaveSource cleanup, total=206918, chan=(nil) [0x7f57cc006440]
1795
2018-02-22_21:23:36.998640 >>> DataTranslator::detachChain(0x7f57cc006440,0x7f57cc001cb0)
1796
2018-02-22_21:23:36.999243   >>> DataTranslator::detachChain(0x7f57cc006440,0x7f57cc00e930)
1797
2018-02-22_21:23:36.999699   <<< DataTranslator::detachChain
1798
2018-02-22_21:23:37.000332   <ALL> GsmCodec::~GsmCodec() [0x7f57cc00e930]
1799
2018-02-22_21:23:37.000772 <<< DataTranslator::detachChain
1800
2018-02-22_21:23:37.001302 <ybts/3:ALL> Disconnected '(null)' [0x7f57c000b510]
1801
2018-02-22_21:23:37.002142 <wave:INFO> Record to wave file '-'
1802
2018-02-22_21:23:37.002169 <wave/2:ALL> WaveChan::WaveChan(record) [0x7f57b401d120]
1803
2018-02-22_21:23:37.002185 <wave:ALL> WaveConsumer::WaveConsumer("-",0x7f57b401d120,180000,"(null)",false,0x7f57b401b790) [0x7f57b4000eb0]
1804
2018-02-22_21:23:37.002215 <ALL> GsmCodec::GsmCodec("gsm","slin",decoding) [0x7f57b4001000]
1805
2018-02-22_21:23:37.002229 <ALL> Created DataTranslator 0x7f57b4001000 for 'gsm' -> 'slin' by factory 0x7f57ef5a81a0 (len=1)
1806
2018-02-22_21:23:37.002244 <ALL> DataTranslator::attachChain [0x7f57cc00e850] 'gsm' -> [0x7f57b4000eb0] 'slin' succeeded
1807
2018-02-22_21:23:37.002762 <wave:ALL> WaveSource::~WaveSource() [0x7f57cc006440] total=206918 stamp=103360
1808
2018-02-22_21:23:37.003060 <wave:INFO> WaveSource rate=16009 b/s
1809
2018-02-22_21:23:37.003347 <wave/1:ALL> WaveChan::~WaveChan() wave/1 [0x7f57cc00d3f0]
1810
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]
1811
2018-02-22_21:23:39.601650 <bladerf/1:WARN> RX: timestamp jumped by 4803 to 273228650 in buffer 6/8 [0x7f57bc000e40]
1812
2018-02-22_21:23:39.601681 <bladerf/1:NOTE> RX: timestamp adjusted by 4803 to 273233705 [0x7f57bc000e40]
1813
2018-02-22_21:23:39.603981 <gsmtrx:MILD> Transmit underrun by 2 timeslots [0x7f57bc01f570]
1814
2018-02-22_21:23:39.604014 <gsmtrx:NOTE> ARFCN[0]: 1 burst(s) expired at 218598 27324/6 [0x7f57bc00f350]
1815
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]
1816
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]
1817
2018-02-22_21:23:39.814763 <bladerf/1:WARN> RX: timestamp jumped by 22413 to 273672941 in buffer 6/8 [0x7f57bc000e40]
1818
2018-02-22_21:23:39.814778 <bladerf/1:NOTE> RX: timestamp adjusted by 22413 to 273695606 [0x7f57bc000e40]
1819
2018-02-22_21:23:39.815807 <gsmtrx:MILD> Transmit underrun by 7 timeslots [0x7f57bc01f570]
1820
2018-02-22_21:23:39.815855 <gsmtrx:NOTE> ARFCN[0]: 1 burst(s) expired at 218965 27370/5 [0x7f57bc00f350]
1821
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]
1822
2018-02-22_21:23:39.913962 <gsmtrx:ALL> ARFCN[0]: Slot 7. Excessive TOA error=5 peak/mean=3.174 count=1 [0x7f57bc00f350]
1823
2018-02-22_21:23:40.244113 <gsmtrx:ALL> ARFCN[0]: Slot 3. Excessive TOA error=-4 peak/mean=3.2315 count=2 [0x7f57bc00f350]
1824
2018-02-22_21:23:40.490114 <ybts-signalling:INFO> Received [0x564a86b94a90]
1825

    
1826
-----
1827

    
1828
Primitive: PhysicalInfo
1829

    
1830
Info: 0
1831

    
1832
Connection: 2
1833

    
1834

    
1835

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

    
1838
-----
1839
2018-02-22_21:23:40.490198 <ybts-signalling:INFO> Received [0x564a86b94a90]
1840

    
1841
-----
1842

    
1843
Primitive: L3Message
1844

    
1845
Info: 0
1846

    
1847
Connection: 2
1848

    
1849

    
1850

    
1851
<CC>
1852

    
1853
  <TID TIFlag="false">0</TID>
1854

    
1855
  <NSD>1</NSD>
1856

    
1857
  <Message type="StartDTMF">
1858

    
1859
    <KeypadFacility>1</KeypadFacility>
1860

    
1861
  </Message>
1862

    
1863
</CC>
1864

    
1865
-----
1866
2018-02-22_21:23:40.490261 <ybts-signalling:INFO> Sending [0x564a86b94a90]
1867

    
1868
-----
1869

    
1870
Primitive: L3Message
1871

    
1872
Info: 0
1873

    
1874
Connection: 2
1875

    
1876

    
1877

    
1878
<CC>
1879

    
1880
  <TID TIFlag="true">0</TID>
1881

    
1882
  <Message type="StartDTMFAck">
1883

    
1884
    <KeypadFacility>1</KeypadFacility>
1885

    
1886
  </Message>
1887

    
1888
</CC>
1889

    
1890
-----
1891
2018-02-22_21:23:40.492267 <wave:INFO> Play from wave file '/usr/share/yate/sounds/echo.au'
1892
2018-02-22_21:23:40.492297 <wave/3:ALL> WaveChan::WaveChan(play) [0x7f57b401e1f0]
1893
2018-02-22_21:23:40.492313 <wave:ALL> WaveSource::WaveSource("/usr/share/yate/sounds/echo.au",0x7f57b401e1f0) [0x7f57b4011a60]
1894
2018-02-22_21:23:40.492836 >>> DataTranslator::detachChain(0x7f57cc00e850,0x7f57b4000eb0)
1895
2018-02-22_21:23:40.492856   >>> DataTranslator::detachChain(0x7f57cc00e850,0x7f57b4001000)
1896
2018-02-22_21:23:40.492873   <<< DataTranslator::detachChain
1897
2018-02-22_21:23:40.492887   <ALL> GsmCodec::~GsmCodec() [0x7f57b4001000]
1898
2018-02-22_21:23:40.492902 <<< DataTranslator::detachChain
1899
2018-02-22_21:23:40.492953 <ALL> GsmCodec::GsmCodec("slin","gsm",encoding) [0x7f57b4001000]
1900
2018-02-22_21:23:40.492978 <ALL> Created DataTranslator 0x7f57b4001000 for 'mulaw' -> 'gsm' by factory 0x564a868eda00 (len=2)
1901
2018-02-22_21:23:40.493004 <ALL> DataTranslator::attachChain [0x7f57b4011a60] 'mulaw' -> [0x7f57cc001cb0] 'gsm' succeeded
1902
2018-02-22_21:23:40.493111 <wave:ALL> WaveConsumer::~WaveConsumer() [0x7f57b4000eb0] total=55680 stamp=131200
1903
2018-02-22_21:23:40.493126 <wave:INFO> WaveConsumer rate=15993 b/s
1904
2018-02-22_21:23:40.493150 <wave/2:ALL> WaveChan::~WaveChan() wave/2 [0x7f57b401d120]
1905
2018-02-22_21:23:40.711503 <ybts-signalling:INFO> Received [0x564a86b94a90]
1906

    
1907
-----
1908

    
1909
Primitive: PhysicalInfo
1910

    
1911
Info: 0
1912

    
1913
Connection: 2
1914

    
1915

    
1916

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

    
1919
-----
1920
2018-02-22_21:23:40.711585 <ybts-signalling:INFO> Received [0x564a86b94a90]
1921

    
1922
-----
1923

    
1924
Primitive: L3Message
1925

    
1926
Info: 0
1927

    
1928
Connection: 2
1929

    
1930

    
1931

    
1932
<CC>
1933

    
1934
  <TID TIFlag="false">0</TID>
1935

    
1936
  <NSD>0</NSD>
1937

    
1938
  <Message type="StopDTMF"/>
1939

    
1940
</CC>
1941

    
1942
-----
1943
2018-02-22_21:23:40.711633 <ybts-signalling:INFO> Sending [0x564a86b94a90]
1944

    
1945
-----
1946

    
1947
Primitive: L3Message
1948

    
1949
Info: 0
1950

    
1951
Connection: 2
1952

    
1953

    
1954

    
1955
<CC>
1956

    
1957
  <TID TIFlag="true">0</TID>
1958

    
1959
  <Message type="StopDTMFAck"/>
1960

    
1961
</CC>
1962

    
1963
-----
1964
2018-02-22_21:23:41.324338 <gsmtrx:ALL> ARFCN[0]: Slot 3. Excessive TOA error=5 peak/mean=4.71805 count=1 [0x7f57bc00f350]
1965
2018-02-22_21:23:43.214281 <gsmtrx:ALL> ARFCN[0]: Slot 7. Excessive TOA error=5 peak/mean=5.19542 count=2 [0x7f57bc00f350]
1966
2018-02-22_21:23:44.414251 <wave:ALL> WaveSource '(null)' end of data (31520 played) chan=0x7f57b401e1f0 [0x7f57b4011a60]
1967
2018-02-22_21:23:44.414642 <wave:ALL> WaveSource cleanup, total=31520, chan=(nil) [0x7f57b4011a60]
1968
2018-02-22_21:23:44.414817 >>> DataTranslator::detachChain(0x7f57b4011a60,0x7f57cc001cb0)
1969
2018-02-22_21:23:44.415087   >>> DataTranslator::detachChain(0x7f57b4011a60,0x7f57b4001000)
1970
2018-02-22_21:23:44.415460     >>> DataTranslator::detachChain(0x7f57b4011a60,0x7f57b401c420)
1971
2018-02-22_21:23:44.415734     <<< DataTranslator::detachChain
1972
2018-02-22_21:23:44.416004   <<< DataTranslator::detachChain
1973
2018-02-22_21:23:44.416331   <ALL> GsmCodec::~GsmCodec() [0x7f57b4001000]
1974
2018-02-22_21:23:44.416608 <<< DataTranslator::detachChain
1975
2018-02-22_21:23:44.416842 <ybts/3:ALL> Disconnected '(null)' [0x7f57c000b510]
1976
2018-02-22_21:23:44.417325 <wave:ALL> WaveSource::~WaveSource() [0x7f57b4011a60] total=31520 stamp=31360
1977
2018-02-22_21:23:44.417621 <wave:INFO> WaveSource rate=8033 b/s
1978
2018-02-22_21:23:44.417907 <wave/3:ALL> WaveChan::~WaveChan() wave/3 [0x7f57b401e1f0]
1979
2018-02-22_21:23:44.422410 <welcome:INFO> Returned to main function in state 'echoTest'
1980
2018-02-22_21:23:44.422861 <ALL> ExtModChan::ExtModChan(4) [0x7f57b4026ba0]
1981
2018-02-22_21:23:44.422895 <ALL> ExtModConsumer::ExtModConsumer(0x7f57b40297f0) [0x7f57b40103b0]
1982
2018-02-22_21:23:44.422915 <ALL> ExtModSource::ExtModSource(0x7f57b4029870) [0x7f57c0005690]
1983
2018-02-22_21:23:44.422998 <ALL> ExtModReceiver::ExtModReceiver("echo.sh","(null)") [0x7f57b4012060]
1984
Execing '/usr/share/yate/scripts/echo.sh' ''
1985
2018-02-22_21:23:44.430916 <INFO> Launched External Script '/usr/share/yate/scripts/echo.sh' ''
1986
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]
1987
2018-02-22_21:23:44.433218 <bladerf/1:WARN> RX: timestamp jumped by 6714 to 283694714 in buffer 6/8 [0x7f57bc000e40]
1988
2018-02-22_21:23:44.433233 <bladerf/1:NOTE> RX: timestamp adjusted by 6714 to 283701680 [0x7f57bc000e40]
1989
2018-02-22_21:23:44.434625 <gsmtrx:MILD> Transmit underrun by 4 timeslots [0x7f57bc01f570]
1990
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]
1991
=================== play ====================
1992
2018-02-22_21:23:44.441354 <ALL> GsmCodec::GsmCodec("gsm","slin",decoding) [0x7f57b401d780]
1993
2018-02-22_21:23:44.441390 <ALL> Created DataTranslator 0x7f57b401d780 for 'gsm' -> 'slin' by factory 0x7f57ef5a81a0 (len=1)
1994
2018-02-22_21:23:44.441416 <ALL> DataTranslator::attachChain [0x7f57cc00e850] 'gsm' -> [0x7f57b40103b0] 'slin' succeeded
1995
2018-02-22_21:23:44.441439 <ALL> GsmCodec::GsmCodec("slin","gsm",encoding) [0x7f57b4010d90]
1996
2018-02-22_21:23:44.441455 <ALL> Created DataTranslator 0x7f57b4010d90 for 'slin' -> 'gsm' by factory 0x7f57ef5a81a0 (len=1)
1997
2018-02-22_21:23:44.441482 <ALL> DataTranslator::attachChain [0x7f57c0005690] 'slin' -> [0x7f57cc001cb0] 'gsm' succeeded
1998
2018-02-22_21:23:45.104363 <gsmtrx:ALL> ARFCN[0]: Slot 3. Excessive TOA error=5 peak/mean=5.04826 count=1 [0x7f57bc00f350]
1999
2018-02-22_21:23:48.014845 <gsmtrx:ALL> ARFCN[0]: Slot 7. Excessive TOA error=-4 peak/mean=3.32295 count=1 [0x7f57bc00f350]
2000
2018-02-22_21:23:49.154570 <gsmtrx:ALL> ARFCN[0]: Slot 7. Excessive TOA errors 1 [0x7f57bc00f350]
2001
2018-02-22_21:23:49.231248 <ybts-signalling:INFO> Received [0x564a86b94a90]
2002

    
2003
-----
2004

    
2005
Primitive: PhysicalInfo
2006

    
2007
Info: 0
2008

    
2009
Connection: 2
2010

    
2011

    
2012

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

    
2015
-----
2016
2018-02-22_21:23:49.231340 <ybts-signalling:INFO> Received [0x564a86b94a90]
2017

    
2018
-----
2019

    
2020
Primitive: L3Message
2021

    
2022
Info: 0
2023

    
2024
Connection: 2
2025

    
2026

    
2027

    
2028
<CC>
2029

    
2030
  <TID TIFlag="false">0</TID>
2031

    
2032
  <NSD>1</NSD>
2033

    
2034
  <Message type="Disconnect">
2035

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

    
2038
  </Message>
2039

    
2040
</CC>
2041

    
2042
-----
2043
2018-02-22_21:23:49.231368 <ybts/3:INFO> Removing call 'i0' [0x7f57c000b510]
2044
2018-02-22_21:23:49.231386 <ybts/3:INFO> Call 'i0' changed state Active -> Release [0x7f57c000b510]
2045
2018-02-22_21:23:49.231440 <ybts-signalling:INFO> Sending [0x564a86b94a90]
2046

    
2047
-----
2048

    
2049
Primitive: L3Message
2050

    
2051
Info: 0
2052

    
2053
Connection: 2
2054

    
2055

    
2056

    
2057
<CC>
2058

    
2059
  <TID TIFlag="true">0</TID>
2060

    
2061
  <Message type="Release">
2062

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

    
2065
  </Message>
2066

    
2067
</CC>
2068

    
2069
-----
2070
2018-02-22_21:23:49.231497 <ybts/3:CALL> Hangup reason='normal-clearing' [0x7f57c000b510]
2071
2018-02-22_21:23:49.231532 >>> DataTranslator::detachChain(0x7f57cc00e850,0x7f57b40103b0)
2072
2018-02-22_21:23:49.231548   >>> DataTranslator::detachChain(0x7f57cc00e850,0x7f57b401d780)
2073
2018-02-22_21:23:49.231560   <<< DataTranslator::detachChain
2074
2018-02-22_21:23:49.232556   <ALL> GsmCodec::~GsmCodec() [0x7f57b401d780]
2075
2018-02-22_21:23:49.232573 <<< DataTranslator::detachChain
2076
2018-02-22_21:23:49.232584 >>> DataTranslator::detachChain(0x7f57c0005690,0x7f57cc001cb0)
2077
2018-02-22_21:23:49.232653   >>> DataTranslator::detachChain(0x7f57c0005690,0x7f57b4010d90)
2078
2018-02-22_21:23:49.232668   <<< DataTranslator::detachChain
2079
2018-02-22_21:23:49.232679   <ALL> GsmCodec::~GsmCodec() [0x7f57b4010d90]
2080
2018-02-22_21:23:49.232696 <<< DataTranslator::detachChain
2081
2018-02-22_21:23:49.232708 <ALL> ExtModChan::disconnected() 'normal-clearing' [0x7f57b4026ba0]
2082
2018-02-22_21:23:49.232722 <ALL> ExtModConsumer::~ExtModConsumer() [0x7f57b40103b0] total=76800
2083
2018-02-22_21:23:49.232750 >>> ExtModChan::~ExtModChan() [0x7f57b4026ba0]
2084
2018-02-22_21:23:49.232764   <ALL> ExtModReceiver::die() pid=9070 dead=no [0x7f57b4012060]
2085
2018-02-22_21:23:49.232782   <ALL> ExtModReceiver::die() waiting for pid=9070 to die [0x7f57b4012060]
2086
================= play done =================
2087
2018-02-22_21:23:49.233858   <ALL> ExtModSource [0x7f57c0005690] end of data total=76800
2088
2018-02-22_21:23:49.233887   <ALL> ExtModSource::~ExtModSource() [0x7f57c0005690] total=76800
2089
2018-02-22_21:23:49.233958   <ExtModule:INFO> Read EOF on 0x7f57a4001010 [0x7f57b4012060]
2090
2018-02-22_21:23:49.234202   <ALL> ExtModReceiver::destruct() pid=0 [0x7f57b4012060]
2091
2018-02-22_21:23:49.234219   <ALL> ExtModReceiver::die() pid=0 dead=yes [0x7f57b4012060]
2092
2018-02-22_21:23:49.234233 <<< ExtModChan::~ExtModChan()
2093
2018-02-22_21:23:49.234261 <ybts/3:CALL> Destroyed [0x7f57c000b510]
2094
2018-02-22_21:23:49.236580 <javascript:ALL> Assistant for 'ybts/3' deleted
2095
2018-02-22_21:23:49.236607 <ALL> Rescanning handler list for 'chan.hangup' [0x7f57cc00ee20] at priority 15
2096
2018-02-22_21:23:49.369786 <ybts-signalling:INFO> Received [0x564a86b94a90]
2097

    
2098
-----
2099

    
2100
Primitive: L3Message
2101

    
2102
Info: 0
2103

    
2104
Connection: 2
2105

    
2106

    
2107

    
2108
<CC>
2109

    
2110
  <TID TIFlag="false">0</TID>
2111

    
2112
  <NSD>0</NSD>
2113

    
2114
  <Message type="ReleaseComplete"/>
2115

    
2116
</CC>
2117

    
2118
-----
2119
2018-02-22_21:23:49.369831 <ybts:NOTE> Removing terminated call 'i0' conn=2
2120
2018-02-22_21:23:52.000528 <ybts-signalling:ALL> Connection 2 idle timeout [0x564a86b94a90]
2121
2018-02-22_21:23:52.000575 <ybts-signalling:ALL> Releasing connection (0x7f57c0006f60,2) [0x564a86b94a90]
2122
2018-02-22_21:23:52.000607 <ybts-signalling:INFO> Sending [0x564a86b94a90]
2123

    
2124
-----
2125

    
2126
Primitive: ConnRelease
2127

    
2128
Info: 0
2129

    
2130
Connection: 2
2131

    
2132
-----
2133
2018-02-22_21:23:54.126140 <gsmtrx:INFO> ARFCN[0]: Slot 0. Receiver clipping 1.37174 dB (FN=30470) count=1 [0x7f57bc00f350]
2134
2018-02-22_21:23:56.714759 <gsmtrx:ALL> ARFCN[0]: Slot 7. Excessive TOA error=4 peak/mean=3.19168 count=1 [0x7f57bc00f350]
2135
2018-02-22_21:23:58.131776 <gsmtrx:INFO> ARFCN[0]: Slot 0. Receiver clipping 0.974068 dB (FN=31338) count=1 [0x7f57bc00f350]
2136
2018-02-22_21:23:58.132771 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f57bc01f570]
2137
2018-02-22_21:23:58.132818 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0) RSP '0'
2138
2018-02-22_21:23:58.133052 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f57bc01f570]
2139
2018-02-22_21:23:58.133081 <gsmtrx:ALL> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0) RSP '0'
2140
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
2141

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

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

    
2146
	 mtMsgExpectedBits=0x20 mtMsgAckBits=0x2 mtExpectedAckBSN[5]=7238
2147

    
2148
	 mtUnAckMode=0 OnCCCH=0 mtAssignCounter=1 N3101=21
2149

    
2150
	 msNumDataUSFGrants=356 msAckNackUSFGrant=352 msOldTlli=0x80006001 Pacch= idle=0
2151

    
2152
	 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
2153

    
2154
	 VR=1 VQ=1 stalled=0 mNumUpBlocksSinceAckNack=0 mtUpState=1
2155

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

    
2159
-----
2160

    
2161
Primitive: PhysicalInfo
2162

    
2163
Info: 0
2164

    
2165
Connection: 3
2166

    
2167

    
2168

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

    
2171
-----
2172
2018-02-22_21:23:58.441771 <ybts-signalling:INFO> Received [0x564a86b94a90]
2173

    
2174
-----
2175

    
2176
Primitive: L3Message
2177

    
2178
Info: 0
2179

    
2180
Connection: 3
2181

    
2182

    
2183

    
2184
<MM>
2185

    
2186
  <SkipIndicator>0</SkipIndicator>
2187

    
2188
  <NSD>0</NSD>
2189

    
2190
  <Message type="IMSIDetachIndication">
2191

    
2192
    <MSClassmark1>
2193

    
2194
      <RFPowerCapability>class1</RFPowerCapability>
2195

    
2196
      <RevisionLevel>GSM-phase2</RevisionLevel>
2197

    
2198
      <Flags>ES-IND</Flags>
2199

    
2200
    </MSClassmark1>
2201

    
2202
    <MobileIdentity>
2203

    
2204
      <TMSI>007b0002</TMSI>
2205

    
2206
    </MobileIdentity>
2207

    
2208
  </Message>
2209

    
2210
</MM>
2211

    
2212
-----
2213
2018-02-22_21:23:58.441828 <ybts-signalling:ALL> Added connection (0x7f57c000c0f0,3) [0x564a86b94a90]
2214
2018-02-22_21:23:58.441857 <ybts-signalling:ALL> Releasing connection (0x7f57c000c0f0,3) [0x564a86b94a90]
2215
2018-02-22_21:23:58.441887 <ybts-signalling:INFO> Sending [0x564a86b94a90]
2216

    
2217
-----
2218

    
2219
Primitive: ConnRelease
2220

    
2221
Info: 0
2222

    
2223
Connection: 3
2224

    
2225
-----
2226
2018-02-22_21:23:58.441932 <ybts-mm:INFO> Detached UE (0x7f57c00089e0) TMSI=007b0002 IMSI=(null) [0x564a86b94e70]
2227
2018-02-22_21:23:58.444392 <nipc:INFO> Finished onUnregister imsi 001010000000001
2228
2018-02-22_21:23:59.137596 <gsmtrx:INFO> ARFCN[0]: Slot 0. Receiver clipping -0.334817 dB (FN=31556) count=18 [0x7f57bc00f350]
2229
^C2018-02-22_21:23:59.558378 <sip:ALL> Returning false from engine.stop handler
2230
2018-02-22_21:23:59.558448 <iaxengine:INFO> Status changed Listening -> Exiting [0x564a86ac49c0]
2231
2018-02-22_21:23:59.558478 <iaxengine:ALL> Set terminate timeout=16500ms status=Exiting [0x564a86ac49c0]
2232
2018-02-22_21:23:59.558523 <iax:INFO> Removed listener (0x564a86ac49c0) 'iaxengine' status='Exiting'
2233
2018-02-22_21:23:59.559507 <iaxengine:ALL> Thread (0x564a86a0ce60) 'YIAXTrunking' terminated [0x564a86ac49c0]
2234
2018-02-22_21:23:59.559953 <iaxengine:ALL> Thread (0x564a86a18ce0) 'YIAXListener' terminated [0x564a86ac49c0]
2235
2018-02-22_21:23:59.560557 <iaxengine:ALL> Thread (0x564a86a0f9b0) 'YIAXGetEvent' terminated [0x564a86ac49c0]
2236
2018-02-22_21:23:59.560666 <iaxengine:ALL> Thread (0x564a86a17250) 'YIAXListener' terminated [0x564a86ac49c0]
2237
2018-02-22_21:23:59.560900 <iaxengine:ALL> Thread (0x564a86a10ad0) 'YIAXGetEvent' terminated [0x564a86ac49c0]
2238
2018-02-22_21:23:59.561311 <iaxengine:ALL> Thread (0x564a86a136a0) 'YIAXGetEvent' terminated [0x564a86ac49c0]
2239
2018-02-22_21:23:59.561777 <jingle:ALL> YJGEngineWorker stop running
2240
2018-02-22_21:23:59.562967 <iaxengine:ALL> Thread (0x564a86a17c70) 'YIAXListener' terminated [0x564a86ac49c0]
2241
2018-02-22_21:23:59.563662 <iaxengine:ALL> Destroyed [0x564a86ac49c0]
2242
2018-02-22_21:23:59.564038 <mbts:MILD> CLI.cpp:228:exit_function: dropping 1 control transactions on exit
2243
2018-02-22_21:23:59.564072 <gsmtrx:INFO> Module state changed Running -> Waiting
2244
2018-02-22_21:23:59.564089 <gsmtrx:NOTE> Stopping transceiver: received STOP command
2245
2018-02-22_21:23:59.564110 <gsmtrx:ALL> Stopping [0x7f57bc01f570]
2246
2018-02-22_21:23:59.564125 <gsmtrx:ALL> Sending 'EXITING' on clock interface [0x7f57bc01f570]
2247
2018-02-22_21:23:59.564158 <gsmtrx:NOTE> State changed PowerOn -> Idle [0x7f57bc01f570]
2248
2018-02-22_21:23:59.564240 <mbts:NOTE> TRXManager.cpp:281:clockHandler: TRX clock 'EXITING' indication
2249
2018-02-22_21:23:59.564256 <ybts-signalling:INFO> Received [0x564a86b94a90]
2250

    
2251
-----
2252

    
2253
Primitive: Stop
2254

    
2255
Info: 3
2256

    
2257
-----
2258
2018-02-22_21:23:59.564497 <gsmtrx:ALL> Radio device read thread (0x7f57bc042530) terminated [0x7f57bc01f570]
2259
2018-02-22_21:23:59.564521 <gsmtrx:ALL> Radio device send thread (0x7f57bc043490) terminated [0x7f57bc01f570]
2260
2018-02-22_21:23:59.565944 <gsmtrx:ALL> ARFCN[1]: Data socket read thread (0x7f57bc043cc0) terminated [0x7f57bc0317f0]
2261
2018-02-22_21:23:59.566719 <gsmtrx:ALL> ARFCN[2]: Radio input process thread (0x7f57bc043f20) terminated [0x7f57bc0380a0]
2262
2018-02-22_21:23:59.566769 <gsmtrx:ALL> ARFCN[1]: Radio input process thread (0x7f57bc043a60) terminated [0x7f57bc0317f0]
2263
2018-02-22_21:23:59.566814 <gsmtrx:ALL> ARFCN[3]: Radio input process thread (0x7f57bc042e20) terminated [0x7f57bc03bf80]
2264
2018-02-22_21:23:59.567040 <gsmtrx:ALL> ARFCN[0]: Radio input process thread (0x7f57bc043660) terminated [0x7f57bc00f350]
2265
2018-02-22_21:23:59.567626 <gsmtrx:ALL> ARFCN[3]: Data socket read thread (0x7f57bc044480) terminated [0x7f57bc03bf80]
2266
2018-02-22_21:23:59.568083 <gsmtrx:ALL> ARFCN[2]: Data socket read thread (0x7f57bc042db0) terminated [0x7f57bc0380a0]
2267
2018-02-22_21:23:59.568675 <gsmtrx:ALL> Radio read process thread (0x7f57bc042120) terminated [0x7f57bc01f570]
2268
2018-02-22_21:23:59.569012 <gsmtrx:ALL> ARFCN[0]: Data socket read thread (0x7f57bc043830) terminated [0x7f57bc00f350]
2269
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]
2270
2018-02-22_21:23:59.569754 <bladerf/1:ALL> Destroying device=(0x7f57bc0208b0) [0x7f57bc000e40]
2271
2018-02-22_21:23:59.589982 <bladerf/1:ALL> Disabled RF TX [0x7f57bc000e40]
2272
2018-02-22_21:23:59.611014 <bladerf/1:ALL> Disabled RF RX [0x7f57bc000e40]
2273
2018-02-22_21:23:59.611057 <bladerf/1:NOTE> Powered OFF the radio [0x7f57bc000e40]
2274
2018-02-22_21:23:59.612188 <bladerf/1:ALL> Device closed [0x7f57bc000e40]
2275
2018-02-22_21:23:59.612291 <bladerf/1:ALL> Interface destroyed [0x7f57bc000e40]
2276
MBTS exiting
2277
2018-02-22_21:23:59.612824 <ybts:ALL> 'shutdown' command result:
2278

    
2279
-----
2280

    
2281
exiting with loads:
2282

    
2283
== GSM ==
2284

    
2285
SDCCH load: 1/4
2286

    
2287
TCH/F load: 0/4
2288

    
2289
AGCH/PCH load: 0,0
2290

    
2291
Paging table size: 0
2292

    
2293
T3122: 2000 ms (target 5000 ms)
2294

    
2295
== GPRS ==
2296

    
2297
current PDCHs: 3
2298

    
2299
utilization: 0%
2300

    
2301

    
2302

    
2303
exiting...
2304

    
2305
-----
2306
2018-02-22_21:23:59.612891 <ybts:ALL> Stopping ...
2307
2018-02-22_21:23:59.615401 <ybts-signalling:ALL> Worker thread terminated [0x564a86b94a90]
2308
2018-02-22_21:23:59.618032 <ybts-mm:ALL> Removed UE (0x7f57c00089e0): destroyed [0x564a86b94e70]
2309
2018-02-22_21:23:59.618063 <ybts:ALL> UE destroyed [0x7f57c00089e0]
2310
2018-02-22_21:23:59.618105 <ybts-signalling:INFO> Stopped [0x564a86b94a90]
2311
2018-02-22_21:23:59.619481 <ybts-media:ALL> Worker thread terminated [0x564a86b947b0]
2312
2018-02-22_21:23:59.623271 <ybts-media:INFO> Stopped [0x564a86b947b0]
2313
2018-02-22_21:23:59.623307 <ybts:NOTE> Peer pid 8944 has not exited - we'll kill it
2314
2018-02-22_21:23:59.723435 <ybts:INFO> Peer pid 8944 terminated
2315
2018-02-22_21:23:59.723514 <ybts-command:INFO> Stopped [0x564a86b94650]
2316
2018-02-22_21:23:59.727225 <transceiver:ALL> Worker thread terminated [0x564a86b941e0]
2317
2018-02-22_21:23:59.728699 <transceiver:INFO> Stopped [0x564a86b941e0]
2318
2018-02-22_21:23:59.730595 <mbts:ALL> Worker thread terminated [0x564a86b943f0]
2319
2018-02-22_21:23:59.733893 <mbts:INFO> Stopped [0x564a86b943f0]
2320
2018-02-22_21:23:59.733936 <ybts:NOTE> State changed RadioUp -> Idle
2321
Yate engine is shutting down with code 0
2322
2018-02-22_21:23:59.734245 <ALL> Rescanning handler list for 'engine.halt' [0x7ffc6f718740] at priority 100
2323
2018-02-22_21:23:59.734288 <ALL> Handler list for 'engine.halt' [0x7ffc6f718740] changed, skipping from 0x564a868e87f0 (100) to 0x564a868e90f0 (100)
2324
2018-02-22_21:23:59.735638 <jabber:ALL> JBPendingWorker(0) terminated [0x564a868e7f50]
2325
2018-02-22_21:23:59.735709 <jabber:ALL> Terminated 1 pending worker threads
2326
2018-02-22_21:23:59.735829 <ALL> Rescanning handler list for 'engine.halt' [0x7ffc6f718740] at priority 100
2327
2018-02-22_21:23:59.735921 <ALL> Rescanning handler list for 'engine.halt' [0x7ffc6f718740] at priority 100
2328
2018-02-22_21:23:59.736059 <ALL> Rescanning handler list for 'engine.halt' [0x7ffc6f718740] at priority 100
2329
2018-02-22_21:23:59.736099 <ALL> Handler list for 'engine.halt' [0x7ffc6f718740] changed, skipping from 0x564a8692fc20 (100) to 0x564a86930900 (100)
2330
2018-02-22_21:23:59.736143 <sip:ALL> Clearing udp transports reason=Exiting
2331
2018-02-22_21:23:59.736201 <sip:INFO> Listener(UDP,'general') stop listening reason='Exiting' [0x564a86aba500]
2332
2018-02-22_21:23:59.736237 <sip:ALL> Removed transport (0x564a86aba500,'general')
2333
2018-02-22_21:23:59.736267 <sip:INFO> Reset default UDP transport
2334
2018-02-22_21:23:59.745043 <sip:ALL> Transport(general) destroyed [0x564a86aba500]
2335
2018-02-22_21:23:59.745093 <sip:ALL> Endpoint stopped
2336
2018-02-22_21:23:59.745138 <sip:ALL> YateSIPEndPoint::~YateSIPEndPoint() [0x564a86abf880]
2337
2018-02-22_21:23:59.745174 <ALL> Rescanning handler list for 'engine.halt' [0x7ffc6f718740] at priority 100
2338
2018-02-22_21:23:59.745332 <ALL> Rescanning handler list for 'engine.halt' [0x7ffc6f718740] at priority 100
2339
2018-02-22_21:23:59.745359 <ALL> Handler list for 'engine.halt' [0x7ffc6f718740] changed, skipping from 0x564a86ad2cf0 (100) to 0x564a86ad2f40 (100)
2340
2018-02-22_21:23:59.745421 <queuesnotify:ALL> Cancelling worker(s)
2341
2018-02-22_21:23:59.750601 <queuesnotify:ALL> Worker(s) terminated
2342
2018-02-22_21:23:59.750637 <ALL> Rescanning handler list for 'engine.halt' [0x7ffc6f718740] at priority 100
2343
2018-02-22_21:23:59.750656 <ALL> Handler list for 'engine.halt' [0x7ffc6f718740] changed, skipping from 0x564a86adf4e0 (100) to 0x564a86ae0d90 (100)
2344
2018-02-22_21:23:59.750716 <ALL> Rescanning handler list for 'engine.halt' [0x7ffc6f718740] at priority 100
2345
2018-02-22_21:23:59.750733 <ALL> Handler list for 'engine.halt' [0x7ffc6f718740] changed, skipping from 0x564a86ae1e60 (100) to 0x564a86ae2590 (100)
2346
2018-02-22_21:23:59.750787 <ALL> Rescanning handler list for 'engine.halt' [0x7ffc6f718740] at priority 100
2347
2018-02-22_21:23:59.750804 <ALL> Handler list for 'engine.halt' [0x7ffc6f718740] changed, skipping from 0x564a86ae29f0 (100) to 0x564a86b91f00 (100)
2348
2018-02-22_21:23:59.750841 <ALL> Rescanning handler list for 'engine.halt' [0x7ffc6f718740] at priority 100
2349
2018-02-22_21:23:59.750861 <snmpagent:ALL> ::unload()
2350
2018-02-22_21:23:59.766486 <ALL> Rescanning handler list for 'engine.halt' [0x7ffc6f718740] at priority 100
2351
2018-02-22_21:23:59.766555 <ALL> Handler list for 'engine.halt' [0x7ffc6f718740] changed, skipping from 0x564a86b96590 (100) to 0x564a86b97000 (100)
2352
2018-02-22_21:23:59.766710 <ALL> Rescanning handler list for 'engine.halt' [0x7ffc6f718740] at priority 100
2353
2018-02-22_21:23:59.766761 <ALL> Handler list for 'engine.halt' [0x7ffc6f718740] changed, skipping from 0x564a86b97910 (100) to 0x564a86b9ae40 (100)
2354
2018-02-22_21:23:59.766803 <gsmtrx:INFO> Module state changed Waiting -> Idle
2355
2018-02-22_21:23:59.766865 <gsmtrx:ALL> 'control' thread terminated
2356
2018-02-22_21:23:59.776823 <signalling:ALL> Engine stopped worker thread [0x564a86b9de10]
2357
2018-02-22_21:23:59.981222 >>> ThreadPrivate::killall()
2358
2018-02-22_21:23:59.981301   <NOTE> Soft cancelling 4 running threads
2359
2018-02-22_21:23:59.981361   <INFO> Stopping ThreadPrivate 'RManager Listener' [0x564a869ee420]
2360
2018-02-22_21:23:59.981418   <INFO> Stopping ThreadPrivate 'MGCP Receive' [0x564a86963a60]
2361
2018-02-22_21:23:59.981473   <INFO> Stopping ThreadPrivate 'MGCP Process' [0x564a86a5c2b0]
2362
2018-02-22_21:23:59.981507   <INFO> Stopping ThreadPrivate 'Engine Worker' [0x564a86c56d10]
2363
2018-02-22_21:23:59.991813 <<< ThreadPrivate::killall()
2364
2018-02-22_21:23:59.993346 <sig/isup.decode:INFO> ISUP Call Controller destroyed [0x564a86b9cc10]
2365
2018-02-22_21:23:59.993388 <sig/isup.encode:INFO> ISUP Call Controller destroyed [0x564a86b9e670]
2366
Unloading module Radio Test
2367
2018-02-22_21:23:59.998034 >>> Plugin::~Plugin() "radiotest" [0x7f57e3ef3ce0]
2368
2018-02-22_21:23:59.998084 <<< Plugin::~Plugin()
2369
Unloading module Signalling Channel
2370
2018-02-22_21:23:59.998289 >>> Plugin::~Plugin() "sig" [0x7f57e56bd000]
2371
2018-02-22_21:23:59.998320 <<< Plugin::~Plugin()
2372
2018-02-22_21:23:59.998390 <WARN> Unloading 'gsmtrx' removed 0 out of 1 plugins
2373
Unloaded module Users Management
2374
2018-02-22_21:23:59.998497 >>> Plugin::~Plugin() "users" [0x7f57e5d05260]
2375
2018-02-22_21:23:59.998530 <<< Plugin::~Plugin()
2376
Unloading module MRCP
2377
2018-02-22_21:23:59.998703 >>> Plugin::~Plugin() "mrcp" [0x7f57e5f0d280]
2378
2018-02-22_21:23:59.998732 <<< Plugin::~Plugin()
2379
2018-02-22_21:23:59.998793 <WARN> Unloading 'ysnmpagent' removed 0 out of 1 plugins
2380
Unloading module Cache
2381
2018-02-22_21:23:59.998917 >>> Plugin::~Plugin() "cache" [0x7f57e6570920]
2382
2018-02-22_21:23:59.998942 <<< Plugin::~Plugin()
2383
2018-02-22_21:23:59.999004 <WARN> Unloading 'ybts' removed 0 out of 1 plugins
2384
Unloading module Analog Channel
2385
2018-02-22_21:23:59.999173 >>> Plugin::~Plugin() "analog" [0x7f57e6c15680]
2386
2018-02-22_21:23:59.999210 <<< Plugin::~Plugin()
2387
Unloading module CallCounters
2388
2018-02-22_21:23:59.999365 >>> Plugin::~Plugin() "callcounters" [0x7f57e702f1c0]
2389
2018-02-22_21:23:59.999398 <<< Plugin::~Plugin()
2390
2018-02-22_21:23:59.999525 >>> Plugin::~Plugin() "pbxassist" [0x7f57e723f3c0]
2391
2018-02-22_21:23:59.999561 <<< Plugin::~Plugin()
2392
Unloading module PBX for database
2393
2018-02-22_21:23:59.999727 >>> Plugin::~Plugin() "dbpbx" [0x7f57e7448280]
2394
2018-02-22_21:23:59.999756 <<< Plugin::~Plugin()
2395
Unloading module SIP Features
2396
2018-02-22_21:23:59.999935 >>> Plugin::~Plugin() "sipfeatures" [0x7f57e7651380]
2397
2018-02-22_21:23:59.999971 <<< Plugin::~Plugin()
2398
Unloading module MGCP-GW
2399
2018-02-22_21:24:00.000136 >>> Plugin::~Plugin() "mgcpgw" [0x7f57e7a740e0]
2400
2018-02-22_21:24:00.000170 <<< Plugin::~Plugin()
2401
Unloading module Late Router
2402
2018-02-22_21:24:00.000346 >>> Plugin::~Plugin() "lateroute" [0x7f57e82c2240]
2403
2018-02-22_21:24:00.000379 <<< Plugin::~Plugin()
2404
2018-02-22_21:24:00.000512 >>> Plugin::~Plugin() "accfile" [0x7f57e84c8240]
2405
2018-02-22_21:24:00.000542 <<< Plugin::~Plugin()
2406
Unloading module CCongestion
2407
2018-02-22_21:24:00.000662 >>> Plugin::~Plugin() "ccongestion" [0x7f57e86ce2c0]
2408
2018-02-22_21:24:00.000690 <<< Plugin::~Plugin()
2409
Unloading module Heartbeat
2410
2018-02-22_21:24:00.000846 >>> Plugin::~Plugin() "heartbeat" [0x7f57e88d5260]
2411
2018-02-22_21:24:00.000879 <<< Plugin::~Plugin()
2412
Unloaded module Monitoring
2413
2018-02-22_21:24:00.001014 >>> Plugin::~Plugin() "monitoring" [0x7f57e8af1280]
2414
2018-02-22_21:24:00.001045 <<< Plugin::~Plugin()
2415
Unloading module Queues Notify
2416
2018-02-22_21:24:00.001163 >>> Plugin::~Plugin() "queuesnotify" [0x7f57e8cfb480]
2417
2018-02-22_21:24:00.001242 <<< Plugin::~Plugin()
2418
Unloading module Queues
2419
2018-02-22_21:24:00.001366 >>> Plugin::~Plugin() "queues" [0x7f57e8f04580]
2420
2018-02-22_21:24:00.001401 <<< Plugin::~Plugin()
2421
Unloading module Call Parking
2422
2018-02-22_21:24:00.001520 >>> Plugin::~Plugin() "park" [0x7f57e910a240]
2423
2018-02-22_21:24:00.001553 <<< Plugin::~Plugin()
2424
Unloaded module Radius client
2425
2018-02-22_21:24:00.001691 >>> Plugin::~Plugin() "yradius" [0x7f57e9c86a40]
2426
2018-02-22_21:24:00.001727 <<< Plugin::~Plugin()
2427
Unloading module Register for database
2428
2018-02-22_21:24:00.001864 >>> Plugin::~Plugin() "register" [0x7f57ea3a6600]
2429
2018-02-22_21:24:00.001900 <<< Plugin::~Plugin()
2430
Unload module Registration from file
2431
2018-02-22_21:24:00.002021 >>> Plugin::~Plugin() "regfile" [0x7f57ea7b4380]
2432
2018-02-22_21:24:00.002054 <<< Plugin::~Plugin()
2433
Unloaded module Presence
2434
2018-02-22_21:24:00.002179 >>> Plugin::~Plugin() "presence" [0x7f57ea9bd2a0]
2435
2018-02-22_21:24:00.002207 <<< Plugin::~Plugin()
2436
Unloading module Analyzer
2437
2018-02-22_21:24:00.002310 >>> Plugin::~Plugin() "analyzer" [0x7f57eabc8420]
2438
2018-02-22_21:24:00.002335 <<< Plugin::~Plugin()
2439
Unloading module FileInfo
2440
2018-02-22_21:24:00.002495 >>> Plugin::~Plugin() "fileinfo" [0x7f57eadd3b20]
2441
2018-02-22_21:24:00.002520 <<< Plugin::~Plugin()
2442
Unloading module CdrCombine
2443
2018-02-22_21:24:00.002624 >>> Plugin::~Plugin() "cdrcombine" [0x7f57eafd9900]
2444
2018-02-22_21:24:00.002653 <<< Plugin::~Plugin()
2445
Unloading module PBX
2446
2018-02-22_21:24:00.002757 >>> Plugin::~Plugin() "pbx" [0x7f57eb1e0480]
2447
2018-02-22_21:24:00.002779 <<< Plugin::~Plugin()
2448
Unloading module iLBC webrtc with 0 codecs still in use
2449
2018-02-22_21:24:00.003022 >>> Plugin::~Plugin() "ilbcwebrtc" [0x7f57eb3f8580]
2450
2018-02-22_21:24:00.003054 <<< Plugin::~Plugin()
2451
Unloading module YSTUN
2452
2018-02-22_21:24:00.003174 >>> Plugin::~Plugin() "stun" [0x7f57eb6023a0]
2453
2018-02-22_21:24:00.003207 <<< Plugin::~Plugin()
2454
Unloading module ToneGen
2455
2018-02-22_21:24:00.003648 >>> Plugin::~Plugin() "tone" [0x7f57ec5315e0]
2456
2018-02-22_21:24:00.003680 <<< Plugin::~Plugin()
2457
2018-02-22_21:24:00.003898 >>> Plugin::~Plugin() "wave" [0x7f57ec741900]
2458
2018-02-22_21:24:00.003935 <<< Plugin::~Plugin()
2459
Unloading module DumbChannel
2460
2018-02-22_21:24:00.004051 >>> Plugin::~Plugin() "dumb" [0x7f57ec9471a0]
2461
2018-02-22_21:24:00.004087 <<< Plugin::~Plugin()
2462
Unloading module RManager
2463
2018-02-22_21:24:00.004193 >>> Plugin::~Plugin() "rmanager" [0x7f57ecb585c0]
2464
2018-02-22_21:24:00.004225 <<< Plugin::~Plugin()
2465
2018-02-22_21:24:00.004307 <WARN> Unloading 'tonedetect' removed 0 out of 1 plugins
2466
Unloading module YRTP
2467
2018-02-22_21:24:00.004450 >>> Plugin::~Plugin() "yrtp" [0x7f57ecf88f60]
2468
2018-02-22_21:24:00.004473 <<< Plugin::~Plugin()
2469
Unloading module MOH
2470
2018-02-22_21:24:00.004641 >>> Plugin::~Plugin() "moh" [0x7f57ed60f400]
2471
2018-02-22_21:24:00.004674 <<< Plugin::~Plugin()
2472
Unloading module Call Generator, clearing 0 calls
2473
2018-02-22_21:24:00.004784 >>> Plugin::~Plugin() "callgen" [0x7f57ed81a360]
2474
2018-02-22_21:24:00.004812 <<< Plugin::~Plugin()
2475
2018-02-22_21:24:00.004870 <WARN> Unloading 'yjinglechan' removed 0 out of 1 plugins
2476
2018-02-22_21:24:00.004934 >>> Plugin::~Plugin() "msgsniff" [0x7f57ee2d1220]
2477
2018-02-22_21:24:00.004960 <<< Plugin::~Plugin()
2478
Unloading module GVoice
2479
2018-02-22_21:24:00.005122 >>> Plugin::~Plugin() "gvoice" [0x7f57ee4d7460]
2480
2018-02-22_21:24:00.005150 <<< Plugin::~Plugin()
2481
Unloading module YIAX
2482
2018-02-22_21:24:00.005291 >>> Plugin::~Plugin() "iax" [0x7f57ee7134c0]
2483
2018-02-22_21:24:00.005320 <<< Plugin::~Plugin()
2484
Unloading module iSAC with 0 codecs still in use
2485
2018-02-22_21:24:00.005986 >>> Plugin::~Plugin() "isaccodec" [0x7f57ee94f6e0]
2486
2018-02-22_21:24:00.006006 <<< Plugin::~Plugin()
2487
Unloading module iLBC with 0 codecs still in use
2488
2018-02-22_21:24:00.006322 >>> Plugin::~Plugin() "ilbccodec" [0x7f57eeb72000]
2489
2018-02-22_21:24:00.006340 <<< Plugin::~Plugin()
2490
2018-02-22_21:24:00.006399 >>> Plugin::~Plugin() "enumroute" [0x7f57eed79200]
2491
2018-02-22_21:24:00.006417 <<< Plugin::~Plugin()
2492
2018-02-22_21:24:00.006499 >>> Plugin::~Plugin() "regexroute" [0x7f57eef86f00]
2493
2018-02-22_21:24:00.006517 <<< Plugin::~Plugin()
2494
Unloading module Conference
2495
2018-02-22_21:24:00.006596 >>> Plugin::~Plugin() "conf" [0x7f57ef197480]
2496
2018-02-22_21:24:00.006613 <<< Plugin::~Plugin()
2497
Unloading module GSM with 0 codecs still in use
2498
2018-02-22_21:24:00.006783 >>> Plugin::~Plugin() "gsmcodec" [0x7f57ef5a8140]
2499
2018-02-22_21:24:00.006801 <<< Plugin::~Plugin()
2500
Unloading module Call Forker
2501
2018-02-22_21:24:00.006878 >>> Plugin::~Plugin() "callfork" [0x7f57ef7b4420]
2502
2018-02-22_21:24:00.006895 <<< Plugin::~Plugin()
2503
Unloading module Speex with 0 codecs still in use
2504
2018-02-22_21:24:00.007087 >>> Plugin::~Plugin() "speexcodec" [0x7f57efbd51e0]
2505
2018-02-22_21:24:00.007106 <<< Plugin::~Plugin()
2506
2018-02-22_21:24:00.007182 >>> Plugin::~Plugin() "fax" [0x7f57f0beb4c0]
2507
2018-02-22_21:24:00.007202 <<< Plugin::~Plugin()
2508
Unloading module CdrBuild
2509
2018-02-22_21:24:00.007408 >>> Plugin::~Plugin() "cdrbuild" [0x7f57f0ffd1e0]
2510
2018-02-22_21:24:00.007428 <<< Plugin::~Plugin()
2511
2018-02-22_21:24:00.007469 <WARN> Unloading 'extmodule' removed 0 out of 1 plugins
2512
2018-02-22_21:24:00.007498 <WARN> Unloading 'ysipchan' removed 0 out of 1 plugins
2513
Unloading module CdrFile
2514
2018-02-22_21:24:00.007542 >>> Plugin::~Plugin() "cdrfile" [0x7f57f0df0240]
2515
2018-02-22_21:24:00.007562 <<< Plugin::~Plugin()
2516
Unloading module MUX
2517
2018-02-22_21:24:00.007625 >>> Plugin::~Plugin() "mux" [0x7f57ee0cb600]
2518
2018-02-22_21:24:00.007644 <<< Plugin::~Plugin()
2519
Unloading module File Transfer
2520
2018-02-22_21:24:00.007703 >>> Plugin::~Plugin() "filetransfer" [0x7f57eda2a820]
2521
2018-02-22_21:24:00.007720 <<< Plugin::~Plugin()
2522
2018-02-22_21:24:00.007755 <WARN> Unloading 'javascript' removed 0 out of 1 plugins
2523
Unloading module ZLib
2524
2018-02-22_21:24:00.007797 >>> Plugin::~Plugin() "zlibcompress" [0x7f57ec320220]
2525
2018-02-22_21:24:00.007810 <<< Plugin::~Plugin()
2526
2018-02-22_21:24:00.007845 <WARN> Unloading 'ysockschan' removed 0 out of 1 plugins
2527
Unloading module OpenSSL
2528
2018-02-22_21:24:00.007912 >>> Plugin::~Plugin() "openssl" [0x7f57ebf025a0]
2529
2018-02-22_21:24:00.007930 <<< Plugin::~Plugin()
2530
Unloading module Event Logs
2531
2018-02-22_21:24:00.007986 >>> Plugin::~Plugin() "eventlogs" [0x7f57ea5ab2e0]
2532
2018-02-22_21:24:00.008003 <<< Plugin::~Plugin()
2533
Unloading module SQLite
2534
2018-02-22_21:24:00.008075 >>> Plugin::~Plugin() "sqlitedb" [0x7f57ea198380]
2535
2018-02-22_21:24:00.008095 <<< Plugin::~Plugin()
2536
Unloading module Clustering
2537
2018-02-22_21:24:00.008182 >>> Plugin::~Plugin() "clustering" [0x7f57e9a751e0]
2538
2018-02-22_21:24:00.008196 <<< Plugin::~Plugin()
2539
2018-02-22_21:24:00.008225 <WARN> Unloading 'ciscosm' removed 0 out of 1 plugins
2540
Unloading module SigTransport
2541
2018-02-22_21:24:00.008290 >>> Plugin::~Plugin() "sigtransport" [0x7f57e80bd700]
2542
2018-02-22_21:24:00.008319 <<< Plugin::~Plugin()
2543
Unloading module MGCP-CA
2544
2018-02-22_21:24:00.008389 >>> Plugin::~Plugin() "mgcpca" [0x7f57e7ead240]
2545
2018-02-22_21:24:00.008408 <<< Plugin::~Plugin()
2546
Unloading module ToneDetector
2547
2018-02-22_21:24:00.008448 >>> Plugin::~Plugin() "tonedetect" [0x7f57ecd612e0]
2548
2018-02-22_21:24:00.008467 <<< Plugin::~Plugin()
2549
Unloading module SIP Channel
2550
2018-02-22_21:24:00.008521 >>> Plugin::~Plugin() "sip" [0x7f57f147c800]
2551
2018-02-22_21:24:00.008542 <<< Plugin::~Plugin()
2552
2018-02-22_21:24:00.008641 <WARN> Unloading 'mgcpca' removed 3 out of 1 plugins
2553
2018-02-22_21:24:00.008674 >>> Plugin::~Plugin() "dbwave" [0x7f57e7857260]
2554
2018-02-22_21:24:00.008714 <<< Plugin::~Plugin()
2555
Unloading module Subscriptions
2556
2018-02-22_21:24:00.008781 >>> Plugin::~Plugin() "subscription" [0x7f57e6e297a0]
2557
2018-02-22_21:24:00.008800 <<< Plugin::~Plugin()
2558
Unloading module Analog Detector
2559
2018-02-22_21:24:00.008861 >>> Plugin::~Plugin() "analogdetect" [0x7f57e63609c0]
2560
2018-02-22_21:24:00.008881 <<< Plugin::~Plugin()
2561
Unloading module Cpu
2562
2018-02-22_21:24:00.008941 >>> Plugin::~Plugin() "cpuload" [0x7f57e58c63e0]
2563
2018-02-22_21:24:00.008960 <<< Plugin::~Plugin()
2564
Unloading module Jabber Server
2565
2018-02-22_21:24:00.009038 >>> Plugin::~Plugin() "jabber" [0x7f57e548fd20]
2566
2018-02-22_21:24:00.009060 <<< Plugin::~Plugin()
2567
Unloading module Jabber Server Features
2568
2018-02-22_21:24:00.009128 >>> Plugin::~Plugin() "jbfeatures" [0x7f57e5264420]
2569
2018-02-22_21:24:00.009147 <<< Plugin::~Plugin()
2570
Unloading module BladeRF
2571
2018-02-22_21:24:00.009388 <bladerf:ALL> Cleared libusb context
2572
2018-02-22_21:24:00.009408 >>> Plugin::~Plugin() "bladerf" [0x7f57e5058280]
2573
2018-02-22_21:24:00.009419 <<< Plugin::~Plugin()
2574
Unloading module DummyRadio
2575
2018-02-22_21:24:00.009668 >>> Plugin::~Plugin() "dummyradio" [0x7f57e3ce2700]
2576
2018-02-22_21:24:00.009683 <<< Plugin::~Plugin()
2577
2018-02-22_21:24:00.009725 <WARN> Exiting with 0 locked mutexes and 8 plugins loaded!
2578
Yate (8914) is stopping Thu Feb 22 21:24:00 2018
2579
Unloading module GSM Transceiver
2580
2018-02-22_21:24:00.009935 >>> Plugin::~Plugin() "gsmtrx" [0x7f57e5afe060]
2581
2018-02-22_21:24:00.009983 <<< Plugin::~Plugin()
2582
Unloaded module SNMP Agent
2583
2018-02-22_21:24:00.010213 >>> Plugin::~Plugin() "snmpagent" [0x7f57e6150f40]
2584
2018-02-22_21:24:00.010231 <<< Plugin::~Plugin()
2585
Unloading module YBTS
2586
2018-02-22_21:24:00.010292 >>> Plugin::~Plugin() "ybts" [0x7f57e69fb440]
2587
2018-02-22_21:24:00.010310 <<< Plugin::~Plugin()
2588
Unloading module Cisco SM
2589
2018-02-22_21:24:00.010444 >>> Plugin::~Plugin() "ciscosm" [0x7f57e986f540]
2590
2018-02-22_21:24:00.010465 <<< Plugin::~Plugin()
2591
Unloading module YSOCKS
2592
2018-02-22_21:24:00.010902 >>> Plugin::~Plugin() "socks" [0x7f57ec119960]
2593
2018-02-22_21:24:00.010921 <<< Plugin::~Plugin()
2594
Unloading module Javascript
2595
2018-02-22_21:24:00.010991 >>> Plugin::~Plugin() "javascript" [0x7f57ed4068a0]
2596
2018-02-22_21:24:00.011009 <<< Plugin::~Plugin()
2597
Unloading module YJingle
2598
2018-02-22_21:24:00.011070 >>> Plugin::~Plugin() "jingle" [0x7f57edebfa40]
2599
2018-02-22_21:24:00.011088 <<< Plugin::~Plugin()
2600
Unloading module ExtModule
2601
2018-02-22_21:24:00.011196 >>> Plugin::~Plugin() "extmodule" [0x7f57f1212940]
2602
2018-02-22_21:24:00.011207 <<< Plugin::~Plugin()
2603
lifebook:~ #(B exit
(6-6/7)
Add picture from clipboard (Maximum size: 48.8 MB)