2
« on: April 04, 2015, 09:00:48 AM »
Hello,
I need helps to resolve my setup issue: unable to perform any of the test described in the NIB using one successfully registered phone (via regex: .*).
The config folder and the log are in the attachment.
OS: Ubuntu 14.04, 64bit, 3.13.0-46-generic #79-Ubuntu SMP Tue Mar 10 20:06:50 UTC 2015 x86_64 x86_64 x86_64 GNU/Linux
gcc version 4.9.2 (Ubuntu 4.9.2-0ubuntu1~14.04)
yate-5.4.2-1.tar.gz
yate-bts-4.0.2-1.tar.gz
======================================================================================================
bladeRF> info
Serial #: 13d7b32e83118203e3e6db0b04de23bf
VCTCXO DAC calibration: 0x91b1
FPGA size: 115 KLE
FPGA loaded: yes
USB bus: 4
USB address: 2
USB speed: SuperSpeed
Backend: libusb
Instance: 0
bladeRF> version
bladeRF-cli version: 1.1.2-git-37c5b38
libbladeRF version: 1.2.1-git-37c5b38
Firmware version: 1.8.0
FPGA version: 0.1.2
bladeRF>
=============================================================================================================
NIB setting as per the WIKI:
javascript.conf
; routing: string: Name of the file holding the routing instructions
; Example: routing=route.js
routing=welcome.js
ybts.conf
...
mode=nib
config folder: usr-local-etc-yate.tar.gz
yate log: yate.gz
log:
-----
Primitive: Handshake
Info: 0
-----
20150404143546.100401 <javascript:INFO> Parsed 'nib' script: /usr/local/share/yate/scripts/nib.js
20150404143546.102621 <nib:INFO> Finished reading saved registered subscribers. Found 1 registered_subscribers.
20150404143546.102855 <nib:INFO> Checked that only NIB is enabled.
20150404143546.102889 <ALL> Rescanning handler list for 'engine.start' [0x7fff13710de0] at priority 100
Yate engine is initialized and starting up on...
20150404143546.102970 <INFO> Creating first message dispatching thread
20150404143546.892332 <cpuload:NOTE> Updating CPU core number from 1 to 4
20150404143546.945799 <mbts:MILD> TRXManager.cpp:283:sendCommandPacket: TRX link timeout on attempt 1
20150404143547.946257 <mbts:MILD> TRXManager.cpp:283:sendCommandPacket: TRX link timeout on attempt 2
20150404143548.947236 <mbts:MILD> TRXManager.cpp:283:sendCommandPacket: TRX link timeout on attempt 3
20150404143549.948508 <mbts:MILD> TRXManager.cpp:283:sendCommandPacket: TRX link timeout on attempt 4
20150404143550.949599 <mbts:MILD> TRXManager.cpp:283:sendCommandPacket: TRX link timeout on attempt 5
20150404143550.949645 <mbts:NOTE> TRXManager.cpp:293:sendCommandPacket: lost control link to transceiver
ALERT 140437058942848 10:35:50.9 TRXManager.cpp:432:powerOff: POWEROFF failed with status -1
20150404143550.949723 <mbts:WARN> TRXManager.cpp:432:powerOff: POWEROFF failed with status -1
20150404143551.011847 <mbts:NOTE> OpenBTS.cpp:145:startTransceiver: starting transceiver ./transceiver-bladerf w/ 1 ARFCNs and Args:
20150404143551.165150 <transceiver:NOTE> bladeRFDevice.cpp:106:open: Opened bladeRF serial=13d7b32e83118203e3e6db0b04de23bf firmware version 1.8.0 (1.8.0)
20150404143551.657441 <transceiver:NOTE> Transceiver.cpp:68:Transceiver: running 1 ARFCNs with oversampling 4
Starting transceiver
20150404143555.975268 <transceiver:NOTE> bladeRFDevice.cpp:239:start: starting bladeRF in super speed mode...
20150404143555.977841 <transceiver:NOTE> bladeRFDevice.cpp:482:readSamples: RX Timestamp adjusted by 1 to 2
20150404143555.984484 <mbts:NOTE> GSMConfig.cpp:532:createCombinationI: Configuring combination I on C0T1
20150404143555.984898 <mbts:NOTE> GSMConfig.cpp:532:createCombinationI: Configuring combination I on C0T2
20150404143555.985388 <mbts:NOTE> GSMConfig.cpp:532:createCombinationI: Configuring combination I on C0T3
20150404143555.985754 <mbts:NOTE> GSMConfig.cpp:532:createCombinationI: Configuring combination I on C0T4
20150404143555.986482 <mbts:NOTE> GSMConfig.cpp:532:createCombinationI: Configuring combination I on C0T5
20150404143555.987108 <mbts:NOTE> GSMConfig.cpp:532:createCombinationI: Configuring combination I on C0T6
20150404143555.987557 <mbts:NOTE> GSMConfig.cpp:532:createCombinationI: Configuring combination I on C0T7
RTNETLINK answers: File exists
20150404143556.724381 <transceiver:NOTE> Transceiver.cpp:274:pushRadioVector: dumping STALE burst in TRX->USRP interface cn=0 at 2:1779366
MBTS ready
20150404143556.744533 <ybts-signalling:INFO> Received [0x1b75500]
-----
Primitive: RadioReady
Info: 0
-----
20150404143556.744566 <ybts:NOTE> State changed Running -> RadioUp
20150404143628.743658 <ybts-signalling:INFO> Received [0x1b75500]
-----
<PhysicalInfo>TA=0 TE=0.426 UpRSSI=-11 TxPwr=33 DnRSSIdBm=-111 time=1428158188.505</PhysicalInfo>
-----
20150404143628.743916 <ybts-signalling:INFO> Received [0x1b75500]
-----
Primitive: L3Message
Info: 0
Connection: 1
<MM>
<SkipIndicator>0</SkipIndicator>
<NSD>0</NSD>
<Message type="CMServiceRequest">
<CMServiceType>MO-call-establishment-or-PM-connection-establishment</CMServiceType>
<CipheringKeySequenceNumber>no-key/reserved</CipheringKeySequenceNumber>
<MobileStationClassmark2>
<RFPowerCapability>class4</RFPowerCapability>
<RevisionLevel>GSM-phase2</RevisionLevel>
<SSScreeningIndicator>ellipsis-notation-and-phase2-error-handling</SSScreeningIndicator>
<Flags>ES-IND,E-GSM-and-R-GSM-support,MT-sms-point-to-point-capability,pseudo-sync-capability,A5/3-support,CMSP-support,LCS-VA-support,CM3-support</Flags>
</MobileStationClassmark2>
<MobileIdentity>
<TMSI>007b0001</TMSI>
</MobileIdentity>
</Message>
</MM>
-----
20150404143628.743949 <ybts-signalling:ALL> Added connection (0x7fd948001ae0,1) [0x1b75500]
20150404143628.743990 <ybts-mm:ALL> Handling CMServiceRequest conn=1: ident=TMSI/007b0001 type=MO-call-establishment-or-PM-connection-establishment [0x1b758e0]
20150404143628.744009 <ybts-mm:ALL> Added UE (0x7fd948002120) TMSI=007b0001 IMSI= [0x1b758e0]
20150404143628.744021 <ybts-signalling:ALL> Connection 1 set UE (0x7fd948002120) TMSI=007b0001 IMSI= [0x7fd948001ae0]
20150404143628.744135 <ybts-signalling:INFO> Sending [0x1b75500]
-----
Primitive: L3Message
Info: 0
Connection: 1
<MM>
<Message type="CMServiceAccept"/>
</MM>
Primitive: L3Message
Info: 0
Connection: 1
<CC>
<TID TIFlag="false">0</TID>
<NSD>1</NSD>
<Message type="Setup">
<BearerCapability1>
<ITC>speech</ITC>
<TransferMode>circuit-mode</TransferMode>
<CodingStandard>GSM</CodingStandard>
<RadioChannelRequirement>FR-and-HR-speech-version1-supported/FR-speech-preferred</RadioChannelRequirement>
<SpeechVersions>GSM-FR-speech-version3,GSM-FR-speech-version2,GSM-FR-speech-version1,GSM-HR-speech-version3,GSM-HR-speech-version1</SpeechVersions>
</BearerCapability1>
<CalledPartyBCDNumber nature="unknown" plan="isdn">32843</CalledPartyBCDNumber>
<CCCapabilities>
<Flags>DTMF</Flags>
<MaxSupportedBearers>0</MaxSupportedBearers>
<MaxSpeechBearers>0</MaxSpeechBearers>
</CCCapabilities>
</Message>
</CC>
-----
20150404143629.084794 <ybts/1:CALL> Incoming address=TMSI007b0001 conn=1 [0x7fd948002a40]
20150404143629.084837 <ybts-signalling:INFO> Sending [0x1b75500]
-----
Primitive: AllocMedia
Info: 0
Connection: 1
-----
20150404143629.084864 <ybts/1:INFO> Call 'i0' changed state Null -> CallProceeding [0x7fd948002a40]
20150404143629.084942 <ybts-signalling:INFO> Sending [0x1b75500]
-----
Primitive: L3Message
Info: 0
Connection: 1
<CC>
<TID TIFlag="true">0</TID>
<Message type="CallProceeding"/>
</CC>
-----
20150404143629.084971 <ybts/1:INFO> Added call 'i0' [0x7fd948002a40]
20150404143629.085008 <ybts-signalling:INFO> Sending [0x1b75500]
-----
Primitive: StartMedia
Info: 1
Connection: 1
-----
20150404143629.085035 <ybts-signalling:ALL> Connection 1 waiting for traffic channel allocation mode=1 ... [0x1b75500]
20150404143701.558527 <mbts:NOTE> DCCHDispatch.cpp:127:connDispatchLoop: error reading on connection 1
20150404143701.558615 <ybts-signalling:INFO> Received [0x1b75500]
-----
Primitive: ConnLost
Info: 0
Connection: 1
-----
20150404143701.558659 <ybts-signalling:ALL> Removing connection (0x7fd948001ae0,1) [0x1b75500]
20150404143701.558704 <ybts/1:INFO> Connection released [0x7fd948002a40]
20150404143701.558730 <ybts/1:NOTE> deref() on releasing initial route message [0x7fd948002a40]
20150404143701.558765 <ybts/1:CALL> Hangup reason='net-out-of-order' [0x7fd948002a40][/i][/i]
20150404143701.558880 <ybts/1:CALL> Destroyed [0x7fd948002a40]
20150404143701.558931 <ybts-mm:ALL> Removed UE (0x7fd948002120): destroyed [0x1b758e0]
20150404143701.558952 <ybts:ALL> UE destroyed [0x7fd948002120]
20150404143701.559213 <mbts:MILD> SigConnection.cpp:506:process: primitive 3 length 0 took 32474 ms
20150404143701.559885 <javascript:ALL> Assistant for 'ybts/1' deleted
20150404143814.671183 <sip:ALL> Returning false from engine.stop handler
20150404143814.671203 <iaxengine:INFO> Status changed Listening -> Exiting [0x1a9ab60]
20150404143814.671207 <iaxengine:ALL> Set terminate timeout=16500ms status=Exiting [0x1a9ab60]
20150404143814.671216 <iax:INFO> Removed listener (0x1a9ab60) 'iaxengine' status='Exiting'
20150404143814.671377 <iaxengine:ALL> Thread (0x1aa8650) 'YIAXGetEvent' terminated [0x1a9ab60]
20150404143814.671537 <iaxengine:ALL> Thread (0x1aa8140) 'YIAXListener' terminated [0x1a9ab60]
20150404143814.671572 <iaxengine:ALL> Thread (0x1aa8360) 'YIAXListener' terminated [0x1a9ab60]
20150404143814.671608 <iaxengine:ALL> Thread (0x1aab290) 'YIAXListener' terminated [0x1a9ab60]
20150404143814.671666 <iaxengine:ALL> Thread (0x1aa8a90) 'YIAXGetEvent' terminated [0x1a9ab60]
20150404143814.672022 <iaxengine:ALL> Thread (0x1aa8d80) 'YIAXTrunking' terminated [0x1a9ab60]
20150404143814.672054 <jingle:ALL> YJGEngineWorker stop running
20150404143814.672935 <iaxengine:ALL> Thread (0x1aa8870) 'YIAXGetEvent' terminated [0x1a9ab60]
20150404143814.676296 <iaxengine:ALL> Destroyed [0x1a9ab60]
====================================================================================
Thank you,
Vic