Re: [OpenSBC] OpenSBC and problems with calling between internal

[OpenSBC F. <ope...@op...>]

 users and registering to provider
MIME-Version: 1.0
Content-Type: text/plain; charset=ISO-8859-1
Content-Transfer-Encoding: 7bit


Hi, thanks for the reply. This is the part of the logs for packet exchange between voicetrading and OpenSBC:


2008/10/30 17:30:49.834 DBG: [CID=0x1171]       
2008/10/30 17:30:49.837 DTL: [CID=0x1171] Event: ---> UnknownTransaction - REGISTER
2008/10/30 17:30:49.837 WRN: [CID=0x06cb] *** UNHANDLED *** Event(SIPUnknownTransaction) 
2008/10/30 17:30:53.806 DTL: [CID=0x1171] NICT(1225384246316)   Event( Timer-E ) Interval: 4000
2008/10/30 17:30:53.807 INF: [CID=0x1171] >>> REGISTER sip:sip.voicetrading.com SIP/2.0 DST: 194.xxx.xxx.198:5060:UDP  SRC: 87.xxx.xxx.102:8006 enc=0 bytes=599
2008/10/30 17:30:53.807 DBG: [CID=0x1171]       
2008/10/30 17:30:53.807 DBG: [CID=0x1171]       REGISTER sip:sip.voicetrading.com SIP/2.0
2008/10/30 17:30:53.807 DBG: [CID=0x1171]       From:  <sip:voi...@si...>;tag=78cdd8c20da5dd1181e3c10d24aff1d1
2008/10/30 17:30:53.807 DBG: [CID=0x1171]       To: sip:voi...@si...
2008/10/30 17:30:53.807 DBG: [CID=0x1171]       Via: SIP/2.0/UDP 87.xxx.xxx.102:8006;iid=28941;branch=z9hG4bK2cfbd8c20da5dd1181e3c10d24aff1d1;uas-addr=194.xxx.xxx.198;rport
2008/10/30 17:30:53.807 DBG: [CID=0x1171]       CSeq: 1 REGISTER
2008/10/30 17:30:53.807 DBG: [CID=0x1171]       Call-ID: fed...@si...
2008/10/30 17:30:53.807 DBG: [CID=0x1171]       Contact: <sip:voi...@87....102:8006;transport=udp>
2008/10/30 17:30:53.807 DBG: [CID=0x1171]       User-Agent:
2008/10/30 17:30:53.807 DBG: [CID=0x1171]       Expires: 120
2008/10/30 17:30:53.807 DBG: [CID=0x1171]       Max-Forwards: 70
2008/10/30 17:30:53.807 DBG: [CID=0x1171]       Allow: INVITE, BYE, ACK, REFER, MESSAGE, INFO, NOTIFY, OPTIONS, PRACK
2008/10/30 17:30:53.807 DBG: [CID=0x1171]       Content-Length: 0
2008/10/30 17:30:53.807 DBG: [CID=0x1171]       
2008/10/30 17:30:53.807 DBG: [CID=0x1171]       
2008/10/30 17:30:54.134 INF: [CID=0x1171] <<< SIP/2.0 401 Unauthorized  SRC: 194.xxx.xxx.198:5060:UDP enc=0 bytes=502
2008/10/30 17:30:54.134 DBG: [CID=0x1171]       
2008/10/30 17:30:54.134 DBG: [CID=0x1171]       SIP/2.0 401 Unauthorized
2008/10/30 17:30:54.134 DBG: [CID=0x1171]       From:  <sip:voi...@si...>;tag=78cdd8c20da5dd1181e3c10d24aff1d1
2008/10/30 17:30:54.134 DBG: [CID=0x1171]       To:  <sip:voi...@si...>
2008/10/30 17:30:54.134 DBG: [CID=0x1171]       CSeq: 1 REGISTER
2008/10/30 17:30:54.134 DBG: [CID=0x1171]       Call-ID: fed...@si...
2008/10/30 17:30:54.134 DBG: [CID=0x1171]       Contact: sip:194.xxx.xxx.198:5060
2008/10/30 17:30:54.134 DBG: [CID=0x1171]       Server: (Very nice Sip Registrar/Proxy Server)
2008/10/30 17:30:54.134 DBG: [CID=0x1171]       WWW-Authenticate: Digest realm="sip.voicetrading.com", nonce="617080828", algorithm=MD5
2008/10/30 17:30:54.134 DBG: [CID=0x1171]       Allow: ACKBYE, CANCEL, INVITE, REGISTER, OPTIONS, INFO, MESSAGE
2008/10/30 17:30:54.134 DBG: [CID=0x1171]       Content-Length: 0
2008/10/30 17:30:54.134 DBG: [CID=0x1171]       
2008/10/30 17:30:54.134 DBG: [CID=0x1171]       
2008/10/30 17:30:54.135 DTL: [CID=0x1171] Event: ---> UnknownTransaction - REGISTER
2008/10/30 17:30:54.135 WRN: [CID=0x06cb] *** UNHANDLED *** Event(SIPUnknownTransaction) 
2008/10/30 17:30:56.316 DTL: [CID=0x1171] NICT(1225384246316)   Event( Timer-F ) Interval: 10000
2008/10/30 17:30:56.316 DTL: [CID=0x1171] NICT(1225384246316)   StateTrying->StateTerminated
2008/10/30 17:30:56.317 DTL: [CID=0x1171] Event: ---> TimerExpire - REGISTER
2008/10/30 17:30:56.317 WRN: [CID=0x06cb] *** TIMER EXPIRATION *** for SIP Session REGISTER-voicetradingusername:@sip.voicetrading.com
2008/10/30 17:30:56.317 DTL: [CID=0x06cb] *** QUEUED FOR DELETION *** SIPSession: REGISTER-voicetradingusername:@sip.voicetrading.com
2008/10/30 17:30:56.317 DBG: [CID=0x0000] GC:  First Stale Object GCObject<T>


I have not askedthem yet, but probably i will do that. It is strange because i can register to the provider with asterisk, with other softphones, everywhere i use the same data.But i can't do that with OSBC.


Yes, i can comfirm that the calls are router from sipx to OSBC. I have tried this with trying to call real numbers, and all calls are routed to my voip provider and i'm able to call the real numbers. 


Only problem are calls between internal users. 


I get these errors in sipx logs:
 


 "2008-10-29T19:40:18.275331Z":238:SIP:WARNING:asxxxx.igxxxxx.com:SipRouter-13:F67FFB90:SipXProxy:"SipUserAgent::send INVITE request matches existing transaction"
        "2008-10-29T19:40:18.282874Z":239:SIP:ERR:asxxxx.igxxxxx.com:SipClientTcp-80:F5DFFB90:SipXProxy:"SipClientWriteBuffer[SipClientTcp-80]::empty had 1 total messages"
        "2008-10-29T19:40:26.253983Z":240:SIP:ERR:asxxxx.igxxxxx.com:SipUserAgent-2:F66FEB90:SipXProxy:"SipUserAgent::handleMessage SIP message timeout expired with no matching transaction"
        "2008-10-29T19:45:32.585043Z":241:SIP:ERR:asxxxx.igxxxxx.com:SipClientTcp-78:F55FEB90:SipXProxy:"SipClientWriteBuffer[SipClientTcp-78]::empty had 0 total messages"
        "2008-10-29T19:45:47.031784Z":242:SIP:WARNING:asxxxx.igxxxxx.com:SipRouter-13:F67FFB90:SipXProxy:"SipUserAgent::send INVITE request matches existing transaction"
        "2008-10-29T19:45:47.041093Z":243:SIP:WARNING:asxxxx.igxxxxx.com:SipRouter-13:F67FFB90:SipXProxy:"SipUserAgent::send INVITE request matches existing transaction"
        "2008-10-29T19:45:47.054241Z":244:SIP:ERR:asxxxx.igxxxxx.com:SipClientTcp-81:F55FEB90:SipXProxy:"SipClientWriteBuffer[SipClientTcp-81]::empty had 1 total messages"
        "2008-10-29T19:45:51.079730Z":245:SIP:ERR:asxxxx.igxxxxx.com:SipClientTcp-82:F55FEB90:SipXProxy:"SipClientWriteBuffer[SipClientTcp-82]::empty had 1 total messages"
        "2008-10-29T19:45:51.560491Z":246:SIP:ERR:asxxxx.igxxxxx.com:SipUserAgent-2:F66FEB90:SipXProxy:"SipUserAgent::handleMessage SIP message timeout expired with no matching transaction"


 


I asked in sipx mailing list about this problem too.