This Question is Answered

2 "helpful" answers available (1 pts)
5 Replies Last post: Jan 1, 2009 12:53 PM by mpicher  
Click to view mpicher's profile   40 posts since
Dec 28, 2008

Jan 1, 2009 7:48 AM

Calls to outside with SRV record addresses

Alright, got inbound / outbound working.

One problem with outbound calling however, if I try calling a location by SRV record it doesn't want to complete the call but if I call to an A record the call goes through.

For example... if i dial out to mpicher at sipphone dot com the call does not complete (see log below)

But if I try mpicher at proxy01 dot sipphone dot com the call will complete (see log below).

Any thoughts?

My only other problem (well at the moment, and with opensbc) is calls to the AutoAttendant on sipX seem really scratchy, whereas call into my UA have good call quality.

Thanks,
Mike

Here's a log of the call to mpicher at sipphone dot com not working:

2009/01/01 12:40:43.144 INF: [CID=0xa651360e] <<< INVITE sip:mpicher at sipphone dot com SIP/2.0 Method(INVITE) SRC: 172.16.1.2:5060:UDP enc=0 bytes=1760
2009/01/01 12:40:43.148 INF: [CID=0xa651360e] >>> SIP/2.0 100 Trying Method(INVITE) DST: 172.16.1.2:5060:UDP SRC=172.16.1.254:5061 enc=0 bytes=756
2009/01/01 12:40:43.150 INF: [CID=0xa651360e] *** CREATED (UAS) CALL *** NmRiYzY2MzUzOWE5ZDMyNmNiMWEzM2M0MzdjZDhlMTc.
2009/01/01 12:40:43.152 INF: [CID=0xa651360e] *** NO REGISTRATION FOUND *** Fetching route through local database URI sip:mpicher at sipphone dot com
2009/01/01 12:40:43.152 INF: [CID=0xa651360e] *** NO STATIC ROUTE *** URI sip:mpicher at sipphone dot com
2009/01/01 12:40:43.153 INF: [CID=0xa651360e] *** UPPER REGISTRATION RELAY *** -->> Callee: sip:MPicher at xyzcompany dot com AOR: sip:mpicher at sipphone dot com BINDING: sip:mpicher at sipphone dot com
2009/01/01 12:40:43.156 INF: [CID=0xa651360e] *** CREATED (UAC) CALL *** NmRiYzY2MzUzOWE5ZDMyNmNiMWEzM2M0MzdjZDhlMTc.-0x0003
2009/01/01 12:40:43.158 INF: [CID=0xa651360e] *** RTP Session CREATED *** l-addr=172.16.1.254 r-addr=172.16.1.129/172.16.1.129 r-port=50334
2009/01/01 12:40:43.169 INF: [CID=0xa651360e] >>> INVITE sip:mpicher at sipphone dot com SIP/2.0 Method(INVITE) DST: 198.65.166.139:5060:UDP SRC: 76.178.252.229:5060 enc=0 bytes=1320
2009/01/01 12:40:43.669 INF: [CID=0xa651360e] >>> INVITE sip:mpicher at sipphone dot com SIP/2.0 Method(INVITE) DST: 198.65.166.139:5060:UDP SRC: 76.178.252.229:5060 enc=0 bytes=1320
2009/01/01 12:40:44.669 INF: [CID=0xa651360e] >>> INVITE sip:mpicher at sipphone dot com SIP/2.0 Method(INVITE) DST: 198.65.166.139:5060:UDP SRC: 76.178.252.229:5060 enc=0 bytes=1320
2009/01/01 12:40:46.669 INF: [CID=0xa651360e] >>> INVITE sip:mpicher at sipphone dot com SIP/2.0 Method(INVITE) DST: 198.65.166.139:5060:UDP SRC: 76.178.252.229:5060 enc=0 bytes=1320
2009/01/01 12:40:50.669 INF: [CID=0xa651360e] >>> INVITE sip:mpicher at sipphone dot com SIP/2.0 Method(INVITE) DST: 198.65.166.139:5060:UDP SRC: 76.178.252.229:5060 enc=0 bytes=1320
2009/01/01 12:40:58.669 INF: [CID=0xa651360e] >>> INVITE sip:mpicher at sipphone dot com SIP/2.0 Method(INVITE) DST: 198.65.166.139:5060:UDP SRC: 76.178.252.229:5060 enc=0 bytes=1320
2009/01/01 12:41:13.169 WRN: [CID=0x99b5a101] *** TIMER EXPIRATION *** for SIP Session NmRiYzY2MzUzOWE5ZDMyNmNiMWEzM2M0MzdjZDhlMTc.-0x0003
2009/01/01 12:41:13.170 ERR: [CID=0xa651360e] *** ALERTING/CONNECT TIMEOUT!!! ***
2009/01/01 12:41:13.170 INF: [CID=0xa651360e] *** RTP (Audio) Statistics *** addr=76.178.252.229:30010->0.0.0.0:0 enc=0 rx=0 tx=0 lost=0 outOfOrder=0 late=0 rxTime=0/0 txTime=0/0 jitter=0/0
2009/01/01 12:41:13.171 INF: [CID=0xa651360e] Connection: Rejected / Code = 408
2009/01/01 12:41:13.175 INF: [CID=0xa651360e] *** CALL TEAR DOWN *** NmRiYzY2MzUzOWE5ZDMyNmNiMWEzM2M0MzdjZDhlMTc.-connection
2009/01/01 12:41:13.175 INF: [CID=0x8428bd16] *** B2BUA CONNECTION OnDestroySession ***
2009/01/01 12:41:13.175 INF: [CID=0xa651360e] *** RTP (Audio) Statistics *** addr=172.16.1.254:30008->0.0.0.0:0 enc=0 rx=0 tx=0 lost=0 outOfOrder=0 late=0 rxTime=0/0 txTime=0/0 jitter=0/0
2009/01/01 12:41:13.176 INF: [CID=0xa651360e] *** DESTROYED CALL *** NmRiYzY2MzUzOWE5ZDMyNmNiMWEzM2M0MzdjZDhlMTc.-0x0003
2009/01/01 12:41:13.178 INF: [CID=0xa651360e] >>> CANCEL sip:mpicher at sipphone dot com SIP/2.0 Method(CANCEL) DST: 198.65.166.139:5060:UDP SRC: 76.178.252.229:5060 enc=0 bytes=387
2009/01/01 12:41:13.181 INF: [CID=0xa651360e] >>> SIP/2.0 408 Alerting Timeout Method(INVITE) DST: 172.16.1.2:5060:UDP SRC=172.16.1.254:5061 enc=0 bytes=839
2009/01/01 12:41:13.192 INF: [CID=0xa651360e] <<< ACK sip:mpicher at sipphone dot com SIP/2.0 Method(ACK) SRC: 172.16.1.2:5060:UDP enc=0 bytes=471
2009/01/01 12:41:13.668 INF: [CID=0xa651360e] >>> CANCEL sip:mpicher at sipphone dot com SIP/2.0 Method(CANCEL) DST: 198.65.166.139:5060:UDP SRC: 76.178.252.229:5060 enc=0 bytes=387
2009/01/01 12:41:14.668 INF: [CID=0xa651360e] >>> CANCEL sip:mpicher at sipphone dot com SIP/2.0 Method(CANCEL) DST: 198.65.166.139:5060:UDP SRC: 76.178.252.229:5060 enc=0 bytes=387
2009/01/01 12:41:16.668 INF: [CID=0xa651360e] >>> CANCEL sip:mpicher at sipphone dot com SIP/2.0 Method(CANCEL) DST: 198.65.166.139:5060:UDP SRC: 76.178.252.229:5060 enc=0 bytes=387
2009/01/01 12:41:17.177 INF: [CID=0x8428bd16] *** DESTROYED B2BUA CONNECTION *** 0x0x887d130
2009/01/01 12:41:17.178 INF: [CID=0x8428bd16] *** COUNTERS *** ICT=0 NICT=1 IST=1 NIST=0 TIMERS=7 CALL=0 CONN=0 REG=0 RTP=0 QUEUE=1 CACHE=2 GC=4 TOTALCONN=3 TOTALREG=0 TOTALSZR=1 AVGDUR=3385 HIGHDUR=3385 IDLETIME=34
2009/01/01 12:41:17.178 INF: [CID=0xa651360e] *** DESTROYED CALL *** NmRiYzY2MzUzOWE5ZDMyNmNiMWEzM2M0MzdjZDhlMTc.
2009/01/01 12:41:20.668 INF: [CID=0xa651360e] >>> CANCEL sip:mpicher at sipphone dot com SIP/2.0 Method(CANCEL) DST: 198.65.166.139:5060:UDP SRC: 76.178.252.229:5060 enc=0 bytes=387

Here's a log of the call to mpicher at proxy01 dot sipphone dot com working:

2009/01/01 12:45:17.906 INF: [CID=0x78099be1] <<< INVITE sip:mpicher at proxy01 dot sipphone dot com SIP/2.0 Method(INVITE) SRC: 172.16.1.2:5060:UDP enc=0 bytes=1626
2009/01/01 12:45:17.910 INF: [CID=0x78099be1] *** CREATED (UAS) CALL *** YTU5MzRmN2RlMWUyYTU2ZTRmNTY2NThmNDgwMWNlZmU.
2009/01/01 12:45:17.911 INF: [CID=0x78099be1] *** NO REGISTRATION FOUND *** Fetching route through local database URI sip:mpicher at proxy01 dot sipphone dot com
2009/01/01 12:45:17.912 INF: [CID=0x78099be1] *** NO STATIC ROUTE *** URI sip:mpicher at proxy01 dot sipphone dot com
2009/01/01 12:45:17.913 INF: [CID=0x78099be1] *** UPPER REGISTRATION RELAY *** -->> Callee: sip:200 at xyzcompany dot com AOR: sip:mpicher at proxy01 dot sipphone dot com BINDING: sip:mpicher at proxy01 dot sipphone dot com
2009/01/01 12:45:17.915 INF: [CID=0x78099be1] *** CREATED (UAC) CALL *** YTU5MzRmN2RlMWUyYTU2ZTRmNTY2NThmNDgwMWNlZmU.-0x0004
2009/01/01 12:45:17.918 INF: [CID=0x78099be1] *** RTP Session CREATED *** l-addr=172.16.1.254 r-addr=172.16.1.129/172.16.1.129 r-port=34390
2009/01/01 12:45:17.926 INF: [CID=0x78099be1] >>> SIP/2.0 100 Trying Method(INVITE) DST: 172.16.1.2:5060:UDP SRC=172.16.1.254:5061 enc=0 bytes=606
2009/01/01 12:45:17.929 INF: [CID=0x78099be1] >>> INVITE sip:mpicher at proxy01 dot sipphone dot com SIP/2.0 Method(INVITE) DST: 198.65.166.131:5060:UDP SRC: 76.178.252.229:5060 enc=0 bytes=1359
2009/01/01 12:45:18.428 INF: [CID=0x78099be1] >>> INVITE sip:mpicher at proxy01 dot sipphone dot com SIP/2.0 Method(INVITE) DST: 198.65.166.131:5060:UDP SRC: 76.178.252.229:5060 enc=0 bytes=1359
2009/01/01 12:45:19.212 INF: [CID=0x78099be1] <<< SIP/2.0 100 Giving a try Method(INVITE) SRC: 198.65.166.131:5060:UDP enc=0 bytes=382
2009/01/01 12:45:19.216 INF: [CID=0x78099be1] <<< SIP/2.0 180 Ringing Method(INVITE) SRC: 198.65.166.131:5060:UDP enc=0 bytes=560
2009/01/01 12:45:19.226 INF: [CID=0x78099be1] >>> SIP/2.0 180 Ringing Method(INVITE) DST: 172.16.1.2:5060:UDP SRC=172.16.1.254:5061 enc=0 bytes=695
2009/01/01 12:45:20.429 INF: [CID=0x78099be1] <<< SIP/2.0 180 Ringing Method(INVITE) SRC: 198.65.166.131:5060:UDP enc=0 bytes=560
2009/01/01 12:45:20.436 INF: [CID=0x78099be1] >>> SIP/2.0 180 Ringing Method(INVITE) DST: 172.16.1.2:5060:UDP SRC=172.16.1.254:5061 enc=0 bytes=695
2009/01/01 12:45:20.881 INF: [CID=0x78099be1] <<< SIP/2.0 200 OK Method(INVITE) SRC: 198.65.166.131:5060:UDP enc=0 bytes=855
2009/01/01 12:45:20.886 INF: [CID=0x78099be1] *** RTP Session CREATED *** l-addr=76.178.252.229 r-addr=130.94.88.90/76.178.252.229 r-port=16416
2009/01/01 12:45:20.886 INF: [CID=0x78099be1] *** CALL ESTABLISHED *** YTU5MzRmN2RlMWUyYTU2ZTRmNTY2NThmNDgwMWNlZmU.-0x0004
2009/01/01 12:45:20.890 INF: [CID=0x78099be1] *** RTP PROXY STARTED ***
2009/01/01 12:45:20.892 INF: [CID=0x78099be1] >>> ACK sip:hostedvm17476040590@130.94.88.90 SIP/2.0 Method(ACK) DST: 198.65.166.131:5060:UDP SRC: 76.178.252.229:5060 enc=0 bytes=667
2009/01/01 12:45:20.893 INF: [CID=0x78099be1] *** RTP PROXY STARTED ***
2009/01/01 12:45:20.898 INF: [CID=0x78099be1] >>> SIP/2.0 200 OK Method(INVITE) DST: 172.16.1.2:5060:UDP SRC=172.16.1.254:5061 enc=0 bytes=1133
2009/01/01 12:45:21.092 INF: [CID=0x78099be1] <<< ACK sip:mpicher@172.16.1.254:5060 SIP/2.0 Method(ACK) SRC: 172.16.1.2:5060:UDP enc=0 bytes=895
2009/01/01 12:45:26.515 INF: [CID=0x78099be1] <<< BYE sip:mpicher@172.16.1.254:5060 SIP/2.0 Method(BYE) SRC: 172.16.1.2:5060:UDP enc=0 bytes=932
2009/01/01 12:45:26.526 INF: [CID=0x78099be1] *** CALL TEAR DOWN *** YTU5MzRmN2RlMWUyYTU2ZTRmNTY2NThmNDgwMWNlZmU.-connection
2009/01/01 12:45:26.526 INF: [CID=0x0cfee90a] *** B2BUA CONNECTION OnDestroySession ***
2009/01/01 12:45:26.527 INF: [CID=0x78099be1] *** RTP (Audio) Statistics *** addr=172.16.1.254:30012->172.16.1.129:34390 enc=0 rx=275 tx=269 lost=0 outOfOrder=0 late=0 rxTime=20/27 txTime=20/42 jitter=0/1
2009/01/01 12:45:26.529 INF: [CID=0x78099be1] >>> SIP/2.0 200 OK Method(BYE) DST: 172.16.1.2:5060:UDP SRC=172.16.1.254:5061 enc=0 bytes=526
2009/01/01 12:45:26.531 INF: [CID=0x78099be1] >>> BYE sip:hostedvm17476040590@130.94.88.90 SIP/2.0 Method(BYE) DST: 198.65.166.131:5060:UDP SRC: 76.178.252.229:5060 enc=0 bytes=722
2009/01/01 12:45:26.533 INF: [CID=0x78099be1] *** RTP PROXY STOPPED ***
2009/01/01 12:45:26.533 INF: [CID=0x78099be1] *** RTP PROXY STOPPED ***
2009/01/01 12:45:26.539 INF: [CID=0x78099be1] *** RTP (Audio) Statistics *** addr=76.178.252.229:30014->130.94.88.90:16416 enc=0 rx=269 tx=275 lost=7 outOfOrder=0 late=0 rxTime=19/22 txTime=20/27 jitter=1/5
2009/01/01 12:45:26.658 INF: [CID=0x78099be1] <<< SIP/2.0 200 OK Method(BYE) SRC: 198.65.166.131:5060:UDP enc=0 bytes=608
2009/01/01 12:45:31.541 INF: [CID=0x0cfee90a] *** DESTROYED B2BUA CONNECTION *** 0x0x886d4b0
2009/01/01 12:45:31.541 INF: [CID=0x0cfee90a] *** COUNTERS *** ICT=1 NICT=1 IST=0 NIST=1 TIMERS=12 CALL=0 CONN=0 REG=0 RTP=0 QUEUE=1 CACHE=2 GC=6 TOTALCONN=4 TOTALREG=0 TOTALSZR=2 AVGDUR=4507 HIGHDUR=5630 IDLETIME=14
2009/01/01 12:45:31.542 INF: [CID=0x78099be1] *** DESTROYED CALL *** YTU5MzRmN2RlMWUyYTU2ZTRmNTY2NThmNDgwMWNlZmU.
2009/01/01 12:45:31.543 INF: [CID=0x78099be1] *** DESTROYED CALL *** YTU5MzRmN2RlMWUyYTU2ZTRmNTY2NThmNDgwMWNlZmU.-0x0004

Click to view joegen's profile   519 posts since
Apr 28, 2007
1. Jan 1, 2009 11:02 AM in response to: mpicher
Re: Calls to outside with SRV record addresses
Hi Mike,

Indeed. This is a confirmed bug. OpenSBC currently prioritizes A records over DNS SRV. Since sipphone.com is also an A record, OpenSBC uses that instead of the DNS/SRV record. I have applied a patch for this in CVS. Thanks for pointing it out.

Joegen

{quote:title=mpicher wrote:}{quote}
Alright, got inbound / outbound working.

One problem with outbound calling however, if I try calling a location by SRV record it doesn't want to complete the call but if I call to an A record the call goes through.

For example... if i dial out to mpicher at sipphone dot com the call does not complete (see log below)

But if I try mpicher at proxy01 dot sipphone dot com the call will complete (see log below).

Any thoughts?

My only other problem (well at the moment, and with opensbc) is calls to the AutoAttendant on sipX seem really scratchy, whereas call into my UA have good call quality.

Thanks,
Mike

Here's a log of the call to mpicher at sipphone dot com not working:

2009/01/01 12:40:43.144 INF: [CID=0xa651360e] <<< INVITE sip:mpicher at sipphone dot com SIP/2.0 Method(INVITE) SRC: 172.16.1.2:5060:UDP enc=0 bytes=1760
2009/01/01 12:40:43.148 INF: [CID=0xa651360e] >>> SIP/2.0 100 Trying Method(INVITE) DST: 172.16.1.2:5060:UDP SRC=172.16.1.254:5061 enc=0 bytes=756
2009/01/01 12:40:43.150 INF: [CID=0xa651360e] *** CREATED (UAS) CALL *** NmRiYzY2MzUzOWE5ZDMyNmNiMWEzM2M0MzdjZDhlMTc.
2009/01/01 12:40:43.152 INF: [CID=0xa651360e] *** NO REGISTRATION FOUND *** Fetching route through local database URI sip:mpicher at sipphone dot com
2009/01/01 12:40:43.152 INF: [CID=0xa651360e] *** NO STATIC ROUTE *** URI sip:mpicher at sipphone dot com
2009/01/01 12:40:43.153 INF: [CID=0xa651360e] *** UPPER REGISTRATION RELAY *** -->> Callee: sip:MPicher at xyzcompany dot com AOR: sip:mpicher at sipphone dot com BINDING: sip:mpicher at sipphone dot com
2009/01/01 12:40:43.156 INF: [CID=0xa651360e] *** CREATED (UAC) CALL *** NmRiYzY2MzUzOWE5ZDMyNmNiMWEzM2M0MzdjZDhlMTc.-0x0003
2009/01/01 12:40:43.158 INF: [CID=0xa651360e] *** RTP Session CREATED *** l-addr=172.16.1.254 r-addr=172.16.1.129/172.16.1.129 r-port=50334
2009/01/01 12:40:43.169 INF: [CID=0xa651360e] >>> INVITE sip:mpicher at sipphone dot com SIP/2.0 Method(INVITE) DST: 198.65.166.139:5060:UDP SRC: 76.178.252.229:5060 enc=0 bytes=1320
2009/01/01 12:40:43.669 INF: [CID=0xa651360e] >>> INVITE sip:mpicher at sipphone dot com SIP/2.0 Method(INVITE) DST: 198.65.166.139:5060:UDP SRC: 76.178.252.229:5060 enc=0 bytes=1320
2009/01/01 12:40:44.669 INF: [CID=0xa651360e] >>> INVITE sip:mpicher at sipphone dot com SIP/2.0 Method(INVITE) DST: 198.65.166.139:5060:UDP SRC: 76.178.252.229:5060 enc=0 bytes=1320
2009/01/01 12:40:46.669 INF: [CID=0xa651360e] >>> INVITE sip:mpicher at sipphone dot com SIP/2.0 Method(INVITE) DST: 198.65.166.139:5060:UDP SRC: 76.178.252.229:5060 enc=0 bytes=1320
2009/01/01 12:40:50.669 INF: [CID=0xa651360e] >>> INVITE sip:mpicher at sipphone dot com SIP/2.0 Method(INVITE) DST: 198.65.166.139:5060:UDP SRC: 76.178.252.229:5060 enc=0 bytes=1320
2009/01/01 12:40:58.669 INF: [CID=0xa651360e] >>> INVITE sip:mpicher at sipphone dot com SIP/2.0 Method(INVITE) DST: 198.65.166.139:5060:UDP SRC: 76.178.252.229:5060 enc=0 bytes=1320
2009/01/01 12:41:13.169 WRN: [CID=0x99b5a101] *** TIMER EXPIRATION *** for SIP Session NmRiYzY2MzUzOWE5ZDMyNmNiMWEzM2M0MzdjZDhlMTc.-0x0003
2009/01/01 12:41:13.170 ERR: [CID=0xa651360e] *** ALERTING/CONNECT TIMEOUT!!! ***
2009/01/01 12:41:13.170 INF: [CID=0xa651360e] *** RTP (Audio) Statistics *** addr=76.178.252.229:30010->0.0.0.0:0 enc=0 rx=0 tx=0 lost=0 outOfOrder=0 late=0 rxTime=0/0 txTime=0/0 jitter=0/0
2009/01/01 12:41:13.171 INF: [CID=0xa651360e] Connection: Rejected / Code = 408
2009/01/01 12:41:13.175 INF: [CID=0xa651360e] *** CALL TEAR DOWN *** NmRiYzY2MzUzOWE5ZDMyNmNiMWEzM2M0MzdjZDhlMTc.-connection
2009/01/01 12:41:13.175 INF: [CID=0x8428bd16] *** B2BUA CONNECTION OnDestroySession ***
2009/01/01 12:41:13.175 INF: [CID=0xa651360e] *** RTP (Audio) Statistics *** addr=172.16.1.254:30008->0.0.0.0:0 enc=0 rx=0 tx=0 lost=0 outOfOrder=0 late=0 rxTime=0/0 txTime=0/0 jitter=0/0
2009/01/01 12:41:13.176 INF: [CID=0xa651360e] *** DESTROYED CALL *** NmRiYzY2MzUzOWE5ZDMyNmNiMWEzM2M0MzdjZDhlMTc.-0x0003
2009/01/01 12:41:13.178 INF: [CID=0xa651360e] >>> CANCEL sip:mpicher at sipphone dot com SIP/2.0 Method(CANCEL) DST: 198.65.166.139:5060:UDP SRC: 76.178.252.229:5060 enc=0 bytes=387
2009/01/01 12:41:13.181 INF: [CID=0xa651360e] >>> SIP/2.0 408 Alerting Timeout Method(INVITE) DST: 172.16.1.2:5060:UDP SRC=172.16.1.254:5061 enc=0 bytes=839
2009/01/01 12:41:13.192 INF: [CID=0xa651360e] <<< ACK sip:mpicher at sipphone dot com SIP/2.0 Method(ACK) SRC: 172.16.1.2:5060:UDP enc=0 bytes=471
2009/01/01 12:41:13.668 INF: [CID=0xa651360e] >>> CANCEL sip:mpicher at sipphone dot com SIP/2.0 Method(CANCEL) DST: 198.65.166.139:5060:UDP SRC: 76.178.252.229:5060 enc=0 bytes=387
2009/01/01 12:41:14.668 INF: [CID=0xa651360e] >>> CANCEL sip:mpicher at sipphone dot com SIP/2.0 Method(CANCEL) DST: 198.65.166.139:5060:UDP SRC: 76.178.252.229:5060 enc=0 bytes=387
2009/01/01 12:41:16.668 INF: [CID=0xa651360e] >>> CANCEL sip:mpicher at sipphone dot com SIP/2.0 Method(CANCEL) DST: 198.65.166.139:5060:UDP SRC: 76.178.252.229:5060 enc=0 bytes=387
2009/01/01 12:41:17.177 INF: [CID=0x8428bd16] *** DESTROYED B2BUA CONNECTION *** 0x0x887d130
2009/01/01 12:41:17.178 INF: [CID=0x8428bd16] *** COUNTERS *** ICT=0 NICT=1 IST=1 NIST=0 TIMERS=7 CALL=0 CONN=0 REG=0 RTP=0 QUEUE=1 CACHE=2 GC=4 TOTALCONN=3 TOTALREG=0 TOTALSZR=1 AVGDUR=3385 HIGHDUR=3385 IDLETIME=34
2009/01/01 12:41:17.178 INF: [CID=0xa651360e] *** DESTROYED CALL *** NmRiYzY2MzUzOWE5ZDMyNmNiMWEzM2M0MzdjZDhlMTc.
2009/01/01 12:41:20.668 INF: [CID=0xa651360e] >>> CANCEL sip:mpicher at sipphone dot com SIP/2.0 Method(CANCEL) DST: 198.65.166.139:5060:UDP SRC: 76.178.252.229:5060 enc=0 bytes=387

Here's a log of the call to mpicher at proxy01 dot sipphone dot com working:

2009/01/01 12:45:17.906 INF: [CID=0x78099be1] <<< INVITE sip:mpicher at proxy01 dot sipphone dot com SIP/2.0 Method(INVITE) SRC: 172.16.1.2:5060:UDP enc=0 bytes=1626
2009/01/01 12:45:17.910 INF: [CID=0x78099be1] *** CREATED (UAS) CALL *** YTU5MzRmN2RlMWUyYTU2ZTRmNTY2NThmNDgwMWNlZmU.
2009/01/01 12:45:17.911 INF: [CID=0x78099be1] *** NO REGISTRATION FOUND *** Fetching route through local database URI sip:mpicher at proxy01 dot sipphone dot com
2009/01/01 12:45:17.912 INF: [CID=0x78099be1] *** NO STATIC ROUTE *** URI sip:mpicher at proxy01 dot sipphone dot com
2009/01/01 12:45:17.913 INF: [CID=0x78099be1] *** UPPER REGISTRATION RELAY *** -->> Callee: sip:200 at xyzcompany dot com AOR: sip:mpicher at proxy01 dot sipphone dot com BINDING: sip:mpicher at proxy01 dot sipphone dot com
2009/01/01 12:45:17.915 INF: [CID=0x78099be1] *** CREATED (UAC) CALL *** YTU5MzRmN2RlMWUyYTU2ZTRmNTY2NThmNDgwMWNlZmU.-0x0004
2009/01/01 12:45:17.918 INF: [CID=0x78099be1] *** RTP Session CREATED *** l-addr=172.16.1.254 r-addr=172.16.1.129/172.16.1.129 r-port=34390
2009/01/01 12:45:17.926 INF: [CID=0x78099be1] >>> SIP/2.0 100 Trying Method(INVITE) DST: 172.16.1.2:5060:UDP SRC=172.16.1.254:5061 enc=0 bytes=606
2009/01/01 12:45:17.929 INF: [CID=0x78099be1] >>> INVITE sip:mpicher at proxy01 dot sipphone dot com SIP/2.0 Method(INVITE) DST: 198.65.166.131:5060:UDP SRC: 76.178.252.229:5060 enc=0 bytes=1359
2009/01/01 12:45:18.428 INF: [CID=0x78099be1] >>> INVITE sip:mpicher at proxy01 dot sipphone dot com SIP/2.0 Method(INVITE) DST: 198.65.166.131:5060:UDP SRC: 76.178.252.229:5060 enc=0 bytes=1359
2009/01/01 12:45:19.212 INF: [CID=0x78099be1] <<< SIP/2.0 100 Giving a try Method(INVITE) SRC: 198.65.166.131:5060:UDP enc=0 bytes=382
2009/01/01 12:45:19.216 INF: [CID=0x78099be1] <<< SIP/2.0 180 Ringing Method(INVITE) SRC: 198.65.166.131:5060:UDP enc=0 bytes=560
2009/01/01 12:45:19.226 INF: [CID=0x78099be1] >>> SIP/2.0 180 Ringing Method(INVITE) DST: 172.16.1.2:5060:UDP SRC=172.16.1.254:5061 enc=0 bytes=695
2009/01/01 12:45:20.429 INF: [CID=0x78099be1] <<< SIP/2.0 180 Ringing Method(INVITE) SRC: 198.65.166.131:5060:UDP enc=0 bytes=560
2009/01/01 12:45:20.436 INF: [CID=0x78099be1] >>> SIP/2.0 180 Ringing Method(INVITE) DST: 172.16.1.2:5060:UDP SRC=172.16.1.254:5061 enc=0 bytes=695
2009/01/01 12:45:20.881 INF: [CID=0x78099be1] <<< SIP/2.0 200 OK Method(INVITE) SRC: 198.65.166.131:5060:UDP enc=0 bytes=855
2009/01/01 12:45:20.886 INF: [CID=0x78099be1] *** RTP Session CREATED *** l-addr=76.178.252.229 r-addr=130.94.88.90/76.178.252.229 r-port=16416
2009/01/01 12:45:20.886 INF: [CID=0x78099be1] *** CALL ESTABLISHED *** YTU5MzRmN2RlMWUyYTU2ZTRmNTY2NThmNDgwMWNlZmU.-0x0004
2009/01/01 12:45:20.890 INF: [CID=0x78099be1] *** RTP PROXY STARTED ***
2009/01/01 12:45:20.892 INF: [CID=0x78099be1] >>> ACK sip:hostedvm17476040590@130.94.88.90 SIP/2.0 Method(ACK) DST: 198.65.166.131:5060:UDP SRC: 76.178.252.229:5060 enc=0 bytes=667
2009/01/01 12:45:20.893 INF: [CID=0x78099be1] *** RTP PROXY STARTED ***
2009/01/01 12:45:20.898 INF: [CID=0x78099be1] >>> SIP/2.0 200 OK Method(INVITE) DST: 172.16.1.2:5060:UDP SRC=172.16.1.254:5061 enc=0 bytes=1133
2009/01/01 12:45:21.092 INF: [CID=0x78099be1] <<< ACK sip:mpicher@172.16.1.254:5060 SIP/2.0 Method(ACK) SRC: 172.16.1.2:5060:UDP enc=0 bytes=895
2009/01/01 12:45:26.515 INF: [CID=0x78099be1] <<< BYE sip:mpicher@172.16.1.254:5060 SIP/2.0 Method(BYE) SRC: 172.16.1.2:5060:UDP enc=0 bytes=932
2009/01/01 12:45:26.526 INF: [CID=0x78099be1] *** CALL TEAR DOWN *** YTU5MzRmN2RlMWUyYTU2ZTRmNTY2NThmNDgwMWNlZmU.-connection
2009/01/01 12:45:26.526 INF: [CID=0x0cfee90a] *** B2BUA CONNECTION OnDestroySession ***
2009/01/01 12:45:26.527 INF: [CID=0x78099be1] *** RTP (Audio) Statistics *** addr=172.16.1.254:30012->172.16.1.129:34390 enc=0 rx=275 tx=269 lost=0 outOfOrder=0 late=0 rxTime=20/27 txTime=20/42 jitter=0/1
2009/01/01 12:45:26.529 INF: [CID=0x78099be1] >>> SIP/2.0 200 OK Method(BYE) DST: 172.16.1.2:5060:UDP SRC=172.16.1.254:5061 enc=0 bytes=526
2009/01/01 12:45:26.531 INF: [CID=0x78099be1] >>> BYE sip:hostedvm17476040590@130.94.88.90 SIP/2.0 Method(BYE) DST: 198.65.166.131:5060:UDP SRC: 76.178.252.229:5060 enc=0 bytes=722
2009/01/01 12:45:26.533 INF: [CID=0x78099be1] *** RTP PROXY STOPPED ***
2009/01/01 12:45:26.533 INF: [CID=0x78099be1] *** RTP PROXY STOPPED ***
2009/01/01 12:45:26.539 INF: [CID=0x78099be1] *** RTP (Audio) Statistics *** addr=76.178.252.229:30014->130.94.88.90:16416 enc=0 rx=269 tx=275 lost=7 outOfOrder=0 late=0 rxTime=19/22 txTime=20/27 jitter=1/5
2009/01/01 12:45:26.658 INF: [CID=0x78099be1] <<< SIP/2.0 200 OK Method(BYE) SRC: 198.65.166.131:5060:UDP enc=0 bytes=608
2009/01/01 12:45:31.541 INF: [CID=0x0cfee90a] *** DESTROYED B2BUA CONNECTION *** 0x0x886d4b0
2009/01/01 12:45:31.541 INF: [CID=0x0cfee90a] *** COUNTERS *** ICT=1 NICT=1 IST=0 NIST=1 TIMERS=12 CALL=0 CONN=0 REG=0 RTP=0 QUEUE=1 CACHE=2 GC=6 TOTALCONN=4 TOTALREG=0 TOTALSZR=2 AVGDUR=4507 HIGHDUR=5630 IDLETIME=14
2009/01/01 12:45:31.542 INF: [CID=0x78099be1] *** DESTROYED CALL *** YTU5MzRmN2RlMWUyYTU2ZTRmNTY2NThmNDgwMWNlZmU.
2009/01/01 12:45:31.543 INF: [CID=0x78099be1] *** DESTROYED CALL *** YTU5MzRmN2RlMWUyYTU2ZTRmNTY2NThmNDgwMWNlZmU.-0x0004