BladeRF - YateBTS Segmentation Fault

[lifelonglearnerall]

Hi! I have a trouble with bladeRFx40 and YateBTS on Ubuntu 18.04. Yate works normally for a while but it stops working after a few minutes.

Versions info is shown below;

I run the following command;

$ sudo yate -vvv

The device successfully work and I can connect my phone to gsm but after a while, Yate turns itself off with "Segmentation Fault".

All Yate outputs are shown below;

Yate (19141) is starting Sat Aug 28 10:16:28 2021
2021-08-28_10:16:28.493763 <ALL> Plugin::Plugin("stun",false) [0x7f8f604383a0]
Loaded module YSTUN
2021-08-28_10:16:28.494076 <ALL> Plugin::Plugin("yrtp",false) [0x7f8f6022e120]
Loaded module YRTP
2021-08-28_10:16:28.494365 <ALL> Plugin::Plugin("extmodule",false) [0x7f8f60005960]
Loaded module ExtModule
2021-08-28_10:16:28.495221 <ALL> Plugin::Plugin("jingle",false) [0x7f8f5fdefae0]
Loaded module YJingle
2021-08-28_10:16:28.495363 <ALL> Plugin::Plugin("cdrcombine",false) [0x7f8f5f95c900]
Loaded module CdrCombine
2021-08-28_10:16:28.496146 <ALL> Plugin::Plugin("javascript",true) [0x7f8f5f756460]
Loaded module Javascript
2021-08-28_10:16:28.496264 <ALL> Plugin::Plugin("regexroute",false) [0x7f8f5f2c9300]
Loaded module RegexRoute
2021-08-28_10:16:28.496367 <ALL> Plugin::Plugin("tone",false) [0x7f8f5f0b96c0]
Loaded module ToneGen
2021-08-28_10:16:28.496382 <tone:ALL> Building comfort noise at level -10
2021-08-28_10:16:28.496457 <tone:ALL> Building tone of 1336 + 941 Hz
2021-08-28_10:16:28.496924 <tone:ALL> Building tone of 1209 + 697 Hz
2021-08-28_10:16:28.497360 <tone:ALL> Building tone of 1336 + 697 Hz
2021-08-28_10:16:28.497837 <tone:ALL> Building tone of 1477 + 697 Hz
2021-08-28_10:16:28.498315 <tone:ALL> Building tone of 1209 + 770 Hz
2021-08-28_10:16:28.498783 <tone:ALL> Building tone of 1336 + 770 Hz
2021-08-28_10:16:28.499263 <tone:ALL> Building tone of 1477 + 770 Hz
2021-08-28_10:16:28.499687 <tone:ALL> Building tone of 1209 + 852 Hz
2021-08-28_10:16:28.500081 <tone:ALL> Building tone of 1336 + 852 Hz
2021-08-28_10:16:28.500472 <tone:ALL> Building tone of 1477 + 852 Hz
2021-08-28_10:16:28.500893 <tone:ALL> Building tone of 1209 + 941 Hz
2021-08-28_10:16:28.501233 <tone:ALL> Building tone of 1477 + 941 Hz
2021-08-28_10:16:28.501584 <tone:ALL> Building tone of 1633 + 697 Hz
2021-08-28_10:16:28.501945 <tone:ALL> Building tone of 1633 + 770 Hz
2021-08-28_10:16:28.502314 <tone:ALL> Building tone of 1633 + 852 Hz
2021-08-28_10:16:28.502632 <tone:ALL> Building tone of 1633 + 941 Hz
2021-08-28_10:16:28.502946 <tone:ALL> Building tone of 2000 + 125 Hz
2021-08-28_10:16:28.503874 <tone:ALL> Building tone of 2000 modulated by 1000 Hz
2021-08-28_10:16:28.505013 <tone:ALL> Building tone of 2010 Hz
2021-08-28_10:16:28.505094 <tone:ALL> Building tone of 1780 Hz
2021-08-28_10:16:28.505496 <ALL> Plugin::Plugin("sip",false) [0x7f8f5eea7da0]
Loaded module SIP Channel
2021-08-28_10:16:28.505614 <ALL> Plugin::Plugin("wiresniff",false) [0x7f8f5ec38240]
Loaded module WireSniff
2021-08-28_10:16:28.505715 <ALL> Plugin::Plugin("conf",false) [0x7f8f5ea32480]
Loaded module Conference
2021-08-28_10:16:28.505935 <ALL> Plugin::Plugin("iax",false) [0x7f8f5e8234c0]
Loaded module YIAX
2021-08-28_10:16:28.506008 <ALL> Plugin::Plugin("cdrbuild",false) [0x7f8f5e5e7220]
Loaded module CdrBuild
2021-08-28_10:16:28.506087 <ALL> Plugin::Plugin("mux",true) [0x7f8f5e3da600]
Loaded module MUX
2021-08-28_10:16:28.506166 <ALL> Plugin::Plugin("socks",true) [0x7f8f5e1cf960]
Loaded module YSOCKS
2021-08-28_10:16:28.506233 <ALL> Plugin::Plugin("tonedetect",false) [0x7f8f5dfb82e0]
Loaded module ToneDetector
2021-08-28_10:16:28.506299 <ALL> Plugin::Plugin("fileinfo",false) [0x7f8f5ddafb20]
Loaded module FileInfo
2021-08-28_10:16:28.506357 <ALL> Plugin::Plugin("cdrfile",true) [0x7f8f5dba4240]
Loaded module CdrFile
2021-08-28_10:16:28.506421 <ALL> Plugin::Plugin("gvoice",false) [0x7f8f5d99f460]
Loaded module GVoice
2021-08-28_10:16:28.506485 <ALL> Plugin::Plugin("dumb",false) [0x7f8f5d7992a0]
Loaded module DumbChannel
2021-08-28_10:16:28.506549 <ALL> Plugin::Plugin("enumroute",false) [0x7f8f5d593300]
2021-08-28_10:16:28.506776 <ALL> Plugin::Plugin("isaccodec",false) [0x7f8f5d38b6e0]
Loaded module iSAC floating point - based on WebRTC iSAC library version 4.3.0 (SPL version 1.2.0)
2021-08-28_10:16:28.506899 <ALL> Plugin::Plugin("zlibcompress",true) [0x7f8f5d150220]
Loaded module ZLib - using zlib library version 1.2.11
2021-08-28_10:16:28.506969 <ALL> Plugin::Plugin("msgsniff",false) [0x7f8f5cd2c220]
Loaded module MsgSniffer
2021-08-28_10:16:28.507061 <ALL> Plugin::Plugin("filetransfer",false) [0x7f8f5cb26820]
Loaded module File Transfer
2021-08-28_10:16:28.507137 <ALL> Plugin::Plugin("g722webrtc",false) [0x7f8f5c916240]
Loaded module G722 - based on WebRTC G.722 library version 2.0.0
2021-08-28_10:16:28.507227 <ALL> Plugin::Plugin("rmanager",false) [0x7f8f5c70e600]
Loaded module RManager
2021-08-28_10:16:28.507308 <ALL> Plugin::Plugin("analyzer",false) [0x7f8f5c4fc420]
Loaded module Analyzer
2021-08-28_10:16:28.507388 <ALL> Plugin::Plugin("moh",false) [0x7f8f5c2f1400]
Loaded module MOH
2021-08-28_10:16:28.507461 <ALL> Plugin::Plugin("callgen",false) [0x7f8f5c0e8360]
Loaded module Call Generator
2021-08-28_10:16:28.507518 <ALL> Plugin::Plugin("pbx",false) [0x7f8f5bedd480]
Loaded module PBX
2021-08-28_10:16:28.507674 <ALL> Plugin::Plugin("ilbcwebrtc",false) [0x7f8f5bcd6580]
Loaded module iLBC - based on WebRTC iLBC library version 1.1.1
2021-08-28_10:16:28.507792 <ALL> Plugin::Plugin("wave",false) [0x7f8f5babf9c0]
Loaded module WaveFile
2021-08-28_10:16:28.508955 <ALL> Plugin::Plugin("openssl",true) [0x7f8f5b8af5a0]
Loaded module OpenSSL - based on OpenSSL 1.1.1  11 Sep 2018
2021-08-28_10:16:28.509099 <ALL> Plugin::Plugin("ilbccodec",false) [0x7f8f5af4c000]
Loaded module iLBC - based on iLBC reference library
2021-08-28_10:16:28.509177 <ALL> Plugin::Plugin("callfork",false) [0x7f8f5ad2b420]
Loaded module Call Forker
2021-08-28_10:16:28.509288 <ALL> Plugin::Plugin("gsmcodec",false) [0x7f8f5ab20140]
Loaded module GSM - based on libgsm-1.0.10
2021-08-28_10:16:28.509417 <ALL> Plugin::Plugin("sipfeatures",false) [0x7f8f5a70e380]
Loaded module SIP Features
2021-08-28_10:16:28.509973 <ALL> Plugin::Plugin("snmpagent",false) [0x7f8f5a504f40]
Loaded module SNMP Agent
2021-08-28_10:16:28.510063 <ALL> Plugin::Plugin("pbxassist",false) [0x7f8f5a0b93c0]
2021-08-28_10:16:28.510128 <ALL> Plugin::Plugin("park",false) [0x7f8f59ea9240]
Loaded module Call Parking
2021-08-28_10:16:28.510199 <ALL> Plugin::Plugin("cache",false) [0x7f8f59ca3920]
Loaded module Cache
2021-08-28_10:16:28.510262 <ALL> Plugin::Plugin("mrcp",false) [0x7f8f59a93280]
Loaded module MRCP
2021-08-28_10:16:28.510319 <ALL> Plugin::Plugin("users",false) [0x7f8f5988b260]
Loaded module Users Management
2021-08-28_10:16:28.510418 <ALL> Plugin::Plugin("analogdetect",true) [0x7f8f596849c0]
Loaded module Analog Detector
2021-08-28_10:16:28.510530 <libyatemodem:INFO> Initialized filter tables for type 'etsi' headerlen=6400
2021-08-28_10:16:28.510579 <ALL> Plugin::Plugin("callcounters",false) [0x7f8f594751c0]
Loaded module CallCounters
2021-08-28_10:16:28.510815 <ALL> Plugin::Plugin("mgcpgw",false) [0x7f8f5926f100]
Loaded module MGCP-GW
2021-08-28_10:16:28.512329 <ALL> Plugin::Plugin("sig",false) [0x7f8f58e40140]
Loaded module Signalling Channel
2021-08-28_10:16:28.512423 <ALL> Plugin::Plugin("monitoring",false) [0x7f8f588cc280]
Loaded module Monitoring
2021-08-28_10:16:28.512529 <ALL> Plugin::Plugin("sigtransport",true) [0x7f8f586b0700]
Loaded module SigTransport
2021-08-28_10:16:28.512585 <ALL> Plugin::Plugin("lateroute",false) [0x7f8f584a0240]
Loaded module Late Router
2021-08-28_10:16:28.512752 <ALL> Plugin::Plugin("mgcpca",true) [0x7f8f5829b260]
Loaded module MGCP-CA
2021-08-28_10:16:28.512819 <ALL> Plugin::Plugin("presence",false) [0x7f8f580732a0]
Loaded module Presence
2021-08-28_10:16:28.513165 <ALL> Plugin::Plugin("ybts",false) [0x7f8f57e66b00]
Loaded module YBTS
2021-08-28_10:16:28.513249 <ALL> Plugin::Plugin("queuesnotify",false) [0x7f8f579cd480]
Loaded module Queues Notify
2021-08-28_10:16:28.513413 <ALL> Plugin::Plugin("sqlitedb",true) [0x7f8f577c4380]
Loaded module SQLite based on 3.22.0
2021-08-28_10:16:28.513526 <ALL> Plugin::Plugin("register",false) [0x7f8f572b2600]
Loaded module Register for database
2021-08-28_10:16:28.513625 <ALL> Plugin::Plugin("dbpbx",false) [0x7f8f570a4280]
Loaded module PBX for database
2021-08-28_10:16:28.513686 <ALL> Plugin::Plugin("heartbeat",false) [0x7f8f56e9c260]
Loaded module Heartbeat
2021-08-28_10:16:28.513787 <ALL> Plugin::Plugin("ciscosm",true) [0x7f8f56c96540]
Loaded module Cisco SM
2021-08-28_10:16:28.513874 <ALL> Plugin::Plugin("accfile",false) [0x7f8f56a852e0]
Loaded module Accounts from file
2021-08-28_10:16:28.513934 <ALL> Plugin::Plugin("clustering",true) [0x7f8f5687f1e0]
Loaded module Clustering
2021-08-28_10:16:28.514054 <ALL> Plugin::Plugin("analog",false) [0x7f8f566796a0]
Loaded module Analog Channel
2021-08-28_10:16:28.514133 <ALL> Plugin::Plugin("subscription",true) [0x7f8f564607a0]
Loaded module Subscriptions
2021-08-28_10:16:28.514196 <ALL> Plugin::Plugin("queues",false) [0x7f8f5624c580]
Loaded module Queues
2021-08-28_10:16:28.514605 <ALL> Plugin::Plugin("gsmtrx",false) [0x7f8f56041d00]
Loaded module GSM Transceiver
2021-08-28_10:16:28.514672 <ALL> Plugin::Plugin("dbwave",true) [0x7f8f55e07260]
Loaded module DbWave
2021-08-28_10:16:28.514745 <ALL> Plugin::Plugin("regfile",false) [0x7f8f55c01380]
Loaded module Registration from file
2021-08-28_10:16:28.514828 <ALL> Plugin::Plugin("yradius",false) [0x7f8f559f8a40]
Loaded module Radius client
2021-08-28_10:16:28.514887 <ALL> Plugin::Plugin("ccongestion",false) [0x7f8f557e72c0]
Loaded module CCongestion
2021-08-28_10:16:28.514952 <ALL> Plugin::Plugin("cpuload",true) [0x7f8f555e13e0]
Loaded module Cpu
2021-08-28_10:16:28.515068 <ALL> Plugin::Plugin("eventlogs",true) [0x7f8f553d82e0]
Loaded module Event Logs
2021-08-28_10:16:28.515182 <ALL> Plugin::Plugin("jbfeatures",true) [0x7f8f551d3420]
Loaded module Jabber Server Features
2021-08-28_10:16:28.515350 <ALL> Plugin::Plugin("jabber",true) [0x7f8f54fc6d20]
Loaded module Jabber Server
2021-08-28_10:16:28.515465 <ALL> Plugin::Plugin("radiotest",false) [0x7f8f54d9bce0]
Loaded module Radio Test
2021-08-28_10:16:28.515913 <ALL> Plugin::Plugin("bladerf",true) [0x7f8f54b8ada0]
Loaded module BladeRF using libusb 1.0.21.11156 desc='http://libusb.info'
2021-08-28_10:16:28.516021 <ALL> Plugin::Plugin("dummyradio",true) [0x7f8f54099700]
Loaded module DummyRadio
2021-08-28_10:16:28.516042 <ALL> Loaded 77 plugins
Initializing plugins
Initializing module DummyRadio
Initializing module BladeRF
Initializing module Jabber Server
2021-08-28_10:16:28.516229 <jabber:ALL> JBPendingWorker(0) start running [0x5645b7c20d60]
2021-08-28_10:16:28.516296 <jbserverengine:NOTE> Failed to load entity caps from '/usr/local/etc/yate/jabberentitycaps.xml': I/O error 2 'No such file or directory'
2021-08-28_10:16:28.516312 <jbserverengine:NOTE> No domains configured
Initializing module Jabber Server Features
Initializing module Event Logs
Initializing module Cpu
2021-08-28_10:16:28.516610 <cpuload:NOTE> Updating CPU core number from 1 to 8
Initializing module DbWave
Initializing module Subscriptions
Initializing module Clustering
Initializing module Cisco SM
Initializing module SQLite
Initializing module MGCP Call Agent
2021-08-28_10:16:28.516838 <mgcpca:ALL> No gateways defined so module not initialized.
2021-08-28_10:16:28.518174 <mgcpca:ALL> Initialized audio codecs: mulaw,alaw,gsm,slin,g722/16000,ilbc20,ilbc30,isac/16000,isac/32000
Initializing module SigTransport
Initializing module Analog Detector
Initializing module OpenSSL
Initializing module ZLib
2021-08-28_10:16:28.519148 <zlibcompress:ALL> Initialized compressor_buflen=256 decompressor_buflen=1024 compress_level=default
Initializing module CdrFile
Initializing module YSOCKS
2021-08-28_10:16:28.519227 <socks:INFO> Initialized auth-timeout=10000ms reply-timeout=30000ms print-msg=false print-extended=false
Initializing module MUX
Initializing module Javascript
2021-08-28_10:16:28.521300 <javascript:INFO> Parsed 'eliza' script: /usr/local/share/yate/scripts/eliza.js
Initializing module YSTUN
2021-08-28_10:16:28.521867 <stun:ALL> Bind request interval set to 15000 msec.
Initializing module YRTP
Initializing module ExtModule
Initializing module YJingle
2021-08-28_10:16:28.522108 <jgengine:ALL> Jingle engine initialized: jingle_flags=0 stanza_timeout=20000 ping_interval=300000 [0x5645b7cf8530]
2021-08-28_10:16:28.522121 <jingle:ALL> YJGEngineWorker start running
Initializing module CdrCombine
Initializing module RegexRoute
Initializing module ToneGen
2021-08-28_10:16:28.522628 <tone:ALL> ToneDesc(at/dial) initializing from '420' [0x5645b7d100f0]
2021-08-28_10:16:28.522641 <tone:ALL> Building tone of 420 Hz
2021-08-28_10:16:28.522728 <tone:ALL> ToneDesc(at/busy) initializing from '420/400,0/400' [0x5645b7cfe540]
2021-08-28_10:16:28.522735 <tone:ALL> ToneDesc(at/ring) initializing from '420/1000,0/5000' [0x5645b7cfe750]
2021-08-28_10:16:28.522741 <tone:ALL> ToneDesc(at/congestion) initializing from '420/200,0/200' [0x5645b7cfe8f0]
2021-08-28_10:16:28.522747 <tone:ALL> ToneDesc(at/callwaiting) initializing from '420/40,0/1960' [0x5645b7cfeb10]
2021-08-28_10:16:28.522754 <tone:ALL> ToneDesc(at/dialrecall) initializing from '420' [0x5645b7cfed30]
2021-08-28_10:16:28.522759 <tone:ALL> ToneDesc(at/record) initializing from '1400/80,0/14920' [0x5645b7cfee90]
2021-08-28_10:16:28.522764 <tone:ALL> Building tone of 1400 Hz
2021-08-28_10:16:28.522877 <tone:ALL> ToneDesc(at/info) initializing from '950/330,1450/330,1850/330,0/1000' [0x5645b7cff230]
2021-08-28_10:16:28.522883 <tone:ALL> Building tone of 950 Hz
2021-08-28_10:16:28.522969 <tone:ALL> Building tone of 1450 Hz
2021-08-28_10:16:28.523057 <tone:ALL> Building tone of 1850 Hz
2021-08-28_10:16:28.523146 <tone:ALL> ToneDesc(at/stutter) initializing from '380+420' [0x5645b7d1f080]
2021-08-28_10:16:28.523151 <tone:ALL> Building tone of 420 + 380 Hz
2021-08-28_10:16:28.523503 <tone:ALL> ToneDesc(au/dial) initializing from '413+438' [0x5645b7d23100]
2021-08-28_10:16:28.523509 <tone:ALL> Building tone of 438 + 413 Hz
2021-08-28_10:16:28.523869 <tone:ALL> ToneDesc(au/busy) initializing from '425/375,0/375' [0x5645b7d27240]
2021-08-28_10:16:28.523876 <tone:ALL> Building tone of 425 Hz
2021-08-28_10:16:28.524048 <tone:ALL> ToneDesc(au/ring) initializing from '413+438/400,0/200,413+438/400,0/2000' [0x5645b7d2b490]
2021-08-28_10:16:28.524058 <tone:ALL> ToneDesc(au/congestion) initializing from '425/375,0/375,420/375,0/375' [0x5645b7d2b760]
2021-08-28_10:16:28.524072 <tone:ALL> ToneDesc(au/callwaiting) initializing from '425/200,0/200,425/200,0/4400' [0x5645b7d2b9c0]
2021-08-28_10:16:28.524083 <tone:ALL> ToneDesc(au/dialrecall) initializing from '413+438' [0x5645b7d2bc20]
2021-08-28_10:16:28.524091 <tone:ALL> ToneDesc(au/record) initializing from '!425/1000,!0/15000,425/360,0/15000' [0x5645b7d2bd90]
2021-08-28_10:16:28.524103 <tone:ALL> ToneDesc(au/info) initializing from '425/2500,0/500' [0x5645b7d2c050]
2021-08-28_10:16:28.524111 <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' [0x5645b7d2c1d0]
2021-08-28_10:16:28.524118 <tone:ALL> Building tone of 525 Hz
2021-08-28_10:16:28.524341 <tone:ALL> ToneDesc(au/facility) initializing from '425' [0x5645b7d305d0]
2021-08-28_10:16:28.524348 <tone:ALL> ToneDesc(au/stutter) initializing from '413+438/100,0/40' [0x5645b7d30730]
2021-08-28_10:16:28.524356 <tone:ALL> ToneDesc(au/ringmobile) initializing from '400+450/400,0/200,400+450/400,0/2000' [0x5645b7d308b0]
2021-08-28_10:16:28.524362 <tone:ALL> Building tone of 450 + 400 Hz
2021-08-28_10:16:28.524919 <tone:ALL> ToneDesc(bg/dial) initializing from '425' [0x5645b7d34990]
2021-08-28_10:16:28.524926 <tone:ALL> ToneDesc(bg/busy) initializing from '425/500,0/500' [0x5645b7d34af0]
2021-08-28_10:16:28.524932 <tone:ALL> ToneDesc(bg/ring) initializing from '425/1000,0/4000' [0x5645b7d34c70]
2021-08-28_10:16:28.524939 <tone:ALL> ToneDesc(bg/congestion) initializing from '425/250,0/250' [0x5645b7d34e60]
2021-08-28_10:16:28.524949 <tone:ALL> ToneDesc(bg/callwaiting) initializing from '425/150,0/150,425/150,0/4000' [0x5645b7d35080]
2021-08-28_10:16:28.524957 <tone:ALL> ToneDesc(bg/dialrecall) initializing from '!425/100,!0/100,!425/100,!0/100,!425/100,!0/100,425' [0x5645b7d353e0]
2021-08-28_10:16:28.524967 <tone:ALL> ToneDesc(bg/record) initializing from '1400/425,0/15000' [0x5645b7d35760]
2021-08-28_10:16:28.524974 <tone:ALL> ToneDesc(bg/info) initializing from '950/330,1400/330,1800/330,0/1000' [0x5645b7d358e0]
2021-08-28_10:16:28.524981 <tone:ALL> Building tone of 1800 Hz
2021-08-28_10:16:28.525120 <tone:ALL> ToneDesc(bg/stutter) initializing from '425/1500,0/100' [0x5645b7d37ab0]
2021-08-28_10:16:28.525130 <tone:ALL> ToneDesc(br/dial) initializing from '425' [0x5645b7d37c30]
2021-08-28_10:16:28.525136 <tone:ALL> ToneDesc(br/busy) initializing from '425/250,0/250' [0x5645b7d37d90]
2021-08-28_10:16:28.525145 <tone:ALL> ToneDesc(br/ring) initializing from '425/1000,0/4000' [0x5645b7d37fd0]
2021-08-28_10:16:28.525152 <tone:ALL> ToneDesc(br/congestion) initializing from '425/250,0/250,425/750,0/250' [0x5645b7d381f0]
2021-08-28_10:16:28.525161 <tone:ALL> ToneDesc(br/callwaiting) initializing from '425/50,0/1000' [0x5645b7d38550]
2021-08-28_10:16:28.525168 <tone:ALL> ToneDesc(br/dialrecall) initializing from '350+440' [0x5645b7d386d0]
2021-08-28_10:16:28.525174 <tone:ALL> Building tone of 440 + 350 Hz
2021-08-28_10:16:28.525619 <tone:ALL> ToneDesc(br/record) initializing from '425/250,0/250' [0x5645b7d3c790]
2021-08-28_10:16:28.525627 <tone:ALL> ToneDesc(br/info) initializing from '950/330,1400/330,1800/330' [0x5645b7d3ca10]
2021-08-28_10:16:28.525635 <tone:ALL> ToneDesc(br/stutter) initializing from '350+440' [0x5645b7d3ccd0]
2021-08-28_10:16:28.525643 <tone:ALL> ToneDesc(be/dial) initializing from '425' [0x5645b7d3ce30]
2021-08-28_10:16:28.525650 <tone:ALL> ToneDesc(be/busy) initializing from '425/500,0/500' [0x5645b7d3cf90]
2021-08-28_10:16:28.525666 <tone:ALL> ToneDesc(be/ring) initializing from '425/1000,0/3000' [0x5645b7d3d250]
2021-08-28_10:16:28.525673 <tone:ALL> ToneDesc(be/congestion) initializing from '425/167,0/167' [0x5645b7d3d470]
2021-08-28_10:16:28.525680 <tone:ALL> ToneDesc(be/callwaiting) initializing from '1400/175,0/175,1400/175,0/3500' [0x5645b7d3d690]
2021-08-28_10:16:28.525689 <tone:ALL> ToneDesc(be/dialrecall) initializing from '!350+440/100,!0/100,!350+440/100,!0/100,!350+440/100,!0/100,350+440"' [0x5645b7d3d9f0]
2021-08-28_10:16:28.525700 <tone:ALL> ToneDesc(be/record) initializing from '1400/500,0/15000' [0x5645b7d3de20]
2021-08-28_10:16:28.525710 <tone:ALL> ToneDesc(be/info) initializing from '900/330,1400/330,1800/330,0/1000' [0x5645b7d3dfa0]
2021-08-28_10:16:28.525716 <tone:ALL> Building tone of 900 Hz
2021-08-28_10:16:28.525841 <tone:ALL> ToneDesc(be/stutter) initializing from '425/1000,0/250' [0x5645b7d40170]
2021-08-28_10:16:28.525849 <tone:ALL> ToneDesc(ch/dial) initializing from '425' [0x5645b7d402f0]
2021-08-28_10:16:28.525855 <tone:ALL> ToneDesc(ch/busy) initializing from '425/500,0/500' [0x5645b7d40450]
2021-08-28_10:16:28.525862 <tone:ALL> ToneDesc(ch/ring) initializing from '425/1000,0/4000' [0x5645b7d40690]
2021-08-28_10:16:28.525869 <tone:ALL> ToneDesc(ch/congestion) initializing from '425/200,0/200' [0x5645b7d408b0]
2021-08-28_10:16:28.525876 <tone:ALL> ToneDesc(ch/callwaiting) initializing from '425/200,0/200,425/200,0/4000' [0x5645b7d40ad0]
2021-08-28_10:16:28.525884 <tone:ALL> ToneDesc(ch/dialrecall) initializing from '!425/100,!0/100,!425/100,!0/100,!425/100,!0/100,425' [0x5645b7d40e30]
2021-08-28_10:16:28.525897 <tone:ALL> ToneDesc(ch/record) initializing from '1400/80,0/15000' [0x5645b7d41250]
2021-08-28_10:16:28.525904 <tone:ALL> ToneDesc(ch/info) initializing from '950/330,1400/330,1800/330,0/1000' [0x5645b7d413d0]
2021-08-28_10:16:28.525912 <tone:ALL> ToneDesc(ch/stutter) initializing from '425+340/1100,0/1100' [0x5645b7d41590]
2021-08-28_10:16:28.525918 <tone:ALL> Building tone of 425 + 340 Hz
2021-08-28_10:16:28.526332 <tone:ALL> ToneDesc(cl/dial) initializing from '400' [0x5645b7d45630]
2021-08-28_10:16:28.526337 <tone:ALL> Building tone of 400 Hz
2021-08-28_10:16:28.526503 <tone:ALL> ToneDesc(cl/busy) initializing from '400/500,0/500' [0x5645b7d477d0]
2021-08-28_10:16:28.526511 <tone:ALL> ToneDesc(cl/ring) initializing from '400/1000,0/3000' [0x5645b7d47a70]
2021-08-28_10:16:28.526518 <tone:ALL> ToneDesc(cl/congestion) initializing from '400/200,0/200' [0x5645b7d47c90]
2021-08-28_10:16:28.526525 <tone:ALL> ToneDesc(cl/callwaiting) initializing from '400/250,0/8750' [0x5645b7d47eb0]
2021-08-28_10:16:28.526533 <tone:ALL> ToneDesc(cl/dialrecall) initializing from '!400/100,!0/100,!400/100,!0/100,!400/100,!0/100,400' [0x5645b7d480d0]
2021-08-28_10:16:28.526543 <tone:ALL> ToneDesc(cl/record) initializing from '1400/500,0/15000' [0x5645b7d485f0]
2021-08-28_10:16:28.526551 <tone:ALL> ToneDesc(cl/info) initializing from '950/333,1400/333,1800/333,0/1000' [0x5645b7d48770]
2021-08-28_10:16:28.526558 <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' [0x5645b7d48930]
2021-08-28_10:16:28.526575 <tone:ALL> ToneDesc(cn/dial) initializing from '450' [0x5645b7d490b0]
2021-08-28_10:16:28.526581 <tone:ALL> Building tone of 450 Hz
2021-08-28_10:16:28.526695 <tone:ALL> ToneDesc(cn/busy) initializing from '450/350,0/350' [0x5645b7d48b10]
2021-08-28_10:16:28.526704 <tone:ALL> ToneDesc(cn/ring) initializing from '450/1000,0/4000' [0x5645b7d48cc0]
2021-08-28_10:16:28.526712 <tone:ALL> ToneDesc(cn/congestion) initializing from '450/700,0/700' [0x5645b7d4b210]
2021-08-28_10:16:28.526719 <tone:ALL> ToneDesc(cn/callwaiting) initializing from '450/400,0/4000' [0x5645b7d4b400]
2021-08-28_10:16:28.526726 <tone:ALL> ToneDesc(cn/dialrecall) initializing from '450' [0x5645b7d4b620]
2021-08-28_10:16:28.526732 <tone:ALL> ToneDesc(cn/record) initializing from '950/400,0/10000' [0x5645b7d4b780]
2021-08-28_10:16:28.526740 <tone:ALL> ToneDesc(cn/info) initializing from '450/100,0/100,450/100,0/100,450/100,0/100,450/400,0/400' [0x5645b7d4ba00]
2021-08-28_10:16:28.526751 <tone:ALL> ToneDesc(cn/stutter) initializing from '450+425' [0x5645b7d4bef0]
2021-08-28_10:16:28.526758 <tone:ALL> Building tone of 450 + 425 Hz
2021-08-28_10:16:28.527194 <tone:ALL> ToneDesc(cz/dial) initializing from '425/330,0/330,425/660,0/660' [0x5645b7d4bd10]
2021-08-28_10:16:28.527205 <tone:ALL> ToneDesc(cz/busy) initializing from '425/330,0/330' [0x5645b7d500f0]
2021-08-28_10:16:28.527214 <tone:ALL> ToneDesc(cz/ring) initializing from '425/1000,0/4000' [0x5645b7d50230]
2021-08-28_10:16:28.527222 <tone:ALL> ToneDesc(cz/congestion) initializing from '425/165,0/165' [0x5645b7d50420]
2021-08-28_10:16:28.527229 <tone:ALL> ToneDesc(cz/callwaiting) initializing from '425/330,0/9000' [0x5645b7d50640]
2021-08-28_10:16:28.527236 <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' [0x5645b7d50860]
2021-08-28_10:16:28.527249 <tone:ALL> ToneDesc(cz/record) initializing from '1400/500,0/14000' [0x5645b7d50ea0]
2021-08-28_10:16:28.527256 <tone:ALL> ToneDesc(cz/info) initializing from '950/330,0/30,1400/330,0/30,1800/330,0/1000' [0x5645b7d50ab0]
2021-08-28_10:16:28.527270 <tone:ALL> ToneDesc(cz/stutter) initializing from '425/450,0/50' [0x5645b7d51130]
2021-08-28_10:16:28.527279 <tone:ALL> ToneDesc(de/dial) initializing from '425' [0x5645b7d512b0]
2021-08-28_10:16:28.527286 <tone:ALL> ToneDesc(de/busy) initializing from '425/480,0/480' [0x5645b7d51410]
2021-08-28_10:16:28.527293 <tone:ALL> ToneDesc(de/ring) initializing from '425/1000,0/4000' [0x5645b7d515b0]
2021-08-28_10:16:28.527301 <tone:ALL> ToneDesc(de/congestion) initializing from '425/240,0/240' [0x5645b7d51770]
2021-08-28_10:16:28.527308 <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' [0x5645b7d51990]
2021-08-28_10:16:28.527327 <tone:ALL> ToneDesc(de/dialrecall) initializing from '!425/100,!0/100,!425/100,!0/100,!425/100,!0/100,425' [0x5645b7d526f0]
2021-08-28_10:16:28.527338 <tone:ALL> ToneDesc(de/record) initializing from '1400/80,0/15000' [0x5645b7d52910]
2021-08-28_10:16:28.527346 <tone:ALL> ToneDesc(de/info) initializing from '950/330,1400/330,1800/330,0/1000' [0x5645b7d52a90]
2021-08-28_10:16:28.527357 <tone:ALL> ToneDesc(de/stutter) initializing from '425+400' [0x5645b7d52c50]
2021-08-28_10:16:28.527363 <tone:ALL> Building tone of 425 + 400 Hz
2021-08-28_10:16:28.527939 <tone:ALL> ToneDesc(dk/dial) initializing from '425' [0x5645b7d52390]
2021-08-28_10:16:28.527952 <tone:ALL> ToneDesc(dk/busy) initializing from '425/500,0/500' [0x5645b7d51d10]
2021-08-28_10:16:28.527962 <tone:ALL> ToneDesc(dk/ring) initializing from '425/1000,0/4000' [0x5645b7d51f10]
2021-08-28_10:16:28.527970 <tone:ALL> ToneDesc(dk/congestion) initializing from '425/200,0/200' [0x5645b7d56d10]
2021-08-28_10:16:28.527978 <tone:ALL> ToneDesc(dk/callwaiting) initializing from '!425/200,!0/600,!425/200,!0/3000,!425/200,!0/200,!425/200,0' [0x5645b7d56f30]
2021-08-28_10:16:28.527992 <tone:ALL> ToneDesc(dk/dialrecall) initializing from '!425/100,!0/100,!425/100,!0/100,!425/100,!0/100,425' [0x5645b7d57430]
2021-08-28_10:16:28.528002 <tone:ALL> ToneDesc(dk/record) initializing from '1400/80,0/15000' [0x5645b7d57650]
2021-08-28_10:16:28.528009 <tone:ALL> ToneDesc(dk/info) initializing from '950/330,1400/330,1800/330,0/1000' [0x5645b7d577d0]
2021-08-28_10:16:28.528018 <tone:ALL> ToneDesc(dk/stutter) initializing from '425/450,0/50' [0x5645b7d57990]
2021-08-28_10:16:28.528028 <tone:ALL> ToneDesc(ee/dial) initializing from '425' [0x5645b7d57b10]
2021-08-28_10:16:28.528036 <tone:ALL> ToneDesc(ee/busy) initializing from '425/300,0/300' [0x5645b7d57c70]
2021-08-28_10:16:28.528044 <tone:ALL> ToneDesc(ee/ring) initializing from '425/1000,0/4000' [0x5645b7d57e50]
2021-08-28_10:16:28.528054 <tone:ALL> ToneDesc(ee/congestion) initializing from '425/200,0/200' [0x5645b7d58070]
2021-08-28_10:16:28.528063 <tone:ALL> ToneDesc(ee/callwaiting) initializing from '950/650,0/325,950/325,0/30,1400/1300,0/2600' [0x5645b7d58290]
2021-08-28_10:16:28.528074 <tone:ALL> ToneDesc(ee/dialrecall) initializing from '425/650,0/25' [0x5645b7d58730]
2021-08-28_10:16:28.528082 <tone:ALL> ToneDesc(ee/record) initializing from '1400/500,0/15000' [0x5645b7d588b0]
2021-08-28_10:16:28.528091 <tone:ALL> ToneDesc(ee/info) initializing from '950/650,0/325,950/325,0/30,1400/1300,0/2600' [0x5645b7d58a30]
2021-08-28_10:16:28.528101 <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' [0x5645b7d58dd0]
2021-08-28_10:16:28.528121 <tone:ALL> ToneDesc(es/dial) initializing from '425' [0x5645b7d594b0]
2021-08-28_10:16:28.528129 <tone:ALL> ToneDesc(es/busy) initializing from '425/200,0/200' [0x5645b7d59610]
2021-08-28_10:16:28.528137 <tone:ALL> ToneDesc(es/ring) initializing from '425/1500,0/3000' [0x5645b7d59790]
2021-08-28_10:16:28.528145 <tone:ALL> ToneDesc(es/congestion) initializing from '425/200,0/200,425/200,0/200,425/200,0/600' [0x5645b7d59910]
2021-08-28_10:16:28.528155 <tone:ALL> ToneDesc(es/callwaiting) initializing from '425/175,0/175,425/175,0/3500' [0x5645b7d59b30]
2021-08-28_10:16:28.528165 <tone:ALL> ToneDesc(es/dialrecall) initializing from '!425/200,!0/200,!425/200,!0/200,!425/200,!0/200,425' [0x5645b7d59cf0]
2021-08-28_10:16:28.528176 <tone:ALL> ToneDesc(es/record) initializing from '1400/500,0/15000' [0x5645b7d59f90]
2021-08-28_10:16:28.528186 <tone:ALL> ToneDesc(es/info) initializing from '950/330,0/1000' [0x5645b7d5a110]
2021-08-28_10:16:28.528194 <tone:ALL> ToneDesc(es/dialout) initializing from '500' [0x5645b7d5a290]
2021-08-28_10:16:28.528201 <tone:ALL> Building tone of 500 Hz
2021-08-28_10:16:28.528388 <tone:ALL> ToneDesc(fi/dial) initializing from '425' [0x5645b7d5c3d0]
2021-08-28_10:16:28.528397 <tone:ALL> ToneDesc(fi/busy) initializing from '425/300,0/300' [0x5645b7d5c530]
2021-08-28_10:16:28.528405 <tone:ALL> ToneDesc(fi/ring) initializing from '425/1000,0/4000' [0x5645b7d5c7a0]
2021-08-28_10:16:28.528414 <tone:ALL> ToneDesc(fi/congestion) initializing from '425/200,0/200' [0x5645b7d5c9c0]
2021-08-28_10:16:28.528423 <tone:ALL> ToneDesc(fi/callwaiting) initializing from '425/150,0/150,425/150,0/8000' [0x5645b7d5cbe0]
2021-08-28_10:16:28.528432 <tone:ALL> ToneDesc(fi/dialrecall) initializing from '425/650,0/25' [0x5645b7d5cf40]
2021-08-28_10:16:28.528443 <tone:ALL> ToneDesc(fi/record) initializing from '1400/500,0/15000' [0x5645b7d5d0c0]
2021-08-28_10:16:28.528451 <tone:ALL> ToneDesc(fi/info) initializing from '950/650,0/325,950/325,0/30,1400/1300,0/2600' [0x5645b7d5d240]
2021-08-28_10:16:28.528462 <tone:ALL> ToneDesc(fi/stutter) initializing from '425/650,0/25' [0x5645b7d5d6e0]
2021-08-28_10:16:28.528472 <tone:ALL> ToneDesc(fr/dial) initializing from '440' [0x5645b7d5d860]
2021-08-28_10:16:28.528479 <tone:ALL> Building tone of 440 Hz
2021-08-28_10:16:28.528591 <tone:ALL> ToneDesc(fr/busy) initializing from '440/500,0/500' [0x5645b7d5f9a0]
2021-08-28_10:16:28.528601 <tone:ALL> ToneDesc(fr/ring) initializing from '440/1500,0/3500' [0x5645b7d5fb70]
2021-08-28_10:16:28.528609 <tone:ALL> ToneDesc(fr/congestion) initializing from '440/250,0/250' [0x5645b7d5fd90]
2021-08-28_10:16:28.528619 <tone:ALL> ToneDesc(fr/callwait) initializing from '440/300,0/10000' [0x5645b7d5ffb0]
2021-08-28_10:16:28.528628 <tone:ALL> ToneDesc(fr/dialrecall) initializing from '!350+440/100,!0/100,!350+440/100,!0/100,!350+440/100,!0/100,350+440' [0x5645b7d601b0]
2021-08-28_10:16:28.528641 <tone:ALL> ToneDesc(fr/record) initializing from '1400/500,0/15000' [0x5645b7d606e0]
2021-08-28_10:16:28.528649 <tone:ALL> ToneDesc(fr/info) initializing from '!950/330,!1400/330,!1800/330' [0x5645b7d60860]
2021-08-28_10:16:28.528659 <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' [0x5645b7d60a10]
2021-08-28_10:16:28.528678 <tone:ALL> ToneDesc(gr/dial) initializing from '425/200,0/300,425/700,0/800' [0x5645b7d61190]
2021-08-28_10:16:28.528688 <tone:ALL> ToneDesc(gr/busy) initializing from '425/300,0/300' [0x5645b7d61350]
2021-08-28_10:16:28.528697 <tone:ALL> ToneDesc(gr/ring) initializing from '425/1000,0/4000' [0x5645b7d614d0]
2021-08-28_10:16:28.528706 <tone:ALL> ToneDesc(gr/congestion) initializing from '425/200,0/200' [0x5645b7d61650]
2021-08-28_10:16:28.528715 <tone:ALL> ToneDesc(gr/callwaiting) initializing from '425/150,0/150,425/150,0/8000' [0x5645b7d617d0]
2021-08-28_10:16:28.528725 <tone:ALL> ToneDesc(gr/dialrecall) initializing from '425/650,0/25' [0x5645b7d619b0]
2021-08-28_10:16:28.528733 <tone:ALL> ToneDesc(gr/record) initializing from '1400/400,0/15000' [0x5645b7d61b30]
2021-08-28_10:16:28.528742 <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' [0x5645b7d61cb0]
2021-08-28_10:16:28.528760 <tone:ALL> ToneDesc(gr/stutter) initializing from '425/650,0/25' [0x5645b7d62360]
2021-08-28_10:16:28.528771 <tone:ALL> ToneDesc(hu/dial) initializing from '425' [0x5645b7d624e0]
2021-08-28_10:16:28.528779 <tone:ALL> ToneDesc(hu/busy) initializing from '425/300,0/300' [0x5645b7d62640]
2021-08-28_10:16:28.528788 <tone:ALL> ToneDesc(hu/ring) initializing from '425/1250,0/3750' [0x5645b7d627c0]
2021-08-28_10:16:28.528797 <tone:ALL> ToneDesc(hu/congestion) initializing from '425/300,0/300' [0x5645b7d62940]
2021-08-28_10:16:28.528806 <tone:ALL> ToneDesc(hu/callwaiting) initializing from '425/40,0/1960' [0x5645b7d62ac0]
2021-08-28_10:16:28.528814 <tone:ALL> ToneDesc(hu/dialrecall) initializing from '425+450' [0x5645b7d62c40]
2021-08-28_10:16:28.528823 <tone:ALL> ToneDesc(hu/record) initializing from '1400/400,0/15000' [0x5645b7d62db0]
2021-08-28_10:16:28.528832 <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' [0x5645b7d62f70]
2021-08-28_10:16:28.528849 <tone:ALL> ToneDesc(hu/stutter) initializing from '350+375+400' [0x5645b7d63680]
2021-08-28_10:16:28.528856 <tone:ALL> Building tone of 375 + 350 Hz
2021-08-28_10:16:28.529326 <tone:ALL> ToneDesc(il/dial) initializing from '414' [0x5645b7d630c0]
2021-08-28_10:16:28.529333 <tone:ALL> Building tone of 414 Hz
2021-08-28_10:16:28.529440 <tone:ALL> ToneDesc(il/busy) initializing from '414/500,0/500' [0x5645b7d632f0]
2021-08-28_10:16:28.529450 <tone:ALL> ToneDesc(il/ring) initializing from '414/1000,0/3000' [0x5645b7d69740]
2021-08-28_10:16:28.529459 <tone:ALL> ToneDesc(il/congestion) initializing from '414/250,0/250' [0x5645b7d69950]
2021-08-28_10:16:28.529468 <tone:ALL> ToneDesc(il/callwaiting) initializing from '414/100,0/100,414/100,0/100,414/600,0/3000' [0x5645b7d69b70]
2021-08-28_10:16:28.529482 <tone:ALL> ToneDesc(il/dialrecall) initializing from '!414/100,!0/100,!414/100,!0/100,!414/100,!0/100,414' [0x5645b7d6a010]
2021-08-28_10:16:28.529495 <tone:ALL> ToneDesc(il/record) initializing from '1400/500,0/15000' [0x5645b7d6a330]
2021-08-28_10:16:28.529504 <tone:ALL> ToneDesc(il/info) initializing from '1000/330,1400/330,1800/330,0/1000' [0x5645b7d6a4b0]
2021-08-28_10:16:28.529511 <tone:ALL> Building tone of 1000 Hz
2021-08-28_10:16:28.529775 <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' [0x5645b7d6c680]
2021-08-28_10:16:28.529800 <tone:ALL> ToneDesc(in/dial) initializing from '400*25' [0x5645b7d6d360]
2021-08-28_10:16:28.529807 <tone:ALL> Building tone of 400 modulated by 25 Hz
2021-08-28_10:16:28.530355 <tone:ALL> ToneDesc(in/busy) initializing from '400/750,0/750' [0x5645b7d6c900]
2021-08-28_10:16:28.530365 <tone:ALL> ToneDesc(in/ring) initializing from '400*25/400,0/200,400*25/400,0/2000' [0x5645b7d6cab0]
2021-08-28_10:16:28.530376 <tone:ALL> ToneDesc(in/congestion) initializing from '400/250,0/250' [0x5645b7d6ce10]
2021-08-28_10:16:28.530386 <tone:ALL> ToneDesc(in/callwaiting) initializing from '400/200,0/100,400/200,0/7500' [0x5645b7d6cf90]
2021-08-28_10:16:28.530397 <tone:ALL> ToneDesc(in/dialrecall) initializing from '!350+440/100,!0/100,!350+440/100,!0/100,!350+440/100,!0/100,350+440' [0x5645b7d715b0]
2021-08-28_10:16:28.530411 <tone:ALL> ToneDesc(in/record) initializing from '1400/500,0/15000' [0x5645b7d719e0]
2021-08-28_10:16:28.530421 <tone:ALL> ToneDesc(in/info) initializing from '!950/330,!1400/330,!1800/330,0/1000' [0x5645b7d71b60]
2021-08-28_10:16:28.530432 <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' [0x5645b7d71d20]
2021-08-28_10:16:28.530455 <tone:ALL> ToneDesc(it/dial) initializing from '425/200,0/200,425/600,0/1000' [0x5645b7d724c0]
2021-08-28_10:16:28.530467 <tone:ALL> ToneDesc(it/busy) initializing from '425/500,0/500' [0x5645b7d72680]
2021-08-28_10:16:28.530476 <tone:ALL> ToneDesc(it/ring) initializing from '425/1000,0/4000' [0x5645b7d72800]
2021-08-28_10:16:28.530485 <tone:ALL> ToneDesc(it/congestion) initializing from '425/200,0/200' [0x5645b7d72980]
2021-08-28_10:16:28.530495 <tone:ALL> ToneDesc(it/callwaiting) initializing from '425/400,0/100,425/250,0/100,425/150,0/14000' [0x5645b7d72b00]
2021-08-28_10:16:28.530507 <tone:ALL> ToneDesc(it/dialrecall) initializing from '470/400,425/400' [0x5645b7d72d60]
2021-08-28_10:16:28.530514 <tone:ALL> Building tone of 470 Hz
2021-08-28_10:16:28.530622 <tone:ALL> ToneDesc(it/record) initializing from '1400/400,0/15000' [0x5645b7d74ec0]
2021-08-28_10:16:28.530634 <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' [0x5645b7d75040]
2021-08-28_10:16:28.530650 <tone:ALL> ToneDesc(it/stutter) initializing from '470/400,425/400' [0x5645b7d75740]
2021-08-28_10:16:28.530662 <tone:ALL> ToneDesc(lt/dial) initializing from '425' [0x5645b7d758c0]
2021-08-28_10:16:28.530671 <tone:ALL> ToneDesc(lt/busy) initializing from '425/350,0/350' [0x5645b7d75a20]
2021-08-28_10:16:28.530680 <tone:ALL> ToneDesc(lt/ring) initializing from '425/1000,0/4000' [0x5645b7d75ba0]
2021-08-28_10:16:28.530689 <tone:ALL> ToneDesc(lt/congestion) initializing from '425/200,0/200' [0x5645b7d75d20]
2021-08-28_10:16:28.530699 <tone:ALL> ToneDesc(lt/callwaiting) initializing from '425/150,0/150,425/150,0/4000' [0x5645b7d75ea0]
2021-08-28_10:16:28.530711 <tone:ALL> ToneDesc(lt/dialrecall) initializing from '425/500,0/50' [0x5645b7d760a0]
2021-08-28_10:16:28.530720 <tone:ALL> ToneDesc(lt/record) initializing from '1400/500,0/15000' [0x5645b7d76220]
2021-08-28_10:16:28.530730 <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' [0x5645b7d763a0]
2021-08-28_10:16:28.530747 <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' [0x5645b7d76b90]
2021-08-28_10:16:28.530765 <tone:ALL> ToneDesc(jp/dial) initializing from '400' [0x5645b7d76ef0]
2021-08-28_10:16:28.530776 <tone:ALL> ToneDesc(jp/busy) initializing from '400/500,0/500' [0x5645b7d77050]
2021-08-28_10:16:28.530785 <tone:ALL> ToneDesc(jp/ring) initializing from '400+15/1000,0/2000' [0x5645b7d771d0]
2021-08-28_10:16:28.530792 <tone:ALL> Building tone of 400 + 15 Hz
2021-08-28_10:16:28.531329 <tone:ALL> ToneDesc(jp/congestion) initializing from '400/500,0/500' [0x5645b7d7b270]
2021-08-28_10:16:28.531340 <tone:ALL> ToneDesc(jp/callwaiting) initializing from '400+16/500,0/8000' [0x5645b7d7b360]
2021-08-28_10:16:28.531348 <tone:ALL> Building tone of 400 + 16 Hz
2021-08-28_10:16:28.531897 <tone:ALL> ToneDesc(jp/dialrecall) initializing from '!400/200,!0/200,!400/200,!0/200,!400/200,!0/200,400' [0x5645b7d7f3c0]
2021-08-28_10:16:28.531913 <tone:ALL> ToneDesc(jp/record) initializing from '1400/500,0/15000' [0x5645b7d7f890]
2021-08-28_10:16:28.531923 <tone:ALL> ToneDesc(jp/info) initializing from '!950/330,!1400/330,!1800/330,0' [0x5645b7d7fa10]
2021-08-28_10:16:28.531935 <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' [0x5645b7d7fbd0]
2021-08-28_10:16:28.531955 <tone:ALL> ToneDesc(mx/dial) initializing from '425' [0x5645b7d80350]
2021-08-28_10:16:28.531965 <tone:ALL> ToneDesc(mx/busy) initializing from '425/250,0/250' [0x5645b7d804b0]
2021-08-28_10:16:28.531975 <tone:ALL> ToneDesc(mx/ring) initializing from '425/1000,0/4000' [0x5645b7d80630]
2021-08-28_10:16:28.531985 <tone:ALL> ToneDesc(mx/congestion) initializing from '425/250,0/250' [0x5645b7d807b0]
2021-08-28_10:16:28.531995 <tone:ALL> ToneDesc(mx/callwaiting) initializing from '425/200,0/600,425/200,0/10000' [0x5645b7d80930]
2021-08-28_10:16:28.532006 <tone:ALL> ToneDesc(mx/dialrecall) initializing from '!350+440/100,!0/100,!350+440/100,!0/100,!350+440/100,!0/100,350+440' [0x5645b7d80b10]
2021-08-28_10:16:28.532020 <tone:ALL> ToneDesc(mx/record) initializing from '1400/500,0/15000' [0x5645b7d80dc0]
2021-08-28_10:16:28.532029 <tone:ALL> ToneDesc(mx/info) initializing from '950/330,0/30,1400/330,0/30,1800/330,0/1000' [0x5645b7d80f40]
2021-08-28_10:16:28.532044 <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' [0x5645b7d811d0]
2021-08-28_10:16:28.532063 <tone:ALL> ToneDesc(my/dial) initializing from '425' [0x5645b7d818d0]
2021-08-28_10:16:28.532073 <tone:ALL> ToneDesc(my/busy) initializing from '425/500,0/500' [0x5645b7d81a30]
2021-08-28_10:16:28.532082 <tone:ALL> ToneDesc(my/ring) initializing from '425/400,0/200' [0x5645b7d81bb0]
2021-08-28_10:16:28.532092 <tone:ALL> ToneDesc(my/congestion) initializing from '425/500,0/500' [0x5645b7d81d30]
2021-08-28_10:16:28.532103 <tone:ALL> ToneDesc(nl/dial) initializing from '425' [0x5645b7d81eb0]
2021-08-28_10:16:28.532114 <tone:ALL> ToneDesc(nl/busy) initializing from '425/500,0/500' [0x5645b7d82010]
2021-08-28_10:16:28.532124 <tone:ALL> ToneDesc(nl/ring) initializing from '425/1000,0/4000' [0x5645b7d821b0]
2021-08-28_10:16:28.532134 <tone:ALL> ToneDesc(nl/congestion) initializing from '425/250,0/250' [0x5645b7d82370]
2021-08-28_10:16:28.532144 <tone:ALL> ToneDesc(nl/callwaiting) initializing from '425/500,0/9500' [0x5645b7d82530]
2021-08-28_10:16:28.532154 <tone:ALL> ToneDesc(nl/dialrecall) initializing from '425/500,0/50' [0x5645b7d826f0]
2021-08-28_10:16:28.532164 <tone:ALL> ToneDesc(nl/record) initializing from '1400/500,0/15000' [0x5645b7d82890]
2021-08-28_10:16:28.532174 <tone:ALL> ToneDesc(nl/info) initializing from '950/330,1400/330,1800/330,0/1000' [0x5645b7d82a30]
2021-08-28_10:16:28.532186 <tone:ALL> ToneDesc(nl/stutter) initializing from '425/500,0/50' [0x5645b7d82d90]
2021-08-28_10:16:28.532197 <tone:ALL> ToneDesc(no/dial) initializing from '425' [0x5645b7d82f10]
2021-08-28_10:16:28.532208 <tone:ALL> ToneDesc(no/busy) initializing from '425/500,0/500' [0x5645b7d83070]
2021-08-28_10:16:28.532219 <tone:ALL> ToneDesc(no/ring) initializing from '425/1000,0/4000' [0x5645b7d832b0]
2021-08-28_10:16:28.532229 <tone:ALL> ToneDesc(no/congestion) initializing from '425/200,0/200' [0x5645b7d834d0]
2021-08-28_10:16:28.532239 <tone:ALL> ToneDesc(no/callwaiting) initializing from '425/200,0/600,425/200,0/10000' [0x5645b7d836f0]
2021-08-28_10:16:28.532251 <tone:ALL> ToneDesc(no/dialrecall) initializing from '470/400,425/400' [0x5645b7d83a50]
2021-08-28_10:16:28.532262 <tone:ALL> ToneDesc(no/record) initializing from '1400/400,0/15000' [0x5645b7d83bd0]
2021-08-28_10:16:28.532282 <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' [0x5645b7d83d50]
2021-08-28_10:16:28.532304 <tone:ALL> ToneDesc(no/stutter) initializing from '470/400,425/400' [0x5645b7d84660]
2021-08-28_10:16:28.532318 <tone:ALL> ToneDesc(nz/dial) initializing from '400' [0x5645b7d847e0]
2021-08-28_10:16:28.532328 <tone:ALL> ToneDesc(nz/busy) initializing from '400/250,0/250' [0x5645b7d84940]
2021-08-28_10:16:28.532338 <tone:ALL> ToneDesc(nz/ring) initializing from '400+450/400,0/200,400+450/400,0/2000' [0x5645b7d84ac0]
2021-08-28_10:16:28.532350 <tone:ALL> ToneDesc(nz/congestion) initializing from '400/375,0/375' [0x5645b7d84c80]
2021-08-28_10:16:28.532360 <tone:ALL> ToneDesc(nz/callwaiting) initializing from '!400/200,!0/3000,!400/200,!0/3000,!400/200,!0/3000,!400/200' [0x5645b7d84e00]
2021-08-28_10:16:28.532375 <tone:ALL> ToneDesc(nz/dialrecall) initializing from '!400/100!0/100,!400/100,!0/100,!400/100,!0/100,400' [0x5645b7d850d0]
2021-08-28_10:16:28.532389 <tone:ALL> ToneDesc(nz/record) initializing from '1400/425,0/15000' [0x5645b7d852d0]
2021-08-28_10:16:28.532399 <tone:ALL> ToneDesc(nz/info) initializing from '400/750,0/100,400/750,0/100,400/750,0/100,400/750,0/400' [0x5645b7d85450]
2021-08-28_10:16:28.532412 <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' [0x5645b7d858a0]
2021-08-28_10:16:28.532428 <tone:ALL> ToneDesc(nz/unobtainable) initializing from '400/75,0/100,400/75,0/100,400/75,0/100,400/75,0/400' [0x5645b7d85c20]
2021-08-28_10:16:28.532444 <tone:ALL> ToneDesc(ph/dial) initializing from '425' [0x5645b7d85e60]
2021-08-28_10:16:28.532455 <tone:ALL> ToneDesc(ph/busy) initializing from '480+620/500,0/500' [0x5645b7d85fc0]
2021-08-28_10:16:28.532463 <tone:ALL> Building tone of 620 + 480 Hz
2021-08-28_10:16:28.532943 <tone:ALL> ToneDesc(ph/ring) initializing from '425+480/1000,0/4000' [0x5645b7d8a060]
2021-08-28_10:16:28.532955 <tone:ALL> Building tone of 480 + 425 Hz
2021-08-28_10:16:28.533445 <tone:ALL> ToneDesc(ph/congestion) initializing from '480+620/250,0/250' [0x5645b7d8e0c0]
2021-08-28_10:16:28.533458 <tone:ALL> ToneDesc(ph/callwaiting) initializing from '440/300,0/10000' [0x5645b7d8e240]
2021-08-28_10:16:28.533469 <tone:ALL> ToneDesc(ph/dialrecall) initializing from '!350+440/100,!0/100,!350+440/100,!0/100,!350+440/100,!0/100,350+440' [0x5645b7d8e440]
2021-08-28_10:16:28.533484 <tone:ALL> ToneDesc(ph/record) initializing from '1400/500,0/15000' [0x5645b7d8e970]
2021-08-28_10:16:28.533494 <tone:ALL> ToneDesc(ph/info) initializing from '!950/330,!1400/330,!1800/330,0' [0x5645b7d8eaf0]
2021-08-28_10:16:28.533506 <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' [0x5645b7d8ecb0]
2021-08-28_10:16:28.533528 <tone:ALL> ToneDesc(pl/dial) initializing from '425' [0x5645b7d8f450]
2021-08-28_10:16:28.533539 <tone:ALL> ToneDesc(pl/busy) initializing from '425/500,0/500' [0x5645b7d8f5b0]
2021-08-28_10:16:28.533550 <tone:ALL> ToneDesc(pl/ring) initializing from '425/1000,0/4000' [0x5645b7d8f730]
2021-08-28_10:16:28.533560 <tone:ALL> ToneDesc(pl/congestion) initializing from '425/500,0/500' [0x5645b7d8f8b0]
2021-08-28_10:16:28.533570 <tone:ALL> ToneDesc(pl/callwaiting) initializing from '425/150,0/150,425/150,0/4000' [0x5645b7d8fa30]
2021-08-28_10:16:28.533582 <tone:ALL> ToneDesc(pl/dialrecall) initializing from '425/500,0/50' [0x5645b7d8fc10]
2021-08-28_10:16:28.533593 <tone:ALL> ToneDesc(pl/record) initializing from '1400/500,0/15000' [0x5645b7d8fd90]
2021-08-28_10:16:28.533604 <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' [0x5645b7d8ff10]
2021-08-28_10:16:28.533623 <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' [0x5645b7d90600]
2021-08-28_10:16:28.533642 <tone:ALL> ToneDesc(pt/dial) initializing from '425' [0x5645b7d909e0]
2021-08-28_10:16:28.533652 <tone:ALL> ToneDesc(pt/busy) initializing from '425/500,0/500' [0x5645b7d90b40]
2021-08-28_10:16:28.533662 <tone:ALL> ToneDesc(pt/ring) initializing from '425/1000,0/5000' [0x5645b7d90cc0]
2021-08-28_10:16:28.533673 <tone:ALL> ToneDesc(pt/congestion) initializing from '425/200,0/200' [0x5645b7d90e40]
2021-08-28_10:16:28.533684 <tone:ALL> ToneDesc(pt/callwaiting) initializing from '440/300,0/10000' [0x5645b7d90fc0]
2021-08-28_10:16:28.533695 <tone:ALL> ToneDesc(pt/dialrecall) initializing from '425/1000,0/200' [0x5645b7d91140]
2021-08-28_10:16:28.533705 <tone:ALL> ToneDesc(pt/record) initializing from '1400/500,0/15000' [0x5645b7d912c0]
2021-08-28_10:16:28.533714 <tone:ALL> ToneDesc(pt/info) initializing from '950/330,1400/330,1800/330,0/1000' [0x5645b7d91460]
2021-08-28_10:16:28.533726 <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' [0x5645b7d916a0]
2021-08-28_10:16:28.533745 <tone:ALL> ToneDesc(ru/dial) initializing from '425' [0x5645b7d91e20]
2021-08-28_10:16:28.533755 <tone:ALL> ToneDesc(ru/busy) initializing from '425/350,0/350' [0x5645b7d91f80]
2021-08-28_10:16:28.533768 <tone:ALL> ToneDesc(ru/ring) initializing from '425/800,0/3200' [0x5645b7d92100]
2021-08-28_10:16:28.533778 <tone:ALL> ToneDesc(ru/congestion) initializing from '425/350,0/350' [0x5645b7d92280]
2021-08-28_10:16:28.533788 <tone:ALL> ToneDesc(ru/callwaiting) initializing from '425/200,0/5000' [0x5645b7d92400]
2021-08-28_10:16:28.533798 <tone:ALL> ToneDesc(ru/dialrecall) initializing from '!350+440/100,!0/100,!350+440/100,!0/100,!350+440/100,!0/100,350+440' [0x5645b7d92580]
2021-08-28_10:16:28.533813 <tone:ALL> ToneDesc(ru/record) initializing from '1400/500,0/15000' [0x5645b7d92850]
2021-08-28_10:16:28.533823 <tone:ALL> ToneDesc(ru/info) initializing from '!950/330,!1400/330,!1800/330,0' [0x5645b7d929d0]
2021-08-28_10:16:28.533836 <tone:ALL> ToneDesc(se/dial) initializing from '425' [0x5645b7d92b90]
2021-08-28_10:16:28.533846 <tone:ALL> ToneDesc(se/busy) initializing from '425/250,0/250' [0x5645b7d92cf0]
2021-08-28_10:16:28.533856 <tone:ALL> ToneDesc(se/ring) initializing from '425/1000,0/5000' [0x5645b7d92eb0]
2021-08-28_10:16:28.533870 <tone:ALL> ToneDesc(se/congestion) initializing from '425/250,0/750' [0x5645b7d93070]
2021-08-28_10:16:28.533880 <tone:ALL> ToneDesc(se/callwaiting) initializing from '425/200,0/500,425/200,0/9100' [0x5645b7d93290]
2021-08-28_10:16:28.533892 <tone:ALL> ToneDesc(se/dialrecall) initializing from '!425/100,!0/100,!425/100,!0/100,!425/100,!0/100,425' [0x5645b7d935f0]
2021-08-28_10:16:28.533905 <tone:ALL> ToneDesc(se/record) initializing from '1400/500,0/15000' [0x5645b7d93a10]
2021-08-28_10:16:28.533915 <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' [0x5645b7d93b90]
2021-08-28_10:16:28.533945 <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' [0x5645b7d94d30]
2021-08-28_10:16:28.533966 <tone:ALL> ToneDesc(sg/dial) initializing from '425' [0x5645b7d95010]
2021-08-28_10:16:28.533976 <tone:ALL> ToneDesc(sg/ring) initializing from '425*24/400,0/200,425*24/400,0/2000' [0x5645b7d95170]
2021-08-28_10:16:28.533984 <tone:ALL> Building tone of 425 modulated by 24 Hz
2021-08-28_10:16:28.534412 <tone:ALL> ToneDesc(sg/busy) initializing from '425/750,0/750' [0x5645b7d948b0]
2021-08-28_10:16:28.534424 <tone:ALL> ToneDesc(sg/congestion) initializing from '425/250,0/250' [0x5645b7d94920]
2021-08-28_10:16:28.534434 <tone:ALL> ToneDesc(sg/callwaiting) initializing from '425*24/300,0/200,425*24/300,0/3200' [0x5645b7d93f80]
2021-08-28_10:16:28.534447 <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' [0x5645b7d94180]
2021-08-28_10:16:28.534466 <tone:ALL> ToneDesc(sg/info) initializing from '950/330,1400/330,1800/330,0/1000' [0x5645b7d998f0]
2021-08-28_10:16:28.534478 <tone:ALL> ToneDesc(sg/dialrecall) initializing from '425*24/500,0/500,425/500,0/2500' [0x5645b7d99a70]
2021-08-28_10:16:28.534490 <tone:ALL> ToneDesc(sg/record) initializing from '1400/500,0/15000' [0x5645b7d99bf0]
2021-08-28_10:16:28.534500 <tone:ALL> ToneDesc(sg/nutone) initializing from '425/2500,0/500' [0x5645b7d99d70]
2021-08-28_10:16:28.534510 <tone:ALL> ToneDesc(sg/intrusion) initializing from '425/250,0/2000' [0x5645b7d99ef0]
2021-08-28_10:16:28.534523 <tone:ALL> ToneDesc(sg/warning) initializing from '425/624,0/4376' [0x5645b7d9a070]
2021-08-28_10:16:28.534534 <tone:ALL> ToneDesc(sg/acceptance) initializing from '425/125,0/125' [0x5645b7d9a1f0]
2021-08-28_10:16:28.534544 <tone:ALL> ToneDesc(sg/holdinga) initializing from '!425*24/500,!0/500' [0x5645b7d9a370]
2021-08-28_10:16:28.534555 <tone:ALL> ToneDesc(sg/holdingb) initializing from '!425/500,!0/2500' [0x5645b7d9a4f0]
2021-08-28_10:16:28.534566 <tone:ALL> ToneDesc(th/dial) initializing from '400*50' [0x5645b7d9a670]
2021-08-28_10:16:28.534573 <tone:ALL> Building tone of 400 modulated by 50 Hz
2021-08-28_10:16:28.535122 <tone:ALL> ToneDesc(th/busy) initializing from '400/500,0/500' [0x5645b7d9e700]
2021-08-28_10:16:28.535134 <tone:ALL> ToneDesc(th/ring) initializing from '420/1000,0/5000' [0x5645b7d9e880]
2021-08-28_10:16:28.535145 <tone:ALL> ToneDesc(th/congestion) initializing from '400/300,0/300' [0x5645b7d9ea60]
2021-08-28_10:16:28.535155 <tone:ALL> ToneDesc(th/callwaiting) initializing from '1000/400,10000/400,1000/400' [0x5645b7d9ec20]
2021-08-28_10:16:28.535163 <tone:ALL> Building tone of 10000 Hz
2021-08-28_10:16:28.535584 <tone:ALL> ToneDesc(th/dialrecall) initializing from '400*50/400,0/100,400*50/400,0/100' [0x5645b7da0f30]
2021-08-28_10:16:28.535598 <tone:ALL> ToneDesc(th/record) initializing from '1400/500,0/15000' [0x5645b7da11f0]
2021-08-28_10:16:28.535609 <tone:ALL> ToneDesc(th/info) initializing from '950/330,1400/330,1800/330' [0x5645b7da1370]
2021-08-28_10:16:28.535620 <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' [0x5645b7da15b0]
2021-08-28_10:16:28.535643 <tone:ALL> ToneDesc(uk/dial) initializing from '350+440' [0x5645b7da2090]
2021-08-28_10:16:28.535654 <tone:ALL> ToneDesc(uk/specdial) initializing from '350+440/750,440/750' [0x5645b7da21f0]
2021-08-28_10:16:28.535665 <tone:ALL> ToneDesc(uk/busy) initializing from '400/375,0/375' [0x5645b7da2370]
2021-08-28_10:16:28.535676 <tone:ALL> ToneDesc(uk/congestion) initializing from '400/400,0/350,400/225,0/525' [0x5645b7da24f0]
2021-08-28_10:16:28.535687 <tone:ALL> ToneDesc(uk/speccongestion) initializing from '400/200,1004/300' [0x5645b7da26b0]
2021-08-28_10:16:28.535695 <tone:ALL> Building tone of 1004 Hz
2021-08-28_10:16:28.535810 <tone:ALL> ToneDesc(uk/unobtainable) initializing from '400' [0x5645b7da4810]
2021-08-28_10:16:28.535823 <tone:ALL> ToneDesc(uk/ring) initializing from '400+450/400,0/200,400+450/400,0/2000' [0x5645b7da4900]
2021-08-28_10:16:28.535836 <tone:ALL> ToneDesc(uk/callwaiting) initializing from '400/100,0/4000' [0x5645b7da4a80]
2021-08-28_10:16:28.535847 <tone:ALL> ToneDesc(uk/speccallwaiting) initializing from '400/250,0/250,400/250,0/250,400/250,0/5000' [0x5645b7da4c00]
2021-08-28_10:16:28.535861 <tone:ALL> ToneDesc(uk/creditexpired) initializing from '400/125,0/125' [0x5645b7da4f10]
2021-08-28_10:16:28.535873 <tone:ALL> ToneDesc(uk/confirm) initializing from '1400' [0x5645b7da5090]
2021-08-28_10:16:28.535884 <tone:ALL> ToneDesc(uk/switching) initializing from '400/200,0/400,400/2000,0/400' [0x5645b7da51f0]
2021-08-28_10:16:28.535896 <tone:ALL> ToneDesc(uk/info) initializing from '950/330,0/15,1400/330,0/15,1800/330,0/1000' [0x5645b7da5430]
2021-08-28_10:16:28.535909 <tone:ALL> ToneDesc(uk/record) initializing from '1400/500,0/60000' [0x5645b7da57c0]
2021-08-28_10:16:28.535920 <tone:ALL> ToneDesc(uk/stutter) initializing from '350+440/750,440/750' [0x5645b7da5940]
2021-08-28_10:16:28.535932 <tone:ALL> ToneDesc(us/dial) initializing from '350+440' [0x5645b7da5ac0]
2021-08-28_10:16:28.535943 <tone:ALL> ToneDesc(us/busy) initializing from '480+620/500,0/500' [0x5645b7da5c20]
2021-08-28_10:16:28.535953 <tone:ALL> ToneDesc(us/ring) initializing from '440+480/2000,0/4000' [0x5645b7da5de0]
2021-08-28_10:16:28.535962 <tone:ALL> Building tone of 480 + 440 Hz
2021-08-28_10:16:28.536442 <tone:ALL> ToneDesc(us/congestion) initializing from '480+620/250,0/250' [0x5645b7da9fb0]
2021-08-28_10:16:28.536455 <tone:ALL> ToneDesc(us/callwaiting) initializing from '440/300,0/10000' [0x5645b7daa1d0]
2021-08-28_10:16:28.536466 <tone:ALL> ToneDesc(us/dialrecall) initializing from '!350+440/100,!0/100,!350+440/100,!0/100,!350+440/100,!0/100,350+440' [0x5645b7daa3f0]
2021-08-28_10:16:28.536481 <tone:ALL> ToneDesc(us/record) initializing from '1400/500,0/15000' [0x5645b7daa920]
2021-08-28_10:16:28.536492 <tone:ALL> ToneDesc(us/info) initializing from '!950/330,!1400/330,!1800/330,0' [0x5645b7daaaa0]
2021-08-28_10:16:28.536504 <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' [0x5645b7daac60]
2021-08-28_10:16:28.536526 <tone:ALL> ToneDesc(us-old/dial) initializing from '600*120' [0x5645b7dab400]
2021-08-28_10:16:28.536533 <tone:ALL> Building tone of 600 modulated by 120 Hz
2021-08-28_10:16:28.537016 <tone:ALL> ToneDesc(us-old/busy) initializing from '500*100/500,0/500' [0x5645b7daae60]
2021-08-28_10:16:28.537024 <tone:ALL> Building tone of 500 modulated by 100 Hz
2021-08-28_10:16:28.537618 <tone:ALL> ToneDesc(us-old/ring) initializing from '420*40/2000,0/4000' [0x5645b7dab140]
2021-08-28_10:16:28.537629 <tone:ALL> Building tone of 420 modulated by 40 Hz
2021-08-28_10:16:28.538071 <tone:ALL> ToneDesc(us-old/congestion) initializing from '500*100/250,0/250' [0x5645b7db7410]
2021-08-28_10:16:28.538090 <tone:ALL> ToneDesc(us-old/callwaiting) initializing from '440/300,0/10000' [0x5645b7db7640]
2021-08-28_10:16:28.538103 <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' [0x5645b7db7860]
2021-08-28_10:16:28.538119 <tone:ALL> ToneDesc(us-old/record) initializing from '1400/500,0/15000' [0x5645b7db7da0]
2021-08-28_10:16:28.538130 <tone:ALL> ToneDesc(us-old/info) initializing from '!950/330,!1400/330,!1800/330,0' [0x5645b7db7f20]
2021-08-28_10:16:28.538144 <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' [0x5645b7db80e0]
2021-08-28_10:16:28.538168 <tone:ALL> ToneDesc(tw/dial) initializing from '350+440' [0x5645b7db8880]
2021-08-28_10:16:28.538179 <tone:ALL> ToneDesc(tw/busy) initializing from '480+620/500,0/500' [0x5645b7db89e0]
2021-08-28_10:16:28.538191 <tone:ALL> ToneDesc(tw/ring) initializing from '440+480/1000,0/2000' [0x5645b7db8b60]
2021-08-28_10:16:28.538203 <tone:ALL> ToneDesc(tw/congestion) initializing from '480+620/250,0/250' [0x5645b7db8ce0]
2021-08-28_10:16:28.538214 <tone:ALL> ToneDesc(tw/callwaiting) initializing from '350+440/250,0/250,350+440/250,0/3250' [0x5645b7db8e60]
2021-08-28_10:16:28.538229 <tone:ALL> ToneDesc(tw/dialrecall) initializing from '300/1500,0/500' [0x5645b7db9050]
2021-08-28_10:16:28.538237 <tone:ALL> Building tone of 300 Hz
2021-08-28_10:16:28.538372 <tone:ALL> ToneDesc(tw/record) initializing from '1400/500,0/15000' [0x5645b7dbb1b0]
2021-08-28_10:16:28.538384 <tone:ALL> ToneDesc(tw/info) initializing from '!950/330,!1400/330,!1800/330,0' [0x5645b7dbb330]
2021-08-28_10:16:28.538397 <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' [0x5645b7dbb5a0]
2021-08-28_10:16:28.538418 <tone:ALL> ToneDesc(ve/dial) initializing from '425' [0x5645b7dbbda0]
2021-08-28_10:16:28.538429 <tone:ALL> ToneDesc(ve/busy) initializing from '425/500,0/500' [0x5645b7dbbf00]
2021-08-28_10:16:28.538442 <tone:ALL> ToneDesc(ve/ring) initializing from '425/1000,0/4000' [0x5645b7dbc080]
2021-08-28_10:16:28.538454 <tone:ALL> ToneDesc(ve/congestion) initializing from '425/250,0/250' [0x5645b7dbc200]
2021-08-28_10:16:28.538465 <tone:ALL> ToneDesc(ve/callwaiting) initializing from '400+450/300,0/6000' [0x5645b7dbc380]
2021-08-28_10:16:28.538476 <tone:ALL> ToneDesc(ve/dialrecall) initializing from '425' [0x5645b7dbc500]
2021-08-28_10:16:28.538487 <tone:ALL> ToneDesc(ve/record) initializing from '1400/500,0/15000' [0x5645b7dbc660]
2021-08-28_10:16:28.538498 <tone:ALL> ToneDesc(ve/info) initializing from '!950/330,!1440/330,!1800/330,0/1000' [0x5645b7dbc800]
2021-08-28_10:16:28.538506 <tone:ALL> Building tone of 1440 Hz
2021-08-28_10:16:28.538646 <tone:ALL> ToneDesc(za/dial) initializing from '400*33' [0x5645b7dbea50]
2021-08-28_10:16:28.538654 <tone:ALL> Building tone of 400 modulated by 33 Hz
2021-08-28_10:16:28.539191 <tone:ALL> ToneDesc(za/ring) initializing from '400*33/400,0/200,400*33/400,0/2000' [0x5645b7dc2ae0]
2021-08-28_10:16:28.539205 <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' [0x5645b7dc2e70]
2021-08-28_10:16:28.539223 <tone:ALL> ToneDesc(za/congestion) initializing from '400/250,0/250' [0x5645b7dc3350]
2021-08-28_10:16:28.539235 <tone:ALL> ToneDesc(za/busy) initializing from '400/500,0/500' [0x5645b7dc34d0]
2021-08-28_10:16:28.539247 <tone:ALL> ToneDesc(za/dialrecall) initializing from '350+440' [0x5645b7dc3650]
2021-08-28_10:16:28.539258 <tone:ALL> ToneDesc(za/record) initializing from '1400/500,0/10000' [0x5645b7dc37c0]
2021-08-28_10:16:28.539270 <tone:ALL> ToneDesc(za/info) initializing from '950/330,1400/330,1800/330,0/330' [0x5645b7dc3940]
2021-08-28_10:16:28.539284 <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' [0x5645b7dc3be0]
Initializing module SIP Channel
2021-08-28_10:16:28.539543 <sip:ALL> Initialized audio codecs: mulaw,alaw,gsm,slin,g722/16000,ilbc20,ilbc30,isac/16000,isac/32000
2021-08-28_10:16:28.539632 <sip:ALL> YateSIPEndPoint::YateSIPEndPoint(normal) [0x5645b7cffce0]
2021-08-28_10:16:28.539707 <sip:ALL> Transport(general) created [0x5645b7d03040]
2021-08-28_10:16:28.539727 <sip:ALL> Listener(UDP,'general') initialized addr=':5060' default=true maxpkt=1500 rtp_localip=(null) nat_address=(null) [0x5645b7d03040]
2021-08-28_10:16:28.539762 <sip:INFO> Default UDP transport is 'general'
Initializing module WireSniff
2021-08-28_10:16:28.539821 <sip:ALL> Listener(UDP,'general') initializing socket addr='(null)' port=5060
Initializing module Conference
2021-08-28_10:16:28.539923 <sip:INFO> Listener(UDP,'general') started on ':5060' (IPv4)
Initializing module YIAX
2021-08-28_10:16:28.540015 <iax:ALL> Enabled audio format(s) 'gsm,ilbc30,mulaw,alaw,slin,g722/16000' default=g722/16000
2021-08-28_10:16:28.540027 <iax:ALL> No video format(s) available
2021-08-28_10:16:28.540108 <iaxengine:INFO> Bound on '0.0.0.0:4569' [0x5645b7dc44f0]
2021-08-28_10:16:28.540120 <iaxengine:ALL> Adjust ts out set to thres=120 over=120 under=60 [0x5645b7dc44f0]
2021-08-28_10:16:28.540130 <iaxengine:INFO> Status changed Idle -> Listening [0x5645b7dc44f0]
2021-08-28_10:16:28.540137 <iax:INFO> Added listener (0x5645b7dc44f0) 'iaxengine' status='Listening'
2021-08-28_10:16:28.540227 <iaxengine:ALL> Created 3 'YIAXListener' threads [0x5645b7dc44f0]
2021-08-28_10:16:28.540302 <iaxengine:ALL> Created 3 'YIAXGetEvent' threads [0x5645b7dc44f0]
2021-08-28_10:16:28.540333 <iaxengine:ALL> Created 1 'YIAXTrunking' threads [0x5645b7dc44f0]
2021-08-28_10:16:28.540348 <iax:INFO> Default listener is 'iaxengine'
Initializing module CdrBuild
Initializing module ToneDetector
Initializing module FileInfo
Initializing module GVoice
Initializing module DumbChannel
DumbChannel initialized
Initializing module iSAC
Initializing module MsgSniffer
2021-08-28_10:16:28.540608 <INFO> Registered broadcast message handler 0x5645b7d00300
Initializing module File Transfer
2021-08-28_10:16:28.540672 <filetransfer:INFO> Initialized send_chunk_size=4096 send_interval=50ms send_linger_intervals=20 notify_progress=false
Initializing module G722
Initializing module RManager
2021-08-28_10:16:28.540737 <RManager:INFO> Starting listener 'general' on 127.0.0.1:5038
Initializing module Analyzer
Initializing module MOH
Initializing module Call Generator
2021-08-28_10:16:28.540901 <CallGen:INFO> CleanThread::run() [0x5645b7d0f9e0]
Initializing module PBX
2021-08-28_10:16:28.540946 <CallGen:INFO> GenThread::run() [0x5645b7d0fbe0]
Initializing module iLBC webrtc
Initializing module WaveFile
Initializing module Call Forker
2021-08-28_10:16:28.541131 <callfork:INFO> Default fork for 'chan.dtmf' matching 'peerid' priority 100
Initializing module SIP Features
2021-08-28_10:16:28.541166 <sipfeatures:ALL> Allowed subscriptions: dialog,message-summary
Initializing module SNMP Agent
2021-08-28_10:16:28.545461 <snmpagent:ALL> SnmpMsgQueue created for 0.0.0.0:20211 with priority 'normal'
2021-08-28_10:16:28.545589 <snmpagent:INFO> SNMP UDP Listener initialized on port 20211
Initializing module Call Parking
Initializing module Cache
Initializing module MrcpSpeech
Initializing module Users Management
Initializing module MGCP Gateway
2021-08-28_10:16:28.546046 <mgcp_gw:INFO> Initialized:
type:              Gateway
bind address:      :2427
allow_unknown_cmd: false
retrans_interval:  250000
retrans_count:     3
lower_case_params: true
max_recv_packet:   1500
send_provisional:  true
2021-08-28_10:16:28.546106 <mgcpgw:ALL> Initialized audio codecs: mulaw,alaw,gsm,slin,g722/16000,ilbc20,ilbc30,isac/16000,isac/32000
Initializing module Signalling Channel
2021-08-28_10:16:28.546308 <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 [0x5645b7ddae40]
2021-08-28_10:16:28.546337 <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 [0x5645b7ddc420]
2021-08-28_10:16:28.546383 <sig:NOTE> Section 'tcapuser_test'. Unknown/missing type '(null)'
Initializing module Monitoring
2021-08-28_10:16:28.546513 <monitoring:ALL> RTPTable created [0x5645b7dde7a0]
2021-08-28_10:16:28.546526 <monitoring:ALL> RTPTable [0x5645b7dde7a0] configured with directions='(null)',resetTime=3600
Initializing module Late Router
Initializing module Presence
2021-08-28_10:16:28.546642 <presence:ALL> Initialized lists=16 expirecheck=0 expiretime=0 account=(null)
Initializing module YBTS
2021-08-28_10:16:28.546775 <ybts:INFO> LAI changed (null) -> 28607_03e8
Initializing module Queues Notify
2021-08-28_10:16:28.546916 <queuesnotify:INFO> Query 'callinfo' not configured
2021-08-28_10:16:28.546926 <queuesnotify:INFO> Query 'cdrinfo' not configured
2021-08-28_10:16:28.546933 <queuesnotify:ALL> Initialized: account= notifyhanguponunload=true
Initializing module Register for database
Initializing module PBX for database
Initializing module Accounts from file
Initializing module Analog Channel
Initializing module Queues for database
Initializing module GSM Transceiver
2021-08-28_10:16:28.547441 <gsmtrx:INFO> Socket(control) bound on 127.0.0.1:5700 (remote: 127.0.0.1:5701) [0x5645b7de94e0]
2021-08-28_10:16:28.547480 <gsmtrx:INFO> Module state changed Idle -> Waiting
Initializing module Register from file
Initializing module Radius client
2021-08-28_10:16:28.547626 <yradius:NOTE> Local address not set or invalid. Radius functions disabled
Initializing module CCongestion
Initializing module Radio Test
Initialization complete
2021-08-28_10:16:28.547700 <ALL> Engine dispatching start message
2021-08-28_10:16:28.547819 <ybts:NOTE> YBTSDriver State changed Idle -> Starting restart counter 1/10
2021-08-28_10:16:28.547889 <transceiver:ALL> Started worker thread [0x5645b7de4e80]
2021-08-28_10:16:28.547906 <transceiver:INFO> Started [0x5645b7de4e80]
2021-08-28_10:16:28.547950 <mbts:ALL> Started worker thread [0x5645b7de50c0]
2021-08-28_10:16:28.547960 <mbts:INFO> Started [0x5645b7de50c0]
2021-08-28_10:16:28.547976 <ybts-command:INFO> Started [0x5645b7de5320]
2021-08-28_10:16:28.548012 <ybts-signalling:ALL> Started worker thread [0x5645b7de5760]
2021-08-28_10:16:28.548023 <ybts-signalling:INFO> Started [0x5645b7de5760]
2021-08-28_10:16:28.548057 <ybts-media:ALL> Started worker thread [0x5645b7de5480]
2021-08-28_10:16:28.548067 <ybts-media:INFO> Started [0x5645b7de5480]
2021-08-28_10:16:28.548074 <ybts:ALL> Starting peer '/usr/local/lib/yate/server/bts/mbts' '(null)'
2021-08-28_10:16:28.548783 <ybts:INFO> Started peer pid=19170
2021-08-28_10:16:28.548851 <ybts:NOTE> YBTSDriver State changed Starting -> WaitHandshake
2021-08-28_10:16:28.548867 <ybts:ALL> Restart scheduled in 120000ms [0x7f8f57e66b00]
MBTS connected to YBTS
Yate-BTS MBTS Component
Copyright 2008, 2009, 2010 Free Software Foundation, Inc.
Copyright 2010 Kestrel Signal Processing, Inc.
Copyright 2011, 2012, 2013 Range Networks, Inc.
Copyright 2013, 2014 Null Team Impex SRL
Copyright 2014 Legba, Inc.
"OpenBTS" is a registered trademark of Range Networks, Inc.

Contributors:
  SC Null Team Impex SRL:
    Paul Chitescu
  Legba, Inc.
    David Burgess
  Range Networks, Inc.:
    David Burgess, Harvind Samra, Donald Kirker, Doug Brown,
    Pat Thompson, Kurtis Heimerl
  Kestrel Signal Processing, Inc.:
    David Burgess, Harvind Samra, Raffi Sevlian, Roshan Baliga
  GNU Radio:
    Johnathan Corgan
  Others:
    Anne Kwong, Jacob Appelbaum, Joshua Lackey, Alon Levy
    Alexander Chemeris, Alberto Escudero-Pascual
Incorporated L/GPL libraries and components:
  libusb, LGPL 2.1, various copyright holders, www.libusb.org
Incorporated BSD/MIT-style libraries and components:
  A5/1 Pedagogical Implementation, Simplified BSD License,
    Copyright 1998-1999 Marc Briceno, Ian Goldberg, and David Wagner
Incorporated public domain libraries and components:
  sqlite3, released to public domain 15 Sept 2001, www.sqlite.org


This program comes with ABSOLUTELY NO WARRANTY.

Use of this software may be subject to other legal restrictions,
including patent licensing and radio spectrum licensing.
All users of this software are expected to comply with applicable
regulations and laws.  See the LEGAL file in the source code for
more information.

Release 6.1.1 formal build date Aug 22 2021 rev
2021-08-28_10:16:28.554631 <mbts:NOTE> proc 19170 GSMConfig.cpp:80:regenerateBeacon: thread 140433301886784: regenerating system information messages, changemark 1
Starting MBTS...
2021-08-28_10:16:28.554852 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 command CMD RESET 1
2021-08-28_10:16:28.554994 <ybts-signalling:INFO> Received [0x5645b7de5760]
-----
Primitive: Handshake
Info: 0
-----
2021-08-28_10:16:28.555045 <ybts:NOTE> YBTSDriver State changed WaitHandshake -> Running
2021-08-28_10:16:28.555081 <ybts-signalling:INFO> Sending [0x5645b7de5760]
-----
Primitive: Handshake
Info: 0
-----
2021-08-28_10:16:28.556018 >>> RSP RESET ANSWER
2021-08-28_10:16:28.556103   >>> BrfModule::createIface()
2021-08-28_10:16:28.556141     <bladerf/1:ALL> Interface created [0x7f8f300011d0]
2021-08-28_10:16:28.568705     <javascript:INFO> Parsed 'nipc' script: /usr/local/share/yate/scripts/nipc.js
2021-08-28_10:16:28.571355     <nipc:INFO> Finished reading saved registered subscribers. Found 5 registered_subscribers.
2021-08-28_10:16:28.571630     <nipc:INFO> Checked that only NiPC is enabled.
2021-08-28_10:16:28.571668     <ALL> Rescanning handler list for 'engine.start' [0x7ffe3b1553a0] at priority 100
Yate engine is initialized and starting up on sandbox-HP-255-G8-Notebook-PC
2021-08-28_10:16:28.571759     <INFO> Creating first 1 message dispatching threads and semaphore
[WARNING @ host/libraries/libbladeRF/src/board/bladerf1/bladerf1.c:618] AGC commands for FPGA v0.7.1 are incompatible with this version of libbladeRF. Please update to FPGA v0.8.0 or newer to use AGC.
2021-08-28_10:16:28.719648     <bladerf:ALL> Initialized libbladerf context
2021-08-28_10:16:28.719798     <bladerf/1:ALL> Created device (0x7f8f30001410) [0x7f8f300011d0]
bladeRF detected, attached to serial=4a8a54b4dd653717e25379477a05909c
2021-08-28_10:16:28.719852   <<< BrfModule::createIface()
2021-08-28_10:16:28.719945   <gsmtrx:ALL> Adjusting parameter arfcns='1' -> 4 [0x7f8f300213f0]
2021-08-28_10:16:28.720426   <gsmtrx:ALL> Initialized [0x7f8f300213f0]
-----
ARFCNs=4
oversampling=8
-----
2021-08-28_10:16:28.728914   <gsmtrx:INFO> radio_latency_slots changed 0 -> 5 [0x7f8f300213f0]
2021-08-28_10:16:28.728983   <gsmtrx:INFO> tx_slots changed 1 -> 16 [0x7f8f300213f0]
2021-08-28_10:16:28.729013   <gsmtrx:INFO> Transceiver initialized radio=(0x7f8f300011d0) 'bladerf/1' [0x7f8f300213f0]
2021-08-28_10:16:28.729035   <gsmtrx:NOTE> Transceiver State changed Invalid -> Idle [0x7f8f300213f0]
2021-08-28_10:16:28.729060   <gsmtrx:ALL> Starting [0x7f8f300213f0]
2021-08-28_10:16:28.729109   <gsmtrx:INFO> Socket(clock) bound on 127.0.0.1:5702 (remote: 127.0.0.1:5703) [0x7f8f300213f0]
2021-08-28_10:16:28.729162   <gsmtrx:INFO> ARFCN[0]: Socket(data) bound on 127.0.0.1:5704 (remote: 127.0.0.1:5705) [0x7f8f3001c110]
2021-08-28_10:16:28.729202   <gsmtrx:INFO> ARFCN[1]: Socket(data) bound on 127.0.0.1:5706 (remote: 127.0.0.1:5707) [0x7f8f3000c2b0]
2021-08-28_10:16:28.729240   <gsmtrx:INFO> ARFCN[2]: Socket(data) bound on 127.0.0.1:5708 (remote: 127.0.0.1:5709) [0x7f8f3000f7e0]
2021-08-28_10:16:28.729277   <gsmtrx:INFO> ARFCN[3]: Socket(data) bound on 127.0.0.1:5710 (remote: 127.0.0.1:5711) [0x7f8f30026500]
2021-08-28_10:16:28.729298   <gsmtrx:NOTE> Transceiver State changed Idle -> PowerOff [0x7f8f300213f0]
2021-08-28_10:16:28.729319   <gsmtrx:INFO> Module state changed Waiting -> Running
2021-08-28_10:16:28.729344 <<< RSP RESET ANSWER
2021-08-28_10:16:28.729519 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 response RSP RESET 0 to command CMD RESET 1
2021-08-28_10:16:28.729576 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 command CMD STATISTICS OFF
2021-08-28_10:16:28.730713 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 response RSP STATISTICS 0 to command CMD STATISTICS OFF
2021-08-28_10:16:28.730772 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 command CMD 0 READFACTORY sdrsn
2021-08-28_10:16:28.731832 <gsmtrx:ALL> Handling command 'CMD 0 READFACTORY sdrsn' arfcn=0 [0x7f8f300213f0]
2021-08-28_10:16:28.731883 <gsmtrx:INFO> Command 'CMD 0 READFACTORY sdrsn' (ARFCN=0)
2021-08-28_10:16:28.731927 <gsmtrx:INFO> Response 'RSP READFACTORY 0 0' (ARFCN=0)
2021-08-28_10:16:28.732009 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 response RSP READFACTORY 0 0 to command CMD 0 READFACTORY sdrsn
2021-08-28_10:16:28.732150 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 command CMD 0 RXTUNE 1710200
2021-08-28_10:16:28.733279 <gsmtrx:ALL> Handling command 'CMD 0 RXTUNE 1710200' arfcn=0 [0x7f8f300213f0]
2021-08-28_10:16:28.735161 <gsmtrx:INFO> Command 'CMD 0 RXTUNE 1710200' (ARFCN=0)
2021-08-28_10:16:28.735233 <gsmtrx:INFO> Response 'RSP RXTUNE 0 1710800000' (ARFCN=0)
2021-08-28_10:16:28.735347 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 response RSP RXTUNE 0 1710800000 to command CMD 0 RXTUNE 1710200
2021-08-28_10:16:28.735392 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 command CMD 0 TXTUNE 1805200
2021-08-28_10:16:28.736435 <gsmtrx:ALL> Handling command 'CMD 0 TXTUNE 1805200' arfcn=0 [0x7f8f300213f0]
2021-08-28_10:16:28.738309 <gsmtrx:INFO> Command 'CMD 0 TXTUNE 1805200' (ARFCN=0)
2021-08-28_10:16:28.738374 <gsmtrx:INFO> Response 'RSP TXTUNE 0 1805800000' (ARFCN=0)
2021-08-28_10:16:28.738455 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 response RSP TXTUNE 0 1805800000 to command CMD 0 TXTUNE 1805200
2021-08-28_10:16:28.738495 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 command CMD 0 SETTSC 2
2021-08-28_10:16:28.739598 <gsmtrx:ALL> Handling command 'CMD 0 SETTSC 2' arfcn=0 [0x7f8f300213f0]
2021-08-28_10:16:28.739654 <gsmtrx:INFO> TSC changed 0 -> 2 [0x7f8f300213f0]
2021-08-28_10:16:28.739680 <gsmtrx:INFO> Command 'CMD 0 SETTSC 2' (ARFCN=0)
2021-08-28_10:16:28.739728 <gsmtrx:INFO> Response 'RSP SETTSC 0 2' (ARFCN=0)
2021-08-28_10:16:28.739865 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 response RSP SETTSC 0 2 to command CMD 0 SETTSC 2
2021-08-28_10:16:28.739912 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 command CMD 0 SETMAXDLY 2
2021-08-28_10:16:28.740976 <gsmtrx:ALL> Handling command 'CMD 0 SETMAXDLY 2' arfcn=0 [0x7f8f300213f0]
2021-08-28_10:16:28.741025 <gsmtrx:INFO> Command 'CMD 0 SETMAXDLY 2' (ARFCN=0)
2021-08-28_10:16:28.741074 <gsmtrx:INFO> Response 'RSP SETMAXDLY 0 2' (ARFCN=0)
2021-08-28_10:16:28.741149 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 response RSP SETMAXDLY 0 2 to command CMD 0 SETMAXDLY 2
2021-08-28_10:16:28.741197 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 command CMD 0 SETRXGAIN 0
2021-08-28_10:16:28.742266 <gsmtrx:ALL> Handling command 'CMD 0 SETRXGAIN 0' arfcn=0 [0x7f8f300213f0]
2021-08-28_10:16:28.743415 <gsmtrx:INFO> Command 'CMD 0 SETRXGAIN 0' (ARFCN=0)
2021-08-28_10:16:28.743479 <gsmtrx:INFO> Response 'RSP SETRXGAIN 0 0' (ARFCN=0)
2021-08-28_10:16:28.743568 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 response RSP SETRXGAIN 0 0 to command CMD 0 SETRXGAIN 0
2021-08-28_10:16:28.743616 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 command CMD 0 POWERON
2021-08-28_10:16:28.744675 <gsmtrx:ALL> Handling command 'CMD 0 POWERON' arfcn=0 [0x7f8f300213f0]
2021-08-28_10:16:28.744720 <gsmtrx:INFO> Starting radio [0x7f8f300213f0]
2021-08-28_10:16:28.766033 <gsmtrx:NOTE> Transceiver State changed PowerOff -> PowerOn [0x7f8f300213f0]
2021-08-28_10:16:28.766142 <gsmtrx:INFO> Command 'CMD 0 POWERON' (ARFCN=0)
2021-08-28_10:16:28.766217 <gsmtrx:INFO> Response 'RSP POWERON 0' (ARFCN=0)
2021-08-28_10:16:28.766339 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 response RSP POWERON 0 to command CMD 0 POWERON
2021-08-28_10:16:28.766395 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 command CMD 0 SETPOWER 0
2021-08-28_10:16:28.767438 <gsmtrx:ALL> Handling command 'CMD 0 SETPOWER 0' arfcn=0 [0x7f8f300213f0]
[WARNING @ host/libraries/libbladeRF/src/board/bladerf1/bladerf1.c:1615] set_tx_gain: unable to achieve requested gain 410 (missed by 337)
2021-08-28_10:16:28.768012 <gsmtrx:INFO> Set Tx gain=0 power_scaling=1 [0x7f8f300213f0]
2021-08-28_10:16:28.768056 <gsmtrx:INFO> Command 'CMD 0 SETPOWER 0' (ARFCN=0)
2021-08-28_10:16:28.768102 <gsmtrx:INFO> Response 'RSP SETPOWER 0 0' (ARFCN=0)
2021-08-28_10:16:28.768165 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 response RSP SETPOWER 0 0 to command CMD 0 SETPOWER 0
2021-08-28_10:16:28.768204 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 command CMD 0 SETSLOT 0 5
2021-08-28_10:16:28.769247 <gsmtrx:ALL> Handling command 'CMD 0 SETSLOT 0 5' arfcn=0 [0x7f8f300213f0]
2021-08-28_10:16:28.769276 <gsmtrx:ALL> ARFCN[0]: Slot 0 type set to 5 'V' [0x7f8f3001c110]
2021-08-28_10:16:28.769295 <gsmtrx:ALL> ARFCN[0]: Slot 0 burst type changed None -> Check [0x7f8f3001c110]
2021-08-28_10:16:28.769319 <gsmtrx:INFO> Command 'CMD 0 SETSLOT 0 5' (ARFCN=0)
2021-08-28_10:16:28.769352 <gsmtrx:INFO> Response 'RSP SETSLOT 0 0 5' (ARFCN=0)
2021-08-28_10:16:28.769400 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 response RSP SETSLOT 0 0 5 to command CMD 0 SETSLOT 0 5
2021-08-28_10:16:28.769442 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 command CMD 0 NOHANDOVER 0
2021-08-28_10:16:28.770551 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f8f300213f0]
2021-08-28_10:16:28.770624 <gsmtrx:INFO> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0)
2021-08-28_10:16:28.770722 <gsmtrx:INFO> Response 'RSP NOHANDOVER 0 0' (ARFCN=0)
2021-08-28_10:16:28.770860 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 response RSP NOHANDOVER 0 0 to command CMD 0 NOHANDOVER 0
2021-08-28_10:16:28.770969 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 command CMD 0 NOHANDOVER 0
2021-08-28_10:16:28.772067 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f8f300213f0]
2021-08-28_10:16:28.772125 <gsmtrx:INFO> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0)
2021-08-28_10:16:28.772174 <gsmtrx:INFO> Response 'RSP NOHANDOVER 0 0' (ARFCN=0)
2021-08-28_10:16:28.772254 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 response RSP NOHANDOVER 0 0 to command CMD 0 NOHANDOVER 0
2021-08-28_10:16:28.772358 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 command CMD 0 NOHANDOVER 0
2021-08-28_10:16:28.773446 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f8f300213f0]
2021-08-28_10:16:28.773505 <gsmtrx:INFO> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0)
2021-08-28_10:16:28.773591 <gsmtrx:INFO> Response 'RSP NOHANDOVER 0 0' (ARFCN=0)
2021-08-28_10:16:28.773690 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 response RSP NOHANDOVER 0 0 to command CMD 0 NOHANDOVER 0
2021-08-28_10:16:28.773967 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 command CMD 0 NOHANDOVER 0
2021-08-28_10:16:28.775080 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f8f300213f0]
2021-08-28_10:16:28.775136 <gsmtrx:INFO> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0)
2021-08-28_10:16:28.775177 <gsmtrx:INFO> Response 'RSP NOHANDOVER 0 0' (ARFCN=0)
2021-08-28_10:16:28.775265 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 response RSP NOHANDOVER 0 0 to command CMD 0 NOHANDOVER 0
2021-08-28_10:16:28.775379 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 command CMD 0 NOHANDOVER 0
2021-08-28_10:16:28.776475 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f8f300213f0]
2021-08-28_10:16:28.776533 <gsmtrx:INFO> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0)
2021-08-28_10:16:28.776583 <gsmtrx:INFO> Response 'RSP NOHANDOVER 0 0' (ARFCN=0)
2021-08-28_10:16:28.776662 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 response RSP NOHANDOVER 0 0 to command CMD 0 NOHANDOVER 0
2021-08-28_10:16:28.776780 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 command CMD 0 NOHANDOVER 0
2021-08-28_10:16:28.777855 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f8f300213f0]
2021-08-28_10:16:28.777893 <gsmtrx:INFO> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0)
2021-08-28_10:16:28.777921 <gsmtrx:INFO> Response 'RSP NOHANDOVER 0 0' (ARFCN=0)
2021-08-28_10:16:28.777977 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 response RSP NOHANDOVER 0 0 to command CMD 0 NOHANDOVER 0
2021-08-28_10:16:28.778417 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 command CMD 0 NOHANDOVER 0
2021-08-28_10:16:28.779529 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f8f300213f0]
2021-08-28_10:16:28.779587 <gsmtrx:INFO> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0)
2021-08-28_10:16:28.779631 <gsmtrx:INFO> Response 'RSP NOHANDOVER 0 0' (ARFCN=0)
2021-08-28_10:16:28.779712 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 response RSP NOHANDOVER 0 0 to command CMD 0 NOHANDOVER 0
2021-08-28_10:16:28.779923 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 command CMD 0 NOHANDOVER 0
2021-08-28_10:16:28.781011 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f8f300213f0]
2021-08-28_10:16:28.781061 <gsmtrx:INFO> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0)
2021-08-28_10:16:28.781099 <gsmtrx:INFO> Response 'RSP NOHANDOVER 0 0' (ARFCN=0)
2021-08-28_10:16:28.781195 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 response RSP NOHANDOVER 0 0 to command CMD 0 NOHANDOVER 0
2021-08-28_10:16:28.781489 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 command CMD 0 NOHANDOVER 0
2021-08-28_10:16:28.782575 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f8f300213f0]
2021-08-28_10:16:28.782615 <gsmtrx:INFO> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0)
2021-08-28_10:16:28.782651 <gsmtrx:INFO> Response 'RSP NOHANDOVER 0 0' (ARFCN=0)
2021-08-28_10:16:28.782744 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 response RSP NOHANDOVER 0 0 to command CMD 0 NOHANDOVER 0
2021-08-28_10:16:28.782901 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 command CMD 0 NOHANDOVER 0
2021-08-28_10:16:28.784117 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f8f300213f0]
2021-08-28_10:16:28.784158 <gsmtrx:INFO> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0)
2021-08-28_10:16:28.784195 <gsmtrx:INFO> Response 'RSP NOHANDOVER 0 0' (ARFCN=0)
2021-08-28_10:16:28.784273 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 response RSP NOHANDOVER 0 0 to command CMD 0 NOHANDOVER 0
2021-08-28_10:16:28.784513 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 command CMD 0 NOHANDOVER 0
2021-08-28_10:16:28.785601 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f8f300213f0]
2021-08-28_10:16:28.785638 <gsmtrx:INFO> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0)
2021-08-28_10:16:28.785673 <gsmtrx:INFO> Response 'RSP NOHANDOVER 0 0' (ARFCN=0)
2021-08-28_10:16:28.785751 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 response RSP NOHANDOVER 0 0 to command CMD 0 NOHANDOVER 0
2021-08-28_10:16:28.785918 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 command CMD 0 NOHANDOVER 0
2021-08-28_10:16:28.787004 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f8f300213f0]
2021-08-28_10:16:28.787033 <gsmtrx:INFO> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0)
2021-08-28_10:16:28.787062 <gsmtrx:INFO> Response 'RSP NOHANDOVER 0 0' (ARFCN=0)
2021-08-28_10:16:28.787128 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 response RSP NOHANDOVER 0 0 to command CMD 0 NOHANDOVER 0
2021-08-28_10:16:28.787313 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 command CMD 0 NOHANDOVER 0
2021-08-28_10:16:28.788387 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f8f300213f0]
2021-08-28_10:16:28.788422 <gsmtrx:INFO> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0)
2021-08-28_10:16:28.788466 <gsmtrx:INFO> Response 'RSP NOHANDOVER 0 0' (ARFCN=0)
2021-08-28_10:16:28.788548 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 response RSP NOHANDOVER 0 0 to command CMD 0 NOHANDOVER 0
2021-08-28_10:16:28.788692 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 command CMD 0 NOHANDOVER 0
2021-08-28_10:16:28.789758 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f8f300213f0]
2021-08-28_10:16:28.789801 <gsmtrx:INFO> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0)
2021-08-28_10:16:28.789902 <gsmtrx:INFO> Response 'RSP NOHANDOVER 0 0' (ARFCN=0)
2021-08-28_10:16:28.789981 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 response RSP NOHANDOVER 0 0 to command CMD 0 NOHANDOVER 0
2021-08-28_10:16:28.790175 <mbts:NOTE> proc 19170 GSMConfig.cpp:532:createCombinationI: thread 140433301886784: Configuring combination I on C0T1
2021-08-28_10:16:28.790204 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 command CMD 0 SETSLOT 1 1
2021-08-28_10:16:28.791352 <gsmtrx:ALL> Handling command 'CMD 0 SETSLOT 1 1' arfcn=0 [0x7f8f300213f0]
2021-08-28_10:16:28.791411 <gsmtrx:ALL> ARFCN[0]: Slot 1 type set to 1 'I' [0x7f8f3001c110]
2021-08-28_10:16:28.791429 <gsmtrx:ALL> ARFCN[0]: Slot 1 burst type changed None -> Normal [0x7f8f3001c110]
2021-08-28_10:16:28.791446 <gsmtrx:INFO> Command 'CMD 0 SETSLOT 1 1' (ARFCN=0)
2021-08-28_10:16:28.791498 <gsmtrx:INFO> Response 'RSP SETSLOT 0 1 1' (ARFCN=0)
2021-08-28_10:16:28.791600 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 response RSP SETSLOT 0 1 1 to command CMD 0 SETSLOT 1 1
2021-08-28_10:16:28.791824 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 command CMD 0 NOHANDOVER 1
2021-08-28_10:16:28.792895 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 1' arfcn=0 [0x7f8f300213f0]
2021-08-28_10:16:28.792943 <gsmtrx:INFO> Command 'CMD 0 NOHANDOVER 1' (ARFCN=0)
2021-08-28_10:16:28.792982 <gsmtrx:INFO> Response 'RSP NOHANDOVER 0 1' (ARFCN=0)
2021-08-28_10:16:28.793042 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 response RSP NOHANDOVER 0 1 to command CMD 0 NOHANDOVER 1
2021-08-28_10:16:28.793195 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 command CMD 0 NOHANDOVER 1
2021-08-28_10:16:28.794267 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 1' arfcn=0 [0x7f8f300213f0]
2021-08-28_10:16:28.794304 <gsmtrx:INFO> Command 'CMD 0 NOHANDOVER 1' (ARFCN=0)
2021-08-28_10:16:28.794339 <gsmtrx:INFO> Response 'RSP NOHANDOVER 0 1' (ARFCN=0)
2021-08-28_10:16:28.794395 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 response RSP NOHANDOVER 0 1 to command CMD 0 NOHANDOVER 1
2021-08-28_10:16:28.794524 <mbts:NOTE> proc 19170 GSMConfig.cpp:532:createCombinationI: thread 140433301886784: Configuring combination I on C0T2
2021-08-28_10:16:28.794570 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 command CMD 0 SETSLOT 2 1
2021-08-28_10:16:28.795614 <gsmtrx:ALL> Handling command 'CMD 0 SETSLOT 2 1' arfcn=0 [0x7f8f300213f0]
2021-08-28_10:16:28.795652 <gsmtrx:ALL> ARFCN[0]: Slot 2 type set to 1 'I' [0x7f8f3001c110]
2021-08-28_10:16:28.795671 <gsmtrx:ALL> ARFCN[0]: Slot 2 burst type changed None -> Normal [0x7f8f3001c110]
2021-08-28_10:16:28.795688 <gsmtrx:INFO> Command 'CMD 0 SETSLOT 2 1' (ARFCN=0)
2021-08-28_10:16:28.795724 <gsmtrx:INFO> Response 'RSP SETSLOT 0 2 1' (ARFCN=0)
2021-08-28_10:16:28.795800 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 response RSP SETSLOT 0 2 1 to command CMD 0 SETSLOT 2 1
2021-08-28_10:16:28.795992 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 command CMD 0 NOHANDOVER 2
2021-08-28_10:16:28.797068 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 2' arfcn=0 [0x7f8f300213f0]
2021-08-28_10:16:28.797107 <gsmtrx:INFO> Command 'CMD 0 NOHANDOVER 2' (ARFCN=0)
2021-08-28_10:16:28.797141 <gsmtrx:INFO> Response 'RSP NOHANDOVER 0 2' (ARFCN=0)
2021-08-28_10:16:28.797198 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 response RSP NOHANDOVER 0 2 to command CMD 0 NOHANDOVER 2
2021-08-28_10:16:28.797332 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 command CMD 0 NOHANDOVER 2
2021-08-28_10:16:28.798405 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 2' arfcn=0 [0x7f8f300213f0]
2021-08-28_10:16:28.798439 <gsmtrx:INFO> Command 'CMD 0 NOHANDOVER 2' (ARFCN=0)
2021-08-28_10:16:28.798471 <gsmtrx:INFO> Response 'RSP NOHANDOVER 0 2' (ARFCN=0)
2021-08-28_10:16:28.798524 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 response RSP NOHANDOVER 0 2 to command CMD 0 NOHANDOVER 2
2021-08-28_10:16:28.798656 <mbts:NOTE> proc 19170 GSMConfig.cpp:532:createCombinationI: thread 140433301886784: Configuring combination I on C0T3
2021-08-28_10:16:28.798684 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 command CMD 0 SETSLOT 3 1
2021-08-28_10:16:28.799787 <gsmtrx:ALL> Handling command 'CMD 0 SETSLOT 3 1' arfcn=0 [0x7f8f300213f0]
2021-08-28_10:16:28.799841 <gsmtrx:ALL> ARFCN[0]: Slot 3 type set to 1 'I' [0x7f8f3001c110]
2021-08-28_10:16:28.799860 <gsmtrx:ALL> ARFCN[0]: Slot 3 burst type changed None -> Normal [0x7f8f3001c110]
2021-08-28_10:16:28.799877 <gsmtrx:INFO> Command 'CMD 0 SETSLOT 3 1' (ARFCN=0)
2021-08-28_10:16:28.799912 <gsmtrx:INFO> Response 'RSP SETSLOT 0 3 1' (ARFCN=0)
2021-08-28_10:16:28.799975 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 response RSP SETSLOT 0 3 1 to command CMD 0 SETSLOT 3 1
2021-08-28_10:16:28.800295 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 command CMD 0 NOHANDOVER 3
2021-08-28_10:16:28.801386 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 3' arfcn=0 [0x7f8f300213f0]
2021-08-28_10:16:28.801419 <gsmtrx:INFO> Command 'CMD 0 NOHANDOVER 3' (ARFCN=0)
2021-08-28_10:16:28.801453 <gsmtrx:INFO> Response 'RSP NOHANDOVER 0 3' (ARFCN=0)
2021-08-28_10:16:28.801517 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 response RSP NOHANDOVER 0 3 to command CMD 0 NOHANDOVER 3
2021-08-28_10:16:28.801702 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 command CMD 0 NOHANDOVER 3
2021-08-28_10:16:28.802803 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 3' arfcn=0 [0x7f8f300213f0]
2021-08-28_10:16:28.802863 <gsmtrx:INFO> Command 'CMD 0 NOHANDOVER 3' (ARFCN=0)
2021-08-28_10:16:28.802945 <gsmtrx:INFO> Response 'RSP NOHANDOVER 0 3' (ARFCN=0)
2021-08-28_10:16:28.803066 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 response RSP NOHANDOVER 0 3 to command CMD 0 NOHANDOVER 3
2021-08-28_10:16:28.803261 <mbts:NOTE> proc 19170 GSMConfig.cpp:532:createCombinationI: thread 140433301886784: Configuring combination I on C0T4
2021-08-28_10:16:28.803311 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 command CMD 0 SETSLOT 4 1
2021-08-28_10:16:28.804364 <gsmtrx:ALL> Handling command 'CMD 0 SETSLOT 4 1' arfcn=0 [0x7f8f300213f0]
2021-08-28_10:16:28.804423 <gsmtrx:ALL> ARFCN[0]: Slot 4 type set to 1 'I' [0x7f8f3001c110]
2021-08-28_10:16:28.804458 <gsmtrx:ALL> ARFCN[0]: Slot 4 burst type changed None -> Normal [0x7f8f3001c110]
2021-08-28_10:16:28.804509 <gsmtrx:INFO> Command 'CMD 0 SETSLOT 4 1' (ARFCN=0)
2021-08-28_10:16:28.804574 <gsmtrx:INFO> Response 'RSP SETSLOT 0 4 1' (ARFCN=0)
2021-08-28_10:16:28.804709 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 response RSP SETSLOT 0 4 1 to command CMD 0 SETSLOT 4 1
2021-08-28_10:16:28.804907 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 command CMD 0 NOHANDOVER 4
2021-08-28_10:16:28.805982 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 4' arfcn=0 [0x7f8f300213f0]
2021-08-28_10:16:28.806042 <gsmtrx:INFO> Command 'CMD 0 NOHANDOVER 4' (ARFCN=0)
2021-08-28_10:16:28.806097 <gsmtrx:INFO> Response 'RSP NOHANDOVER 0 4' (ARFCN=0)
2021-08-28_10:16:28.806185 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 response RSP NOHANDOVER 0 4 to command CMD 0 NOHANDOVER 4
2021-08-28_10:16:28.806468 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 command CMD 0 NOHANDOVER 4
2021-08-28_10:16:28.807554 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 4' arfcn=0 [0x7f8f300213f0]
2021-08-28_10:16:28.807649 <gsmtrx:INFO> Command 'CMD 0 NOHANDOVER 4' (ARFCN=0)
2021-08-28_10:16:28.807739 <gsmtrx:INFO> Response 'RSP NOHANDOVER 0 4' (ARFCN=0)
2021-08-28_10:16:28.807870 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 response RSP NOHANDOVER 0 4 to command CMD 0 NOHANDOVER 4
2021-08-28_10:16:28.808066 <mbts:NOTE> proc 19170 GSMConfig.cpp:532:createCombinationI: thread 140433301886784: Configuring combination I on C0T5
2021-08-28_10:16:28.808130 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 command CMD 0 SETSLOT 5 1
2021-08-28_10:16:28.809150 <gsmtrx:ALL> Handling command 'CMD 0 SETSLOT 5 1' arfcn=0 [0x7f8f300213f0]
2021-08-28_10:16:28.809191 <gsmtrx:ALL> ARFCN[0]: Slot 5 type set to 1 'I' [0x7f8f3001c110]
2021-08-28_10:16:28.809216 <gsmtrx:ALL> ARFCN[0]: Slot 5 burst type changed None -> Normal [0x7f8f3001c110]
2021-08-28_10:16:28.809238 <gsmtrx:INFO> Command 'CMD 0 SETSLOT 5 1' (ARFCN=0)
2021-08-28_10:16:28.809278 <gsmtrx:INFO> Response 'RSP SETSLOT 0 5 1' (ARFCN=0)
2021-08-28_10:16:28.809351 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 response RSP SETSLOT 0 5 1 to command CMD 0 SETSLOT 5 1
2021-08-28_10:16:28.809537 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 command CMD 0 NOHANDOVER 5
2021-08-28_10:16:28.810602 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 5' arfcn=0 [0x7f8f300213f0]
2021-08-28_10:16:28.810644 <gsmtrx:INFO> Command 'CMD 0 NOHANDOVER 5' (ARFCN=0)
2021-08-28_10:16:28.810691 <gsmtrx:INFO> Response 'RSP NOHANDOVER 0 5' (ARFCN=0)
2021-08-28_10:16:28.810789 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 response RSP NOHANDOVER 0 5 to command CMD 0 NOHANDOVER 5
2021-08-28_10:16:28.810936 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 command CMD 0 NOHANDOVER 5
2021-08-28_10:16:28.812034 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 5' arfcn=0 [0x7f8f300213f0]
2021-08-28_10:16:28.812074 <gsmtrx:INFO> Command 'CMD 0 NOHANDOVER 5' (ARFCN=0)
2021-08-28_10:16:28.812128 <gsmtrx:INFO> Response 'RSP NOHANDOVER 0 5' (ARFCN=0)
2021-08-28_10:16:28.812220 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 response RSP NOHANDOVER 0 5 to command CMD 0 NOHANDOVER 5
2021-08-28_10:16:28.812432 <mbts:NOTE> proc 19170 GSMConfig.cpp:532:createCombinationI: thread 140433301886784: Configuring combination I on C0T6
2021-08-28_10:16:28.812464 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 command CMD 0 SETSLOT 6 1
2021-08-28_10:16:28.813541 <gsmtrx:ALL> Handling command 'CMD 0 SETSLOT 6 1' arfcn=0 [0x7f8f300213f0]
2021-08-28_10:16:28.813586 <gsmtrx:ALL> ARFCN[0]: Slot 6 type set to 1 'I' [0x7f8f3001c110]
2021-08-28_10:16:28.813614 <gsmtrx:ALL> ARFCN[0]: Slot 6 burst type changed None -> Normal [0x7f8f3001c110]
2021-08-28_10:16:28.813635 <gsmtrx:INFO> Command 'CMD 0 SETSLOT 6 1' (ARFCN=0)
2021-08-28_10:16:28.813677 <gsmtrx:INFO> Response 'RSP SETSLOT 0 6 1' (ARFCN=0)
2021-08-28_10:16:28.813759 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 response RSP SETSLOT 0 6 1 to command CMD 0 SETSLOT 6 1
2021-08-28_10:16:28.814032 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 command CMD 0 NOHANDOVER 6
2021-08-28_10:16:28.815131 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 6' arfcn=0 [0x7f8f300213f0]
2021-08-28_10:16:28.815208 <gsmtrx:INFO> Command 'CMD 0 NOHANDOVER 6' (ARFCN=0)
2021-08-28_10:16:28.815262 <gsmtrx:INFO> Response 'RSP NOHANDOVER 0 6' (ARFCN=0)
2021-08-28_10:16:28.815348 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 response RSP NOHANDOVER 0 6 to command CMD 0 NOHANDOVER 6
2021-08-28_10:16:28.815596 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 command CMD 0 NOHANDOVER 6
2021-08-28_10:16:28.816717 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 6' arfcn=0 [0x7f8f300213f0]
2021-08-28_10:16:28.816783 <gsmtrx:INFO> Command 'CMD 0 NOHANDOVER 6' (ARFCN=0)
2021-08-28_10:16:28.816829 <gsmtrx:INFO> Response 'RSP NOHANDOVER 0 6' (ARFCN=0)
2021-08-28_10:16:28.816926 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 response RSP NOHANDOVER 0 6 to command CMD 0 NOHANDOVER 6
2021-08-28_10:16:28.817155 <mbts:NOTE> proc 19170 GSMConfig.cpp:532:createCombinationI: thread 140433301886784: Configuring combination I on C0T7
2021-08-28_10:16:28.817185 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 command CMD 0 SETSLOT 7 1
2021-08-28_10:16:28.818248 <gsmtrx:ALL> Handling command 'CMD 0 SETSLOT 7 1' arfcn=0 [0x7f8f300213f0]
2021-08-28_10:16:28.818285 <gsmtrx:ALL> ARFCN[0]: Slot 7 type set to 1 'I' [0x7f8f3001c110]
2021-08-28_10:16:28.818311 <gsmtrx:ALL> ARFCN[0]: Slot 7 burst type changed None -> Normal [0x7f8f3001c110]
2021-08-28_10:16:28.818335 <gsmtrx:INFO> Command 'CMD 0 SETSLOT 7 1' (ARFCN=0)
2021-08-28_10:16:28.818380 <gsmtrx:INFO> Response 'RSP SETSLOT 0 7 1' (ARFCN=0)
2021-08-28_10:16:28.818456 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 response RSP SETSLOT 0 7 1 to command CMD 0 SETSLOT 7 1
2021-08-28_10:16:28.818717 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 command CMD 0 NOHANDOVER 7
2021-08-28_10:16:28.819883 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 7' arfcn=0 [0x7f8f300213f0]
2021-08-28_10:16:28.819949 <gsmtrx:INFO> Command 'CMD 0 NOHANDOVER 7' (ARFCN=0)
2021-08-28_10:16:28.819994 <gsmtrx:INFO> Response 'RSP NOHANDOVER 0 7' (ARFCN=0)
2021-08-28_10:16:28.820061 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 response RSP NOHANDOVER 0 7 to command CMD 0 NOHANDOVER 7
2021-08-28_10:16:28.820215 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 command CMD 0 NOHANDOVER 7
2021-08-28_10:16:28.821314 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 7' arfcn=0 [0x7f8f300213f0]
2021-08-28_10:16:28.821362 <gsmtrx:INFO> Command 'CMD 0 NOHANDOVER 7' (ARFCN=0)
2021-08-28_10:16:28.821410 <gsmtrx:INFO> Response 'RSP NOHANDOVER 0 7' (ARFCN=0)
2021-08-28_10:16:28.821490 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 response RSP NOHANDOVER 0 7 to command CMD 0 NOHANDOVER 7
2021-08-28_10:16:28.821657 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 command CMD STATISTICS ON
2021-08-28_10:16:28.822765 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 response RSP STATISTICS 0 to command CMD STATISTICS ON
2021-08-28_10:16:28.822804 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 command CMD 0 SETPOWER 10
2021-08-28_10:16:28.823855 <gsmtrx:ALL> Handling command 'CMD 0 SETPOWER 10' arfcn=0 [0x7f8f300213f0]
[WARNING @ host/libraries/libbladeRF/src/board/bladerf1/bladerf1.c:1615] set_tx_gain: unable to achieve requested gain 310 (missed by 237)
2021-08-28_10:16:28.824495 <gsmtrx:INFO> Set Tx gain=0 power_scaling=0.316228 [0x7f8f300213f0]
2021-08-28_10:16:28.824547 <gsmtrx:INFO> Command 'CMD 0 SETPOWER 10' (ARFCN=0)
2021-08-28_10:16:28.824594 <gsmtrx:INFO> Response 'RSP SETPOWER 0 10' (ARFCN=0)
2021-08-28_10:16:28.824678 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433301886784: this:0x55e9810835c0 response RSP SETPOWER 0 10 to command CMD 0 SETPOWER 10
2021-08-28_10:16:29.076248 <mbts:MILD> proc 19170 iputils.cpp:456:ip_finddns: thread 140433301886784: GGSN: DNS servers: 127.0.0.53 0.0.0.0
RTNETLINK answers: File exists
2021-08-28_10:16:29.518752 <cpuload:NOTE> Updating CPU core number from 1 to 8
MBTS ready
2021-08-28_10:16:29.579423 <ybts-signalling:INFO> Received [0x5645b7de5760]
-----
Primitive: RadioReady
Info: 0
-----
2021-08-28_10:16:29.579461 <ybts:NOTE> YBTSDriver State changed Running -> RadioUp
2021-08-28_10:16:32.806910 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433261487872: this:0x55e9810835c0 command CMD 0 SETSLOT 1 10
2021-08-28_10:16:32.808026 <gsmtrx:ALL> Handling command 'CMD 0 SETSLOT 1 10' arfcn=0 [0x7f8f300213f0]
2021-08-28_10:16:32.808063 <gsmtrx:ALL> ARFCN[0]: Slot 1 type set to 10 'IGPRS' [0x7f8f3001c110]
2021-08-28_10:16:32.808078 <gsmtrx:INFO> Command 'CMD 0 SETSLOT 1 10' (ARFCN=0)
2021-08-28_10:16:32.808114 <gsmtrx:INFO> Response 'RSP SETSLOT 0 1 10' (ARFCN=0)
2021-08-28_10:16:32.808242 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433261487872: this:0x55e9810835c0 response RSP SETSLOT 0 1 10 to command CMD 0 SETSLOT 1 10
2021-08-28_10:16:32.808837 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433261487872: this:0x55e9810835c0 command CMD 0 SETSLOT 2 10
2021-08-28_10:16:32.809920 <gsmtrx:ALL> Handling command 'CMD 0 SETSLOT 2 10' arfcn=0 [0x7f8f300213f0]
2021-08-28_10:16:32.809949 <gsmtrx:ALL> ARFCN[0]: Slot 2 type set to 10 'IGPRS' [0x7f8f3001c110]
2021-08-28_10:16:32.809961 <gsmtrx:INFO> Command 'CMD 0 SETSLOT 2 10' (ARFCN=0)
2021-08-28_10:16:32.809994 <gsmtrx:INFO> Response 'RSP SETSLOT 0 2 10' (ARFCN=0)
2021-08-28_10:16:32.810077 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433261487872: this:0x55e9810835c0 response RSP SETSLOT 0 2 10 to command CMD 0 SETSLOT 2 10
2021-08-28_10:16:32.810659 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433261487872: this:0x55e9810835c0 command CMD 0 SETSLOT 3 10
2021-08-28_10:16:32.811747 <gsmtrx:ALL> Handling command 'CMD 0 SETSLOT 3 10' arfcn=0 [0x7f8f300213f0]
2021-08-28_10:16:32.811764 <gsmtrx:ALL> ARFCN[0]: Slot 3 type set to 10 'IGPRS' [0x7f8f3001c110]
2021-08-28_10:16:32.811790 <gsmtrx:INFO> Command 'CMD 0 SETSLOT 3 10' (ARFCN=0)
2021-08-28_10:16:32.811837 <gsmtrx:INFO> Response 'RSP SETSLOT 0 3 10' (ARFCN=0)
2021-08-28_10:16:32.811928 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433261487872: this:0x55e9810835c0 response RSP SETSLOT 0 3 10 to command CMD 0 SETSLOT 3 10
2021-08-28_10:16:34.825215 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433262929664: this:0x55e9810835c0 command CMD 0 SETPOWER 9
2021-08-28_10:16:34.826304 <gsmtrx:ALL> Handling command 'CMD 0 SETPOWER 9' arfcn=0 [0x7f8f300213f0]
[WARNING @ host/libraries/libbladeRF/src/board/bladerf1/bladerf1.c:1615] set_tx_gain: unable to achieve requested gain 320 (missed by 247)
2021-08-28_10:16:34.826934 <gsmtrx:INFO> Set Tx gain=0 power_scaling=0.354813 [0x7f8f300213f0]
2021-08-28_10:16:34.826956 <gsmtrx:INFO> Command 'CMD 0 SETPOWER 9' (ARFCN=0)
2021-08-28_10:16:34.826994 <gsmtrx:INFO> Response 'RSP SETPOWER 0 9' (ARFCN=0)
2021-08-28_10:16:34.827072 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433262929664: this:0x55e9810835c0 response RSP SETPOWER 0 9 to command CMD 0 SETPOWER 9
2021-08-28_10:16:40.827427 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433262929664: this:0x55e9810835c0 command CMD 0 SETPOWER 8
2021-08-28_10:16:40.828530 <gsmtrx:ALL> Handling command 'CMD 0 SETPOWER 8' arfcn=0 [0x7f8f300213f0]
[WARNING @ host/libraries/libbladeRF/src/board/bladerf1/bladerf1.c:1615] set_tx_gain: unable to achieve requested gain 330 (missed by 257)
2021-08-28_10:16:40.829118 <gsmtrx:INFO> Set Tx gain=0 power_scaling=0.398107 [0x7f8f300213f0]
2021-08-28_10:16:40.829143 <gsmtrx:INFO> Command 'CMD 0 SETPOWER 8' (ARFCN=0)
2021-08-28_10:16:40.829183 <gsmtrx:INFO> Response 'RSP SETPOWER 0 8' (ARFCN=0)
2021-08-28_10:16:40.829273 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433262929664: this:0x55e9810835c0 response RSP SETPOWER 0 8 to command CMD 0 SETPOWER 8
2021-08-28_10:16:46.829597 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433262929664: this:0x55e9810835c0 command CMD 0 SETPOWER 7
2021-08-28_10:16:46.830686 <gsmtrx:ALL> Handling command 'CMD 0 SETPOWER 7' arfcn=0 [0x7f8f300213f0]
[WARNING @ host/libraries/libbladeRF/src/board/bladerf1/bladerf1.c:1615] set_tx_gain: unable to achieve requested gain 340 (missed by 267)
2021-08-28_10:16:46.831274 <gsmtrx:INFO> Set Tx gain=0 power_scaling=0.446684 [0x7f8f300213f0]
2021-08-28_10:16:46.831295 <gsmtrx:INFO> Command 'CMD 0 SETPOWER 7' (ARFCN=0)
2021-08-28_10:16:46.831340 <gsmtrx:INFO> Response 'RSP SETPOWER 0 7' (ARFCN=0)
2021-08-28_10:16:46.831412 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433262929664: this:0x55e9810835c0 response RSP SETPOWER 0 7 to command CMD 0 SETPOWER 7
2021-08-28_10:16:52.831722 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433262929664: this:0x55e9810835c0 command CMD 0 SETPOWER 6
2021-08-28_10:16:52.832836 <gsmtrx:ALL> Handling command 'CMD 0 SETPOWER 6' arfcn=0 [0x7f8f300213f0]
[WARNING @ host/libraries/libbladeRF/src/board/bladerf1/bladerf1.c:1615] set_tx_gain: unable to achieve requested gain 350 (missed by 277)
2021-08-28_10:16:52.833426 <gsmtrx:INFO> Set Tx gain=0 power_scaling=0.501187 [0x7f8f300213f0]
2021-08-28_10:16:52.833449 <gsmtrx:INFO> Command 'CMD 0 SETPOWER 6' (ARFCN=0)
2021-08-28_10:16:52.833493 <gsmtrx:INFO> Response 'RSP SETPOWER 0 6' (ARFCN=0)
2021-08-28_10:16:52.833564 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433262929664: this:0x55e9810835c0 response RSP SETPOWER 0 6 to command CMD 0 SETPOWER 6
2021-08-28_10:16:58.833878 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433262929664: this:0x55e9810835c0 command CMD 0 SETPOWER 5
2021-08-28_10:16:58.834967 <gsmtrx:ALL> Handling command 'CMD 0 SETPOWER 5' arfcn=0 [0x7f8f300213f0]
[WARNING @ host/libraries/libbladeRF/src/board/bladerf1/bladerf1.c:1615] set_tx_gain: unable to achieve requested gain 360 (missed by 287)
2021-08-28_10:16:58.835596 <gsmtrx:INFO> Set Tx gain=0 power_scaling=0.562341 [0x7f8f300213f0]
2021-08-28_10:16:58.835619 <gsmtrx:INFO> Command 'CMD 0 SETPOWER 5' (ARFCN=0)
2021-08-28_10:16:58.835664 <gsmtrx:INFO> Response 'RSP SETPOWER 0 5' (ARFCN=0)
2021-08-28_10:16:58.835746 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433262929664: this:0x55e9810835c0 response RSP SETPOWER 0 5 to command CMD 0 SETPOWER 5
2021-08-28_10:17:04.836059 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433262929664: this:0x55e9810835c0 command CMD 0 SETPOWER 4
2021-08-28_10:17:04.837163 <gsmtrx:ALL> Handling command 'CMD 0 SETPOWER 4' arfcn=0 [0x7f8f300213f0]
[WARNING @ host/libraries/libbladeRF/src/board/bladerf1/bladerf1.c:1615] set_tx_gain: unable to achieve requested gain 370 (missed by 297)
2021-08-28_10:17:04.837745 <gsmtrx:INFO> Set Tx gain=0 power_scaling=0.630957 [0x7f8f300213f0]
2021-08-28_10:17:04.837773 <gsmtrx:INFO> Command 'CMD 0 SETPOWER 4' (ARFCN=0)
2021-08-28_10:17:04.837815 <gsmtrx:INFO> Response 'RSP SETPOWER 0 4' (ARFCN=0)
2021-08-28_10:17:04.837908 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433262929664: this:0x55e9810835c0 response RSP SETPOWER 0 4 to command CMD 0 SETPOWER 4
2021-08-28_10:17:10.838202 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433262929664: this:0x55e9810835c0 command CMD 0 SETPOWER 3
2021-08-28_10:17:10.839273 <gsmtrx:ALL> Handling command 'CMD 0 SETPOWER 3' arfcn=0 [0x7f8f300213f0]
[WARNING @ host/libraries/libbladeRF/src/board/bladerf1/bladerf1.c:1615] set_tx_gain: unable to achieve requested gain 380 (missed by 307)
2021-08-28_10:17:10.839928 <gsmtrx:INFO> Set Tx gain=0 power_scaling=0.707946 [0x7f8f300213f0]
2021-08-28_10:17:10.839945 <gsmtrx:INFO> Command 'CMD 0 SETPOWER 3' (ARFCN=0)
2021-08-28_10:17:10.839980 <gsmtrx:INFO> Response 'RSP SETPOWER 0 3' (ARFCN=0)
2021-08-28_10:17:10.840053 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433262929664: this:0x55e9810835c0 response RSP SETPOWER 0 3 to command CMD 0 SETPOWER 3
2021-08-28_10:17:16.840372 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433262929664: this:0x55e9810835c0 command CMD 0 SETPOWER 2
2021-08-28_10:17:16.841474 <gsmtrx:ALL> Handling command 'CMD 0 SETPOWER 2' arfcn=0 [0x7f8f300213f0]
[WARNING @ host/libraries/libbladeRF/src/board/bladerf1/bladerf1.c:1615] set_tx_gain: unable to achieve requested gain 390 (missed by 317)
2021-08-28_10:17:16.842144 <gsmtrx:INFO> Set Tx gain=0 power_scaling=0.794328 [0x7f8f300213f0]
2021-08-28_10:17:16.842193 <gsmtrx:INFO> Command 'CMD 0 SETPOWER 2' (ARFCN=0)
2021-08-28_10:17:16.842237 <gsmtrx:INFO> Response 'RSP SETPOWER 0 2' (ARFCN=0)
2021-08-28_10:17:16.842351 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433262929664: this:0x55e9810835c0 response RSP SETPOWER 0 2 to command CMD 0 SETPOWER 2
2021-08-28_10:17:22.842657 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433262929664: this:0x55e9810835c0 command CMD 0 SETPOWER 1
2021-08-28_10:17:22.843745 <gsmtrx:ALL> Handling command 'CMD 0 SETPOWER 1' arfcn=0 [0x7f8f300213f0]
[WARNING @ host/libraries/libbladeRF/src/board/bladerf1/bladerf1.c:1615] set_tx_gain: unable to achieve requested gain 400 (missed by 327)
2021-08-28_10:17:22.844346 <gsmtrx:INFO> Set Tx gain=0 power_scaling=0.891251 [0x7f8f300213f0]
2021-08-28_10:17:22.844371 <gsmtrx:INFO> Command 'CMD 0 SETPOWER 1' (ARFCN=0)
2021-08-28_10:17:22.844415 <gsmtrx:INFO> Response 'RSP SETPOWER 0 1' (ARFCN=0)
2021-08-28_10:17:22.844497 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433262929664: this:0x55e9810835c0 response RSP SETPOWER 0 1 to command CMD 0 SETPOWER 1
2021-08-28_10:17:27.960186 <gsmtrx:INFO> ARFCN[0]: Slot 0. Receiver clipping 1.77552 dB (FN=12825) count=1 [0x7f8f3001c110]
2021-08-28_10:17:27.960610 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433261221632: this:0x55e9810835c0 command CMD 0 NOHANDOVER 0
2021-08-28_10:17:27.961687 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f8f300213f0]
2021-08-28_10:17:27.961713 <gsmtrx:INFO> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0)
2021-08-28_10:17:27.961753 <gsmtrx:INFO> Response 'RSP NOHANDOVER 0 0' (ARFCN=0)
2021-08-28_10:17:27.961834 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433261221632: this:0x55e9810835c0 response RSP NOHANDOVER 0 0 to command CMD 0 NOHANDOVER 0
2021-08-28_10:17:27.961956 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433261221632: this:0x55e9810835c0 command CMD 0 NOHANDOVER 0
2021-08-28_10:17:27.963041 <gsmtrx:ALL> Handling command 'CMD 0 NOHANDOVER 0' arfcn=0 [0x7f8f300213f0]
2021-08-28_10:17:27.963061 <gsmtrx:INFO> Command 'CMD 0 NOHANDOVER 0' (ARFCN=0)
2021-08-28_10:17:27.963096 <gsmtrx:INFO> Response 'RSP NOHANDOVER 0 0' (ARFCN=0)
2021-08-28_10:17:27.963157 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433261221632: this:0x55e9810835c0 response RSP NOHANDOVER 0 0 to command CMD 0 NOHANDOVER 0
2021-08-28_10:17:28.270180 <ybts-signalling:INFO> Received [0x5645b7de5760]
-----
Primitive: PhysicalInfo
Info: 0
Connection: 1

<PhysicalInfo>TA=0 TE=3.000 UpRSSI=1 TxPwr=33 DnRSSIdBm=-111 time=1630135047.976</PhysicalInfo>
-----
2021-08-28_10:17:28.270341 <ybts-signalling:INFO> Received [0x5645b7de5760]
-----
Primitive: L3Message
Info: 0
Connection: 1

<MM>
  <SkipIndicator>0</SkipIndicator>
  <NSD>0</NSD>
  <Message type="LocationUpdatingRequest">
    <LocationUpdatingType>
      <FOR>false</FOR>
      <LUT>normal-location-updating</LUT>
    </LocationUpdatingType>
    <CKSN>0</CKSN>
    <LAI>
      <PLMNidentity>28603</PLMNidentity>
      <LAC>9e9a</LAC>
    </LAI>
    <MSClassmark1>
      <RFPowerCapability>class1</RFPowerCapability>
      <RevisionLevel>GSM-phase2</RevisionLevel>
      <Flags>ES-IND</Flags>
    </MSClassmark1>
    <MobileIdentity>
      <TMSI>aa086747</TMSI>
    </MobileIdentity>
  </Message>
</MM>
-----
2021-08-28_10:17:28.270402 <ybts-signalling:ALL> Added connection (0x7f8f2c004860,1) [0x5645b7de5760]
2021-08-28_10:17:28.270465 <ybts-mm:ALL> Handling LocationUpdatingRequest conn=1: ident=TMSI/aa086747 LAI=28603_9e9a [0x5645b7dd90f0]
2021-08-28_10:17:28.270479 <ybts-mm:ALL> Added empty UE (0x7f8f2c0049a0) [0x5645b7dd90f0]
2021-08-28_10:17:28.270494 <ybts-signalling:ALL> Connection 1 set UE (0x7f8f2c0049a0) TMSI= IMSI= [0x7f8f2c004860]
2021-08-28_10:17:28.270560 <ybts-signalling:INFO> Sending [0x5645b7de5760]
-----
Primitive: L3Message
Info: 0
Connection: 1

<MM>
  <Message type="IdentityRequest">
    <IdentityType>IMSI</IdentityType>
  </Message>
</MM>
-----
2021-08-28_10:17:28.504520 <ybts-signalling:INFO> Received [0x5645b7de5760]
-----
Primitive: PhysicalInfo
Info: 0
Connection: 1

<PhysicalInfo>TA=3 TE=0.000 UpRSSI=1 TxPwr=30 DnRSSIdBm=-111 time=1630135047.963</PhysicalInfo>
-----
2021-08-28_10:17:28.504609 <mbts:NOTE> proc 19170 Sgsn.cpp:1188:handleGprsSuspensionRequest: thread 140433282365184:   10:17:28.5,2989:Received GPRS suspension request for unknown TLLI=0xa6156a4f
2021-08-28_10:17:28.504641 <mbts:NOTE> proc 19170 DCCHDispatch.cpp:82:connDispatchRR: thread 140433282365184:  not handled GPRS suspension request on connection 1
2021-08-28_10:17:28.844849 <mbts:NOTE> proc 19170 TRXManager.cpp:127:sendCommandPacket: thread 140433262929664: this:0x55e9810835c0 command CMD 0 SETPOWER 0
2021-08-28_10:17:28.845937 <gsmtrx:ALL> Handling command 'CMD 0 SETPOWER 0' arfcn=0 [0x7f8f300213f0]
[WARNING @ host/libraries/libbladeRF/src/board/bladerf1/bladerf1.c:1615] set_tx_gain: unable to achieve requested gain 410 (missed by 337)
2021-08-28_10:17:28.846615 <gsmtrx:INFO> Set Tx gain=0 power_scaling=1 [0x7f8f300213f0]
2021-08-28_10:17:28.846636 <gsmtrx:INFO> Command 'CMD 0 SETPOWER 0' (ARFCN=0)
2021-08-28_10:17:28.846672 <gsmtrx:INFO> Response 'RSP SETPOWER 0 0' (ARFCN=0)
2021-08-28_10:17:28.846752 <mbts:NOTE> proc 19170 TRXManager.cpp:153:sendCommandPacket: thread 140433262929664: this:0x55e9810835c0 response RSP SETPOWER 0 0 to command CMD 0 SETPOWER 0
2021-08-28_10:17:28.975349 <ybts-signalling:INFO> Received [0x5645b7de5760]
-----
Primitive: PhysicalInfo
Info: 0
Connection: 1

<PhysicalInfo>TA=3 TE=0.000 UpRSSI=1 TxPwr=30 DnRSSIdBm=-111 time=1630135047.963</PhysicalInfo>
-----
2021-08-28_10:17:28.975475 <ybts-signalling:INFO> Received [0x5645b7de5760]
-----
Primitive: L3Message
Info: 0
Connection: 1

<MM>
  <SkipIndicator>0</SkipIndicator>
  <NSD>1</NSD>
  <Message type="IdentityResponse">
    <MobileIdentity>
      <IMSI>286038110336712</IMSI>
    </MobileIdentity>
  </Message>
</MM>
-----
2021-08-28_10:17:28.975522 <ybts-mm:ALL> UE (0x7f8f2c0049a0) IMSI set to 286038110336712 on conn=1 [0x5645b7dd90f0]
2021-08-28_10:17:28.975564 <ybts-signalling:INFO> Sending [0x5645b7de5760]
-----
Primitive: L3Message
Info: 0
Connection: 1

<MM>
  <Message type="IdentityRequest">
    <IdentityType>IMEI</IdentityType>
  </Message>
</MM>
-----
2021-08-28_10:17:29.682454 <ybts-signalling:INFO> Received [0x5645b7de5760]
-----
Primitive: PhysicalInfo
Info: 0
Connection: 1

<PhysicalInfo>TA=3 TE=0.000 UpRSSI=-11 TxPwr=14 DnRSSIdBm=-78 time=1630135048.847</PhysicalInfo>
-----
2021-08-28_10:17:29.682549 <ybts-signalling:INFO> Received [0x5645b7de5760]
-----
Primitive: L3Message
Info: 0
Connection: 1

<MM>
  <SkipIndicator>0</SkipIndicator>
  <NSD>0</NSD>
  <Message type="IdentityResponse">
    <MobileIdentity>
      <IMEI>123456789101112</IMEI>
    </MobileIdentity>
  </Message>
</MM>
-----
2021-08-28_10:17:29.682722 <ybts:ALL> Started location updating thread for (0x7f8f2c0049a0) TMSI= IMSI=286038110336712 [0x7f8f2c002f60]
2021-08-28_10:17:29.683085 <nipc:INFO> Got user.register for imsi='286038110336712', tmsi='007b0002'
2021-08-28_10:17:29.684322 <nipc:INFO> Registered imsi 286038110336712 with number 900336712
2021-08-28_10:17:29.684372 <ybts:ALL> Location updating thread for (0x7f8f2c0049a0) TMSI= IMSI=286038110336712 terminated [0x7f8f2c002f60]
2021-08-28_10:17:29.684392 <ybts-mm:ALL> UE (0x7f8f2c0049a0) registered TMSI '' -> '007b0002', IMSI '286038110336712' -> '286038110336712' conn=1 [0x5645b7dd90f0]
2021-08-28_10:17:29.684406 <ybts-mm:ALL> UE (0x7f8f2c0049a0) TMSI=007b0002 IMSI=286038110336712 register succeeded [0x5645b7dd90f0]
2021-08-28_10:17:29.684499 <ybts-signalling:INFO> Sending [0x5645b7de5760]
-----
Primitive: L3Message
Info: 0
Connection: 1

<MM>
  <Message type="LocationUpdatingAccept">
    <LAI>
      <PLMNidentity>28607</PLMNidentity>
      <LAC>03e8</LAC>
    </LAI>
    <MobileIdentity>
      <TMSI>007b0002</TMSI>
    </MobileIdentity>
  </Message>
</MM>
-----
2021-08-28_10:17:30.387701 <ybts-signalling:INFO> Received [0x5645b7de5760]
-----
Primitive: PhysicalInfo
Info: 0
Connection: 1

<PhysicalInfo>TA=3 TE=0.000 UpRSSI=-16 TxPwr=6 DnRSSIdBm=-79 time=1630135049.848</PhysicalInfo>
-----
2021-08-28_10:17:30.387834 <ybts-signalling:INFO> Received [0x5645b7de5760]
-----
Primitive: L3Message
Info: 0
Connection: 1

<MM>
  <SkipIndicator>0</SkipIndicator>
  <NSD>1</NSD>
  <Message type="TMSIReallocationComplete"/>
</MM>
-----
2021-08-28_10:17:30.387877 <ybts-signalling:ALL> Releasing connection (0x7f8f2c004860,1) [0x5645b7de5760]
2021-08-28_10:17:30.387896 <ybts-signalling:INFO> Sending [0x5645b7de5760]
-----
Primitive: ConnRelease
Info: 0
Connection: 1
-----
2021-08-28_10:17:31.587940 <gsmtrx:INFO> ARFCN[0]: Slot 0. Receiver clipping 1.66502 dB (FN=13611) count=22 [0x7f8f3001c110]
2021-08-28_10:17:31.848398 <gsmtrx:INFO> ARFCN[0]: Slot 2. Receiver clipping 1.23977 dB (FN=13667) count=1 [0x7f8f3001c110]
2021-08-28_10:17:31.965776 <ybts-signalling:INFO> Received [0x5645b7de5760]
-----
Primitive: GprsAttachReq
Info: 0
Connection: 8193

<GprsAttach>
  <tlli>a6156a4f</tlli>
  <ptmsi>e6156a4f</ptmsi>
  <authenticated>false</authenticated>
  <pdps>0000</pdps>
</GprsAttach>
-----
2021-08-28_10:17:31.965830 <ybts-signalling:ALL> Added GPRS connection (0x7f8f2c0017b0,8193) [0x5645b7de5760]
2021-08-28_10:17:31.965898 <ybts/ps/1:ALL> Created ConnID=8193 [0x7f8f2c0054b0]
2021-08-28_10:17:31.966605 <INFO> Could not route gprs to 'sgsn' in context 'default', wasted 200 usec
2021-08-28_10:17:31.966679 <ybts/ps/1:MILD> Call rejected error='noroute' reason='No route to call target' [0x7f8f2c0054b0]
2021-08-28_10:17:31.966703 <ybts/ps/1:INFO> Connection continuing locally in mbts
2021-08-28_10:17:31.966723 <ybts-signalling:INFO> Sending [0x5645b7de5760]
-----
Primitive: GprsAttachLBO
Info: 0
Connection: 8193
-----
2021-08-28_10:17:31.966798 <ybts/ps/1:ALL> Destroyed ConnID=8193 [0x7f8f2c0054b0]
2021-08-28_10:17:35.401030 <gsmtrx:INFO> ARFCN[0]: Slot 0. Receiver clipping 2.02712 dB (FN=14437) count=1 [0x7f8f3001c110]
2021-08-28_10:17:37.047908 <gsmtrx:INFO> ARFCN[0]: Slot 0. Receiver clipping 0.752546 dB (FN=14794) count=1 [0x7f8f3001c110]
2021-08-28_10:17:37.505652 <gsmtrx:INFO> ARFCN[0]: Slot 2. Receiver clipping 1.37562 dB (FN=14893) count=4 [0x7f8f3001c110]
2021-08-28_10:17:54.184294 <gsmtrx:INFO> ARFCN[0]: Slot 0. Receiver clipping 2.54916 dB (FN=18507) count=1 [0x7f8f3001c110]
2021-08-28_10:18:01.000265 <ybts-signalling:ALL> Removing released connection 1 [0x5645b7de5760]
2021-08-28_10:18:01.000301 <ybts-mm:ALL> Removed UE (0x7f8f2c0049a0): destroyed [0x5645b7dd90f0]
2021-08-28_10:18:01.000315 <ybts:ALL> UE destroyed [0x7f8f2c0049a0]
2021-08-28_10:18:08.242672 <gsmtrx:INFO> ARFCN[0]: Slot 0. Receiver clipping 1.97137 dB (FN=21553) count=1 [0x7f8f3001c110]
2021-08-28_10:18:36.411710 <gsmtrx:INFO> ARFCN[0]: Slot 0. Receiver clipping 1.57753 dB (FN=27656) count=1 [0x7f8f3001c110]
2021-08-28_10:18:51.965002 <gsmtrx:INFO> ARFCN[0]: Slot 0. Receiver clipping 1.90446 dB (FN=31026) count=1 [0x7f8f3001c110]
2021-08-28_10:18:56.607395 <gsmtrx:INFO> ARFCN[0]: Slot 0. Receiver clipping 1.3021 dB (FN=32032) count=1 [0x7f8f3001c110]
2021-08-28_10:19:10.684454 <gsmtrx:INFO> ARFCN[0]: Slot 0. Receiver clipping 1.23179 dB (FN=35082) count=1 [0x7f8f3001c110]
2021-08-28_10:19:15.028469 <gsmtrx:INFO> ARFCN[0]: Slot 0. Receiver clipping 0.99945 dB (FN=36023) count=1 [0x7f8f3001c110]
2021-08-28_10:21:21.437401 <bladerf/1:NOTE> Read timestamp in the future by 117 at 634123750 [0x7f8f300011d0]
2021-08-28_10:21:21.437779 <bladerf/1:FAIL> Read timestamp in the past by 388 at 634125117 [0x7f8f300011d0]
2021-08-28_10:21:21.438188 <bladerf/1:NOTE> Read timestamp in the future by 474 at 634125117 [0x7f8f300011d0]
2021-08-28_10:21:21.438606 <bladerf/1:FAIL> Read timestamp in the past by 201 at 634126724 [0x7f8f300011d0]
2021-08-28_10:21:21.439027 <bladerf/1:NOTE> Read timestamp in the future by 714 at 634126724 [0x7f8f300011d0]
2021-08-28_10:21:21.439422 <bladerf/1:NOTE> Read timestamp in the future by 68 at 634128214 [0x7f8f300011d0]
2021-08-28_10:21:21.439833 <bladerf/1:NOTE> Read timestamp in the future by 361 at 634128818 [0x7f8f300011d0]
2021-08-28_10:21:21.440231 <bladerf/1:FAIL> Read timestamp in the past by 302 at 634130361 [0x7f8f300011d0]
2021-08-28_10:21:21.440634 <bladerf/1:NOTE> Read timestamp in the future by 525 at 634130361 [0x7f8f300011d0]
2021-08-28_10:21:21.440873 <bladerf/1:FAIL> Read timestamp in the past by 380 at 634131775 [0x7f8f300011d0]
2021-08-28_10:21:21.441290 <bladerf/1:NOTE> Read timestamp in the future by 570 at 634131775 [0x7f8f300011d0]
2021-08-28_10:21:21.441518 <bladerf/1:FAIL> Read timestamp in the past by 233 at 634133070 [0x7f8f300011d0]
2021-08-28_10:21:21.441922 <bladerf/1:NOTE> Read timestamp in the future by 646 at 634133070 [0x7f8f300011d0]
2021-08-28_10:21:21.442148 <bladerf/1:FAIL> Read timestamp in the past by 240 at 634134396 [0x7f8f300011d0]
2021-08-28_10:21:21.442532 <bladerf/1:NOTE> Read timestamp in the future by 639 at 634134396 [0x7f8f300011d0]
2021-08-28_10:21:21.442752 <bladerf/1:FAIL> Read timestamp in the past by 147 at 634135639 [0x7f8f300011d0]
2021-08-28_10:21:21.443156 <bladerf/1:NOTE> Read timestamp in the future by 750 at 634135639 [0x7f8f300011d0]
2021-08-28_10:21:21.443382 <bladerf/1:FAIL> Read timestamp in the past by 136 at 634137000 [0x7f8f300011d0]
2021-08-28_10:21:21.443766 <bladerf/1:NOTE> Read timestamp in the future by 708 at 634137000 [0x7f8f300011d0]
2021-08-28_10:21:21.444014 <bladerf/1:NOTE> Read timestamp in the future by 9 at 634138208 [0x7f8f300011d0]
2021-08-28_10:21:21.444480 <bladerf/1:NOTE> Read timestamp in the future by 390 at 634138759 [0x7f8f300011d0]
2021-08-28_10:21:21.444930 <bladerf/1:FAIL> Read timestamp in the past by 203 at 634140390 [0x7f8f300011d0]
2021-08-28_10:21:21.445367 <bladerf/1:NOTE> Read timestamp in the future by 746 at 634140390 [0x7f8f300011d0]
2021-08-28_10:21:21.445596 <bladerf/1:FAIL> Read timestamp in the past by 350 at 634141996 [0x7f8f300011d0]
2021-08-28_10:21:21.446032 <bladerf/1:NOTE> Read timestamp in the future by 582 at 634141996 [0x7f8f300011d0]
2021-08-28_10:21:21.446264 <bladerf/1:NOTE> Read timestamp in the future by 41 at 634143082 [0x7f8f300011d0]
2021-08-28_10:21:21.446675 <bladerf/1:NOTE> Read timestamp in the future by 211 at 634143791 [0x7f8f300011d0]
2021-08-28_10:21:21.447085 <bladerf/1:FAIL> Read timestamp in the past by 312 at 634145211 [0x7f8f300011d0]
2021-08-28_10:21:21.447494 <bladerf/1:NOTE> Read timestamp in the future by 550 at 634145211 [0x7f8f300011d0]
2021-08-28_10:21:21.447706 <bladerf/1:FAIL> Read timestamp in the past by 547 at 634146800 [0x7f8f300011d0]
2021-08-28_10:21:21.448093 <bladerf/1:NOTE> Read timestamp in the future by 298 at 634146800 [0x7f8f300011d0]
2021-08-28_10:21:21.448305 <bladerf/1:FAIL> Read timestamp in the past by 243 at 634147798 [0x7f8f300011d0]
2021-08-28_10:21:21.448673 <bladerf/1:NOTE> Read timestamp in the future by 566 at 634147798 [0x7f8f300011d0]
2021-08-28_10:21:21.448881 <bladerf/1:FAIL> Read timestamp in the past by 513 at 634149316 [0x7f8f300011d0]
2021-08-28_10:21:21.449269 <bladerf/1:NOTE> Read timestamp in the future by 332 at 634149316 [0x7f8f300011d0]
2021-08-28_10:21:21.449485 <bladerf/1:FAIL> Read timestamp in the past by 227 at 634150332 [0x7f8f300011d0]
2021-08-28_10:21:21.449858 <bladerf/1:NOTE> Read timestamp in the future by 582 at 634150332 [0x7f8f300011d0]
2021-08-28_10:21:21.450100 <bladerf/1:FAIL> Read timestamp in the past by 461 at 634151832 [0x7f8f300011d0]
2021-08-28_10:21:21.450490 <bladerf/1:NOTE> Read timestamp in the future by 436 at 634151832 [0x7f8f300011d0]
2021-08-28_10:21:21.450720 <bladerf/1:FAIL> Read timestamp in the past by 194 at 634152936 [0x7f8f300011d0]
2021-08-28_10:21:21.451143 <bladerf/1:NOTE> Read timestamp in the future by 738 at 634152936 [0x7f8f300011d0]
2021-08-28_10:21:21.451373 <bladerf/1:FAIL> Read timestamp in the past by 304 at 634154488 [0x7f8f300011d0]
2021-08-28_10:21:21.451384 <gsmtrx:WARN> Too many read errors, last: 0x40 TooEarly [0x7f8f300213f0]
2021-08-28_10:21:21.451422 <gsmtrx:ALL> Sending 'EXITING code=64 operation=radio-read' on clock interface [0x7f8f300213f0]
2021-08-28_10:21:21.451481 <gsmtrx:ALL> Radio device read thread (0x7f8f2806c900) terminated [0x7f8f300213f0]
2021-08-28_10:21:21.451601 <gsmtrx:ALL> Radio device send thread (0x7f8f3002bf70) terminated [0x7f8f300213f0]
2021-08-28_10:21:21.451689 <mbts:NOTE> proc 19170 TRXManager.cpp:296:clockHandler: thread 140433301006080: TRX clock 'EXITING' indication
2021-08-28_10:21:21.451767 <ybts-signalling:INFO> Received [0x5645b7de5760]
-----
Primitive: Stop
Info: 3

<Stop>
  <code>64</code>
  <operation>radio-read</operation>
</Stop>
-----
2021-08-28_10:21:21.451829 <ybts-signalling:NOTE> Peer stop notification 'RadioExiting' code=0x40 (TooEarly) operation=radio-read [0x5645b7de5760]
2021-08-28_10:21:21.451846 <ybts:ALL> Restart scheduled in 1ms [0x7f8f57e66b00]
2021-08-28_10:21:21.451857 <ybts:ALL> Scheduled stop in 0ms
2021-08-28_10:21:21.451867 <ybts-signalling:ALL> Worker thread terminated [0x5645b7de5760]
2021-08-28_10:21:21.452675 <snmpagent:INFO> Dropping message from 127.0.0.1 with wrong community ''
2021-08-28_10:21:21.452720 <snmpagent:ALL> Error processing message [0x7f8f2c001ba0]
2021-08-28_10:21:21.452765 <gsmtrx:ALL> ARFCN[0]: Data socket read thread (0x7f8f2806d1f0) terminated [0x7f8f3001c110]
2021-08-28_10:21:21.453942 <gsmtrx:ALL> ARFCN[2]: Radio input process thread (0x7f8f2806d8e0) terminated [0x7f8f3000f7e0]
2021-08-28_10:21:21.454672 <gsmtrx:ALL> ARFCN[2]: Data socket read thread (0x7f8f2806db30) terminated [0x7f8f3000f7e0]
2021-08-28_10:21:21.454701 <gsmtrx:ALL> ARFCN[3]: Data socket read thread (0x7f8f2806dfd0) terminated [0x7f8f30026500]
2021-08-28_10:21:21.454802 <gsmtrx:ALL> ARFCN[1]: Data socket read thread (0x7f8f2806d690) terminated [0x7f8f3000c2b0]
2021-08-28_10:21:21.455002 <gsmtrx:ALL> ARFCN[0]: Radio input process thread (0x7f8f3002bdb0) terminated [0x7f8f3001c110]
2021-08-28_10:21:21.455770 <gsmtrx:ALL> ARFCN[3]: Radio input process thread (0x7f8f2806dd80) terminated [0x7f8f30026500]
2021-08-28_10:21:21.455814 <gsmtrx:ALL> ARFCN[1]: Radio input process thread (0x7f8f2806d440) terminated [0x7f8f3000c2b0]
2021-08-28_10:21:21.456251 <gsmtrx:ALL> Radio read process thread (0x7f8f3002c100) terminated [0x7f8f300213f0]
2021-08-28_10:21:22.000377 <ybts:ALL> 'shutdown' command result:
-----
exiting...
-----
2021-08-28_10:21:22.000409 <ybts:NOTE> Stopping ...
2021-08-28_10:21:22.000440 <mbts:NOTE> proc 19170 OpenBTS.cpp:490:main: thread 140433301886784: MBTS exiting
MBTS exiting
2021-08-28_10:21:22.000469 <ybts-signalling:INFO> Stopped [0x5645b7de5760]
2021-08-28_10:21:22.001752 <ybts-media:ALL> Worker thread terminated [0x5645b7de5480]
2021-08-28_10:21:22.005571 <ybts-media:INFO> Stopped [0x5645b7de5480]
2021-08-28_10:21:22.005594 <ybts:NOTE> Peer pid 19170 has not exited - we'll kill it
2021-08-28_10:21:22.107261 <ybts:INFO> Peer pid 19170 terminated
2021-08-28_10:21:22.107305 <ybts-command:INFO> Stopped [0x5645b7de5320]
2021-08-28_10:21:22.109167 <transceiver:ALL> Worker thread terminated [0x5645b7de4e80]
2021-08-28_10:21:22.112419 <transceiver:INFO> Stopped [0x5645b7de4e80]
2021-08-28_10:21:22.116979 <mbts:ALL> Worker thread terminated [0x5645b7de50c0]
2021-08-28_10:21:22.117506 <mbts:INFO> Stopped [0x5645b7de50c0]
2021-08-28_10:21:22.117519 <ybts:NOTE> YBTSDriver State changed RadioUp -> Idle
2021-08-28_10:21:22.117565 <gsmtrx:INFO> Module state changed Running -> Waiting
2021-08-28_10:21:22.117574 <gsmtrx:NOTE> Stopping transceiver: In error
2021-08-28_10:21:22.117583 <gsmtrx:ALL> Stopping [0x7f8f300213f0]
2021-08-28_10:21:22.117592 <gsmtrx:ALL> Sending 'EXITING' on clock interface [0x7f8f300213f0]
2021-08-28_10:21:22.117651 <gsmtrx:NOTE> Transceiver State changed PowerOn -> Idle [0x7f8f300213f0]
Transceiver(gsmtrx) status: [0x7f8f300213f0]
-----
RadioClock:	507323 63415/3
LastSyncUpper:	507296 63412/0
TxTime:		507343 63417/7
TxBursts:	507327
RxBursts:	507312
ARFCN[0]
  UplinkLastOutTime:	507312 63414/0
  DownlinkLastInTime:	507392 63424/0
  RxBursts:		507213
  RxDroppedBursts:	477910 LowSNR=477908 LowPeakMin=2
  DownlinkInBursts:	47351 (13091/876/14636/15416/836/832/832/832)
  TxMissSyncOnSend:	4
  TxExpiredOnSend:	1
  TxExpiredOnRecv:	0
  TxFutureOnRecv:	0
  TxDupOnRecv:		0
-----
2021-08-28_10:21:22.117794 <gsmtrx:NOTE> ARFCN[0]: Radio power off. RX: bursts=507213 passed=29303 (5.78%) dropped=477910 (94.22%) (LowSNR=477908 LowPeakMin=2) ellapsed=293sec avg=1731.10 bursts/sec [0x7f8f3001c110]
2021-08-28_10:21:22.118146 <bladerf/1:ALL> Destroying device=((nil)) [0x7f8f300011d0]
2021-08-28_10:21:22.118161 <bladerf/1:ALL> Interface destroyed [0x7f8f300011d0]
2021-08-28_10:21:23.000251 <ybts:NOTE> YBTSDriver State changed Idle -> Starting restart counter 1/10
2021-08-28_10:21:23.000387 <transceiver:ALL> Started worker thread [0x5645b7de4e80]
2021-08-28_10:21:23.000400 <transceiver:INFO> Started [0x5645b7de4e80]
2021-08-28_10:21:23.000448 <mbts:ALL> Started worker thread [0x5645b7de50c0]
2021-08-28_10:21:23.000458 <mbts:INFO> Started [0x5645b7de50c0]
2021-08-28_10:21:23.000483 <ybts-command:INFO> Started [0x5645b7de5320]
2021-08-28_10:21:23.000544 <ybts-signalling:ALL> Started worker thread [0x5645b7de5760]
2021-08-28_10:21:23.000557 <ybts-signalling:INFO> Started [0x5645b7de5760]
2021-08-28_10:21:23.000608 <ybts-media:ALL> Started worker thread [0x5645b7de5480]
2021-08-28_10:21:23.000618 <ybts-media:INFO> Started [0x5645b7de5480]
2021-08-28_10:21:23.000628 <ybts:ALL> Starting peer '/usr/local/lib/yate/server/bts/mbts' '(null)'
2021-08-28_10:21:23.003454 <ybts:INFO> Started peer pid=19303
2021-08-28_10:21:23.003528 <ybts:NOTE> YBTSDriver State changed Starting -> WaitHandshake
2021-08-28_10:21:23.003551 <ybts:ALL> Restart scheduled in 120000ms [0x7f8f57e66b00]
MBTS connected to YBTS
Yate-BTS MBTS Component
Copyright 2008, 2009, 2010 Free Software Foundation, Inc.
Copyright 2010 Kestrel Signal Processing, Inc.
Copyright 2011, 2012, 2013 Range Networks, Inc.
Copyright 2013, 2014 Null Team Impex SRL
Copyright 2014 Legba, Inc.
"OpenBTS" is a registered trademark of Range Networks, Inc.

Contributors:
  SC Null Team Impex SRL:
    Paul Chitescu
  Legba, Inc.
    David Burgess
  Range Networks, Inc.:
    David Burgess, Harvind Samra, Donald Kirker, Doug Brown,
    Pat Thompson, Kurtis Heimerl
  Kestrel Signal Processing, Inc.:
    David Burgess, Harvind Samra, Raffi Sevlian, Roshan Baliga
  GNU Radio:
    Johnathan Corgan
  Others:
    Anne Kwong, Jacob Appelbaum, Joshua Lackey, Alon Levy
    Alexander Chemeris, Alberto Escudero-Pascual
Incorporated L/GPL libraries and components:
  libusb, LGPL 2.1, various copyright holders, www.libusb.org
Incorporated BSD/MIT-style libraries and components:
  A5/1 Pedagogical Implementation, Simplified BSD License,
    Copyright 1998-1999 Marc Briceno, Ian Goldberg, and David Wagner
Incorporated public domain libraries and components:
  sqlite3, released to public domain 15 Sept 2001, www.sqlite.org


This program comes with ABSOLUTELY NO WARRANTY.

Use of this software may be subject to other legal restrictions,
including patent licensing and radio spectrum licensing.
All users of this software are expected to comply with applicable
regulations and laws.  See the LEGAL file in the source code for
more information.

Release 6.1.1 formal build date Aug 22 2021 rev
2021-08-28_10:21:23.015835 <mbts:NOTE> proc 19303 GSMConfig.cpp:80:regenerateBeacon: thread 139711937922880: regenerating system information messages, changemark 1
Starting MBTS...
2021-08-28_10:21:23.016052 <mbts:NOTE> proc 19303 TRXManager.cpp:127:sendCommandPacket: thread 139711937922880: this:0x55da9ba0b5c0 command CMD RESET 1
2021-08-28_10:21:23.016144 <ybts-signalling:INFO> Received [0x5645b7de5760]
-----
Primitive: Handshake
Info: 0
-----
2021-08-28_10:21:23.016166 <ybts:NOTE> YBTSDriver State changed WaitHandshake -> Running
2021-08-28_10:21:23.016186 <ybts-signalling:INFO> Sending [0x5645b7de5760]
-----
Primitive: Handshake
Info: 0
-----
2021-08-28_10:21:23.017157 >>> RSP RESET ANSWER
2021-08-28_10:21:23.017224   >>> BrfModule::createIface()
2021-08-28_10:21:23.017255     <bladerf/2:ALL> Interface created [0x7f8f30014370]
2021-08-28_10:21:23.017267   <<< BrfModule::createIface()
2021-08-28_10:21:23.017345   <gsmtrx:ALL> Adjusting parameter arfcns='1' -> 4 [0x7f8f300213f0]
2021-08-28_10:21:23.017708   <gsmtrx:ALL> Initialized [0x7f8f300213f0]
-----
ARFCNs=4
oversampling=8
-----
Segmentation fault