[Openbts-discuss] B200 won't connect Phone Calls

[Matthew G. <mr...@dr...>]

Hi, 
 
 
 
I need some help with using OpenBTS. I have an Ettus USRP
B200. I have OpenBTS(v5.0) installed with UHD drivers 3.9.1. I have two phones
which I would like to connect to OpenBTS. I know both of the SIM’s IMSIs but I
don’t know their ki values. The phones identify and connect with OpenBTS after
I register them as subs using the cached connection. They can send and receive sms from each other, openbts, and the 411 number. However once connected,
they cannot make any calls including the built in 2600 echo number, 2601
tone number, and between them. I have the error logs from an attempted phone call between them below. I'll dial one phone and then after 6 seconds or so the phone initiating the call will signal that there was a connection error and the receiving phone will show nothing. Does anyone know how to fix my problem?




Thanks,




Matt G.




Dec 9 09:48:56 user1-desktop asterisk[4266]: VERBOSE[4292][C-00000001]: netsock2.c:516 in ast_set_qos: == Using SIP RTP TOS bits 184

Dec 9 09:48:56 user1-desktop asterisk[4266]: VERBOSE[4292][C-00000001]: netsock2.c:538 in ast_set_qos: == Using SIP RTP CoS mark 5

Dec 9 09:48:56 user1-desktop asterisk[4266]: VERBOSE[4301][C-00000001]: pbx.c:4898 in pbx_extension_helper: -- Executing [300@from-openBTS:1] Set("SIP/00101100010-00000001", "CDR(B-Number)=300") in new stack

Dec 9 09:48:56 user1-desktop asterisk[4266]: VERBOSE[4301][C-00000001]: pbx.c:4898 in pbx_extension_helper: -- Executing [300@from-openBTS:2] Set("SIP/00101100010-00000001", "CDR(A-IMSI)=IMSI001010123456404") in new stack

Dec 9 09:48:56 user1-desktop asterisk[4266]: VERBOSE[4301][C-00000001]: pbx.c:4898 in pbx_extension_helper: -- Executing [300@from-openBTS:3] Set("SIP/00101100010-00000001", "CDR(A-Name)=") in new stack

Dec 9 09:48:56 user1-desktop asterisk[4266]: VERBOSE[4301][C-00000001]: pbx.c:4898 in pbx_extension_helper: -- Executing [300@from-openBTS:4] Set("SIP/00101100010-00000001", "CDR(A-Number)=500") in new stack

Dec 9 09:48:56 user1-desktop asterisk[4266]: VERBOSE[4301][C-00000001]: pbx.c:4898 in pbx_extension_helper: -- Executing [300@from-openBTS:5] ExecIf("SIP/00101100010-00000001", "0?Set(CDR(A-Number)=IMSI001010123456404):Set(CALLERID(num)=500)") in new stack

Dec 9 09:48:56 user1-desktop asterisk[4266]: VERBOSE[4301][C-00000001]: pbx.c:4898 in pbx_extension_helper: -- Executing [300@from-openBTS:6] GotoIf("SIP/00101100010-00000001", "0?emergency,911,1") in new stack

Dec 9 09:48:56 user1-desktop asterisk[4266]: VERBOSE[4301][C-00000001]: pbx.c:4898 in pbx_extension_helper: -- Executing [300@from-openBTS:7] GotoIf("SIP/00101100010-00000001", "0?default,300,1") in new stack

Dec 9 09:48:56 user1-desktop asterisk[4266]: VERBOSE[4301][C-00000001]: pbx.c:4898 in pbx_extension_helper: -- Executing [300@from-openBTS:8] Goto("SIP/00101100010-00000001", "phones,300,1") in new stack

Dec 9 09:48:56 user1-desktop asterisk[4266]: VERBOSE[4301][C-00000001]: pbx.c:11493 in pbx_builtin_goto: -- Goto (phones,300,1)

Dec 9 09:48:56 user1-desktop asterisk[4266]: VERBOSE[4301][C-00000001]: pbx.c:4898 in pbx_extension_helper: -- Executing [300@phones:1] Set("SIP/00101100010-00000001", "CDR(B-IMSI)=IMSI001010123456102") in new stack

Dec 9 09:48:56 user1-desktop asterisk[4266]: VERBOSE[4301][C-00000001]: pbx.c:4898 in pbx_extension_helper: -- Executing [300@phones:2] GotoIf("SIP/00101100010-00000001", "1?B-IPAddr") in new stack

Dec 9 09:48:56 user1-desktop asterisk[4266]: VERBOSE[4301][C-00000001]: pbx.c:11493 in pbx_builtin_goto: -- Goto (phones,300,7)

Dec 9 09:48:56 user1-desktop asterisk[4266]: VERBOSE[4301][C-00000001]: pbx.c:4898 in pbx_extension_helper: -- Executing [300@phones:7] Set("SIP/00101100010-00000001", "CDR(B-IPAddr)=127.0.0.1") in new stack

Dec 9 09:48:56 user1-desktop asterisk[4266]: VERBOSE[4301][C-00000001]: pbx.c:4898 in pbx_extension_helper: -- Executing [300@phones:8] ExecIf("SIP/00101100010-00000001", "0?Set(CDR(B-IPAddr)="127.0.0.1")") in new stack

Dec 9 09:48:56 user1-desktop asterisk[4266]: VERBOSE[4301][C-00000001]: pbx.c:4898 in pbx_extension_helper: -- Executing [300@phones:9] Set("SIP/00101100010-00000001", "CDR(B-Port)=5062") in new stack

Dec 9 09:48:56 user1-desktop asterisk[4266]: VERBOSE[4301][C-00000001]: pbx.c:4898 in pbx_extension_helper: -- Executing [300@phones:10] ExecIf("SIP/00101100010-00000001", "0?Set(CDR(B-Port)=5062)") in new stack

Dec 9 09:48:56 user1-desktop asterisk[4266]: VERBOSE[4301][C-00000001]: pbx.c:4898 in pbx_extension_helper: -- Executing [300@phones:11] Gosub("SIP/00101100010-00000001", "to-openBTS,300,1(IMSI001010123456102,127.0.0.1,5062)") in new stack

Dec 9 09:48:56 user1-desktop asterisk[4266]: VERBOSE[4301][C-00000001]: pbx.c:4898 in pbx_extension_helper: -- Executing [300@to-openBTS:1] Set("SIP/00101100010-00000001", "CDR(hangupdirection)=A") in new stack

Dec 9 09:48:56 user1-desktop asterisk[4266]: VERBOSE[4301][C-00000001]: pbx.c:4898 in pbx_extension_helper: -- Executing [300@to-openBTS:2] Gosub("SIP/00101100010-00000001", "CallLimit,s,1(500,300)") in new stack

Dec 9 09:48:56 user1-desktop asterisk[4266]: VERBOSE[4301][C-00000001]: pbx.c:4898 in pbx_extension_helper: -- Executing [s@CallLimit:1] GotoIf("SIP/00101100010-00000001", "0?A-IMSI") in new stack

Dec 9 09:48:56 user1-desktop asterisk[4266]: VERBOSE[4301][C-00000001]: pbx.c:4898 in pbx_extension_helper: -- Executing [s@CallLimit:2] Set("SIP/00101100010-00000001", "GROUP(B)=IMSI001010123456102") in new stack

Dec 9 09:48:56 user1-desktop asterisk[4266]: VERBOSE[4301][C-00000001]: pbx.c:4898 in pbx_extension_helper: -- Executing [s@CallLimit:3] GotoIf("SIP/00101100010-00000001", "0?VM") in new stack

Dec 9 09:48:56 user1-desktop asterisk[4266]: VERBOSE[4301][C-00000001]: pbx.c:4898 in pbx_extension_helper: -- Executing [s@CallLimit:4] ExecIf("SIP/00101100010-00000001", "0?Return()") in new stack

Dec 9 09:48:56 user1-desktop asterisk[4266]: VERBOSE[4301][C-00000001]: pbx.c:4898 in pbx_extension_helper: -- Executing [s@CallLimit:5] Set("SIP/00101100010-00000001", "GROUP(A)=IMSI001010123456404") in new stack

Dec 9 09:48:56 user1-desktop asterisk[4266]: VERBOSE[4301][C-00000001]: pbx.c:4898 in pbx_extension_helper: -- Executing [s@CallLimit:6] GotoIf("SIP/00101100010-00000001", "0?VM") in new stack

Dec 9 09:48:56 user1-desktop asterisk[4266]: VERBOSE[4301][C-00000001]: pbx.c:4898 in pbx_extension_helper: -- Executing [s@CallLimit:7] Return("SIP/00101100010-00000001", "") in new stack

Dec 9 09:48:56 user1-desktop asterisk[4266]: VERBOSE[4301][C-00000001]: pbx.c:4898 in pbx_extension_helper: -- Executing [300@to-openBTS:3] Set("SIP/00101100010-00000001", "CALLERID(num)=500") in new stack

Dec 9 09:48:56 user1-desktop asterisk[4266]: VERBOSE[4301][C-00000001]: pbx.c:4898 in pbx_extension_helper: -- Executing [300@to-openBTS:4] Set("SIP/00101100010-00000001", "CALLERID(name)=500") in new stack

Dec 9 09:48:56 user1-desktop asterisk[4266]: VERBOSE[4301][C-00000001]: pbx.c:4898 in pbx_extension_helper: -- Executing [300@to-openBTS:5] Dial("SIP/00101100010-00000001", "SIP/IMSI001010123456102@127.0.0.1:5062,3600,g") in new stack

Dec 9 09:48:56 user1-desktop asterisk[4266]: VERBOSE[4301][C-00000001]: netsock2.c:516 in ast_set_qos: == Using SIP RTP TOS bits 184

Dec 9 09:48:56 user1-desktop asterisk[4266]: VERBOSE[4301][C-00000001]: netsock2.c:538 in ast_set_qos: == Using SIP RTP CoS mark 5

Dec 9 09:48:56 user1-desktop asterisk[4266]: VERBOSE[4301][C-00000001]: app_dial.c:2625 in dial_exec_full: -- Called SIP/IMSI001010123456102@127.0.0.1:5062

Dec 9 09:49:03 user1-desktop asterisk[4266]: VERBOSE[4301][C-00000001]: pbx.c:6574 in __ast_pbx_run: == Spawn extension (to-openBTS, 300, 5) exited non-zero on 'SIP/00101100010-00000001'

Dec 9 09:49:03 user1-desktop asterisk[4266]: VERBOSE[4301][C-00000001]: pbx.c:4898 in pbx_extension_helper: -- Executing [h@to-openBTS:1] Log("SIP/00101100010-00000001", "NOTICE,A-Number=500 A-Name= A-IMSI=IMSI001010123456404 B-Number=300 B-Name= B-IMSI=IMSI001010123456102 hangupcause=0 dialstatus=CANCEL hangupdirection=A duration=6 billsec=0") in new stack

Dec 9 09:49:03 user1-desktop asterisk[4266]: NOTICE[4301][C-00000001]: Ext. h:1 in @ to-openBTS: A-Number=500 A-Name= A-IMSI=IMSI001010123456404 B-Number=300 B-Name= B-IMSI=IMSI001010123456102 hangupcause=0 dialstatus=CANCEL hangupdirection=A duration=6 billsec=0

Dec 9 09:49:03 user1-desktop asterisk[4266]: VERBOSE[4301][C-00000001]: pbx.c:4898 in pbx_extension_helper: -- Executing [h@to-openBTS:2] Hangup("SIP/00101100010-00000001", "") in new stack

Dec 9 09:49:03 user1-desktop asterisk[4266]: VERBOSE[4301][C-00000001]: pbx.c:6092 in ast_pbx_h_exten_run: == Spawn extension (to-openBTS, h, 2) exited non-zero on 'SIP/00101100010-00000001'

Dec 9 09:49:03 user1-desktop openbts: NOTICE 2532:2756 2015-12-09T09:49:03.0 SIPDialog.cpp:1433:handleInviteResponse: request failure code 487 SipBase( localUsername=IMSI001010123456404 remoteUsername=300 InviteViaBranch=z9hG4bKOBTSomegvconzmrwtedd DialogStateVars=( CallId=3f58eb6c3bf2d2e LocalHeader.value()= <sip:IMSI001010123456404@127.0.0.1:5062>;tag=OBTSdlfskpnmursmswgf RemoteHeader.value()=<sip:300@127.0.0.1>;tag=as27c062c2 LocalCSeq=116460 RouteSet= proxy= ipName=127.0.0.1:5060 ipIP=127.0.0.1 ipPort=5060) LastResponse=SipMessage( code=487 reason=Request Terminated CSeq=116459 INVITE callid=3f58eb6c3bf2d2e To=<sip:300@127.0.0.1>;tag=as27c062c2 From=<sip:IMSI001010123456404@127.0.0.1:5062>;tag=OBTSdlfskpnmursmswgf Vias=SIP/2.0/UDP 127.0.0.1:5062;branch=z9hG4bKOBTSomegvconzmrwtedd;received=127.0.0.1 header Server=Asterisk PBX 11.7.0.4 header Allow=INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH header Supported=replaces, timer header Content-Length=0 firstLine=) SipState=MODCanceling DialogType=3)

Dec 9 09:49:56 user1-desktop transceiver: NOTICE 2739:2764 2015-12-09T09:49:56.3 Transceiver.cpp:192:pushRadioVector: dumping STALE burst in TRX->USRP interface

Dec 9 09:50:48 user1-desktop transceiver: NOTICE 2739:2764 2015-12-09T09:50:48.6 Transceiver.cpp:192:pushRadioVector: dumping STALE burst in TRX->USRP interface

Dec 9 09:51:34 user1-desktop transceiver: NOTICE 2739:2764 2015-12-09T09:51:34.7 Transceiver.cpp:192:pushRadioVector: dumping STALE burst in TRX->USRP interface

Dec 9 09:51:35 user1-desktop transceiver: NOTICE 2739:2764 2015-12-09T09:51:35.9 Transceiver.cpp:192:pushRadioVector: dumping STALE burst in TRX->USRP interface