<div>I send SIP trace</div><div> </div><div>======== Opensips send 200 OK ==============</div><p>SIP/2.0 200 OK<br>Via: SIP/2.0/UDP 11.22.33.44;rport=5060;received=11.22.33.44;branch=z9hG4bK3bd4.e77f7ba1.0<br>Via: SIP/2.0/UDP 66.55.44.33:59050;received=66.55.44.33;rport=59050;branch=z9hG4bKPjbf00VZezyzupEI5-i2gZmPGnwFiQay9a<br>
Record-Route: <sip:77.88.100.100;lr;ftag=gTVXPlnKDDix.LcLHTy4Ck9s5gLwC4PU;did=c39.cf631a61><br>Record-Route: <sip:11.22.33.44;lr;ftag=gTVXPlnKDDix.LcLHTy4Ck9s5gLwC4PU;did=c39.c01d4622><br>From: <<a href="mailto:sip%3A77@11.22.33.44">sip:77@11.22.33.44</a>>;tag=gTVXPlnKDDix.LcLHTy4Ck9s5gLwC4PU<br>
To: <<a href="mailto:sip%3A15488@11.22.33.44">sip:15488@11.22.33.44</a>>;tag=as776fa5cd<br>Call-ID: tcwJKO8ofRAPk3ndJbSfW4k5envUn9Bf<br>CSeq: 22589 INVITE<br>Server: ITG SBC<br>Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH<br>
Supported: replaces, timer<br>Session-Expires: 1800;refresher=uas<br>Contact: <<a href="http://sip:15488@87.11.11.11:2000">sip:15488@87.11.11.11:2000</a>><br>Content-Type: application/sdp<br>Content-Length: 325</p><p>
v=0<br>o=root 1580617885 1580617886 IN IP4 87.11.11.11<br>s=ITG SBC<br>c=IN IP4 87.11.11.11<br>t=0 0<br>m=audio 10466 RTP/AVP 8 0 18 101<br>a=rtpmap:8 PCMA/8000<br>a=rtpmap:0 PCMU/8000<br>a=rtpmap:18 G729/8000<br>a=fmtp:18 annexb=no<br>
a=rtpmap:101 telephone-event/8000<br>a=fmtp:101 0-16<br>a=silenceSupp:off - - - -<br>a=ptime:20<br>a=sendrecv</p><p>======== Opensips receive ACK ==============</p><p>ACK <a href="http://sip:15488@77.88.100.100:5060">sip:15488@77.88.100.100:5060</a> SIP/2.0<br>
Via: SIP/2.0/UDP 11.22.33.44;branch=z9hG4bK3bd4.e77f7ba1.2<br>Via: SIP/2.0/UDP 66.55.44.33:59050;received=66.55.44.33;rport=59050;branch=z9hG4bKPjhAnLMELM0.GE.jF7GfflQnGmFz8oMoGv<br>Max-Forwards: 69<br>From: <<a href="mailto:sip%3A77@11.22.33.44">sip:77@11.22.33.44</a>>;tag=gTVXPlnKDDix.LcLHTy4Ck9s5gLwC4PU<br>
To: <<a href="mailto:sip%3A15488@11.22.33.44">sip:15488@11.22.33.44</a>>;tag=as776fa5cd<br>Call-ID: tcwJKO8ofRAPk3ndJbSfW4k5envUn9Bf<br>CSeq: 22589 ACK<br>Route: <sip:77.88.100.100;lr;ftag=gTVXPlnKDDix.LcLHTy4Ck9s5gLwC4PU;did=c39.cf631a61><br>
Content-Length: 0</p><p><br>======= trace log 6 ========================</p><p>Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:core:parse_msg: SIP Request:<br>Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:core:parse_msg: method: <ACK><br>
Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:core:parse_msg: uri: <<a href="http://sip:15488@77.88.100.100:5060">sip:15488@77.88.100.100:5060</a>><br>Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:core:parse_msg: version: <SIP/2.0><br>
Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:core:parse_headers: flags=2<br>Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:core:parse_via_param: found param type 232, <branch> = <z9hG4bK3bd4.e77f7ba1.2>; state=16<br>
Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:core:parse_via: end of header reached, state=5<br>Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:core:parse_headers: via found, flags=2<br>Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:core:parse_headers: this is the first via<br>
Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:core:receive_msg: After parse_msg...<br>Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:core:receive_msg: preparing to run routing scripts...<br>
Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:sl:sl_filter_ACK: to late to be a local ACK!<br>Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: ++++++++++++++++++++++++++++++++++++++++++++++++++++ from <a href="http://11.22.33.44:5060">11.22.33.44:5060</a><br>
Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:core:parse_headers: flags=100<br>Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:core:parse_via_param: found param type 234, <received> = <66.55.44.33>; state=6<br>
Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:core:parse_via_param: found param type 235, <rport> = <59050>; state=6<br>Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:core:parse_via_param: found param type 232, <branch> = <z9hG4bKPjhAnLMELM0.GE.jF7GfflQnGmFz8oMoGv>; state=16<br>
Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:core:parse_via: end of header reached, state=5<br>Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:core:parse_headers: via found, flags=100<br>Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:core:parse_headers: parse_headers: this is the second via<br>
Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:maxfwd:is_maxfwd_present: value = 69 <br>Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: ASK from <a href="http://11.22.33.44:5060">11.22.33.44:5060</a><br>
Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:core:parse_headers: flags=ffffffffffffffff<br>Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:core:parse_to_param: tag=as776fa5cd<br>Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:core:parse_to: end of header reached, state=29<br>
Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:core:parse_to: display={}, ruri={<a href="mailto:sip%3A15488@11.22.33.44">sip:15488@11.22.33.44</a>}<br>Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:core:get_hdr_field: <To> [40]; uri=[<a href="mailto:sip%3A15488@11.22.33.44">sip:15488@11.22.33.44</a>] <br>
Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:core:get_hdr_field: to body [<<a href="mailto:sip%3A15488@11.22.33.44">sip:15488@11.22.33.44</a>>]<br>Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:core:get_hdr_field: cseq <CSeq>: <22589> <ACK><br>
Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:core:get_hdr_field: content_length=0<br>Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:core:get_hdr_field: found end of header<br>Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:core:parse_headers: flags=ffffffffffffffff<br>
Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:core:parse_headers: flags=ffffffffffffffff<br>Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:uri:has_totag: totag found<br>Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: Have to_tag <br>
Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:core:parse_headers: flags=200<br>Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:rr:is_preloaded: is_preloaded: No<br>Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:core:grep_sock_info: checking if host==us: 14==9 && [77.88.100.100] == [127.0.0.1]<br>
Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:core:grep_sock_info: checking if port 5060 matches port 5060<br>Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:core:grep_sock_info: checking if host==us: 14==14 && [77.88.100.100] == [77.88.100.100]<br>
Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:core:grep_sock_info: checking if port 5060 matches port 5060<br>Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:rr:after_strict: Next hop: 'sip:77.88.100.100;lr;ftag=gTVXPlnKDDix.LcLHTy4Ck9s5gLwC4PU;did=c39.cf631a61' is loose router<br>
Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:core:parse_headers: flags=ffffffffffffffff<br>Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:rr:after_strict: The last route URI: 'sip:77.88.100.100;lr;ftag=gTVXPlnKDDix.LcLHTy4Ck9s5gLwC4PU;did=c39.cf631a61'<br>
Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:rr:run_rr_callbacks: callback id 1 entered with <><br>Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:dialog:dlg_onroute: Route param 'did' not found<br>
Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:rr:run_rr_callbacks: callback id 2 entered with <><br>Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:uac:restore_uri: getting 'vsf' Route param<br>
Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:uac:restore_uri: route param 'vsf' not found<br>Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:uac:restore_uri: getting 'vst' Route param<br>
Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:uac:restore_uri: route param 'vst' not found<br>Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: Sip Trace = <null><br>Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:siptrace:sip_trace: nothing to trace...<br>
Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: Has totag - loose route. Trace id <null><br>Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:tm:t_newtran: transaction on entrance=0xffffffffffffffff<br>
Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:core:parse_headers: flags=ffffffffffffffff<br>Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:core:parse_headers: flags=78<br>Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:tm:t_lookup_request: start searching: hash=19891, isACK=1<br>
Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:core:parse_headers: flags=38<br>Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:core:parse_to_param: tag=gTVXPlnKDDix.LcLHTy4Ck9s5gLwC4PU<br>Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:core:parse_to: end of header reached, state=29<br>
Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:core:parse_to: display={}, ruri={<a href="mailto:sip%3A77@11.22.33.44">sip:77@11.22.33.44</a>}<br>Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:tm:t_lookup_request: REF_UNSAFE:[0x7f90daccda40] after is 1<br>
Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:tm:t_lookup_request: e2e proxy ACK found<br>Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:tm:t_newtran: building branch for end2end ACK - flags=1<br>
Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:tm:t_relay_to: forwarding ACK<br>Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:core:mk_proxy: doing DNS lookup...<br>Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:core:forward_request: sending:#012ACK sip:77.88.100.100;lr;ftag=gTVXPlnKDDix.LcLHTy4Ck9s5gLwC4PU;did=c39.cf631a61 SIP/2.0#015#012Via: SIP/2.0/UDP 77.88.100.100;branch=z9hG4bK3bd4.ad913244.2#015#012Via: SIP/2.0/UDP 11.22.33.44;rport=5060;received=11.22.33.44;branch=z9hG4bK3bd4.e77f7ba1.2#015#012Via: SIP/2.0/UDP 66.55.44.33:59050;received=66.55.44.33;rport=59050;branch=z9hG4bKPjhAnLMELM0.GE.jF7GfflQnGmFz8oMoGv#015#012Max-Forwards: 68#015#012From: <<a href="mailto:sip%3A77@11.22.33.44">sip:77@11.22.33.44</a>>;tag=gTVXPlnKDDix.LcLHTy4Ck9s5gLwC4PU#015#012To: <<a href="mailto:sip%3A15488@11.22.33.44">sip:15488@11.22.33.44</a>>;tag=as776fa5cd#015#012Call-ID: tcwJKO8ofRAPk3ndJbSfW4k5envUn9Bf#015#012CSeq: 22589 ACK#015#012Content-Length: 0#015#012#015#012.<br>
Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:core:forward_request: orig. len=517, new_len=572, proto=1<br>Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:core:run_fwd_callbacks: FWD callback entered<br>
Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:siptrace:trace_msg_out: trace off...<br>Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: Send relay.<br>Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:tm:t_unref_cell: UNREF_UNSAFE: [0x7f90daccda40] after is 0<br>
Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:core:destroy_avp_list: destroying list 0x7f90dacd12c0<br>Apr 20 14:16:41 routecall /usr/local/sbin/opensips[31138]: DBG:core:receive_msg: cleaning up</p><p><br>
<br> </p><div class="gmail_quote">2012/4/20 Vlad Paiu <span dir="ltr"><<a href="mailto:vladpaiu@opensips.org">vladpaiu@opensips.org</a>></span><br><blockquote style="margin:0px 0px 0px 0.8ex;padding-left:1ex;border-left-color:rgb(204,204,204);border-left-width:1px;border-left-style:solid" class="gmail_quote">
<u></u>
<div text="#000000" bgcolor="#ffffff">
Hi,<br>
<br>
Please post the SIP trace as well, for the entire dialog.<br>
<br>
Regards,<br>
<pre cols="72">Vlad Paiu
OpenSIPS Developer
<a href="http://www.opensips-solutions.com" target="_blank">http://www.opensips-solutions.com</a> </pre><div><div class="h5">
<br>
On 04/20/2012 02:26 AM, goup2010 wrote:
</div></div><blockquote type="cite"><div><div class="h5">
<div>Hello,</div>
<div> </div>
<div>I use latest openisps 1.8 by follow sheme:</div>
<div> </div>
<div>opensips 1 (11.22.33.44)-----> opensips 2 <a href="tel:%2877.88.100.100" target="_blank" value="+17788100100">(77.88.100.100</a>)</div>
<div> </div>
<div>When opensips 2 receive ACK I see follow problems:</div>
<div> </div>
<div>1. In trace debug 6 I see message:</div>
<div>" DBG:sipmsgops:sip_validate_hdrs: duplicate header
'Content-Length' "</div>
<div> </div>
<div>There is not duplicate header. </div>
<div> </div>
<div>2. The ACK messages not find dialog parameters.</div>
<div> </div>
<div>Here is debug 6 :</div>
<div> </div>
<div>: DBG:core:parse_msg: SIP Request:<br>
: DBG:core:parse_msg: method: <ACK><br>
: DBG:core:parse_msg: uri: <<a href="http://sip:15488@77.88.100.100:5060" target="_blank">sip:15488@77.88.100.100:5060</a>><br>
: DBG:core:parse_msg: version: <SIP/2.0><br>
: DBG:core:parse_headers: flags=2<br>
: DBG:core:parse_via_param: found param type 232,
<branch> = <z9hG4bK1af6.8dfa1be2.2>; state=16<br>
: DBG:core:parse_via: end of header reached, state=5<br>
: DBG:core:parse_headers: via found, flags=2<br>
: DBG:core:parse_headers: this is the first via<br>
: DBG:core:receive_msg: After parse_msg...<br>
: DBG:core:receive_msg: preparing to run routing scripts...<br>
: DBG:sl:sl_filter_ACK: to late to be a local ACK!<br>
: ++++++++++++++++++++++++++++++++++++++++++++++++++++ from <a href="http://11.22.33.44:5060" target="_blank">11.22.33.44:5060</a><br>
: DBG:core:parse_headers: flags=ffffffffffffffff<br>
: DBG:core:parse_via_param: found param type 234,
<received> = <66.55.44.33>; state=6<br>
: DBG:core:parse_via_param: found param type 235, <rport>
= <52625>; state=6<br>
: DBG:core:parse_via_param: found param type 232,
<branch> =
<z9hG4bKPj-BqsfN5zeuwvMDMyA9q93C5tBdFaeMZg>; state=16<br>
: DBG:core:parse_via: end of header reached, state=5<br>
: DBG:core:parse_headers: via found, flags=ffffffffffffffff<br>
: DBG:core:parse_headers: parse_headers: this is the second via<br>
: DBG:core:parse_to_param: tag=as25148399<br>
: DBG:core:parse_to: end of header reached, state=29<br>
: DBG:core:parse_to: display={}, ruri={<a href="mailto:sip%3A15488@11.22.33.44" target="_blank">sip:15488@11.22.33.44</a>}<br>
: DBG:core:get_hdr_field: <To> [40]; uri=[<a href="mailto:sip%3A15488@11.22.33.44" target="_blank">sip:15488@11.22.33.44</a>]
<br>
: DBG:core:get_hdr_field: to body [<<a href="mailto:sip%3A15488@11.22.33.44" target="_blank">sip:15488@11.22.33.44</a>>]<br>
: DBG:core:get_hdr_field: cseq <CSeq>: <31391>
<ACK><br>
: DBG:core:get_hdr_field: content_length=0<br>
: DBG:core:get_hdr_field: found end of header<br>
: DBG:core:parse_to_param: tag=9XfR8ZmrUUho2HYFHzJ3tYmiMp2GbOXj<br>
: DBG:core:parse_to: end of header reached, state=29<br>
: DBG:core:parse_to: display={}, ruri={<a href="mailto:sip%3A77@11.22.33.44" target="_blank">sip:77@11.22.33.44</a>}<br>
: DBG:sipmsgops:sip_validate_hdrs: duplicate header
'Content-Length'<br>
: ASK from <a href="http://11.22.33.44:5060" target="_blank">11.22.33.44:5060</a><br>
: DBG:core:parse_headers: flags=ffffffffffffffff<br>
: DBG:core:parse_headers: flags=ffffffffffffffff<br>
: DBG:core:parse_headers: flags=ffffffffffffffff<br>
: DBG:uri:has_totag: totag found<br>
: Have to_tag <br>
: DBG:core:parse_headers: flags=200<br>
: DBG:rr:is_preloaded: is_preloaded: No<br>
: DBG:core:grep_sock_info: checking if host==us: 14==9
&& <a href="tel:%5B77.88.100.100" target="_blank" value="+17788100100">[77.88.100.100</a>] == [127.0.0.1]<br>
: DBG:core:grep_sock_info: checking if port 5060 matches port
5060<br>
: DBG:core:grep_sock_info: checking if host==us: 14==14
&& <a href="tel:%5B77.88.100.100" target="_blank" value="+17788100100">[77.88.100.100</a>] == <a href="tel:%5B77.88.100.100" target="_blank" value="+17788100100">[77.88.100.100</a>]<br>
: DBG:core:grep_sock_info: checking if port 5060 matches port
5060<br>
: DBG:rr:after_strict: Next hop:
'sip:<a href="tel:77.88.100.100" target="_blank" value="+17788100100">77.88.100.100</a>;lr;ftag=9XfR8ZmrUUho2HYFHzJ3tYmiMp2GbOXj;did=706.c142d645'
is loose router<br>
: DBG:core:parse_headers: flags=ffffffffffffffff<br>
: DBG:rr:after_strict: The last route URI:
'sip:<a href="tel:77.88.100.100" target="_blank" value="+17788100100">77.88.100.100</a>;lr;ftag=9XfR8ZmrUUho2HYFHzJ3tYmiMp2GbOXj;did=706.c142d645'<br>
: DBG:rr:run_rr_callbacks: callback id 1 entered with <><br>
: DBG:dialog:dlg_onroute: Route param 'did' not found<br>
: DBG:rr:run_rr_callbacks: callback id 2 entered with <><br>
: DBG:uac:restore_uri: getting 'vsf' Route param<br>
: DBG:uac:restore_uri: route param 'vsf' not found<br>
: DBG:uac:restore_uri: getting 'vst' Route param<br>
: DBG:uac:restore_uri: route param 'vst' not found<br>
: Sip Trace = <null><br>
: DBG:siptrace:sip_trace: nothing to trace...<br>
: Has totag - loose route. Trace id <null><br>
: DBG:tm:t_newtran: transaction on entrance=0xffffffffffffffff<br>
: DBG:core:parse_headers: flags=ffffffffffffffff<br>
: DBG:core:parse_headers: flags=78<br>
: DBG:tm:t_lookup_request: start searching: hash=28577, isACK=1<br>
: DBG:core:parse_headers: flags=38<br>
: DBG:tm:t_lookup_request: REF_UNSAFE:[0x7f619a9e92f8] after is
1<br>
: DBG:tm:t_lookup_request: e2e proxy ACK found<br>
: DBG:tm:t_newtran: building branch for end2end ACK - flags=1<br>
: DBG:tm:t_relay_to: forwarding ACK<br>
: DBG:core:mk_proxy: doing DNS lookup...<br>
: DBG:core:forward_request: sending:#012ACK
sip:<a href="tel:77.88.100.100" target="_blank" value="+17788100100">77.88.100.100</a>;lr;ftag=9XfR8ZmrUUho2HYFHzJ3tYmiMp2GbOXj;did=706.c142d645
SIP/2.0#015#012Via: SIP/2.0/UDP
<a href="tel:77.88.100.100" target="_blank" value="+17788100100">77.88.100.100</a>;branch=z9hG4bK1af6.7d6364b3.2#015#012Via:
SIP/2.0/UDP
11.22.33.44;rport=5060;received=11.22.33.44;branch=z9hG4bK1af6.8dfa1be2.2#015#012Via:
SIP/2.0/UDP
66.55.44.33:52625;received=66.55.44.33;rport=52625;branch=z9hG4bKPj-BqsfN5zeuwvMDMyA9q93C5tBdFaeMZg#015#012Max-Forwards:
67#015#012From: <<a href="mailto:sip%3A77@11.22.33.44" target="_blank">sip:77@11.22.33.44</a>>;tag=9XfR8ZmrUUho2HYFHzJ3tYmiMp2GbOXj#015#012To:
<<a href="mailto:sip%3A15488@11.22.33.44" target="_blank">sip:15488@11.22.33.44</a>>;tag=as25148399#015#012Call-ID:
BFq2DLQKfpwA1bgpaaKLDeZJsLgtbDBA#015#012CSeq: 31391
ACK#015#012Content-Length: 0#015#012#015#012.<br>
: DBG:core:forward_request: orig. len=517, new_len=572, proto=1<br>
: DBG:core:run_fwd_callbacks: FWD callback entered<br>
: DBG:siptrace:trace_msg_out: trace off...<br>
: Send relay.<br>
: DBG:tm:t_unref_cell: UNREF_UNSAFE: [0x7f619a9e92f8] after is
0<br>
: DBG:core:destroy_avp_list: destroying list 0x7f619a9f3258<br>
: DBG:core:receive_msg: cleaning up<br>
: DBG:core:parse_msg: SIP Request:<br>
: DBG:core:parse_msg: method: <ACK><br>
: DBG:core:parse_msg: uri:
<sip:<a href="tel:77.88.100.100" target="_blank" value="+17788100100">77.88.100.100</a>;lr;ftag=9XfR8ZmrUUho2HYFHzJ3tYmiMp2GbOXj;did=706.c142d645><br>
: DBG:core:parse_msg: version: <SIP/2.0><br>
: DBG:core:parse_headers: flags=2<br>
: DBG:core:parse_via_param: found param type 232,
<branch> = <z9hG4bK1af6.7d6364b3.2>; state=16<br>
: DBG:core:parse_via: end of header reached, state=5<br>
: DBG:core:parse_headers: via found, flags=2<br>
: DBG:core:parse_headers: this is the first via<br>
: DBG:core:receive_msg: After parse_msg...<br>
: DBG:core:receive_msg: preparing to run routing scripts...<br>
: DBG:sl:sl_filter_ACK: to late to be a local ACK!<br>
: ++++++++++++++++++++++++++++++++++++++++++++++++++++ from <a href="http://77.88.100.100:5060" target="_blank">77.88.100.100:5060</a><br>
: DBG:core:parse_headers: flags=ffffffffffffffff<br>
: DBG:core:parse_via_param: found param type 235, <rport>
= <5060>; state=6<br>
: DBG:core:parse_via_param: found param type 234,
<received> = <11.22.33.44>; state=6<br>
: DBG:core:parse_via_param: found param type 232,
<branch> = <z9hG4bK1af6.8dfa1be2.2>; state=16<br>
: DBG:core:parse_via: end of header reached, state=5<br>
: DBG:core:parse_headers: via found, flags=ffffffffffffffff<br>
: DBG:core:parse_headers: parse_headers: this is the second via<br>
: DBG:core:parse_via_param: found param type 234,
<received> = <66.55.44.33>; state=6<br>
: DBG:core:parse_via_param: found param type 235, <rport>
= <52625>; state=6<br>
: DBG:core:parse_via_param: found param type 232,
<branch> =
<z9hG4bKPj-BqsfN5zeuwvMDMyA9q93C5tBdFaeMZg>; state=16<br>
: DBG:core:parse_via: end of header reached, state=5<br>
: DBG:core:parse_headers: via found, flags=ffffffffffffffff<br>
: DBG:core:parse_to_param: tag=as25148399<br>
: DBG:core:parse_to: end of header reached, state=29<br>
: DBG:core:parse_to: display={}, ruri={<a href="mailto:sip%3A15488@11.22.33.44" target="_blank">sip:15488@11.22.33.44</a>}<br>
: DBG:core:get_hdr_field: <To> [40]; uri=[<a href="mailto:sip%3A15488@11.22.33.44" target="_blank">sip:15488@11.22.33.44</a>]
<br>
: DBG:core:get_hdr_field: to body [<<a href="mailto:sip%3A15488@11.22.33.44" target="_blank">sip:15488@11.22.33.44</a>>]<br>
: DBG:core:get_hdr_field: cseq <CSeq>: <31391>
<ACK><br>
: DBG:core:get_hdr_field: content_length=0<br>
: DBG:core:get_hdr_field: found end of header<br>
: DBG:core:parse_to_param: tag=9XfR8ZmrUUho2HYFHzJ3tYmiMp2GbOXj<br>
: DBG:core:parse_to: end of header reached, state=29<br>
: DBG:core:parse_to: display={}, ruri={<a href="mailto:sip%3A77@11.22.33.44" target="_blank">sip:77@11.22.33.44</a>}<br>
: DBG:sipmsgops:sip_validate_hdrs: duplicate header
'Content-Length'<br>
: ASK from <a href="http://77.88.100.100:5060" target="_blank">77.88.100.100:5060</a><br>
: DBG:core:parse_headers: flags=ffffffffffffffff<br>
: DBG:core:parse_headers: flags=ffffffffffffffff<br>
: DBG:core:parse_headers: flags=ffffffffffffffff<br>
: DBG:uri:has_totag: totag found<br>
: Have to_tag <br>
: DBG:core:parse_headers: flags=200<br>
: DBG:rr:find_first_route: No Route headers found<br>
: DBG:rr:loose_route: There is no Route HF<br>
: ASK when not loose_route.<br>
: DBG:core:parse_headers: flags=78<br>
: DBG:tm:t_lookup_request: start searching: hash=28577, isACK=1<br>
: DBG:core:parse_headers: flags=38<br>
: DBG:tm:t_lookup_request: REF_UNSAFE:[0x7f619a9e92f8] after is
1<br>
: DBG:tm:t_lookup_request: e2e proxy ACK found<br>
: t_check_trans (YES).<br>
: DBG:tm:t_newtran: transaction on entrance=(nil)<br>
: DBG:core:parse_headers: flags=ffffffffffffffff<br>
: DBG:tm:t_newtran: building branch for end2end ACK - flags=1<br>
: DBG:tm:t_relay_to: forwarding ACK<br>
: DBG:core:mk_proxy: doing DNS lookup...<br>
: DBG:core:forward_request: sending:#012ACK
sip:<a href="tel:77.88.100.100" target="_blank" value="+17788100100">77.88.100.100</a>;lr;ftag=9XfR8ZmrUUho2HYFHzJ3tYmiMp2GbOXj;did=706.c142d645
SIP/2.0#015#012Via: SIP/2.0/UDP
<a href="tel:77.88.100.100" target="_blank" value="+17788100100">77.88.100.100</a>;branch=z9hG4bK1af6.7d6364b3.2#015#012Via:
SIP/2.0/UDP
<a href="tel:77.88.100.100" target="_blank" value="+17788100100">77.88.100.100</a>;rport=5060;received=<a href="tel:77.88.100.100" target="_blank" value="+17788100100">77.88.100.100</a>;branch=z9hG4bK1af6.7d6364b3.2#015#012Via:
SIP/2.0/UDP
11.22.33.44;rport=5060;received=11.22.33.44;branch=z9hG4bK1af6.8dfa1be2.2#015#012Via:
SIP/2.0/UDP
66.55.44.33:52625;received=66.55.44.33;rport=52625;branch=z9hG4bKPj-BqsfN5zeuwvMDMyA9q93C5tBdFaeMZg#015#012Max-Forwards:
65#015#012From: <<a href="mailto:sip%3A77@11.22.33.44" target="_blank">sip:77@11.22.33.44</a>>;tag=9XfR8ZmrUUho2HYFHzJ3tYmiMp2GbOXj#015#012To:
<<a href="mailto:sip%3A15488@11.22.33.44" target="_blank">sip:15488@11.22.33.44</a>>;tag=as25148399#015#012Call-ID:
BFq2DLQKfpwA1bgpaaKLDeZJsLgtbDBA#015#012CSeq: 31391
ACK#015#012Content-Length: 0#015#012#015#012.<br>
: DBG:core:forward_request: orig. len=572, new_len=670, proto=1<br>
: DBG:core:run_fwd_callbacks: FWD callback entered<br>
: DBG:siptrace:trace_msg_out: trace off...<br>
: Send relay.<br>
: DBG:tm:t_unref_cell: UNREF_UNSAFE: [0x7f619a9e92f8] after is
0<br>
: DBG:core:destroy_avp_list: destroying list 0x7f619a9f3258<br>
: DBG:core:receive_msg: cleaning up<br>
: DBG:core:parse_msg: SIP Request:<br>
: DBG:core:parse_msg: method: <ACK><br>
: DBG:core:parse_msg: uri:
<sip:<a href="tel:77.88.100.100" target="_blank" value="+17788100100">77.88.100.100</a>;lr;ftag=9XfR8ZmrUUho2HYFHzJ3tYmiMp2GbOXj;did=706.c142d645><br>
: DBG:core:parse_msg: version: <SIP/2.0><br>
: DBG:core:parse_headers: flags=2<br>
: DBG:core:parse_via_param: found param type 232,
<branch> = <z9hG4bK1af6.7d6364b3.2>; state=16<br>
: DBG:core:parse_via: end of header reached, state=5<br>
: DBG:core:parse_headers: via found, flags=2<br>
: DBG:core:parse_headers: this is the first via<br>
: DBG:core:receive_msg: After parse_msg...<br>
: DBG:core:receive_msg: preparing to run routing scripts...<br>
: DBG:sl:sl_filter_ACK: to late to be a local ACK!<br>
: ++++++++++++++++++++++++++++++++++++++++++++++++++++ from <a href="http://77.88.100.100:5060" target="_blank">77.88.100.100:5060</a><br>
: DBG:core:parse_headers: flags=ffffffffffffffff<br>
: DBG:core:parse_via_param: found param type 235, <rport>
= <5060>; state=6<br>
: DBG:core:parse_via_param: found param type 234,
<received> = <<a href="tel:77.88.100.100" target="_blank" value="+17788100100">77.88.100.100</a>>; state=6<br>
: DBG:core:parse_via_param: found param type 232,
<branch> = <z9hG4bK1af6.7d6364b3.2>; state=16<br>
: DBG:core:parse_via: end of header reached, state=5<br>
: DBG:core:parse_headers: via found, flags=ffffffffffffffff<br>
: DBG:core:parse_headers: parse_headers: this is the second via<br>
: DBG:core:parse_via_param: found param type 235, <rport>
= <5060>; state=6<br>
: DBG:core:parse_via_param: found param type 234,
<received> = <11.22.33.44>; state=6<br>
: DBG:core:parse_via_param: found param type 232,
<branch> = <z9hG4bK1af6.8dfa1be2.2>; state=16<br>
: DBG:core:parse_via: end of header reached, state=5<br>
: DBG:core:parse_headers: via found, flags=ffffffffffffffff<br>
: DBG:core:parse_via_param: found param type 234,
<received> = <66.55.44.33>; state=6<br>
: DBG:core:parse_via_param: found param type 235, <rport>
= <52625>; state=6<br>
: DBG:core:parse_via_param: found param type 232,
<branch> =
<z9hG4bKPj-BqsfN5zeuwvMDMyA9q93C5tBdFaeMZg>; state=16<br>
: DBG:core:parse_via: end of header reached, state=5<br>
: DBG:core:parse_headers: via found, flags=ffffffffffffffff<br>
: DBG:core:parse_to_param: tag=as25148399<br>
: DBG:core:parse_to: end of header reached, state=29<br>
: DBG:core:parse_to: display={}, ruri={<a href="mailto:sip%3A15488@11.22.33.44" target="_blank">sip:15488@11.22.33.44</a>}<br>
: DBG:core:get_hdr_field: <To> [40]; uri=[<a href="mailto:sip%3A15488@11.22.33.44" target="_blank">sip:15488@11.22.33.44</a>]
<br>
: DBG:core:get_hdr_field: to body [<<a href="mailto:sip%3A15488@11.22.33.44" target="_blank">sip:15488@11.22.33.44</a>>]<br>
: DBG:core:get_hdr_field: cseq <CSeq>: <31391>
<ACK><br>
: DBG:core:get_hdr_field: content_length=0<br>
: DBG:core:get_hdr_field: found end of header<br>
: DBG:core:parse_to_param: tag=9XfR8ZmrUUho2HYFHzJ3tYmiMp2GbOXj<br>
: DBG:core:parse_to: end of header reached, state=29<br>
: DBG:core:parse_to: display={}, ruri={<a href="mailto:sip%3A77@11.22.33.44" target="_blank">sip:77@11.22.33.44</a>}<br>
: DBG:sipmsgops:sip_validate_hdrs: duplicate header
'Content-Length'<br>
: ASK from <a href="http://77.88.100.100:5060" target="_blank">77.88.100.100:5060</a><br>
: DBG:core:parse_headers: flags=ffffffffffffffff<br>
: DBG:core:parse_headers: flags=ffffffffffffffff<br>
: DBG:core:parse_headers: flags=ffffffffffffffff<br>
: DBG:uri:has_totag: totag found<br>
: Have to_tag <br>
: DBG:core:parse_headers: flags=200<br>
: DBG:rr:find_first_route: No Route headers found<br>
: DBG:rr:loose_route: There is no Route HF<br>
: ASK when not loose_route.<br>
: DBG:core:parse_headers: flags=78<br>
: DBG:tm:t_lookup_request: start searching: hash=28577, isACK=1<br>
: DBG:core:parse_headers: flags=38<br>
: DBG:tm:t_lookup_request: REF_UNSAFE:[0x7f619a9e92f8] after is
1<br>
: DBG:tm:t_lookup_request: e2e proxy ACK found<br>
: t_check_trans (YES).<br>
: DBG:tm:t_newtran: transaction on entrance=(nil)<br>
: DBG:core:parse_headers: flags=ffffffffffffffff<br>
: DBG:tm:t_newtran: building branch for end2end ACK - flags=1<br>
: DBG:tm:t_relay_to: forwarding ACK<br>
: DBG:core:mk_proxy: doing DNS lookup...<br>
: DBG:core:forward_request: sending:#012ACK
sip:<a href="tel:77.88.100.100" target="_blank" value="+17788100100">77.88.100.100</a>;lr;ftag=9XfR8ZmrUUho2HYFHzJ3tYmiMp2GbOXj;did=706.c142d645
SIP/2.0#015#012Via: SIP/2.0/UDP
<a href="tel:77.88.100.100" target="_blank" value="+17788100100">77.88.100.100</a>;branch=z9hG4bK1af6.7d6364b3.2#015#012Via:
SIP/2.0/UDP
<a href="tel:77.88.100.100" target="_blank" value="+17788100100">77.88.100.100</a>;rport=5060;received=<a href="tel:77.88.100.100" target="_blank" value="+17788100100">77.88.100.100</a>;branch=z9hG4bK1af6.7d6364b3.2#015#012Via:
SIP/2.0/UDP
<a href="tel:77.88.100.100" target="_blank" value="+17788100100">77.88.100.100</a>;rport=5060;received=<a href="tel:77.88.100.100" target="_blank" value="+17788100100">77.88.100.100</a>;branch=z9hG4bK1af6.7d6364b3.2#015#012Via:
SIP/2.0/UDP
11.22.33.44;rport=5060;received=11.22.33.44;branch=z9hG4bK1af6.8dfa1be2.2#015#012Via:
SIP/2.0/UDP
66.55.44.33:52625;received=66.55.44.33;rport=52625;branch=z9hG4bKPj-BqsfN5zeuwvMDMyA9q93C5tBdFaeMZg#015#012Max-Forwards:
63#015#012From: <<a href="mailto:sip%3A77@11.22.33.44" target="_blank">sip:77@11.22.33.44</a>>;tag=9XfR8ZmrUUho2HYFHzJ3tYmiMp2GbOXj#015#012To:
<<a href="mailto:sip%3A15488@11.22.33.44" target="_blank">sip:15488@11.22.33.44</a>>;tag=as25148399#015#012Call-ID:
BFq2DLQKfpwA1bgpaaKLDeZJsLgtbDBA#015#012CSeq: 31391
ACK#015#012Content-Length: 0#015#012#015#012.<br>
: DBG:core:forward_request: orig. len=670, new_len=768, proto=1<br>
: DBG:core:run_fwd_callbacks: FWD callback entered<br>
: DBG:siptrace:trace_msg_out: trace off...<br>
: Send relay.<br>
: DBG:tm:t_unref_cell: UNREF_UNSAFE: [0x7f619a9e92f8] after is
0<br>
: DBG:core:destroy_avp_list: destroying list 0x7f619a9f3258<br>
</div>
<div>Best regards;</div>
<div>PlayMen</div>
</div></div><pre><fieldset></fieldset>
_______________________________________________
Users mailing list
<a href="mailto:Users@lists.opensips.org" target="_blank">Users@lists.opensips.org</a>
<a href="http://lists.opensips.org/cgi-bin/mailman/listinfo/users" target="_blank">http://lists.opensips.org/cgi-bin/mailman/listinfo/users</a>
</pre>
</blockquote>
</div>
<br>_______________________________________________<br>
Users mailing list<br>
<a href="mailto:Users@lists.opensips.org">Users@lists.opensips.org</a><br>
<a href="http://lists.opensips.org/cgi-bin/mailman/listinfo/users" target="_blank">http://lists.opensips.org/cgi-bin/mailman/listinfo/users</a><br>
<br></blockquote></div><br>