Extension not found in context (Asterisk+openBTS+USRP N210)

Dear all,
I’m building a test-bed, in which two mobile phones connect to the usrp N210 box via SIP connection.
The two mobile phones have successfully registered to the GSM network. Also, SipAuthServe, Smqueue, OpenBTS2.8 , and asterisk 11 are running in the same PCin different ports.
For each phone, the echo test works fine (by dialing 2600). However, when I try to call each other, the error occurs in the asterisk CLI:
Call from ‘IMSIXXXXXXX’ (127.0.0.1:5060) to extension ‘IMSIXXXXXXXX’ rejected because extension not found in context ‘sip-local’.
got crazy about this problem (took me two days…), anyone can help me please!! Thanks a million!!!
Here are the configuration files (only show the part I modified):

sip.conf

[IMSIXXXXXXXXXXXX]
username=IMSIXXXXXXXXXXXX ; usually the same with the section title
callerid=1111
canreinvite=yes ;allow RTP voice traffic to go through Asterisk
type=friend
context=sip-local
host=127.0.0.1
disallow=all ;use disallow=all before using allow=..
allow=gsm
allow=g729
dtmfmode=info ;either RFC2833 or INFO for the BudgeTone
qualify=yes
nat=no ;there is no NAT between phone and asterisk

[IMSIXXXXXXXXXXXXX]
username=IMSIXXXXXXXXXXXXX
callerid=2222
canreinvite=yes
type=friend
context=sip-local
host=127.0.0.1
disallow=all
allow=gsm
allow=g729
dtmfmode=info
qualify=yes
nat=no 

extensions.conf

[code]
[sip-local]
include => default
exten => 1111,1,Dial(SIP/IMSI272017211649760,10,t)
exten => 2222,1,Dial(SIP/IMSI272017012733244,10,t)

I don’t understand why this hasn’t been faulted as a loop back!

If you have host=127.0.0.1, you need to specify an explicit port number, as Asterisk itself will have bound to the default one. You will need to specify that port number in the configuration for the other SIP software on the same machine.

canreinvite is a deprecated synonym for directmedia. directmedia=yes does not cause media to go through Asterisk. It is actually one of the conditions that have to be fulfilled for media to NOT go through Asterisk.

Normally, type=peer causes less problems than type=friend. It is only needed in relatively unusual dynamic host cases.

When you say “only the part you modified”, where did you get the unmodified version from?

I hope you have an appropriate radio telephony licence, as the documentation for OpenBTS seems to say it is only sometimes necessary, when it will always be necessary.

The error is very clear, You dont have an extension IMSIXXXXXXXX in your dialplan.

If you want more support provide the debug of the complete call without masking anything, and the complete extensions.conf

navaismo: It is not as simple as that. He does have that address on OpenBTS, but he is actually routing the call back to Asterisk, rather than to the OpenBTS software.

The immediate cause is the lack of that extension on Asterisk, but the underlying cause is that he has multiple SIP UAs on the same IP address, but hasn’t set up their port numbers correctly.

Although I haven’t used OpenBTS, it is software for running a private GSM cell and I assume the extension number will be derived from the IMSI of the SIM card on the phone.

[quote=“david55”]navaismo: It is not as simple as that. He does have that address on OpenBTS, but he is actually routing the call back to Asterisk, rather than to the OpenBTS software.

The immediate cause is the lack of that extension on Asterisk, but the underlying cause is that he has multiple SIP UAs on the same IP address, but hasn’t set up their port numbers correctly.

Although I haven’t used OpenBTS, it is software for running a private GSM cell and I assume the extension number will be derived from the IMSI of the SIM card on the phone.[/quote]
Understood :bulb:

Hi David,
Thanks a lot for your advice. Here are the port numbers used in my testbed and they are setup in openBTS.db (wush.net/trac/rangepublic/wiki/multiBTS):
PBX (Asterisk)- 192.168.1.0:5060
smqueue - 192.168.1.0:5063
sipauthserve - 192.168.1.0: 5064
OpenBTS (1st BTS) - 192.168.1.1:5062
OpenBTS (2nd BTS) -192.168.1.2:5062
I did some changes as follows:
1)added the port number (5060) into extensions.conf
2)change canreinvite=no, so go through Asterisk
3)change type=peer
echo test still fine, unfortunately, when I call from one pone to another, the error comes at asterisk CLI: (please understand that I hide the IMSI number for security reason)
[color=#FF0000][May 28 10:19:45] NOTICE[3926][C-00000001]: chan_sip.c:25288 handle_request_invite: Call from ‘IMSIXXXXXXXXXXXXX’ (127.0.0.1:5060) to extension ‘IMSIXXXXXXXXXXXXX’ rejected because extension not found in context ‘sip-local’.[/color]
I also noticed that:

  1. I can only set the port number in sip.conf as 5060, otherwise, whenever I reload the dialplan (CLI>dialplan reload), the error is :“extension not reachable”
  2. everytime when I dial the number, for instance, 1st phone is 1111, 2nd phone is 2222, when dialing 2222 on 1st phone, the port number of 1st is changed to 5062 in sqlite3.db (in the table SIP_buddies).
    ==================================================================================================
    I attached my code here:
    sip.conf
[general]
bindport=5060			; asterisk 1.6
				; UDP Port to bind to (SIP standard port for unencrypted UDP 
				; and TCP sessions is 5060)
				; bindport is the local UDP port that Asterisk will listen on
bindaddr=0.0.0.0		; asterisk 1.6
				; IP address to bind UDP listen socket to (0.0.0.0 binds to all)
				; You can specify port here too, like 123.123.123.123:5080
udpbindaddr=0.0.0.0		; asterisk 1.8
				; IP address to bind UDP listen socket to (0.0.0.0 binds to all)
				; Optionally add a port number, 192.168.1.1:5062 (default is port 5060)

tos_sip=cs3			; Sets TOS for SIP packets.
tos_audio=ef			; Sets TOS for RTP audio packets.
tos_video=af41			; Sets TOS for RTP video packets.
tos_text=af41			; Sets TOS for RTP text packets.

cos_sip=3			; Sets 802.1p priority for SIP packets.
cos_audio=5			; Sets 802.1p priority for RTP audio packets.
cos_video=4			; Sets 802.1p priority for RTP video packets.
cos_text=3			; Sets 802.1p priority for RTP text packets.

maxexpiry=3600			; Maximum allowed time of incoming registrations
				; and subscriptions (seconds)
minexpiry=60			; Minimum length of registrations/subscriptions (default 60)
defaultexpiry=3600		; Default length of incoming/outgoing registration
dynamic_exclude_static=yes	; Disallow all dynamic hosts from registering
				; as any IP address used for staticly defined
				; hosts.  This helps avoid the configuration
				; error of allowing your users to register at
				; the same address as a SIP provider.
use_q850_reason=yes		; Set to yes add Reason header and use Reason header if it is available.

;t1min=100			; Minimum roundtrip time for messages to monitored hosts
				; Defaults to 100 ms
;timert1=500			; Default T1 timer
				; Defaults to 500 ms or the measured round-trip
				; time to a peer (qualify=yes).
;timerb=32000			; Call setup timer. If a provisional response is not received
				; in this amount of time, the call will autocongest
				; Defaults to 64*timert1
rtptimeout=60			; Terminate call if 60 seconds of no RTP or RTCP activity
				; on the audio channel
				; when we're not on hold. This is to be able to hangup
				; a call in the case of a phone disappearing from the net,
				; like a powerloss or grandma tripping over a cable.
rtpholdtimeout=300		; Terminate call if 300 seconds of no RTP or RTCP activity
				; on the audio channel
				; when we're on hold (must be > rtptimeout)

;allowguest=no			; Allow or reject guest calls (default is yes)
autocreatepeer=yes		; The Autocreatepeer option allows, 
				; if set to Yes, any SIP ua to register with your Asterisk PBX as a peer. 
				; This peer's settings will be based on global options. 
				; The peer's name will be based on the user part of the Contact: header field's URL. 

context=phones			; Default context for incoming calls
allowoverlap=no			; Disable overlap dialing support. (Default is yes)

disallow=all			; need to disallow=all before we can use allow=
allow=gsm			; GSM
allow=ulaw			; ISDN US
allow=alaw			; ISDN EU

relaxdtmf=yes			; Relax dtmf handling
dtmfmode=auto			; Set default dtmfmode for sending DTMF. Default: rfc2833
				; Other options:
				; info : SIP INFO messages (application/dtmf-relay)
				; shortinfo : SIP INFO messages (application/dtmf)
				; inband : Inband audio (requires 64 kbit codec -alaw, ulaw)
				; auto : Use rfc2833 if offered, inband otherwise

[IMSIXXXXXXXXXXXXX]
username=IMSIXXXXXXXXXXXXX ; usually the same with the section title
callerid=1111
canreinvite=no ; allow RTP voice traffic  go through Asterisk
type=peer
context=sip-local
host=127.0.0.1
port=5060
disallow=all ;use disallow=all before using allow=..
allow=gsm
allow=g729
dtmfmode=info ;either RFC2833 or INFO for the BudgeTone
qualify=yes
nat=no ;there is no NAT between phone and asterisk

[IMSIXXXXXXXXXXXXX]
username=IMSIXXXXXXXXXXXXX
callerid=2222
canreinvite=no
type=peer
context=sip-local
host=127.0.0.1
port=5060
disallow=all
allow=gsm
allow=g729
dtmfmode=info
qualify=yes
nat=no 

extensions.conf

[globals]
[default]
; This is the context for handsets that are allowed to attached via open registration.
; Normally, this context is only used for testing.

; These are test extensions that you might want to disable after installation.

; Create an extension, 2600, for evaluating echo latency.
exten => 2600,1,Answer()                     ; Do the echo test
exten => 2600,n,Echo                     ; Do the echo test
exten => 2600,n,Hangup

[outbound-trunk]
; If you had an external trunk, you would dial it here.
exten => _N.,1,Answer()

[sip-local]
include => default
include => macro-dialGSM
exten => 1111,1,Dial(SIP/IMSIXXXXXXXXXXXXX,10,t)
exten => 2222,1,Dial(SIP/IMSIXXXXXXXXXXXXX,10,t) 

[macro-dialGSM]
exten => s,1,Dial(SIP/${ARG1})
exten => s,2,Goto(s-${DIALSTATUS},1)
exten => s-CANCEL,1,Hangup
exten => s-NOANSWER,1,Hangup
exten => s-BUSY,1,Busy(30)
exten => s-CONGESTION,1,Congestion(30)
exten => s-CHANUNAVAIL,1,playback(ss-noservice)
exten => s-CANCEL,1,Hangup

[sip-external]
; This is the top-level context that gives access to out-of-network calling.
; also includes the in-network calling.
include => sip-local
include => outbound-trunk

=============================================================

[quote=“david55”]I don’t understand why this hasn’t been faulted as a loop back!

If you have host=127.0.0.1, you need to specify an explicit port number, as Asterisk itself will have bound to the default one. You will need to specify that port number in the configuration for the other SIP software on the same machine.

canreinvite is a deprecated synonym for directmedia. directmedia=yes does not cause media to go through Asterisk. It is actually one of the conditions that have to be fulfilled for media to NOT go through Asterisk.

Normally, type=peer causes less problems than type=friend. It is only needed in relatively unusual dynamic host cases.

When you say “only the part you modified”, where did you get the unmodified version from?

I hope you have an appropriate radio telephony licence, as the documentation for OpenBTS seems to say it is only sometimes necessary, when it will always be necessary.[/quote]

The port number doesn’t go in extensions.conf.

The port number that needs to go in SIP.conf is that of the destination and it needs to be in the individual destination sections.

If you are running two instances of OpenBTS, they will each need different port numbers.

If you are getting error 20 when trying to make a call to a port number, I would look at the firewall settings, and try setting qualify to no (maybe it is not even rejecting OPTIONS properly).

[quote=“david55”]The port number doesn’t go in extensions.conf.

The port number that needs to go in SIP.conf is that of the destination and it needs to be in the individual destination sections.

If you are running two instances of OpenBTS, they will each need different port numbers.[/quote]
Dear David,
Thanks for your quick feedback! unfortunately, still failed:(
First, I did the changes as follows:

  1. quality=no
  2. firewall shutdown (always closed)
    Then in sip.conf, i tried different settings of port numbers for the two phones separately:
    a) 127.0.0.1:16387 and 127.0.0.1:16389 (RTP on UDP ports 16386-16483: http://gnuradio.org/redmine/projects/gnuradio/wiki/OpenBTSBuildingAndRunning)
    b) 127.0.0.1:5701 and 127.0.0.1:5702 (openbts transceiver interface: starting from 5700)
    When I call from one phone to the other, there is no errors, and the phone is waiting untill the following warning:
    [color=#FF0000][May 28 14:21:41] WARNING[9430]: chan_sip.c:4169 retrans_pkt: Retransmission timeout reached on transmission 122a92a122cf1b5139da12123698e45e@127.0.0.1:5060 for seqno 102 (Critical Request) – See wiki.asterisk.org/wiki/display/ … nsmissions[/color]
    Here is /var/log/syslog file, where you can see the phone is registered successfully.
May 28 14:20:13 zhenhui openbts: INFO 140362755741440 GSML3Message.cpp:164:parseL3: L3 recv MM Location Updating Request LAI=(MCC=001 MNC=01 LAC=0xfffe) MobileIdentity=([color=#FF0000]IMSI[/color]=272017211649760) classmark=(revision=1 ES-IND=1 A5/1=0 powerCap=3)
May 28 14:20:13 zhenhui openbts: INFO 140362755741440 MobilityManagement.cpp:149:LocationUpdatingController: MM Location Updating Request LAI=(MCC=001 MNC=01 LAC=0xfffe) MobileIdentity=([color=#FF0000]IMSI[/color]=272017211649760) classmark=(revision=1 ES-IND=1 A5/1=0 powerCap=3)
May 28 14:20:13 zhenhui openbts: INFO 140362754942720 GSML2LAPDm.cpp:906:sendUFrameUI: obj: 0x22f6fb0 state=LinkReleased payload=primitive=UNIT_DATA raw=(061d8e13a800000000000000000000000000)
May 28 14:20:13 zhenhui openbts: INFO 140362754942720 GSML1FEC.cpp:1565:sendFrame: obj: 0x22f4d50 SACCHL1Encoder primitive=DATA raw=(031349061d8e13a8000000000000000000000000002b2b)
May 28 14:20:13 zhenhui openbts: INFO 140362754942720 GSML1FEC.cpp:1605:sendFrame: obj: 0x22f4d50 SACCHL1Encoder orders pow=5 TA=1.35547
May 28 14:20:13 zhenhui openbts: INFO 140362754676480 GSML2LAPDm.cpp:775:receiveIFrame: obj: 0x22f4570 state=ContentionResolution NS=0 NR=0 primitive=DATA raw=(0100350634c8021a3b72f210003d0101a63a534ce2b27c)
May 28 14:20:13 zhenhui openbts: INFO 140362754676480 GSML2LAPDm.cpp:809:sendSFrameRR: obj: 0x22f4570 F=0 state=LinkEstablished VS=0 VR=1
May 28 14:20:13 zhenhui openbts: INFO 140362753345280 GSML2LAPDm.cpp:906:sendUFrameUI: obj: 0x22fc650 state=LinkReleased payload=primitive=UNIT_DATA raw=(061e000a00f11003e82f01)
May 28 14:20:13 zhenhui openbts: INFO 140362753345280 GSML1FEC.cpp:1565:sendFrame: obj: 0x22fa350 SACCHL1Encoder primitive=DATA raw=(03132d061e000a00f11003e82f012b2b2b2b2b2b2b2b2b)
May 28 14:20:13 zhenhui openbts: INFO 140362753345280 GSML1FEC.cpp:1605:sendFrame: obj: 0x22fa350 SACCHL1Encoder orders pow=5 TA=1.44531
May 28 14:20:13 zhenhui openbts: INFO 140362790426368 GSML1FEC.cpp:709:processBurst: obj: 0x22f58a0 SACCHL1Decoder  RSSI=-19 timingError=0.34375
May 28 14:20:13 zhenhui openbts: INFO 140362790426368 GSML1FEC.cpp:709:processBurst: obj: 0x22f58a0 SACCHL1Decoder  RSSI=-19 timingError=0.347656
May 28 14:20:13 zhenhui openbts: INFO 140362790426368 GSML1FEC.cpp:709:processBurst: obj: 0x22f58a0 SACCHL1Decoder  RSSI=-19 timingError=0.34375
May 28 14:20:13 zhenhui openbts: INFO 140362790426368 GSML1FEC.cpp:709:processBurst: obj: 0x22f58a0 SACCHL1Decoder  RSSI=-19 timingError=0.347656
May 28 14:20:13 zhenhui openbts: INFO 140362790426368 GSML1FEC.cpp:726:handleGoodFrame: obj: 0x22f58a0 SACCHL1Decoder actuals pow=33 TA=1
May 28 14:20:13 zhenhui openbts: INFO 140362755741440 SIPEngine.cpp:319:Register: user [color=#FF0000]IMSI272017211649760[/color] state Null 0 callID 537099436
[color=#FF0000]May 28 14:20:13 zhenhui openbts: INFO 140362755741440 SIPInterface.cpp:139:addCall: creating SIP message FIFO callID 537099436[/color]
May 28 14:20:13 zhenhui openbts: INFO 140362753079040 GSML2LAPDm.cpp:520:receiveUFrameSABM: obj: 0x22f99f0 state=LinkReleased primitive=DATA raw=(013f4905087000f110fffe3308292710172115251360ca)
May 28 14:20:13 zhenhui openbts: INFO 140362753079040 GSML2LAPDm.cpp:865:sendUFrameUA: obj: 0x22f99f0 state=ContentionResolution primitive=DATA raw=(013f4905087000f110fffe3308292710172115251360ca)
May 28 14:20:13 zhenhui openbts: INFO 140362755741440 SubscriberRegistry.cpp:269:sqlUpdate: update sip_buddies set ipaddr = "127.0.0.1" where name = "[color=#FF0000]IMSI272017211649760[/color]"
May 28 14:20:13 zhenhui openbts: INFO 140362755741440 SubscriberRegistry.cpp:218:sqlLocal: update sip_buddies set ipaddr = "127.0.0.1" where name = "IMSI272017211649760"
May 28 14:20:13 zhenhui openbts: INFO 140362754144000 GSML3Message.cpp:164:parseL3: L3 recv MM Location Updating Request LAI=(MCC=001 MNC=01 LAC=0xfffe) MobileIdentity=(IMSI=272017112515231) classmark=(revision=1 ES-IND=1 A5/1=0 powerCap=3)
May 28 14:20:13 zhenhui openbts: INFO 140362754144000 MobilityManagement.cpp:149:LocationUpdatingController: MM Location Updating Request LAI=(MCC=001 MNC=01 LAC=0xfffe) MobileIdentity=(IMSI=272017112515231) classmark=(revision=1 ES-IND=1 A5/1=0 powerCap=3)
May 28 14:20:13 zhenhui transceiver: INFO 140655103735552 Transceiver.cpp:767:writeClockInterface: ClockInterface: sending IND CLOCK 2403358
May 28 14:20:13 zhenhui openbts: INFO 140362755741440 SubscriberRegistry.cpp:269:sqlUpdate: update sip_buddies set port = "5062" where name = "IMSI272017211649760"
May 28 14:20:13 zhenhui openbts: INFO 140362755741440 SubscriberRegistry.cpp:218:sqlLocal: update sip_buddies set port = "5062" where name = "IMSI272017211649760"
May 28 14:20:13 zhenhui openbts: INFO 140362754942720 GSML2LAPDm.cpp:906:sendUFrameUI: obj: 0x22f6fb0 state=LinkReleased payload=primitive=UNIT_DATA raw=(061e000a00f11003e82f01)
May 28 14:20:13 zhenhui openbts: INFO 140362755475200 GSML2LAPDm.cpp:685:receiveUFrameUI: obj: 0x22f6fb0 state=LinkReleased primitive=DATA raw=(01034906153f7f7e000000000000000000000000002b2b)
May 28 14:20:13 zhenhui openbts: INFO 140362754942720 GSML1FEC.cpp:1565:sendFrame: obj: 0x22f4d50 SACCHL1Encoder primitive=DATA raw=(03132d061e000a00f11003e82f012b2b2b2b2b2b2b2b2b)
May 28 14:20:13 zhenhui openbts: INFO 140362754942720 GSML1FEC.cpp:1605:sendFrame: obj: 0x22f4d50 SACCHL1Encoder orders pow=5 TA=1.35059
May 28 14:20:13 zhenhui openbts: INFO 140362753345280 GSML2LAPDm.cpp:906:sendUFrameUI: obj: 0x22fc650 state=LinkReleased payload=primitive=UNIT_DATA raw=(061d8e13a800000000000000000000000000)
May 28 14:20:13 zhenhui openbts: INFO 140362753345280 GSML1FEC.cpp:1565:sendFrame: obj: 0x22fa350 SACCHL1Encoder primitive=DATA raw=(031349061d8e13a8000000000000000000000000002b2b)
May 28 14:20:13 zhenhui openbts: INFO 140362753345280 GSML1FEC.cpp:1605:sendFrame: obj: 0x22fa350 SACCHL1Encoder orders pow=5 TA=1.44531
May 28 14:20:13 zhenhui openbts: INFO 140362753079040 GSML2LAPDm.cpp:775:receiveIFrame: obj: 0x22f99f0 state=ContentionResolution NS=0 NR=0 primitive=DATA raw=(0100350634de00583972f210003d0101112d767fb05cba)
May 28 14:20:13 zhenhui openbts: INFO 140362753079040 GSML2LAPDm.cpp:809:sendSFrameRR: obj: 0x22f99f0 F=0 state=LinkEstablished VS=0 VR=1
May 28 14:20:13 zhenhui openbts: INFO 140362790426368 GSML1FEC.cpp:709:processBurst: obj: 0x22f58a0 SACCHL1Decoder  RSSI=-24 timingError=0.347656
May 28 14:20:13 zhenhui openbts: INFO 140362790426368 GSML1FEC.cpp:709:processBurst: obj: 0x22f58a0 SACCHL1Decoder  RSSI=-24 timingError=0.335938
May 28 14:20:13 zhenhui openbts: INFO 140362790426368 GSML1FEC.cpp:709:processBurst: obj: 0x22f58a0 SACCHL1Decoder  RSSI=-24 timingError=0.351562
May 28 14:20:13 zhenhui openbts: INFO 140362790426368 GSML1FEC.cpp:709:processBurst: obj: 0x22f58a0 SACCHL1Decoder  RSSI=-24 timingError=0.335938
May 28 14:20:13 zhenhui openbts: INFO 140362790426368 GSML1FEC.cpp:726:handleGoodFrame: obj: 0x22f58a0 SACCHL1Decoder actuals pow=33 TA=1
May 28 14:20:13 zhenhui openbts: INFO 140362790426368 GSML1FEC.cpp:709:processBurst: obj: 0x22faf40 SACCHL1Decoder  RSSI=-16 timingError=0.46875
May 28 14:20:13 zhenhui openbts: INFO 140362790426368 GSML1FEC.cpp:709:processBurst: obj: 0x22faf40 SACCHL1Decoder  RSSI=-16 timingError=0.464844
May 28 14:20:13 zhenhui openbts: INFO 140362790426368 GSML1FEC.cpp:709:processBurst: obj: 0x22faf40 SACCHL1Decoder  RSSI=-16 timingError=0.460938
May 28 14:20:13 zhenhui openbts: INFO 140362790426368 GSML1FEC.cpp:709:processBurst: obj: 0x22faf40 SACCHL1Decoder  RSSI=-16 timingError=0.464844
May 28 14:20:13 zhenhui openbts: INFO 140362790426368 GSML1FEC.cpp:726:handleGoodFrame: obj: 0x22faf40 SACCHL1Decoder actuals pow=33 TA=1
May 28 14:20:14 zhenhui openbts: INFO 140362755741440 SIPInterface.cpp:207:write: write REGISTER sip:127.0.0.1 SIP/2.0#015
May 28 14:20:14 zhenhui openbts: INFO 140362754144000 SIPEngine.cpp:319:Register: user IMSI272017112515231 state Null 0 callID 400776675
[color=#FF0000]May 28 14:20:14 zhenhui openbts: INFO 140362754144000 SIPInterface.cpp:139:addCall: creating SIP message FIFO callID 400776675[/color]
May 28 14:20:14 zhenhui openbts: INFO 140362755475200 GSML2LAPDm.cpp:685:receiveUFrameUI: obj: 0x22f6fb0 state=LinkReleased primitive=DATA raw=(01034906153f3f00000000000000000000000000002b2b)
May 28 14:20:14 zhenhui openbts: INFO 140362754942720 GSML3Message.cpp:164:parseL3: L3 recv RR Measurement Report BA_USED=0 DTX_USED=0 MEAS_VALID=1
May 28 14:20:14 zhenhui openbts: INFO 140362753345280 GSML2LAPDm.cpp:906:sendUFrameUI: obj: 0x22fc650 state=LinkReleased payload=primitive=UNIT_DATA raw=(061e000a00f11003e82f01)
May 28 14:20:14 zhenhui openbts: INFO 140362753877760 GSML2LAPDm.cpp:685:receiveUFrameUI: obj: 0x22fc650 state=LinkReleased primitive=DATA raw=(01034906153f7f7e000000000000000000000000002b2b)
May 28 14:20:14 zhenhui openbts: INFO 140362753345280 GSML1FEC.cpp:1565:sendFrame: obj: 0x22fa350 SACCHL1Encoder primitive=DATA raw=(03132d061e000a00f11003e82f012b2b2b2b2b2b2b2b2b)
May 28 14:20:14 zhenhui openbts: INFO 140362753345280 GSML1FEC.cpp:1605:sendFrame: obj: 0x22fa350 SACCHL1Encoder orders pow=5 TA=1.45508
May 28 14:20:14 zhenhui openbts: INFO 140362754144000 SubscriberRegistry.cpp:269:sqlUpdate: update sip_buddies set ipaddr = "127.0.0.1" where name = "IMSI272017112515231"
May 28 14:20:14 zhenhui openbts: INFO 140362754144000 SubscriberRegistry.cpp:218:sqlLocal: update sip_buddies set ipaddr = "127.0.0.1" where name = "IMSI272017112515231"
May 28 14:20:14 zhenhui openbts: INFO 140362790426368 GSML1FEC.cpp:709:processBurst: obj: 0x22f58a0 SACCHL1Decoder  RSSI=-27 timingError=0.339844
May 28 14:20:14 zhenhui openbts: INFO 140362790426368 GSML1FEC.cpp:709:processBurst: obj: 0x22f58a0 SACCHL1Decoder  RSSI=-27 timingError=0.335938
May 28 14:20:14 zhenhui openbts: INFO 140362790426368 GSML1FEC.cpp:709:processBurst: obj: 0x22f58a0 SACCHL1Decoder  RSSI=-27 timingError=0.332031
May 28 14:20:14 zhenhui openbts: INFO 140362790426368 GSML1FEC.cpp:709:processBurst: obj: 0x22f58a0 SACCHL1Decoder  RSSI=-27 timingError=0.347656
May 28 14:20:14 zhenhui openbts: INFO 140362790426368 GSML1FEC.cpp:726:handleGoodFrame: obj: 0x22f58a0 SACCHL1Decoder actuals pow=27 TA=1
May 28 14:20:14 zhenhui openbts: INFO 140362755475200 GSML2LAPDm.cpp:685:receiveUFrameUI: obj: 0x22f6fb0 state=LinkReleased primitive=DATA raw=(01034906153f3f00000000000000000000000000002b2b)
May 28 14:20:14 zhenhui openbts: INFO 140362790426368 GSML1FEC.cpp:709:processBurst: obj: 0x22faf40 SACCHL1Decoder  RSSI=-10 timingError=0.46875
May 28 14:20:14 zhenhui openbts: INFO 140362790426368 GSML1FEC.cpp:709:processBurst: obj: 0x22faf40 SACCHL1Decoder  RSSI=-10 timingError=0.472656
May 28 14:20:14 zhenhui openbts: INFO 140362790426368 GSML1FEC.cpp:709:processBurst: obj: 0x22faf40 SACCHL1Decoder  RSSI=-10 timingError=0.46875
May 28 14:20:14 zhenhui openbts: INFO 140362790426368 GSML1FEC.cpp:709:processBurst: obj: 0x22faf40 SACCHL1Decoder  RSSI=-10 timingError=0.46875
May 28 14:20:14 zhenhui openbts: INFO 140362790426368 GSML1FEC.cpp:726:handleGoodFrame: obj: 0x22faf40 SACCHL1Decoder actuals pow=33 TA=1
May 28 14:20:14 zhenhui openbts: INFO 140362757072640 SIPInterface.cpp:262:drive: read SIP/2.0 200 OK#015
May 28 14:20:14 zhenhui openbts: INFO 140362757072640 SubscriberRegistry.cpp:269:sqlUpdate: update sip_buddies set ipaddr = "127.0.0.1" where name = "IMSI272017211649760"
May 28 14:20:14 zhenhui openbts: INFO 140362757072640 SubscriberRegistry.cpp:218:sqlLocal: update sip_buddies set ipaddr = "127.0.0.1" where name = "IMSI272017211649760"
May 28 14:20:14 zhenhui openbts: INFO 140362757072640 SubscriberRegistry.cpp:269:sqlUpdate: update sip_buddies set port = "5062" where name = "IMSI272017211649760"
May 28 14:20:14 zhenhui openbts: INFO 140362757072640 SubscriberRegistry.cpp:218:sqlLocal: update sip_buddies set port = "5062" where name = "IMSI272017211649760"
May 28 14:20:14 zhenhui openbts: INFO 140362755741440 SIPEngine.cpp:371:Register: received status 200 OK
[color=#FF0000]May 28 14:20:14 zhenhui openbts: INFO 140362755741440 SIPEngine.cpp:374:Register: REGISTER success[/color]
May 28 14:20:14 zhenhui openbts: INFO 140362755741440 SIPInterface.cpp:146:removeCall: removing SIP message FIFO callID 537099436
May 28 14:20:14 zhenhui openbts: INFO 140362755741440 MobilityManagement.cpp:281:LocationUpdatingController: registration SUCCESS: IMSI=272017211649760
May 28 14:20:14 zhenhui openbts: INFO 140362755741440 GSMLogicalChannel.cpp:92:send: L3 SAP0 sending MM Location Updating Accept LAI=(MCC=001 MNC=01 LAC=0x3e8)
May 28 14:20:14 zhenhui openbts: INFO 140362755741440 GSML2LAPDm.cpp:924:sendMultiframeData: obj: 0x22f4570 state=LinkEstablished payload=primitive=DATA raw=(050200f11003e8)
May 28 14:20:14 zhenhui openbts: INFO 140362755741440 GSML2LAPDm.cpp:972:sendIFrame: obj: 0x22f4570 M=0 VS=0 payload=00000101000000100000000011110001000100000000001111101000
May 28 14:20:14 zhenhui openbts: INFO 140362755741440 GSMLogicalChannel.cpp:92:send: L3 SAP0 sending RR Channel Release cause=0x0
May 28 14:20:14 zhenhui openbts: INFO 140362755741440 GSML2LAPDm.cpp:924:sendMultiframeData: obj: 0x22f4570 state=LinkEstablished payload=primitive=DATA raw=(060d00)
May 28 14:20:14 zhenhui openbts: INFO 140362754942720 GSML3Message.cpp:164:parseL3: L3 recv RR Measurement Report BA_USED=0 DTX_USED=0 MEAS_VALID=0 RXLEV_FULL_SERVING_CELL=63 RXLEV_SUB_SERVING_CELL=63 RXQUAL_FULL_SERVING_CELL=0 RXQUAL_SUB_SERVING_CELL=0 NO_NCELL=0
May 28 14:20:14 zhenhui openbts: INFO 140362754676480 GSML2LAPDm.cpp:710:receiveSFrameRR: obj: 0x22f4570 state=LinkEstablished primitive=DATA raw=(032101ad01c8b370fc36a032dfc1c1aeaa9d71324a8f95)
May 28 14:20:14 zhenhui openbts: INFO 140362755741440 GSML2LAPDm.cpp:972:sendIFrame: obj: 0x22f4570 M=0 VS=1 payload=000001100000110100000000
May 28 14:20:14 zhenhui openbts: INFO 140362754144000 SubscriberRegistry.cpp:269:sqlUpdate: update sip_buddies set port = "5062" where name = "IMSI272017112515231"
May 28 14:20:14 zhenhui openbts: INFO 140362754144000 SubscriberRegistry.cpp:218:sqlLocal: update sip_buddies set port = "5062" where name = "IMSI272017112515231"
May 28 14:20:14 zhenhui openbts: INFO 140362754942720 GSML3Message.cpp:164:parseL3: L3 recv RR Measurement Report BA_USED=0 DTX_USED=0 MEAS_VALID=0 RXLEV_FULL_SERVING_CELL=63 RXLEV_SUB_SERVING_CELL=63 RXQUAL_FULL_SERVING_CELL=0 RXQUAL_SUB_SERVING_CELL=0 NO_NCELL=0
May 28 14:20:14 zhenhui openbts: INFO 140362754676480 GSML2LAPDm.cpp:596:receiveUFrameDISC: obj: 0x22f4570 state=LinkEstablished primitive=DATA raw=(0153016a9ccc839b5d4115c29e409d5c2d2d46f387ac49)
May 28 14:20:14 zhenhui openbts: INFO 140362754676480 GSML2LAPDm.cpp:850:sendUFrameUA: obj: 0x22f4570 F=1 state=LinkEstablished
May 28 14:20:14 zhenhui transceiver: INFO 140655103735552 Transceiver.cpp:767:writeClockInterface: ClockInterface: sending IND CLOCK 2403574
May 28 14:20:14 zhenhui openbts: INFO 140362754144000 SIPInterface.cpp:207:write: write REGISTER sip:127.0.0.1 SIP/2.0#015
May 28 14:20:14 zhenhui openbts: INFO 140362753877760 GSML2LAPDm.cpp:685:receiveUFrameUI: obj: 0x22fc650 state=LinkReleased primitive=DATA raw=(01034906153f3f00000000000000000000000000002b2b)
May 28 14:20:14 zhenhui openbts: INFO 140362753345280 GSML3Message.cpp:164:parseL3: L3 recv RR Measurement Report BA_USED=0 DTX_USED=0 MEAS_VALID=1
May 28 14:20:14 zhenhui openbts: INFO 140362790426368 GSML1FEC.cpp:709:processBurst: obj: 0x22faf40 SACCHL1Decoder  RSSI=-19 timingError=0.457031
May 28 14:20:14 zhenhui openbts: INFO 140362790426368 GSML1FEC.cpp:709:processBurst: obj: 0x22faf40 SACCHL1Decoder  RSSI=-20 timingError=0.460938
May 28 14:20:14 zhenhui openbts: INFO 140362790426368 GSML1FEC.cpp:709:processBurst: obj: 0x22faf40 SACCHL1Decoder  RSSI=-22 timingError=0.457031
May 28 14:20:14 zhenhui openbts: INFO 140362790426368 GSML1FEC.cpp:709:processBurst: obj: 0x22faf40 SACCHL1Decoder  RSSI=-22 timingError=0.464844
May 28 14:20:14 zhenhui openbts: INFO 140362790426368 GSML1FEC.cpp:726:handleGoodFrame: obj: 0x22faf40 SACCHL1Decoder actuals pow=27 TA=1
May 28 14:20:14 zhenhui openbts: INFO 140362753877760 GSML2LAPDm.cpp:685:receiveUFrameUI: obj: 0x22fc650 state=LinkReleased primitive=DATA raw=(01034906153f3f00000000000000000000000000002b2b)
May 28 14:20:14 zhenhui openbts: INFO 140362754942720 GSML2LAPDm.cpp:906:sendUFrameUI: obj: 0x22f6fb0 state=LinkReleased payload=primitive=UNIT_DATA raw=(061d8e13a800000000000000000000000000)
May 28 14:20:14 zhenhui openbts: INFO 140362754942720 GSML1FEC.cpp:1565:sendFrame: obj: 0x22f4d50 SACCHL1Encoder primitive=DATA raw=(031349061d8e13a8000000000000000000000000002b2b)
May 28 14:20:14 zhenhui openbts: INFO 140362754942720 GSML1FEC.cpp:1605:sendFrame: obj: 0x22f4d50 SACCHL1Encoder orders pow=5 TA=1.34473
May 28 14:20:14 zhenhui openbts: INFO 140362754942720 GSML2LAPDm.cpp:906:sendUFrameUI: obj: 0x22f6fb0 state=LinkReleased payload=primitive=UNIT_DATA raw=(061e000a00f11003e82f01)
May 28 14:20:14 zhenhui openbts: INFO 140362754942720 GSML1FEC.cpp:1565:sendFrame: obj: 0x22f4d50 SACCHL1Encoder primitive=DATA raw=(03132d061e000a00f11003e82f012b2b2b2b2b2b2b2b2b)
May 28 14:20:14 zhenhui openbts: INFO 140362754942720 GSML1FEC.cpp:1605:sendFrame: obj: 0x22f4d50 SACCHL1Encoder orders pow=5 TA=1.3418
May 28 14:20:15 zhenhui openbts: INFO 140362753345280 GSML3Message.cpp:164:parseL3: L3 recv RR Measurement Report BA_USED=0 DTX_USED=0 MEAS_VALID=0 RXLEV_FULL_SERVING_CELL=63 RXLEV_SUB_SERVING_CELL=63 RXQUAL_FULL_SERVING_CELL=0 RXQUAL_SUB_SERVING_CELL=0 NO_NCELL=0
May 28 14:20:15 zhenhui openbts: INFO 140362757072640 SIPInterface.cpp:262:drive: read SIP/2.0 200 OK#015
May 28 14:20:15 zhenhui openbts: INFO 140362757072640 SubscriberRegistry.cpp:269:sqlUpdate: update sip_buddies set ipaddr = "127.0.0.1" where name = "IMSI272017112515231"
May 28 14:20:15 zhenhui openbts: INFO 140362757072640 SubscriberRegistry.cpp:218:sqlLocal: update sip_buddies set ipaddr = "127.0.0.1" where name = "IMSI272017112515231"
May 28 14:20:15 zhenhui openbts: INFO 140362754942720 GSML2LAPDm.cpp:906:sendUFrameUI: obj: 0x22f6fb0 state=LinkReleased payload=primitive=UNIT_DATA raw=(061d8e13a800000000000000000000000000)
May 28 14:20:15 zhenhui openbts: INFO 140362754942720 GSML1FEC.cpp:1565:sendFrame: obj: 0x22f4d50 SACCHL1Encoder primitive=DATA raw=(031349061d8e13a8000000000000000000000000002b2b)
May 28 14:20:15 zhenhui openbts: INFO 140362754942720 GSML1FEC.cpp:1605:sendFrame: obj: 0x22f4d50 SACCHL1Encoder orders pow=5 TA=1.34033
May 28 14:20:15 zhenhui openbts: NOTICE 140362754144000 SIPEngine.cpp:364:Register: SIP REGISTER packet to 127.0.0.1:5064 timeout; resending
May 28 14:20:15 zhenhui openbts: INFO 140362754144000 SubscriberRegistry.cpp:269:sqlUpdate: update sip_buddies set ipaddr = "127.0.0.1" where name = "IMSI272017112515231"
May 28 14:20:15 zhenhui openbts: INFO 140362754144000 SubscriberRegistry.cpp:218:sqlLocal: update sip_buddies set ipaddr = "127.0.0.1" where name = "IMSI272017112515231"
May 28 14:20:15 zhenhui openbts: INFO 140362753345280 GSML3Message.cpp:164:parseL3: L3 recv RR Measurement Report BA_USED=0 DTX_USED=0 MEAS_VALID=0 RXLEV_FULL_SERVING_CELL=63 RXLEV_SUB_SERVING_CELL=63 RXQUAL_FULL_SERVING_CELL=0 RXQUAL_SUB_SERVING_CELL=0 NO_NCELL=0
May 28 14:20:15 zhenhui openbts: INFO 140362790426368 GSML1FEC.cpp:709:processBurst: obj: 0x22faf40 SACCHL1Decoder  RSSI=-42 timingError=0.460938
May 28 14:20:15 zhenhui openbts: INFO 140362790426368 GSML1FEC.cpp:709:processBurst: obj: 0x22faf40 SACCHL1Decoder  RSSI=-42 timingError=0.445312
May 28 14:20:15 zhenhui openbts: INFO 140362790426368 GSML1FEC.cpp:709:processBurst: obj: 0x22faf40 SACCHL1Decoder  RSSI=-44 timingError=0.453125
May 28 14:20:15 zhenhui openbts: INFO 140362790426368 GSML1FEC.cpp:709:processBurst: obj: 0x22faf40 SACCHL1Decoder  RSSI=-43 timingError=0.453125
May 28 14:20:15 zhenhui openbts: INFO 140362790426368 GSML1FEC.cpp:726:handleGoodFrame: obj: 0x22faf40 SACCHL1Decoder actuals pow=11 TA=1
May 28 14:20:15 zhenhui openbts: INFO 140362753877760 GSML2LAPDm.cpp:685:receiveUFrameUI: obj: 0x22fc650 state=LinkReleased primitive=DATA raw=(01034906153f3f00000000000000000000000000002b2b)
May 28 14:20:15 zhenhui openbts: INFO 140362757072640 SubscriberRegistry.cpp:269:sqlUpdate: update sip_buddies set port = "5062" where name = "IMSI272017112515231"
May 28 14:20:15 zhenhui openbts: INFO 140362757072640 SubscriberRegistry.cpp:218:sqlLocal: update sip_buddies set port = "5062" where name = "IMSI272017112515231"
May 28 14:20:15 zhenhui openbts: INFO 140362753345280 GSML3Message.cpp:164:parseL3: L3 recv RR Measurement Report BA_USED=0 DTX_USED=0 MEAS_VALID=0 RXLEV_FULL_SERVING_CELL=63 RXLEV_SUB_SERVING_CELL=63 RXQUAL_FULL_SERVING_CELL=0 RXQUAL_SUB_SERVING_CELL=0 NO_NCELL=0
May 28 14:20:15 zhenhui openbts: INFO 140362754942720 GSML2LAPDm.cpp:906:sendUFrameUI: obj: 0x22f6fb0 state=LinkReleased payload=primitive=UNIT_DATA raw=(061e000a00f11003e82f01)
May 28 14:20:15 zhenhui openbts: INFO 140362754942720 GSML1FEC.cpp:1565:sendFrame: obj: 0x22f4d50 SACCHL1Encoder primitive=DATA raw=(03132d061e000a00f11003e82f012b2b2b2b2b2b2b2b2b)
May 28 14:20:15 zhenhui openbts: INFO 140362754942720 GSML1FEC.cpp:1605:sendFrame: obj: 0x22f4d50 SACCHL1Encoder orders pow=5 TA=1.3396
May 28 14:20:15 zhenhui openbts: INFO 140362753345280 GSML2LAPDm.cpp:906:sendUFrameUI: obj: 0x22fc650 state=LinkReleased payload=primitive=UNIT_DATA raw=(061d8e13a800000000000000000000000000)
May 28 14:20:15 zhenhui openbts: INFO 140362753345280 GSML1FEC.cpp:1565:sendFrame: obj: 0x22fa350 SACCHL1Encoder primitive=DATA raw=(031349061d8e13a8000000000000000000000000002b2b)
May 28 14:20:15 zhenhui openbts: INFO 140362753345280 GSML1FEC.cpp:1605:sendFrame: obj: 0x22fa350 SACCHL1Encoder orders pow=5 TA=1.4541
May 28 14:20:15 zhenhui openbts: INFO 140362753345280 GSML2LAPDm.cpp:906:sendUFrameUI: obj: 0x22fc650 state=LinkReleased payload=primitive=UNIT_DATA raw=(061e000a00f11003e82f01)
May 28 14:20:15 zhenhui openbts: INFO 140362753345280 GSML1FEC.cpp:1565:sendFrame: obj: 0x22fa350 SACCHL1Encoder primitive=DATA raw=(03132d061e000a00f11003e82f012b2b2b2b2b2b2b2b2b)
May 28 14:20:15 zhenhui openbts: INFO 140362753345280 GSML1FEC.cpp:1605:sendFrame: obj: 0x22fa350 SACCHL1Encoder orders pow=5 TA=1.45361
May 28 14:20:15 zhenhui openbts: INFO 140362753345280 GSML2LAPDm.cpp:906:sendUFrameUI: obj: 0x22fc650 state=LinkReleased payload=primitive=UNIT_DATA raw=(061d8e13a800000000000000000000000000)
May 28 14:20:15 zhenhui openbts: INFO 140362753345280 GSML1FEC.cpp:1565:sendFrame: obj: 0x22fa350 SACCHL1Encoder primitive=DATA raw=(031349061d8e13a8000000000000000000000000002b2b)
May 28 14:20:15 zhenhui openbts: INFO 140362753345280 GSML1FEC.cpp:1605:sendFrame: obj: 0x22fa350 SACCHL1Encoder orders pow=5 TA=1.45337
May 28 14:20:15 zhenhui transceiver: INFO 140655103735552 Transceiver.cpp:767:writeClockInterface: ClockInterface: sending IND CLOCK 2403792
May 28 14:20:15 zhenhui openbts: INFO 140362790426368 GSML1FEC.cpp:709:processBurst: obj: 0x22faf40 SACCHL1Decoder  RSSI=-42 timingError=0.445312
May 28 14:20:15 zhenhui openbts: INFO 140362790426368 GSML1FEC.cpp:709:processBurst: obj: 0x22faf40 SACCHL1Decoder  RSSI=-42 timingError=0.445312
May 28 14:20:15 zhenhui openbts: INFO 140362790426368 GSML1FEC.cpp:709:processBurst: obj: 0x22faf40 SACCHL1Decoder  RSSI=-42 timingError=0.453125
May 28 14:20:15 zhenhui openbts: INFO 140362790426368 GSML1FEC.cpp:709:processBurst: obj: 0x22faf40 SACCHL1Decoder  RSSI=-42 timingError=0.445312
May 28 14:20:15 zhenhui openbts: INFO 140362790426368 GSML1FEC.cpp:726:handleGoodFrame: obj: 0x22faf40 SACCHL1Decoder actuals pow=5 TA=1
May 28 14:20:15 zhenhui openbts: INFO 140362754144000 SubscriberRegistry.cpp:269:sqlUpdate: update sip_buddies set port = "5062" where name = "IMSI272017112515231"
May 28 14:20:15 zhenhui openbts: INFO 140362754144000 SubscriberRegistry.cpp:218:sqlLocal: update sip_buddies set port = "5062" where name = "IMSI272017112515231"
May 28 14:20:15 zhenhui openbts: INFO 140362754144000 SIPInterface.cpp:207:write: write REGISTER sip:127.0.0.1 SIP/2.0#015
May 28 14:20:15 zhenhui openbts: INFO 140362754144000 SIPEngine.cpp:371:Register: received status 200 OK
May 28 14:20:15 zhenhui openbts: INFO 140362754144000 SIPEngine.cpp:374:Register: REGISTER success
May 28 14:20:15 zhenhui openbts: INFO 140362754144000 SIPInterface.cpp:146:removeCall: removing SIP message FIFO callID 400776675
May 28 14:20:15 zhenhui openbts: INFO 140362754144000 MobilityManagement.cpp:281:LocationUpdatingController: registration SUCCESS: IMSI=272017112515231
May 28 14:20:15 zhenhui openbts: INFO 140362754144000 GSMLogicalChannel.cpp:92:send: L3 SAP0 sending MM Location Updating Accept LAI=(MCC=001 MNC=01 LAC=0x3e8)
May 28 14:20:15 zhenhui openbts: INFO 140362754144000 GSML2LAPDm.cpp:924:sendMultiframeData: obj: 0x22f99f0 state=LinkEstablished payload=primitive=DATA raw=(050200f11003e8)
May 28 14:20:15 zhenhui openbts: INFO 140362754144000 GSML2LAPDm.cpp:972:sendIFrame: obj: 0x22f99f0 M=0 VS=0 payload=00000101000000100000000011110001000100000000001111101000
May 28 14:20:15 zhenhui openbts: INFO 140362754144000 GSMLogicalChannel.cpp:92:send: L3 SAP0 sending RR Channel Release cause=0x0
May 28 14:20:15 zhenhui openbts: INFO 140362754144000 GSML2LAPDm.cpp:924:sendMultiframeData: obj: 0x22f99f0 state=LinkEstablished payload=primitive=DATA raw=(060d00)
May 28 14:20:16 zhenhui openbts: INFO 140362754942720 GSML2LAPDm.cpp:906:sendUFrameUI: obj: 0x22f6fb0 state=LinkReleased payload=primitive=UNIT_DATA raw=(061d8e13a800000000000000000000000000)
May 28 14:20:16 zhenhui openbts: INFO 140362754942720 GSML1FEC.cpp:1565:sendFrame: obj: 0x22f4d50 SACCHL1Encoder primitive=DATA raw=(031349061d8e13a8000000000000000000000000002b2b)
May 28 14:20:16 zhenhui openbts: INFO 140362754942720 GSML1FEC.cpp:1605:sendFrame: obj: 0x22f4d50 SACCHL1Encoder orders pow=5 TA=1.33923
May 28 14:20:16 zhenhui openbts: INFO 140362753345280 GSML2LAPDm.cpp:906:sendUFrameUI: obj: 0x22fc650 state=LinkReleased payload=primitive=UNIT_DATA raw=(061e000a00f11003e82f01)
May 28 14:20:16 zhenhui openbts: INFO 140362753877760 GSML2LAPDm.cpp:685:receiveUFrameUI: obj: 0x22fc650 state=LinkReleased primitive=DATA raw=(01034906153f3f00000000000000000000000000002b2b)
May 28 14:20:16 zhenhui openbts: INFO 140362757072640 SIPInterface.cpp:262:drive: read SIP/2.0 200 OK#015
May 28 14:20:16 zhenhui openbts: INFO 140362757072640 SubscriberRegistry.cpp:269:sqlUpdate: update sip_buddies set ipaddr = "127.0.0.1" where name = "IMSI272017112515231"
May 28 14:20:16 zhenhui openbts: INFO 140362757072640 SubscriberRegistry.cpp:218:sqlLocal: update sip_buddies set ipaddr = "127.0.0.1" where name = "IMSI272017112515231"
May 28 14:20:16 zhenhui openbts: INFO 140362753345280 GSML1FEC.cpp:1565:sendFrame: obj: 0x22fa350 SACCHL1Encoder primitive=DATA raw=(03132d061e000a00f11003e82f012b2b2b2b2b2b2b2b2b)
May 28 14:20:16 zhenhui openbts: INFO 140362753345280 GSML1FEC.cpp:1605:sendFrame: obj: 0x22fa350 SACCHL1Encoder orders pow=5 TA=1.45032
May 28 14:20:16 zhenhui rsyslogd-2177: imuxsock begins to drop messages from pid 9245 due to rate-limiting
May 28 14:20:16 zhenhui transceiver: INFO 140655103735552 Transceiver.cpp:767:writeClockInterface: ClockInterface: sending IND CLOCK 2404008
May 28 14:20:17 zhenhui rsyslogd-2177: imuxsock lost 22 messages from pid 9245 due to rate-limiting
May 28 14:20:17 zhenhui openbts: INFO 140362754942720 GSML2LAPDm.cpp:906:sendUFrameUI: obj: 0x22f6fb0 state=LinkReleased payload=primitive=UNIT_DATA raw=(061d8e13a800000000000000000000000000)
May 28 14:20:17 zhenhui openbts: INFO 140362754942720 GSML1FEC.cpp:1565:sendFrame: obj: 0x22f4d50 SACCHL1Encoder primitive=DATA raw=(031349061d8e13a8000000000000000000000000002b2b)
May 28 14:20:17 zhenhui openbts: INFO 140362754942720 GSML1FEC.cpp:1605:sendFrame: obj: 0x22f4d50 SACCHL1Encoder orders pow=5 TA=1.33896
May 28 14:20:17 zhenhui openbts: INFO 140362753345280 GSML2LAPDm.cpp:906:sendUFrameUI: obj: 0x22fc650 state=LinkReleased payload=primitive=UNIT_DATA raw=(061e000a00f11003e82f01)
May 28 14:20:17 zhenhui openbts: INFO 140362753345280 GSML1FEC.cpp:1565:sendFrame: obj: 0x22fa350 SACCHL1Encoder primitive=DATA raw=(03132d061e000a00f11003e82f012b2b2b2b2b2b2b2b2b)
May 28 14:20:17 zhenhui openbts: INFO 140362753345280 GSML1FEC.cpp:1605:sendFrame: obj: 0x22fa350 SACCHL1Encoder orders pow=5 TA=1.43777
May 28 14:20:17 zhenhui openbts: INFO 140362754942720 GSML2LAPDm.cpp:906:sendUFrameUI: obj: 0x22f6fb0 state=LinkReleased payload=primitive=UNIT_DATA raw=(061e000a00f11003e82f01)
May 28 14:20:17 zhenhui openbts: INFO 140362754942720 GSML1FEC.cpp:1565:sendFrame: obj: 0x22f4d50 SACCHL1Encoder primitive=DATA raw=(03132d061e000a00f11003e82f012b2b2b2b2b2b2b2b2b)
May 28 14:20:17 zhenhui openbts: INFO 140362754942720 GSML1FEC.cpp:1605:sendFrame: obj: 0x22f4d50 SACCHL1Encoder orders pow=5 TA=1.33891
May 28 14:20:17 zhenhui openbts: INFO 140362753345280 GSML2LAPDm.cpp:906:sendUFrameUI: obj: 0x22fc650 state=LinkReleased payload=primitive=UNIT_DATA raw=(061d8e13a800000000000000000000000000)
May 28 14:20:17 zhenhui openbts: INFO 140362753345280 GSML1FEC.cpp:1565:sendFrame: obj: 0x22fa350 SACCHL1Encoder primitive=DATA raw=(031349061d8e13a8000000000000000000000000002b2b)
May 28 14:20:17 zhenhui openbts: INFO 140362753345280 GSML1FEC.cpp:1605:sendFrame: obj: 0x22fa350 SACCHL1Encoder orders pow=5 TA=1.43568
May 28 14:20:17 zhenhui transceiver: INFO 140655103735552 Transceiver.cpp:767:writeClockInterface: ClockInterface: sending IND CLOCK 2404225
May 28 14:20:18 zhenhui openbts: INFO 140362754942720 GSML2LAPDm.cpp:906:sendUFrameUI: obj: 0x22f6fb0 state=LinkReleased payload=primitive=UNIT_DATA raw=(061d8e13a800000000000000000000000000)
May 28 14:20:18 zhenhui openbts: INFO 140362754942720 GSML1FEC.cpp:1565:sendFrame: obj: 0x22f4d50 SACCHL1Encoder primitive=DATA raw=(031349061d8e13a8000000000000000000000000002b2b)
May 28 14:20:18 zhenhui openbts: INFO 140362754942720 GSML1FEC.cpp:1605:sendFrame: obj: 0x22f4d50 SACCHL1Encoder orders pow=5 TA=1.33889
May 28 14:20:18 zhenhui openbts: INFO 140362753345280 GSML2LAPDm.cpp:906:sendUFrameUI: obj: 0x22fc650 state=LinkReleased payload=primitive=UNIT_DATA raw=(061e000a00f11003e82f01)
May 28 14:20:18 zhenhui openbts: INFO 140362753345280 GSML1FEC.cpp:1565:sendFrame: obj: 0x22fa350 SACCHL1Encoder primitive=DATA raw=(03132d061e000a00f11003e82f012b2b2b2b2b2b2b2b2b)
May 28 14:20:18 zhenhui openbts: INFO 140362753345280 GSML1FEC.cpp:1605:sendFrame: obj: 0x22fa350 SACCHL1Encoder orders pow=5 TA=1.43464
May 28 14:20:18 zhenhui openbts: INFO 140362754942720 GSML2LAPDm.cpp:906:sendUFrameUI: obj: 0x22f6fb0 state=LinkReleased payload=primitive=UNIT_DATA raw=(061e000a00f11003e82f01)
May 28 14:20:18 zhenhui openbts: INFO 140362754942720 GSML1FEC.cpp:1565:sendFrame: obj: 0x22f4d50 SACCHL1Encoder primitive=DATA raw=(03132d061e000a00f11003e82f012b2b2b2b2b2b2b2b2b)
May 28 14:20:18 zhenhui openbts: INFO 140362754942720 GSML1FEC.cpp:1605:sendFrame: obj: 0x22f4d50 SACCHL1Encoder orders pow=5 TA=1.33888
May 28 14:20:18 zhenhui openbts: INFO 140362753345280 GSML2LAPDm.cpp:906:sendUFrameUI: obj: 0x22fc650 state=LinkReleased payload=primitive=UNIT_DATA raw=(061d8e13a800000000000000000000000000)
May 28 14:20:18 zhenhui openbts: INFO 140362753345280 GSML1FEC.cpp:1565:sendFrame: obj: 0x22fa350 SACCHL1Encoder primitive=DATA raw=(031349061d8e13a8000000000000000000000000002b2b)
May 28 14:20:18 zhenhui openbts: INFO 140362753345280 GSML1FEC.cpp:1605:sendFrame: obj: 0x22fa350 SACCHL1Encoder orders pow=5 TA=1.43412
May 28 14:20:18 zhenhui transceiver: INFO 140655103735552 Transceiver.cpp:767:writeClockInterface: ClockInterface: sending IND CLOCK 2404442
May 28 14:20:19 zhenhui openbts: INFO 140362754942720 GSML2LAPDm.cpp:906:sendUFrameUI: obj: 0x22f6fb0 state=LinkReleased payload=primitive=UNIT_DATA raw=(061d8e13a800000000000000000000000000)
May 28 14:20:19 zhenhui openbts: INFO 140362754942720 GSML1FEC.cpp:1565:sendFrame: obj: 0x22f4d50 SACCHL1Encoder primitive=DATA raw=(031349061d8e13a8000000000000000000000000002b2b)
May 28 14:20:19 zhenhui openbts: INFO 140362754942720 GSML1FEC.cpp:1605:sendFrame: obj: 0x22f4d50 SACCHL1Encoder orders pow=5 TA=1.33887
May 28 14:20:19 zhenhui openbts: INFO 140362753345280 GSML2LAPDm.cpp:906:sendUFrameUI: obj: 0x22fc650 state=LinkReleased payload=primitive=UNIT_DATA raw=(061e000a00f11003e82f01)
May 28 14:20:19 zhenhui openbts: INFO 140362753345280 GSML1FEC.cpp:1565:sendFrame: obj: 0x22fa350 SACCHL1Encoder primitive=DATA raw=(03132d061e000a00f11003e82f012b2b2b2b2b2b2b2b2b)
May 28 14:20:19 zhenhui openbts: INFO 140362753345280 GSML1FEC.cpp:1605:sendFrame: obj: 0x22fa350 SACCHL1Encoder orders pow=5 TA=1.43386
May 28 14:20:19 zhenhui openbts: INFO 140362754942720 GSML2LAPDm.cpp:906:sendUFrameUI: obj: 0x22f6fb0 state=LinkReleased payload=primitive=UNIT_DATA raw=(061e000a00f11003e82f01)
May 28 14:20:19 zhenhui openbts: INFO 140362754942720 GSML1FEC.cpp:1565:sendFrame: obj: 0x22f4d50 SACCHL1Encoder primitive=DATA raw=(03132d061e000a00f11003e82f012b2b2b2b2b2b2b2b2b)
May 28 14:20:19 zhenhui openbts: INFO 140362754942720 GSML1FEC.cpp:1605:sendFrame: obj: 0x22f4d50 SACCHL1Encoder orders pow=5 TA=1.33887
May 28 14:20:19 zhenhui openbts: INFO 140362753345280 GSML2LAPDm.cpp:906:sendUFrameUI: obj: 0x22fc650 state=LinkReleased payload=primitive=UNIT_DATA raw=(061d8e13a800000000000000000000000000)
May 28 14:20:19 zhenhui openbts: INFO 140362753345280 GSML1FEC.cpp:1565:sendFrame: obj: 0x22fa350 SACCHL1Encoder primitive=DATA raw=(031349061d8e13a8000000000000000000000000002b2b)
May 28 14:20:19 zhenhui openbts: INFO 140362753345280 GSML1FEC.cpp:1605:sendFrame: obj: 0x22fa350 SACCHL1Encoder orders pow=5 TA=1.43372
May 28 14:20:19 zhenhui transceiver: INFO 140655103735552 Transceiver.cpp:767:writeClockInterface: ClockInterface: sending IND CLOCK 2404658
May 28 14:20:19 zhenhui openbts: INFO 140362754942720 GSML2LAPDm.cpp:906:sendUFrameUI: obj: 0x22f6fb0 state=LinkReleased payload=primitive=UNIT_DATA raw=(061d8e13a800000000000000000000000000)
May 28 14:20:19 zhenhui openbts: INFO 140362754942720 GSML1FEC.cpp:1565:sendFrame: obj: 0x22f4d50 SACCHL1Encoder primitive=DATA raw=(031349061d8e13a8000000000000000000000000002b2b)
May 28 14:20:19 zhenhui openbts: INFO 140362754942720 GSML1FEC.cpp:1605:sendFrame: obj: 0x22f4d50 SACCHL1Encoder orders pow=5 TA=1.33887
May 28 14:20:19 zhenhui openbts: INFO 140362753345280 GSML2LAPDm.cpp:906:sendUFrameUI: obj: 0x22fc650 state=LinkReleased payload=primitive=UNIT_DATA raw=(061e000a00f11003e82f01)
May 28 14:20:19 zhenhui openbts: INFO 140362753345280 GSML1FEC.cpp:1565:sendFrame: obj: 0x22fa350 SACCHL1Encoder primitive=DATA raw=(03132d061e000a00f11003e82f012b2b2b2b2b2b2b2b2b)
May 28 14:20:19 zhenhui openbts: INFO 140362753345280 GSML1FEC.cpp:1605:sendFrame: obj: 0x22fa350 SACCHL1Encoder orders pow=5 TA=1.43366
May 28 14:20:20 zhenhui openbts: INFO 140362754942720 GSML2LAPDm.cpp:906:sendUFrameUI: obj: 0x22f6fb0 state=LinkReleased payload=primitive=UNIT_DATA raw=(061e000a00f11003e82f01)
May 28 14:20:20 zhenhui openbts: INFO 140362754942720 GSML1FEC.cpp:1565:sendFrame: obj: 0x22f4d50 SACCHL1Encoder primitive=DATA raw=(03132d061e000a00f11003e82f012b2b2b2b2b2b2b2b2b)
May 28 14:20:20 zhenhui openbts: INFO 140362754942720 GSML1FEC.cpp:1605:sendFrame: obj: 0x22f4d50 SACCHL1Encoder orders pow=5 TA=1.33887
May 28 14:20:20 zhenhui openbts: INFO 140362753345280 GSML2LAPDm.cpp:906:sendUFrameUI: obj: 0x22fc650 state=LinkReleased payload=primitive=UNIT_DATA raw=(061d8e13a800000000000000000000000000)
May 28 14:20:20 zhenhui openbts: INFO 140362753345280 GSML1FEC.cpp:1565:sendFrame: obj: 0x22fa350 SACCHL1Encoder primitive=DATA raw=(031349061d8e13a8000000000000000000000000002b2b)
May 28 14:20:20 zhenhui openbts: INFO 140362753345280 GSML1FEC.cpp:1605:sendFrame: obj: 0x22fa350 SACCHL1Encoder orders pow=5 TA=1.43363
May 28 14:20:20 zhenhui transceiver: INFO 140655103735552 Transceiver.cpp:767:writeClockInterface: ClockInterface: sending IND CLOCK 2404875
May 28 14:20:20 zhenhui openbts: INFO 140362754942720 GSML2LAPDm.cpp:906:sendUFrameUI: obj: 0x22f6fb0 state=LinkReleased payload=primitive=UNIT_DATA raw=(061d8e13a800000000000000000000000000)
May 28 14:20:20 zhenhui openbts: INFO 140362754942720 GSML1FEC.cpp:1565:sendFrame: obj: 0x22f4d50 SACCHL1Encoder primitive=DATA raw=(031349061d8e13a8000000000000000000000000002b2b)
May 28 14:20:20 zhenhui openbts: INFO 140362754942720 GSML1FEC.cpp:1605:sendFrame: obj: 0x22f4d50 SACCHL1Encoder orders pow=5 TA=1.33887

really lost now…

Basically, either OpenBTS isn’t actually configured on the port you claim it to be on, or something is stopping packets reaching that port, or returning from it.

I would suggest that you get it working with one machine for each SIP user agent, then worry about trying to make them all run on the same machine.

I don’t know enough about OpenBTS to analyze its logs in a reasonable amount of time.

I’d also look for OpenBTS support forums, as very few Asterisk users will be using it, given that radio licenses will be difficult to obtain.

[quote=“david55”]Basically, either OpenBTS isn’t actually configured on the port you claim it to be on, or something is stopping packets reaching that port, or returning from it.

I would suggest that you get it working with one machine for each SIP user agent, then worry about trying to make them all run on the same machine.

I don’t know enough about OpenBTS to analyze its logs in a reasonable amount of time.

I’d also look for OpenBTS support forums, as very few Asterisk users will be using it, given that radio licenses will be difficult to obtain.[/quote]
Dear David,
Thanks! I’m learning again the Asterisk book and found something confusing.

  1. In my case, asterisk updates the table ‘sip_buddies’ of sqlite3 databse in real time. there are two fields: ‘host’ and ‘ipaddr’, what are the differences? I guess host is the ‘phone’ and ‘ipaddr’ refers to asterisk server?
  2. my test-bed topology is: an USRP device (provide GSM signal) is connected to a PC in which asterisk is running. I connect two iphone to USRP. IP of PC Ethernet: 192.168.10.1, IP of USRP device: 192.168.10.2. Next, I need set the IP for the two iphone in sip.conf like this: ‘host=dynamic’ as i don’t know the IP of the two phones. is this correct?
    thanks a lot!

I don’t use “realtime”.