Re: [Users] OpenSer Sending CANCEL after receiving 200 OK for a INVITE

7 Dec 2006


      Really strange. Can you set debug=4 and and add the syslog logging from 
openser?
MAybe there is a timer (fr_inv_timer) which triggers the CANCEL?
regards
klaus
Md. Samiul Aftad Chowdhury wrote:
...
Hello Everyone,
I am facing a problem when I am trying  to send calls from my
Openser(1.1.0) to another
sip switch "MERA MSIP v.1.0.2."
Here is the scenario:
openser( PublicIP  54.15.32.44)  <====>  Mera Sip Switch(PublicIP 
52.132.36.43)
=======================                  ====================
             INVITE               ------------------>
                                      <-----------------    100 Trying
                                      <-----------------    183
Session Progress
                                      <-----------------    200 OK
(for invite)
             CANCEL            ------------------->
                                      <-----------------    200 OK (for 
cancel)
                                      <-----------------    487
Request Terminated
              ACK                 ------------------->
This seems quite normal scenario but problem is that this "CANCEL" is
not generated by the
end point (in my case IP Phone in public IP). This "CANCEL" generated
from openser just after receiving the 200 OK of INVITE request.
Why openser generating a CANCEL while it already received 200 OK for
that INVITE??
Here is my openser configuration script: openser.cfg
===========Script Begin============
debug=3
fork=yes
log_stderror=no
log_facility=LOG_LOCAL0
listen= 54.15.32.44
alias=" sip.myfone.com"
check_via=no
dns=no
rev_dns=no
port=5060
children=4
fifo="/tmp/openser_fifo"
mpath="/usr/local/openser/lib/openser/modules/"
loadmodule "exec.so"
loadmodule "sl.so  "
loadmodule "tm.so"
loadmodule "rr.so"
loadmodule "maxfwd.so"
loadmodule "usrloc.so"
loadmodule "registrar.so"
loadmodule "textops.so"
 loadmodule "xlog.so"
loadmodule "auth.so"
loadmodule "auth_radius.so"
loadmodule "acc.so"
loadmodule "nathelper.so"
modparam("usrloc", "db_mode",   0)
modparam("rr", "enable_full_lr", 1)
modparam("tm", "fr_inv_timer", 60 )
modparam("tm", "fr_timer", 30 )
modparam("acc", "radius_flag", 1 )
modparam("acc", "radius_missed_flag", 3 )
modparam("registrar", "nat_flag", 6)
modparam("nathelper", "natping_interval", 30)
modparam("nathelper", "ping_nated_only", 1)
route{
   if (!mf_process_maxfwd_header("10")) {
       sl_send_reply("483","Too Many Hops");
       exit;
   };
   if (msg:len >=  2048 ) {
       sl_send_reply("513", "Message too big");
       exit;
   };
route(2);
if (!method=="REGISTER")
       record_route();
   if(method=="INVITE" || method=="BYE")
       setflag(1);


if (loose_route()) {
       append_hf("P-hint: rr-enforced\r\n");
       route(1);
   };
if (!uri==myself) {
         append_hf("P-hint: outbound\r\n");
       route(1);
   };
   if (!method=="REGISTER") {
       rewritehostport(" 52.132.36.43:5060");
   };


if (uri==myself) {
       if (method=="REGISTER") {
                   if (!radius_www_authorize("54.15.32.44 ")) {
                       www_challenge(" 54.15.32.44", "0");
                              exit;
                   };
   if (isflagset(5)) {
               setflag(6);
           };
           save("location");
           exit;
       };
   if (!lookup("location")) {
       sl_send_reply("404", "Not Found");
       exit;
   };
   append_hf("P-hint: usrloc applied\r\n");
};


route(1);
}
route[1] {
   if (subst_uri('/(sip:.*);nat=yes/\1/')){
       setflag(6);
   };
   if (isflagset(5)||isflagset(6)) {
       route(3);
   }
     if (!t_relay()) {
       sl_reply_error();
   };
   exit;
}
route[2]{
   force_rport();
   if (nat_uac_test("19")) {
       if (method=="REGISTER") {
           fix_nated_register();
       } else {
           fix_nated_contact();
       };
       setflag(5);
   };
if (method=="INVITE") {
       xlog("L_INFO", "Invite -> [CallerID=$fu] and [DialedNo=$tU]\n");
               if(uri=~"sip:[0-9]{20,}@.+") {
                       sl_send_reply("513", "Message too big");
                       exit;
               };
       if (!radius_www_authorize("54.15.32.44")) {
           www_challenge(" 54.15.32.44", "0");
            exit;
       };
       consume_credentials();
   };
}
route[3] {
   if (is_method("BYE|CANCEL")) {
       unforce_rtp_proxy();
   } else if (is_method("INVITE")){
       if (!search("^Content-Length:[ ]*0")) {
           force_rtp_proxy();
       }
       t_on_failure("1");
   };
   if (isflagset(5))
       search_append('Contact:.*sip:[^>[:cntrl:]]*', ';nat=yes;lr=on');
   t_on_reply("1");
}
failure_route[1] {
   if(!t_was_cancelled())
   {
           rewritehostport("52.132.36.43:5060");
           xlog("L_INFO","Invite -> Fail Over Route 1  at Time [$Tf]");
           if (!search("^Content-Length:[ ]*0")){
               force_rtp_proxy();
           }
           t_on_failure("2");
           t_relay();
   }
}
failure_route[2] {
if(!t_was_cancelled())
  {
       rewritehostport("52.132.36.43:5060 ");
       xlog("L_INFO","Invite -> Fail Over Route 2  at Time [$Tf]");
       if (!search("^Content-Length:[ ]*0")) {
           force_rtp_proxy();
       }
       t_on_failure("3");
       t_relay();
  }
}
failure_route[3] {
if(!t_was_cancelled())
  {
       rewritehostport("52.132.36.43:5060");
       xlog("L_INFO","Invite -> Fail Over Route 3  at Time [$Tf]");
       if (!search("^Content-Length:[ ]*0")) {
           force_rtp_proxy();
       }
       t_on_failure("4");
       t_relay();
  }
}
failure_route[4] {
if(!t_was_cancelled()) {
       rewritehostport("52.132.36.43:5060");
       xlog("L_INFO","Invite -> Fail Over Route 4  at Time [$Tf]");
       if (!search("^Content-Length:[ ]*0")) {
               force_rtp_proxy();
       }
       t_on_failure("5");
       t_relay();
  }
}
failure_route[5] {
      if (isflagset(6) || isflagset(5)) {
              unforce_rtp_proxy();
      }
}
onreply_route[1] {
       if (!search("^Content-Length:[ ]*0")) {
           force_rtp_proxy("r");
       }
search_append('Contact:.*sip:[^>[:cntrl:]]*', ';nat=yes');
if (isflagset(6)) {
       fix_nated_contact();
   }
   exit;
}
=========Script End=============
Here is the sip trace (from ngrep):
IP Phone -> Openser
INVITE sip:9128222570841@54.15.32.44  SIP/2.0
Via: SIP/2.0/UDP  58.88.13.13:5060;branch=z9hG4bKI7LP7qhgGKFHt904;rport
Max-Forwards: 70
User-Agent: PA168S V1.55.013 CFG0
From: "561705239298" < sip:561705239298@54.15.32.44 >;tag=1pRGOP6lOmlBFIxy
To: "9128222570841" sip:9128222570841@54.15.32.44
Call-ID: BCmB3zph5YmuC8oz@58.88.13.13
Contact: sip:561705239298@58.88.13.13:5060
Authorization: Digest username="561705239298", realm=" 54.15.32.44",
nonce="4577de9a6e32a8fafc108687cbc9b520cfd2b1ea", uri="
sip:9128222570841@54.15.32.44",
response="c18eeb314b2c5524ab3d7fe50b52851c", algorithm=MD5
CSeq: 2 INVITE
Supported: replaces
Content-Type: application/sdp
Content-Length: 134
v=0
o=- 12369099 22311551 IN IP4 58.88.13.13
s=SIP CALL
c=IN IP4  58.88.13.13
t=0 0
m=audio 8704 RTP/AVP 4
a=rtpmap:4 G723/8000
Openser -> IP PHONE
SIP/2.0 100 trying -- your call is important to us
Via: SIP/2.0/UDP  
58.88.13.13:5060;branch=z9hG4bKI7LP7qhgGKFHt904;rport=5060
From: "561705239298" sip:561705239298@54.15.32.44;tag=1pRGOP6lOmlBFIxy
To: "9128222570841" <  sip:9128222570841@54.15.32.44>
Call-ID: BCmB3zph5YmuC8oz@58.88.13.13
CSeq: 2 INVITE
Server: openser (1.1.0 (i386/linux))
Content-Length: 0
Warning: 392  54.15.32.44:5060 "Noisy feedback tells:  pid=27317
req_src_ip= 58.88.13.13 req_src_port=5060 in_uri=
sip:9128222570841@54.15.32.44
out_uri=sip:9128222570841@52.132.36.43:5060 via_cnt==1"
Openser -> Mera
INVITE sip:9128222570841@52.132.36.43:5060 SIP/2.0
Record-Route: <sip:  54.15.32.44;lr=on;ftag=1pRGOP6lOmlBFIxy>
Via: SIP/2.0/UDP 54.15.32.44;branch=z9hG4bKa124.220e0023.0
Via: SIP/2.0/UDP  
58.88.13.13:5060;branch=z9hG4bKI7LP7qhgGKFHt904;rport=5060
Max-Forwards: 69
User-Agent: PA168S V1.55.013 CFG0
From: "561705239298" <sip:561705239298@54.15.32.44 >;tag=1pRGOP6lOmlBFIxy
To: "9128222570841" < sip:9128222570841@54.15.32.44>
Call-ID:  BCmB3zph5YmuC8oz@58.88.13.13
Contact: <sip:561705239298@58.88.13.13 :5060>
CSeq: 2 INVITE
Supported: replaces
Content-Type: application/sdp
Content-Length: 152
v=0
o=- 12369099 22311551 IN IP4  58.88.13.13
s=SIP CALL
c=IN IP4 54.15.32.44
t=0 0
m=audio 39780 RTP/AVP 4
a=rtpmap:4 G723/8000
a=nortpproxy:yes
Openser -> Mera
INVITE sip:9128222570841@52.132.36.43:5060 SIP/2.0
Record-Route: sip:54.15.32.44;lr=on;ftag=1pRGOP6lOmlBFIxy
Via: SIP/2.0/UDP  54.15.32.44;branch=z9hG4bKa124.220e0023.1
Via: SIP/2.0/UDP  
58.88.13.13:5060;branch=z9hG4bKI7LP7qhgGKFHt904;rport=5060
Max-Forwards: 69
User-Agent: PA168S V1.55.013 CFG0
From: "561705239298" < sip:561705239298@54.15.32.44 >;tag=1pRGOP6lOmlBFIxy
To: "9128222570841" sip:9128222570841@54.15.32.44
Call-ID: BCmB3zph5YmuC8oz@58.88.13.13
Contact: sip:561705239298@58.88.13.13:5060
CSeq: 2 INVITE
Supported: replaces
Content-Type: application/sdp
Content-Length: 152
v=0
o=- 12369099 22311551 IN IP4   58.88.13.13
s=SIP CALL
c=IN IP4 54.15.32.44
t=0 0
m=audio 39780 RTP/AVP 4
a=rtpmap:4 G723/8000
a=nortpproxy:yes
Mera -> Openser
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 54.15.32.44;branch=z9hG4bKa124.220e0023.0,SIP/2.0/UDP
58.88.13.13:5060;branch=z9hG4bKI7LP7qhgGKFHt904;rport=5060
From: "561705239298" < sip:561705239298@54.15.32.44>;tag=1pRGOP6lOmlBFIxy
To: "9128222570841" < sip:9128222570841@54.15.32.44 >
Call-ID: BCmB3zph5YmuC8oz@58.88.13.13
CSeq: 2 INVITE
Content-Length: 0
Mera -> Openser
SIP/2.0 100 Trying
Via: SIP/2.0/UDP
54.15.32.44;branch=z9hG4bKa124.220e0023.1,SIP/2.0/UDP
58.88.13.13:5060;branch=z9hG4bKI7LP7qhgGKFHt904;rport=5060
From: "561705239298" < sip:561705239298@54.15.32.44>;tag=1pRGOP6lOmlBFIxy
To: "9128222570841" <sip:9128222570841@54.15.32.44 >
Call-ID:  BCmB3zph5YmuC8oz@58.88.13.13
CSeq: 2 INVITE
Content-Length: 0
Mera -> Openser
SIP/2.0 183 Session Progress
Record-Route: <sip: 52.132.36.43:5060
;lr>,sip:54.15.32.44;lr=on;ftag=1pRGOP6lOmlBFIxy
From: "561705239298" < sip:561705239298@54.15.32.44>;tag=1pRGOP6lOmlBFIxy
To: "9128222570841"
sip:9128222570841@54.15.32.44;tag=ffff1200ffff7710ff00000f1f6fff3b
Call-ID: BCmB3zph5YmuC8oz@58.88.13.13
CSeq: 2 INVITE
Server: MERA MSIP v.1.0.2
Contact: sip:9128222570841@52.132.36.43:5060
Content-Type: application/sdp
Via: SIP/2.0/UDP  54.15.32.44:5060;branch=
z9hG4bKa124.220e0023.0,SIP/2.0/UDP
58.88.13.13:5060;branch=z9hG4bKI7LP7qhgGKFHt904;rport=5060
Content-Length: 136
v=0
o=- 1165483497 1165483497 IN IP4  52.132.36.43
s=-
c=IN IP4 52.132.36.43
t=0 0
m=audio 37548 RTP/AVP 4
a=rtpmap:4 G723/8000
Openser  -> IP PHONE
SIP/2.0 183 Session Progress
Record-Route: sip:52.132.36.43:5060;lr,sip:
54.15.32.44;lr=on;ftag=1pRGOP6lOmlBFIxy
From: "561705239298" < sip:561705239298@54.15.32.44>;tag=1pRGOP6lOmlBFIxy
To: "9128222570841" <sip:9128222570841@54.15.32.44
...
;tag=ffff1200ffff7710ff00000f1f6fff3b
Call-ID: BCmB3zph5YmuC8oz@58.88.13.13
CSeq: 2 INVITE
Server: MERA MSIP v.1.0.2
Contact: < sip:9128222570841@52.132.36.43:5060;nat=yes>
Content-Type: application/sdp
Via: SIP/2.0/UDP 58.88.13.13:5060;branch=z9hG4bKI7LP7qhgGKFHt904;rport=5060
Content-Length: 153
v=0
o=- 1165483497 1165483497 IN IP4  52.132.36.43
s=-
c=IN IP4 54.15.32.44
t=0 0
m=audio 39784 RTP/AVP 4
a=rtpmap:4 G723/8000
a=nortpproxy:yes
Mera -> Openser
SIP/2.0 200 OK
Record-Route: sip:52.132.36.43:5060;lr,sip:
54.15.32.44;lr=on;ftag=1pRGOP6lOmlBFIxy
From: "561705239298" < sip:561705239298@54.15.32.44>;tag=1pRGOP6lOmlBFIxy.
To: "9128222570841" <sip:9128222570841@54.15.32.44
...
;tag=ffff1200ffff7710ff00000f1f6fff3b
Call-ID: BCmB3zph5YmuC8oz@58.88.13.13
CSeq: 2 INVITE
Server: MERA MSIP v.1.0.2
Contact: < sip:9128222570841@52.132.36.43:5060>
Content-Type: application/sdp
Via: SIP/2.0/UDP
54.15.32.44:5060;branch=z9hG4bKa124.220e0023.0,SIP/2.0/UDP
58.88.13.13:5060;branch=z9hG4bKI7LP7qhgGKFHt904;rport=5060
Content-Length: 136
v=0
o=- 1165483501 1165483501 IN IP4 52.132.36.43
s=
c=IN IP4  52.132.36.43
t=0 0
m=audio 37548 RTP/AVP 4
a=rtpmap:4 G723/8000
Openser -> IP Phone
SIP/2.0 200 OK
Record-Route: sip:52.132.36.43:5060;lr,sip:
54.15.32.44;lr=on;ftag=1pRGOP6lOmlBFIxy
From: "561705239298" sip:561705239298@54.15.32.44;tag=1pRGOP6lOmlBFIxy
To: "9128222570841" <
sip:9128222570841@54.15.32.44>;tag=ffff1200ffff7710ff00000f1f6fff3b
Call-ID: BCmB3zph5YmuC8oz@58.88.13.13
CSeq: 2 INVITE
Server: MERA MSIP v.1.0.2
Contact: < sip:9128222570841@52.132.36.43:5060;nat=yes>
Content-Type: application/sdp
Via: SIP/2.0/UDP 58.88.13.13:5060 
;branch=z9hG4bKI7LP7qhgGKFHt904;rport=5060
Content-Length: 153
v=0
o=- 1165483501 1165483501 IN IP4 52.132.36.43
s=-
c=IN IP4  54.15.32.44
t=0 0
m=audio 39784 RTP/AVP 4
a=rtpmap:4 G723/8000
a=nortpproxy:yes
Openser -> Mera
CANCEL sip:9128222570841@52.132.36.43:5060 SIP/2.0.
Via: SIP/2.0/UDP  54.15.32.44;branch=z9hG4bKa124.220e0023.1
From: "561705239298" < sip:561705239298@54.15.32.44>;tag=1pRGOP6lOmlBFIxy
Call-ID:  BCmB3zph5YmuC8oz@58.88.13.13
To: "9128222570841" < sip:9128222570841@54.15.32.44>
CSeq: 2 CANCEL
User-Agent: openser (1.1.0 (i386/linux))
Content-Length: 0
Mera -> Openser
SIP/2.0 200 OK
Via: SIP/2.0/UDP 54.15.32.44;branch=z9hG4bKa124.220e0023.1
From: "561705239298" < sip:561705239298@54.15.32.44>;tag=1pRGOP6lOmlBFIxy
Call-ID: BCmB3zph5YmuC8oz@58.88.13.13
To: "9128222570841" < sip:9128222570841@54.15.32.44>
CSeq: 2 CANCEL
Content-Length: 0
Mera -> Openser
SIP/2.0 487 Request Terminated
Via: SIP/2.0/UDP 54.15.32.44 ;branch=z9hG4bKa124.220e0023.1
From: "561705239298" < sip:561705239298@54.15.32.44>;tag=1pRGOP6lOmlBFIxy
Call-ID:  BCmB3zph5YmuC8oz@58.88.13.13
To: "9128222570841" < sip:9128222570841@54.15.32.44>
CSeq: 2 INVITE
Content-Length: 0
Openser -> Mera
ACK sip:9128222570841@52.132.36.43:5060 SIP/2.0
Via: SIP/2.0/UDP  54.15.32.44;branch=z9hG4bKa124.220e0023.1
From: "561705239298" < sip:561705239298@54.15.32.44>;tag=1pRGOP6lOmlBFIxy
Call-ID: BCmB3zph5YmuC8oz@58.88.13.13
To: "9128222570841" < sip:9128222570841@54.15.32.44>
CSeq: 2 ACK
User-Agent: openser (1.1.0 (i386/linux))
Content-Length: 0
IP Phone -> Openser
ACK sip:9128222570841@52.132.36.43:5060 SIP/2.0
Via: SIP/2.0/UDP  58.88.13.13:5060 ;branch=z9hG4bKTpMoqwSSNb4LvZrv;rport
Route: sip:54.15.32.44;lr=on;ftag=1pRGOP6lOmlBFIxy, sip:
52.132.36.43:5060;lr
Max-Forwards: 70
User-Agent: PA168S V1.55.013 CFG0
From: "561705239298" <sip:561705239298@54.15.32.44 >;tag=1pRGOP6lOmlBFIxy
To: "9128222570841" <
sip:9128222570841@54.15.32.44>;tag=ffff1200ffff7710ff00000f1f6fff3b
Call-ID: BCmB3zph5YmuC8oz@58.88.13.13
Contact: sip:561705239298@58.88.13.13:5060
Authorization: Digest username="561705239298", realm="54.15.32.44",
nonce="4577de9a6e32a8fafc108687cbc9b520cfd2b1ea", uri="
sip:9128222570841@52.132.36.43 :5060",
response="7b312414aa7de3f999f8fd4a2a81c8a5", algorithm=MD5
CSeq: 2 ACK
Content-Length: 0
Openser -> Mera
ACK sip:9128222570841@52.132.36.43:5060 SIP/2.0
Record-Route: <sip: 54.15.32.44;lr=on;ftag=1pRGOP6lOmlBFIxy>
Via: SIP/2.0/UDP  54.15.32.44;branch=z9hG4bKa124.220e0023.3
Via: SIP/2.0/UDP  
58.88.13.13:5060;branch=z9hG4bKTpMoqwSSNb4LvZrv;rport=5060
Route: sip:52.132.36.43:5060;lr
Max-Forwards: 69
User-Agent: PA168S V1.55.013 CFG0
From: "561705239298" < sip:561705239298@54.15.32.44>;tag=1pRGOP6lOmlBFIxy
To: "9128222570841" < sip:9128222570841@54.15.32.44
...
;tag=ffff1200ffff7710ff00000f1f6fff3b
Call-ID: BCmB3zph5YmuC8oz@58.88.13.13
Contact: < sip:561705239298@58.88.13.13:5060>
Authorization: Digest username="561705239298", realm=" 54.15.32.44",
nonce="4577de9a6e32a8fafc108687cbc9b520cfd2b1ea",
uri="sip:9128222570841@52.132.36.43 :5060",
response="7b312414aa7de3f999f8fd4a2a81c8a5", algorithm=MD5
CSeq: 2 ACK
Content-Length: 0
P-hint: rr-enforced
==
Regards,
Aftad

Users mailing list
Users@openser.org
http://openser.org/cgi-bin/mailman/listinfo/users
-- 
Klaus Darilion
nic.at

2025

2024

2023

2022

2021

2020

2019

2018

2017

2016

2015

2014

2013

2012

2011

2010

2009

2008

2007

2006

2005

2004

2003

2002

Re: [Users] OpenSer Sending CANCEL after receiving 200 OK for a INVITE