From: Kurtis H. <khe...@cs...> - 2012-01-25 00:24:56
|
Hey Arghyadip, classed just started here at Cal so I dropped the ball on following up here. Have you made any progress? On Tue, Jan 17, 2012 at 4:14 PM, Arghyadip Paul <arg...@gm...> wrote: > Hi Kurtis, > > Some progress. > > You were right. I did an SVN update for the Smqueue and Sipauthserve. > OpenBTS codes were already updated. Currently still there are some bad > behaviors in the SMS. > > > TC 1: > -------- > > - Two UE registered to same OpenBTS server(192.168.2.131) and remote > Asterisk, smqueue and sipauthserve(192.168.2.131). > - One UE sends sms to another. > - SIP MESSAGE goes out from OpenBTS to Smqueue and Smqueue replies back with > 202 queued. > - Smqueue forwards the SIP MESSAGE to OpenBTS again as intended recipient is > registered there. > - SMS comes to Intended UEs inbox. > - But no response to forwared SIP MESSAGE from Smqueue goes out from > OpenBTS. > - SIM MESSAGE retransmission is happening again and again. > - Same sms is getting received by the recipient UE multiple number of times. > - Role of the UEs changed. Now from UE2 , SMS is sent to UE1. Same behavior > observed. > > > OpenBTS Machine Logs: > ------------------------------------ > Jan 17 16:00:50 moment-portable openbts: INFO 3063122800 > SMSControl.cpp:456:MTSMSController: transaction: 1804289402 C0T0 SDCCH/4-2 > IMSI=404864430002302 L3TI=0 SIP-call-id=21pOn/ SIP-proxy=127.0.0.1:5063 > MTSMS from=9733013520 GSMState=SMS-delivery SIPState=Proceeding (0 sec) > message="01a303a100000019000aa1793310530200002110715195732b07cb303c4d0fbb01" > Jan 17 16:00:51 moment-portable openbts: INFO 3063122800 > SMSControl.cpp:369:deliverSMSToMS: sending CP-DATA TI=0 > RPDU=(01a303a100000019000aa1793310530200002110715195732b07cb303c4d0fbb01) > Jan 17 16:00:51 moment-portable openbts: INFO 3045149552 > RadioResource.cpp:228:AccessGrantResponder: sending PageMode=(0) > DedicatedModeOrTBF=(TMA=0 Downlink=0 DMOrTBF=0) > ChannelDescription=(typeAndOffset=SDCCH/4-3 TN=0 TSC=3 ARFCN=51) > RequestReference=(RA=21 T1'=29 T2=4 T3=33) TimingAdvance=0 > Jan 17 16:00:52 moment-portable openbts: INFO 3063122800 > SMSControl.cpp:423:deliverSMSToMS: MTSMS: sending CPAck > Jan 17 16:00:52 moment-portable openbts: INFO 3063122800 > SMSControl.cpp:464:MTSMSController: closing the Um channel > Jan 17 16:00:53 moment-portable openbts: INFO 3070172016 > RadioResource.cpp:378:addID: IMSI=404864430002302 added to table > Jan 17 16:00:53 moment-portable openbts: INFO 3045415792 > RadioResource.cpp:422:pageAll: paging 1 mobile(s) > Jan 17 16:00:53 moment-portable openbts: INFO 3070172016 > RadioResource.cpp:378:addID: IMSI=404864415448667 added to table > Jan 17 16:00:53 moment-portable openbts: INFO 3045415792 > RadioResource.cpp:422:pageAll: paging 2 mobile(s) > Jan 17 16:00:53 moment-portable openbts: INFO 3045149552 > RadioResource.cpp:148:AccessGrantResponder: RA=0x17 when=0:2627757 age=5 > delay=0.0546875 RSSI=-21 > Jan 17 16:00:54 moment-portable openbts: INFO 3045415792 > RadioResource.cpp:422:pageAll: paging 2 mobile(s) > Jan 17 16:00:55 moment-portable openbts: last message repeated 2 times > Jan 17 16:00:55 moment-portable openbts: INFO 3045149552 > RadioResource.cpp:228:AccessGrantResponder: sending PageMode=(0) > DedicatedModeOrTBF=(TMA=0 Downlink=0 DMOrTBF=0) > ChannelDescription=(typeAndOffset=SDCCH/4-1 TN=0 TSC=3 ARFCN=51) > RequestReference=(RA=23 T1'=29 T2=15 T3=33) TimingAdvance=0 > Jan 17 16:00:55 moment-portable openbts: INFO 3045149552 > RadioResource.cpp:148:AccessGrantResponder: RA=0x14 when=0:2627951 age=129 > delay=0.664062 RSSI=-4 > Jan 17 16:00:55 moment-portable openbts: INFO 3064720240 > RadioResource.cpp:259:PagingResponseHandler: RR Paging Response > mobileID=(IMSI=404864430002302) classmark=(revision=1 ES-IND=1 A5/1=0 A5/2=0 > A5/3=0 powerCap=3 PS=1 SSScrenInd=1 SM=1 VBS=0 VGCS=0 FC=1 CM3=1 LCSVA=1 > SoLSA=0 CMSF=1) > Jan 17 16:00:55 moment-portable openbts: INFO 3064720240 > RadioResource.cpp:386:removeID: IMSI=404864430002302 > Jan 17 16:00:55 moment-portable openbts: INFO 3064720240 > SMSControl.cpp:456:MTSMSController: transaction: 1804289405 C0T0 SDCCH/4-1 > IMSI=404864430002302 L3TI=1 SIP-call-id=4Y2Sd/ SIP-proxy=127.0.0.1:5063 > MTSMS from=9733013520 GSMState=SMS-delivery SIPState=Proceeding (0 sec) > message="01a303a100000019000aa1793310530200002110715195732b07cb303c4d0fbb01" > Jan 17 16:00:55 moment-portable openbts: INFO 3045415792 > RadioResource.cpp:422:pageAll: paging 1 mobile(s) > Jan 17 16:00:55 moment-portable openbts: INFO 3064720240 > SMSControl.cpp:369:deliverSMSToMS: sending CP-DATA TI=1 > RPDU=(01a303a100000019000aa1793310530200002110715195732b07cb303c4d0fbb01) > Jan 17 16:00:56 moment-portable openbts: INFO 3045415792 > RadioResource.cpp:422:pageAll: paging 1 mobile(s) > Jan 17 16:00:56 moment-portable openbts: INFO 3045415792 > RadioResource.cpp:422:pageAll: paging 1 mobile(s) > Jan 17 16:00:56 moment-portable openbts: INFO 3064720240 > SMSControl.cpp:423:deliverSMSToMS: MTSMS: sending CPAck > Jan 17 16:00:56 moment-portable openbts: INFO 3045415792 > RadioResource.cpp:422:pageAll: paging 1 mobile(s) > Jan 17 16:00:56 moment-portable openbts: INFO 3064720240 > SMSControl.cpp:464:MTSMSController: closing the Um channel > Jan 17 16:00:57 moment-portable openbts: INFO 3045415792 > RadioResource.cpp:422:pageAll: paging 1 mobile(s) > > Smqueue Machine Logs: > ------------------------------------ > > Jan 17 16:00:57 arz smqueue: DEBUG 139971089184544 > smqueue.cpp:1774:lookup_uri_hostport: We have an IMSI: IMSI404864430002302 > Jan 17 16:00:57 arz smqueue: INFO 139971089184544 > HLR.cpp:318:getRegistrationIP: getRegistrationIP(IMSI404864430002302) > Jan 17 16:00:57 arz smqueue: INFO 139971089184544 HLR.cpp:202:sqlLocal: > select ipaddr from sip_buddies where name = "IMSI404864430002302" > Jan 17 16:00:57 arz smqueue: INFO 139971089184544 HLR.cpp:242:sqlQuery: > result = 192.168.2.131 > Jan 17 16:00:57 arz smqueue: DEBUG 139971089184544 > smqueue.cpp:1779:lookup_uri_hostport: We are going to try to send to > 192.168.2.131 on > Jan 17 16:00:57 arz smqueue: DEBUG 139971089184544 > smqueue.cpp:1799:lookup_uri_hostport: We will send to 192.168.2.131 on 5062 > Jan 17 16:00:57 arz smqueue: DEBUG 139971089184544 smqueue.cpp:885:set_qtag: > Param tag=xqsbytbvoevrzyzd > Jan 17 16:00:57 arz smqueue: INFO 139971089184544 > smqueue.cpp:372:process_timeout: Delivering '90--xqsbytbvoevrzyzd' from > 9733013520 at 192.168.2.131:5062. > Jan 17 16:00:57 arz smqueue: DEBUG 139971089184544 > smnet.cpp:104:deliver_msg_datagram: #012--Deliver message: > Jan 17 16:00:57 arz smqueue: DEBUG 139971089184544 > smnet.cpp:105:deliver_msg_datagram: MESSAGE > sip:IMSI404864430002302@192.168.2.131:5062 SIP/2.0#015#012Via: SIP/2.0/UDP > 127.0.0.1:5063;branch=123#015#012Via: SIP/2.0/UDP > 192.168.2.131:5062;branch=z9hG4bKobts281a54fd7e1a78aa0a#015#012From: > IMSI404864415448667 > <sip:9733013520@192.168.2.130>;tag=xqsbytbvoevrzyzd#015#012To: 2103 > <sip:2103@192.168.2.130>#015#012Call-ID: 4QWcA/@127.0.0.1#015#012CSeq: 90 > MESSAGE#015#012Content-Type: application/vnd.3gpp.sms#015#012Max-forwards: > 5#015#012Content-Length: > 66#015#012#015#01201a303a100000019000aa1793310530200002110715195732b07cb303c4d0fbb01 > Jan 17 16:00:57 arz smqueue: INFO 139971089184544 > smqueue.cpp:2000:main_loop: === Jan 17 16:00:57 2 queued; 5 seconds til > Request Destination SIP URL for 90--xqsbytbvoevrzyzd > Jan 17 16:01:02 arz smqueue: DEBUG 139971089184544 > smqueue.cpp:2015:main_loop: Timeout... > > > On Sun, Jan 15, 2012 at 11:45 AM, Kurtis Heimerl <khe...@cs...> > wrote: >> >> When did you pull smqueue? >> >> smqueue.cpp:1924 is well outside of main_loop in the repository. >> >> On Fri, Jan 13, 2012 at 2:18 PM, Arghyadip Paul <arg...@gm...> >> wrote: >> > TC 1: >> > -------- >> > Register two UEs to the Remote Asterisk from the same OpenBTS Server And >> > then Send SMS.(Remote Smqueue, Remote Sipauthserve and Remote Asterisk >> > running in the same machine) >> > >> > Observation: >> > ------------------- >> > Sender's SMS is sent successfully, and OpenBTS receives 202 Queued from >> > Remote Smqueue but after that Smqueue is not able to forward the SMS to >> > receiver. >> > >> > /var/log/OpenBTS.log: >> > ------------------------------- >> > Jan 13 14:01:00 arz smqueue: INFO 140215893899040 >> > HLR.cpp:318:getRegistrationIP: getRegistrationIP(IMSI404864415448667) >> > Jan 13 14:01:00 arz smqueue: INFO 140215893899040 HLR.cpp:202:sqlLocal: >> > select ipaddr from sip_buddies where name = "IMSI404864415448667" >> > Jan 13 14:01:00 arz smqueue: INFO 140215893899040 HLR.cpp:242:sqlQuery: >> > result = 192.168.2.148 >> > Jan 13 14:01:00 arz smqueue: DEBUG 140215893899040 >> > smqueue.cpp:845:set_qtag: >> > Param tag=xhvwtpcsgncnexma >> > Jan 13 14:01:00 arz smqueue: DEBUG 140215893899040 >> > smnet.cpp:104:deliver_msg_datagram: #012--Deliver message: >> > Jan 13 14:01:00 arz smqueue: DEBUG 140215893899040 >> > smnet.cpp:105:deliver_msg_datagram: MESSAGE >> > sip:IMSI404864415448667@127.0.0.1:5062 SIP/2.0#015#012Via: SIP/2.0/UDP >> > 127.0.0.1:5063;branch=123#015#012Via: SIP/2.0/UDP >> > 192.168.2.148:5062;branch=z9hG4bKobts283069cf23352e3ad1#015#012From: >> > IMSI404864430002302 >> > <sip:2103@192.168.2.130>;tag=xhvwtpcsgncnexma#015#012To: >> > 9733013520 <sip:9733013520@192.168.2.130>#015#012Call-ID: >> > ORCvy/@127.0.0.1#015#012CSeq: 184 MESSAGE#015#012Content-Type: >> > application/vnd.3gpp.sms#015#012Max-forwards: 5#015#012Content-Length: >> > >> > 54#015#012#015#01201a303a1000000130004a1123000002110313175502b04c872fe0d >> > Jan 13 14:01:00 arz smqueue: INFO 140215893899040 >> > smqueue.cpp:1909:main_loop: === Jan 13 14:01:00 2 queued; 3 seconds til >> > Request Destination SIP URL for 27--oxosxomhayeygxrc >> > Jan 13 14:01:03 arz smqueue: DEBUG 140215893899040 >> > smqueue.cpp:1924:main_loop: Timeout... >> > >> > >> > This behavior is consistent and results are confirmed. >> > >> > >> > TC 2: >> > -------- >> > Register two UEs to the Remote Asterisk from the same OpenBTS Server. >> > Establish a call session first and then send SMS. >> > >> > Observation: >> > ------------------ >> > Call established successfully. >> > Same behavior for SMS found this time too. >> > >> > >> > So, please consider these set of test trials as finals and observations >> > are >> > consistent. I am relying too much on the SMS feature at this point of >> > time. >> > So, any other thing if you require to investigate the issue, please let >> > me >> > know. >> > >> > Regards, >> > Arghyadip >> > >> > On Fri, Jan 13, 2012 at 2:00 AM, Arghyadip Paul <arg...@gm...> >> > wrote: >> >> >> >> Yes...both the UEs...Sender(IMSIXXXXXXXX) and Receiver UE( >> >> IMSI404864415448667 ) are in the same IP...where OpenBTS is running.... >> >> 192.168.2.148 >> >> >> >> So this finding is right..but after that I am getting no clue why >> >> timeout >> >> is happening..!!! >> >> >> >> >> >> On Fri, Jan 13, 2012 at 12:42 AM, Kurtis Heimerl >> >> <khe...@cs...> wrote: >> >>> >> >>> First off, you're the first one to try out the multi-BTS setup, so >> >>> good on you for doing this. Please document whatever stuff you trip up >> >>> on. >> >>> >> >>> Anyhow, with regards to the log... >> >>> >> >>> Are these two lines: >> >>> > Jan 12 16:51:52 arz smqueue: INFO 140176208975648 >> >>> > HLR.cpp:202:sqlLocal: >> >>> > select ipaddr from sip_buddies where name = "IMSI404864415448667" >> >>> > Jan 12 16:51:52 arz smqueue: INFO 140176208975648 >> >>> > HLR.cpp:242:sqlQuery: >> >>> > result = 192.168.2.148 >> >>> >> >>> Right? As in, is IMSI404864415448667 supposed to be on IP >> >>> 192.168.2.148? >> >>> >> >>> On Thu, Jan 12, 2012 at 7:42 PM, Arghyadip Paul <arg...@gm...> >> >>> wrote: >> >>> > I talked about the asterisk logs.. I completely agree with you about >> >>> > the >> >>> > smqueue logs which was coming earlier as follows: >> >>> > >> >>> > Jan 11 16:35:56 arz smqueue: INFO 140052744574752 >> >>> > smsc.cpp:106:sendSIP_init: >> >>> > from IMSI404864415448667 to 2103 >> >>> > Jan 11 16:35:56 arz smqueue: ERR 140052744574752 >> >>> > smqueue.cpp:1552:lookup_uri_imsi: host not valid >> >>> > Jan 11 16:35:56 arz smqueue: NOTICE 140052744574752 >> >>> > smqueue.cpp:301:process_timeout: == This message had an error and is >> >>> > being >> >>> > deleted:# >> >>> > >> >>> > But now with the SMS test I have mentioned...I can see only the >> >>> > follow >> >>> > logs: >> >>> > >> >>> > Jan 12 16:51:52 arz smqueue: INFO 140176208975648 >> >>> > HLR.cpp:318:getRegistrationIP: >> >>> > getRegistrationIP(IMSI404864415448667) >> >>> > Jan 12 16:51:52 arz smqueue: INFO 140176208975648 >> >>> > HLR.cpp:202:sqlLocal: >> >>> > select ipaddr from sip_buddies where name = "IMSI404864415448667" >> >>> > Jan 12 16:51:52 arz smqueue: INFO 140176208975648 >> >>> > HLR.cpp:242:sqlQuery: >> >>> > result = 192.168.2.148 >> >>> > Jan 12 16:51:52 arz smqueue: DEBUG 140176208975648 >> >>> > smqueue.cpp:845:set_qtag: >> >>> > Param tag=bgaaympfvuzorfqh >> >>> > Jan 12 16:51:52 arz smqueue: DEBUG 140176208975648 >> >>> > smnet.cpp:104:deliver_msg_datagram: #012--Deliver message: >> >>> > Jan 12 16:51:52 arz smqueue: DEBUG 140176208975648 >> >>> > smnet.cpp:105:deliver_msg_datagram: >> >>> > MESSAGE sip:IMSI404864415448667@127.0.0.1:5062 SIP/2.0#015#012Via: >> >>> > SIP/2.0/UDP 127.0.0.1:5063;branch=123#015#012Via: SIP/2.0/UDP >> >>> > 192.168.2.148:5062;branch=z9hG4bKobts287f9f09ab151b2d4c#015#012From: >> >>> > IMSI404864430002302 >> >>> > >> >>> > >> >>> > <sip:2103@192.168.2.130>;tag=bgaaympfvuzorfqh#015#012To:9733013520 <sip:9733013520@192.168.2.130>#015#012Call-ID: aeZ3e@127.0.0.1#015#012CSeq: >> >>> > 220 MESSAGE#015#012Content-Type: >> >>> > application/vnd.3gpp.sms#015#012Max-forwards: >> >>> > 5#015#012Content-Length: >> >>> > >> >>> > >> >>> > 58#015#012#015#01201a303a1000000150004a1123000002110216115222b065076393c2f03 >> >>> > Jan 12 16:51:52 arz smqueue: INFO 140176208975648 >> >>> > smqueue.cpp:1909:main_loop: === Jan 12 16:51:52 1 queued; 5 seconds >> >>> > til >> >>> > Request Destination SIP URL for 220--bgaaympfvuzorfqh >> >>> > Jan 12 16:51:57 arz smqueue: DEBUG 140176208975648 >> >>> > smqueue.cpp:1924:main_loop: Timeout... >> >>> > >> >>> > >> >>> > May be I can run couple more rounds test tomorrow and confirm the >> >>> > behavior >> >>> > for all possible test scenarios for SMS in multi-BTS setting. >> >>> > >> >>> > >> >>> > >> >>> > >> >>> > On Thu, Jan 12, 2012 at 7:32 PM, Kurtis Heimerl >> >>> > <khe...@cs...> >> >>> > wrote: >> >>> >> >> >>> >> No.. those were smqueue logs and they were ending in an error. >> >>> >> >> >>> >> On Thu, Jan 12, 2012 at 7:31 PM, Arghyadip Paul >> >>> >> <arg...@gm...> >> >>> >> wrote: >> >>> >> > Dear Kurtis, >> >>> >> > >> >>> >> > Yes..I did not modify the SIP.myIP and SIP.myIP2...Last time I >> >>> >> > reported >> >>> >> > some >> >>> >> > asterisk logs..which was actually not related to SMS but a voice >> >>> >> > call >> >>> >> > session I tested..Mistakenly I considered them as asterisk logs.. >> >>> >> > also I >> >>> >> > tested SMS after testing a voice call session... >> >>> >> > >> >>> >> > This time..after registering the device...I tested the SMS first >> >>> >> > and >> >>> >> > got >> >>> >> > the >> >>> >> > logs posted. >> >>> >> > >> >>> >> > With the configuration I have mentioned currently the said >> >>> >> > behavior >> >>> >> > is >> >>> >> > coming consistently... >> >>> >> > >> >>> >> > >> >>> >> > On Thu, Jan 12, 2012 at 5:13 PM, Kurtis Heimerl >> >>> >> > <khe...@cs...> >> >>> >> > wrote: >> >>> >> >> >> >>> >> >> Wait, so the behavior changes without you modifying SIP.myIP and >> >>> >> >> SIP.myIP2? >> >>> >> >> >> >>> >> >> On Thu, Jan 12, 2012 at 4:58 PM, Arghyadip Paul >> >>> >> >> <arg...@gm...> >> >>> >> >> wrote: >> >>> >> >> > Kurits, >> >>> >> >> > >> >>> >> >> > Verified the SIP.myIP and SIP.myIP2 and they are fine.. >> >>> >> >> > >> >>> >> >> > SIP.myIP=127.0.0.1 >> >>> >> >> > SIP.myIP2=192.168.2.130 (The Ip of the machine where Asterisk, >> >>> >> >> > SipAuthServe >> >>> >> >> > and SMS is running) >> >>> >> >> > >> >>> >> >> > I can see some new behavior now: >> >>> >> >> > >> >>> >> >> > I can UE1 message is getting queue. Smqueue is able to >> >>> >> >> > discover >> >>> >> >> > the >> >>> >> >> > ip >> >>> >> >> > address of the receiver but then getting a time out as per the >> >>> >> >> > log in >> >>> >> >> > /var/log/OpenBTS.log >> >>> >> >> > >> >>> >> >> > Jan 12 16:51:52 arz smqueue: INFO 140176208975648 >> >>> >> >> > HLR.cpp:318:getRegistrationIP: >> >>> >> >> > getRegistrationIP(IMSI404864415448667) >> >>> >> >> > Jan 12 16:51:52 arz smqueue: INFO 140176208975648 >> >>> >> >> > HLR.cpp:202:sqlLocal: >> >>> >> >> > select ipaddr from sip_buddies where name = >> >>> >> >> > "IMSI404864415448667" >> >>> >> >> > Jan 12 16:51:52 arz smqueue: INFO 140176208975648 >> >>> >> >> > HLR.cpp:242:sqlQuery: >> >>> >> >> > result = 192.168.2.148 >> >>> >> >> > Jan 12 16:51:52 arz smqueue: DEBUG 140176208975648 >> >>> >> >> > smqueue.cpp:845:set_qtag: >> >>> >> >> > Param tag=bgaaympfvuzorfqh >> >>> >> >> > Jan 12 16:51:52 arz smqueue: DEBUG 140176208975648 >> >>> >> >> > smnet.cpp:104:deliver_msg_datagram: #012--Deliver message: >> >>> >> >> > Jan 12 16:51:52 arz smqueue: DEBUG 140176208975648 >> >>> >> >> > smnet.cpp:105:deliver_msg_datagram: MESSAGE >> >>> >> >> > sip:IMSI404864415448667@127.0.0.1:5062 SIP/2.0#015#012Via: >> >>> >> >> > SIP/2.0/UDP >> >>> >> >> > 127.0.0.1:5063;branch=123#015#012Via: SIP/2.0/UDP >> >>> >> >> > >> >>> >> >> > >> >>> >> >> > 192.168.2.148:5062;branch=z9hG4bKobts287f9f09ab151b2d4c#015#012From: >> >>> >> >> > IMSI404864430002302 >> >>> >> >> > <sip:2103@192.168.2.130>;tag=bgaaympfvuzorfqh#015#012To: >> >>> >> >> > 9733013520 <sip:9733013520@192.168.2.130>#015#012Call-ID: >> >>> >> >> > aeZ3e@127.0.0.1#015#012CSeq: 220 MESSAGE#015#012Content-Type: >> >>> >> >> > application/vnd.3gpp.sms#015#012Max-forwards: >> >>> >> >> > 5#015#012Content-Length: >> >>> >> >> > >> >>> >> >> > >> >>> >> >> > >> >>> >> >> > >> >>> >> >> > 58#015#012#015#01201a303a1000000150004a1123000002110216115222b065076393c2f03 >> >>> >> >> > Jan 12 16:51:52 arz smqueue: INFO 140176208975648 >> >>> >> >> > smqueue.cpp:1909:main_loop: === Jan 12 16:51:52 1 queued; 5 >> >>> >> >> > seconds >> >>> >> >> > til >> >>> >> >> > Request Destination SIP URL for 220--bgaaympfvuzorfqh >> >>> >> >> > Jan 12 16:51:57 arz smqueue: DEBUG 140176208975648 >> >>> >> >> > smqueue.cpp:1924:main_loop: Timeout... >> >>> >> >> > >> >>> >> >> > >> >>> >> >> > This log is coming repeatedly... >> >>> >> >> > >> >>> >> >> > >> >>> >> >> > Also, I am not able to see any MESSAGE packet in wireshark >> >>> >> >> > when >> >>> >> >> > Smqueue >> >>> >> >> > forwards the packet to receiver... >> >>> >> >> > >> >>> >> >> > >> >>> >> >> > Please let me know if you can get a catch... >> >>> >> >> > >> >>> >> >> > >> >>> >> >> > >> >>> >> >> > >> >>> >> >> > On Wed, Jan 11, 2012 at 5:19 PM, Kurtis Heimerl >> >>> >> >> > <khe...@cs...> >> >>> >> >> > wrote: >> >>> >> >> >> >> >>> >> >> >> The "host not valid" error message is triggered by the >> >>> >> >> >> following >> >>> >> >> >> code: >> >>> >> >> >> >> >>> >> >> >> if (!host) { LOG(ERR) << "no host!"; return NO_STATE; >> >>> >> >> >> } >> >>> >> >> >> if (0 != strcmp("127.0.0.1", host) >> >>> >> >> >> && 0 != strcmp("localhost", host) >> >>> >> >> >> && 0 != strcmp(my_ipaddress.c_str(), host) >> >>> >> >> >> && 0 != strcmp(my_2nd_ipaddress.c_str(), host)) { >> >>> >> >> >> LOG(ERR) << "host not valid"; >> >>> >> >> >> return NO_STATE; >> >>> >> >> >> } >> >>> >> >> >> >> >>> >> >> >> Any chance the SIP.myIP or SIP.myIP2 fields in your >> >>> >> >> >> smqueue.db >> >>> >> >> >> file >> >>> >> >> >> are incorrect? >> >>> >> >> >> >> >>> >> >> >> On Wed, Jan 11, 2012 at 4:49 PM, Arghyadip Paul >> >>> >> >> >> <arg...@gm...> >> >>> >> >> >> wrote: >> >>> >> >> >> > After testing a number of features in a simple SIngle >> >>> >> >> >> > OpenBTS/Asterisk/Smqueue/SipAuthServe setting, I am trying >> >>> >> >> >> > hands-on >> >>> >> >> >> > with >> >>> >> >> >> > multiple BTS station scenario. >> >>> >> >> >> > >> >>> >> >> >> > The test set up is as follows: >> >>> >> >> >> > >> >>> >> >> >> > 1. OpenBTS is installed and configured in machine 1 with ip >> >>> >> >> >> > address >> >>> >> >> >> > 192.168.2.148/ >> >>> >> >> >> > 2. Real Time Asterisk, Smqueue and SipAuthserve is running >> >>> >> >> >> > in >> >>> >> >> >> > machine >> >>> >> >> >> > 2 >> >>> >> >> >> > with >> >>> >> >> >> > ip address 192.168.2.130. >> >>> >> >> >> > >> >>> >> >> >> > Test Cases tried: >> >>> >> >> >> > >> >>> >> >> >> > 1. Register two different UEs in the OpenBTS running in >> >>> >> >> >> > machine 1. >> >>> >> >> >> > [OK] >> >>> >> >> >> > 2. Set up call session. [OK] >> >>> >> >> >> > 3. Sending SMS from one UE to another.[FAILED] >> >>> >> >> >> > >> >>> >> >> >> > >> >>> >> >> >> > In test case 3, I have observed that Message is getting >> >>> >> >> >> > queued >> >>> >> >> >> > in >> >>> >> >> >> > Smqueue >> >>> >> >> >> > and Smqueue is esquiring Asterisk to get the forward >> >>> >> >> >> > address >> >>> >> >> >> > of >> >>> >> >> >> > the >> >>> >> >> >> > receiver >> >>> >> >> >> > but Asterisk is printing some failure messages as follows: >> >>> >> >> >> > >> >>> >> >> >> > Asterisk Logs: >> >>> >> >> >> > ----------------------- >> >>> >> >> >> > [Jan 11 16:35:35] ERROR[5012]: cdr_csv.c:306 csv_log: >> >>> >> >> >> > Unable >> >>> >> >> >> > to >> >>> >> >> >> > re-open >> >>> >> >> >> > master file /var/log/asterisk//cdr-csv//Master.csv : >> >>> >> >> >> > Permission >> >>> >> >> >> > denied >> >>> >> >> >> > == Spawn extension (phones, 9733013520, 5) exited >> >>> >> >> >> > non-zero >> >>> >> >> >> > on >> >>> >> >> >> > 'SIP/IMSI404864430002302-00000002' >> >>> >> >> >> > >> >>> >> >> >> > >> >>> >> >> >> > OpenBTS.log Says: >> >>> >> >> >> > ------------------------------ >> >>> >> >> >> > Jan 11 16:35:56 arz smqueue: NOTICE 140052744574752 >> >>> >> >> >> > smqueue.cpp:1964:main_loop: Got SMS '118--qppefgvlzedfnxth' >> >>> >> >> >> > from >> >>> >> >> >> > IMSI404864415448667 for smsc. >> >>> >> >> >> > Jan 11 16:35:56 arz smqueue: INFO 140052744574752 >> >>> >> >> >> > smqueue.cpp:1855:respond_sip_ack: Responding with "202 >> >>> >> >> >> > Queued". >> >>> >> >> >> > Jan 11 16:35:56 arz smqueue: INFO 140052744574752 >> >>> >> >> >> > smqueue.cpp:1326:handle_short_code: Short-code SMS smsc >> >>> >> >> >> > with >> >>> >> >> >> > text >> >>> >> >> >> > "Sms >> >>> >> >> >> > sending" >> >>> >> >> >> > Jan 11 16:35:56 arz smqueue: INFO 140052744574752 >> >>> >> >> >> > smsc.cpp:216:submitSMS: >> >>> >> >> >> > from IMSI404864415448667 message: 1 RD=0 VPF=2 RP=0 UDHI=0 >> >>> >> >> >> > SRR=0 >> >>> >> >> >> > MR=26 >> >>> >> >> >> > DA=(type=unknown plan=E.164/ISDN digits=2103) PI=0 DCS=0 >> >>> >> >> >> > VP=(expiration=(Wed >> >>> >> >> >> > Mar 27 17:35:56 2013)) UD="DCS=0 UDHI=0 UDLength=11 >> >>> >> >> >> > UD=(cb6f382cf4dd9396ef98)" >> >>> >> >> >> > Jan 11 16:35:56 arz smqueue: INFO 140052744574752 >> >>> >> >> >> > smsc.cpp:106:sendSIP_init: >> >>> >> >> >> > from IMSI404864415448667 to 2103 >> >>> >> >> >> > Jan 11 16:35:56 arz smqueue: ERR 140052744574752 >> >>> >> >> >> > smqueue.cpp:1552:lookup_uri_imsi: host not valid >> >>> >> >> >> > Jan 11 16:35:56 arz smqueue: NOTICE 140052744574752 >> >>> >> >> >> > smqueue.cpp:301:process_timeout: == This message had an >> >>> >> >> >> > error >> >>> >> >> >> > and >> >>> >> >> >> > is >> >>> >> >> >> > being >> >>> >> >> >> > deleted:#012MSG = MESSAGE sip:2103@192.168.2.130 >> >>> >> >> >> > SIP/2.0#015#012Via: >> >>> >> >> >> > SIP/2.0/UDP 127.0.0.1:5063;branch=123#015#012Via: >> >>> >> >> >> > SIP/2.0/UDP >> >>> >> >> >> > >> >>> >> >> >> > >> >>> >> >> >> > >> >>> >> >> >> > 192.168.2.148:5062;branch=z9hG4bKobts282cd0d0037977477c#015#012From: >> >>> >> >> >> > IMSI404864415448667 >> >>> >> >> >> > <sip:211@127.0.0.1>;tag=qppefgvlzedfnxth#015#012To: >> >>> >> >> >> > 2103 >> >>> >> >> >> > <sip:2103@192.168.2.130>#015#012Call-ID: >> >>> >> >> >> > 821936150@192.168.2.148#015#012CSeq: 118 >> >>> >> >> >> > MESSAGE#015#012Content-Type: >> >>> >> >> >> > application/vnd.3gpp.sms#015#012Max-forwards: >> >>> >> >> >> > 5#015#012Content-Length: >> >>> >> >> >> > 45#015#012#015#012IMSI404864415448667@192.168.2.130 Sms >> >>> >> >> >> > sending >> >>> >> >> >> > Jan 11 16:35:56 arz smqueue: INFO 140052744574752 >> >>> >> >> >> > smqueue.cpp:1905:main_loop: === Jan 11 16:35:56 0 queued; >> >>> >> >> >> > waiting. >> >>> >> >> >> > >> >>> >> >> >> > >> >>> >> >> >> > I have configured the Smqueue.db as per the instructions >> >>> >> >> >> > given >> >>> >> >> >> > in >> >>> >> >> >> > https://wush.net/trac/rangepublic/wiki/multiBTS and >> >>> >> >> >> > verified >> >>> >> >> >> > that >> >>> >> >> >> > they >> >>> >> >> >> > are >> >>> >> >> >> > fine. From the logs it seems that it is more of a access >> >>> >> >> >> > permission >> >>> >> >> >> > problem. >> >>> >> >> >> > >> >>> >> >> >> > Any guess?? >> >>> >> >> >> > >> >>> >> >> >> > -- >> >>> >> >> >> > Arghyadip Paul >> >>> >> >> >> > Graduate Student >> >>> >> >> >> > Department of Computer Science >> >>> >> >> >> > University of California Santa Barbara >> >>> >> >> >> > Santa Barbara , CA 93106 USA >> >>> >> >> >> > >> >>> >> >> >> > >> >>> >> >> >> > >> >>> >> >> >> > >> >>> >> >> >> > >> >>> >> >> >> > >> >>> >> >> >> > >> >>> >> >> >> > ------------------------------------------------------------------------------ >> >>> >> >> >> > RSA(R) Conference 2012 >> >>> >> >> >> > Mar 27 - Feb 2 >> >>> >> >> >> > Save $400 by Jan. 27 >> >>> >> >> >> > Register now! >> >>> >> >> >> > http://p.sf.net/sfu/rsa-sfdev2dev2 >> >>> >> >> >> > _______________________________________________ >> >>> >> >> >> > Openbts-discuss mailing list >> >>> >> >> >> > Ope...@li... >> >>> >> >> >> > >> >>> >> >> >> > https://lists.sourceforge.net/lists/listinfo/openbts-discuss >> >>> >> >> >> > >> >>> >> >> > >> >>> >> >> > >> >>> >> >> > >> >>> >> >> > >> >>> >> >> > -- >> >>> >> >> > Arghyadip Paul >> >>> >> >> > Graduate Student >> >>> >> >> > Department of Computer Science >> >>> >> >> > University of California Santa Barbara >> >>> >> >> > Santa Barbara , CA 93106 USA >> >>> >> >> > >> >>> >> > >> >>> >> > >> >>> >> > >> >>> >> > >> >>> >> > -- >> >>> >> > Arghyadip Paul >> >>> >> > Graduate Student >> >>> >> > Department of Computer Science >> >>> >> > University of California Santa Barbara >> >>> >> > Santa Barbara , CA 93106 USA >> >>> >> > >> >>> > >> >>> > >> >>> > >> >>> > >> >>> > -- >> >>> > Arghyadip Paul >> >>> > Graduate Student >> >>> > Department of Computer Science >> >>> > University of California Santa Barbara >> >>> > Santa Barbara , CA 93106 USA >> >>> > >> >> >> >> >> >> >> >> >> >> -- >> >> Arghyadip Paul >> >> Graduate Student >> >> Department of Computer Science >> >> University of California Santa Barbara >> >> Santa Barbara , CA 93106 USA >> >> >> > >> > >> > >> > -- >> > Arghyadip Paul >> > Graduate Student >> > Department of Computer Science >> > University of California Santa Barbara >> > Santa Barbara , CA 93106 USA >> > > > > > > -- > Arghyadip Paul > Graduate Student > Department of Computer Science > University of California Santa Barbara > Santa Barbara , CA 93106 USA > |